Project

General

Profile

Bug #7883 ยป 7883-test-failed[1].log

fail logged with verbose - Janos Mattyasovszky, 2016-02-08 09:30

 
2016-02-08T09:24:38+0100 verbose: /default/default/vars: Evaluating promise 'tests'
2016-02-08T09:24:38+0100 verbose: /default/default/methods: Evaluating promise 'any'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths_init: Augment scope 'paths_init' with variable 'filename' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths_init/classes: Evaluating promise 'filename_absolute'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths_init/classes/'filename_absolute'[0]: Adding global class 'filename_absolute'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths_init: Evaluating bundle pass 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths_init/classes: Evaluating promise 'filename_absolute'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths_init/classes: Evaluating promise 'filename_absolute'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths_init: Evaluating bundle pass 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths_init/classes: Evaluating promise 'filename_absolute'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths_init/classes: Evaluating promise 'filename_absolute'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths_init: Evaluating bundle pass 3
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths_init/classes: Evaluating promise 'filename_absolute'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths_init/classes: Evaluating promise 'filename_absolute'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths_init: Zero promises executed for bundle 'paths_init'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'[0]: Additional promise info: version '1.0' source path 'ncf/tests/acceptance/default.cf.sub' at line 72
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'[0]: Method 'paths_init' verified
2016-02-08T09:24:38+0100 verbose: /default/default/methods: Evaluating promise 'any'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths2: Augment scope 'paths2' with variable 'filename' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths2/vars: Evaluating promise 'input_file'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths2/vars: Evaluating promise 'input_file'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths2/vars: Skipping next promise 'input_file', as context '!filename_absolute' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths2: Evaluating bundle pass 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths2/vars: Evaluating promise 'input_file'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths2/vars: Evaluating promise 'input_file'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths2/vars: Skipping next promise 'input_file', as context '!filename_absolute' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths2: Evaluating bundle pass 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths2/vars: Evaluating promise 'input_file'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths2/vars: Evaluating promise 'input_file'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths2/vars: Skipping next promise 'input_file', as context '!filename_absolute' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths2: Evaluating bundle pass 3
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths2/vars: Evaluating promise 'input_file'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths2/vars: Evaluating promise 'input_file'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths2/vars: Skipping next promise 'input_file', as context '!filename_absolute' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/paths2: Zero promises executed for bundle 'paths2'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'[0]: Additional promise info: version '1.0' source path 'ncf/tests/acceptance/default.cf.sub' at line 74
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'[0]: Method 'paths2' verified
2016-02-08T09:24:38+0100 verbose: /default/default/methods: Evaluating promise 'any'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/init/vars: Evaluating promise 'command_0'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/init/vars: Evaluating promise 'canonified_command_0'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/init: Evaluating bundle pass 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/init/vars: Evaluating promise 'command_0'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/init/vars: Evaluating promise 'canonified_command_0'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/init: Evaluating bundle pass 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/init/vars: Evaluating promise 'command_0'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/init/vars: Evaluating promise 'canonified_command_0'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/init: Evaluating bundle pass 3
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/init/vars: Evaluating promise 'command_0'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/init/vars: Evaluating promise 'canonified_command_0'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/init: Zero promises executed for bundle 'init'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'[0]: Additional promise info: version '1.0' source path 'ncf/tests/acceptance/default.cf.sub' at line 78
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'[0]: Method 'init' verified
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/vars: Evaluating promise 'kept_codes'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/vars: Evaluating promise 'repaired_codes'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test: Evaluating bundle pass 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/vars: Evaluating promise 'kept_codes'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/vars: Evaluating promise 'repaired_codes'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods: Evaluating promise 'phA'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result: Augment scope 'command_execution_result' with variable 'command' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result: Augment scope 'command_execution_result' with variable 'kept_codes' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result: Augment scope 'command_execution_result' with variable 'repaired_codes' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/vars: Evaluating promise 'class_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/vars: Evaluating promise 'kept_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/vars: Evaluating promise 'repaired_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result: Evaluating bundle pass 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/vars: Evaluating promise 'class_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/vars: Evaluating promise 'kept_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/vars: Evaluating promise 'repaired_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods: Evaluating promise 'report'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods: Skipping next promise 'report', as ifvarclass 'command_execution_result_exit_0_reached' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/commands: Evaluating promise '${command}'
2016-02-08T09:24:38+0100 info: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/commands/'exit 0'[0]: Executing 'no timeout' ... 'exit 0'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/commands/'exit 0'[0]: (Setting umask to 77)
2016-02-08T09:24:38+0100 info: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/commands/'exit 0'[0]: Command related to promiser 'exit 0' returned code defined as promise kept 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/commands/'exit 0'[0]: Defining promise result class 'promise_kept_command_execution_result_exit_0'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/commands/'exit 0'[0]: Defining promise result class 'command_execution_result_exit_0_kept'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/commands/'exit 0'[0]: Defining promise result class 'command_execution_result_exit_0_ok'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/commands/'exit 0'[0]: Defining promise result class 'command_execution_result_exit_0_not_repaired'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/commands/'exit 0'[0]: Defining promise result class 'command_execution_result_exit_0_reached'
2016-02-08T09:24:38+0100 info: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/commands/'exit 0'[0]: Completed execution of 'exit 0'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result: Evaluating bundle pass 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/vars: Evaluating promise 'class_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/vars: Evaluating promise 'kept_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/vars: Evaluating promise 'repaired_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods: Evaluating promise 'report'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger: Augment scope '_logger' with variable 'message' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger: Augment scope '_logger' with variable 'class_prefix' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger: Evaluating bundle pass 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods: Evaluating promise 'logging'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Augment scope '_bundle_caller_two' with variable 'method' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Augment scope '_bundle_caller_two' with variable 'first_argument' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Augment scope '_bundle_caller_two' with variable 'second_argument' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Evaluating bundle pass 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods: Evaluating promise '${method}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'[0]: Comment 'Call the _logger_default bundle with arguments Execute the command exit 0, {second_argument}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default: Augment scope '_logger_default' with variable 'message' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default: Augment scope '_logger_default' with variable 'class_prefix' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/vars: Evaluating promise 'canonified_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default: Evaluating bundle pass 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/vars: Evaluating promise 'canonified_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.debug} Promise kept, not doing anything: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration.debug} Promise kept, not doing anything: ${message}', as context 'debug' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.info} Promise repaired, made a change: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration.info} Promise repaired, made a change: ${message}', as context 'debug|info' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.error} Promise could not be repaired, error encountered: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration#error} Promise could not be repaired, error encountered: ${message}', as ifvarclass 'command_execution_result_exit_0_not_ok' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default: Evaluating bundle pass 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/vars: Evaluating promise 'canonified_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.debug} Promise kept, not doing anything: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration.debug} Promise kept, not doing anything: ${message}', as context 'debug' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.info} Promise repaired, made a change: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration.info} Promise repaired, made a change: ${message}', as context 'debug|info' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.error} Promise could not be repaired, error encountered: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration#error} Promise could not be repaired, error encountered: ${message}', as ifvarclass 'command_execution_result_exit_0_not_ok' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default: Evaluating bundle pass 3
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/vars: Evaluating promise 'canonified_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.debug} Promise kept, not doing anything: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration.debug} Promise kept, not doing anything: ${message}', as context 'debug' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.info} Promise repaired, made a change: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration.info} Promise repaired, made a change: ${message}', as context 'debug|info' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.error} Promise could not be repaired, error encountered: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration#error} Promise could not be repaired, error encountered: ${message}', as ifvarclass 'command_execution_result_exit_0_not_ok' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default: Zero promises executed for bundle '_logger_default'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'[0]: Additional promise info: version '1.0' source path 'ncf/tests/acceptance/../../tree/30_generic_methods/_bundle_caller_two.cf' at line 30 comment 'Call the _logger_default bundle with arguments Execute the command exit 0, {second_argument}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'[0]: Method '_logger_default' verified
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Evaluating bundle pass 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods: Evaluating promise '${method}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Evaluating bundle pass 3
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods: Evaluating promise '${method}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Bundle Accounting Summary for '_bundle_caller_two'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Promises kept in '_bundle_caller_two' = 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Promises not kept in '_bundle_caller_two' = 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Promises repaired in '_bundle_caller_two' = 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Aggregate compliance (promises kept/repaired) for bundle '_bundle_caller_two' = 100.0%
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'[0]: Additional promise info: version '1.0' source path 'ncf/tests/acceptance/../../tree/30_generic_methods/_logger.cf' at line 36
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'[0]: Method '_bundle_caller_two' verified
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger: Evaluating bundle pass 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods: Evaluating promise 'logging'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger: Evaluating bundle pass 3
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger/methods: Evaluating promise 'logging'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger: Bundle Accounting Summary for '_logger'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger: Promises kept in '_logger' = 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger: Promises not kept in '_logger' = 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger: Promises repaired in '_logger' = 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'/default/_logger: Aggregate compliance (promises kept/repaired) for bundle '_logger' = 100.0%
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'[0]: Additional promise info: version '1.0' source path 'ncf/tests/acceptance/../../tree/30_generic_methods/command_execution_result.cf' at line 43
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods/'report'[0]: Method '_logger' verified
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/commands: Evaluating promise '${command}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result: Evaluating bundle pass 3
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/vars: Evaluating promise 'class_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/vars: Evaluating promise 'kept_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/vars: Evaluating promise 'repaired_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/methods: Evaluating promise 'report'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result/commands: Evaluating promise '${command}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result: Bundle Accounting Summary for 'command_execution_result'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result: Promises kept in 'command_execution_result' = 4
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result: Promises not kept in 'command_execution_result' = 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result: Promises repaired in 'command_execution_result' = 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'/default/command_execution_result: Aggregate compliance (promises kept/repaired) for bundle 'command_execution_result' = 100.0%
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'[0]: Additional promise info: version '1.0' source path './30_generic_methods/command_execution_result.cf' at line 38
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phA'[0]: Method 'command_execution_result' verified
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods: Evaluating promise 'phB'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result: Augment scope 'command_execution_result' with variable 'command' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result: Augment scope 'command_execution_result' with variable 'kept_codes' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result: Augment scope 'command_execution_result' with variable 'repaired_codes' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/vars: Evaluating promise 'class_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/vars: Evaluating promise 'kept_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/vars: Evaluating promise 'repaired_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result: Evaluating bundle pass 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/vars: Evaluating promise 'class_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/vars: Evaluating promise 'kept_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/vars: Evaluating promise 'repaired_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods: Evaluating promise 'report'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods: Skipping next promise 'report', as ifvarclass 'command_execution_result_exit_2_reached' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/commands: Evaluating promise '${command}'
2016-02-08T09:24:38+0100 info: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/commands/'exit 2'[0]: Executing 'no timeout' ... 'exit 2'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/commands/'exit 2'[0]: (Setting umask to 77)
2016-02-08T09:24:38+0100 info: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/commands/'exit 2'[0]: Command related to promiser 'exit 2' returned code defined as promise kept 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/commands/'exit 2'[0]: Defining promise result class 'promise_kept_command_execution_result_exit_2'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/commands/'exit 2'[0]: Defining promise result class 'command_execution_result_exit_2_kept'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/commands/'exit 2'[0]: Defining promise result class 'command_execution_result_exit_2_ok'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/commands/'exit 2'[0]: Defining promise result class 'command_execution_result_exit_2_not_repaired'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/commands/'exit 2'[0]: Defining promise result class 'command_execution_result_exit_2_reached'
2016-02-08T09:24:38+0100 info: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/commands/'exit 2'[0]: Completed execution of 'exit 2'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result: Evaluating bundle pass 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/vars: Evaluating promise 'class_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/vars: Evaluating promise 'kept_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/vars: Evaluating promise 'repaired_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods: Evaluating promise 'report'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger: Augment scope '_logger' with variable 'message' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger: Augment scope '_logger' with variable 'class_prefix' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger: Evaluating bundle pass 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods: Evaluating promise 'logging'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Augment scope '_bundle_caller_two' with variable 'method' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Augment scope '_bundle_caller_two' with variable 'first_argument' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Augment scope '_bundle_caller_two' with variable 'second_argument' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Evaluating bundle pass 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods: Evaluating promise '${method}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'[0]: Comment 'Call the _logger_default bundle with arguments Execute the command exit 2, {second_argument}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default: Augment scope '_logger_default' with variable 'message' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default: Augment scope '_logger_default' with variable 'class_prefix' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/vars: Evaluating promise 'canonified_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default: Evaluating bundle pass 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/vars: Evaluating promise 'canonified_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.debug} Promise kept, not doing anything: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration.debug} Promise kept, not doing anything: ${message}', as context 'debug' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.info} Promise repaired, made a change: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration.info} Promise repaired, made a change: ${message}', as context 'debug|info' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.error} Promise could not be repaired, error encountered: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration#error} Promise could not be repaired, error encountered: ${message}', as ifvarclass 'command_execution_result_exit_2_not_ok' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default: Evaluating bundle pass 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/vars: Evaluating promise 'canonified_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.debug} Promise kept, not doing anything: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration.debug} Promise kept, not doing anything: ${message}', as context 'debug' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.info} Promise repaired, made a change: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration.info} Promise repaired, made a change: ${message}', as context 'debug|info' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.error} Promise could not be repaired, error encountered: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration#error} Promise could not be repaired, error encountered: ${message}', as ifvarclass 'command_execution_result_exit_2_not_ok' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default: Evaluating bundle pass 3
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/vars: Evaluating promise 'canonified_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.debug} Promise kept, not doing anything: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration.debug} Promise kept, not doing anything: ${message}', as context 'debug' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.info} Promise repaired, made a change: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration.info} Promise repaired, made a change: ${message}', as context 'debug|info' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.error} Promise could not be repaired, error encountered: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration#error} Promise could not be repaired, error encountered: ${message}', as ifvarclass 'command_execution_result_exit_2_not_ok' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default: Zero promises executed for bundle '_logger_default'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'[0]: Additional promise info: version '1.0' source path 'ncf/tests/acceptance/../../tree/30_generic_methods/_bundle_caller_two.cf' at line 30 comment 'Call the _logger_default bundle with arguments Execute the command exit 2, {second_argument}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'[0]: Method '_logger_default' verified
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Evaluating bundle pass 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods: Evaluating promise '${method}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Evaluating bundle pass 3
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods: Evaluating promise '${method}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Bundle Accounting Summary for '_bundle_caller_two'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Promises kept in '_bundle_caller_two' = 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Promises not kept in '_bundle_caller_two' = 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Promises repaired in '_bundle_caller_two' = 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Aggregate compliance (promises kept/repaired) for bundle '_bundle_caller_two' = 100.0%
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'[0]: Additional promise info: version '1.0' source path 'ncf/tests/acceptance/../../tree/30_generic_methods/_logger.cf' at line 36
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'[0]: Method '_bundle_caller_two' verified
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger: Evaluating bundle pass 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods: Evaluating promise 'logging'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger: Evaluating bundle pass 3
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger/methods: Evaluating promise 'logging'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger: Bundle Accounting Summary for '_logger'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger: Promises kept in '_logger' = 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger: Promises not kept in '_logger' = 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger: Promises repaired in '_logger' = 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'/default/_logger: Aggregate compliance (promises kept/repaired) for bundle '_logger' = 100.0%
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'[0]: Additional promise info: version '1.0' source path 'ncf/tests/acceptance/../../tree/30_generic_methods/command_execution_result.cf' at line 43
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods/'report'[0]: Method '_logger' verified
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/commands: Evaluating promise '${command}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result: Evaluating bundle pass 3
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/vars: Evaluating promise 'class_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/vars: Evaluating promise 'kept_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/vars: Evaluating promise 'repaired_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/methods: Evaluating promise 'report'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result/commands: Evaluating promise '${command}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result: Bundle Accounting Summary for 'command_execution_result'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result: Promises kept in 'command_execution_result' = 4
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result: Promises not kept in 'command_execution_result' = 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result: Promises repaired in 'command_execution_result' = 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'/default/command_execution_result: Aggregate compliance (promises kept/repaired) for bundle 'command_execution_result' = 100.0%
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'[0]: Additional promise info: version '1.0' source path './30_generic_methods/command_execution_result.cf' at line 39
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phB'[0]: Method 'command_execution_result' verified
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods: Evaluating promise 'phC'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result: Augment scope 'command_execution_result' with variable 'command' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result: Augment scope 'command_execution_result' with variable 'kept_codes' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result: Augment scope 'command_execution_result' with variable 'repaired_codes' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/vars: Evaluating promise 'class_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/vars: Evaluating promise 'kept_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/vars: Evaluating promise 'repaired_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result: Evaluating bundle pass 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/vars: Evaluating promise 'class_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/vars: Evaluating promise 'kept_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/vars: Evaluating promise 'repaired_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods: Evaluating promise 'report'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods: Skipping next promise 'report', as ifvarclass 'command_execution_result_exit_3_reached' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/commands: Evaluating promise '${command}'
2016-02-08T09:24:38+0100 info: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/commands/'exit 3'[0]: Executing 'no timeout' ... 'exit 3'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/commands/'exit 3'[0]: (Setting umask to 77)
2016-02-08T09:24:38+0100 info: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/commands/'exit 3'[0]: Command related to promiser 'exit 3' returned code defined as promise repaired 3
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/commands/'exit 3'[0]: Defining promise result class 'promise_repaired_command_execution_result_exit_3'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/commands/'exit 3'[0]: Defining promise result class 'command_execution_result_exit_3_repaired'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/commands/'exit 3'[0]: Defining promise result class 'command_execution_result_exit_3_ok'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/commands/'exit 3'[0]: Defining promise result class 'command_execution_result_exit_3_reached'
2016-02-08T09:24:38+0100 info: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/commands/'exit 3'[0]: Completed execution of 'exit 3'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result: Evaluating bundle pass 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/vars: Evaluating promise 'class_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/vars: Evaluating promise 'kept_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/vars: Evaluating promise 'repaired_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods: Evaluating promise 'report'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger: Augment scope '_logger' with variable 'message' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger: Augment scope '_logger' with variable 'class_prefix' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger: Evaluating bundle pass 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods: Evaluating promise 'logging'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Augment scope '_bundle_caller_two' with variable 'method' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Augment scope '_bundle_caller_two' with variable 'first_argument' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Augment scope '_bundle_caller_two' with variable 'second_argument' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Evaluating bundle pass 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods: Evaluating promise '${method}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'[0]: Comment 'Call the _logger_default bundle with arguments Execute the command exit 3, {second_argument}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default: Augment scope '_logger_default' with variable 'message' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default: Augment scope '_logger_default' with variable 'class_prefix' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/vars: Evaluating promise 'canonified_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default: Evaluating bundle pass 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/vars: Evaluating promise 'canonified_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.debug} Promise kept, not doing anything: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration.debug} Promise kept, not doing anything: ${message}', as context 'debug' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.info} Promise repaired, made a change: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration.info} Promise repaired, made a change: ${message}', as context 'debug|info' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.error} Promise could not be repaired, error encountered: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration#error} Promise could not be repaired, error encountered: ${message}', as ifvarclass 'command_execution_result_exit_3_not_ok' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default: Evaluating bundle pass 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/vars: Evaluating promise 'canonified_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.debug} Promise kept, not doing anything: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration.debug} Promise kept, not doing anything: ${message}', as context 'debug' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.info} Promise repaired, made a change: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration.info} Promise repaired, made a change: ${message}', as context 'debug|info' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.error} Promise could not be repaired, error encountered: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration#error} Promise could not be repaired, error encountered: ${message}', as ifvarclass 'command_execution_result_exit_3_not_ok' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default: Evaluating bundle pass 3
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/vars: Evaluating promise 'canonified_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.debug} Promise kept, not doing anything: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration.debug} Promise kept, not doing anything: ${message}', as context 'debug' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.info} Promise repaired, made a change: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration.info} Promise repaired, made a change: ${message}', as context 'debug|info' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.error} Promise could not be repaired, error encountered: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration#error} Promise could not be repaired, error encountered: ${message}', as ifvarclass 'command_execution_result_exit_3_not_ok' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default: Zero promises executed for bundle '_logger_default'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'[0]: Additional promise info: version '1.0' source path 'ncf/tests/acceptance/../../tree/30_generic_methods/_bundle_caller_two.cf' at line 30 comment 'Call the _logger_default bundle with arguments Execute the command exit 3, {second_argument}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'[0]: Method '_logger_default' verified
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Evaluating bundle pass 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods: Evaluating promise '${method}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Evaluating bundle pass 3
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods: Evaluating promise '${method}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Bundle Accounting Summary for '_bundle_caller_two'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Promises kept in '_bundle_caller_two' = 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Promises not kept in '_bundle_caller_two' = 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Promises repaired in '_bundle_caller_two' = 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Aggregate compliance (promises kept/repaired) for bundle '_bundle_caller_two' = 100.0%
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'[0]: Additional promise info: version '1.0' source path 'ncf/tests/acceptance/../../tree/30_generic_methods/_logger.cf' at line 36
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'[0]: Method '_bundle_caller_two' verified
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger: Evaluating bundle pass 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods: Evaluating promise 'logging'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger: Evaluating bundle pass 3
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger/methods: Evaluating promise 'logging'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger: Bundle Accounting Summary for '_logger'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger: Promises kept in '_logger' = 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger: Promises not kept in '_logger' = 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger: Promises repaired in '_logger' = 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'/default/_logger: Aggregate compliance (promises kept/repaired) for bundle '_logger' = 100.0%
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'[0]: Additional promise info: version '1.0' source path 'ncf/tests/acceptance/../../tree/30_generic_methods/command_execution_result.cf' at line 43
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods/'report'[0]: Method '_logger' verified
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/commands: Evaluating promise '${command}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result: Evaluating bundle pass 3
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/vars: Evaluating promise 'class_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/vars: Evaluating promise 'kept_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/vars: Evaluating promise 'repaired_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/methods: Evaluating promise 'report'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result/commands: Evaluating promise '${command}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result: Bundle Accounting Summary for 'command_execution_result'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result: Promises kept in 'command_execution_result' = 3
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result: Promises not kept in 'command_execution_result' = 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result: Promises repaired in 'command_execution_result' = 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'/default/command_execution_result: Aggregate compliance (promises kept/repaired) for bundle 'command_execution_result' = 100.0%
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'[0]: Additional promise info: version '1.0' source path './30_generic_methods/command_execution_result.cf' at line 40
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phC'[0]: Method 'command_execution_result' invoked repairs
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods: Evaluating promise 'phD'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result: Augment scope 'command_execution_result' with variable 'command' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result: Augment scope 'command_execution_result' with variable 'kept_codes' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result: Augment scope 'command_execution_result' with variable 'repaired_codes' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/vars: Evaluating promise 'class_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/vars: Evaluating promise 'kept_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/vars: Evaluating promise 'repaired_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result: Evaluating bundle pass 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/vars: Evaluating promise 'class_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/vars: Evaluating promise 'kept_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/vars: Evaluating promise 'repaired_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods: Evaluating promise 'report'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods: Skipping next promise 'report', as ifvarclass 'command_execution_result_exit_4_reached' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/commands: Evaluating promise '${command}'
2016-02-08T09:24:38+0100 info: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/commands/'exit 4'[0]: Executing 'no timeout' ... 'exit 4'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/commands/'exit 4'[0]: (Setting umask to 77)
2016-02-08T09:24:38+0100 info: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/commands/'exit 4'[0]: Command related to promiser 'exit 4' returned code defined as promise repaired 4
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/commands/'exit 4'[0]: Defining promise result class 'promise_repaired_command_execution_result_exit_4'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/commands/'exit 4'[0]: Defining promise result class 'command_execution_result_exit_4_repaired'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/commands/'exit 4'[0]: Defining promise result class 'command_execution_result_exit_4_ok'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/commands/'exit 4'[0]: Defining promise result class 'command_execution_result_exit_4_reached'
2016-02-08T09:24:38+0100 info: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/commands/'exit 4'[0]: Completed execution of 'exit 4'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result: Evaluating bundle pass 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/vars: Evaluating promise 'class_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/vars: Evaluating promise 'kept_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/vars: Evaluating promise 'repaired_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods: Evaluating promise 'report'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger: Augment scope '_logger' with variable 'message' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger: Augment scope '_logger' with variable 'class_prefix' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger: Evaluating bundle pass 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods: Evaluating promise 'logging'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Augment scope '_bundle_caller_two' with variable 'method' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Augment scope '_bundle_caller_two' with variable 'first_argument' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Augment scope '_bundle_caller_two' with variable 'second_argument' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Evaluating bundle pass 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods: Evaluating promise '${method}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'[0]: Comment 'Call the _logger_default bundle with arguments Execute the command exit 4, {second_argument}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default: Augment scope '_logger_default' with variable 'message' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default: Augment scope '_logger_default' with variable 'class_prefix' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/vars: Evaluating promise 'canonified_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default: Evaluating bundle pass 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/vars: Evaluating promise 'canonified_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.debug} Promise kept, not doing anything: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration.debug} Promise kept, not doing anything: ${message}', as context 'debug' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.info} Promise repaired, made a change: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration.info} Promise repaired, made a change: ${message}', as context 'debug|info' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.error} Promise could not be repaired, error encountered: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration#error} Promise could not be repaired, error encountered: ${message}', as ifvarclass 'command_execution_result_exit_4_not_ok' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default: Evaluating bundle pass 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/vars: Evaluating promise 'canonified_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.debug} Promise kept, not doing anything: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration.debug} Promise kept, not doing anything: ${message}', as context 'debug' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.info} Promise repaired, made a change: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration.info} Promise repaired, made a change: ${message}', as context 'debug|info' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.error} Promise could not be repaired, error encountered: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration#error} Promise could not be repaired, error encountered: ${message}', as ifvarclass 'command_execution_result_exit_4_not_ok' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default: Evaluating bundle pass 3
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/vars: Evaluating promise 'canonified_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.debug} Promise kept, not doing anything: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration.debug} Promise kept, not doing anything: ${message}', as context 'debug' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.info} Promise repaired, made a change: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration.info} Promise repaired, made a change: ${message}', as context 'debug|info' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Evaluating promise '${configuration.error} Promise could not be repaired, error encountered: ${message}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default/reports: Skipping next promise '${configuration#error} Promise could not be repaired, error encountered: ${message}', as ifvarclass 'command_execution_result_exit_4_not_ok' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'/default/_logger_default: Zero promises executed for bundle '_logger_default'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'[0]: Additional promise info: version '1.0' source path 'ncf/tests/acceptance/../../tree/30_generic_methods/_bundle_caller_two.cf' at line 30 comment 'Call the _logger_default bundle with arguments Execute the command exit 4, {second_argument}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods/'_logger_default'[0]: Method '_logger_default' verified
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Evaluating bundle pass 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods: Evaluating promise '${method}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Evaluating bundle pass 3
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two/methods: Evaluating promise '${method}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Bundle Accounting Summary for '_bundle_caller_two'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Promises kept in '_bundle_caller_two' = 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Promises not kept in '_bundle_caller_two' = 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Promises repaired in '_bundle_caller_two' = 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'/default/_bundle_caller_two: Aggregate compliance (promises kept/repaired) for bundle '_bundle_caller_two' = 100.0%
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'[0]: Additional promise info: version '1.0' source path 'ncf/tests/acceptance/../../tree/30_generic_methods/_logger.cf' at line 36
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods/'logging'[0]: Method '_bundle_caller_two' verified
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger: Evaluating bundle pass 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods: Evaluating promise 'logging'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger: Evaluating bundle pass 3
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger/methods: Evaluating promise 'logging'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger: Bundle Accounting Summary for '_logger'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger: Promises kept in '_logger' = 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger: Promises not kept in '_logger' = 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger: Promises repaired in '_logger' = 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'/default/_logger: Aggregate compliance (promises kept/repaired) for bundle '_logger' = 100.0%
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'[0]: Additional promise info: version '1.0' source path 'ncf/tests/acceptance/../../tree/30_generic_methods/command_execution_result.cf' at line 43
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods/'report'[0]: Method '_logger' verified
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/commands: Evaluating promise '${command}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result: Evaluating bundle pass 3
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/vars: Evaluating promise 'class_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/vars: Evaluating promise 'kept_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/vars: Evaluating promise 'repaired_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/methods: Evaluating promise 'report'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result/commands: Evaluating promise '${command}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result: Bundle Accounting Summary for 'command_execution_result'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result: Promises kept in 'command_execution_result' = 3
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result: Promises not kept in 'command_execution_result' = 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result: Promises repaired in 'command_execution_result' = 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'/default/command_execution_result: Aggregate compliance (promises kept/repaired) for bundle 'command_execution_result' = 100.0%
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'[0]: Additional promise info: version '1.0' source path './30_generic_methods/command_execution_result.cf' at line 41
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phD'[0]: Method 'command_execution_result' invoked repairs
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods: Evaluating promise 'phE'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result: Augment scope 'command_execution_result' with variable 'command' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result: Augment scope 'command_execution_result' with variable 'kept_codes' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result: Augment scope 'command_execution_result' with variable 'repaired_codes' (type: s)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result/vars: Evaluating promise 'class_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result/vars: Evaluating promise 'kept_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result/vars: Evaluating promise 'repaired_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result: Evaluating bundle pass 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result/vars: Evaluating promise 'class_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result/vars: Evaluating promise 'kept_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result/vars: Evaluating promise 'repaired_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result/methods: Evaluating promise 'report'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result/methods: Skipping next promise 'report', as ifvarclass 'command_execution_result_exit_5_reached' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result/commands: Evaluating promise '${command}'
2016-02-08T09:24:38+0100 info: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result/commands/'exit 5'[0]: Executing 'no timeout' ... 'exit 5'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result/commands/'exit 5'[0]: (Setting umask to 77)
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result/commands/'exit 5'[0]: Command related to promiser 'exit 5' returned code 5 -- did not match any failed, repaired or kept lists
2016-02-08T09:24:38+0100 info: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result/commands/'exit 5'[0]: Completed execution of 'exit 5'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result: Evaluating bundle pass 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result/vars: Evaluating promise 'class_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result/vars: Evaluating promise 'kept_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result/vars: Evaluating promise 'repaired_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result/methods: Evaluating promise 'report'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result/methods: Skipping next promise 'report', as ifvarclass 'command_execution_result_exit_5_reached' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result/commands: Evaluating promise '${command}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result: Evaluating bundle pass 3
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result/vars: Evaluating promise 'class_prefix'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result/vars: Evaluating promise 'kept_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result/vars: Evaluating promise 'repaired_list'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result/methods: Evaluating promise 'report'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result/methods: Skipping next promise 'report', as ifvarclass 'command_execution_result_exit_5_reached' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result/commands: Evaluating promise '${command}'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'/default/command_execution_result: Zero promises executed for bundle 'command_execution_result'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'[0]: Additional promise info: version '1.0' source path './30_generic_methods/command_execution_result.cf' at line 42
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods/'phE'[0]: Method 'command_execution_result' verified
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test: Evaluating bundle pass 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/vars: Evaluating promise 'kept_codes'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/vars: Evaluating promise 'repaired_codes'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods: Evaluating promise 'phA'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods: Evaluating promise 'phB'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods: Evaluating promise 'phC'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods: Evaluating promise 'phD'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods: Evaluating promise 'phE'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test: Evaluating bundle pass 3
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/vars: Evaluating promise 'kept_codes'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/vars: Evaluating promise 'repaired_codes'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods: Evaluating promise 'phA'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods: Evaluating promise 'phB'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods: Evaluating promise 'phC'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods: Evaluating promise 'phD'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test/methods: Evaluating promise 'phE'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test: Bundle Accounting Summary for 'test'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test: Promises kept in 'test' = 17
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test: Promises not kept in 'test' = 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test: Promises repaired in 'test' = 4
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/test: Aggregate compliance (promises kept/repaired) for bundle 'test' = 100.0%
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'[1]: Additional promise info: version '1.0' source path 'ncf/tests/acceptance/default.cf.sub' at line 78
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'[1]: Method 'test' invoked repairs
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check: Evaluating bundle pass 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/classes: Evaluating promise 'ok_A'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/classes/'ok_A'[0]: Adding local bundle class 'ok_A'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/classes: Evaluating promise 'ok_B'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/classes/'ok_B'[0]: Adding local bundle class 'ok_B'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/classes: Evaluating promise 'ok_C'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/classes/'ok_C'[0]: Adding local bundle class 'ok_C'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/classes: Evaluating promise 'ok_D'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/classes/'ok_D'[0]: Adding local bundle class 'ok_D'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/classes: Evaluating promise 'ok_E'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/classes: Evaluating promise 'ok'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check: Private classes augmented: ok_B ok_D ok_A ok_C
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/reports: Evaluating promise '$(this.promise_filename) Pass'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/reports: Skipping next promise '$(this.promise_filename) Pass', as context 'ok' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/reports: Evaluating promise '$(this.promise_filename) FAIL'
R: ncf/tests/acceptance/./30_generic_methods/command_execution_result.cf FAIL
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check: Evaluating bundle pass 2
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/classes: Evaluating promise 'ok_A'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/classes: Evaluating promise 'ok_B'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/classes: Evaluating promise 'ok_C'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/classes: Evaluating promise 'ok_D'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/classes: Evaluating promise 'ok_E'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/classes: Evaluating promise 'ok'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check: Private classes augmented: ok_B ok_D ok_A ok_C
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/reports: Evaluating promise '$(this.promise_filename) Pass'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/reports: Skipping next promise '$(this.promise_filename) Pass', as context 'ok' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/reports: Evaluating promise '$(this.promise_filename) FAIL'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check: Evaluating bundle pass 3
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/classes: Evaluating promise 'ok_A'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/classes: Evaluating promise 'ok_B'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/classes: Evaluating promise 'ok_C'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/classes: Evaluating promise 'ok_D'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/classes: Evaluating promise 'ok_E'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/classes: Evaluating promise 'ok'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check: Private classes augmented: ok_B ok_D ok_A ok_C
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/reports: Evaluating promise '$(this.promise_filename) Pass'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/reports: Skipping next promise '$(this.promise_filename) Pass', as context 'ok' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check/reports: Evaluating promise '$(this.promise_filename) FAIL'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check: Bundle Accounting Summary for 'check'
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check: Promises kept in 'check' = 1
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check: Promises not kept in 'check' = 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check: Promises repaired in 'check' = 0
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'/default/check: Aggregate compliance (promises kept/repaired) for bundle 'check' = 100.0%
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'[2]: Additional promise info: version '1.0' source path 'ncf/tests/acceptance/default.cf.sub' at line 78
2016-02-08T09:24:38+0100 verbose: /default/default/methods/'any'[2]: Method 'check' verified
2016-02-08T09:24:38+0100 verbose: /default/default/reports: Evaluating promise '# You must either specify '-D AUTO' or run the following commands:'
2016-02-08T09:24:38+0100 verbose: /default/default/reports: Skipping next promise '# You must either specify '-D AUTO' or run the following commands:', as context '!AUTO' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/reports: Evaluating promise 'cf-agent -f .$(DS)$(filename) -b $(tests)'
2016-02-08T09:24:38+0100 verbose: /default/default/reports: Skipping next promise 'cf-agent -f .$(DS)$(filename) -b $(tests)', as context '!AUTO' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default: Evaluating bundle pass 2
2016-02-08T09:24:38+0100 verbose: /default/default/vars: Evaluating promise 'tests'
2016-02-08T09:24:38+0100 verbose: /default/default/methods: Evaluating promise 'any'
2016-02-08T09:24:38+0100 verbose: /default/default/methods: Evaluating promise 'any'
2016-02-08T09:24:38+0100 verbose: /default/default/methods: Evaluating promise 'any'
2016-02-08T09:24:38+0100 verbose: /default/default/reports: Evaluating promise '# You must either specify '-D AUTO' or run the following commands:'
2016-02-08T09:24:38+0100 verbose: /default/default/reports: Skipping next promise '# You must either specify '-D AUTO' or run the following commands:', as context '!AUTO' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/reports: Evaluating promise 'cf-agent -f .$(DS)$(filename) -b $(tests)'
2016-02-08T09:24:38+0100 verbose: /default/default/reports: Skipping next promise 'cf-agent -f .$(DS)$(filename) -b $(tests)', as context '!AUTO' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default: Evaluating bundle pass 3
2016-02-08T09:24:38+0100 verbose: /default/default/vars: Evaluating promise 'tests'
2016-02-08T09:24:38+0100 verbose: /default/default/methods: Evaluating promise 'any'
2016-02-08T09:24:38+0100 verbose: /default/default/methods: Evaluating promise 'any'
2016-02-08T09:24:38+0100 verbose: /default/default/methods: Evaluating promise 'any'
2016-02-08T09:24:38+0100 verbose: /default/default/reports: Evaluating promise '# You must either specify '-D AUTO' or run the following commands:'
2016-02-08T09:24:38+0100 verbose: /default/default/reports: Skipping next promise '# You must either specify '-D AUTO' or run the following commands:', as context '!AUTO' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default/reports: Evaluating promise 'cf-agent -f .$(DS)$(filename) -b $(tests)'
2016-02-08T09:24:38+0100 verbose: /default/default/reports: Skipping next promise 'cf-agent -f .$(DS)$(filename) -b $(tests)', as context '!AUTO' is not relevant
2016-02-08T09:24:38+0100 verbose: /default/default: Bundle Accounting Summary for 'default'
2016-02-08T09:24:38+0100 verbose: /default/default: Promises kept in 'default' = 22
2016-02-08T09:24:38+0100 verbose: /default/default: Promises not kept in 'default' = 0
2016-02-08T09:24:38+0100 verbose: /default/default: Promises repaired in 'default' = 5
2016-02-08T09:24:38+0100 verbose: /default/default: Aggregate compliance (promises kept/repaired) for bundle 'default' = 100.0%
2016-02-08T09:24:38+0100 verbose: Looking for stale locks to purge
2016-02-08T09:24:38+0100 verbose: Logging total compliance, total 'Outcome of version 1.0 (agent-0): Promises observed to be kept 74.19%, Promises repaired 25.81%, Promises not repaired 0.00%'

Return code is 0.

==> FAIL


======================================================================
Testsuite finished at 2016-02-08 09:24:39 (4 seconds)

Passed tests: 0
Failed tests: 1
Failed to crash tests: 0
Skipped tests: 0
    (1-1/1)