[18:52:40.363] New invocation is queued and will start shortly
[18:52:42.913] Starting the invocation (attempt 1)
[18:52:42.958] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:52:42.958] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:52:42.958] Buildbucket request:
{
"bucket": "luci.nacl.toolchain",
"client_operation_id": "9032503564030405216",
"parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"972758aa12bb3e5e6fc6159c2e27d287d4350218\"},\"id\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@972758aa12bb3e5e6fc6159c2e27d287d4350218\",\"title\":\"972758aa12bb3e5e6fc6159c2e27d287d4350218\",\"url\":\"https://chromium.googlesource.com/native_client/src/native_client.git/+/972758aa12bb3e5e6fc6159c2e27d287d4350218\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"972758aa12bb3e5e6fc6159c2e27d287d4350218\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-pnacl-x86_64",
"scheduler_invocation_id:9032503564030405216",
"scheduler_job_id:nacl/linux-pnacl-x86_64",
"user_agent:luci-scheduler",
"buildset:commit/git/972758aa12bb3e5e6fc6159c2e27d287d4350218",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/972758aa12bb3e5e6fc6159c2e27d287d4350218",
"gitiles_ref:refs/heads/master"
]
}
[18:52:43.716] Buildbucket response:
{
"build": {
"bucket": "luci.nacl.toolchain",
"canary_preference": "PROD",
"created_by": "project:nacl",
"created_ts": "1602096763074950",
"id": "8867073500521079968",
"parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"972758aa12bb3e5e6fc6159c2e27d287d4350218\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@972758aa12bb3e5e6fc6159c2e27d287d4350218\", \"title\": \"972758aa12bb3e5e6fc6159c2e27d287d4350218\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/972758aa12bb3e5e6fc6159c2e27d287d4350218\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"972758aa12bb3e5e6fc6159c2e27d287d4350218\"}}",
"project": "nacl",
"result_details_json": "{\"properties\": {}}",
"service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1602096763594067",
"tags": [
"build_address:luci.nacl.toolchain/linux-pnacl-x86_64/7078",
"builder:linux-pnacl-x86_64",
"buildset:commit/git/972758aa12bb3e5e6fc6159c2e27d287d4350218",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/972758aa12bb3e5e6fc6159c2e27d287d4350218",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032503564030405216",
"scheduler_job_id:nacl/linux-pnacl-x86_64",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8867073500521079968/+/annotations",
"swarming_tag:luci_project:nacl",
"swarming_tag:recipe_name:nacl",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1602096763594180",
"url": "https://ci.chromium.org/b/8867073500521079968",
"utcnow_ts": "1602096763709304"
}
}
[18:52:43.716] Task URL: https://ci.chromium.org/b/8867073500521079968
[18:52:43.716] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:2:0) after 1m0s
[18:53:43.909] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:2:0)
[18:53:43.909] Timer tick, asking Buildbucket for the build status
[18:53:44.025] Build 8867073500521079968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:53:44.025] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:3:0) after 1m0s
[18:54:44.135] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:3:0)
[18:54:44.135] Timer tick, asking Buildbucket for the build status
[18:54:44.297] Build 8867073500521079968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:54:44.298] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:4:0) after 1m0s
[18:55:44.322] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:4:0)
[18:55:44.322] Timer tick, asking Buildbucket for the build status
[18:55:44.404] Build 8867073500521079968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:55:44.404] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:5:0) after 1m0s
[18:56:44.426] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:5:0)
[18:56:44.426] Timer tick, asking Buildbucket for the build status
[18:56:44.533] Build 8867073500521079968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:56:44.533] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:6:0) after 1m0s
[18:57:44.540] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:6:0)
[18:57:44.540] Timer tick, asking Buildbucket for the build status
[18:57:44.635] Build 8867073500521079968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:57:44.635] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:7:0) after 1m0s
[18:58:44.699] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:7:0)
[18:58:44.699] Timer tick, asking Buildbucket for the build status
[18:58:44.860] Build 8867073500521079968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:58:44.860] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:8:0) after 1m0s
[18:59:44.937] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:8:0)
[18:59:44.937] Timer tick, asking Buildbucket for the build status
[18:59:45.051] Build 8867073500521079968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:59:45.051] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:9:0) after 1m0s
[19:00:45.118] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:9:0)
[19:00:45.118] Timer tick, asking Buildbucket for the build status
[19:00:45.295] Build 8867073500521079968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:00:45.295] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:10:0) after 1m0s
[19:01:45.397] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:10:0)
[19:01:45.397] Timer tick, asking Buildbucket for the build status
[19:01:45.507] Build 8867073500521079968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:01:45.507] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:11:0) after 1m0s
[19:02:45.585] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:11:0)
[19:02:45.585] Timer tick, asking Buildbucket for the build status
[19:02:45.709] Build 8867073500521079968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:02:45.709] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:12:0) after 1m0s
[19:03:45.829] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:12:0)
[19:03:45.829] Timer tick, asking Buildbucket for the build status
[19:03:45.906] Build 8867073500521079968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:03:45.906] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:13:0) after 1m0s
[19:04:46.014] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:13:0)
[19:04:46.014] Timer tick, asking Buildbucket for the build status
[19:04:46.098] Build 8867073500521079968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:04:46.098] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:14:0) after 1m0s
[19:05:46.299] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:14:0)
[19:05:46.299] Timer tick, asking Buildbucket for the build status
[19:05:46.390] Build 8867073500521079968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:05:46.390] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:15:0) after 1m0s
[19:06:46.407] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:15:0)
[19:06:46.407] Timer tick, asking Buildbucket for the build status
[19:06:46.956] Build 8867073500521079968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:06:46.956] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:16:0) after 1m0s
[19:07:47.012] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:16:0)
[19:07:47.013] Timer tick, asking Buildbucket for the build status
[19:07:47.129] Build 8867073500521079968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:07:47.129] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:17:0) after 1m0s
[19:08:47.158] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:17:0)
[19:08:47.158] Timer tick, asking Buildbucket for the build status
[19:08:47.274] Build 8867073500521079968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:08:47.274] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:18:0) after 1m0s
[19:09:47.354] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:18:0)
[19:09:47.354] Timer tick, asking Buildbucket for the build status
[19:09:47.429] Build 8867073500521079968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:09:47.429] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:19:0) after 1m0s
[19:10:08.939] Received PubSub notification, asking Buildbucket for the build status
[19:10:09.030] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:47.488] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:19:0)
[19:10:47.488] Timer tick, asking Buildbucket for the build status
[19:10:47.753] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:47.753] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:21:0) after 1m0s
[19:11:47.856] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:21:0)
[19:11:47.856] Timer tick, asking Buildbucket for the build status
[19:11:47.965] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:47.965] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:22:0) after 1m0s
[19:12:47.999] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:22:0)
[19:12:47.999] Timer tick, asking Buildbucket for the build status
[19:12:48.129] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:48.129] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:23:0) after 1m0s
[19:13:48.133] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:23:0)
[19:13:48.133] Timer tick, asking Buildbucket for the build status
[19:13:48.261] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:48.261] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:24:0) after 1m0s
[19:14:48.291] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:24:0)
[19:14:48.291] Timer tick, asking Buildbucket for the build status
[19:14:48.517] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:48.517] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:25:0) after 1m0s
[19:15:48.609] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:25:0)
[19:15:48.609] Timer tick, asking Buildbucket for the build status
[19:15:48.704] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:48.704] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:26:0) after 1m0s
[19:16:48.796] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:26:0)
[19:16:48.796] Timer tick, asking Buildbucket for the build status
[19:16:49.495] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:49.495] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:27:0) after 1m0s
[19:17:49.500] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:27:0)
[19:17:49.500] Timer tick, asking Buildbucket for the build status
[19:17:49.604] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:49.604] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:28:0) after 1m0s
[19:18:49.637] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:28:0)
[19:18:49.637] Timer tick, asking Buildbucket for the build status
[19:18:49.927] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:49.927] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:29:0) after 1m0s
[19:19:49.968] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:29:0)
[19:19:49.968] Timer tick, asking Buildbucket for the build status
[19:19:50.076] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:50.076] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:30:0) after 1m0s
[19:20:50.203] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:30:0)
[19:20:50.203] Timer tick, asking Buildbucket for the build status
[19:20:50.472] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:50.472] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:31:0) after 1m0s
[19:21:50.544] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:31:0)
[19:21:50.544] Timer tick, asking Buildbucket for the build status
[19:21:50.650] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:50.650] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:32:0) after 1m0s
[19:22:50.708] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:32:0)
[19:22:50.708] Timer tick, asking Buildbucket for the build status
[19:22:50.827] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:50.827] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:33:0) after 1m0s
[19:23:50.916] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:33:0)
[19:23:50.916] Timer tick, asking Buildbucket for the build status
[19:23:51.025] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:51.025] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:34:0) after 1m0s
[19:24:51.166] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:34:0)
[19:24:51.166] Timer tick, asking Buildbucket for the build status
[19:24:51.403] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:51.403] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:35:0) after 1m0s
[19:25:51.470] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:35:0)
[19:25:51.470] Timer tick, asking Buildbucket for the build status
[19:25:51.569] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:51.569] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:36:0) after 1m0s
[19:26:51.642] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:36:0)
[19:26:51.642] Timer tick, asking Buildbucket for the build status
[19:26:52.771] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:52.771] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:37:0) after 1m0s
[19:27:52.798] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:37:0)
[19:27:52.798] Timer tick, asking Buildbucket for the build status
[19:27:53.177] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:53.177] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:38:0) after 1m0s
[19:28:53.451] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:38:0)
[19:28:53.451] Timer tick, asking Buildbucket for the build status
[19:28:53.553] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:53.553] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:39:0) after 1m0s
[19:29:53.834] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:39:0)
[19:29:53.834] Timer tick, asking Buildbucket for the build status
[19:29:53.944] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:53.944] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:40:0) after 1m0s
[19:30:53.985] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:40:0)
[19:30:53.985] Timer tick, asking Buildbucket for the build status
[19:30:54.104] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:54.104] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:41:0) after 1m0s
[19:31:54.386] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:41:0)
[19:31:54.386] Timer tick, asking Buildbucket for the build status
[19:31:54.629] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:54.629] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:42:0) after 1m0s
[19:32:54.774] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:42:0)
[19:32:54.774] Timer tick, asking Buildbucket for the build status
[19:32:54.875] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:54.875] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:43:0) after 1m0s
[19:33:55.004] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:43:0)
[19:33:55.004] Timer tick, asking Buildbucket for the build status
[19:33:55.108] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:55.108] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:44:0) after 1m0s
[19:34:55.284] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:44:0)
[19:34:55.284] Timer tick, asking Buildbucket for the build status
[19:34:55.389] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:55.389] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:45:0) after 1m0s
[19:35:55.546] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:45:0)
[19:35:55.546] Timer tick, asking Buildbucket for the build status
[19:35:55.727] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:55.727] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:46:0) after 1m0s
[19:36:55.788] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:46:0)
[19:36:55.788] Timer tick, asking Buildbucket for the build status
[19:36:55.884] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:55.884] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:47:0) after 1m0s
[19:37:55.913] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:47:0)
[19:37:55.913] Timer tick, asking Buildbucket for the build status
[19:37:56.035] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:56.035] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:48:0) after 1m0s
[19:38:56.124] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:48:0)
[19:38:56.124] Timer tick, asking Buildbucket for the build status
[19:38:56.219] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:56.219] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:49:0) after 1m0s
[19:39:56.316] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:49:0)
[19:39:56.316] Timer tick, asking Buildbucket for the build status
[19:39:56.445] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:56.445] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:50:0) after 1m0s
[19:40:56.449] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:50:0)
[19:40:56.449] Timer tick, asking Buildbucket for the build status
[19:40:56.569] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:56.569] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:51:0) after 1m0s
[19:41:56.637] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:51:0)
[19:41:56.637] Timer tick, asking Buildbucket for the build status
[19:41:56.743] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:56.743] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:52:0) after 1m0s
[19:42:56.790] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:52:0)
[19:42:56.790] Timer tick, asking Buildbucket for the build status
[19:42:56.914] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:56.914] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:53:0) after 1m0s
[19:43:56.924] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:53:0)
[19:43:56.924] Timer tick, asking Buildbucket for the build status
[19:43:57.016] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:57.016] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:54:0) after 1m0s
[19:44:57.023] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:54:0)
[19:44:57.023] Timer tick, asking Buildbucket for the build status
[19:44:57.092] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:57.092] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:55:0) after 1m0s
[19:45:57.147] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:55:0)
[19:45:57.147] Timer tick, asking Buildbucket for the build status
[19:45:57.222] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:57.222] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:56:0) after 1m0s
[19:46:57.278] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:56:0)
[19:46:57.278] Timer tick, asking Buildbucket for the build status
[19:46:57.834] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:57.834] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:57:0) after 1m0s
[19:47:57.894] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:57:0)
[19:47:57.894] Timer tick, asking Buildbucket for the build status
[19:47:58.015] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:58.015] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:58:0) after 1m0s
[19:48:58.048] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:58:0)
[19:48:58.048] Timer tick, asking Buildbucket for the build status
[19:48:58.118] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:58.118] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:59:0) after 1m0s
[19:49:58.183] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:59:0)
[19:49:58.183] Timer tick, asking Buildbucket for the build status
[19:49:58.296] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:58.296] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:60:0) after 1m0s
[19:50:58.319] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:60:0)
[19:50:58.319] Timer tick, asking Buildbucket for the build status
[19:50:58.420] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:58.420] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:61:0) after 1m0s
[19:51:58.463] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:61:0)
[19:51:58.463] Timer tick, asking Buildbucket for the build status
[19:51:58.564] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:58.564] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:62:0) after 1m0s
[19:52:58.615] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:62:0)
[19:52:58.615] Timer tick, asking Buildbucket for the build status
[19:52:58.716] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:58.716] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:63:0) after 1m0s
[19:53:58.742] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:63:0)
[19:53:58.742] Timer tick, asking Buildbucket for the build status
[19:53:58.898] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:58.898] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:64:0) after 1m0s
[19:54:58.926] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:64:0)
[19:54:58.926] Timer tick, asking Buildbucket for the build status
[19:54:59.030] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:59.030] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:65:0) after 1m0s
[19:55:59.047] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:65:0)
[19:55:59.047] Timer tick, asking Buildbucket for the build status
[19:55:59.104] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:59.104] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:66:0) after 1m0s
[19:56:59.175] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:66:0)
[19:56:59.175] Timer tick, asking Buildbucket for the build status
[19:56:59.231] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:59.231] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:67:0) after 1m0s
[19:57:59.252] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:67:0)
[19:57:59.252] Timer tick, asking Buildbucket for the build status
[19:57:59.364] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:59.364] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:68:0) after 1m0s
[19:58:59.409] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:68:0)
[19:58:59.409] Timer tick, asking Buildbucket for the build status
[19:58:59.805] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:59.805] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:69:0) after 1m0s
[19:59:59.828] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:69:0)
[19:59:59.828] Timer tick, asking Buildbucket for the build status
[19:59:59.967] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:59.967] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:70:0) after 1m0s
[20:00:59.986] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:70:0)
[20:00:59.986] Timer tick, asking Buildbucket for the build status
[20:01:00.355] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:00.355] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:71:0) after 1m0s
[20:02:00.380] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:71:0)
[20:02:00.380] Timer tick, asking Buildbucket for the build status
[20:02:00.539] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:00.539] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:72:0) after 1m0s
[20:03:01.648] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:72:0)
[20:03:01.648] Timer tick, asking Buildbucket for the build status
[20:03:01.760] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:01.760] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:73:0) after 1m0s
[20:04:01.789] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:73:0)
[20:04:01.789] Timer tick, asking Buildbucket for the build status
[20:04:01.966] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:01.966] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:74:0) after 1m0s
[20:05:02.018] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:74:0)
[20:05:02.018] Timer tick, asking Buildbucket for the build status
[20:05:02.147] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:02.147] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:75:0) after 1m0s
[20:06:02.220] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:75:0)
[20:06:02.220] Timer tick, asking Buildbucket for the build status
[20:06:02.362] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:02.362] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:76:0) after 1m0s
[20:07:02.445] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:76:0)
[20:07:02.445] Timer tick, asking Buildbucket for the build status
[20:07:02.526] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:02.526] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:77:0) after 1m0s
[20:08:02.538] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:77:0)
[20:08:02.538] Timer tick, asking Buildbucket for the build status
[20:08:02.651] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:02.651] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:78:0) after 1m0s
[20:09:03.991] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:78:0)
[20:09:03.991] Timer tick, asking Buildbucket for the build status
[20:09:04.048] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:04.048] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:79:0) after 1m0s
[20:10:04.063] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:79:0)
[20:10:04.064] Timer tick, asking Buildbucket for the build status
[20:10:04.195] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:04.195] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:80:0) after 1m0s
[20:11:04.249] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:80:0)
[20:11:04.249] Timer tick, asking Buildbucket for the build status
[20:11:04.645] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:04.645] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:81:0) after 1m0s
[20:12:04.733] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:81:0)
[20:12:04.733] Timer tick, asking Buildbucket for the build status
[20:12:06.043] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:06.043] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:82:0) after 1m0s
[20:13:06.111] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:82:0)
[20:13:06.111] Timer tick, asking Buildbucket for the build status
[20:13:06.433] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:06.433] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:83:0) after 1m0s
[20:14:06.437] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:83:0)
[20:14:06.437] Timer tick, asking Buildbucket for the build status
[20:14:06.768] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:06.768] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:84:0) after 1m0s
[20:15:06.784] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:84:0)
[20:15:06.784] Timer tick, asking Buildbucket for the build status
[20:15:07.101] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:07.101] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:85:0) after 1m0s
[20:16:07.181] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:85:0)
[20:16:07.181] Timer tick, asking Buildbucket for the build status
[20:16:07.242] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:07.242] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:86:0) after 1m0s
[20:17:07.265] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:86:0)
[20:17:07.265] Timer tick, asking Buildbucket for the build status
[20:17:07.347] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:07.347] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:87:0) after 1m0s
[20:18:07.428] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:87:0)
[20:18:07.428] Timer tick, asking Buildbucket for the build status
[20:18:07.812] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:07.812] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:88:0) after 1m0s
[20:19:07.871] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:88:0)
[20:19:07.871] Timer tick, asking Buildbucket for the build status
[20:19:08.241] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:08.241] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:89:0) after 1m0s
[20:20:08.296] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:89:0)
[20:20:08.296] Timer tick, asking Buildbucket for the build status
[20:20:08.448] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:08.448] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:90:0) after 1m0s
[20:21:08.618] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:90:0)
[20:21:08.618] Timer tick, asking Buildbucket for the build status
[20:21:08.921] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:08.921] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:91:0) after 1m0s
[20:22:09.119] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:91:0)
[20:22:09.119] Timer tick, asking Buildbucket for the build status
[20:22:09.383] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:09.383] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:92:0) after 1m0s
[20:23:09.448] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:92:0)
[20:23:09.448] Timer tick, asking Buildbucket for the build status
[20:23:09.574] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:09.574] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:93:0) after 1m0s
[20:24:09.707] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:93:0)
[20:24:09.707] Timer tick, asking Buildbucket for the build status
[20:24:09.897] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:09.897] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:94:0) after 1m0s
[20:25:10.051] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:94:0)
[20:25:10.051] Timer tick, asking Buildbucket for the build status
[20:25:10.438] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:10.438] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:95:0) after 1m0s
[20:26:10.573] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:95:0)
[20:26:10.573] Timer tick, asking Buildbucket for the build status
[20:26:10.693] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:10.693] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:96:0) after 1m0s
[20:27:10.930] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:96:0)
[20:27:10.930] Timer tick, asking Buildbucket for the build status
[20:27:11.038] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:11.038] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:97:0) after 1m0s
[20:28:11.168] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:97:0)
[20:28:11.168] Timer tick, asking Buildbucket for the build status
[20:28:11.522] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:11.522] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:98:0) after 1m0s
[20:29:11.550] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:98:0)
[20:29:11.550] Timer tick, asking Buildbucket for the build status
[20:29:11.633] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:11.633] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:99:0) after 1m0s
[20:30:11.653] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:99:0)
[20:30:11.653] Timer tick, asking Buildbucket for the build status
[20:30:11.903] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:11.903] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:100:0) after 1m0s
[20:31:11.929] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:100:0)
[20:31:11.929] Timer tick, asking Buildbucket for the build status
[20:31:18.553] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:18.553] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:101:0) after 1m0s
[20:32:18.650] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:101:0)
[20:32:18.650] Timer tick, asking Buildbucket for the build status
[20:32:18.715] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:18.715] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:102:0) after 1m0s
[20:33:18.748] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:102:0)
[20:33:18.748] Timer tick, asking Buildbucket for the build status
[20:33:18.868] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:18.868] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:103:0) after 1m0s
[20:34:18.929] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:103:0)
[20:34:18.929] Timer tick, asking Buildbucket for the build status
[20:34:19.041] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:19.041] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:104:0) after 1m0s
[20:35:19.044] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:104:0)
[20:35:19.044] Timer tick, asking Buildbucket for the build status
[20:35:19.177] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:19.177] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:105:0) after 1m0s
[20:36:19.205] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:105:0)
[20:36:19.205] Timer tick, asking Buildbucket for the build status
[20:36:19.324] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:19.324] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:106:0) after 1m0s
[20:37:19.362] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:106:0)
[20:37:19.362] Timer tick, asking Buildbucket for the build status
[20:37:19.446] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:19.447] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:107:0) after 1m0s
[20:38:19.530] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:107:0)
[20:38:19.530] Timer tick, asking Buildbucket for the build status
[20:38:19.635] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:19.635] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:108:0) after 1m0s
[20:39:19.689] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:108:0)
[20:39:19.689] Timer tick, asking Buildbucket for the build status
[20:39:19.799] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:19.799] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:109:0) after 1m0s
[20:40:19.785] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:109:0)
[20:40:19.785] Timer tick, asking Buildbucket for the build status
[20:40:19.897] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:19.897] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:110:0) after 1m0s
[20:41:19.956] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:110:0)
[20:41:19.956] Timer tick, asking Buildbucket for the build status
[20:41:20.058] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:20.058] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:111:0) after 1m0s
[20:42:20.110] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:111:0)
[20:42:20.110] Timer tick, asking Buildbucket for the build status
[20:42:20.169] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:20.169] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:112:0) after 1m0s
[20:43:20.158] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:112:0)
[20:43:20.158] Timer tick, asking Buildbucket for the build status
[20:43:20.281] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:20.281] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:113:0) after 1m0s
[20:44:20.333] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:113:0)
[20:44:20.333] Timer tick, asking Buildbucket for the build status
[20:44:20.529] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:20.529] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:114:0) after 1m0s
[20:45:20.589] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:114:0)
[20:45:20.589] Timer tick, asking Buildbucket for the build status
[20:45:20.835] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:20.835] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:115:0) after 1m0s
[20:46:20.838] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:115:0)
[20:46:20.838] Timer tick, asking Buildbucket for the build status
[20:46:20.906] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:20.906] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:116:0) after 1m0s
[20:47:20.968] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:116:0)
[20:47:20.968] Timer tick, asking Buildbucket for the build status
[20:47:21.278] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:21.278] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:117:0) after 1m0s
[20:48:21.432] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:117:0)
[20:48:21.432] Timer tick, asking Buildbucket for the build status
[20:48:21.579] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:21.579] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:118:0) after 1m0s
[20:49:21.651] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:118:0)
[20:49:21.651] Timer tick, asking Buildbucket for the build status
[20:49:21.835] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:21.835] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:119:0) after 1m0s
[20:50:21.913] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:119:0)
[20:50:21.913] Timer tick, asking Buildbucket for the build status
[20:50:22.023] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:22.023] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:120:0) after 1m0s
[20:51:22.133] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:120:0)
[20:51:22.133] Timer tick, asking Buildbucket for the build status
[20:51:22.201] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:22.201] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:121:0) after 1m0s
[20:52:22.267] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:121:0)
[20:52:22.267] Timer tick, asking Buildbucket for the build status
[20:52:22.339] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:22.339] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:122:0) after 1m0s
[20:53:22.415] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:122:0)
[20:53:22.415] Timer tick, asking Buildbucket for the build status
[20:53:22.697] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:22.697] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:123:0) after 1m0s
[20:54:22.767] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:123:0)
[20:54:22.767] Timer tick, asking Buildbucket for the build status
[20:54:22.888] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:22.888] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:124:0) after 1m0s
[20:55:22.962] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:124:0)
[20:55:22.962] Timer tick, asking Buildbucket for the build status
[20:55:23.054] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:23.054] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:125:0) after 1m0s
[20:56:23.081] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:125:0)
[20:56:23.081] Timer tick, asking Buildbucket for the build status
[20:56:23.488] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:23.488] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:126:0) after 1m0s
[20:57:23.529] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:126:0)
[20:57:23.529] Timer tick, asking Buildbucket for the build status
[20:57:23.661] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:23.661] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:127:0) after 1m0s
[20:58:23.746] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:127:0)
[20:58:23.746] Timer tick, asking Buildbucket for the build status
[20:58:23.873] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:23.873] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:128:0) after 1m0s
[20:59:24.012] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:128:0)
[20:59:24.012] Timer tick, asking Buildbucket for the build status
[20:59:24.201] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:24.201] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:129:0) after 1m0s
[21:00:24.471] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:129:0)
[21:00:24.471] Timer tick, asking Buildbucket for the build status
[21:00:24.680] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:24.680] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:130:0) after 1m0s
[21:01:24.806] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:130:0)
[21:01:24.806] Timer tick, asking Buildbucket for the build status
[21:01:24.916] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:24.916] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:131:0) after 1m0s
[21:02:25.332] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:131:0)
[21:02:25.332] Timer tick, asking Buildbucket for the build status
[21:02:25.637] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:25.637] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:132:0) after 1m0s
[21:03:25.856] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:132:0)
[21:03:25.856] Timer tick, asking Buildbucket for the build status
[21:03:25.956] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:25.956] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:133:0) after 1m0s
[21:04:26.057] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:133:0)
[21:04:26.057] Timer tick, asking Buildbucket for the build status
[21:04:26.287] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:26.287] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:134:0) after 1m0s
[21:05:26.511] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:134:0)
[21:05:26.511] Timer tick, asking Buildbucket for the build status
[21:05:26.657] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:26.657] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:135:0) after 1m0s
[21:06:26.751] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:135:0)
[21:06:26.751] Timer tick, asking Buildbucket for the build status
[21:06:26.885] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:26.885] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:136:0) after 1m0s
[21:07:27.064] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:136:0)
[21:07:27.064] Timer tick, asking Buildbucket for the build status
[21:07:27.236] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:27.236] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:137:0) after 1m0s
[21:08:27.285] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:137:0)
[21:08:27.285] Timer tick, asking Buildbucket for the build status
[21:08:27.378] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:27.378] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:138:0) after 1m0s
[21:09:27.489] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:138:0)
[21:09:27.489] Timer tick, asking Buildbucket for the build status
[21:09:27.585] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:27.585] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:139:0) after 1m0s
[21:10:27.681] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:139:0)
[21:10:27.681] Timer tick, asking Buildbucket for the build status
[21:10:27.975] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:27.975] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:140:0) after 1m0s
[21:11:28.025] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:140:0)
[21:11:28.025] Timer tick, asking Buildbucket for the build status
[21:11:28.093] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:28.094] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:141:0) after 1m0s
[21:12:28.129] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:141:0)
[21:12:28.129] Timer tick, asking Buildbucket for the build status
[21:12:28.194] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:28.194] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:142:0) after 1m0s
[21:13:28.320] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:142:0)
[21:13:28.320] Timer tick, asking Buildbucket for the build status
[21:13:28.421] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:28.421] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:143:0) after 1m0s
[21:14:28.530] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:143:0)
[21:14:28.530] Timer tick, asking Buildbucket for the build status
[21:14:28.657] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:28.657] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:144:0) after 1m0s
[21:15:28.777] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:144:0)
[21:15:28.777] Timer tick, asking Buildbucket for the build status
[21:15:28.984] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:28.984] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:145:0) after 1m0s
[21:16:29.098] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:145:0)
[21:16:29.098] Timer tick, asking Buildbucket for the build status
[21:16:29.174] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:29.174] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:146:0) after 1m0s
[21:17:29.275] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:146:0)
[21:17:29.275] Timer tick, asking Buildbucket for the build status
[21:17:29.364] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:29.364] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:147:0) after 1m0s
[21:18:29.530] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:147:0)
[21:18:29.530] Timer tick, asking Buildbucket for the build status
[21:18:29.629] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:29.629] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:148:0) after 1m0s
[21:19:29.874] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:148:0)
[21:19:29.874] Timer tick, asking Buildbucket for the build status
[21:19:29.940] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:29.940] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:149:0) after 1m0s
[21:20:30.138] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:149:0)
[21:20:30.138] Timer tick, asking Buildbucket for the build status
[21:20:30.235] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:30.235] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:150:0) after 1m0s
[21:21:30.298] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:150:0)
[21:21:30.298] Timer tick, asking Buildbucket for the build status
[21:21:30.368] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:30.368] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:151:0) after 1m0s
[21:22:30.463] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:151:0)
[21:22:30.463] Timer tick, asking Buildbucket for the build status
[21:22:30.540] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:30.540] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:152:0) after 1m0s
[21:23:30.713] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:152:0)
[21:23:30.713] Timer tick, asking Buildbucket for the build status
[21:23:30.828] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:30.828] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:153:0) after 1m0s
[21:24:30.924] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:153:0)
[21:24:30.924] Timer tick, asking Buildbucket for the build status
[21:24:30.985] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:30.985] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:154:0) after 1m0s
[21:25:31.051] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:154:0)
[21:25:31.051] Timer tick, asking Buildbucket for the build status
[21:25:31.132] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:31.132] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:155:0) after 1m0s
[21:26:31.210] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:155:0)
[21:26:31.210] Timer tick, asking Buildbucket for the build status
[21:26:31.519] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:31.519] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:156:0) after 1m0s
[21:27:31.736] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:156:0)
[21:27:31.736] Timer tick, asking Buildbucket for the build status
[21:27:31.805] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:31.805] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:157:0) after 1m0s
[21:28:31.857] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:157:0)
[21:28:31.857] Timer tick, asking Buildbucket for the build status
[21:28:31.999] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:31.999] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:158:0) after 1m0s
[21:29:32.053] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:158:0)
[21:29:32.053] Timer tick, asking Buildbucket for the build status
[21:29:32.123] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:32.123] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:159:0) after 1m0s
[21:30:32.229] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:159:0)
[21:30:32.229] Timer tick, asking Buildbucket for the build status
[21:30:32.587] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:32.587] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:160:0) after 1m0s
[21:31:32.656] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:160:0)
[21:31:32.656] Timer tick, asking Buildbucket for the build status
[21:31:32.979] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:32.979] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:161:0) after 1m0s
[21:32:33.073] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:161:0)
[21:32:33.073] Timer tick, asking Buildbucket for the build status
[21:32:33.427] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:33.427] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:162:0) after 1m0s
[21:33:33.518] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:162:0)
[21:33:33.518] Timer tick, asking Buildbucket for the build status
[21:33:33.704] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:33.704] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:163:0) after 1m0s
[21:34:33.719] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:163:0)
[21:34:33.719] Timer tick, asking Buildbucket for the build status
[21:34:33.822] Build 8867073500521079968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:33.822] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64:9032503564030405216:164:0) after 1m0s
[21:34:51.768] Received PubSub notification, asking Buildbucket for the build status
[21:34:51.888] Build 8867073500521079968: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:34:51.888] Buildbucket build:
{
"build": {
"bucket": "luci.nacl.toolchain",
"canary_preference": "PROD",
"completed_ts": "1602106491183619",
"created_by": "project:nacl",
"created_ts": "1602096763074950",
"id": "8867073500521079968",
"parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"972758aa12bb3e5e6fc6159c2e27d287d4350218\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@972758aa12bb3e5e6fc6159c2e27d287d4350218\", \"title\": \"972758aa12bb3e5e6fc6159c2e27d287d4350218\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/972758aa12bb3e5e6fc6159c2e27d287d4350218\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"972758aa12bb3e5e6fc6159c2e27d287d4350218\"}}",
"project": "nacl",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"972758aa12bb3e5e6fc6159c2e27d287d4350218\"}, \"id\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/refs/heads/master@972758aa12bb3e5e6fc6159c2e27d287d4350218\", \"title\": \"972758aa12bb3e5e6fc6159c2e27d287d4350218\", \"url\": \"https://chromium.googlesource.com/native_client/src/native_client.git/+/972758aa12bb3e5e6fc6159c2e27d287d4350218\"}]}, \"bot_id\": \"luci-nacl-toolchain-xenial-0-sw98\", \"branch\": \"refs/heads/master\", \"builder_group\": \"client.nacl.toolchain\", \"buildername\": \"linux-pnacl-x86_64\", \"buildnumber\": 7078, \"got_revision\": \"972758aa12bb3e5e6fc6159c2e27d287d4350218\", \"path_config\": \"generic\", \"recipe\": \"nacl\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"972758aa12bb3e5e6fc6159c2e27d287d4350218\", \"slavetype\": \"BuilderTester\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_5e264a2b33887b9708d9efa4d555f48b42be33439102d42d27d93f0b7d878c19_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"16\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-nacl-toolchain-xenial-0-sw98\"], \"image\": [\"chrome-xenial-20100400-30cd36dae64\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.nacl.toolchain\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5570-fa4adf0\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1602097806567346",
"status": "COMPLETED",
"status_changed_ts": "1602106491438039",
"tags": [
"build_address:luci.nacl.toolchain/linux-pnacl-x86_64/7078",
"builder:linux-pnacl-x86_64",
"buildset:commit/git/972758aa12bb3e5e6fc6159c2e27d287d4350218",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/972758aa12bb3e5e6fc6159c2e27d287d4350218",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032503564030405216",
"scheduler_job_id:nacl/linux-pnacl-x86_64",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8867073500521079968/+/annotations",
"swarming_tag:luci_project:nacl",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:nacl",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4f1d3a1c6c7aab10",
"user_agent:luci-scheduler"
],
"updated_ts": "1602106491448502",
"url": "https://ci.chromium.org/b/8867073500521079968",
"utcnow_ts": "1602106491879000"
}
}
[21:34:51.888] Invocation finished in 2h42m11.544705212s with status SUCCEEDED