[17:05:54.113] New invocation is queued and will start shortly
[17:05:54.113] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[17:05:55.264] Starting the invocation (attempt 1)
[17:05:55.389] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:05:55.389] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:05:55.390] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9084875328049107456",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"75.0.3730.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:9084875328049107456",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[17:05:56.412] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552151155511884",
"id": "8919445265917200256",
"lease_expiration_ts": "1552269956067424",
"lease_key": "1266709046",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"75.0.3730.0\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552151156070890",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9084875328049107456",
"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/8919445265917200256/+/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:437c3c9c7955a310",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1552151156071000",
"url": "https://ci.chromium.org/b/8919445265917200256",
"utcnow_ts": "1552151156394086"
}
}
[17:05:56.412] Task URL: https://ci.chromium.org/b/8919445265917200256
[17:05:56.412] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:2:0) after 1m0s
[17:06:16.511] Received PubSub notification, asking Buildbucket for the build status
[17:06:16.571] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:56.444] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:2:0)
[17:06:56.444] Timer tick, asking Buildbucket for the build status
[17:06:56.614] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:56.614] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:4:0) after 1m0s
[17:07:56.621] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:4:0)
[17:07:56.621] Timer tick, asking Buildbucket for the build status
[17:07:56.824] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:56.824] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:5:0) after 1m0s
[17:08:56.855] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:5:0)
[17:08:56.855] Timer tick, asking Buildbucket for the build status
[17:08:56.916] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:56.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:6:0) after 1m0s
[17:09:56.900] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:6:0)
[17:09:56.900] Timer tick, asking Buildbucket for the build status
[17:09:56.955] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:56.955] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:7:0) after 1m0s
[17:10:56.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:7:0)
[17:10:56.972] Timer tick, asking Buildbucket for the build status
[17:10:57.025] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:57.025] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:8:0) after 1m0s
[17:11:57.042] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:8:0)
[17:11:57.042] Timer tick, asking Buildbucket for the build status
[17:11:57.098] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:57.098] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:9:0) after 1m0s
[17:12:57.127] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:9:0)
[17:12:57.127] Timer tick, asking Buildbucket for the build status
[17:12:57.193] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:57.193] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:10:0) after 1m0s
[17:13:57.215] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:10:0)
[17:13:57.215] Timer tick, asking Buildbucket for the build status
[17:13:57.294] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:57.294] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:11:0) after 1m0s
[17:14:57.309] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:11:0)
[17:14:57.309] Timer tick, asking Buildbucket for the build status
[17:14:57.359] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:57.359] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:12:0) after 1m0s
[17:15:57.386] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:12:0)
[17:15:57.386] Timer tick, asking Buildbucket for the build status
[17:15:57.440] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:57.440] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:13:0) after 1m0s
[17:16:57.456] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:13:0)
[17:16:57.456] Timer tick, asking Buildbucket for the build status
[17:16:57.597] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:57.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:14:0) after 1m0s
[17:17:57.623] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:14:0)
[17:17:57.623] Timer tick, asking Buildbucket for the build status
[17:17:57.690] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:57.690] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:15:0) after 1m0s
[17:18:57.711] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:15:0)
[17:18:57.711] Timer tick, asking Buildbucket for the build status
[17:18:57.863] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:57.863] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:16:0) after 1m0s
[17:19:57.881] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:16:0)
[17:19:57.881] Timer tick, asking Buildbucket for the build status
[17:19:57.943] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:57.943] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:17:0) after 1m0s
[17:20:57.966] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:17:0)
[17:20:57.966] Timer tick, asking Buildbucket for the build status
[17:20:58.108] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:58.108] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:18:0) after 1m0s
[17:21:58.126] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:18:0)
[17:21:58.126] Timer tick, asking Buildbucket for the build status
[17:21:58.267] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:58.267] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:19:0) after 1m0s
[17:22:58.289] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:19:0)
[17:22:58.289] Timer tick, asking Buildbucket for the build status
[17:22:58.387] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:58.387] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:20:0) after 1m0s
[17:23:58.355] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:20:0)
[17:23:58.355] Timer tick, asking Buildbucket for the build status
[17:23:58.475] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:58.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:21:0) after 1m0s
[17:24:58.493] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:21:0)
[17:24:58.493] Timer tick, asking Buildbucket for the build status
[17:24:58.550] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:58.550] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:22:0) after 1m0s
[17:25:58.566] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:22:0)
[17:25:58.566] Timer tick, asking Buildbucket for the build status
[17:25:58.789] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:58.789] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:23:0) after 1m0s
[17:26:58.790] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:23:0)
[17:26:58.790] Timer tick, asking Buildbucket for the build status
[17:26:59.076] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:59.076] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:24:0) after 1m0s
[17:27:59.098] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:24:0)
[17:27:59.098] Timer tick, asking Buildbucket for the build status
[17:27:59.383] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:59.383] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:25:0) after 1m0s
[17:28:59.367] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:25:0)
[17:28:59.367] Timer tick, asking Buildbucket for the build status
[17:28:59.450] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:59.450] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:26:0) after 1m0s
[17:29:59.467] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:26:0)
[17:29:59.467] Timer tick, asking Buildbucket for the build status
[17:29:59.522] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:59.522] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:27:0) after 1m0s
[17:30:59.525] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:27:0)
[17:30:59.525] Timer tick, asking Buildbucket for the build status
[17:30:59.609] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:59.609] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:28:0) after 1m0s
[17:31:59.627] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:28:0)
[17:31:59.627] Timer tick, asking Buildbucket for the build status
[17:31:59.712] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:59.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:29:0) after 1m0s
[17:32:59.688] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:29:0)
[17:32:59.688] Timer tick, asking Buildbucket for the build status
[17:32:59.832] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:59.832] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:30:0) after 1m0s
[17:33:59.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:30:0)
[17:33:59.852] Timer tick, asking Buildbucket for the build status
[17:33:59.916] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:59.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:31:0) after 1m0s
[17:34:59.936] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:31:0)
[17:34:59.936] Timer tick, asking Buildbucket for the build status
[17:34:59.994] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:59.994] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:32:0) after 1m0s
[17:35:59.989] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:32:0)
[17:35:59.989] Timer tick, asking Buildbucket for the build status
[17:36:00.107] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:00.107] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:33:0) after 1m0s
[17:37:00.112] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:33:0)
[17:37:00.112] Timer tick, asking Buildbucket for the build status
[17:37:00.166] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:00.166] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:34:0) after 1m0s
[17:38:00.184] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:34:0)
[17:38:00.184] Timer tick, asking Buildbucket for the build status
[17:38:00.374] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:00.374] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:35:0) after 1m0s
[17:39:00.385] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:35:0)
[17:39:00.385] Timer tick, asking Buildbucket for the build status
[17:39:00.434] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:00.434] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:36:0) after 1m0s
[17:40:00.455] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:36:0)
[17:40:00.455] Timer tick, asking Buildbucket for the build status
[17:40:00.519] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:00.519] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:37:0) after 1m0s
[17:41:00.580] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:37:0)
[17:41:00.580] Timer tick, asking Buildbucket for the build status
[17:41:00.683] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:00.683] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:38:0) after 1m0s
[17:42:00.704] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:38:0)
[17:42:00.704] Timer tick, asking Buildbucket for the build status
[17:42:00.802] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:00.802] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:39:0) after 1m0s
[17:43:00.820] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:39:0)
[17:43:00.820] Timer tick, asking Buildbucket for the build status
[17:43:00.903] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:00.903] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:40:0) after 1m0s
[17:44:00.923] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:40:0)
[17:44:00.923] Timer tick, asking Buildbucket for the build status
[17:44:01.013] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:01.013] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:41:0) after 1m0s
[17:45:01.033] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:41:0)
[17:45:01.033] Timer tick, asking Buildbucket for the build status
[17:45:01.090] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:01.091] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:42:0) after 1m0s
[17:46:01.123] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:42:0)
[17:46:01.123] Timer tick, asking Buildbucket for the build status
[17:46:01.179] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:01.179] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:43:0) after 1m0s
[17:47:01.153] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:43:0)
[17:47:01.153] Timer tick, asking Buildbucket for the build status
[17:47:01.499] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:01.499] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:44:0) after 1m0s
[17:48:01.519] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:44:0)
[17:48:01.519] Timer tick, asking Buildbucket for the build status
[17:48:01.667] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:01.667] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:45:0) after 1m0s
[17:49:01.687] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:45:0)
[17:49:01.687] Timer tick, asking Buildbucket for the build status
[17:49:01.738] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:01.738] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:46:0) after 1m0s
[17:50:01.743] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:46:0)
[17:50:01.743] Timer tick, asking Buildbucket for the build status
[17:50:01.879] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:01.879] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:47:0) after 1m0s
[17:51:01.897] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:47:0)
[17:51:01.897] Timer tick, asking Buildbucket for the build status
[17:51:02.179] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:02.179] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:48:0) after 1m0s
[17:52:02.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:48:0)
[17:52:02.287] Timer tick, asking Buildbucket for the build status
[17:52:02.400] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:02.400] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:49:0) after 1m0s
[17:53:02.372] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:49:0)
[17:53:02.372] Timer tick, asking Buildbucket for the build status
[17:53:02.554] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:02.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:50:0) after 1m0s
[17:54:02.592] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:50:0)
[17:54:02.592] Timer tick, asking Buildbucket for the build status
[17:54:02.651] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:02.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:51:0) after 1m0s
[17:55:02.684] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:51:0)
[17:55:02.684] Timer tick, asking Buildbucket for the build status
[17:55:02.754] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:02.754] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:52:0) after 1m0s
[17:56:02.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:52:0)
[17:56:02.750] Timer tick, asking Buildbucket for the build status
[17:56:02.810] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:02.810] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:53:0) after 1m0s
[17:57:02.834] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:53:0)
[17:57:02.834] Timer tick, asking Buildbucket for the build status
[17:57:02.911] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:02.911] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:54:0) after 1m0s
[17:58:02.935] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:54:0)
[17:58:02.935] Timer tick, asking Buildbucket for the build status
[17:58:02.994] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:02.994] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:55:0) after 1m0s
[17:59:03.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:55:0)
[17:59:03.016] Timer tick, asking Buildbucket for the build status
[17:59:03.364] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:03.364] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:56:0) after 1m0s
[18:00:03.384] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:56:0)
[18:00:03.384] Timer tick, asking Buildbucket for the build status
[18:00:03.491] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:03.491] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:57:0) after 1m0s
[18:01:03.502] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:57:0)
[18:01:03.502] Timer tick, asking Buildbucket for the build status
[18:01:03.771] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:03.771] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:58:0) after 1m0s
[18:02:03.780] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:58:0)
[18:02:03.780] Timer tick, asking Buildbucket for the build status
[18:02:03.875] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:03.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:59:0) after 1m0s
[18:03:03.875] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:59:0)
[18:03:03.876] Timer tick, asking Buildbucket for the build status
[18:03:04.025] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:04.025] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:60:0) after 1m0s
[18:04:04.018] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:60:0)
[18:04:04.018] Timer tick, asking Buildbucket for the build status
[18:04:04.087] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:04.087] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:61:0) after 1m0s
[18:05:04.119] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:61:0)
[18:05:04.119] Timer tick, asking Buildbucket for the build status
[18:05:04.223] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:04.223] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:62:0) after 1m0s
[18:06:04.212] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:62:0)
[18:06:04.212] Timer tick, asking Buildbucket for the build status
[18:06:04.272] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:04.272] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:63:0) after 1m0s
[18:07:04.294] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:63:0)
[18:07:04.294] Timer tick, asking Buildbucket for the build status
[18:07:04.389] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:04.389] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:64:0) after 1m0s
[18:08:04.378] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:64:0)
[18:08:04.378] Timer tick, asking Buildbucket for the build status
[18:08:04.443] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:04.443] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:65:0) after 1m0s
[18:09:04.476] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:65:0)
[18:09:04.476] Timer tick, asking Buildbucket for the build status
[18:09:04.604] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:04.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:66:0) after 1m0s
[18:10:04.627] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:66:0)
[18:10:04.627] Timer tick, asking Buildbucket for the build status
[18:10:04.705] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:04.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:67:0) after 1m0s
[18:11:04.724] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:67:0)
[18:11:04.724] Timer tick, asking Buildbucket for the build status
[18:11:04.793] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:04.793] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:68:0) after 1m0s
[18:12:04.779] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:68:0)
[18:12:04.779] Timer tick, asking Buildbucket for the build status
[18:12:04.957] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:04.957] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:69:0) after 1m0s
[18:13:04.959] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:69:0)
[18:13:04.959] Timer tick, asking Buildbucket for the build status
[18:13:05.018] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:05.019] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:70:0) after 1m0s
[18:14:05.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:70:0)
[18:14:05.097] Timer tick, asking Buildbucket for the build status
[18:14:05.157] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:05.157] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:71:0) after 1m0s
[18:15:05.258] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:71:0)
[18:15:05.258] Timer tick, asking Buildbucket for the build status
[18:15:05.391] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:05.391] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:72:0) after 1m0s
[18:16:05.401] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:72:0)
[18:16:05.401] Timer tick, asking Buildbucket for the build status
[18:16:05.615] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:05.615] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:73:0) after 1m0s
[18:17:05.640] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:73:0)
[18:17:05.640] Timer tick, asking Buildbucket for the build status
[18:17:05.718] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:05.718] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:74:0) after 1m0s
[18:18:05.783] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:74:0)
[18:18:05.783] Timer tick, asking Buildbucket for the build status
[18:18:05.915] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:05.915] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:75:0) after 1m0s
[18:19:05.939] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:75:0)
[18:19:05.939] Timer tick, asking Buildbucket for the build status
[18:19:06.057] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:06.057] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:76:0) after 1m0s
[18:20:06.061] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:76:0)
[18:20:06.061] Timer tick, asking Buildbucket for the build status
[18:20:06.183] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:06.183] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:77:0) after 1m0s
[18:21:06.187] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:77:0)
[18:21:06.187] Timer tick, asking Buildbucket for the build status
[18:21:06.250] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:06.250] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:78:0) after 1m0s
[18:22:06.273] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:78:0)
[18:22:06.273] Timer tick, asking Buildbucket for the build status
[18:22:06.326] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:06.326] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:79:0) after 1m0s
[18:23:06.322] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:79:0)
[18:23:06.322] Timer tick, asking Buildbucket for the build status
[18:23:06.396] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:06.396] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:80:0) after 1m0s
[18:24:06.406] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:80:0)
[18:24:06.406] Timer tick, asking Buildbucket for the build status
[18:24:06.594] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:06.594] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:81:0) after 1m0s
[18:25:06.644] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:81:0)
[18:25:06.644] Timer tick, asking Buildbucket for the build status
[18:25:06.702] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:06.702] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:82:0) after 1m0s
[18:26:06.705] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:82:0)
[18:26:06.705] Timer tick, asking Buildbucket for the build status
[18:26:06.793] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:06.793] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:83:0) after 1m0s
[18:27:06.797] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:83:0)
[18:27:06.797] Timer tick, asking Buildbucket for the build status
[18:27:06.963] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:06.963] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:84:0) after 1m0s
[18:28:06.985] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:84:0)
[18:28:06.985] Timer tick, asking Buildbucket for the build status
[18:28:07.051] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:07.051] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:85:0) after 1m0s
[18:29:07.127] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:85:0)
[18:29:07.127] Timer tick, asking Buildbucket for the build status
[18:29:07.213] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:07.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:86:0) after 1m0s
[18:30:07.227] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:86:0)
[18:30:07.227] Timer tick, asking Buildbucket for the build status
[18:30:07.300] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:07.300] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:87:0) after 1m0s
[18:31:07.305] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:87:0)
[18:31:07.305] Timer tick, asking Buildbucket for the build status
[18:31:07.371] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:07.371] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:88:0) after 1m0s
[18:32:07.389] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:88:0)
[18:32:07.389] Timer tick, asking Buildbucket for the build status
[18:32:07.442] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:07.442] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:89:0) after 1m0s
[18:33:07.480] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:89:0)
[18:33:07.480] Timer tick, asking Buildbucket for the build status
[18:33:07.532] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:07.532] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:90:0) after 1m0s
[18:34:07.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:90:0)
[18:34:07.551] Timer tick, asking Buildbucket for the build status
[18:34:07.698] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:07.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:91:0) after 1m0s
[18:35:07.688] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:91:0)
[18:35:07.688] Timer tick, asking Buildbucket for the build status
[18:35:07.800] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:07.800] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:92:0) after 1m0s
[18:36:07.825] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:92:0)
[18:36:07.825] Timer tick, asking Buildbucket for the build status
[18:36:07.919] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:07.919] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:93:0) after 1m0s
[18:37:07.935] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:93:0)
[18:37:07.935] Timer tick, asking Buildbucket for the build status
[18:37:08.112] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:08.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:94:0) after 1m0s
[18:38:08.121] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:94:0)
[18:38:08.121] Timer tick, asking Buildbucket for the build status
[18:38:08.186] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:08.186] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:95:0) after 1m0s
[18:39:08.198] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:95:0)
[18:39:08.198] Timer tick, asking Buildbucket for the build status
[18:39:08.282] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:08.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:96:0) after 1m0s
[18:40:08.317] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:96:0)
[18:40:08.317] Timer tick, asking Buildbucket for the build status
[18:40:08.414] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:08.414] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:97:0) after 1m0s
[18:41:08.444] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:97:0)
[18:41:08.444] Timer tick, asking Buildbucket for the build status
[18:41:08.521] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:08.521] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:98:0) after 1m0s
[18:42:08.522] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:98:0)
[18:42:08.522] Timer tick, asking Buildbucket for the build status
[18:42:08.600] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:08.600] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:99:0) after 1m0s
[18:43:08.614] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:99:0)
[18:43:08.614] Timer tick, asking Buildbucket for the build status
[18:43:08.672] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:08.672] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:100:0) after 1m0s
[18:44:08.695] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:100:0)
[18:44:08.695] Timer tick, asking Buildbucket for the build status
[18:44:08.774] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:08.774] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:101:0) after 1m0s
[18:45:08.768] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:101:0)
[18:45:08.768] Timer tick, asking Buildbucket for the build status
[18:45:09.157] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:09.157] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:102:0) after 1m0s
[18:46:09.177] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:102:0)
[18:46:09.177] Timer tick, asking Buildbucket for the build status
[18:46:09.275] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:09.275] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:103:0) after 1m0s
[18:47:09.297] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:103:0)
[18:47:09.297] Timer tick, asking Buildbucket for the build status
[18:47:09.391] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:09.391] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:104:0) after 1m0s
[18:48:09.414] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:104:0)
[18:48:09.414] Timer tick, asking Buildbucket for the build status
[18:48:09.464] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:09.464] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:105:0) after 1m0s
[18:49:09.447] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:105:0)
[18:49:09.447] Timer tick, asking Buildbucket for the build status
[18:49:09.510] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:09.510] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:106:0) after 1m0s
[18:50:09.530] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:106:0)
[18:50:09.530] Timer tick, asking Buildbucket for the build status
[18:50:09.593] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:09.593] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:107:0) after 1m0s
[18:51:09.575] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:107:0)
[18:51:09.575] Timer tick, asking Buildbucket for the build status
[18:51:09.630] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:09.630] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:108:0) after 1m0s
[18:52:09.651] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:108:0)
[18:52:09.651] Timer tick, asking Buildbucket for the build status
[18:52:09.769] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:09.769] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:109:0) after 1m0s
[18:53:09.792] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:109:0)
[18:53:09.792] Timer tick, asking Buildbucket for the build status
[18:53:09.905] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:09.905] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:110:0) after 1m0s
[18:54:09.914] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:110:0)
[18:54:09.914] Timer tick, asking Buildbucket for the build status
[18:54:09.972] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:09.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:111:0) after 1m0s
[18:55:09.995] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:111:0)
[18:55:09.995] Timer tick, asking Buildbucket for the build status
[18:55:10.169] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:10.169] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:112:0) after 1m0s
[18:56:10.177] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:112:0)
[18:56:10.177] Timer tick, asking Buildbucket for the build status
[18:56:10.242] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:10.242] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:113:0) after 1m0s
[18:57:10.264] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:113:0)
[18:57:10.264] Timer tick, asking Buildbucket for the build status
[18:57:10.336] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:10.336] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:114:0) after 1m0s
[18:58:10.349] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:114:0)
[18:58:10.349] Timer tick, asking Buildbucket for the build status
[18:58:10.471] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:10.471] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:115:0) after 1m0s
[18:59:10.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:115:0)
[18:59:10.491] Timer tick, asking Buildbucket for the build status
[18:59:10.676] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:10.676] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:116:0) after 1m0s
[19:00:10.657] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:116:0)
[19:00:10.657] Timer tick, asking Buildbucket for the build status
[19:00:10.718] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:10.718] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:117:0) after 1m0s
[19:01:10.708] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:117:0)
[19:01:10.708] Timer tick, asking Buildbucket for the build status
[19:01:10.765] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:10.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:118:0) after 1m0s
[19:02:10.786] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:118:0)
[19:02:10.787] Timer tick, asking Buildbucket for the build status
[19:02:10.871] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:10.871] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:119:0) after 1m0s
[19:03:10.877] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:119:0)
[19:03:10.877] Timer tick, asking Buildbucket for the build status
[19:03:10.959] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:10.959] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:120:0) after 1m0s
[19:04:10.958] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:120:0)
[19:04:10.958] Timer tick, asking Buildbucket for the build status
[19:04:11.022] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:11.022] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:121:0) after 1m0s
[19:05:11.031] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:121:0)
[19:05:11.031] Timer tick, asking Buildbucket for the build status
[19:05:11.278] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:11.278] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:122:0) after 1m0s
[19:06:11.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:122:0)
[19:06:11.288] Timer tick, asking Buildbucket for the build status
[19:06:11.390] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:11.390] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:123:0) after 1m0s
[19:07:11.376] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:123:0)
[19:07:11.376] Timer tick, asking Buildbucket for the build status
[19:07:11.519] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:11.519] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:124:0) after 1m0s
[19:08:11.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:124:0)
[19:08:11.495] Timer tick, asking Buildbucket for the build status
[19:08:11.596] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:11.596] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:125:0) after 1m0s
[19:09:11.586] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:125:0)
[19:09:11.586] Timer tick, asking Buildbucket for the build status
[19:09:11.665] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:11.665] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:126:0) after 1m0s
[19:10:11.710] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:126:0)
[19:10:11.710] Timer tick, asking Buildbucket for the build status
[19:10:11.778] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:11.778] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:127:0) after 1m0s
[19:11:11.801] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:127:0)
[19:11:11.801] Timer tick, asking Buildbucket for the build status
[19:11:11.892] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:11.892] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:128:0) after 1m0s
[19:12:11.913] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:128:0)
[19:12:11.913] Timer tick, asking Buildbucket for the build status
[19:12:12.051] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:12.051] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:129:0) after 1m0s
[19:13:12.054] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:129:0)
[19:13:12.054] Timer tick, asking Buildbucket for the build status
[19:13:12.122] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:12.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:130:0) after 1m0s
[19:14:12.126] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:130:0)
[19:14:12.126] Timer tick, asking Buildbucket for the build status
[19:14:12.195] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:12.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:131:0) after 1m0s
[19:15:12.174] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:131:0)
[19:15:12.174] Timer tick, asking Buildbucket for the build status
[19:15:12.304] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:12.304] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:132:0) after 1m0s
[19:16:12.319] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:132:0)
[19:16:12.319] Timer tick, asking Buildbucket for the build status
[19:16:12.472] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:12.472] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:133:0) after 1m0s
[19:17:12.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:133:0)
[19:17:12.494] Timer tick, asking Buildbucket for the build status
[19:17:12.621] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:12.621] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:134:0) after 1m0s
[19:18:12.632] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:134:0)
[19:18:12.632] Timer tick, asking Buildbucket for the build status
[19:18:12.795] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:12.795] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:135:0) after 1m0s
[19:19:12.816] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:135:0)
[19:19:12.816] Timer tick, asking Buildbucket for the build status
[19:19:13.081] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:13.081] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:136:0) after 1m0s
[19:20:13.102] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:136:0)
[19:20:13.102] Timer tick, asking Buildbucket for the build status
[19:20:13.209] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:13.209] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:137:0) after 1m0s
[19:21:13.184] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:137:0)
[19:21:13.184] Timer tick, asking Buildbucket for the build status
[19:21:13.241] Build 8919445265917200256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:13.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9084875328049107456:138:0) after 1m0s
[19:22:04.284] Received PubSub notification, asking Buildbucket for the build status
[19:22:04.329] Build 8919445265917200256: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:22:04.329] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1552159323469202",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552151155511884",
"id": "8919445265917200256",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"75.0.3730.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-sxtq\"], \"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\": \"75.0.3730.0\", \"path_config\": \"generic\", \"bot_id\": \"gce-trusty-32-0258d129-us-east1-b-sxtq\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1552151175723433",
"status": "COMPLETED",
"status_changed_ts": "1552159323968126",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9084875328049107456",
"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/8919445265917200256/+/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:437c3c9c7955a310",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1552159323970827",
"url": "https://ci.chromium.org/b/8919445265917200256",
"utcnow_ts": "1552159324321338"
}
}
[19:22:04.329] Invocation finished in 2h16m10.235502904s with status SUCCEEDED