Quiet mode disabled
[0.000000000000:ASSIGN] Assign cpal_system new value: Scheduling_Policy.FIFO
[0.000000000000:subprocess:STARTING_TASK] 'p0'
[0.000000000000:STATE] process "Master", instance "p0", state "Main"
[0.000000000000:PRINTLN] Master - common
[0.000000000000:PRINTLN] Sub - common
[0.000000000000:PRINTLN] Sub - First - a: 166
[0.000000000000:PRINTLN] Sub - finally
[0.000000000000:PRINTLN] Master - finally
[0.000000000000:subprocess:TASK_FINISHED] 'p0'
[0.100000000000:subprocess:STARTING_TASK] 'p0'
[0.100000000000:PRINTLN] Master - common
[0.100000000000:PRINTLN] Sub - common
[0.100000000000:PRINTLN] Sub - Second
[0.100000000000:PRINTLN] Sub - finally
[0.100000000000:PRINTLN] Master - finally
[0.100000000000:subprocess:TASK_FINISHED] 'p0'
[0.200000000000:subprocess:STARTING_TASK] 'p0'
[0.200000000000:PRINTLN] Master - common
[0.200000000000:PRINTLN] Sub - common
[0.200000000000:PRINTLN] Sub - First - a: 248
[0.200000000000:PRINTLN] Sub - finally
[0.200000000000:PRINTLN] Master - finally
[0.200000000000:subprocess:TASK_FINISHED] 'p0'
[0.300000000000:subprocess:STARTING_TASK] 'p0'
[0.300000000000:PRINTLN] Master - common
[0.300000000000:PRINTLN] Sub - common
[0.300000000000:PRINTLN] Sub - Second
[0.300000000000:PRINTLN] Sub - finally
[0.300000000000:PRINTLN] Master - finally
[0.300000000000:subprocess:TASK_FINISHED] 'p0'
[0.400000000000:subprocess:STARTING_TASK] 'p0'
[0.400000000000:PRINTLN] Master - common
[0.400000000000:PRINTLN] Sub - common
[0.400000000000:PRINTLN] Sub - First - a: 204
[0.400000000000:PRINTLN] Sub - finally
[0.400000000000:PRINTLN] Master - finally
[0.400000000000:subprocess:TASK_FINISHED] 'p0'
[0.500000000000:subprocess:STARTING_TASK] 'p0'
[0.500000000000:PRINTLN] Master - common
[0.500000000000:PRINTLN] Sub - common
[0.500000000000:PRINTLN] Sub - Second
[0.500000000000:PRINTLN] Sub - finally
[0.500000000000:PRINTLN] Master - finally
[0.500000000000:subprocess:TASK_FINISHED] 'p0'
[0.600000000000:subprocess:STARTING_TASK] 'p0'
[0.600000000000:PRINTLN] Master - common
[0.600000000000:PRINTLN] Sub - common
[0.600000000000:PRINTLN] Sub - First - a: 33
[0.600000000000:PRINTLN] Sub - finally
[0.600000000000:PRINTLN] Master - finally
[0.600000000000:subprocess:TASK_FINISHED] 'p0'
[0.700000000000:subprocess:STARTING_TASK] 'p0'
[0.700000000000:PRINTLN] Master - common
[0.700000000000:PRINTLN] Sub - common
[0.700000000000:PRINTLN] Sub - Second
[0.700000000000:PRINTLN] Sub - finally
[0.700000000000:PRINTLN] Master - finally
[0.700000000000:subprocess:TASK_FINISHED] 'p0'
[0.800000000000:subprocess:STARTING_TASK] 'p0'
[0.800000000000:PRINTLN] Master - common
[0.800000000000:PRINTLN] Sub - common
[0.800000000000:PRINTLN] Sub - First - a: 204
[0.800000000000:PRINTLN] Sub - finally
[0.800000000000:PRINTLN] Master - finally
[0.800000000000:subprocess:TASK_FINISHED] 'p0'
[0.900000000000:subprocess:STARTING_TASK] 'p0'
[0.900000000000:PRINTLN] Master - common
[0.900000000000:PRINTLN] Sub - common
[0.900000000000:PRINTLN] Sub - Second
[0.900000000000:PRINTLN] Sub - finally
[0.900000000000:PRINTLN] Master - finally
[0.900000000000:subprocess:TASK_FINISHED] 'p0'
[1.000000000000:subprocess:STARTING_TASK] 'p0'
[1.000000000000:PRINTLN] Master - common
[1.000000000000:PRINTLN] Sub - common
[1.000000000000:PRINTLN] Sub - First - a: 209
[1.000000000000:PRINTLN] Sub - finally
[1.000000000000:PRINTLN] Master - finally
[1.000000000000:subprocess:TASK_FINISHED] 'p0'
[1.100000000000:subprocess:STARTING_TASK] 'p0'
[1.100000000000:PRINTLN] Master - common
[1.100000000000:PRINTLN] Sub - common
[1.100000000000:PRINTLN] Sub - Second
[1.100000000000:PRINTLN] Sub - finally
[1.100000000000:PRINTLN] Master - finally
[1.100000000000:subprocess:TASK_FINISHED] 'p0'
[1.200000000000:subprocess:STARTING_TASK] 'p0'
[1.200000000000:PRINTLN] Master - common
[1.200000000000:PRINTLN] Sub - common
[1.200000000000:PRINTLN] Sub - First - a: 112
[1.200000000000:PRINTLN] Sub - finally
[1.200000000000:PRINTLN] Master - finally
[1.200000000000:subprocess:TASK_FINISHED] 'p0'
[1.300000000000:subprocess:STARTING_TASK] 'p0'
[1.300000000000:PRINTLN] Master - common
[1.300000000000:PRINTLN] Sub - common
[1.300000000000:PRINTLN] Sub - Second
[1.300000000000:PRINTLN] Sub - finally
[1.300000000000:PRINTLN] Master - finally
[1.300000000000:subprocess:TASK_FINISHED] 'p0'
[1.400000000000:subprocess:STARTING_TASK] 'p0'
[1.400000000000:PRINTLN] Master - common
[1.400000000000:PRINTLN] Sub - common
[1.400000000000:PRINTLN] Sub - First - a: 44
[1.400000000000:PRINTLN] Sub - finally
[1.400000000000:PRINTLN] Master - finally
[1.400000000000:subprocess:TASK_FINISHED] 'p0'
[1.500000000000:subprocess:STARTING_TASK] 'p0'
[1.500000000000:PRINTLN] Master - common
[1.500000000000:PRINTLN] Sub - common
[1.500000000000:PRINTLN] Sub - Second
[1.500000000000:PRINTLN] Sub - finally
[1.500000000000:PRINTLN] Master - finally
[1.500000000000:subprocess:TASK_FINISHED] 'p0'
[1.600000000000:subprocess:STARTING_TASK] 'p0'
[1.600000000000:PRINTLN] Master - common
[1.600000000000:PRINTLN] Sub - common
[1.600000000000:PRINTLN] Sub - First - a: 18
[1.600000000000:PRINTLN] Sub - finally
[1.600000000000:PRINTLN] Master - finally
[1.600000000000:subprocess:TASK_FINISHED] 'p0'
[1.700000000000:subprocess:STARTING_TASK] 'p0'
[1.700000000000:PRINTLN] Master - common
[1.700000000000:PRINTLN] Sub - common
[1.700000000000:PRINTLN] Sub - Second
[1.700000000000:PRINTLN] Sub - finally
[1.700000000000:PRINTLN] Master - finally
[1.700000000000:subprocess:TASK_FINISHED] 'p0'
[1.800000000000:subprocess:STARTING_TASK] 'p0'
[1.800000000000:PRINTLN] Master - common
[1.800000000000:PRINTLN] Sub - common
[1.800000000000:PRINTLN] Sub - First - a: 61
[1.800000000000:PRINTLN] Sub - finally
[1.800000000000:PRINTLN] Master - finally
[1.800000000000:subprocess:TASK_FINISHED] 'p0'
[1.900000000000:subprocess:STARTING_TASK] 'p0'
[1.900000000000:PRINTLN] Master - common
[1.900000000000:PRINTLN] Sub - common
[1.900000000000:PRINTLN] Sub - Second
[1.900000000000:PRINTLN] Sub - finally
[1.900000000000:PRINTLN] Master - finally
[1.900000000000:subprocess:TASK_FINISHED] 'p0'
[2.000000000000:subprocess:STARTING_TASK] 'p0'
[2.000000000000:PRINTLN] Master - common
[2.000000000000:PRINTLN] Sub - common
[2.000000000000:PRINTLN] Sub - First - a: 226
[2.000000000000:PRINTLN] Sub - finally
[2.000000000000:PRINTLN] Master - finally
[2.000000000000:subprocess:TASK_FINISHED] 'p0'
[2.100000000000:subprocess:STARTING_TASK] 'p0'
[2.100000000000:PRINTLN] Master - common
[2.100000000000:PRINTLN] Sub - common
[2.100000000000:PRINTLN] Sub - Second
[2.100000000000:PRINTLN] Sub - finally
[2.100000000000:PRINTLN] Master - finally
[2.100000000000:subprocess:TASK_FINISHED] 'p0'
[2.200000000000:subprocess:STARTING_TASK] 'p0'
[2.200000000000:PRINTLN] Master - common
[2.200000000000:PRINTLN] Sub - common
[2.200000000000:PRINTLN] Sub - First - a: 33
[2.200000000000:PRINTLN] Sub - finally
[2.200000000000:PRINTLN] Master - finally
[2.200000000000:subprocess:TASK_FINISHED] 'p0'
[2.300000000000:subprocess:STARTING_TASK] 'p0'
[2.300000000000:PRINTLN] Master - common
[2.300000000000:PRINTLN] Sub - common
[2.300000000000:PRINTLN] Sub - Second
[2.300000000000:PRINTLN] Sub - finally
[2.300000000000:PRINTLN] Master - finally
[2.300000000000:subprocess:TASK_FINISHED] 'p0'
[2.400000000000:subprocess:STARTING_TASK] 'p0'
[2.400000000000:PRINTLN] Master - common
[2.400000000000:PRINTLN] Sub - common
[2.400000000000:PRINTLN] Sub - First - a: 55
[2.400000000000:PRINTLN] Sub - finally
[2.400000000000:PRINTLN] Master - finally
[2.400000000000:subprocess:TASK_FINISHED] 'p0'
[2.500000000000:subprocess:STARTING_TASK] 'p0'
[2.500000000000:PRINTLN] Master - common
[2.500000000000:PRINTLN] Sub - common
[2.500000000000:PRINTLN] Sub - Second
[2.500000000000:PRINTLN] Sub - finally
[2.500000000000:PRINTLN] Master - finally
[2.500000000000:subprocess:TASK_FINISHED] 'p0'
[2.600000000000:subprocess:STARTING_TASK] 'p0'
[2.600000000000:PRINTLN] Master - common
[2.600000000000:PRINTLN] Sub - common
[2.600000000000:PRINTLN] Sub - First - a: 126
[2.600000000000:PRINTLN] Sub - finally
[2.600000000000:PRINTLN] Master - finally
[2.600000000000:subprocess:TASK_FINISHED] 'p0'
[2.700000000000:subprocess:STARTING_TASK] 'p0'
[2.700000000000:PRINTLN] Master - common
[2.700000000000:PRINTLN] Sub - common
[2.700000000000:PRINTLN] Sub - Second
[2.700000000000:PRINTLN] Sub - finally
[2.700000000000:PRINTLN] Master - finally
[2.700000000000:subprocess:TASK_FINISHED] 'p0'
[2.800000000000:subprocess:STARTING_TASK] 'p0'
[2.800000000000:PRINTLN] Master - common
[2.800000000000:PRINTLN] Sub - common
[2.800000000000:PRINTLN] Sub - First - a: 36
[2.800000000000:PRINTLN] Sub - finally
[2.800000000000:PRINTLN] Master - finally
[2.800000000000:subprocess:TASK_FINISHED] 'p0'
[2.900000000000:subprocess:STARTING_TASK] 'p0'
[2.900000000000:PRINTLN] Master - common
[2.900000000000:PRINTLN] Sub - common
[2.900000000000:PRINTLN] Sub - Second
[2.900000000000:PRINTLN] Sub - finally
[2.900000000000:PRINTLN] Master - finally
[2.900000000000:subprocess:TASK_FINISHED] 'p0'
[3.000000000000:subprocess:STARTING_TASK] 'p0'
[3.000000000000:PRINTLN] Master - common
[3.000000000000:PRINTLN] Sub - common
[3.000000000000:PRINTLN] Sub - First - a: 164
[3.000000000000:PRINTLN] Sub - finally
[3.000000000000:PRINTLN] Master - finally
[3.000000000000:subprocess:TASK_FINISHED] 'p0'
EXIT (SUCCESS) AT 3s (3000000000000)