[05:23:56.439] New invocation is queued and will start shortly
[05:23:56.439] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[05:23:57.762] Starting the invocation (attempt 1)
[05:23:57.803] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:23:57.803] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:23:57.803] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9086187849162996432",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"74.0.3713.3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9086187849162996432",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[05:23:59.009] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550899437875196",
"id": "8920757786989429616",
"lease_expiration_ts": "1551018238865364",
"lease_key": "146768649",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3713.3\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1550899438865882",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9086187849162996432",
"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/8920757786989429616/+/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:4331a0ebeb477710",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1550899438865962",
"url": "https://ci.chromium.org/b/8920757786989429616",
"utcnow_ts": "1550899439000000"
}
}
[05:23:59.009] Task URL: https://ci.chromium.org/b/8920757786989429616
[05:23:59.009] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:2:0) after 1m0s
[05:24:14.337] Received PubSub notification, asking Buildbucket for the build status
[05:24:14.480] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:59.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:2:0)
[05:24:59.046] Timer tick, asking Buildbucket for the build status
[05:24:59.167] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:59.167] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:4:0) after 1m0s
[05:25:59.187] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:4:0)
[05:25:59.187] Timer tick, asking Buildbucket for the build status
[05:25:59.237] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:59.237] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:5:0) after 1m0s
[05:26:59.263] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:5:0)
[05:26:59.263] Timer tick, asking Buildbucket for the build status
[05:26:59.393] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:59.393] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:6:0) after 1m0s
[05:27:59.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:6:0)
[05:27:59.408] Timer tick, asking Buildbucket for the build status
[05:27:59.586] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:59.586] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:7:0) after 1m0s
[05:28:59.640] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:7:0)
[05:28:59.640] Timer tick, asking Buildbucket for the build status
[05:28:59.770] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:59.770] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:8:0) after 1m0s
[05:29:59.788] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:8:0)
[05:29:59.788] Timer tick, asking Buildbucket for the build status
[05:29:59.881] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:59.881] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:9:0) after 1m0s
[05:30:59.899] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:9:0)
[05:30:59.899] Timer tick, asking Buildbucket for the build status
[05:31:00.079] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:00.079] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:10:0) after 1m0s
[05:32:00.100] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:10:0)
[05:32:00.100] Timer tick, asking Buildbucket for the build status
[05:32:00.234] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:00.234] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:11:0) after 1m0s
[05:33:00.257] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:11:0)
[05:33:00.257] Timer tick, asking Buildbucket for the build status
[05:33:00.484] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:00.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:12:0) after 1m0s
[05:34:00.508] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:12:0)
[05:34:00.508] Timer tick, asking Buildbucket for the build status
[05:34:00.775] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:00.776] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:13:0) after 1m0s
[05:35:00.800] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:13:0)
[05:35:00.800] Timer tick, asking Buildbucket for the build status
[05:35:00.896] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:00.896] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:14:0) after 1m0s
[05:36:00.914] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:14:0)
[05:36:00.914] Timer tick, asking Buildbucket for the build status
[05:36:01.157] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:01.157] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:15:0) after 1m0s
[05:37:01.175] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:15:0)
[05:37:01.175] Timer tick, asking Buildbucket for the build status
[05:37:01.241] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:01.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:16:0) after 1m0s
[05:38:01.269] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:16:0)
[05:38:01.269] Timer tick, asking Buildbucket for the build status
[05:38:01.546] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:01.546] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:17:0) after 1m0s
[05:39:01.569] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:17:0)
[05:39:01.569] Timer tick, asking Buildbucket for the build status
[05:39:01.652] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:01.652] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:18:0) after 1m0s
[05:40:01.667] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:18:0)
[05:40:01.667] Timer tick, asking Buildbucket for the build status
[05:40:01.734] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:01.734] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:19:0) after 1m0s
[05:41:01.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:19:0)
[05:41:01.750] Timer tick, asking Buildbucket for the build status
[05:41:01.820] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:01.820] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:20:0) after 1m0s
[05:42:01.837] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:20:0)
[05:42:01.837] Timer tick, asking Buildbucket for the build status
[05:42:02.218] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:02.218] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:21:0) after 1m0s
[05:43:02.280] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:21:0)
[05:43:02.280] Timer tick, asking Buildbucket for the build status
[05:43:02.364] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:02.364] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:22:0) after 1m0s
[05:44:02.467] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:22:0)
[05:44:02.467] Timer tick, asking Buildbucket for the build status
[05:44:02.627] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:02.627] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:23:0) after 1m0s
[05:45:02.644] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:23:0)
[05:45:02.644] Timer tick, asking Buildbucket for the build status
[05:45:02.730] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:02.730] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:24:0) after 1m0s
[05:46:02.747] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:24:0)
[05:46:02.747] Timer tick, asking Buildbucket for the build status
[05:46:02.804] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:02.804] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:25:0) after 1m0s
[05:47:02.820] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:25:0)
[05:47:02.820] Timer tick, asking Buildbucket for the build status
[05:47:02.870] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:02.870] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:26:0) after 1m0s
[05:48:02.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:26:0)
[05:48:02.888] Timer tick, asking Buildbucket for the build status
[05:48:03.012] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:03.013] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:27:0) after 1m0s
[05:49:03.032] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:27:0)
[05:49:03.032] Timer tick, asking Buildbucket for the build status
[05:49:03.073] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:03.073] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:28:0) after 1m0s
[05:50:03.102] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:28:0)
[05:50:03.102] Timer tick, asking Buildbucket for the build status
[05:50:03.307] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:03.307] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:29:0) after 1m0s
[05:51:03.324] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:29:0)
[05:51:03.324] Timer tick, asking Buildbucket for the build status
[05:51:03.407] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:03.407] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:30:0) after 1m0s
[05:52:03.427] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:30:0)
[05:52:03.427] Timer tick, asking Buildbucket for the build status
[05:52:03.558] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:03.558] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:31:0) after 1m0s
[05:53:03.576] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:31:0)
[05:53:03.576] Timer tick, asking Buildbucket for the build status
[05:53:03.618] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:03.619] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:32:0) after 1m0s
[05:54:03.640] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:32:0)
[05:54:03.640] Timer tick, asking Buildbucket for the build status
[05:54:03.685] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:03.685] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:33:0) after 1m0s
[05:55:03.703] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:33:0)
[05:55:03.703] Timer tick, asking Buildbucket for the build status
[05:55:03.777] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:03.777] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:34:0) after 1m0s
[05:56:03.795] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:34:0)
[05:56:03.795] Timer tick, asking Buildbucket for the build status
[05:56:03.855] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:03.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:35:0) after 1m0s
[05:57:03.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:35:0)
[05:57:03.874] Timer tick, asking Buildbucket for the build status
[05:57:03.998] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:03.998] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:36:0) after 1m0s
[05:58:04.030] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:36:0)
[05:58:04.030] Timer tick, asking Buildbucket for the build status
[05:58:04.102] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:04.102] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:37:0) after 1m0s
[05:59:04.132] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:37:0)
[05:59:04.132] Timer tick, asking Buildbucket for the build status
[05:59:04.597] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:04.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:38:0) after 1m0s
[06:00:04.628] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:38:0)
[06:00:04.628] Timer tick, asking Buildbucket for the build status
[06:00:04.851] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:04.851] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:39:0) after 1m0s
[06:01:04.871] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:39:0)
[06:01:04.871] Timer tick, asking Buildbucket for the build status
[06:01:05.111] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:05.111] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:40:0) after 1m0s
[06:02:05.138] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:40:0)
[06:02:05.138] Timer tick, asking Buildbucket for the build status
[06:02:05.282] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:05.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:41:0) after 1m0s
[06:03:05.306] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:41:0)
[06:03:05.306] Timer tick, asking Buildbucket for the build status
[06:03:05.347] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:05.347] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:42:0) after 1m0s
[06:04:05.362] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:42:0)
[06:04:05.363] Timer tick, asking Buildbucket for the build status
[06:04:05.410] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:05.410] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:43:0) after 1m0s
[06:05:05.430] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:43:0)
[06:05:05.430] Timer tick, asking Buildbucket for the build status
[06:05:05.561] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:05.561] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:44:0) after 1m0s
[06:06:05.591] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:44:0)
[06:06:05.591] Timer tick, asking Buildbucket for the build status
[06:06:05.681] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:05.681] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:45:0) after 1m0s
[06:07:05.699] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:45:0)
[06:07:05.700] Timer tick, asking Buildbucket for the build status
[06:07:05.792] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:05.792] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:46:0) after 1m0s
[06:08:05.812] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:46:0)
[06:08:05.812] Timer tick, asking Buildbucket for the build status
[06:08:05.886] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:05.886] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:47:0) after 1m0s
[06:09:05.965] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:47:0)
[06:09:05.965] Timer tick, asking Buildbucket for the build status
[06:09:06.032] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:06.032] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:48:0) after 1m0s
[06:10:06.071] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:48:0)
[06:10:06.071] Timer tick, asking Buildbucket for the build status
[06:10:06.138] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:06.138] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:49:0) after 1m0s
[06:11:06.158] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:49:0)
[06:11:06.158] Timer tick, asking Buildbucket for the build status
[06:11:06.324] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:06.324] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:50:0) after 1m0s
[06:12:06.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:50:0)
[06:12:06.341] Timer tick, asking Buildbucket for the build status
[06:12:06.586] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:06.586] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:51:0) after 1m0s
[06:13:06.611] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:51:0)
[06:13:06.611] Timer tick, asking Buildbucket for the build status
[06:13:06.676] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:06.676] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:52:0) after 1m0s
[06:14:06.695] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:52:0)
[06:14:06.695] Timer tick, asking Buildbucket for the build status
[06:14:06.823] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:06.823] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:53:0) after 1m0s
[06:15:06.870] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:53:0)
[06:15:06.870] Timer tick, asking Buildbucket for the build status
[06:15:06.928] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:06.928] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:54:0) after 1m0s
[06:16:06.953] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:54:0)
[06:16:06.953] Timer tick, asking Buildbucket for the build status
[06:16:07.029] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:07.029] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:55:0) after 1m0s
[06:17:07.103] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:55:0)
[06:17:07.103] Timer tick, asking Buildbucket for the build status
[06:17:07.158] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:07.158] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:56:0) after 1m0s
[06:18:07.177] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:56:0)
[06:18:07.177] Timer tick, asking Buildbucket for the build status
[06:18:07.426] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:07.426] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:57:0) after 1m0s
[06:19:07.445] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:57:0)
[06:19:07.445] Timer tick, asking Buildbucket for the build status
[06:19:07.777] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:07.777] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:58:0) after 1m0s
[06:20:07.799] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:58:0)
[06:20:07.799] Timer tick, asking Buildbucket for the build status
[06:20:07.892] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:07.892] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:59:0) after 1m0s
[06:21:07.916] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:59:0)
[06:21:07.916] Timer tick, asking Buildbucket for the build status
[06:21:07.988] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:07.988] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:60:0) after 1m0s
[06:22:08.017] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:60:0)
[06:22:08.017] Timer tick, asking Buildbucket for the build status
[06:22:08.076] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:08.076] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:61:0) after 1m0s
[06:23:08.107] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:61:0)
[06:23:08.107] Timer tick, asking Buildbucket for the build status
[06:23:08.215] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:08.215] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:62:0) after 1m0s
[06:24:08.243] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:62:0)
[06:24:08.243] Timer tick, asking Buildbucket for the build status
[06:24:08.412] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:08.412] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:63:0) after 1m0s
[06:25:08.432] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:63:0)
[06:25:08.432] Timer tick, asking Buildbucket for the build status
[06:25:08.511] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:08.511] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:64:0) after 1m0s
[06:26:08.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:64:0)
[06:26:08.532] Timer tick, asking Buildbucket for the build status
[06:26:08.890] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:08.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:65:0) after 1m0s
[06:27:08.908] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:65:0)
[06:27:08.908] Timer tick, asking Buildbucket for the build status
[06:27:09.083] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:09.083] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:66:0) after 1m0s
[06:28:09.130] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:66:0)
[06:28:09.130] Timer tick, asking Buildbucket for the build status
[06:28:09.195] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:09.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:67:0) after 1m0s
[06:29:09.268] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:67:0)
[06:29:09.268] Timer tick, asking Buildbucket for the build status
[06:29:09.387] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:09.387] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:68:0) after 1m0s
[06:30:09.411] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:68:0)
[06:30:09.411] Timer tick, asking Buildbucket for the build status
[06:30:09.472] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:09.472] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:69:0) after 1m0s
[06:31:09.489] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:69:0)
[06:31:09.489] Timer tick, asking Buildbucket for the build status
[06:31:09.532] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:09.532] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:70:0) after 1m0s
[06:32:09.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:70:0)
[06:32:09.551] Timer tick, asking Buildbucket for the build status
[06:32:09.599] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:09.599] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:71:0) after 1m0s
[06:33:09.624] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:71:0)
[06:33:09.624] Timer tick, asking Buildbucket for the build status
[06:33:09.668] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:09.668] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:72:0) after 1m0s
[06:34:09.685] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:72:0)
[06:34:09.685] Timer tick, asking Buildbucket for the build status
[06:34:09.819] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:09.819] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:73:0) after 1m0s
[06:35:09.842] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:73:0)
[06:35:09.842] Timer tick, asking Buildbucket for the build status
[06:35:09.982] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:09.982] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:74:0) after 1m0s
[06:36:10.001] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:74:0)
[06:36:10.001] Timer tick, asking Buildbucket for the build status
[06:36:10.043] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:10.043] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:75:0) after 1m0s
[06:37:10.066] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:75:0)
[06:37:10.066] Timer tick, asking Buildbucket for the build status
[06:37:10.148] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:10.148] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:76:0) after 1m0s
[06:38:10.170] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:76:0)
[06:38:10.170] Timer tick, asking Buildbucket for the build status
[06:38:10.251] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:10.251] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:77:0) after 1m0s
[06:39:10.279] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:77:0)
[06:39:10.279] Timer tick, asking Buildbucket for the build status
[06:39:10.520] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:10.520] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:78:0) after 1m0s
[06:40:10.545] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:78:0)
[06:40:10.545] Timer tick, asking Buildbucket for the build status
[06:40:10.834] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:10.834] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:79:0) after 1m0s
[06:41:10.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:79:0)
[06:41:10.852] Timer tick, asking Buildbucket for the build status
[06:41:10.915] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:10.915] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:80:0) after 1m0s
[06:42:10.936] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:80:0)
[06:42:10.936] Timer tick, asking Buildbucket for the build status
[06:42:11.074] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:11.074] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:81:0) after 1m0s
[06:43:11.096] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:81:0)
[06:43:11.096] Timer tick, asking Buildbucket for the build status
[06:43:11.245] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:11.245] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:82:0) after 1m0s
[06:44:11.273] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:82:0)
[06:44:11.273] Timer tick, asking Buildbucket for the build status
[06:44:11.389] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:11.389] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:83:0) after 1m0s
[06:45:11.413] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:83:0)
[06:45:11.413] Timer tick, asking Buildbucket for the build status
[06:45:11.894] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:11.894] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:84:0) after 1m0s
[06:46:11.922] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:84:0)
[06:46:11.922] Timer tick, asking Buildbucket for the build status
[06:46:11.984] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:11.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:85:0) after 1m0s
[06:47:12.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:85:0)
[06:47:12.004] Timer tick, asking Buildbucket for the build status
[06:47:12.105] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:12.105] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:86:0) after 1m0s
[06:48:12.130] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:86:0)
[06:48:12.130] Timer tick, asking Buildbucket for the build status
[06:48:12.212] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:12.212] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:87:0) after 1m0s
[06:49:12.242] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:87:0)
[06:49:12.242] Timer tick, asking Buildbucket for the build status
[06:49:12.291] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:12.291] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:88:0) after 1m0s
[06:50:12.314] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:88:0)
[06:50:12.314] Timer tick, asking Buildbucket for the build status
[06:50:12.425] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:12.425] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:89:0) after 1m0s
[06:51:12.446] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:89:0)
[06:51:12.446] Timer tick, asking Buildbucket for the build status
[06:51:12.516] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:12.516] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:90:0) after 1m0s
[06:52:12.545] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:90:0)
[06:52:12.545] Timer tick, asking Buildbucket for the build status
[06:52:12.693] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:12.693] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:91:0) after 1m0s
[06:53:12.713] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:91:0)
[06:53:12.713] Timer tick, asking Buildbucket for the build status
[06:53:12.773] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:12.774] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:92:0) after 1m0s
[06:54:12.801] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:92:0)
[06:54:12.801] Timer tick, asking Buildbucket for the build status
[06:54:12.889] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:12.889] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:93:0) after 1m0s
[06:55:12.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:93:0)
[06:55:12.925] Timer tick, asking Buildbucket for the build status
[06:55:13.207] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:13.207] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:94:0) after 1m0s
[06:56:13.262] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:94:0)
[06:56:13.262] Timer tick, asking Buildbucket for the build status
[06:56:13.359] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:13.359] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:95:0) after 1m0s
[06:57:13.383] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:95:0)
[06:57:13.383] Timer tick, asking Buildbucket for the build status
[06:57:13.498] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:13.498] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:96:0) after 1m0s
[06:58:13.518] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:96:0)
[06:58:13.518] Timer tick, asking Buildbucket for the build status
[06:58:13.587] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:13.587] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:97:0) after 1m0s
[06:59:13.607] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:97:0)
[06:59:13.607] Timer tick, asking Buildbucket for the build status
[06:59:13.891] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:13.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:98:0) after 1m0s
[07:00:14.012] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:98:0)
[07:00:14.012] Timer tick, asking Buildbucket for the build status
[07:00:14.121] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:14.121] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:99:0) after 1m0s
[07:01:14.144] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:99:0)
[07:01:14.144] Timer tick, asking Buildbucket for the build status
[07:01:14.277] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:14.277] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:100:0) after 1m0s
[07:02:14.300] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:100:0)
[07:02:14.300] Timer tick, asking Buildbucket for the build status
[07:02:14.367] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:14.367] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:101:0) after 1m0s
[07:03:14.395] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:101:0)
[07:03:14.395] Timer tick, asking Buildbucket for the build status
[07:03:14.494] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:14.494] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:102:0) after 1m0s
[07:04:14.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:102:0)
[07:04:14.515] Timer tick, asking Buildbucket for the build status
[07:04:14.622] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:14.622] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:103:0) after 1m0s
[07:05:14.684] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:103:0)
[07:05:14.684] Timer tick, asking Buildbucket for the build status
[07:05:14.768] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:14.768] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:104:0) after 1m0s
[07:06:14.788] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:104:0)
[07:06:14.788] Timer tick, asking Buildbucket for the build status
[07:06:14.868] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:14.868] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:105:0) after 1m0s
[07:07:14.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:105:0)
[07:07:14.886] Timer tick, asking Buildbucket for the build status
[07:07:14.961] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:14.961] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:106:0) after 1m0s
[07:08:14.999] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:106:0)
[07:08:14.999] Timer tick, asking Buildbucket for the build status
[07:08:15.075] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:15.075] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:107:0) after 1m0s
[07:09:15.098] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:107:0)
[07:09:15.098] Timer tick, asking Buildbucket for the build status
[07:09:15.215] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:15.215] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:108:0) after 1m0s
[07:10:15.237] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:108:0)
[07:10:15.237] Timer tick, asking Buildbucket for the build status
[07:10:15.322] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:15.323] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:109:0) after 1m0s
[07:11:15.365] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:109:0)
[07:11:15.365] Timer tick, asking Buildbucket for the build status
[07:11:15.440] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:15.440] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:110:0) after 1m0s
[07:12:15.469] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:110:0)
[07:12:15.469] Timer tick, asking Buildbucket for the build status
[07:12:15.703] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:15.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:111:0) after 1m0s
[07:13:15.727] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:111:0)
[07:13:15.727] Timer tick, asking Buildbucket for the build status
[07:13:15.819] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:15.819] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:112:0) after 1m0s
[07:14:15.840] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:112:0)
[07:14:15.840] Timer tick, asking Buildbucket for the build status
[07:14:16.067] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:16.067] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:113:0) after 1m0s
[07:15:16.092] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:113:0)
[07:15:16.092] Timer tick, asking Buildbucket for the build status
[07:15:16.228] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:16.228] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:114:0) after 1m0s
[07:16:16.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:114:0)
[07:16:16.248] Timer tick, asking Buildbucket for the build status
[07:16:16.387] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:16.387] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:115:0) after 1m0s
[07:17:16.431] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:115:0)
[07:17:16.431] Timer tick, asking Buildbucket for the build status
[07:17:16.508] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:16.508] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:116:0) after 1m0s
[07:18:16.540] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:116:0)
[07:18:16.540] Timer tick, asking Buildbucket for the build status
[07:18:16.594] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:16.594] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:117:0) after 1m0s
[07:19:16.617] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:117:0)
[07:19:16.617] Timer tick, asking Buildbucket for the build status
[07:19:16.702] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:16.702] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:118:0) after 1m0s
[07:20:16.724] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:118:0)
[07:20:16.724] Timer tick, asking Buildbucket for the build status
[07:20:16.782] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:16.782] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:119:0) after 1m0s
[07:21:16.800] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:119:0)
[07:21:16.800] Timer tick, asking Buildbucket for the build status
[07:21:16.884] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:16.884] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:120:0) after 1m0s
[07:22:16.988] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:120:0)
[07:22:16.988] Timer tick, asking Buildbucket for the build status
[07:22:17.158] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:17.158] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:121:0) after 1m0s
[07:23:17.180] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:121:0)
[07:23:17.180] Timer tick, asking Buildbucket for the build status
[07:23:17.263] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:17.263] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:122:0) after 1m0s
[07:24:17.282] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:122:0)
[07:24:17.283] Timer tick, asking Buildbucket for the build status
[07:24:17.371] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:17.371] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:123:0) after 1m0s
[07:25:17.392] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:123:0)
[07:25:17.392] Timer tick, asking Buildbucket for the build status
[07:25:17.454] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:17.454] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:124:0) after 1m0s
[07:26:17.477] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:124:0)
[07:26:17.477] Timer tick, asking Buildbucket for the build status
[07:26:17.525] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:17.525] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:125:0) after 1m0s
[07:27:17.544] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:125:0)
[07:27:17.544] Timer tick, asking Buildbucket for the build status
[07:27:17.618] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:17.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:126:0) after 1m0s
[07:28:17.704] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:126:0)
[07:28:17.704] Timer tick, asking Buildbucket for the build status
[07:28:17.788] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:17.788] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:127:0) after 1m0s
[07:29:17.808] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:127:0)
[07:29:17.808] Timer tick, asking Buildbucket for the build status
[07:29:17.886] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:17.886] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:128:0) after 1m0s
[07:30:17.907] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:128:0)
[07:30:17.907] Timer tick, asking Buildbucket for the build status
[07:30:18.037] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:18.037] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:129:0) after 1m0s
[07:31:18.059] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:129:0)
[07:31:18.059] Timer tick, asking Buildbucket for the build status
[07:31:18.117] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:18.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:130:0) after 1m0s
[07:32:18.160] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:130:0)
[07:32:18.160] Timer tick, asking Buildbucket for the build status
[07:32:18.294] Build 8920757786989429616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:18.294] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9086187849162996432:131:0) after 1m0s
[07:32:31.300] Received PubSub notification, asking Buildbucket for the build status
[07:32:31.594] Build 8920757786989429616: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:32:31.594] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1550907150383952",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550899437875196",
"id": "8920757786989429616",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3713.3\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-32\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"Build From Tarball\"], \"kvm\": [\"1\"], \"id\": [\"gce-trusty-32-0258d129-us-west1-c-bnng\"], \"server_version\": [\"4120-35fcaef\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"gpu\": [\"none\"], \"cores\": [\"32\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"pool\": [\"luci.infra.cron\"], \"caches\": [\"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"buildername\": \"Build From Tarball\", \"recipe\": \"build_from_tarball\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1550899437875196, \"tags\": [\"builder:Build From Tarball\", \"parent_buildername:publish_tarball\", \"scheduler_invocation_id:9086187849162996432\", \"scheduler_job_id:infra/Build From Tarball\", \"user_agent:luci-scheduler\", \"user_agent:recipe\"], \"bucket\": \"luci.infra.cron\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"infra\", \"id\": \"8920757786989429616\"}}, \"version\": \"74.0.3713.3\", \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Build From Tarball\", \"key\": \"builder\"}, {\"value\": \"publish_tarball\", \"key\": \"parent_buildername\"}, {\"value\": \"9086187849162996432\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"infra/Build From Tarball\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}, {\"value\": \"recipe\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"infra\", \"builder\": \"Build From Tarball\", \"bucket\": \"cron\"}, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"8b9d00ab4b5db6237327191cf61f2fdd41374b9f\"}, \"recipe\": {\"name\": \"build_from_tarball\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/infra/infra\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8920757786989429616\", \"input\": {}, \"createTime\": \"2019-02-23T05:23:57.875196Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"gce-trusty-32-0258d129-us-west1-c-bnng\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1550899452692244",
"status": "COMPLETED",
"status_changed_ts": "1550907150989323",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9086187849162996432",
"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/8920757786989429616/+/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:4331a0ebeb477710",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1550907150990527",
"url": "https://ci.chromium.org/b/8920757786989429616",
"utcnow_ts": "1550907151406057"
}
}
[07:32:31.594] Invocation finished in 2h8m35.175467148s with status SUCCEEDED