AWX Debug Logs v6.0.0

gistfile1.txt

2019-09-09 18:38:43,488 DEBUG    awx.main.dispatch delivered 411a8b3e-b2c3-41b9-b089-f228fd083f54 to worker[30685] qsize 0
2019-09-09 18:38:43,496 DEBUG    awx.main.dispatch task 411a8b3e-b2c3-41b9-b089-f228fd083f54 starting awx.main.tasks.update_inventory_computed_fields(*[6, True])
2019-09-09 18:38:43,523 DEBUG    awx.main.models.inventory Going to update inventory computed fields, pk=6
2019-09-09 18:38:43,647 DEBUG    awx.main.models.inventory Finished updating inventory computed fields, pk=6, in 0.122 seconds
2019-09-09 18:38:43,650 DEBUG    awx.main.dispatch task 411a8b3e-b2c3-41b9-b089-f228fd083f54 is finished
2019-09-09 18:38:43,822 DEBUG    awx.main.dispatch delivered 2b007533-5aca-46c8-a63c-3e1d3ec5daea to worker[7809] qsize 0
2019-09-09 18:38:43,829 DEBUG    awx.main.dispatch task 2b007533-5aca-46c8-a63c-3e1d3ec5daea starting awx.main.scheduler.tasks.run_task_manager(*[])
2019-09-09 18:38:43,835 DEBUG    awx.main.scheduler Running Tower task manager.
2019-09-09 18:38:43,863 DEBUG    awx.main.scheduler Starting Scheduler
2019-09-09 18:38:44,129 DEBUG    awx.main.scheduler Spawned project_update 387 (pending) as dependency of job 386 (pending)
2019-09-09 18:38:44,186 DEBUG    awx.main.scheduler Starting dependent project_update 387 (pending) in group tower instance awx
2019-09-09 18:38:44,194 DEBUG    awx.main.scheduler Submitting project_update 387 (waiting) to <instance group, instance> <1,awx>.
2019-09-09 18:38:44,279 DEBUG    awx.main.scheduler project_update 387 (waiting) consumed 1 capacity units from tower with prior total of 0
2019-09-09 18:38:44,282 DEBUG    awx.main.scheduler job 386 (pending) is blocked from running
2019-09-09 18:38:44,300 DEBUG    awx.main.dispatch publish awx.main.tasks.RunProjectUpdate(8e41c6c8-ae06-4d08-aa13-557129c1a174, queue=awx)
2019-09-09 18:38:44,340 DEBUG    awx.main.dispatch delivered 8e41c6c8-ae06-4d08-aa13-557129c1a174 to worker[23698] qsize 0
2019-09-09 18:38:44,343 DEBUG    awx.main.dispatch task 2b007533-5aca-46c8-a63c-3e1d3ec5daea is finished
2019-09-09 18:38:44,348 DEBUG    awx.main.dispatch task 8e41c6c8-ae06-4d08-aa13-557129c1a174 starting awx.main.tasks.RunProjectUpdate(*[387])
2019-09-09 18:38:52,919 DEBUG    awx.main.dispatch publish awx.main.tasks.awx_periodic_scheduler(93c3c703-185a-42ac-aa4e-772c26ef08e3, queue=awx_private_queue)
[2019-09-09 18:38:52,919: DEBUG/Process-1] publish awx.main.tasks.awx_periodic_scheduler(93c3c703-185a-42ac-aa4e-772c26ef08e3, queue=awx_private_queue)
2019-09-09 18:38:52,985 DEBUG    awx.main.dispatch delivered 93c3c703-185a-42ac-aa4e-772c26ef08e3 to worker[30685] qsize 0
2019-09-09 18:38:52,998 DEBUG    awx.main.dispatch task 93c3c703-185a-42ac-aa4e-772c26ef08e3 starting awx.main.tasks.awx_periodic_scheduler(*[])
2019-09-09 18:38:53,021 DEBUG    awx.main.tasks Starting periodic scheduler
2019-09-09 18:38:53,029 DEBUG    awx.main.tasks Last scheduler run was: 2019-09-09 18:38:22.967965+00:00
2019-09-09 18:38:53,058 DEBUG    awx.main.dispatch task 93c3c703-185a-42ac-aa4e-772c26ef08e3 is finished
2019-09-09 18:38:55,279 DEBUG    awx.main.dispatch publish awx.main.scheduler.tasks.run_task_manager(4d3749ab-fcf8-4878-9c43-06ded65f9a10, queue=awx_private_queue)
[2019-09-09 18:38:55,279: DEBUG/Process-1] publish awx.main.scheduler.tasks.run_task_manager(4d3749ab-fcf8-4878-9c43-06ded65f9a10, queue=awx_private_queue)
2019-09-09 18:38:55,318 DEBUG    awx.main.dispatch delivered 4d3749ab-fcf8-4878-9c43-06ded65f9a10 to worker[31813] qsize 0
2019-09-09 18:38:55,323 DEBUG    awx.main.dispatch task 4d3749ab-fcf8-4878-9c43-06ded65f9a10 starting awx.main.scheduler.tasks.run_task_manager(*[])
2019-09-09 18:38:55,329 DEBUG    awx.main.scheduler Running Tower task manager.
2019-09-09 18:38:55,358 DEBUG    awx.main.scheduler Starting Scheduler
2019-09-09 18:38:55,497 DEBUG    awx.main.scheduler Dependent project_update 387 (running) is blocked from running
2019-09-09 18:38:55,500 DEBUG    awx.main.scheduler job 386 (pending) is blocked from running
2019-09-09 18:38:55,505 DEBUG    awx.main.dispatch task 4d3749ab-fcf8-4878-9c43-06ded65f9a10 is finished
RESULT 2
OKREADY
2019-09-09 18:39:15,286 DEBUG    awx.main.dispatch publish awx.main.scheduler.tasks.run_task_manager(0eb6c653-e3ca-4997-baa0-c55d9bb34df7, queue=awx_private_queue)
[2019-09-09 18:39:15,286: DEBUG/Process-1] publish awx.main.scheduler.tasks.run_task_manager(0eb6c653-e3ca-4997-baa0-c55d9bb34df7, queue=awx_private_queue)
2019-09-09 18:39:15,322 DEBUG    awx.main.dispatch delivered 0eb6c653-e3ca-4997-baa0-c55d9bb34df7 to worker[30685] qsize 0
2019-09-09 18:39:15,331 DEBUG    awx.main.dispatch task 0eb6c653-e3ca-4997-baa0-c55d9bb34df7 starting awx.main.scheduler.tasks.run_task_manager(*[])
2019-09-09 18:39:15,336 DEBUG    awx.main.scheduler Running Tower task manager.
2019-09-09 18:39:15,363 DEBUG    awx.main.scheduler Starting Scheduler
2019-09-09 18:39:15,503 DEBUG    awx.main.scheduler Dependent project_update 387 (running) is blocked from running
2019-09-09 18:39:15,506 DEBUG    awx.main.scheduler job 386 (pending) is blocked from running
2019-09-09 18:39:15,510 DEBUG    awx.main.dispatch task 0eb6c653-e3ca-4997-baa0-c55d9bb34df7 is finished
2019-09-09 18:39:17,586 DEBUG    awx.main.dispatch publish awx.main.tasks.cluster_node_heartbeat(61f231bc-3a25-493f-8595-68b9cd098b9d, queue=awx)
[2019-09-09 18:39:17,586: DEBUG/Process-1] publish awx.main.tasks.cluster_node_heartbeat(61f231bc-3a25-493f-8595-68b9cd098b9d, queue=awx)
2019-09-09 18:39:17,663 DEBUG    awx.main.dispatch delivered 61f231bc-3a25-493f-8595-68b9cd098b9d to worker[7809] qsize 0
2019-09-09 18:39:18,065 DEBUG    awx.main.dispatch task 61f231bc-3a25-493f-8595-68b9cd098b9d starting awx.main.tasks.cluster_node_heartbeat(*[])
2019-09-09 18:39:18,073 DEBUG    awx.main.tasks Cluster node heartbeat task.
2019-09-09 18:39:18,119 DEBUG    awx.main.dispatch task 61f231bc-3a25-493f-8595-68b9cd098b9d is finished
2019-09-09 18:39:22,921 DEBUG    awx.main.dispatch publish awx.main.tasks.awx_periodic_scheduler(294a5e6d-5e57-4b1c-8c62-50d63f38cf6d, queue=awx_private_queue)
[2019-09-09 18:39:22,921: DEBUG/Process-1] publish awx.main.tasks.awx_periodic_scheduler(294a5e6d-5e57-4b1c-8c62-50d63f38cf6d, queue=awx_private_queue)
2019-09-09 18:39:22,957 DEBUG    awx.main.dispatch delivered 294a5e6d-5e57-4b1c-8c62-50d63f38cf6d to worker[31813] qsize 0
2019-09-09 18:39:22,964 DEBUG    awx.main.dispatch task 294a5e6d-5e57-4b1c-8c62-50d63f38cf6d starting awx.main.tasks.awx_periodic_scheduler(*[])
2019-09-09 18:39:22,981 DEBUG    awx.main.tasks Starting periodic scheduler
2019-09-09 18:39:22,987 DEBUG    awx.main.tasks Last scheduler run was: 2019-09-09 18:38:53.024122+00:00
2019-09-09 18:39:23,009 DEBUG    awx.main.dispatch task 294a5e6d-5e57-4b1c-8c62-50d63f38cf6d is finished
^[2019-09-09 18:39:35,304 DEBUG    awx.main.dispatch publish awx.main.scheduler.tasks.run_task_manager(79c93af8-658b-4b4e-8c33-10a2f2d989ed, queue=awx_private_queue)
[2019-09-09 18:39:35,304: DEBUG/Process-1] publish awx.main.scheduler.tasks.run_task_manager(79c93af8-658b-4b4e-8c33-10a2f2d989ed, queue=awx_private_queue)
2019-09-09 18:39:35,394 DEBUG    awx.main.dispatch delivered 79c93af8-658b-4b4e-8c33-10a2f2d989ed to worker[30685] qsize 0
2019-09-09 18:39:35,409 DEBUG    awx.main.dispatch task 79c93af8-658b-4b4e-8c33-10a2f2d989ed starting awx.main.scheduler.tasks.run_task_manager(*[])
2019-09-09 18:39:35,422 DEBUG    awx.main.scheduler Running Tower task manager.
2019-09-09 18:39:35,568 DEBUG    awx.main.scheduler Starting Scheduler
2019-09-09 18:39:35,970 DEBUG    awx.main.scheduler Dependent project_update 387 (running) is blocked from running
2019-09-09 18:39:35,974 DEBUG    awx.main.scheduler job 386 (pending) is blocked from running
2019-09-09 18:39:35,993 DEBUG    awx.main.dispatch task 79c93af8-658b-4b4e-8c33-10a2f2d989ed is finished
2019-09-09 18:39:48,244 INFO     awx.main.commands.run_callback_receiver Event processing is finished for Job 387, sending notifications
2019-09-09 18:39:48,244 INFO     awx.main.commands.run_callback_receiver Event processing is finished for Job 387, sending notifications
2019-09-09 18:39:48,341 DEBUG    awx.main.tasks project_update 387 (running) finished running, producing 60 events.
2019-09-09 18:39:48,792 DEBUG    awx.main.dispatch task 8e41c6c8-ae06-4d08-aa13-557129c1a174 starting awx.main.tasks.handle_work_success(*[])
2019-09-09 18:39:48,805 DEBUG    awx.main.dispatch publish awx.main.scheduler.tasks.run_task_manager(d7b1facb-6db9-4b07-9c25-394034344f1d, queue=awx_private_queue)
2019-09-09 18:39:48,841 DEBUG    awx.main.dispatch delivered d7b1facb-6db9-4b07-9c25-394034344f1d to worker[7809] qsize 0
2019-09-09 18:39:48,841 DEBUG    awx.main.dispatch task 8e41c6c8-ae06-4d08-aa13-557129c1a174 is finished
2019-09-09 18:39:48,847 DEBUG    awx.main.dispatch task d7b1facb-6db9-4b07-9c25-394034344f1d starting awx.main.scheduler.tasks.run_task_manager(*[])
2019-09-09 18:39:48,852 DEBUG    awx.main.scheduler Running Tower task manager.
2019-09-09 18:39:48,879 DEBUG    awx.main.scheduler Starting Scheduler
2019-09-09 18:39:49,071 DEBUG    awx.main.scheduler Starting job 386 (pending) in group tower instance awx (remaining_capacity=57)
2019-09-09 18:39:49,107 DEBUG    awx.main.scheduler Submitting job 386 (waiting) to <instance group, instance> <1,awx>.
2019-09-09 18:39:49,227 DEBUG    awx.main.scheduler job 386 (waiting) consumed 6 capacity units from tower with prior total of 0
2019-09-09 18:39:49,243 DEBUG    awx.main.dispatch publish awx.main.tasks.RunJob(afef2f63-89f8-4c38-b1bb-7f5849463af3, queue=awx)
2019-09-09 18:39:49,300 DEBUG    awx.main.dispatch delivered afef2f63-89f8-4c38-b1bb-7f5849463af3 to worker[23698] qsize 0
2019-09-09 18:39:49,308 DEBUG    awx.main.dispatch task afef2f63-89f8-4c38-b1bb-7f5849463af3 starting awx.main.tasks.RunJob(*[386])
2019-09-09 18:39:49,309 DEBUG    awx.main.dispatch task d7b1facb-6db9-4b07-9c25-394034344f1d is finished
2019-09-09 18:39:52,919 DEBUG    awx.main.dispatch publish awx.main.tasks.awx_periodic_scheduler(78fc2549-ac28-4391-8aa4-7002a8b125a7, queue=awx_private_queue)
[2019-09-09 18:39:52,919: DEBUG/Process-1] publish awx.main.tasks.awx_periodic_scheduler(78fc2549-ac28-4391-8aa4-7002a8b125a7, queue=awx_private_queue)
2019-09-09 18:39:52,934 DEBUG    awx.main.dispatch delivered 78fc2549-ac28-4391-8aa4-7002a8b125a7 to worker[30685] qsize 0
2019-09-09 18:39:52,936 DEBUG    awx.main.dispatch task 78fc2549-ac28-4391-8aa4-7002a8b125a7 starting awx.main.tasks.awx_periodic_scheduler(*[])
2019-09-09 18:39:52,944 DEBUG    awx.main.tasks Starting periodic scheduler
2019-09-09 18:39:52,946 DEBUG    awx.main.tasks Last scheduler run was: 2019-09-09 18:39:22.983521+00:00
2019-09-09 18:39:52,956 DEBUG    awx.main.dispatch task 78fc2549-ac28-4391-8aa4-7002a8b125a7 is finished
2019-09-09 18:39:55,304 DEBUG    awx.main.dispatch publish awx.main.scheduler.tasks.run_task_manager(6daa185c-54cb-4a46-8305-ed57f95ec316, queue=awx_private_queue)
[2019-09-09 18:39:55,304: DEBUG/Process-1] publish awx.main.scheduler.tasks.run_task_manager(6daa185c-54cb-4a46-8305-ed57f95ec316, queue=awx_private_queue)
2019-09-09 18:39:55,339 DEBUG    awx.main.dispatch delivered 6daa185c-54cb-4a46-8305-ed57f95ec316 to worker[30685] qsize 0
2019-09-09 18:39:55,348 DEBUG    awx.main.dispatch task 6daa185c-54cb-4a46-8305-ed57f95ec316 starting awx.main.scheduler.tasks.run_task_manager(*[])
2019-09-09 18:39:55,354 DEBUG    awx.main.scheduler Running Tower task manager.
2019-09-09 18:39:55,377 DEBUG    awx.main.scheduler Starting Scheduler
2019-09-09 18:39:55,459 DEBUG    awx.main.dispatch task 6daa185c-54cb-4a46-8305-ed57f95ec316 is finished
2019-09-09 18:39:57,151 INFO     awx.main.commands.run_callback_receiver Event processing is finished for Job 388, sending notifications
2019-09-09 18:39:57,151 INFO     awx.main.commands.run_callback_receiver Event processing is finished for Job 388, sending notifications
2019-09-09 18:39:57,248 DEBUG    awx.main.tasks project_update 388 (running) finished running, producing 21 events.
RESULT 2
OKREADY
^[2019-09-09 18:40:01,655 DEBUG    awx.main.models.inventory Going to update inventory computed fields, pk=6
2019-09-09 18:40:01,767 DEBUG    awx.main.models.inventory Finished updating inventory computed fields, pk=6, in 0.110 seconds
2019-09-09 18:40:01,997 INFO     awx.main.commands.run_callback_receiver Event processing is finished for Job 386, sending notifications
2019-09-09 18:40:01,997 INFO     awx.main.commands.run_callback_receiver Event processing is finished for Job 386, sending notifications
2019-09-09 18:40:02,095 DEBUG    awx.main.tasks job 386 (running) finished running, producing 9 events.
2019-09-09 18:40:02,335 DEBUG    awx.main.dispatch publish awx.main.tasks.update_inventory_computed_fields(bc154459-4c40-414b-8e9d-8fad87896a7d, queue=awx_private_queue)
2019-09-09 18:40:02,368 DEBUG    awx.main.dispatch delivered bc154459-4c40-414b-8e9d-8fad87896a7d to worker[31813] qsize 0
2019-09-09 18:40:02,374 DEBUG    awx.main.dispatch task bc154459-4c40-414b-8e9d-8fad87896a7d starting awx.main.tasks.update_inventory_computed_fields(*[6, True])
2019-09-09 18:40:02,379 WARNING  awx.main.dispatch job 386 (failed) encountered an error (rc=2), please see task stdout for details.
2019-09-09 18:40:02,386 DEBUG    awx.main.dispatch task afef2f63-89f8-4c38-b1bb-7f5849463af3 starting awx.main.tasks.handle_work_error(*['afef2f63-89f8-4c38-b1bb-7f5849463af3'])
2019-09-09 18:40:02,390 DEBUG    awx.main.tasks Executing error task id afef2f63-89f8-4c38-b1bb-7f5849463af3, subtasks: [{'type': 'job', 'id': 386}]
2019-09-09 18:40:02,402 DEBUG    awx.main.models.inventory Going to update inventory computed fields, pk=6
2019-09-09 18:40:02,402 DEBUG    awx.main.dispatch publish awx.main.scheduler.tasks.run_task_manager(844dd27c-04e6-4593-a7c9-3ba778e78493, queue=awx_private_queue)
2019-09-09 18:40:02,449 DEBUG    awx.main.dispatch task afef2f63-89f8-4c38-b1bb-7f5849463af3 starting awx.main.tasks.handle_work_success(*[])
2019-09-09 18:40:02,450 DEBUG    awx.main.dispatch delivered 844dd27c-04e6-4593-a7c9-3ba778e78493 to worker[7809] qsize 0
2019-09-09 18:40:02,456 DEBUG    awx.main.dispatch task 844dd27c-04e6-4593-a7c9-3ba778e78493 starting awx.main.scheduler.tasks.run_task_manager(*[])
2019-09-09 18:40:02,462 DEBUG    awx.main.scheduler Running Tower task manager.
2019-09-09 18:40:02,471 DEBUG    awx.main.dispatch publish awx.main.scheduler.tasks.run_task_manager(850244a5-f0df-4080-a97f-cd66c4b6035b, queue=awx_private_queue)
2019-09-09 18:40:02,489 DEBUG    awx.main.scheduler Starting Scheduler
2019-09-09 18:40:02,512 DEBUG    awx.main.dispatch task afef2f63-89f8-4c38-b1bb-7f5849463af3 is finished
2019-09-09 18:40:02,512 DEBUG    awx.main.dispatch delivered 850244a5-f0df-4080-a97f-cd66c4b6035b to worker[30685] qsize 0
2019-09-09 18:40:02,519 DEBUG    awx.main.dispatch task 850244a5-f0df-4080-a97f-cd66c4b6035b starting awx.main.scheduler.tasks.run_task_manager(*[])
2019-09-09 18:40:02,524 DEBUG    awx.main.scheduler Running Tower task manager.
2019-09-09 18:40:02,534 DEBUG    awx.main.models.inventory Finished updating inventory computed fields, pk=6, in 0.129 seconds
2019-09-09 18:40:02,537 DEBUG    awx.main.dispatch task bc154459-4c40-414b-8e9d-8fad87896a7d is finished
2019-09-09 18:40:02,547 DEBUG    awx.main.dispatch task 844dd27c-04e6-4593-a7c9-3ba778e78493 is finished
2019-09-09 18:40:02,554 DEBUG    awx.main.scheduler Starting Scheduler
2019-09-09 18:40:02,609 DEBUG    awx.main.dispatch task 850244a5-f0df-4080-a97f-cd66c4b6035b is finished
2019-09-09 18:40:15,309 DEBUG    awx.main.dispatch publish awx.main.scheduler.tasks.run_task_manager(c9eaf066-8ef6-421e-8c5e-b09dd8ce0eae, queue=awx_private_queue)
[2019-09-09 18:40:15,309: DEBUG/Process-1] publish awx.main.scheduler.tasks.run_task_manager(c9eaf066-8ef6-421e-8c5e-b09dd8ce0eae, queue=awx_private_queue)
2019-09-09 18:40:15,348 DEBUG    awx.main.dispatch delivered c9eaf066-8ef6-421e-8c5e-b09dd8ce0eae to worker[23698] qsize 0
2019-09-09 18:40:15,354 DEBUG    awx.main.dispatch task c9eaf066-8ef6-421e-8c5e-b09dd8ce0eae starting awx.main.scheduler.tasks.run_task_manager(*[])
2019-09-09 18:40:15,358 DEBUG    awx.main.scheduler Running Tower task manager.
2019-09-09 18:40:15,385 DEBUG    awx.main.scheduler Starting Scheduler
2019-09-09 18:40:15,441 DEBUG    awx.main.dispatch task c9eaf066-8ef6-421e-8c5e-b09dd8ce0eae is finished
2019-09-09 18:40:17,586 DEBUG    awx.main.dispatch publish awx.main.tasks.cluster_node_heartbeat(bad39a71-bf29-49a4-bfcb-1f35a12db36c, queue=awx)
[2019-09-09 18:40:17,586: DEBUG/Process-1] publish awx.main.tasks.cluster_node_heartbeat(bad39a71-bf29-49a4-bfcb-1f35a12db36c, queue=awx)
2019-09-09 18:40:17,637 DEBUG    awx.main.dispatch delivered bad39a71-bf29-49a4-bfcb-1f35a12db36c to worker[30685] qsize 0
2019-09-09 18:40:17,643 DEBUG    awx.main.dispatch task bad39a71-bf29-49a4-bfcb-1f35a12db36c starting awx.main.tasks.cluster_node_heartbeat(*[])
2019-09-09 18:40:17,648 DEBUG    awx.main.tasks Cluster node heartbeat task.
2019-09-09 18:40:17,695 DEBUG    awx.main.dispatch task bad39a71-bf29-49a4-bfcb-1f35a12db36c is finished

This one downlaoded the roles.