From 3d421b1ca04cd465d44bc70106eec457a36b0578 Mon Sep 17 00:00:00 2001 From: Chris Punches Date: Sat, 3 Apr 2021 20:24:22 -0400 Subject: [PATCH] stdout/stderr routing is now 'virtually' async --- src/Sproc/Sproc.cpp | 132 +++++++++----- test/plans/test.plan | 1 - test/stderr.log | 4 +- test/stdout.log | 424 ++++--------------------------------------- 4 files changed, 121 insertions(+), 440 deletions(-) diff --git a/src/Sproc/Sproc.cpp b/src/Sproc/Sproc.cpp index c76dd92..1eb24dc 100644 --- a/src/Sproc/Sproc.cpp +++ b/src/Sproc/Sproc.cpp @@ -294,59 +294,96 @@ int Sproc::execute(std::string shell, std::string environment_file, std::string char stdout_buf[1000] = {0}; char stderr_buf[1000] = {0}; - std::cout.flush(); - std::cerr.flush(); - bool set_break = false; - // read from fd until child completes - while (! set_break ) { - ssize_t stdout_count = read(child_stdout_pipe[READ_END], stdout_buf, sizeof(stdout_buf) - 1); + // will contain a set of file descriptors to monitor representing stdout and stderr of the child process + fd_set readfds; - // cycle through STDOUT - switch (stdout_count) { - case READ_PIPEOPEN_O_NONBLOCK: - if (errno == EINTR) { - continue; - } else { - perror("read"); - slog.log(E_FATAL, "PIPE ISSUE with STDOUT"); - exit(1); + + + + // loop completion flags + bool set_stdout_break = false; + bool set_stderr_break = false; + + // read from fd until child completes -- signaled by stdout_break and stderr_break flags + while ((! set_stderr_break ) or (! set_stdout_break)) { + // clear it out to make sure it's clean + FD_ZERO( & readfds ); + + // add child stdout and stderr pipes (read end) + FD_SET( child_stdout_pipe[READ_END], & readfds ); + FD_SET( child_stderr_pipe[READ_END], & readfds ); + + + int highest_fd = child_stderr_pipe[READ_END] > child_stdout_pipe[READ_END] ? child_stderr_pipe[READ_END] : child_stdout_pipe[READ_END]; + + if ( select( highest_fd + 1, &readfds, NULL, NULL, NULL ) >= 0 ) + { // can read any + if ( FD_ISSET( child_stdout_pipe[READ_END], &readfds ) ) + { + // can read child stdout pipe + // so do so + + // read and return the byte size of what was read + int stdout_count = read(child_stdout_pipe[READ_END], stdout_buf, sizeof(stdout_buf) - 1); + + // switch on the count size to allow for error return handling + switch(stdout_count) { + case READ_PIPEOPEN_O_NONBLOCK: + if ( errno == EINTR ) { + continue; + } else { + perror("read stdout"); + slog.log(E_FATAL, "PIPE ISSUE with STDOUT"); + exit(1); + } + case READ_EOF: + // signal that STDOUT is complete + set_stdout_break = true; + break; + default: + tee_out.write( stdout_buf, stdout_count ); + tee_out.flush(); + // clear the buffer to prevent artifacts from previous loop + memset( &stdout_buf[0], 0, sizeof( stdout_buf ) -1 ); } - case READ_EOF: - set_break = true; - break; - default: - tee_out.write(stdout_buf, stdout_count); - tee_out.flush(); - memset(&stdout_buf[0], 0, sizeof(stdout_buf)); - // END SWITCH - } + } + if ( FD_ISSET( child_stderr_pipe[READ_END], & readfds ) ) { + // can read child stderr pipe + // so do so + + // read and return the byte size of what was read + int stderr_count = read( child_stderr_pipe[READ_END], stderr_buf, sizeof( stderr_buf ) -1 ); + + // switch on the count size to allow for error return handling + switch( stderr_count ) { + case READ_RESULTS::READ_PIPEOPEN_O_NONBLOCK: + if ( errno == EINTR ) { + continue; + } else { + perror( "read stderr" ); + slog.log( E_FATAL, "PIPE ISSUE WITH STDERR" ); + exit(1); + } + case READ_RESULTS::READ_EOF: + set_stderr_break = true; + //break; + continue; + default: + tee_err.write( stderr_buf, stderr_count ); + tee_err.flush(); + // clear the buffer to prevent artifacts from previous loop + memset( &stderr_buf[0], 0, sizeof( stderr_buf ) -1 ); + + } + } + } else { + // select error, fatal, throw + slog.log( E_FATAL, "Fatal error, Unknown."); + } // end select/if } - set_break = false; - while(! set_break ) { - ssize_t stderr_count = read(child_stderr_pipe[READ_END], stderr_buf, sizeof(stderr_buf) - 1 ); - switch ( stderr_count ) - { - case READ_PIPEOPEN_O_NONBLOCK: - if (errno == EINTR) { - continue; - } else { - perror("read"); - slog.log( E_FATAL, "PIPE ISSUE with STDERR" ); - exit(1); - } - case READ_EOF: - set_break = true; - break; - default: - tee_err.write( stderr_buf, stderr_count ); - tee_err.flush(); - memset(&stderr_buf[0], 0, sizeof(stderr_buf)); - // END SWITCH - } - } while ((pid = waitpid(pid, &exit_code_raw, 0)) == -1) {} //waitpid( pid, &exit_code_raw, 0 ); @@ -356,6 +393,5 @@ int Sproc::execute(std::string shell, std::string environment_file, std::string stderr_log.close(); } } - return WEXITSTATUS( exit_code_raw ); } diff --git a/test/plans/test.plan b/test/plans/test.plan index 0ff7f98..7aaa83e 100644 --- a/test/plans/test.plan +++ b/test/plans/test.plan @@ -3,7 +3,6 @@ { "name": "independent test 1", "dependencies": [ null ] }, { "name": "independent test 2", "dependencies": [ null ] }, { "name": "dependent test", "dependencies": [ "independent test 1" ] }, - { "name": "curses dialog", "dependencies": [ null ] }, { "name": "fail", "dependencies": [ null ] } ] } diff --git a/test/stderr.log b/test/stderr.log index ac8721c..33ca4c6 100644 --- a/test/stderr.log +++ b/test/stderr.log @@ -1,4 +1,2 @@ -12131231321This test is printing to stderr. -1321321This test is printing to stderr. -8675309This test is printing to stderr. +This test is printing to stderr. diff --git a/test/stdout.log b/test/stdout.log index c4bfd7e..a0b9b30 100644 --- a/test/stdout.log +++ b/test/stdout.log @@ -1,398 +1,46 @@ -[2021-03-25_02:00:35] [INFO] [_sproc] [ 'independent test 1' ] TEE Logging enabled. -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'independent test 1' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'independent test 1' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'independent test 1' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'independent test 1' ] UID of 'bagira' is '1000'. -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'independent test 1' ] GID of 'bagira' is '1000'. -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'independent test 1' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'independent test 1' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:00:35] [INFO] [_sproc] [ 'independent test 1' ] Identity context set as user 'bagira' and group 'bagira'. +[2021-04-03_20:20:26] [INFO] [_sproc] [ 'independent test 1' ] TEE Logging enabled. +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'independent test 1' ] DUP2: child_*_pipe[1]->STD*_FILENO +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'independent test 1' ] Attempt: Running as user 'bagira'. +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'independent test 1' ] Attempt: Running as group_name 'bagira'. +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'independent test 1' ] UID of 'bagira' is '1000'. +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'independent test 1' ] GID of 'bagira' is '1000'. +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'independent test 1' ] Successfully set GID to '1000' (bagira). +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'independent test 1' ] Successfully set UID to '1000' (bagira). +[2021-04-03_20:20:26] [INFO] [_sproc] [ 'independent test 1' ] Identity context set as user 'bagira' and group 'bagira'. variables file says hello and set a variable named TEST_VAR TEST OUTPUT: Test var is: 999 -[2021-03-25_02:00:35] [INFO] [_sproc] [ 'independent test 2' ] TEE Logging enabled. -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'independent test 2' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'independent test 2' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'independent test 2' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'independent test 2' ] UID of 'bagira' is '1000'. -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'independent test 2' ] GID of 'bagira' is '1000'. -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'independent test 2' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'independent test 2' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:00:35] [INFO] [_sproc] [ 'independent test 2' ] Identity context set as user 'bagira' and group 'bagira'. +[2021-04-03_20:20:26] [INFO] [_sproc] [ 'independent test 2' ] TEE Logging enabled. +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'independent test 2' ] DUP2: child_*_pipe[1]->STD*_FILENO +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'independent test 2' ] Attempt: Running as user 'bagira'. +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'independent test 2' ] Attempt: Running as group_name 'bagira'. +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'independent test 2' ] UID of 'bagira' is '1000'. +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'independent test 2' ] GID of 'bagira' is '1000'. +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'independent test 2' ] Successfully set GID to '1000' (bagira). +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'independent test 2' ] Successfully set UID to '1000' (bagira). +[2021-04-03_20:20:26] [INFO] [_sproc] [ 'independent test 2' ] Identity context set as user 'bagira' and group 'bagira'. variables file says hello and set a variable named TEST_VAR independent test 2 output independent test says TEST_VAR is 999 -[2021-03-25_02:00:35] [INFO] [_sproc] [ 'dependent test' ] TEE Logging enabled. -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'dependent test' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'dependent test' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'dependent test' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'dependent test' ] UID of 'bagira' is '1000'. -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'dependent test' ] GID of 'bagira' is '1000'. -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'dependent test' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'dependent test' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:00:35] [INFO] [_sproc] [ 'dependent test' ] Identity context set as user 'bagira' and group 'bagira'. +[2021-04-03_20:20:26] [INFO] [_sproc] [ 'dependent test' ] TEE Logging enabled. +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'dependent test' ] DUP2: child_*_pipe[1]->STD*_FILENO +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'dependent test' ] Attempt: Running as user 'bagira'. +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'dependent test' ] Attempt: Running as group_name 'bagira'. +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'dependent test' ] UID of 'bagira' is '1000'. +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'dependent test' ] GID of 'bagira' is '1000'. +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'dependent test' ] Successfully set GID to '1000' (bagira). +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'dependent test' ] Successfully set UID to '1000' (bagira). +[2021-04-03_20:20:26] [INFO] [_sproc] [ 'dependent test' ] Identity context set as user 'bagira' and group 'bagira'. variables file says hello and set a variable named TEST_VAR dependent test -[2021-03-25_02:00:35] [INFO] [_sproc] [ 'curses dialog' ] TEE Logging enabled. -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'curses dialog' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'curses dialog' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'curses dialog' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'curses dialog' ] UID of 'bagira' is '1000'. -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'curses dialog' ] GID of 'bagira' is '1000'. -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'curses dialog' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:00:35] [DBUG] [_sproc] [ 'curses dialog' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:00:35] [INFO] [_sproc] [ 'curses dialog' ] Identity context set as user 'bagira' and group 'bagira'. -variables file says hello and set a variable named TEST_VAR -[?1049h(B[?7h[?1h=[?1006;1000h[?1h=[?1h=(B(B - - - - - - - - - - - - - - - - - - - - - - - - - - (0lqqqqqqq(BDialog title(0qqqqqqqqq(0k(B (0x(B Enter your name: (0x(B  (0x(B (0lqqqqqqqqqqqqqqqqqqqqqqqq(0k(B (0x(B  (0x(B (0x(B(0x(B (0x(B  (0x(B (0m(0qqqqqqqqqqqqqqqqqqqqqqqqj(B (0x(B  (0tqqqqqqqqqqqqqqqqqqqqqqqqqqqq(0u(B  (0x(B (B< OK >(B <C(Bancel(B> (0x(B  (0m(0qqqqqqqqqqqqqqqqqqqqqqqqqqqqj(B  (B(B1(B2(B1(B3(B[?1006;1000l[?1049l [?1l>test -[2021-03-25_02:00:38] [INFO] [_sproc] [ 'fail' ] TEE Logging enabled. -[2021-03-25_02:00:38] [DBUG] [_sproc] [ 'fail' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:00:38] [DBUG] [_sproc] [ 'fail' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:00:38] [DBUG] [_sproc] [ 'fail' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:00:38] [DBUG] [_sproc] [ 'fail' ] UID of 'bagira' is '1000'. -[2021-03-25_02:00:38] [DBUG] [_sproc] [ 'fail' ] GID of 'bagira' is '1000'. -[2021-03-25_02:00:38] [DBUG] [_sproc] [ 'fail' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:00:38] [DBUG] [_sproc] [ 'fail' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:00:38] [INFO] [_sproc] [ 'fail' ] Identity context set as user 'bagira' and group 'bagira'. -variables file says hello and set a variable named TEST_VAR -This test will fail on purpose. -[2021-03-25_02:01:19] [INFO] [_sproc] [ 'independent test 1' ] TEE Logging enabled. -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'independent test 1' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'independent test 1' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'independent test 1' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'independent test 1' ] UID of 'bagira' is '1000'. -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'independent test 1' ] GID of 'bagira' is '1000'. -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'independent test 1' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'independent test 1' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:01:19] [INFO] [_sproc] [ 'independent test 1' ] Identity context set as user 'bagira' and group 'bagira'. -variables file says hello and set a variable named TEST_VAR -TEST OUTPUT: Test var is: 999 -[2021-03-25_02:01:19] [INFO] [_sproc] [ 'independent test 2' ] TEE Logging enabled. -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'independent test 2' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'independent test 2' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'independent test 2' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'independent test 2' ] UID of 'bagira' is '1000'. -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'independent test 2' ] GID of 'bagira' is '1000'. -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'independent test 2' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'independent test 2' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:01:19] [INFO] [_sproc] [ 'independent test 2' ] Identity context set as user 'bagira' and group 'bagira'. -variables file says hello and set a variable named TEST_VAR -independent test 2 output -independent test says TEST_VAR is 999 -[2021-03-25_02:01:19] [INFO] [_sproc] [ 'dependent test' ] TEE Logging enabled. -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'dependent test' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'dependent test' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'dependent test' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'dependent test' ] UID of 'bagira' is '1000'. -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'dependent test' ] GID of 'bagira' is '1000'. -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'dependent test' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'dependent test' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:01:19] [INFO] [_sproc] [ 'dependent test' ] Identity context set as user 'bagira' and group 'bagira'. -variables file says hello and set a variable named TEST_VAR -dependent test -[2021-03-25_02:01:19] [INFO] [_sproc] [ 'curses dialog' ] TEE Logging enabled. -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'curses dialog' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'curses dialog' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'curses dialog' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'curses dialog' ] UID of 'bagira' is '1000'. -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'curses dialog' ] GID of 'bagira' is '1000'. -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'curses dialog' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:01:19] [DBUG] [_sproc] [ 'curses dialog' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:01:19] [INFO] [_sproc] [ 'curses dialog' ] Identity context set as user 'bagira' and group 'bagira'. -variables file says hello and set a variable named TEST_VAR -[?1049h(B[?7h[?1h=[?1006;1000h[?1h=[?1h=(B(B - - - - - - - - - - - - - - - - - - - - - - - - - - (0lqqqqqqq(BDialog title(0qqqqqqqqq(0k(B (0x(B Enter your name: (0x(B  (0x(B (0lqqqqqqqqqqqqqqqqqqqqqqqq(0k(B (0x(B  (0x(B (0x(B(0x(B (0x(B  (0x(B (0m(0qqqqqqqqqqqqqqqqqqqqqqqqj(B (0x(B  (0tqqqqqqqqqqqqqqqqqqqqqqqqqqqq(0u(B  (0x(B (B< OK >(B <C(Bancel(B> (0x(B  (0m(0qqqqqqqqqqqqqqqqqqqqqqqqqqqqj(B  (B(B1(B2(B1(B3(B[?1006;1000l[?1049l [?1l>test -[2021-03-25_02:01:21] [INFO] [_sproc] [ 'fail' ] TEE Logging enabled. -[2021-03-25_02:01:21] [DBUG] [_sproc] [ 'fail' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:01:21] [DBUG] [_sproc] [ 'fail' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:01:21] [DBUG] [_sproc] [ 'fail' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:01:21] [DBUG] [_sproc] [ 'fail' ] UID of 'bagira' is '1000'. -[2021-03-25_02:01:21] [DBUG] [_sproc] [ 'fail' ] GID of 'bagira' is '1000'. -[2021-03-25_02:01:21] [DBUG] [_sproc] [ 'fail' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:01:21] [DBUG] [_sproc] [ 'fail' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:01:21] [INFO] [_sproc] [ 'fail' ] Identity context set as user 'bagira' and group 'bagira'. -variables file says hello and set a variable named TEST_VAR -This test will fail on purpose. -[2021-03-25_02:02:59] [INFO] [_sproc] [ 'independent test 1' ] TEE Logging enabled. -[2021-03-25_02:02:59] [DBUG] [_sproc] [ 'independent test 1' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:02:59] [DBUG] [_sproc] [ 'independent test 1' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:02:59] [DBUG] [_sproc] [ 'independent test 1' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:02:59] [DBUG] [_sproc] [ 'independent test 1' ] UID of 'bagira' is '1000'. -[2021-03-25_02:02:59] [DBUG] [_sproc] [ 'independent test 1' ] GID of 'bagira' is '1000'. -[2021-03-25_02:02:59] [DBUG] [_sproc] [ 'independent test 1' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:02:59] [DBUG] [_sproc] [ 'independent test 1' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:02:59] [INFO] [_sproc] [ 'independent test 1' ] Identity context set as user 'bagira' and group 'bagira'. -variables file says hello and set a variable named TEST_VAR -TEST OUTPUT: Test var is: 999 -[2021-03-25_02:04:11] [INFO] [_sproc] [ 'independent test 2' ] TEE Logging enabled. -[2021-03-25_02:04:11] [DBUG] [_sproc] [ 'independent test 2' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:04:11] [DBUG] [_sproc] [ 'independent test 2' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:04:11] [DBUG] [_sproc] [ 'independent test 2' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:04:11] [DBUG] [_sproc] [ 'independent test 2' ] UID of 'bagira' is '1000'. -[2021-03-25_02:04:11] [DBUG] [_sproc] [ 'independent test 2' ] GID of 'bagira' is '1000'. -[2021-03-25_02:04:11] [DBUG] [_sproc] [ 'independent test 2' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:04:11] [DBUG] [_sproc] [ 'independent test 2' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:04:11] [INFO] [_sproc] [ 'independent test 2' ] Identity context set as user 'bagira' and group 'bagira'. -variables file says hello and set a variable named TEST_VAR -independent test 2 output -independent test says TEST_VAR is 999 -[2021-03-25_02:04:35] [INFO] [_sproc] [ 'dependent test' ] TEE Logging enabled. -[2021-03-25_02:04:35] [DBUG] [_sproc] [ 'dependent test' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:04:35] [DBUG] [_sproc] [ 'dependent test' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:04:35] [DBUG] [_sproc] [ 'dependent test' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:04:35] [DBUG] [_sproc] [ 'dependent test' ] UID of 'bagira' is '1000'. -[2021-03-25_02:04:35] [DBUG] [_sproc] [ 'dependent test' ] GID of 'bagira' is '1000'. -[2021-03-25_02:04:35] [DBUG] [_sproc] [ 'dependent test' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:04:35] [DBUG] [_sproc] [ 'dependent test' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:04:35] [INFO] [_sproc] [ 'dependent test' ] Identity context set as user 'bagira' and group 'bagira'. -variables file says hello and set a variable named TEST_VAR -dependent test -[2021-03-25_02:04:44] [INFO] [_sproc] [ 'curses dialog' ] TEE Logging enabled. -[2021-03-25_02:04:44] [DBUG] [_sproc] [ 'curses dialog' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:04:44] [DBUG] [_sproc] [ 'curses dialog' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:04:44] [DBUG] [_sproc] [ 'curses dialog' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:04:44] [DBUG] [_sproc] [ 'curses dialog' ] UID of 'bagira' is '1000'. -[2021-03-25_02:04:44] [DBUG] [_sproc] [ 'curses dialog' ] GID of 'bagira' is '1000'. -[2021-03-25_02:04:44] [DBUG] [_sproc] [ 'curses dialog' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:04:44] [DBUG] [_sproc] [ 'curses dialog' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:04:44] [INFO] [_sproc] [ 'curses dialog' ] Identity context set as user 'bagira' and group 'bagira'. -variables file says hello and set a variable named TEST_VAR - - - test -[2021-03-25_02:07:20] [INFO] [_sproc] [ 'independent test 1' ] TEE Logging enabled. -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'independent test 1' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'independent test 1' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'independent test 1' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'independent test 1' ] UID of 'bagira' is '1000'. -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'independent test 1' ] GID of 'bagira' is '1000'. -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'independent test 1' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'independent test 1' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:07:20] [INFO] [_sproc] [ 'independent test 1' ] Identity context set as user 'bagira' and group 'bagira'. -variables file says hello and set a variable named TEST_VAR -TEST OUTPUT: Test var is: 999 -[2021-03-25_02:07:20] [INFO] [_sproc] [ 'independent test 2' ] TEE Logging enabled. -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'independent test 2' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'independent test 2' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'independent test 2' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'independent test 2' ] UID of 'bagira' is '1000'. -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'independent test 2' ] GID of 'bagira' is '1000'. -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'independent test 2' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'independent test 2' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:07:20] [INFO] [_sproc] [ 'independent test 2' ] Identity context set as user 'bagira' and group 'bagira'. -variables file says hello and set a variable named TEST_VAR -independent test 2 output -independent test says TEST_VAR is 999 -[2021-03-25_02:07:20] [INFO] [_sproc] [ 'dependent test' ] TEE Logging enabled. -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'dependent test' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'dependent test' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'dependent test' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'dependent test' ] UID of 'bagira' is '1000'. -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'dependent test' ] GID of 'bagira' is '1000'. -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'dependent test' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'dependent test' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:07:20] [INFO] [_sproc] [ 'dependent test' ] Identity context set as user 'bagira' and group 'bagira'. -variables file says hello and set a variable named TEST_VAR -dependent test -[2021-03-25_02:07:20] [INFO] [_sproc] [ 'curses dialog' ] TEE Logging enabled. -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'curses dialog' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'curses dialog' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'curses dialog' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'curses dialog' ] UID of 'bagira' is '1000'. -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'curses dialog' ] GID of 'bagira' is '1000'. -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'curses dialog' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:07:20] [DBUG] [_sproc] [ 'curses dialog' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:07:20] [INFO] [_sproc] [ 'curses dialog' ] Identity context set as user 'bagira' and group 'bagira'. -variables file says hello and set a variable named TEST_VAR -[?1049h(B[?7h[?1h=[?1006;1000h[?1h=[?1h=(B(B - - - - - - (0lqqqqqqq(BDialog title(0qqqqqqqqq(0k(B (0x(B Enter your name: (0x(B  (0x(B (0lqqqqqqqqqqqqqqqqqqqqqqqq(0k(B (0x(B  (0x(B (0x(B(0x(B (0x(B  (0x(B (0m(0qqqqqqqqqqqqqqqqqqqqqqqqj(B (0x(B  (0tqqqqqqqqqqqqqqqqqqqqqqqqqqqq(0u(B  (0x(B (B< OK >(B <C(Bancel(B> (0x(B  (0m(0qqqqqqqqqqqqqqqqqqqqqqqqqqqqj(B  (B(B1(B2(B3(B1(B3(B2(B1(B[?1006;1000l[?1049l [?1l>test -[2021-03-25_02:07:22] [INFO] [_sproc] [ 'fail' ] TEE Logging enabled. -[2021-03-25_02:07:22] [DBUG] [_sproc] [ 'fail' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:07:22] [DBUG] [_sproc] [ 'fail' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:07:22] [DBUG] [_sproc] [ 'fail' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:07:22] [DBUG] [_sproc] [ 'fail' ] UID of 'bagira' is '1000'. -[2021-03-25_02:07:22] [DBUG] [_sproc] [ 'fail' ] GID of 'bagira' is '1000'. -[2021-03-25_02:07:22] [DBUG] [_sproc] [ 'fail' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:07:22] [DBUG] [_sproc] [ 'fail' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:07:22] [INFO] [_sproc] [ 'fail' ] Identity context set as user 'bagira' and group 'bagira'. -variables file says hello and set a variable named TEST_VAR -This test will fail on purpose. -[2021-03-25_02:08:01] [INFO] [_sproc] [ 'independent test 1' ] TEE Logging enabled. -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'independent test 1' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'independent test 1' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'independent test 1' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'independent test 1' ] UID of 'bagira' is '1000'. -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'independent test 1' ] GID of 'bagira' is '1000'. -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'independent test 1' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'independent test 1' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:08:01] [INFO] [_sproc] [ 'independent test 1' ] Identity context set as user 'bagira' and group 'bagira'. -variables file says hello and set a variable named TEST_VAR -TEST OUTPUT: Test var is: 999 -[2021-03-25_02:08:01] [INFO] [_sproc] [ 'independent test 2' ] TEE Logging enabled. -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'independent test 2' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'independent test 2' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'independent test 2' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'independent test 2' ] UID of 'bagira' is '1000'. -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'independent test 2' ] GID of 'bagira' is '1000'. -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'independent test 2' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'independent test 2' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:08:01] [INFO] [_sproc] [ 'independent test 2' ] Identity context set as user 'bagira' and group 'bagira'. -variables file says hello and set a variable named TEST_VAR -independent test 2 output -independent test says TEST_VAR is 999 -[2021-03-25_02:08:01] [INFO] [_sproc] [ 'dependent test' ] TEE Logging enabled. -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'dependent test' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'dependent test' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'dependent test' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'dependent test' ] UID of 'bagira' is '1000'. -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'dependent test' ] GID of 'bagira' is '1000'. -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'dependent test' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'dependent test' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:08:01] [INFO] [_sproc] [ 'dependent test' ] Identity context set as user 'bagira' and group 'bagira'. -variables file says hello and set a variable named TEST_VAR -dependent test -[2021-03-25_02:08:01] [INFO] [_sproc] [ 'curses dialog' ] TEE Logging enabled. -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'curses dialog' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'curses dialog' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'curses dialog' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'curses dialog' ] UID of 'bagira' is '1000'. -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'curses dialog' ] GID of 'bagira' is '1000'. -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'curses dialog' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:08:01] [DBUG] [_sproc] [ 'curses dialog' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:08:01] [INFO] [_sproc] [ 'curses dialog' ] Identity context set as user 'bagira' and group 'bagira'. -variables file says hello and set a variable named TEST_VAR -[?1049h(B[?7h[?1h=[?1006;1000h[?1h=[?1h=(B(B - - - - - - (0lqqqqqqq(BDialog title(0qqqqqqqqq(0k(B (0x(B Enter your name: (0x(B  (0x(B (0lqqqqqqqqqqqqqqqqqqqqqqqq(0k(B (0x(B  (0x(B (0x(B(0x(B (0x(B  (0x(B (0m(0qqqqqqqqqqqqqqqqqqqqqqqqj(B (0x(B  (0tqqqqqqqqqqqqqqqqqqqqqqqqqqqq(0u(B  (0x(B (B< OK >(B <C(Bancel(B> (0x(B  (0m(0qqqqqqqqqqqqqqqqqqqqqqqqqqqqj(B  (B(B1(B3(B2(B1(B3(B2(B1(B[?1006;1000l[?1049l [?1l>test -[2021-03-25_02:08:12] [INFO] [_sproc] [ 'fail' ] TEE Logging enabled. -[2021-03-25_02:08:12] [DBUG] [_sproc] [ 'fail' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:08:12] [DBUG] [_sproc] [ 'fail' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:08:12] [DBUG] [_sproc] [ 'fail' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:08:12] [DBUG] [_sproc] [ 'fail' ] UID of 'bagira' is '1000'. -[2021-03-25_02:08:12] [DBUG] [_sproc] [ 'fail' ] GID of 'bagira' is '1000'. -[2021-03-25_02:08:12] [DBUG] [_sproc] [ 'fail' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:08:12] [DBUG] [_sproc] [ 'fail' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:08:12] [INFO] [_sproc] [ 'fail' ] Identity context set as user 'bagira' and group 'bagira'. -variables file says hello and set a variable named TEST_VAR -This test will fail on purpose. -[2021-03-25_02:08:30] [INFO] [_sproc] [ 'independent test 1' ] TEE Logging enabled. -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'independent test 1' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'independent test 1' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'independent test 1' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'independent test 1' ] UID of 'bagira' is '1000'. -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'independent test 1' ] GID of 'bagira' is '1000'. -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'independent test 1' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'independent test 1' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:08:30] [INFO] [_sproc] [ 'independent test 1' ] Identity context set as user 'bagira' and group 'bagira'. -variables file says hello and set a variable named TEST_VAR -TEST OUTPUT: Test var is: 999 -[2021-03-25_02:08:30] [INFO] [_sproc] [ 'independent test 2' ] TEE Logging enabled. -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'independent test 2' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'independent test 2' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'independent test 2' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'independent test 2' ] UID of 'bagira' is '1000'. -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'independent test 2' ] GID of 'bagira' is '1000'. -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'independent test 2' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'independent test 2' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:08:30] [INFO] [_sproc] [ 'independent test 2' ] Identity context set as user 'bagira' and group 'bagira'. -variables file says hello and set a variable named TEST_VAR -independent test 2 output -independent test says TEST_VAR is 999 -[2021-03-25_02:08:30] [INFO] [_sproc] [ 'dependent test' ] TEE Logging enabled. -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'dependent test' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'dependent test' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'dependent test' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'dependent test' ] UID of 'bagira' is '1000'. -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'dependent test' ] GID of 'bagira' is '1000'. -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'dependent test' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'dependent test' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:08:30] [INFO] [_sproc] [ 'dependent test' ] Identity context set as user 'bagira' and group 'bagira'. -variables file says hello and set a variable named TEST_VAR -dependent test -[2021-03-25_02:08:30] [INFO] [_sproc] [ 'curses dialog' ] TEE Logging enabled. -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'curses dialog' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'curses dialog' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'curses dialog' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'curses dialog' ] UID of 'bagira' is '1000'. -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'curses dialog' ] GID of 'bagira' is '1000'. -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'curses dialog' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:08:30] [DBUG] [_sproc] [ 'curses dialog' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:08:30] [INFO] [_sproc] [ 'curses dialog' ] Identity context set as user 'bagira' and group 'bagira'. -variables file says hello and set a variable named TEST_VAR -[?1049h(B[?7h[?1h=[?1006;1000h[?1h=[?1h=(B(B - - - - - - (0lqqqqqqq(BDialog title(0qqqqqqqqq(0k(B (0x(B Enter your name: (0x(B  (0x(B (0lqqqqqqqqqqqqqqqqqqqqqqqq(0k(B (0x(B  (0x(B (0x(B(0x(B (0x(B  (0x(B (0m(0qqqqqqqqqqqqqqqqqqqqqqqqj(B (0x(B  (0tqqqqqqqqqqqqqqqqqqqqqqqqqqqq(0u(B  (0x(B (B< OK >(B <C(Bancel(B> (0x(B  (0m(0qqqqqqqqqqqqqqqqqqqqqqqqqqqqj(B  (B(B8(B6(B7(B5(B3(B0(B9(B[?1006;1000l[?1049l [?1l>test -[2021-03-25_02:08:38] [INFO] [_sproc] [ 'fail' ] TEE Logging enabled. -[2021-03-25_02:08:38] [DBUG] [_sproc] [ 'fail' ] DUP2: child_*_pipe[1]->STD*_FILENO -[2021-03-25_02:08:38] [DBUG] [_sproc] [ 'fail' ] Attempt: Running as user 'bagira'. -[2021-03-25_02:08:38] [DBUG] [_sproc] [ 'fail' ] Attempt: Running as group_name 'bagira'. -[2021-03-25_02:08:38] [DBUG] [_sproc] [ 'fail' ] UID of 'bagira' is '1000'. -[2021-03-25_02:08:38] [DBUG] [_sproc] [ 'fail' ] GID of 'bagira' is '1000'. -[2021-03-25_02:08:38] [DBUG] [_sproc] [ 'fail' ] Successfully set GID to '1000' (bagira). -[2021-03-25_02:08:38] [DBUG] [_sproc] [ 'fail' ] Successfully set UID to '1000' (bagira). -[2021-03-25_02:08:38] [INFO] [_sproc] [ 'fail' ] Identity context set as user 'bagira' and group 'bagira'. +[2021-04-03_20:20:26] [INFO] [_sproc] [ 'fail' ] TEE Logging enabled. +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'fail' ] DUP2: child_*_pipe[1]->STD*_FILENO +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'fail' ] Attempt: Running as user 'bagira'. +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'fail' ] Attempt: Running as group_name 'bagira'. +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'fail' ] UID of 'bagira' is '1000'. +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'fail' ] GID of 'bagira' is '1000'. +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'fail' ] Successfully set GID to '1000' (bagira). +[2021-04-03_20:20:26] [DBUG] [_sproc] [ 'fail' ] Successfully set UID to '1000' (bagira). +[2021-04-03_20:20:26] [INFO] [_sproc] [ 'fail' ] Identity context set as user 'bagira' and group 'bagira'. variables file says hello and set a variable named TEST_VAR This test will fail on purpose.