[05:30:00.092] New invocation is queued and will start shortly
[05:30:00.092] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[05:30:01.161] Starting the invocation (attempt 1)
[05:30:01.203] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:30:01.203] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:30:01.203] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9081204634688472656",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"74.0.3729.90\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9081204634688472656",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[05:30:01.713] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "project:infra",
"created_ts": "1555651801377600",
"id": "8915774572677604496",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3729.90\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1555651801566569",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9081204634688472656",
"scheduler_job_id:infra/Build From Tarball",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8915774572677604496/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:recipe_name:build_from_tarball",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1555651801566710",
"url": "https://ci.chromium.org/b/8915774572677604496",
"utcnow_ts": "1555651801702073"
}
}
[05:30:01.713] Task URL: https://ci.chromium.org/b/8915774572677604496
[05:30:01.713] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:2:0) after 1m0s
[05:31:01.703] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:2:0)
[05:31:01.703] Timer tick, asking Buildbucket for the build status
[05:31:01.772] Build 8915774572677604496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:31:01.772] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:3:0) after 1m0s
[05:31:03.846] Received PubSub notification, asking Buildbucket for the build status
[05:31:03.895] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:01.795] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:3:0)
[05:32:01.795] Timer tick, asking Buildbucket for the build status
[05:32:01.968] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:01.968] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:5:0) after 1m0s
[05:33:02.009] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:5:0)
[05:33:02.009] Timer tick, asking Buildbucket for the build status
[05:33:02.710] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:02.710] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:6:0) after 1m0s
[05:34:02.703] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:6:0)
[05:34:02.703] Timer tick, asking Buildbucket for the build status
[05:34:03.208] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:03.208] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:7:0) after 1m0s
[05:35:03.185] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:7:0)
[05:35:03.185] Timer tick, asking Buildbucket for the build status
[05:35:03.240] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:03.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:8:0) after 1m0s
[05:36:03.264] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:8:0)
[05:36:03.264] Timer tick, asking Buildbucket for the build status
[05:36:03.340] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:03.340] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:9:0) after 1m0s
[05:37:03.332] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:9:0)
[05:37:03.332] Timer tick, asking Buildbucket for the build status
[05:37:03.375] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:03.375] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:10:0) after 1m0s
[05:38:03.394] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:10:0)
[05:38:03.394] Timer tick, asking Buildbucket for the build status
[05:38:03.512] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:03.512] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:11:0) after 1m0s
[05:39:03.523] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:11:0)
[05:39:03.523] Timer tick, asking Buildbucket for the build status
[05:39:03.804] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:03.804] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:12:0) after 1m0s
[05:40:03.825] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:12:0)
[05:40:03.825] Timer tick, asking Buildbucket for the build status
[05:40:03.898] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:03.898] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:13:0) after 1m0s
[05:41:03.912] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:13:0)
[05:41:03.912] Timer tick, asking Buildbucket for the build status
[05:41:05.465] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:05.465] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:14:0) after 1m0s
[05:42:05.469] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:14:0)
[05:42:05.469] Timer tick, asking Buildbucket for the build status
[05:42:05.536] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:05.536] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:15:0) after 1m0s
[05:43:05.522] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:15:0)
[05:43:05.522] Timer tick, asking Buildbucket for the build status
[05:43:05.597] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:05.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:16:0) after 1m0s
[05:44:05.623] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:16:0)
[05:44:05.623] Timer tick, asking Buildbucket for the build status
[05:44:05.665] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:05.665] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:17:0) after 1m0s
[05:45:05.703] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:17:0)
[05:45:05.703] Timer tick, asking Buildbucket for the build status
[05:45:05.903] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:05.903] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:18:0) after 1m0s
[05:46:05.950] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:18:0)
[05:46:05.950] Timer tick, asking Buildbucket for the build status
[05:46:06.067] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:06.067] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:19:0) after 1m0s
[05:47:06.094] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:19:0)
[05:47:06.094] Timer tick, asking Buildbucket for the build status
[05:47:06.156] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:06.156] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:20:0) after 1m0s
[05:48:06.138] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:20:0)
[05:48:06.138] Timer tick, asking Buildbucket for the build status
[05:48:06.363] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:06.363] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:21:0) after 1m0s
[05:49:06.381] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:21:0)
[05:49:06.381] Timer tick, asking Buildbucket for the build status
[05:49:06.562] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:06.562] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:22:0) after 1m0s
[05:50:06.560] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:22:0)
[05:50:06.560] Timer tick, asking Buildbucket for the build status
[05:50:06.668] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:06.669] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:23:0) after 1m0s
[05:51:06.650] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:23:0)
[05:51:06.650] Timer tick, asking Buildbucket for the build status
[05:51:06.794] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:06.794] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:24:0) after 1m0s
[05:52:06.815] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:24:0)
[05:52:06.815] Timer tick, asking Buildbucket for the build status
[05:52:06.894] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:06.894] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:25:0) after 1m0s
[05:53:06.892] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:25:0)
[05:53:06.892] Timer tick, asking Buildbucket for the build status
[05:53:06.945] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:06.945] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:26:0) after 1m0s
[05:54:06.966] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:26:0)
[05:54:06.966] Timer tick, asking Buildbucket for the build status
[05:54:07.040] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:07.040] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:27:0) after 1m0s
[05:55:07.049] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:27:0)
[05:55:07.049] Timer tick, asking Buildbucket for the build status
[05:55:07.142] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:07.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:28:0) after 1m0s
[05:56:07.166] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:28:0)
[05:56:07.166] Timer tick, asking Buildbucket for the build status
[05:56:07.236] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:07.236] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:29:0) after 1m0s
[05:57:07.315] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:29:0)
[05:57:07.315] Timer tick, asking Buildbucket for the build status
[05:57:07.370] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:07.370] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:30:0) after 1m0s
[05:58:07.380] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:30:0)
[05:58:07.380] Timer tick, asking Buildbucket for the build status
[05:58:07.439] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:07.439] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:31:0) after 1m0s
[05:59:07.443] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:31:0)
[05:59:07.443] Timer tick, asking Buildbucket for the build status
[05:59:07.503] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:07.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:32:0) after 1m0s
[06:00:07.504] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:32:0)
[06:00:07.504] Timer tick, asking Buildbucket for the build status
[06:00:07.565] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:07.565] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:33:0) after 1m0s
[06:01:07.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:33:0)
[06:01:07.578] Timer tick, asking Buildbucket for the build status
[06:01:07.628] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:07.628] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:34:0) after 1m0s
[06:02:07.646] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:34:0)
[06:02:07.646] Timer tick, asking Buildbucket for the build status
[06:02:07.715] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:07.715] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:35:0) after 1m0s
[06:03:07.702] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:35:0)
[06:03:07.702] Timer tick, asking Buildbucket for the build status
[06:03:07.865] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:07.865] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:36:0) after 1m0s
[06:04:07.889] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:36:0)
[06:04:07.889] Timer tick, asking Buildbucket for the build status
[06:04:08.317] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:08.317] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:37:0) after 1m0s
[06:05:08.412] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:37:0)
[06:05:08.412] Timer tick, asking Buildbucket for the build status
[06:05:08.792] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:08.792] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:38:0) after 1m0s
[06:06:08.799] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:38:0)
[06:06:08.799] Timer tick, asking Buildbucket for the build status
[06:06:09.606] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:09.606] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:39:0) after 1m0s
[06:07:09.604] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:39:0)
[06:07:09.604] Timer tick, asking Buildbucket for the build status
[06:07:09.688] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:09.688] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:40:0) after 1m0s
[06:08:09.731] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:40:0)
[06:08:09.731] Timer tick, asking Buildbucket for the build status
[06:08:09.897] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:09.897] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:41:0) after 1m0s
[06:09:10.047] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:41:0)
[06:09:10.047] Timer tick, asking Buildbucket for the build status
[06:09:10.158] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:10.158] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:42:0) after 1m0s
[06:10:10.144] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:42:0)
[06:10:10.144] Timer tick, asking Buildbucket for the build status
[06:10:10.186] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:10.186] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:43:0) after 1m0s
[06:11:10.202] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:43:0)
[06:11:10.202] Timer tick, asking Buildbucket for the build status
[06:11:10.248] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:10.248] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:44:0) after 1m0s
[06:12:10.269] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:44:0)
[06:12:10.269] Timer tick, asking Buildbucket for the build status
[06:12:10.373] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:10.373] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:45:0) after 1m0s
[06:13:10.366] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:45:0)
[06:13:10.366] Timer tick, asking Buildbucket for the build status
[06:13:10.426] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:10.426] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:46:0) after 1m0s
[06:14:10.453] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:46:0)
[06:14:10.453] Timer tick, asking Buildbucket for the build status
[06:14:10.565] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:10.565] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:47:0) after 1m0s
[06:15:10.595] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:47:0)
[06:15:10.595] Timer tick, asking Buildbucket for the build status
[06:15:10.663] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:10.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:48:0) after 1m0s
[06:16:10.700] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:48:0)
[06:16:10.700] Timer tick, asking Buildbucket for the build status
[06:16:10.778] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:10.778] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:49:0) after 1m0s
[06:17:10.822] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:49:0)
[06:17:10.822] Timer tick, asking Buildbucket for the build status
[06:17:10.916] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:10.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:50:0) after 1m0s
[06:18:10.937] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:50:0)
[06:18:10.937] Timer tick, asking Buildbucket for the build status
[06:18:11.016] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:11.016] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:51:0) after 1m0s
[06:19:11.039] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:51:0)
[06:19:11.039] Timer tick, asking Buildbucket for the build status
[06:19:11.101] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:11.101] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:52:0) after 1m0s
[06:20:11.126] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:52:0)
[06:20:11.126] Timer tick, asking Buildbucket for the build status
[06:20:11.222] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:11.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:53:0) after 1m0s
[06:21:11.241] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:53:0)
[06:21:11.241] Timer tick, asking Buildbucket for the build status
[06:21:11.333] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:11.333] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:54:0) after 1m0s
[06:22:11.353] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:54:0)
[06:22:11.353] Timer tick, asking Buildbucket for the build status
[06:22:11.465] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:11.465] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:55:0) after 1m0s
[06:23:11.458] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:55:0)
[06:23:11.458] Timer tick, asking Buildbucket for the build status
[06:23:11.530] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:11.530] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:56:0) after 1m0s
[06:24:11.561] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:56:0)
[06:24:11.561] Timer tick, asking Buildbucket for the build status
[06:24:11.635] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:11.635] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:57:0) after 1m0s
[06:25:11.661] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:57:0)
[06:25:11.661] Timer tick, asking Buildbucket for the build status
[06:25:11.723] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:11.723] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:58:0) after 1m0s
[06:26:11.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:58:0)
[06:26:11.750] Timer tick, asking Buildbucket for the build status
[06:26:11.817] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:11.817] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:59:0) after 1m0s
[06:27:11.841] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:59:0)
[06:27:11.841] Timer tick, asking Buildbucket for the build status
[06:27:11.920] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:11.920] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:60:0) after 1m0s
[06:28:11.944] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:60:0)
[06:28:11.944] Timer tick, asking Buildbucket for the build status
[06:28:12.015] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:12.015] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:61:0) after 1m0s
[06:29:12.034] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:61:0)
[06:29:12.034] Timer tick, asking Buildbucket for the build status
[06:29:12.140] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:12.140] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:62:0) after 1m0s
[06:30:12.206] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:62:0)
[06:30:12.206] Timer tick, asking Buildbucket for the build status
[06:30:12.599] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:12.599] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:63:0) after 1m0s
[06:31:12.639] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:63:0)
[06:31:12.639] Timer tick, asking Buildbucket for the build status
[06:31:13.290] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:13.290] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:64:0) after 1m0s
[06:32:13.320] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:64:0)
[06:32:13.320] Timer tick, asking Buildbucket for the build status
[06:32:13.383] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:13.383] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:65:0) after 1m0s
[06:33:13.423] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:65:0)
[06:33:13.423] Timer tick, asking Buildbucket for the build status
[06:33:13.483] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:13.483] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:66:0) after 1m0s
[06:34:13.505] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:66:0)
[06:34:13.505] Timer tick, asking Buildbucket for the build status
[06:34:13.569] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:13.569] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:67:0) after 1m0s
[06:35:13.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:67:0)
[06:35:13.578] Timer tick, asking Buildbucket for the build status
[06:35:13.617] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:13.617] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:68:0) after 1m0s
[06:36:13.636] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:68:0)
[06:36:13.636] Timer tick, asking Buildbucket for the build status
[06:36:13.738] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:13.738] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:69:0) after 1m0s
[06:37:13.781] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:69:0)
[06:37:13.781] Timer tick, asking Buildbucket for the build status
[06:37:13.879] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:13.879] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:70:0) after 1m0s
[06:38:13.903] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:70:0)
[06:38:13.903] Timer tick, asking Buildbucket for the build status
[06:38:13.959] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:13.959] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:71:0) after 1m0s
[06:39:13.980] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:71:0)
[06:39:13.980] Timer tick, asking Buildbucket for the build status
[06:39:14.021] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:14.021] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:72:0) after 1m0s
[06:40:14.034] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:72:0)
[06:40:14.034] Timer tick, asking Buildbucket for the build status
[06:40:14.092] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:14.092] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:73:0) after 1m0s
[06:41:14.112] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:73:0)
[06:41:14.112] Timer tick, asking Buildbucket for the build status
[06:41:14.207] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:14.207] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:74:0) after 1m0s
[06:42:14.230] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:74:0)
[06:42:14.230] Timer tick, asking Buildbucket for the build status
[06:42:14.295] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:14.295] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:75:0) after 1m0s
[06:43:14.318] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:75:0)
[06:43:14.318] Timer tick, asking Buildbucket for the build status
[06:43:14.409] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:14.409] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:76:0) after 1m0s
[06:44:14.403] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:76:0)
[06:44:14.403] Timer tick, asking Buildbucket for the build status
[06:44:14.486] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:14.486] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:77:0) after 1m0s
[06:45:14.505] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:77:0)
[06:45:14.505] Timer tick, asking Buildbucket for the build status
[06:45:14.590] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:14.590] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:78:0) after 1m0s
[06:46:14.610] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:78:0)
[06:46:14.610] Timer tick, asking Buildbucket for the build status
[06:46:14.647] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:14.647] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:79:0) after 1m0s
[06:47:14.677] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:79:0)
[06:47:14.677] Timer tick, asking Buildbucket for the build status
[06:47:14.870] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:14.870] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:80:0) after 1m0s
[06:48:14.881] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:80:0)
[06:48:14.881] Timer tick, asking Buildbucket for the build status
[06:48:14.923] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:14.923] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:81:0) after 1m0s
[06:49:14.945] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:81:0)
[06:49:14.945] Timer tick, asking Buildbucket for the build status
[06:49:15.147] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:15.147] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:82:0) after 1m0s
[06:50:15.138] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:82:0)
[06:50:15.138] Timer tick, asking Buildbucket for the build status
[06:50:15.247] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:15.247] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:83:0) after 1m0s
[06:51:15.270] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:83:0)
[06:51:15.270] Timer tick, asking Buildbucket for the build status
[06:51:15.318] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:15.318] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:84:0) after 1m0s
[06:52:15.306] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:84:0)
[06:52:15.306] Timer tick, asking Buildbucket for the build status
[06:52:15.374] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:15.374] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:85:0) after 1m0s
[06:53:15.402] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:85:0)
[06:53:15.402] Timer tick, asking Buildbucket for the build status
[06:53:15.475] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:15.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:86:0) after 1m0s
[06:54:15.564] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:86:0)
[06:54:15.564] Timer tick, asking Buildbucket for the build status
[06:54:15.650] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:15.650] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:87:0) after 1m0s
[06:55:15.627] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:87:0)
[06:55:15.627] Timer tick, asking Buildbucket for the build status
[06:55:15.672] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:15.672] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:88:0) after 1m0s
[06:56:15.699] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:88:0)
[06:56:15.699] Timer tick, asking Buildbucket for the build status
[06:56:15.872] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:15.872] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:89:0) after 1m0s
[06:57:15.895] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:89:0)
[06:57:15.895] Timer tick, asking Buildbucket for the build status
[06:57:15.972] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:15.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:90:0) after 1m0s
[06:58:15.997] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:90:0)
[06:58:15.997] Timer tick, asking Buildbucket for the build status
[06:58:16.048] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:16.048] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:91:0) after 1m0s
[06:59:16.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:91:0)
[06:59:16.088] Timer tick, asking Buildbucket for the build status
[06:59:16.146] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:16.147] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:92:0) after 1m0s
[07:00:16.204] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:92:0)
[07:00:16.204] Timer tick, asking Buildbucket for the build status
[07:00:16.300] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:16.300] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:93:0) after 1m0s
[07:01:16.289] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:93:0)
[07:01:16.289] Timer tick, asking Buildbucket for the build status
[07:01:16.558] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:16.558] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:94:0) after 1m0s
[07:02:16.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:94:0)
[07:02:16.593] Timer tick, asking Buildbucket for the build status
[07:02:16.654] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:16.654] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:95:0) after 1m0s
[07:03:16.673] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:95:0)
[07:03:16.673] Timer tick, asking Buildbucket for the build status
[07:03:16.960] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:16.960] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:96:0) after 1m0s
[07:04:16.955] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:96:0)
[07:04:16.955] Timer tick, asking Buildbucket for the build status
[07:04:16.996] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:16.996] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:97:0) after 1m0s
[07:05:17.023] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:97:0)
[07:05:17.023] Timer tick, asking Buildbucket for the build status
[07:05:17.105] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:17.105] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:98:0) after 1m0s
[07:06:17.133] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:98:0)
[07:06:17.133] Timer tick, asking Buildbucket for the build status
[07:06:17.203] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:17.203] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:99:0) after 1m0s
[07:07:17.229] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:99:0)
[07:07:17.229] Timer tick, asking Buildbucket for the build status
[07:07:17.273] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:17.273] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:100:0) after 1m0s
[07:08:17.303] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:100:0)
[07:08:17.303] Timer tick, asking Buildbucket for the build status
[07:08:17.431] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:17.431] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:101:0) after 1m0s
[07:09:17.458] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:101:0)
[07:09:17.458] Timer tick, asking Buildbucket for the build status
[07:09:17.520] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:17.520] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:102:0) after 1m0s
[07:10:17.493] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:102:0)
[07:10:17.493] Timer tick, asking Buildbucket for the build status
[07:10:17.561] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:17.561] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:103:0) after 1m0s
[07:11:17.598] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:103:0)
[07:11:17.598] Timer tick, asking Buildbucket for the build status
[07:11:17.754] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:17.754] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:104:0) after 1m0s
[07:12:17.863] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:104:0)
[07:12:17.863] Timer tick, asking Buildbucket for the build status
[07:12:17.921] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:17.921] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:105:0) after 1m0s
[07:13:17.940] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:105:0)
[07:13:17.940] Timer tick, asking Buildbucket for the build status
[07:13:18.030] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:18.030] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:106:0) after 1m0s
[07:14:18.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:106:0)
[07:14:18.021] Timer tick, asking Buildbucket for the build status
[07:14:18.110] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:18.110] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:107:0) after 1m0s
[07:15:18.111] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:107:0)
[07:15:18.112] Timer tick, asking Buildbucket for the build status
[07:15:18.148] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:18.148] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:108:0) after 1m0s
[07:16:18.202] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:108:0)
[07:16:18.202] Timer tick, asking Buildbucket for the build status
[07:16:18.259] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:18.259] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:109:0) after 1m0s
[07:17:18.292] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:109:0)
[07:17:18.292] Timer tick, asking Buildbucket for the build status
[07:17:18.404] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:18.404] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:110:0) after 1m0s
[07:18:18.428] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:110:0)
[07:18:18.428] Timer tick, asking Buildbucket for the build status
[07:18:18.554] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:18.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:111:0) after 1m0s
[07:19:18.536] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:111:0)
[07:19:18.536] Timer tick, asking Buildbucket for the build status
[07:19:18.592] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:18.592] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:112:0) after 1m0s
[07:20:18.615] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:112:0)
[07:20:18.615] Timer tick, asking Buildbucket for the build status
[07:20:18.680] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:18.680] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:113:0) after 1m0s
[07:21:18.695] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:113:0)
[07:21:18.695] Timer tick, asking Buildbucket for the build status
[07:21:18.905] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:18.905] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:114:0) after 1m0s
[07:22:18.924] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:114:0)
[07:22:18.924] Timer tick, asking Buildbucket for the build status
[07:22:18.993] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:18.993] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:115:0) after 1m0s
[07:23:18.999] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:115:0)
[07:23:18.999] Timer tick, asking Buildbucket for the build status
[07:23:19.090] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:19.090] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:116:0) after 1m0s
[07:24:19.099] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:116:0)
[07:24:19.099] Timer tick, asking Buildbucket for the build status
[07:24:19.191] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:19.191] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:117:0) after 1m0s
[07:25:19.221] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:117:0)
[07:25:19.221] Timer tick, asking Buildbucket for the build status
[07:25:19.293] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:19.293] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:118:0) after 1m0s
[07:26:19.307] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:118:0)
[07:26:19.307] Timer tick, asking Buildbucket for the build status
[07:26:19.370] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:19.370] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:119:0) after 1m0s
[07:27:19.391] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:119:0)
[07:27:19.391] Timer tick, asking Buildbucket for the build status
[07:27:19.515] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:19.515] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:120:0) after 1m0s
[07:28:19.536] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:120:0)
[07:28:19.536] Timer tick, asking Buildbucket for the build status
[07:28:19.602] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:19.602] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:121:0) after 1m0s
[07:29:19.623] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:121:0)
[07:29:19.623] Timer tick, asking Buildbucket for the build status
[07:29:19.663] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:19.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:122:0) after 1m0s
[07:30:19.685] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:122:0)
[07:30:19.685] Timer tick, asking Buildbucket for the build status
[07:30:19.796] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:19.796] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:123:0) after 1m0s
[07:31:19.889] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:123:0)
[07:31:19.889] Timer tick, asking Buildbucket for the build status
[07:31:19.926] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:19.926] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:124:0) after 1m0s
[07:32:19.947] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:124:0)
[07:32:19.947] Timer tick, asking Buildbucket for the build status
[07:32:20.018] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:20.018] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:125:0) after 1m0s
[07:33:20.060] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:125:0)
[07:33:20.060] Timer tick, asking Buildbucket for the build status
[07:33:20.191] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:20.191] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:126:0) after 1m0s
[07:34:20.165] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:126:0)
[07:34:20.165] Timer tick, asking Buildbucket for the build status
[07:34:20.204] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:20.204] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:127:0) after 1m0s
[07:35:20.224] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:127:0)
[07:35:20.224] Timer tick, asking Buildbucket for the build status
[07:35:20.376] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:20.376] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:128:0) after 1m0s
[07:36:20.391] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:128:0)
[07:36:20.391] Timer tick, asking Buildbucket for the build status
[07:36:20.467] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:20.467] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:129:0) after 1m0s
[07:37:20.488] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:129:0)
[07:37:20.488] Timer tick, asking Buildbucket for the build status
[07:37:20.583] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:20.583] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:130:0) after 1m0s
[07:38:20.607] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:130:0)
[07:38:20.607] Timer tick, asking Buildbucket for the build status
[07:38:20.705] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:20.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:131:0) after 1m0s
[07:39:20.780] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:131:0)
[07:39:20.780] Timer tick, asking Buildbucket for the build status
[07:39:20.837] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:20.838] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:132:0) after 1m0s
[07:40:20.865] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:132:0)
[07:40:20.865] Timer tick, asking Buildbucket for the build status
[07:40:21.051] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:21.051] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:133:0) after 1m0s
[07:41:21.142] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:133:0)
[07:41:21.142] Timer tick, asking Buildbucket for the build status
[07:41:21.204] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:21.204] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:134:0) after 1m0s
[07:42:21.226] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:134:0)
[07:42:21.226] Timer tick, asking Buildbucket for the build status
[07:42:21.282] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:21.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:135:0) after 1m0s
[07:43:21.296] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:135:0)
[07:43:21.296] Timer tick, asking Buildbucket for the build status
[07:43:21.413] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:21.413] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:136:0) after 1m0s
[07:44:21.434] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:136:0)
[07:44:21.434] Timer tick, asking Buildbucket for the build status
[07:44:21.601] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:21.601] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:137:0) after 1m0s
[07:45:21.603] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:137:0)
[07:45:21.603] Timer tick, asking Buildbucket for the build status
[07:45:21.662] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:21.662] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:138:0) after 1m0s
[07:46:21.680] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:138:0)
[07:46:21.680] Timer tick, asking Buildbucket for the build status
[07:46:21.739] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:21.739] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:139:0) after 1m0s
[07:47:21.727] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:139:0)
[07:47:21.727] Timer tick, asking Buildbucket for the build status
[07:47:21.806] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:21.806] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:140:0) after 1m0s
[07:48:21.829] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:140:0)
[07:48:21.829] Timer tick, asking Buildbucket for the build status
[07:48:21.903] Build 8915774572677604496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:21.903] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9081204634688472656:141:0) after 1m0s
[07:48:41.160] Received PubSub notification, asking Buildbucket for the build status
[07:48:41.276] Build 8915774572677604496: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:48:41.276] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1555660120194785",
"created_by": "project:infra",
"created_ts": "1555651801377600",
"id": "8915774572677604496",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3729.90\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"build-from-tarball-0-fozp\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"74.0.3729.90\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Build From Tarball\"], \"caches\": [\"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"build-from-tarball-0-fozp\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4267-53b17d5\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1555651863199173",
"status": "COMPLETED",
"status_changed_ts": "1555660120778754",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9081204634688472656",
"scheduler_job_id:infra/Build From Tarball",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8915774572677604496/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:build_from_tarball",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:444ce43e0bd31710",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1555660120788527",
"url": "https://ci.chromium.org/b/8915774572677604496",
"utcnow_ts": "1555660121265849"
}
}
[07:48:41.276] Invocation finished in 2h18m41.199680066s with status SUCCEEDED