[06:37:02.538] New invocation is queued and will start shortly
[06:37:03.697] Starting the invocation (attempt 1)
[06:37:03.737] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:37:03.737] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:37:03.738] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9066070723463708576",
"parameters_json": "{\"builder_name\":\"publish_tarball_dispatcher\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"cron\":{\"generation\":\"4716\"},\"id\":\"cron:v1:4716\"}]}}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9066070723463708576",
"scheduler_job_id:infra/publish_tarball_dispatcher",
"user_agent:luci-scheduler"
]
}
[06:37:04.668] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:infra",
"created_ts": "1570084623841232",
"id": "8900640661429706864",
"parameters_json": "{\"builder_name\": \"publish_tarball_dispatcher\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"4716\"}, \"id\": \"cron:v1:4716\"}]}}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1570084624453102",
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9066070723463708576",
"scheduler_job_id:infra/publish_tarball_dispatcher",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8900640661429706864/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:recipe_name:publish_tarball",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1570084624453221",
"url": "https://ci.chromium.org/b/8900640661429706864",
"utcnow_ts": "1570084624661518"
}
}
[06:37:04.668] Task URL: https://ci.chromium.org/b/8900640661429706864
[06:37:04.668] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:2:0) after 1m0s
[06:38:04.842] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:2:0)
[06:38:04.842] Timer tick, asking Buildbucket for the build status
[06:38:05.023] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:38:05.024] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:3:0) after 1m0s
[06:39:05.271] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:3:0)
[06:39:05.271] Timer tick, asking Buildbucket for the build status
[06:39:05.547] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:39:05.547] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:4:0) after 1m0s
[06:40:05.589] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:4:0)
[06:40:05.589] Timer tick, asking Buildbucket for the build status
[06:40:05.694] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:40:05.694] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:5:0) after 1m0s
[06:41:05.781] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:5:0)
[06:41:05.781] Timer tick, asking Buildbucket for the build status
[06:41:05.962] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:41:05.962] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:6:0) after 1m0s
[06:42:06.048] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:6:0)
[06:42:06.048] Timer tick, asking Buildbucket for the build status
[06:42:06.183] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:42:06.183] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:7:0) after 1m0s
[06:43:06.408] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:7:0)
[06:43:06.408] Timer tick, asking Buildbucket for the build status
[06:43:06.575] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:43:06.576] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:8:0) after 1m0s
[06:44:06.719] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:8:0)
[06:44:06.719] Timer tick, asking Buildbucket for the build status
[06:44:06.888] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:44:06.888] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:9:0) after 1m0s
[06:45:06.910] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:9:0)
[06:45:06.910] Timer tick, asking Buildbucket for the build status
[06:45:07.086] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:45:07.086] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:10:0) after 1m0s
[06:46:07.178] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:10:0)
[06:46:07.178] Timer tick, asking Buildbucket for the build status
[06:46:07.327] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:46:07.327] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:11:0) after 1m0s
[06:47:07.452] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:11:0)
[06:47:07.452] Timer tick, asking Buildbucket for the build status
[06:47:07.532] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:47:07.532] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:12:0) after 1m0s
[06:48:07.570] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:12:0)
[06:48:07.570] Timer tick, asking Buildbucket for the build status
[06:48:07.770] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:48:07.770] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:13:0) after 1m0s
[06:49:07.844] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:13:0)
[06:49:07.844] Timer tick, asking Buildbucket for the build status
[06:49:08.102] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:49:08.102] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:14:0) after 1m0s
[06:50:09.044] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:14:0)
[06:50:09.044] Timer tick, asking Buildbucket for the build status
[06:50:09.145] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:50:09.145] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:15:0) after 1m0s
[06:51:09.196] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:15:0)
[06:51:09.196] Timer tick, asking Buildbucket for the build status
[06:51:09.432] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:51:09.432] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:16:0) after 1m0s
[06:52:09.473] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:16:0)
[06:52:09.473] Timer tick, asking Buildbucket for the build status
[06:52:09.587] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:52:09.587] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:17:0) after 1m0s
[06:53:09.743] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:17:0)
[06:53:09.743] Timer tick, asking Buildbucket for the build status
[06:53:09.872] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:53:09.872] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:18:0) after 1m0s
[06:54:09.882] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:18:0)
[06:54:09.882] Timer tick, asking Buildbucket for the build status
[06:54:10.255] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:54:10.255] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:19:0) after 1m0s
[06:55:10.366] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:19:0)
[06:55:10.366] Timer tick, asking Buildbucket for the build status
[06:55:10.507] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:55:10.507] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:20:0) after 1m0s
[06:56:10.539] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:20:0)
[06:56:10.539] Timer tick, asking Buildbucket for the build status
[06:56:10.736] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:56:10.736] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:21:0) after 1m0s
[06:57:10.720] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:21:0)
[06:57:10.721] Timer tick, asking Buildbucket for the build status
[06:57:11.866] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:57:11.866] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:22:0) after 1m0s
[06:58:11.905] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:22:0)
[06:58:11.905] Timer tick, asking Buildbucket for the build status
[06:58:12.303] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:58:12.303] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:23:0) after 1m0s
[06:59:12.365] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:23:0)
[06:59:12.365] Timer tick, asking Buildbucket for the build status
[06:59:12.475] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:59:12.475] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:24:0) after 1m0s
[07:00:13.336] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:24:0)
[07:00:13.336] Timer tick, asking Buildbucket for the build status
[07:00:13.779] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:00:13.779] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:25:0) after 1m0s
[07:01:13.829] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:25:0)
[07:01:13.829] Timer tick, asking Buildbucket for the build status
[07:01:13.982] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:01:13.982] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:26:0) after 1m0s
[07:02:14.059] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:26:0)
[07:02:14.059] Timer tick, asking Buildbucket for the build status
[07:02:14.530] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:02:14.530] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:27:0) after 1m0s
[07:03:14.627] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:27:0)
[07:03:14.627] Timer tick, asking Buildbucket for the build status
[07:03:16.614] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:03:16.614] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:28:0) after 1m0s
[07:04:16.644] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:28:0)
[07:04:16.644] Timer tick, asking Buildbucket for the build status
[07:04:16.768] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:04:16.768] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:29:0) after 1m0s
[07:05:16.864] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:29:0)
[07:05:16.864] Timer tick, asking Buildbucket for the build status
[07:05:17.640] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:05:17.640] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:30:0) after 1m0s
[07:06:17.678] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:30:0)
[07:06:17.678] Timer tick, asking Buildbucket for the build status
[07:06:18.588] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:06:18.588] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:31:0) after 1m0s
[07:07:18.623] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:31:0)
[07:07:18.623] Timer tick, asking Buildbucket for the build status
[07:07:18.915] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:07:18.915] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:32:0) after 1m0s
[07:08:18.950] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:32:0)
[07:08:18.950] Timer tick, asking Buildbucket for the build status
[07:08:19.223] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:08:19.223] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:33:0) after 1m0s
[07:09:19.308] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:33:0)
[07:09:19.308] Timer tick, asking Buildbucket for the build status
[07:09:19.510] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:09:19.510] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:34:0) after 1m0s
[07:10:19.585] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:34:0)
[07:10:19.585] Timer tick, asking Buildbucket for the build status
[07:10:19.722] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:10:19.722] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:35:0) after 1m0s
[07:11:19.822] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:35:0)
[07:11:19.822] Timer tick, asking Buildbucket for the build status
[07:11:20.124] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:11:20.124] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:36:0) after 1m0s
[07:12:20.478] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:36:0)
[07:12:20.478] Timer tick, asking Buildbucket for the build status
[07:12:20.697] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:12:20.697] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:37:0) after 1m0s
[07:13:20.837] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:37:0)
[07:13:20.837] Timer tick, asking Buildbucket for the build status
[07:13:21.940] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:13:21.940] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:38:0) after 1m0s
[07:14:22.147] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:38:0)
[07:14:22.147] Timer tick, asking Buildbucket for the build status
[07:14:22.584] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:14:22.584] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:39:0) after 1m0s
[07:15:22.640] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:39:0)
[07:15:22.640] Timer tick, asking Buildbucket for the build status
[07:15:23.007] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:15:23.007] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:40:0) after 1m0s
[07:16:23.113] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:40:0)
[07:16:23.113] Timer tick, asking Buildbucket for the build status
[07:16:24.043] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:16:24.044] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:41:0) after 1m0s
[07:17:24.275] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:41:0)
[07:17:24.275] Timer tick, asking Buildbucket for the build status
[07:17:24.610] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:17:24.610] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:42:0) after 1m0s
[07:18:24.765] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:42:0)
[07:18:24.765] Timer tick, asking Buildbucket for the build status
[07:18:24.961] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:18:24.961] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:43:0) after 1m0s
[07:19:24.999] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:43:0)
[07:19:24.999] Timer tick, asking Buildbucket for the build status
[07:19:25.449] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:19:25.449] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:44:0) after 1m0s
[07:20:25.503] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:44:0)
[07:20:25.503] Timer tick, asking Buildbucket for the build status
[07:20:25.607] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:20:25.607] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:45:0) after 1m0s
[07:21:25.728] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:45:0)
[07:21:25.728] Timer tick, asking Buildbucket for the build status
[07:21:25.844] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:21:25.844] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:46:0) after 1m0s
[07:22:25.882] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:46:0)
[07:22:25.882] Timer tick, asking Buildbucket for the build status
[07:22:26.027] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:22:26.027] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:47:0) after 1m0s
[07:23:26.016] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:47:0)
[07:23:26.016] Timer tick, asking Buildbucket for the build status
[07:23:26.124] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:23:26.124] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:48:0) after 1m0s
[07:24:26.148] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:48:0)
[07:24:26.148] Timer tick, asking Buildbucket for the build status
[07:24:26.327] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:24:26.327] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:49:0) after 1m0s
[07:25:26.412] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:49:0)
[07:25:26.412] Timer tick, asking Buildbucket for the build status
[07:25:26.617] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:25:26.617] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:50:0) after 1m0s
[07:26:26.653] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:50:0)
[07:26:26.653] Timer tick, asking Buildbucket for the build status
[07:26:28.692] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:26:28.693] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:51:0) after 1m0s
[07:27:28.802] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:51:0)
[07:27:28.802] Timer tick, asking Buildbucket for the build status
[07:27:29.065] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:27:29.065] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:52:0) after 1m0s
[07:28:29.078] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:52:0)
[07:28:29.078] Timer tick, asking Buildbucket for the build status
[07:28:29.271] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:28:29.271] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:53:0) after 1m0s
[07:29:29.293] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:53:0)
[07:29:29.293] Timer tick, asking Buildbucket for the build status
[07:29:29.580] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:29:29.580] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:54:0) after 1m0s
[07:30:29.715] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:54:0)
[07:30:29.715] Timer tick, asking Buildbucket for the build status
[07:30:29.879] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:30:29.879] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:55:0) after 1m0s
[07:31:29.903] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:55:0)
[07:31:29.903] Timer tick, asking Buildbucket for the build status
[07:31:31.159] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:31:31.159] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:56:0) after 1m0s
[07:32:31.195] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:56:0)
[07:32:31.195] Timer tick, asking Buildbucket for the build status
[07:32:31.413] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:32:31.413] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:57:0) after 1m0s
[07:33:31.734] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:57:0)
[07:33:31.734] Timer tick, asking Buildbucket for the build status
[07:33:31.910] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:33:31.910] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:58:0) after 1m0s
[07:34:32.141] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:58:0)
[07:34:32.141] Timer tick, asking Buildbucket for the build status
[07:34:32.947] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:34:32.947] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:59:0) after 1m0s
[07:35:33.087] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:59:0)
[07:35:33.087] Timer tick, asking Buildbucket for the build status
[07:35:33.287] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:35:33.287] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:60:0) after 1m0s
[07:36:33.452] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:60:0)
[07:36:33.452] Timer tick, asking Buildbucket for the build status
[07:36:33.595] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:36:33.595] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:61:0) after 1m0s
[07:37:33.631] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:61:0)
[07:37:33.631] Timer tick, asking Buildbucket for the build status
[07:37:33.783] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:37:33.783] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:62:0) after 1m0s
[07:38:33.847] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:62:0)
[07:38:33.847] Timer tick, asking Buildbucket for the build status
[07:38:34.226] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:38:34.226] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:63:0) after 1m0s
[07:39:34.266] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:63:0)
[07:39:34.266] Timer tick, asking Buildbucket for the build status
[07:39:34.697] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:39:34.697] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:64:0) after 1m0s
[07:40:34.727] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:64:0)
[07:40:34.727] Timer tick, asking Buildbucket for the build status
[07:40:35.364] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:40:35.364] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:65:0) after 1m0s
[07:41:35.657] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:65:0)
[07:41:35.657] Timer tick, asking Buildbucket for the build status
[07:41:35.912] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:41:35.912] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:66:0) after 1m0s
[07:42:35.946] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:66:0)
[07:42:35.946] Timer tick, asking Buildbucket for the build status
[07:42:36.108] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:42:36.108] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:67:0) after 1m0s
[07:43:36.198] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:67:0)
[07:43:36.198] Timer tick, asking Buildbucket for the build status
[07:43:36.556] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:43:36.556] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:68:0) after 1m0s
[07:44:36.609] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:68:0)
[07:44:36.609] Timer tick, asking Buildbucket for the build status
[07:44:36.799] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:44:36.799] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:69:0) after 1m0s
[07:45:36.900] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:69:0)
[07:45:36.900] Timer tick, asking Buildbucket for the build status
[07:45:37.006] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:45:37.006] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:70:0) after 1m0s
[07:46:37.355] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:70:0)
[07:46:37.355] Timer tick, asking Buildbucket for the build status
[07:46:37.492] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:46:37.492] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:71:0) after 1m0s
[07:47:37.730] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:71:0)
[07:47:37.730] Timer tick, asking Buildbucket for the build status
[07:47:38.290] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:47:38.290] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:72:0) after 1m0s
[07:48:38.392] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:72:0)
[07:48:38.392] Timer tick, asking Buildbucket for the build status
[07:48:38.494] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:48:38.494] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:73:0) after 1m0s
[07:49:38.726] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:73:0)
[07:49:38.726] Timer tick, asking Buildbucket for the build status
[07:49:38.884] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:49:38.885] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:74:0) after 1m0s
[07:50:38.961] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:74:0)
[07:50:38.961] Timer tick, asking Buildbucket for the build status
[07:50:39.085] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:50:39.085] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:75:0) after 1m0s
[07:51:39.783] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:75:0)
[07:51:39.783] Timer tick, asking Buildbucket for the build status
[07:51:39.922] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:51:39.922] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:76:0) after 1m0s
[07:52:40.018] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:76:0)
[07:52:40.018] Timer tick, asking Buildbucket for the build status
[07:52:40.194] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:52:40.194] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:77:0) after 1m0s
[07:53:40.544] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:77:0)
[07:53:40.544] Timer tick, asking Buildbucket for the build status
[07:53:40.709] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:53:40.709] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:78:0) after 1m0s
[07:54:40.800] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:78:0)
[07:54:40.801] Timer tick, asking Buildbucket for the build status
[07:54:41.062] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:54:41.062] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:79:0) after 1m0s
[07:55:41.302] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:79:0)
[07:55:41.302] Timer tick, asking Buildbucket for the build status
[07:55:41.464] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:55:41.464] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:80:0) after 1m0s
[07:56:41.587] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:80:0)
[07:56:41.587] Timer tick, asking Buildbucket for the build status
[07:56:41.997] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:56:41.997] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:81:0) after 1m0s
[07:57:42.171] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:81:0)
[07:57:42.171] Timer tick, asking Buildbucket for the build status
[07:57:42.526] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:57:42.526] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:82:0) after 1m0s
[07:58:42.730] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:82:0)
[07:58:42.730] Timer tick, asking Buildbucket for the build status
[07:58:42.976] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:58:42.976] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:83:0) after 1m0s
[07:59:43.181] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:83:0)
[07:59:43.181] Timer tick, asking Buildbucket for the build status
[07:59:44.524] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:59:44.525] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:84:0) after 1m0s
[08:00:44.953] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:84:0)
[08:00:44.953] Timer tick, asking Buildbucket for the build status
[08:00:45.338] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:00:45.338] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:85:0) after 1m0s
[08:01:45.648] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:85:0)
[08:01:45.648] Timer tick, asking Buildbucket for the build status
[08:01:46.371] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:01:46.371] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:86:0) after 1m0s
[08:02:46.781] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:86:0)
[08:02:46.781] Timer tick, asking Buildbucket for the build status
[08:02:47.083] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:02:47.083] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:87:0) after 1m0s
[08:03:47.226] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:87:0)
[08:03:47.226] Timer tick, asking Buildbucket for the build status
[08:03:47.627] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:03:47.627] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:88:0) after 1m0s
[08:04:47.808] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:88:0)
[08:04:47.808] Timer tick, asking Buildbucket for the build status
[08:04:48.448] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:04:48.448] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:89:0) after 1m0s
[08:05:48.520] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:89:0)
[08:05:48.520] Timer tick, asking Buildbucket for the build status
[08:05:50.313] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:05:50.313] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:90:0) after 1m0s
[08:06:50.435] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:90:0)
[08:06:50.435] Timer tick, asking Buildbucket for the build status
[08:06:50.658] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:06:50.658] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:91:0) after 1m0s
[08:07:50.724] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:91:0)
[08:07:50.724] Timer tick, asking Buildbucket for the build status
[08:07:51.425] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:07:51.425] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:92:0) after 1m0s
[08:08:51.449] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:92:0)
[08:08:51.449] Timer tick, asking Buildbucket for the build status
[08:08:52.654] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:08:52.654] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:93:0) after 1m0s
[08:09:52.704] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:93:0)
[08:09:52.704] Timer tick, asking Buildbucket for the build status
[08:09:52.885] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:09:52.885] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:94:0) after 1m0s
[08:10:52.979] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:94:0)
[08:10:52.979] Timer tick, asking Buildbucket for the build status
[08:10:53.167] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:10:53.167] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:95:0) after 1m0s
[08:11:53.203] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:95:0)
[08:11:53.203] Timer tick, asking Buildbucket for the build status
[08:11:53.911] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:11:53.911] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:96:0) after 1m0s
[08:12:53.885] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:96:0)
[08:12:53.885] Timer tick, asking Buildbucket for the build status
[08:12:54.204] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:12:54.204] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:97:0) after 1m0s
[08:13:54.222] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:97:0)
[08:13:54.222] Timer tick, asking Buildbucket for the build status
[08:13:54.353] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:13:54.353] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:98:0) after 1m0s
[08:14:54.485] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:98:0)
[08:14:54.485] Timer tick, asking Buildbucket for the build status
[08:14:54.615] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:14:54.615] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:99:0) after 1m0s
[08:15:54.650] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:99:0)
[08:15:54.650] Timer tick, asking Buildbucket for the build status
[08:15:54.927] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:15:54.927] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:100:0) after 1m0s
[08:16:54.963] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:100:0)
[08:16:54.963] Timer tick, asking Buildbucket for the build status
[08:16:55.789] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:16:55.789] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:101:0) after 1m0s
[08:17:55.850] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:101:0)
[08:17:55.850] Timer tick, asking Buildbucket for the build status
[08:17:56.174] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:17:56.174] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:102:0) after 1m0s
[08:18:56.223] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:102:0)
[08:18:56.223] Timer tick, asking Buildbucket for the build status
[08:18:56.361] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:18:56.361] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:103:0) after 1m0s
[08:19:56.392] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:103:0)
[08:19:56.392] Timer tick, asking Buildbucket for the build status
[08:19:56.528] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:19:56.528] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:104:0) after 1m0s
[08:20:56.605] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:104:0)
[08:20:56.605] Timer tick, asking Buildbucket for the build status
[08:20:56.807] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:20:56.807] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:105:0) after 1m0s
[08:21:56.904] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:105:0)
[08:21:56.904] Timer tick, asking Buildbucket for the build status
[08:21:57.059] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:21:57.059] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:106:0) after 1m0s
[08:22:57.097] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:106:0)
[08:22:57.097] Timer tick, asking Buildbucket for the build status
[08:22:57.250] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:22:57.250] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:107:0) after 1m0s
[08:23:57.306] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:107:0)
[08:23:57.306] Timer tick, asking Buildbucket for the build status
[08:23:57.441] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:23:57.441] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:108:0) after 1m0s
[08:24:57.495] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:108:0)
[08:24:57.495] Timer tick, asking Buildbucket for the build status
[08:24:57.652] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:24:57.652] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:109:0) after 1m0s
[08:25:57.715] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:109:0)
[08:25:57.715] Timer tick, asking Buildbucket for the build status
[08:25:57.840] Build 8900640661429706864: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:25:57.840] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:110:0) after 1m0s
[08:26:51.190] Received PubSub notification, asking Buildbucket for the build status
[08:26:51.329] Build 8900640661429706864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:57.843] Handling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:110:0)
[08:26:57.843] Timer tick, asking Buildbucket for the build status
[08:26:57.975] Build 8900640661429706864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:57.975] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball_dispatcher:9066070723463708576:112:0) after 1m0s
[08:27:45.053] Received PubSub notification, asking Buildbucket for the build status
[08:27:45.143] Build 8900640661429706864: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:27:45.143] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1570091264006102",
"created_by": "project:infra",
"created_ts": "1570084623841232",
"id": "8900640661429706864",
"parameters_json": "{\"builder_name\": \"publish_tarball_dispatcher\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"4716\"}, \"id\": \"cron:v1:4716\"}]}}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$gatekeeper\": {\"group\": \"chromium.infra.cron\"}, \"$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}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"4716\"}, \"id\": \"cron:v1:4716\"}]}, \"bot_id\": \"luci-infra-cron-xenial-medium-1-wauc\", \"buildername\": \"publish_tarball_dispatcher\", \"path_config\": \"generic\", \"recipe\": \"publish_tarball\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_b136a1c49c74fd832625137d469d90163c6f70564fe4999249905c86add33f53_v2\", \"git\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-medium-1-wauc\"], \"image\": [\"chrome-xenial-19090600-ed917d8233f\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"python3\": [\"3.5.2\"], \"server_version\": [\"4466-755d6a2\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1570091209811814",
"status": "COMPLETED",
"status_changed_ts": "1570091264646578",
"tags": [
"builder:publish_tarball_dispatcher",
"scheduler_invocation_id:9066070723463708576",
"scheduler_job_id:infra/publish_tarball_dispatcher",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8900640661429706864/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:publish_tarball",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:47a927b216cff810",
"user_agent:luci-scheduler"
],
"updated_ts": "1570091264657241",
"url": "https://ci.chromium.org/b/8900640661429706864",
"utcnow_ts": "1570091265126994"
}
}
[08:27:45.143] Invocation finished in 1h50m42.625564201s with status SUCCEEDED