Skip to content

Instantly share code, notes, and snippets.

@fxfitz
Created September 9, 2019 17:53
Show Gist options
  • Save fxfitz/0640924cfd5c3f9540e4fa6ab76d8f70 to your computer and use it in GitHub Desktop.
Save fxfitz/0640924cfd5c3f9540e4fa6ab76d8f70 to your computer and use it in GitHub Desktop.
Logs from AWX as I kickoff a template
019-09-09 17:50:35,818 DEBUG awx.main.dispatch delivered 4491f702-7bf1-4016-acc3-fe1ab66574ac to worker[295] qsize 0
2019-09-09 17:50:35,849 DEBUG awx.main.dispatch task 4491f702-7bf1-4016-acc3-fe1ab66574ac starting awx.main.tasks.update_inventory_computed_fields(*[4, True])
2019-09-09 17:50:36,014 DEBUG awx.main.models.inventory Going to update inventory computed fields, pk=4
2019-09-09 17:50:36,682 DEBUG awx.main.models.inventory Finished updating inventory computed fields, pk=4, in 0.657 seconds
2019-09-09 17:50:36,688 DEBUG awx.main.dispatch task 4491f702-7bf1-4016-acc3-fe1ab66574ac is finished
2019-09-09 17:50:36,977 DEBUG awx.main.dispatch delivered 61d55738-3102-4537-8298-0a5beb863b39 to worker[1996] qsize 0
2019-09-09 17:50:36,980 DEBUG awx.main.dispatch task 61d55738-3102-4537-8298-0a5beb863b39 starting awx.main.scheduler.tasks.run_task_manager(*[])
2019-09-09 17:50:37,001 DEBUG awx.main.scheduler Running Tower task manager.
2019-09-09 17:50:37,152 DEBUG awx.main.scheduler Starting Scheduler
2019-09-09 17:50:38,446 DEBUG awx.main.scheduler Spawned project_update 64 (pending) as dependency of job 63 (pending)
2019-09-09 17:50:38,619 DEBUG awx.main.scheduler Starting dependent project_update 64 (pending) in group tower instance awx
2019-09-09 17:50:38,658 DEBUG awx.main.scheduler Submitting project_update 64 (waiting) to <instance group, instance> <1,awx>.
2019-09-09 17:50:39,000 DEBUG awx.main.scheduler project_update 64 (waiting) consumed 1 capacity units from tower with prior total of 0
2019-09-09 17:50:39,011 DEBUG awx.main.scheduler job 63 (pending) is blocked from running
2019-09-09 17:50:39,199 DEBUG awx.main.dispatch publish awx.main.tasks.RunProjectUpdate(1525e50f-5111-425f-b3f7-50422dcbca7a, queue=awx)
2019-09-09 17:50:39,277 DEBUG awx.main.dispatch task 61d55738-3102-4537-8298-0a5beb863b39 is finished
2019-09-09 17:50:39,278 DEBUG awx.main.dispatch delivered 1525e50f-5111-425f-b3f7-50422dcbca7a to worker[3325] qsize 0
2019-09-09 17:50:39,319 DEBUG awx.main.dispatch task 1525e50f-5111-425f-b3f7-50422dcbca7a starting awx.main.tasks.RunProjectUpdate(*[64])
2019-09-09 17:50:43,056 DEBUG awx.main.dispatch publish awx.main.scheduler.tasks.run_task_manager(9dfabf26-5b11-48ad-b98f-321ce1d13ced, queue=awx_private_queue)
[2019-09-09 17:50:43,056: DEBUG/Process-1] publish awx.main.scheduler.tasks.run_task_manager(9dfabf26-5b11-48ad-b98f-321ce1d13ced, queue=awx_private_queue)
2019-09-09 17:50:43,173 DEBUG awx.main.dispatch delivered 9dfabf26-5b11-48ad-b98f-321ce1d13ced to worker[294] qsize 0
2019-09-09 17:50:43,191 DEBUG awx.main.dispatch task 9dfabf26-5b11-48ad-b98f-321ce1d13ced starting awx.main.scheduler.tasks.run_task_manager(*[])
2019-09-09 17:50:43,244 DEBUG awx.main.scheduler Running Tower task manager.
2019-09-09 17:50:43,353 DEBUG awx.main.scheduler Starting Scheduler
2019-09-09 17:50:43,875 DEBUG awx.main.scheduler Dependent project_update 64 (running) is blocked from running
2019-09-09 17:50:43,879 DEBUG awx.main.scheduler job 63 (pending) is blocked from running
2019-09-09 17:50:43,883 DEBUG awx.main.dispatch task 9dfabf26-5b11-48ad-b98f-321ce1d13ced is finished
2019-09-09 17:50:53,035 DEBUG awx.main.dispatch publish awx.main.tasks.awx_periodic_scheduler(b0e6477b-409f-4f3c-b680-52be81471fa4, queue=awx_private_queue)
[2019-09-09 17:50:53,035: DEBUG/Process-1] publish awx.main.tasks.awx_periodic_scheduler(b0e6477b-409f-4f3c-b680-52be81471fa4, queue=awx_private_queue)
2019-09-09 17:50:53,125 DEBUG awx.main.dispatch delivered b0e6477b-409f-4f3c-b680-52be81471fa4 to worker[1996] qsize 0
2019-09-09 17:50:53,146 DEBUG awx.main.dispatch task b0e6477b-409f-4f3c-b680-52be81471fa4 starting awx.main.tasks.awx_periodic_scheduler(*[])
2019-09-09 17:50:53,221 DEBUG awx.main.tasks Starting periodic scheduler
2019-09-09 17:50:53,261 DEBUG awx.main.tasks Last scheduler run was: 2019-09-09 17:50:23.153659+00:00
2019-09-09 17:50:53,357 DEBUG awx.main.dispatch task b0e6477b-409f-4f3c-b680-52be81471fa4 is finished
2019-09-09 17:50:59,790 DEBUG awx.main.tasks project_update 64 (running) finished running, producing 59 events.
2019-09-09 17:50:59,820 INFO awx.main.commands.run_callback_receiver Event processing is finished for Job 64, sending notifications
2019-09-09 17:50:59,820 INFO awx.main.commands.run_callback_receiver Event processing is finished for Job 64, sending notifications
2019-09-09 17:51:00,748 DEBUG awx.main.dispatch task 1525e50f-5111-425f-b3f7-50422dcbca7a starting awx.main.tasks.handle_work_success(*[])
2019-09-09 17:51:00,788 DEBUG awx.main.dispatch publish awx.main.scheduler.tasks.run_task_manager(9a14af79-4a84-4fa7-916b-b2f13114393b, queue=awx_private_queue)
RESULT 2
OKREADY
2019-09-09 17:51:00,877 DEBUG awx.main.dispatch delivered 9a14af79-4a84-4fa7-916b-b2f13114393b to worker[294] qsize 0
2019-09-09 17:51:00,874 DEBUG awx.main.dispatch task 1525e50f-5111-425f-b3f7-50422dcbca7a is finished
2019-09-09 17:51:00,890 DEBUG awx.main.dispatch task 9a14af79-4a84-4fa7-916b-b2f13114393b starting awx.main.scheduler.tasks.run_task_manager(*[])
2019-09-09 17:51:00,902 DEBUG awx.main.scheduler Running Tower task manager.
2019-09-09 17:51:01,031 DEBUG awx.main.scheduler Starting Scheduler
2019-09-09 17:51:02,132 DEBUG awx.main.scheduler Starting job 63 (pending) in group tower instance awx (remaining_capacity=8)
2019-09-09 17:51:02,248 DEBUG awx.main.scheduler Submitting job 63 (waiting) to <instance group, instance> <1,awx>.
2019-09-09 17:51:02,535 DEBUG awx.main.scheduler job 63 (waiting) consumed 2 capacity units from tower with prior total of 0
2019-09-09 17:51:02,620 DEBUG awx.main.dispatch publish awx.main.tasks.RunJob(cf6cfaea-0149-4ddf-8d1e-64fe9298704b, queue=awx)
2019-09-09 17:51:02,690 DEBUG awx.main.dispatch delivered cf6cfaea-0149-4ddf-8d1e-64fe9298704b to worker[3325] qsize 0
2019-09-09 17:51:02,691 DEBUG awx.main.dispatch task 9a14af79-4a84-4fa7-916b-b2f13114393b is finished
2019-09-09 17:51:02,720 DEBUG awx.main.dispatch task cf6cfaea-0149-4ddf-8d1e-64fe9298704b starting awx.main.tasks.RunJob(*[63])
2019-09-09 17:51:03,070 DEBUG awx.main.dispatch publish awx.main.scheduler.tasks.run_task_manager(ba7a980f-ad16-4654-9f6c-33cc1d8cbd7f, queue=awx_private_queue)
[2019-09-09 17:51:03,070: DEBUG/Process-1] publish awx.main.scheduler.tasks.run_task_manager(ba7a980f-ad16-4654-9f6c-33cc1d8cbd7f, queue=awx_private_queue)
2019-09-09 17:51:03,136 DEBUG awx.main.dispatch delivered ba7a980f-ad16-4654-9f6c-33cc1d8cbd7f to worker[295] qsize 0
2019-09-09 17:51:03,178 DEBUG awx.main.dispatch task ba7a980f-ad16-4654-9f6c-33cc1d8cbd7f starting awx.main.scheduler.tasks.run_task_manager(*[])
2019-09-09 17:51:03,183 DEBUG awx.main.scheduler Running Tower task manager.
2019-09-09 17:51:03,287 DEBUG awx.main.scheduler Starting Scheduler
2019-09-09 17:51:03,915 DEBUG awx.main.dispatch task ba7a980f-ad16-4654-9f6c-33cc1d8cbd7f is finished
2019-09-09 17:51:03,983 INFO awx.main.tasks Skipping project sync for job 63 (running) because commit is locally available
2019-09-09 17:51:23,015 DEBUG awx.main.dispatch publish awx.main.tasks.awx_periodic_scheduler(b7a541d6-df0f-48ba-bb6b-7b3fc8aaa5d7, queue=awx_private_queue)
[2019-09-09 17:51:23,015: DEBUG/Process-1] publish awx.main.tasks.awx_periodic_scheduler(b7a541d6-df0f-48ba-bb6b-7b3fc8aaa5d7, queue=awx_private_queue)
2019-09-09 17:51:23,710 DEBUG awx.main.dispatch delivered b7a541d6-df0f-48ba-bb6b-7b3fc8aaa5d7 to worker[1996] qsize 0
2019-09-09 17:51:23,741 DEBUG awx.main.dispatch task b7a541d6-df0f-48ba-bb6b-7b3fc8aaa5d7 starting awx.main.tasks.awx_periodic_scheduler(*[])
2019-09-09 17:51:23,766 DEBUG awx.main.dispatch publish awx.main.scheduler.tasks.run_task_manager(7e1efcea-382c-482e-a47f-7d2a38009865, queue=awx_private_queue)
[2019-09-09 17:51:23,766: DEBUG/Process-1] publish awx.main.scheduler.tasks.run_task_manager(7e1efcea-382c-482e-a47f-7d2a38009865, queue=awx_private_queue)
2019-09-09 17:51:23,808 DEBUG awx.main.tasks Starting periodic scheduler
2019-09-09 17:51:23,844 DEBUG awx.main.tasks Last scheduler run was: 2019-09-09 17:50:53.231323+00:00
2019-09-09 17:51:23,856 DEBUG awx.main.dispatch delivered 7e1efcea-382c-482e-a47f-7d2a38009865 to worker[294] qsize 0
2019-09-09 17:51:23,865 DEBUG awx.main.dispatch task 7e1efcea-382c-482e-a47f-7d2a38009865 starting awx.main.scheduler.tasks.run_task_manager(*[])
2019-09-09 17:51:23,869 DEBUG awx.main.dispatch publish awx.main.tasks.cluster_node_heartbeat(5d52b39a-75c9-4651-98a4-427c61169b97, queue=awx)
2019-09-09 17:51:23,923 DEBUG awx.main.scheduler Running Tower task manager.
[2019-09-09 17:51:23,869: DEBUG/Process-1] publish awx.main.tasks.cluster_node_heartbeat(5d52b39a-75c9-4651-98a4-427c61169b97, queue=awx)
2019-09-09 17:51:24,012 DEBUG awx.main.dispatch task b7a541d6-df0f-48ba-bb6b-7b3fc8aaa5d7 is finished
2019-09-09 17:51:24,054 DEBUG awx.main.scheduler Starting Scheduler
2019-09-09 17:51:24,132 DEBUG awx.main.dispatch delivered 5d52b39a-75c9-4651-98a4-427c61169b97 to worker[1996] qsize 0
2019-09-09 17:51:24,170 DEBUG awx.main.dispatch task 5d52b39a-75c9-4651-98a4-427c61169b97 starting awx.main.tasks.cluster_node_heartbeat(*[])
2019-09-09 17:51:24,179 DEBUG awx.main.tasks Cluster node heartbeat task.
2019-09-09 17:51:24,313 DEBUG awx.main.dispatch task 5d52b39a-75c9-4651-98a4-427c61169b97 is finished
2019-09-09 17:51:24,465 DEBUG awx.main.dispatch task 7e1efcea-382c-482e-a47f-7d2a38009865 is finished
2019-09-09 17:51:43,752 DEBUG awx.main.dispatch publish awx.main.scheduler.tasks.run_task_manager(0ee0e467-f689-4dfb-9486-b61d70496962, queue=awx_private_queue)
[2019-09-09 17:51:43,752: DEBUG/Process-1] publish awx.main.scheduler.tasks.run_task_manager(0ee0e467-f689-4dfb-9486-b61d70496962, queue=awx_private_queue)
2019-09-09 17:51:43,978 DEBUG awx.main.dispatch delivered 0ee0e467-f689-4dfb-9486-b61d70496962 to worker[1996] qsize 0
2019-09-09 17:51:44,053 DEBUG awx.main.dispatch task 0ee0e467-f689-4dfb-9486-b61d70496962 starting awx.main.scheduler.tasks.run_task_manager(*[])
2019-09-09 17:51:44,088 DEBUG awx.main.scheduler Running Tower task manager.
2019-09-09 17:51:44,243 DEBUG awx.main.scheduler Starting Scheduler
2019-09-09 17:51:44,595 DEBUG awx.main.dispatch task 0ee0e467-f689-4dfb-9486-b61d70496962 is finished
2019-09-09 17:51:52,231 INFO awx.main.commands.run_callback_receiver Event processing is finished for Job 63, sending notifications
2019-09-09 17:51:52,231 INFO awx.main.commands.run_callback_receiver Event processing is finished for Job 63, sending notifications
2019-09-09 17:51:52,326 DEBUG awx.main.tasks job 63 (running) finished running, producing 93 events.
2019-09-09 17:51:52,396 DEBUG awx.main.models.inventory Going to update inventory computed fields, pk=4
2019-09-09 17:51:52,693 DEBUG awx.main.dispatch publish awx.main.tasks.update_inventory_computed_fields(870be880-5422-4717-8f0d-07713769d1d1, queue=awx_private_queue)
2019-09-09 17:51:52,701 DEBUG awx.main.models.inventory Finished updating inventory computed fields, pk=4, in 0.292 seconds
2019-09-09 17:51:52,728 DEBUG awx.main.dispatch delivered 870be880-5422-4717-8f0d-07713769d1d1 to worker[1996] qsize 0
2019-09-09 17:51:52,739 DEBUG awx.main.dispatch task 870be880-5422-4717-8f0d-07713769d1d1 starting awx.main.tasks.update_inventory_computed_fields(*[4, True])
2019-09-09 17:51:52,816 DEBUG awx.main.dispatch task cf6cfaea-0149-4ddf-8d1e-64fe9298704b starting awx.main.tasks.handle_work_success(*[])
2019-09-09 17:51:52,848 DEBUG awx.main.models.inventory Going to update inventory computed fields, pk=4
2019-09-09 17:51:52,864 DEBUG awx.main.dispatch publish awx.main.scheduler.tasks.run_task_manager(6de06d4d-bfea-45c8-9862-3139ee327288, queue=awx_private_queue)
2019-09-09 17:51:52,960 DEBUG awx.main.dispatch delivered 6de06d4d-bfea-45c8-9862-3139ee327288 to worker[295] qsize 0
2019-09-09 17:51:52,988 DEBUG awx.main.dispatch task 6de06d4d-bfea-45c8-9862-3139ee327288 starting awx.main.scheduler.tasks.run_task_manager(*[])
2019-09-09 17:51:52,996 DEBUG awx.main.scheduler Running Tower task manager.
2019-09-09 17:51:52,982 DEBUG awx.main.dispatch task cf6cfaea-0149-4ddf-8d1e-64fe9298704b is finished
2019-09-09 17:51:53,064 DEBUG awx.main.dispatch publish awx.main.tasks.awx_periodic_scheduler(4cecd4b5-4b94-4d60-84e7-d8b5a4956232, queue=awx_private_queue)
[2019-09-09 17:51:53,064: DEBUG/Process-1] publish awx.main.tasks.awx_periodic_scheduler(4cecd4b5-4b94-4d60-84e7-d8b5a4956232, queue=awx_private_queue)
2019-09-09 17:51:53,153 DEBUG awx.main.scheduler Starting Scheduler
2019-09-09 17:51:53,185 DEBUG awx.main.dispatch delivered 4cecd4b5-4b94-4d60-84e7-d8b5a4956232 to worker[3325] qsize 0
2019-09-09 17:51:53,194 DEBUG awx.main.dispatch task 4cecd4b5-4b94-4d60-84e7-d8b5a4956232 starting awx.main.tasks.awx_periodic_scheduler(*[])
2019-09-09 17:51:53,299 DEBUG awx.main.tasks Starting periodic scheduler
2019-09-09 17:51:53,328 DEBUG awx.main.tasks Last scheduler run was: 2019-09-09 17:51:23.832069+00:00
2019-09-09 17:51:53,400 DEBUG awx.main.dispatch task 4cecd4b5-4b94-4d60-84e7-d8b5a4956232 is finished
2019-09-09 17:51:53,461 DEBUG awx.main.models.inventory Finished updating inventory computed fields, pk=4, in 0.608 seconds
2019-09-09 17:51:53,478 DEBUG awx.main.dispatch task 6de06d4d-bfea-45c8-9862-3139ee327288 is finished
2019-09-09 17:51:53,472 DEBUG awx.main.dispatch task 870be880-5422-4717-8f0d-07713769d1d1 is finished
RESULT 2
OKREADY
2019-09-09 17:52:03,741 DEBUG awx.main.dispatch publish awx.main.scheduler.tasks.run_task_manager(fcde9813-71a6-4710-99c8-aea758e227b1, queue=awx_private_queue)
[2019-09-09 17:52:03,741: DEBUG/Process-1] publish awx.main.scheduler.tasks.run_task_manager(fcde9813-71a6-4710-99c8-aea758e227b1, queue=awx_private_queue)
2019-09-09 17:52:03,783 DEBUG awx.main.dispatch delivered fcde9813-71a6-4710-99c8-aea758e227b1 to worker[295] qsize 0
2019-09-09 17:52:03,793 DEBUG awx.main.dispatch task fcde9813-71a6-4710-99c8-aea758e227b1 starting awx.main.scheduler.tasks.run_task_manager(*[])
2019-09-09 17:52:03,798 DEBUG awx.main.scheduler Running Tower task manager.
2019-09-09 17:52:03,877 DEBUG awx.main.scheduler Starting Scheduler
2019-09-09 17:52:04,045 DEBUG awx.main.dispatch task fcde9813-71a6-4710-99c8-aea758e227b1 is finished
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment