[10:55:58.907] New invocation is queued and will start shortly
[10:55:58.907] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[10:55:59.965] Starting the invocation (attempt 1)
[10:55:59.998] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:55:59.998] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:55:59.998] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9084083228710017440",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"75.0.3737.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:9084083228710017440",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[10:56:00.843] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary": true,
"canary_preference": "AUTO",
"created_by": "project:infra",
"created_ts": "1552906560102728",
"id": "8918653166793006304",
"lease_expiration_ts": "1553025360659854",
"lease_key": "359422135",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"75.0.3737.0\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552906560661466",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9084083228710017440",
"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/8918653166793006304/+/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:43a9432b9bc06910",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1552906560661571",
"url": "https://ci.chromium.org/b/8918653166793006304",
"utcnow_ts": "1552906560829314"
}
}
[10:56:00.843] Task URL: https://ci.chromium.org/b/8918653166793006304
[10:56:00.843] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:2:0) after 1m0s
[10:56:08.241] Received PubSub notification, asking Buildbucket for the build status
[10:56:08.290] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:00.860] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:2:0)
[10:57:00.860] Timer tick, asking Buildbucket for the build status
[10:57:01.284] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:01.284] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:4:0) after 1m0s
[10:58:01.295] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:4:0)
[10:58:01.295] Timer tick, asking Buildbucket for the build status
[10:58:01.506] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:01.506] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:5:0) after 1m0s
[10:59:01.526] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:5:0)
[10:59:01.526] Timer tick, asking Buildbucket for the build status
[10:59:01.890] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:01.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:6:0) after 1m0s
[11:00:01.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:6:0)
[11:00:01.909] Timer tick, asking Buildbucket for the build status
[11:00:02.062] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:02.062] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:7:0) after 1m0s
[11:01:02.053] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:7:0)
[11:01:02.053] Timer tick, asking Buildbucket for the build status
[11:01:02.127] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:02.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:8:0) after 1m0s
[11:02:02.111] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:8:0)
[11:02:02.111] Timer tick, asking Buildbucket for the build status
[11:02:02.262] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:02.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:9:0) after 1m0s
[11:03:02.281] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:9:0)
[11:03:02.281] Timer tick, asking Buildbucket for the build status
[11:03:02.399] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:02.399] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:10:0) after 1m0s
[11:04:02.386] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:10:0)
[11:04:02.386] Timer tick, asking Buildbucket for the build status
[11:04:02.436] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:02.436] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:11:0) after 1m0s
[11:05:02.451] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:11:0)
[11:05:02.451] Timer tick, asking Buildbucket for the build status
[11:05:02.702] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:02.702] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:12:0) after 1m0s
[11:06:02.700] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:12:0)
[11:06:02.700] Timer tick, asking Buildbucket for the build status
[11:06:02.751] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:02.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:13:0) after 1m0s
[11:07:02.768] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:13:0)
[11:07:02.768] Timer tick, asking Buildbucket for the build status
[11:07:02.866] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:02.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:14:0) after 1m0s
[11:08:02.853] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:14:0)
[11:08:02.853] Timer tick, asking Buildbucket for the build status
[11:08:02.928] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:02.928] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:15:0) after 1m0s
[11:09:02.946] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:15:0)
[11:09:02.946] Timer tick, asking Buildbucket for the build status
[11:09:03.173] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:03.173] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:16:0) after 1m0s
[11:10:03.162] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:16:0)
[11:10:03.162] Timer tick, asking Buildbucket for the build status
[11:10:03.214] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:03.214] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:17:0) after 1m0s
[11:11:03.204] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:17:0)
[11:11:03.204] Timer tick, asking Buildbucket for the build status
[11:11:03.268] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:03.268] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:18:0) after 1m0s
[11:12:03.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:18:0)
[11:12:03.287] Timer tick, asking Buildbucket for the build status
[11:12:03.328] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:03.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:19:0) after 1m0s
[11:13:03.356] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:19:0)
[11:13:03.356] Timer tick, asking Buildbucket for the build status
[11:13:03.486] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:03.486] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:20:0) after 1m0s
[11:14:03.499] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:20:0)
[11:14:03.499] Timer tick, asking Buildbucket for the build status
[11:14:03.545] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:03.545] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:21:0) after 1m0s
[11:15:03.565] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:21:0)
[11:15:03.565] Timer tick, asking Buildbucket for the build status
[11:15:03.677] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:03.677] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:22:0) after 1m0s
[11:16:03.695] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:22:0)
[11:16:03.695] Timer tick, asking Buildbucket for the build status
[11:16:03.833] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:03.833] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:23:0) after 1m0s
[11:17:03.891] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:23:0)
[11:17:03.891] Timer tick, asking Buildbucket for the build status
[11:17:04.135] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:04.135] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:24:0) after 1m0s
[11:18:04.155] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:24:0)
[11:18:04.155] Timer tick, asking Buildbucket for the build status
[11:18:04.234] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:04.234] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:25:0) after 1m0s
[11:19:04.251] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:25:0)
[11:19:04.251] Timer tick, asking Buildbucket for the build status
[11:19:04.361] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:04.362] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:26:0) after 1m0s
[11:20:04.382] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:26:0)
[11:20:04.382] Timer tick, asking Buildbucket for the build status
[11:20:04.453] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:04.453] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:27:0) after 1m0s
[11:21:04.483] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:27:0)
[11:21:04.483] Timer tick, asking Buildbucket for the build status
[11:21:04.544] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:04.544] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:28:0) after 1m0s
[11:22:04.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:28:0)
[11:22:04.515] Timer tick, asking Buildbucket for the build status
[11:22:04.560] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:04.560] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:29:0) after 1m0s
[11:23:04.579] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:29:0)
[11:23:04.579] Timer tick, asking Buildbucket for the build status
[11:23:04.644] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:04.644] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:30:0) after 1m0s
[11:24:04.636] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:30:0)
[11:24:04.636] Timer tick, asking Buildbucket for the build status
[11:24:04.699] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:04.699] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:31:0) after 1m0s
[11:25:04.700] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:31:0)
[11:25:04.700] Timer tick, asking Buildbucket for the build status
[11:25:04.815] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:04.815] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:32:0) after 1m0s
[11:26:04.786] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:32:0)
[11:26:04.786] Timer tick, asking Buildbucket for the build status
[11:26:04.834] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:04.834] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:33:0) after 1m0s
[11:27:04.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:33:0)
[11:27:04.886] Timer tick, asking Buildbucket for the build status
[11:27:05.016] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:05.016] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:34:0) after 1m0s
[11:28:05.035] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:34:0)
[11:28:05.036] Timer tick, asking Buildbucket for the build status
[11:28:05.101] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:05.101] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:35:0) after 1m0s
[11:29:05.123] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:35:0)
[11:29:05.123] Timer tick, asking Buildbucket for the build status
[11:29:05.198] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:05.198] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:36:0) after 1m0s
[11:30:05.189] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:36:0)
[11:30:05.189] Timer tick, asking Buildbucket for the build status
[11:30:05.228] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:05.228] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:37:0) after 1m0s
[11:31:05.229] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:37:0)
[11:31:05.229] Timer tick, asking Buildbucket for the build status
[11:31:05.387] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:05.388] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:38:0) after 1m0s
[11:32:05.385] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:38:0)
[11:32:05.385] Timer tick, asking Buildbucket for the build status
[11:32:05.422] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:05.422] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:39:0) after 1m0s
[11:33:05.445] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:39:0)
[11:33:05.445] Timer tick, asking Buildbucket for the build status
[11:33:05.539] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:05.539] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:40:0) after 1m0s
[11:34:05.517] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:40:0)
[11:34:05.517] Timer tick, asking Buildbucket for the build status
[11:34:05.714] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:05.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:41:0) after 1m0s
[11:35:05.766] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:41:0)
[11:35:05.766] Timer tick, asking Buildbucket for the build status
[11:35:05.930] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:05.930] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:42:0) after 1m0s
[11:36:05.952] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:42:0)
[11:36:05.952] Timer tick, asking Buildbucket for the build status
[11:36:06.017] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:06.017] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:43:0) after 1m0s
[11:37:06.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:43:0)
[11:37:06.013] Timer tick, asking Buildbucket for the build status
[11:37:06.083] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:06.083] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:44:0) after 1m0s
[11:38:06.107] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:44:0)
[11:38:06.107] Timer tick, asking Buildbucket for the build status
[11:38:06.148] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:06.148] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:45:0) after 1m0s
[11:39:06.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:45:0)
[11:39:06.167] Timer tick, asking Buildbucket for the build status
[11:39:06.229] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:06.229] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:46:0) after 1m0s
[11:40:06.249] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:46:0)
[11:40:06.249] Timer tick, asking Buildbucket for the build status
[11:40:06.474] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:06.474] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:47:0) after 1m0s
[11:41:06.496] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:47:0)
[11:41:06.496] Timer tick, asking Buildbucket for the build status
[11:41:06.653] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:06.653] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:48:0) after 1m0s
[11:42:06.672] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:48:0)
[11:42:06.672] Timer tick, asking Buildbucket for the build status
[11:42:06.717] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:06.717] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:49:0) after 1m0s
[11:43:06.732] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:49:0)
[11:43:06.732] Timer tick, asking Buildbucket for the build status
[11:43:06.964] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:06.964] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:50:0) after 1m0s
[11:44:06.987] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:50:0)
[11:44:06.987] Timer tick, asking Buildbucket for the build status
[11:44:07.053] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:07.053] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:51:0) after 1m0s
[11:45:07.053] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:51:0)
[11:45:07.053] Timer tick, asking Buildbucket for the build status
[11:45:07.169] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:07.169] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:52:0) after 1m0s
[11:46:07.193] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:52:0)
[11:46:07.193] Timer tick, asking Buildbucket for the build status
[11:46:07.266] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:07.266] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:53:0) after 1m0s
[11:47:07.289] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:53:0)
[11:47:07.289] Timer tick, asking Buildbucket for the build status
[11:47:07.386] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:07.386] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:54:0) after 1m0s
[11:48:07.391] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:54:0)
[11:48:07.391] Timer tick, asking Buildbucket for the build status
[11:48:07.459] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:07.459] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:55:0) after 1m0s
[11:49:07.477] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:55:0)
[11:49:07.477] Timer tick, asking Buildbucket for the build status
[11:49:07.517] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:07.517] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:56:0) after 1m0s
[11:50:07.538] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:56:0)
[11:50:07.538] Timer tick, asking Buildbucket for the build status
[11:50:07.715] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:07.715] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:57:0) after 1m0s
[11:51:07.711] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:57:0)
[11:51:07.711] Timer tick, asking Buildbucket for the build status
[11:51:07.792] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:07.792] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:58:0) after 1m0s
[11:52:07.803] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:58:0)
[11:52:07.803] Timer tick, asking Buildbucket for the build status
[11:52:07.878] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:07.878] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:59:0) after 1m0s
[11:53:07.911] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:59:0)
[11:53:07.911] Timer tick, asking Buildbucket for the build status
[11:53:07.983] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:07.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:60:0) after 1m0s
[11:54:07.997] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:60:0)
[11:54:07.997] Timer tick, asking Buildbucket for the build status
[11:54:08.073] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:08.073] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:61:0) after 1m0s
[11:55:08.102] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:61:0)
[11:55:08.102] Timer tick, asking Buildbucket for the build status
[11:55:08.184] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:08.184] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:62:0) after 1m0s
[11:56:08.223] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:62:0)
[11:56:08.223] Timer tick, asking Buildbucket for the build status
[11:56:08.300] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:08.300] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:63:0) after 1m0s
[11:57:08.284] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:63:0)
[11:57:08.284] Timer tick, asking Buildbucket for the build status
[11:57:08.366] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:08.366] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:64:0) after 1m0s
[11:58:08.388] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:64:0)
[11:58:08.388] Timer tick, asking Buildbucket for the build status
[11:58:08.490] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:08.490] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:65:0) after 1m0s
[11:59:08.495] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:65:0)
[11:59:08.495] Timer tick, asking Buildbucket for the build status
[11:59:08.557] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:08.557] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:66:0) after 1m0s
[12:00:08.543] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:66:0)
[12:00:08.543] Timer tick, asking Buildbucket for the build status
[12:00:08.605] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:08.605] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:67:0) after 1m0s
[12:01:08.626] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:67:0)
[12:01:08.626] Timer tick, asking Buildbucket for the build status
[12:01:09.063] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:09.063] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:68:0) after 1m0s
[12:02:09.085] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:68:0)
[12:02:09.085] Timer tick, asking Buildbucket for the build status
[12:02:09.174] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:09.174] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:69:0) after 1m0s
[12:03:09.168] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:69:0)
[12:03:09.168] Timer tick, asking Buildbucket for the build status
[12:03:09.236] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:09.236] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:70:0) after 1m0s
[12:04:09.257] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:70:0)
[12:04:09.257] Timer tick, asking Buildbucket for the build status
[12:04:09.304] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:09.304] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:71:0) after 1m0s
[12:05:09.322] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:71:0)
[12:05:09.322] Timer tick, asking Buildbucket for the build status
[12:05:09.411] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:09.411] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:72:0) after 1m0s
[12:06:09.426] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:72:0)
[12:06:09.426] Timer tick, asking Buildbucket for the build status
[12:06:09.503] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:09.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:73:0) after 1m0s
[12:07:09.522] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:73:0)
[12:07:09.522] Timer tick, asking Buildbucket for the build status
[12:07:09.589] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:09.589] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:74:0) after 1m0s
[12:08:09.611] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:74:0)
[12:08:09.611] Timer tick, asking Buildbucket for the build status
[12:08:09.659] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:09.659] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:75:0) after 1m0s
[12:09:09.678] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:75:0)
[12:09:09.678] Timer tick, asking Buildbucket for the build status
[12:09:09.841] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:09.841] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:76:0) after 1m0s
[12:10:09.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:76:0)
[12:10:09.890] Timer tick, asking Buildbucket for the build status
[12:10:10.166] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:10.166] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:77:0) after 1m0s
[12:11:10.191] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:77:0)
[12:11:10.191] Timer tick, asking Buildbucket for the build status
[12:11:10.272] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:10.272] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:78:0) after 1m0s
[12:12:10.295] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:78:0)
[12:12:10.295] Timer tick, asking Buildbucket for the build status
[12:12:10.478] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:10.478] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:79:0) after 1m0s
[12:13:10.504] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:79:0)
[12:13:10.504] Timer tick, asking Buildbucket for the build status
[12:13:10.761] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:10.761] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:80:0) after 1m0s
[12:14:10.779] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:80:0)
[12:14:10.779] Timer tick, asking Buildbucket for the build status
[12:14:10.874] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:10.874] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:81:0) after 1m0s
[12:15:10.880] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:81:0)
[12:15:10.880] Timer tick, asking Buildbucket for the build status
[12:15:10.948] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:10.948] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:82:0) after 1m0s
[12:16:10.967] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:82:0)
[12:16:10.967] Timer tick, asking Buildbucket for the build status
[12:16:11.017] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:11.017] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:83:0) after 1m0s
[12:17:11.024] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:83:0)
[12:17:11.024] Timer tick, asking Buildbucket for the build status
[12:17:11.122] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:11.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:84:0) after 1m0s
[12:18:11.123] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:84:0)
[12:18:11.123] Timer tick, asking Buildbucket for the build status
[12:18:11.272] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:11.272] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:85:0) after 1m0s
[12:19:11.294] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:85:0)
[12:19:11.294] Timer tick, asking Buildbucket for the build status
[12:19:11.388] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:11.388] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:86:0) after 1m0s
[12:20:11.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:86:0)
[12:20:11.408] Timer tick, asking Buildbucket for the build status
[12:20:11.446] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:11.446] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:87:0) after 1m0s
[12:21:11.469] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:87:0)
[12:21:11.469] Timer tick, asking Buildbucket for the build status
[12:21:11.538] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:11.538] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:88:0) after 1m0s
[12:22:11.561] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:88:0)
[12:22:11.561] Timer tick, asking Buildbucket for the build status
[12:22:11.683] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:11.683] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:89:0) after 1m0s
[12:23:11.703] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:89:0)
[12:23:11.703] Timer tick, asking Buildbucket for the build status
[12:23:11.797] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:11.797] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:90:0) after 1m0s
[12:24:11.770] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:90:0)
[12:24:11.770] Timer tick, asking Buildbucket for the build status
[12:24:11.814] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:11.814] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:91:0) after 1m0s
[12:25:11.833] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:91:0)
[12:25:11.833] Timer tick, asking Buildbucket for the build status
[12:25:11.906] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:11.907] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:92:0) after 1m0s
[12:26:11.926] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:92:0)
[12:26:11.926] Timer tick, asking Buildbucket for the build status
[12:26:11.972] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:11.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:93:0) after 1m0s
[12:27:11.987] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:93:0)
[12:27:11.987] Timer tick, asking Buildbucket for the build status
[12:27:12.146] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:12.146] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:94:0) after 1m0s
[12:28:12.124] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:94:0)
[12:28:12.124] Timer tick, asking Buildbucket for the build status
[12:28:12.170] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:12.170] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:95:0) after 1m0s
[12:29:12.172] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:95:0)
[12:29:12.172] Timer tick, asking Buildbucket for the build status
[12:29:12.224] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:12.224] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:96:0) after 1m0s
[12:30:12.245] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:96:0)
[12:30:12.245] Timer tick, asking Buildbucket for the build status
[12:30:12.299] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:12.299] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:97:0) after 1m0s
[12:31:12.289] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:97:0)
[12:31:12.290] Timer tick, asking Buildbucket for the build status
[12:31:12.390] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:12.390] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:98:0) after 1m0s
[12:32:12.409] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:98:0)
[12:32:12.409] Timer tick, asking Buildbucket for the build status
[12:32:12.455] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:12.455] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:99:0) after 1m0s
[12:33:12.475] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:99:0)
[12:33:12.475] Timer tick, asking Buildbucket for the build status
[12:33:12.549] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:12.549] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:100:0) after 1m0s
[12:34:12.588] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:100:0)
[12:34:12.588] Timer tick, asking Buildbucket for the build status
[12:34:12.645] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:12.645] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:101:0) after 1m0s
[12:35:12.665] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:101:0)
[12:35:12.666] Timer tick, asking Buildbucket for the build status
[12:35:12.722] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:12.722] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:102:0) after 1m0s
[12:36:12.740] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:102:0)
[12:36:12.740] Timer tick, asking Buildbucket for the build status
[12:36:12.928] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:12.928] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:103:0) after 1m0s
[12:37:12.942] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:103:0)
[12:37:12.942] Timer tick, asking Buildbucket for the build status
[12:37:13.096] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:13.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:104:0) after 1m0s
[12:38:13.117] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:104:0)
[12:38:13.117] Timer tick, asking Buildbucket for the build status
[12:38:13.211] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:13.211] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:105:0) after 1m0s
[12:39:13.243] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:105:0)
[12:39:13.243] Timer tick, asking Buildbucket for the build status
[12:39:13.299] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:13.299] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:106:0) after 1m0s
[12:40:13.317] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:106:0)
[12:40:13.317] Timer tick, asking Buildbucket for the build status
[12:40:13.368] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:13.368] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:107:0) after 1m0s
[12:41:13.364] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:107:0)
[12:41:13.364] Timer tick, asking Buildbucket for the build status
[12:41:13.441] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:13.441] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:108:0) after 1m0s
[12:42:13.507] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:108:0)
[12:42:13.507] Timer tick, asking Buildbucket for the build status
[12:42:13.552] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:13.552] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:109:0) after 1m0s
[12:43:13.571] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:109:0)
[12:43:13.571] Timer tick, asking Buildbucket for the build status
[12:43:13.644] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:13.644] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:110:0) after 1m0s
[12:44:13.618] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:110:0)
[12:44:13.618] Timer tick, asking Buildbucket for the build status
[12:44:13.656] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:13.656] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:111:0) after 1m0s
[12:45:13.675] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:111:0)
[12:45:13.675] Timer tick, asking Buildbucket for the build status
[12:45:13.772] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:13.772] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:112:0) after 1m0s
[12:46:13.796] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:112:0)
[12:46:13.796] Timer tick, asking Buildbucket for the build status
[12:46:13.846] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:13.846] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:113:0) after 1m0s
[12:47:13.863] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:113:0)
[12:47:13.863] Timer tick, asking Buildbucket for the build status
[12:47:13.923] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:13.923] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:114:0) after 1m0s
[12:48:13.937] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:114:0)
[12:48:13.937] Timer tick, asking Buildbucket for the build status
[12:48:13.987] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:13.987] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:115:0) after 1m0s
[12:49:14.009] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:115:0)
[12:49:14.009] Timer tick, asking Buildbucket for the build status
[12:49:14.079] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:14.079] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:116:0) after 1m0s
[12:50:14.065] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:116:0)
[12:50:14.065] Timer tick, asking Buildbucket for the build status
[12:50:14.108] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:14.108] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:117:0) after 1m0s
[12:51:14.130] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:117:0)
[12:51:14.130] Timer tick, asking Buildbucket for the build status
[12:51:14.196] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:14.196] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:118:0) after 1m0s
[12:52:14.217] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:118:0)
[12:52:14.217] Timer tick, asking Buildbucket for the build status
[12:52:14.429] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:14.429] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:119:0) after 1m0s
[12:53:14.447] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:119:0)
[12:53:14.447] Timer tick, asking Buildbucket for the build status
[12:53:14.507] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:14.507] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:120:0) after 1m0s
[12:54:14.506] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:120:0)
[12:54:14.506] Timer tick, asking Buildbucket for the build status
[12:54:14.549] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:14.549] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:121:0) after 1m0s
[12:55:14.562] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:121:0)
[12:55:14.562] Timer tick, asking Buildbucket for the build status
[12:55:14.634] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:14.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:122:0) after 1m0s
[12:56:14.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:122:0)
[12:56:14.638] Timer tick, asking Buildbucket for the build status
[12:56:14.976] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:14.976] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:123:0) after 1m0s
[12:57:14.993] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:123:0)
[12:57:14.993] Timer tick, asking Buildbucket for the build status
[12:57:15.053] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:15.053] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:124:0) after 1m0s
[12:58:15.072] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:124:0)
[12:58:15.072] Timer tick, asking Buildbucket for the build status
[12:58:15.156] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:15.156] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:125:0) after 1m0s
[12:59:15.182] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:125:0)
[12:59:15.182] Timer tick, asking Buildbucket for the build status
[12:59:15.227] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:15.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:126:0) after 1m0s
[13:00:15.274] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:126:0)
[13:00:15.274] Timer tick, asking Buildbucket for the build status
[13:00:15.389] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:15.389] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:127:0) after 1m0s
[13:01:15.384] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:127:0)
[13:01:15.384] Timer tick, asking Buildbucket for the build status
[13:01:15.592] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:15.592] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:128:0) after 1m0s
[13:02:15.595] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:128:0)
[13:02:15.595] Timer tick, asking Buildbucket for the build status
[13:02:15.706] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:15.706] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:129:0) after 1m0s
[13:03:15.707] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:129:0)
[13:03:15.707] Timer tick, asking Buildbucket for the build status
[13:03:15.863] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:15.863] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:130:0) after 1m0s
[13:04:15.884] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:130:0)
[13:04:15.884] Timer tick, asking Buildbucket for the build status
[13:04:15.933] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:15.933] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:131:0) after 1m0s
[13:05:15.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:131:0)
[13:05:15.925] Timer tick, asking Buildbucket for the build status
[13:05:16.212] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:16.212] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:132:0) after 1m0s
[13:06:16.232] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:132:0)
[13:06:16.232] Timer tick, asking Buildbucket for the build status
[13:06:16.324] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:16.324] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:133:0) after 1m0s
[13:07:16.308] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:133:0)
[13:07:16.308] Timer tick, asking Buildbucket for the build status
[13:07:16.711] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:16.711] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:134:0) after 1m0s
[13:08:16.723] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:134:0)
[13:08:16.723] Timer tick, asking Buildbucket for the build status
[13:08:16.760] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:16.760] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:135:0) after 1m0s
[13:09:16.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:135:0)
[13:09:16.793] Timer tick, asking Buildbucket for the build status
[13:09:16.859] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:16.859] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:136:0) after 1m0s
[13:10:16.880] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:136:0)
[13:10:16.880] Timer tick, asking Buildbucket for the build status
[13:10:17.000] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:17.000] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:137:0) after 1m0s
[13:11:17.031] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:137:0)
[13:11:17.031] Timer tick, asking Buildbucket for the build status
[13:11:17.182] Build 8918653166793006304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:17.182] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084083228710017440:138:0) after 1m0s
[13:11:51.227] Received PubSub notification, asking Buildbucket for the build status
[13:11:51.268] Build 8918653166793006304: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:11:51.268] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1552914710216186",
"created_by": "project:infra",
"created_ts": "1552906560102728",
"id": "8918653166793006304",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"75.0.3737.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\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"4201-b04f7f8\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"gpu\": [\"none\"], \"cores\": [\"32\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"build-from-tarball-0-1v4b\"], \"pool\": [\"luci.infra.cron\"]}}, \"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\": \"75.0.3737.0\", \"path_config\": \"generic\", \"bot_id\": \"build-from-tarball-0-1v4b\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1552906567308447",
"status": "COMPLETED",
"status_changed_ts": "1552914710829181",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9084083228710017440",
"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/8918653166793006304/+/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:43a9432b9bc06910",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1552914710832218",
"url": "https://ci.chromium.org/b/8918653166793006304",
"utcnow_ts": "1552914711260044"
}
}
[13:11:51.268] Invocation finished in 2h15m52.378056317s with status SUCCEEDED