[13:38:28.303] New invocation is queued and will start shortly
[13:38:28.303] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[13:38:29.465] Starting the invocation (attempt 1)
[13:38:29.512] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:38:29.512] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:38:29.512] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9084978975397867872",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"74.0.3729.0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9084978975397867872",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[13:38:31.475] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552052309621783",
"id": "8919548913345394000",
"lease_expiration_ts": "1552171111192194",
"lease_key": "1756015221",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3729.0\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552052311194332",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9084978975397867872",
"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/8919548913345394000/+/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:43765859beec6210",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1552052311194554",
"url": "https://ci.chromium.org/b/8919548913345394000",
"utcnow_ts": "1552052311457943"
}
}
[13:38:31.475] Task URL: https://ci.chromium.org/b/8919548913345394000
[13:38:31.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:2:0) after 1m0s
[13:38:46.406] Received PubSub notification, asking Buildbucket for the build status
[13:38:46.457] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:31.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:2:0)
[13:39:31.491] Timer tick, asking Buildbucket for the build status
[13:39:31.809] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:31.809] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:4:0) after 1m0s
[13:40:31.830] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:4:0)
[13:40:31.830] Timer tick, asking Buildbucket for the build status
[13:40:32.054] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:32.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:5:0) after 1m0s
[13:41:32.075] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:5:0)
[13:41:32.075] Timer tick, asking Buildbucket for the build status
[13:41:32.153] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:32.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:6:0) after 1m0s
[13:42:32.410] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:6:0)
[13:42:32.410] Timer tick, asking Buildbucket for the build status
[13:42:32.584] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:32.584] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:7:0) after 1m0s
[13:43:32.564] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:7:0)
[13:43:32.564] Timer tick, asking Buildbucket for the build status
[13:43:32.677] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:32.677] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:8:0) after 1m0s
[13:44:32.693] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:8:0)
[13:44:32.693] Timer tick, asking Buildbucket for the build status
[13:44:32.871] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:32.871] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:9:0) after 1m0s
[13:45:32.859] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:9:0)
[13:45:32.859] Timer tick, asking Buildbucket for the build status
[13:45:32.899] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:32.899] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:10:0) after 1m0s
[13:46:32.883] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:10:0)
[13:46:32.883] Timer tick, asking Buildbucket for the build status
[13:46:33.000] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:33.000] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:11:0) after 1m0s
[13:47:32.983] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:11:0)
[13:47:32.983] Timer tick, asking Buildbucket for the build status
[13:47:33.027] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:33.027] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:12:0) after 1m0s
[13:48:33.000] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:12:0)
[13:48:33.000] Timer tick, asking Buildbucket for the build status
[13:48:33.047] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:33.047] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:13:0) after 1m0s
[13:49:33.064] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:13:0)
[13:49:33.064] Timer tick, asking Buildbucket for the build status
[13:49:33.121] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:33.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:14:0) after 1m0s
[13:50:33.144] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:14:0)
[13:50:33.144] Timer tick, asking Buildbucket for the build status
[13:50:33.211] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:33.211] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:15:0) after 1m0s
[13:51:33.230] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:15:0)
[13:51:33.230] Timer tick, asking Buildbucket for the build status
[13:51:33.286] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:33.286] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:16:0) after 1m0s
[13:52:33.275] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:16:0)
[13:52:33.275] Timer tick, asking Buildbucket for the build status
[13:52:33.309] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:33.309] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:17:0) after 1m0s
[13:53:33.328] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:17:0)
[13:53:33.328] Timer tick, asking Buildbucket for the build status
[13:53:33.385] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:33.385] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:18:0) after 1m0s
[13:54:33.388] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:18:0)
[13:54:33.388] Timer tick, asking Buildbucket for the build status
[13:54:33.580] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:33.580] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:19:0) after 1m0s
[13:55:33.598] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:19:0)
[13:55:33.598] Timer tick, asking Buildbucket for the build status
[13:55:34.067] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:34.067] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:20:0) after 1m0s
[13:56:34.095] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:20:0)
[13:56:34.095] Timer tick, asking Buildbucket for the build status
[13:56:34.132] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:34.132] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:21:0) after 1m0s
[13:57:34.108] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:21:0)
[13:57:34.108] Timer tick, asking Buildbucket for the build status
[13:57:34.312] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:34.312] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:22:0) after 1m0s
[13:58:34.304] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:22:0)
[13:58:34.304] Timer tick, asking Buildbucket for the build status
[13:58:34.447] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:34.447] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:23:0) after 1m0s
[13:59:34.419] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:23:0)
[13:59:34.419] Timer tick, asking Buildbucket for the build status
[13:59:34.508] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:34.508] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:24:0) after 1m0s
[14:00:34.526] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:24:0)
[14:00:34.526] Timer tick, asking Buildbucket for the build status
[14:00:34.779] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:34.779] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:25:0) after 1m0s
[14:01:34.795] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:25:0)
[14:01:34.795] Timer tick, asking Buildbucket for the build status
[14:01:34.829] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:34.829] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:26:0) after 1m0s
[14:02:34.849] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:26:0)
[14:02:34.850] Timer tick, asking Buildbucket for the build status
[14:02:34.937] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:34.937] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:27:0) after 1m0s
[14:03:34.963] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:27:0)
[14:03:34.963] Timer tick, asking Buildbucket for the build status
[14:03:35.000] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:35.000] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:28:0) after 1m0s
[14:04:35.022] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:28:0)
[14:04:35.022] Timer tick, asking Buildbucket for the build status
[14:04:35.184] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:35.184] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:29:0) after 1m0s
[14:05:35.207] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:29:0)
[14:05:35.207] Timer tick, asking Buildbucket for the build status
[14:05:35.247] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:35.247] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:30:0) after 1m0s
[14:06:35.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:30:0)
[14:06:35.246] Timer tick, asking Buildbucket for the build status
[14:06:35.361] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:35.361] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:31:0) after 1m0s
[14:07:35.378] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:31:0)
[14:07:35.378] Timer tick, asking Buildbucket for the build status
[14:07:35.679] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:35.679] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:32:0) after 1m0s
[14:08:35.701] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:32:0)
[14:08:35.701] Timer tick, asking Buildbucket for the build status
[14:08:35.887] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:35.887] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:33:0) after 1m0s
[14:09:35.878] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:33:0)
[14:09:35.878] Timer tick, asking Buildbucket for the build status
[14:09:35.909] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:35.909] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:34:0) after 1m0s
[14:10:35.926] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:34:0)
[14:10:35.926] Timer tick, asking Buildbucket for the build status
[14:10:36.226] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:36.226] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:35:0) after 1m0s
[14:11:36.221] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:35:0)
[14:11:36.221] Timer tick, asking Buildbucket for the build status
[14:11:36.497] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:36.497] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:36:0) after 1m0s
[14:12:36.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:36:0)
[14:12:36.532] Timer tick, asking Buildbucket for the build status
[14:12:36.591] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:36.591] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:37:0) after 1m0s
[14:13:36.650] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:37:0)
[14:13:36.650] Timer tick, asking Buildbucket for the build status
[14:13:36.707] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:36.707] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:38:0) after 1m0s
[14:14:36.732] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:38:0)
[14:14:36.732] Timer tick, asking Buildbucket for the build status
[14:14:36.816] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:36.816] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:39:0) after 1m0s
[14:15:36.824] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:39:0)
[14:15:36.824] Timer tick, asking Buildbucket for the build status
[14:15:36.868] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:36.868] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:40:0) after 1m0s
[14:16:36.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:40:0)
[14:16:36.890] Timer tick, asking Buildbucket for the build status
[14:16:37.052] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:37.052] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:41:0) after 1m0s
[14:17:37.079] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:41:0)
[14:17:37.079] Timer tick, asking Buildbucket for the build status
[14:17:37.197] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:37.197] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:42:0) after 1m0s
[14:18:37.203] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:42:0)
[14:18:37.203] Timer tick, asking Buildbucket for the build status
[14:18:37.279] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:37.279] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:43:0) after 1m0s
[14:19:37.303] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:43:0)
[14:19:37.303] Timer tick, asking Buildbucket for the build status
[14:19:37.348] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:37.348] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:44:0) after 1m0s
[14:20:37.364] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:44:0)
[14:20:37.364] Timer tick, asking Buildbucket for the build status
[14:20:37.485] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:37.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:45:0) after 1m0s
[14:21:37.500] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:45:0)
[14:21:37.500] Timer tick, asking Buildbucket for the build status
[14:21:37.540] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:37.540] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:46:0) after 1m0s
[14:22:37.549] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:46:0)
[14:22:37.549] Timer tick, asking Buildbucket for the build status
[14:22:37.625] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:37.625] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:47:0) after 1m0s
[14:23:37.621] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:47:0)
[14:23:37.621] Timer tick, asking Buildbucket for the build status
[14:23:37.746] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:37.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:48:0) after 1m0s
[14:24:37.764] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:48:0)
[14:24:37.764] Timer tick, asking Buildbucket for the build status
[14:24:37.839] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:37.839] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:49:0) after 1m0s
[14:25:37.855] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:49:0)
[14:25:37.855] Timer tick, asking Buildbucket for the build status
[14:25:37.890] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:37.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:50:0) after 1m0s
[14:26:37.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:50:0)
[14:26:37.909] Timer tick, asking Buildbucket for the build status
[14:26:37.959] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:37.959] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:51:0) after 1m0s
[14:27:37.989] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:51:0)
[14:27:37.989] Timer tick, asking Buildbucket for the build status
[14:27:38.033] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:38.033] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:52:0) after 1m0s
[14:28:38.052] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:52:0)
[14:28:38.052] Timer tick, asking Buildbucket for the build status
[14:28:38.131] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:38.131] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:53:0) after 1m0s
[14:29:38.117] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:53:0)
[14:29:38.117] Timer tick, asking Buildbucket for the build status
[14:29:38.192] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:38.192] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:54:0) after 1m0s
[14:30:38.218] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:54:0)
[14:30:38.218] Timer tick, asking Buildbucket for the build status
[14:30:38.268] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:38.268] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:55:0) after 1m0s
[14:31:38.288] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:55:0)
[14:31:38.288] Timer tick, asking Buildbucket for the build status
[14:31:38.347] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:38.347] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:56:0) after 1m0s
[14:32:38.371] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:56:0)
[14:32:38.371] Timer tick, asking Buildbucket for the build status
[14:32:38.433] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:38.433] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:57:0) after 1m0s
[14:33:38.465] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:57:0)
[14:33:38.465] Timer tick, asking Buildbucket for the build status
[14:33:38.555] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:38.555] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:58:0) after 1m0s
[14:34:38.570] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:58:0)
[14:34:38.570] Timer tick, asking Buildbucket for the build status
[14:34:38.687] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:38.687] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:59:0) after 1m0s
[14:35:38.664] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:59:0)
[14:35:38.664] Timer tick, asking Buildbucket for the build status
[14:35:38.983] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:38.983] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:60:0) after 1m0s
[14:36:39.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:60:0)
[14:36:39.004] Timer tick, asking Buildbucket for the build status
[14:36:39.048] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:39.048] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:61:0) after 1m0s
[14:37:39.022] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:61:0)
[14:37:39.022] Timer tick, asking Buildbucket for the build status
[14:37:39.396] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:39.396] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:62:0) after 1m0s
[14:38:39.392] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:62:0)
[14:38:39.392] Timer tick, asking Buildbucket for the build status
[14:38:39.433] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:39.433] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:63:0) after 1m0s
[14:39:39.464] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:63:0)
[14:39:39.464] Timer tick, asking Buildbucket for the build status
[14:39:39.503] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:39.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:64:0) after 1m0s
[14:40:39.518] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:64:0)
[14:40:39.518] Timer tick, asking Buildbucket for the build status
[14:40:39.558] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:39.558] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:65:0) after 1m0s
[14:41:39.572] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:65:0)
[14:41:39.572] Timer tick, asking Buildbucket for the build status
[14:41:39.644] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:39.644] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:66:0) after 1m0s
[14:42:39.653] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:66:0)
[14:42:39.653] Timer tick, asking Buildbucket for the build status
[14:42:39.777] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:39.777] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:67:0) after 1m0s
[14:43:39.786] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:67:0)
[14:43:39.786] Timer tick, asking Buildbucket for the build status
[14:43:39.823] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:39.823] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:68:0) after 1m0s
[14:44:39.796] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:68:0)
[14:44:39.796] Timer tick, asking Buildbucket for the build status
[14:44:39.831] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:39.831] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:69:0) after 1m0s
[14:45:39.818] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:69:0)
[14:45:39.818] Timer tick, asking Buildbucket for the build status
[14:45:39.879] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:39.879] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:70:0) after 1m0s
[14:46:39.898] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:70:0)
[14:46:39.898] Timer tick, asking Buildbucket for the build status
[14:46:39.933] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:39.933] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:71:0) after 1m0s
[14:47:39.955] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:71:0)
[14:47:39.955] Timer tick, asking Buildbucket for the build status
[14:47:39.995] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:39.995] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:72:0) after 1m0s
[14:48:40.036] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:72:0)
[14:48:40.036] Timer tick, asking Buildbucket for the build status
[14:48:40.157] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:40.157] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:73:0) after 1m0s
[14:49:40.152] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:73:0)
[14:49:40.152] Timer tick, asking Buildbucket for the build status
[14:49:40.211] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:40.211] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:74:0) after 1m0s
[14:50:40.235] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:74:0)
[14:50:40.235] Timer tick, asking Buildbucket for the build status
[14:50:40.275] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:40.275] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:75:0) after 1m0s
[14:51:40.296] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:75:0)
[14:51:40.296] Timer tick, asking Buildbucket for the build status
[14:51:40.347] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:40.347] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:76:0) after 1m0s
[14:52:40.373] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:76:0)
[14:52:40.373] Timer tick, asking Buildbucket for the build status
[14:52:40.413] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:40.413] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:77:0) after 1m0s
[14:53:40.435] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:77:0)
[14:53:40.435] Timer tick, asking Buildbucket for the build status
[14:53:40.486] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:40.486] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:78:0) after 1m0s
[14:54:40.507] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:78:0)
[14:54:40.507] Timer tick, asking Buildbucket for the build status
[14:54:40.617] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:40.617] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:79:0) after 1m0s
[14:55:40.639] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:79:0)
[14:55:40.639] Timer tick, asking Buildbucket for the build status
[14:55:40.739] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:40.739] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:80:0) after 1m0s
[14:56:40.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:80:0)
[14:56:40.750] Timer tick, asking Buildbucket for the build status
[14:56:40.891] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:40.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:81:0) after 1m0s
[14:57:40.915] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:81:0)
[14:57:40.915] Timer tick, asking Buildbucket for the build status
[14:57:41.006] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:41.006] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:82:0) after 1m0s
[14:58:41.027] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:82:0)
[14:58:41.027] Timer tick, asking Buildbucket for the build status
[14:58:41.081] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:41.081] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:83:0) after 1m0s
[14:59:41.107] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:83:0)
[14:59:41.107] Timer tick, asking Buildbucket for the build status
[14:59:41.180] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:41.180] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:84:0) after 1m0s
[15:00:41.166] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:84:0)
[15:00:41.166] Timer tick, asking Buildbucket for the build status
[15:00:41.214] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:41.214] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:85:0) after 1m0s
[15:01:41.234] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:85:0)
[15:01:41.234] Timer tick, asking Buildbucket for the build status
[15:01:41.473] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:41.473] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:86:0) after 1m0s
[15:02:41.495] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:86:0)
[15:02:41.495] Timer tick, asking Buildbucket for the build status
[15:02:41.671] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:41.671] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:87:0) after 1m0s
[15:03:41.696] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:87:0)
[15:03:41.696] Timer tick, asking Buildbucket for the build status
[15:03:42.012] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:42.012] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:88:0) after 1m0s
[15:04:42.014] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:88:0)
[15:04:42.014] Timer tick, asking Buildbucket for the build status
[15:04:42.176] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:42.176] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:89:0) after 1m0s
[15:05:42.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:89:0)
[15:05:42.192] Timer tick, asking Buildbucket for the build status
[15:05:42.233] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:42.233] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:90:0) after 1m0s
[15:06:42.252] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:90:0)
[15:06:42.252] Timer tick, asking Buildbucket for the build status
[15:06:42.350] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:42.350] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:91:0) after 1m0s
[15:07:42.372] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:91:0)
[15:07:42.372] Timer tick, asking Buildbucket for the build status
[15:07:42.722] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:42.722] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:92:0) after 1m0s
[15:08:42.706] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:92:0)
[15:08:42.706] Timer tick, asking Buildbucket for the build status
[15:08:42.758] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:42.758] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:93:0) after 1m0s
[15:09:42.776] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:93:0)
[15:09:42.776] Timer tick, asking Buildbucket for the build status
[15:09:42.889] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:42.889] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:94:0) after 1m0s
[15:10:42.914] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:94:0)
[15:10:42.914] Timer tick, asking Buildbucket for the build status
[15:10:42.977] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:42.977] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:95:0) after 1m0s
[15:11:42.986] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:95:0)
[15:11:42.986] Timer tick, asking Buildbucket for the build status
[15:11:43.060] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:43.060] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:96:0) after 1m0s
[15:12:43.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:96:0)
[15:12:43.045] Timer tick, asking Buildbucket for the build status
[15:12:43.090] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:43.090] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:97:0) after 1m0s
[15:13:43.079] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:97:0)
[15:13:43.080] Timer tick, asking Buildbucket for the build status
[15:13:43.145] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:43.145] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:98:0) after 1m0s
[15:14:43.154] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:98:0)
[15:14:43.154] Timer tick, asking Buildbucket for the build status
[15:14:43.214] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:43.214] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:99:0) after 1m0s
[15:15:43.218] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:99:0)
[15:15:43.218] Timer tick, asking Buildbucket for the build status
[15:15:43.381] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:43.381] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:100:0) after 1m0s
[15:16:43.361] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:100:0)
[15:16:43.361] Timer tick, asking Buildbucket for the build status
[15:16:43.503] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:43.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:101:0) after 1m0s
[15:17:43.528] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:101:0)
[15:17:43.528] Timer tick, asking Buildbucket for the build status
[15:17:43.620] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:43.620] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:102:0) after 1m0s
[15:18:43.610] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:102:0)
[15:18:43.610] Timer tick, asking Buildbucket for the build status
[15:18:43.660] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:43.660] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:103:0) after 1m0s
[15:19:43.684] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:103:0)
[15:19:43.684] Timer tick, asking Buildbucket for the build status
[15:19:43.797] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:43.797] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:104:0) after 1m0s
[15:20:43.807] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:104:0)
[15:20:43.807] Timer tick, asking Buildbucket for the build status
[15:20:43.967] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:43.967] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:105:0) after 1m0s
[15:21:43.989] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:105:0)
[15:21:43.989] Timer tick, asking Buildbucket for the build status
[15:21:44.180] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:44.180] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:106:0) after 1m0s
[15:22:44.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:106:0)
[15:22:44.225] Timer tick, asking Buildbucket for the build status
[15:22:44.287] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:44.287] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:107:0) after 1m0s
[15:23:44.284] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:107:0)
[15:23:44.284] Timer tick, asking Buildbucket for the build status
[15:23:44.352] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:44.352] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:108:0) after 1m0s
[15:24:44.376] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:108:0)
[15:24:44.376] Timer tick, asking Buildbucket for the build status
[15:24:44.506] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:44.506] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:109:0) after 1m0s
[15:25:44.522] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:109:0)
[15:25:44.522] Timer tick, asking Buildbucket for the build status
[15:25:44.578] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:44.578] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:110:0) after 1m0s
[15:26:44.602] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:110:0)
[15:26:44.603] Timer tick, asking Buildbucket for the build status
[15:26:44.642] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:44.642] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:111:0) after 1m0s
[15:27:44.637] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:111:0)
[15:27:44.637] Timer tick, asking Buildbucket for the build status
[15:27:44.742] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:44.742] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:112:0) after 1m0s
[15:28:44.761] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:112:0)
[15:28:44.761] Timer tick, asking Buildbucket for the build status
[15:28:44.817] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:44.817] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:113:0) after 1m0s
[15:29:44.800] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:113:0)
[15:29:44.800] Timer tick, asking Buildbucket for the build status
[15:29:44.856] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:44.856] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:114:0) after 1m0s
[15:30:44.870] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:114:0)
[15:30:44.870] Timer tick, asking Buildbucket for the build status
[15:30:44.915] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:44.915] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:115:0) after 1m0s
[15:31:44.938] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:115:0)
[15:31:44.938] Timer tick, asking Buildbucket for the build status
[15:31:45.012] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:45.012] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:116:0) after 1m0s
[15:32:45.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:116:0)
[15:32:45.045] Timer tick, asking Buildbucket for the build status
[15:32:45.084] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:45.084] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:117:0) after 1m0s
[15:33:45.078] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:117:0)
[15:33:45.078] Timer tick, asking Buildbucket for the build status
[15:33:45.204] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:45.204] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:118:0) after 1m0s
[15:34:45.226] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:118:0)
[15:34:45.226] Timer tick, asking Buildbucket for the build status
[15:34:45.288] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:45.288] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:119:0) after 1m0s
[15:35:45.286] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:119:0)
[15:35:45.286] Timer tick, asking Buildbucket for the build status
[15:35:45.357] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:45.357] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:120:0) after 1m0s
[15:36:45.369] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:120:0)
[15:36:45.369] Timer tick, asking Buildbucket for the build status
[15:36:45.507] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:45.507] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:121:0) after 1m0s
[15:37:45.499] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:121:0)
[15:37:45.499] Timer tick, asking Buildbucket for the build status
[15:37:45.560] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:45.560] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:122:0) after 1m0s
[15:38:45.549] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:122:0)
[15:38:45.549] Timer tick, asking Buildbucket for the build status
[15:38:45.728] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:45.728] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:123:0) after 1m0s
[15:39:45.751] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:123:0)
[15:39:45.751] Timer tick, asking Buildbucket for the build status
[15:39:45.798] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:45.798] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:124:0) after 1m0s
[15:40:45.810] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:124:0)
[15:40:45.810] Timer tick, asking Buildbucket for the build status
[15:40:45.894] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:45.894] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:125:0) after 1m0s
[15:41:45.868] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:125:0)
[15:41:45.868] Timer tick, asking Buildbucket for the build status
[15:41:45.946] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:45.946] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:126:0) after 1m0s
[15:42:45.969] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:126:0)
[15:42:45.969] Timer tick, asking Buildbucket for the build status
[15:42:46.063] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:46.063] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:127:0) after 1m0s
[15:43:46.110] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:127:0)
[15:43:46.110] Timer tick, asking Buildbucket for the build status
[15:43:46.381] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:46.381] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:128:0) after 1m0s
[15:44:46.406] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:128:0)
[15:44:46.406] Timer tick, asking Buildbucket for the build status
[15:44:46.459] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:46.459] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:129:0) after 1m0s
[15:45:46.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:129:0)
[15:45:46.461] Timer tick, asking Buildbucket for the build status
[15:45:46.597] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:46.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:130:0) after 1m0s
[15:46:46.607] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:130:0)
[15:46:46.607] Timer tick, asking Buildbucket for the build status
[15:46:46.789] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:46.790] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:131:0) after 1m0s
[15:47:46.849] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:131:0)
[15:47:46.849] Timer tick, asking Buildbucket for the build status
[15:47:46.931] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:46.931] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:132:0) after 1m0s
[15:48:46.952] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:132:0)
[15:48:46.952] Timer tick, asking Buildbucket for the build status
[15:48:46.996] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:46.996] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:133:0) after 1m0s
[15:49:47.053] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:133:0)
[15:49:47.053] Timer tick, asking Buildbucket for the build status
[15:49:47.128] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:47.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:134:0) after 1m0s
[15:50:47.106] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:134:0)
[15:50:47.106] Timer tick, asking Buildbucket for the build status
[15:50:47.234] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:47.234] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:135:0) after 1m0s
[15:51:47.239] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:135:0)
[15:51:47.239] Timer tick, asking Buildbucket for the build status
[15:51:47.284] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:47.284] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:136:0) after 1m0s
[15:52:47.304] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:136:0)
[15:52:47.304] Timer tick, asking Buildbucket for the build status
[15:52:47.369] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:47.369] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:137:0) after 1m0s
[15:53:47.369] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:137:0)
[15:53:47.369] Timer tick, asking Buildbucket for the build status
[15:53:47.535] Build 8919548913345394000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:47.535] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084978975397867872:138:0) after 1m0s
[15:53:59.395] Received PubSub notification, asking Buildbucket for the build status
[15:53:59.433] Build 8919548913345394000: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:53:59.433] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1552060437710784",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552052309621783",
"id": "8919548913345394000",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3729.0\"}}",
"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-east1-b-ltts\"], \"server_version\": [\"4158-1053c58\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"gpu\": [\"none\"], \"cores\": [\"32\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"], \"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}, \"version\": \"74.0.3729.0\", \"path_config\": \"generic\", \"bot_id\": \"gce-trusty-32-0258d129-us-east1-b-ltts\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1552052325674076",
"status": "COMPLETED",
"status_changed_ts": "1552060439118510",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9084978975397867872",
"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/8919548913345394000/+/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:43765859beec6210",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1552060439123298",
"url": "https://ci.chromium.org/b/8919548913345394000",
"utcnow_ts": "1552060439425892"
}
}
[15:53:59.434] Invocation finished in 2h15m31.153924913s with status SUCCEEDED