[21:49:21.520] New invocation is queued and will start shortly
[21:49:23.154] Starting the invocation (attempt 1)
[21:49:23.251] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:49:23.251] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:49:23.251] Buildbucket request:
{
"bucket": "luci.nacl.toolchain",
"client_operation_id": "9084042121789426048",
"parameters_json": "{\"builder_name\":\"linux-pnacl-x86_64-tests-arm\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/native_client/src/native_client.git\",\"revision\":\"7d24fb15d81f95ed30dde0c04b68584749709657\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-pnacl-x86_64-tests-arm",
"scheduler_invocation_id:9084042121789426048",
"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-arm",
"user_agent:luci-scheduler",
"buildset:commit/git/7d24fb15d81f95ed30dde0c04b68584749709657",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/7d24fb15d81f95ed30dde0c04b68584749709657",
"gitiles_ref:refs/heads/master"
]
}
[21:49:24.266] Buildbucket response:
{
"build": {
"bucket": "luci.nacl.toolchain",
"canary": true,
"canary_preference": "AUTO",
"created_by": "project:nacl",
"created_ts": "1552945763378283",
"id": "8918612059178515600",
"lease_expiration_ts": "1553096964025731",
"lease_key": "2045776920",
"parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-arm\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"7d24fb15d81f95ed30dde0c04b68584749709657\"}}",
"project": "nacl",
"result_details_json": "{\"properties\": {}}",
"service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552945764155645",
"tags": [
"build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-arm/4703",
"builder:linux-pnacl-x86_64-tests-arm",
"buildset:commit/git/7d24fb15d81f95ed30dde0c04b68584749709657",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/7d24fb15d81f95ed30dde0c04b68584749709657",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084042121789426048",
"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-arm",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8918612059178515600/+/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:43ab995daa45d810",
"user_agent:luci-scheduler"
],
"updated_ts": "1552945764155719",
"url": "https://ci.chromium.org/b/8918612059178515600",
"utcnow_ts": "1552945764256093"
}
}
[21:49:24.266] Task URL: https://ci.chromium.org/b/8918612059178515600
[21:49:24.266] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:2:0) after 1m0s
[21:50:24.286] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:2:0)
[21:50:24.286] Timer tick, asking Buildbucket for the build status
[21:50:24.479] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:50:24.479] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:3:0) after 1m0s
[21:51:24.498] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:3:0)
[21:51:24.498] Timer tick, asking Buildbucket for the build status
[21:51:24.908] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:24.909] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:4:0) after 1m0s
[21:52:25.082] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:4:0)
[21:52:25.082] Timer tick, asking Buildbucket for the build status
[21:52:25.137] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:52:25.137] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:5:0) after 1m0s
[21:53:25.167] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:5:0)
[21:53:25.167] Timer tick, asking Buildbucket for the build status
[21:53:25.374] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:53:25.374] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:6:0) after 1m0s
[21:54:25.396] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:6:0)
[21:54:25.396] Timer tick, asking Buildbucket for the build status
[21:54:25.461] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:54:25.461] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:7:0) after 1m0s
[21:55:25.534] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:7:0)
[21:55:25.534] Timer tick, asking Buildbucket for the build status
[21:55:25.652] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:55:25.652] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:8:0) after 1m0s
[21:56:25.893] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:8:0)
[21:56:25.893] Timer tick, asking Buildbucket for the build status
[21:56:26.183] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:56:26.183] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:9:0) after 1m0s
[21:57:26.200] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:9:0)
[21:57:26.200] Timer tick, asking Buildbucket for the build status
[21:57:26.300] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:57:26.300] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:10:0) after 1m0s
[21:58:26.355] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:10:0)
[21:58:26.355] Timer tick, asking Buildbucket for the build status
[21:58:26.411] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:58:26.411] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:11:0) after 1m0s
[21:59:26.518] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:11:0)
[21:59:26.518] Timer tick, asking Buildbucket for the build status
[21:59:26.617] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:59:26.617] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:12:0) after 1m0s
[22:00:26.727] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:12:0)
[22:00:26.727] Timer tick, asking Buildbucket for the build status
[22:00:26.837] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:00:26.837] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:13:0) after 1m0s
[22:01:26.980] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:13:0)
[22:01:26.980] Timer tick, asking Buildbucket for the build status
[22:01:27.093] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:01:27.093] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:14:0) after 1m0s
[22:02:27.098] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:14:0)
[22:02:27.098] Timer tick, asking Buildbucket for the build status
[22:02:27.165] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:02:27.165] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:15:0) after 1m0s
[22:03:27.189] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:15:0)
[22:03:27.189] Timer tick, asking Buildbucket for the build status
[22:03:27.410] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:03:27.411] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:16:0) after 1m0s
[22:04:27.431] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:16:0)
[22:04:27.431] Timer tick, asking Buildbucket for the build status
[22:04:27.501] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:04:27.501] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:17:0) after 1m0s
[22:05:27.503] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:17:0)
[22:05:27.503] Timer tick, asking Buildbucket for the build status
[22:05:27.584] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:05:27.584] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:18:0) after 1m0s
[22:06:27.610] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:18:0)
[22:06:27.610] Timer tick, asking Buildbucket for the build status
[22:06:27.704] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:06:27.704] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:19:0) after 1m0s
[22:07:27.766] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:19:0)
[22:07:27.766] Timer tick, asking Buildbucket for the build status
[22:07:27.837] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:07:27.837] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:20:0) after 1m0s
[22:08:27.892] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:20:0)
[22:08:27.892] Timer tick, asking Buildbucket for the build status
[22:08:27.953] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:08:27.953] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:21:0) after 1m0s
[22:09:28.034] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:21:0)
[22:09:28.034] Timer tick, asking Buildbucket for the build status
[22:09:28.115] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:09:28.115] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:22:0) after 1m0s
[22:10:28.116] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:22:0)
[22:10:28.116] Timer tick, asking Buildbucket for the build status
[22:10:28.176] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:10:28.176] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:23:0) after 1m0s
[22:11:28.203] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:23:0)
[22:11:28.203] Timer tick, asking Buildbucket for the build status
[22:11:28.363] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:11:28.363] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:24:0) after 1m0s
[22:12:28.438] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:24:0)
[22:12:28.438] Timer tick, asking Buildbucket for the build status
[22:12:28.699] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:12:28.699] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:25:0) after 1m0s
[22:13:28.688] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:25:0)
[22:13:28.688] Timer tick, asking Buildbucket for the build status
[22:13:28.749] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:13:28.749] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:26:0) after 1m0s
[22:14:28.754] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:26:0)
[22:14:28.754] Timer tick, asking Buildbucket for the build status
[22:14:28.807] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:14:28.807] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:27:0) after 1m0s
[22:15:28.829] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:27:0)
[22:15:28.829] Timer tick, asking Buildbucket for the build status
[22:15:28.913] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:15:28.913] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:28:0) after 1m0s
[22:16:28.903] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:28:0)
[22:16:28.903] Timer tick, asking Buildbucket for the build status
[22:16:28.969] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:16:28.969] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:29:0) after 1m0s
[22:17:28.974] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:29:0)
[22:17:28.974] Timer tick, asking Buildbucket for the build status
[22:17:29.031] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:17:29.031] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:30:0) after 1m0s
[22:18:29.050] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:30:0)
[22:18:29.050] Timer tick, asking Buildbucket for the build status
[22:18:29.193] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:18:29.193] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:31:0) after 1m0s
[22:19:29.198] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:31:0)
[22:19:29.198] Timer tick, asking Buildbucket for the build status
[22:19:29.251] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:19:29.251] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:32:0) after 1m0s
[22:20:29.288] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:32:0)
[22:20:29.288] Timer tick, asking Buildbucket for the build status
[22:20:29.441] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:29.441] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:33:0) after 1m0s
[22:21:29.420] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:33:0)
[22:21:29.420] Timer tick, asking Buildbucket for the build status
[22:21:29.488] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:29.488] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:34:0) after 1m0s
[22:22:29.529] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:34:0)
[22:22:29.529] Timer tick, asking Buildbucket for the build status
[22:22:29.670] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:29.670] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:35:0) after 1m0s
[22:23:29.703] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:35:0)
[22:23:29.703] Timer tick, asking Buildbucket for the build status
[22:23:29.766] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:29.766] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:36:0) after 1m0s
[22:24:29.787] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:36:0)
[22:24:29.787] Timer tick, asking Buildbucket for the build status
[22:24:29.921] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:29.921] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:37:0) after 1m0s
[22:25:29.899] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:37:0)
[22:25:29.899] Timer tick, asking Buildbucket for the build status
[22:25:29.961] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:29.961] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:38:0) after 1m0s
[22:26:29.970] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:38:0)
[22:26:29.970] Timer tick, asking Buildbucket for the build status
[22:26:30.148] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:30.148] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:39:0) after 1m0s
[22:27:30.143] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:39:0)
[22:27:30.143] Timer tick, asking Buildbucket for the build status
[22:27:30.289] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:30.289] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:40:0) after 1m0s
[22:28:30.313] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:40:0)
[22:28:30.313] Timer tick, asking Buildbucket for the build status
[22:28:30.363] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:28:30.363] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:41:0) after 1m0s
[22:29:30.384] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:41:0)
[22:29:30.384] Timer tick, asking Buildbucket for the build status
[22:29:30.478] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:30.478] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:42:0) after 1m0s
[22:30:30.456] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:42:0)
[22:30:30.456] Timer tick, asking Buildbucket for the build status
[22:30:30.558] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:30.558] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:43:0) after 1m0s
[22:31:30.571] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:43:0)
[22:31:30.571] Timer tick, asking Buildbucket for the build status
[22:31:30.624] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:30.624] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:44:0) after 1m0s
[22:32:30.653] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:44:0)
[22:32:30.653] Timer tick, asking Buildbucket for the build status
[22:32:30.787] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:30.787] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:45:0) after 1m0s
[22:33:30.793] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:45:0)
[22:33:30.793] Timer tick, asking Buildbucket for the build status
[22:33:30.970] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:30.970] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:46:0) after 1m0s
[22:34:30.971] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:46:0)
[22:34:30.971] Timer tick, asking Buildbucket for the build status
[22:34:31.034] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:31.034] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:47:0) after 1m0s
[22:35:31.078] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:47:0)
[22:35:31.078] Timer tick, asking Buildbucket for the build status
[22:35:31.153] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:35:31.153] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:48:0) after 1m0s
[22:36:31.168] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:48:0)
[22:36:31.168] Timer tick, asking Buildbucket for the build status
[22:36:31.242] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:36:31.242] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:49:0) after 1m0s
[22:37:31.215] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:49:0)
[22:37:31.215] Timer tick, asking Buildbucket for the build status
[22:37:31.323] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:31.323] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:50:0) after 1m0s
[22:38:31.306] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:50:0)
[22:38:31.306] Timer tick, asking Buildbucket for the build status
[22:38:31.367] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:38:31.367] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:51:0) after 1m0s
[22:39:31.344] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:51:0)
[22:39:31.344] Timer tick, asking Buildbucket for the build status
[22:39:31.530] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:31.530] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:52:0) after 1m0s
[22:40:31.547] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:52:0)
[22:40:31.547] Timer tick, asking Buildbucket for the build status
[22:40:31.761] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:31.761] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:53:0) after 1m0s
[22:41:31.747] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:53:0)
[22:41:31.747] Timer tick, asking Buildbucket for the build status
[22:41:31.840] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:31.840] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:54:0) after 1m0s
[22:42:31.860] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:54:0)
[22:42:31.860] Timer tick, asking Buildbucket for the build status
[22:42:31.980] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:31.980] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:55:0) after 1m0s
[22:43:31.979] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:55:0)
[22:43:31.979] Timer tick, asking Buildbucket for the build status
[22:43:32.052] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:32.052] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:56:0) after 1m0s
[22:44:32.076] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:56:0)
[22:44:32.076] Timer tick, asking Buildbucket for the build status
[22:44:32.134] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:32.134] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:57:0) after 1m0s
[22:45:32.152] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:57:0)
[22:45:32.153] Timer tick, asking Buildbucket for the build status
[22:45:32.275] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:32.275] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:58:0) after 1m0s
[22:46:32.354] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:58:0)
[22:46:32.354] Timer tick, asking Buildbucket for the build status
[22:46:32.406] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:32.406] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:59:0) after 1m0s
[22:47:32.427] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:59:0)
[22:47:32.427] Timer tick, asking Buildbucket for the build status
[22:47:32.520] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:32.520] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:60:0) after 1m0s
[22:48:32.562] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:60:0)
[22:48:32.562] Timer tick, asking Buildbucket for the build status
[22:48:32.634] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:32.634] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:61:0) after 1m0s
[22:49:32.650] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:61:0)
[22:49:32.650] Timer tick, asking Buildbucket for the build status
[22:49:32.823] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:32.823] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:62:0) after 1m0s
[22:50:32.816] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:62:0)
[22:50:32.816] Timer tick, asking Buildbucket for the build status
[22:50:32.881] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:32.881] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:63:0) after 1m0s
[22:51:32.901] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:63:0)
[22:51:32.901] Timer tick, asking Buildbucket for the build status
[22:51:32.958] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:32.958] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:64:0) after 1m0s
[22:52:32.979] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:64:0)
[22:52:32.980] Timer tick, asking Buildbucket for the build status
[22:52:33.072] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:33.072] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:65:0) after 1m0s
[22:53:33.068] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:65:0)
[22:53:33.068] Timer tick, asking Buildbucket for the build status
[22:53:33.125] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:33.125] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:66:0) after 1m0s
[22:54:33.148] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:66:0)
[22:54:33.148] Timer tick, asking Buildbucket for the build status
[22:54:33.205] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:33.205] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:67:0) after 1m0s
[22:55:33.176] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:67:0)
[22:55:33.176] Timer tick, asking Buildbucket for the build status
[22:55:33.245] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:33.245] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:68:0) after 1m0s
[22:56:33.240] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:68:0)
[22:56:33.240] Timer tick, asking Buildbucket for the build status
[22:56:33.310] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:33.310] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:69:0) after 1m0s
[22:57:33.334] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:69:0)
[22:57:33.334] Timer tick, asking Buildbucket for the build status
[22:57:33.545] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:33.545] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:70:0) after 1m0s
[22:58:33.552] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:70:0)
[22:58:33.552] Timer tick, asking Buildbucket for the build status
[22:58:33.612] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:33.612] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:71:0) after 1m0s
[22:59:33.604] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:71:0)
[22:59:33.604] Timer tick, asking Buildbucket for the build status
[22:59:33.710] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:33.710] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:72:0) after 1m0s
[23:00:33.704] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:72:0)
[23:00:33.704] Timer tick, asking Buildbucket for the build status
[23:00:33.875] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:33.875] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:73:0) after 1m0s
[23:01:33.853] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:73:0)
[23:01:33.853] Timer tick, asking Buildbucket for the build status
[23:01:33.947] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:33.947] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:74:0) after 1m0s
[23:02:33.966] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:74:0)
[23:02:33.966] Timer tick, asking Buildbucket for the build status
[23:02:34.090] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:34.090] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:75:0) after 1m0s
[23:03:34.102] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:75:0)
[23:03:34.102] Timer tick, asking Buildbucket for the build status
[23:03:34.164] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:34.164] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:76:0) after 1m0s
[23:04:34.154] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:76:0)
[23:04:34.154] Timer tick, asking Buildbucket for the build status
[23:04:34.212] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:34.212] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:77:0) after 1m0s
[23:05:34.238] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:77:0)
[23:05:34.238] Timer tick, asking Buildbucket for the build status
[23:05:34.310] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:34.310] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:78:0) after 1m0s
[23:06:34.321] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:78:0)
[23:06:34.321] Timer tick, asking Buildbucket for the build status
[23:06:34.388] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:06:34.388] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:79:0) after 1m0s
[23:07:34.369] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:79:0)
[23:07:34.369] Timer tick, asking Buildbucket for the build status
[23:07:34.486] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:07:34.486] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:80:0) after 1m0s
[23:08:34.551] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:80:0)
[23:08:34.551] Timer tick, asking Buildbucket for the build status
[23:08:34.728] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:34.728] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:81:0) after 1m0s
[23:09:34.751] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:81:0)
[23:09:34.751] Timer tick, asking Buildbucket for the build status
[23:09:34.895] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:34.895] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:82:0) after 1m0s
[23:10:34.896] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:82:0)
[23:10:34.896] Timer tick, asking Buildbucket for the build status
[23:10:34.958] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:10:34.958] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:83:0) after 1m0s
[23:11:34.977] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:83:0)
[23:11:34.977] Timer tick, asking Buildbucket for the build status
[23:11:35.084] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:11:35.084] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:84:0) after 1m0s
[23:12:35.113] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:84:0)
[23:12:35.113] Timer tick, asking Buildbucket for the build status
[23:12:35.188] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:35.188] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:85:0) after 1m0s
[23:13:35.218] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:85:0)
[23:13:35.218] Timer tick, asking Buildbucket for the build status
[23:13:35.563] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:35.563] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:86:0) after 1m0s
[23:14:35.541] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:86:0)
[23:14:35.541] Timer tick, asking Buildbucket for the build status
[23:14:35.617] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:35.617] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:87:0) after 1m0s
[23:15:35.638] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:87:0)
[23:15:35.638] Timer tick, asking Buildbucket for the build status
[23:15:35.690] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:35.690] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:88:0) after 1m0s
[23:16:35.749] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:88:0)
[23:16:35.749] Timer tick, asking Buildbucket for the build status
[23:16:35.874] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:35.874] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:89:0) after 1m0s
[23:17:35.870] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:89:0)
[23:17:35.870] Timer tick, asking Buildbucket for the build status
[23:17:35.950] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:35.950] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:90:0) after 1m0s
[23:18:35.972] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:90:0)
[23:18:35.972] Timer tick, asking Buildbucket for the build status
[23:18:36.021] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:36.021] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:91:0) after 1m0s
[23:19:36.049] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:91:0)
[23:19:36.049] Timer tick, asking Buildbucket for the build status
[23:19:36.108] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:36.108] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:92:0) after 1m0s
[23:20:36.099] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:92:0)
[23:20:36.099] Timer tick, asking Buildbucket for the build status
[23:20:36.339] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:20:36.339] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:93:0) after 1m0s
[23:21:36.360] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:93:0)
[23:21:36.360] Timer tick, asking Buildbucket for the build status
[23:21:36.423] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:21:36.423] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:94:0) after 1m0s
[23:22:36.449] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:94:0)
[23:22:36.449] Timer tick, asking Buildbucket for the build status
[23:22:36.654] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:22:36.654] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:95:0) after 1m0s
[23:23:36.663] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:95:0)
[23:23:36.663] Timer tick, asking Buildbucket for the build status
[23:23:36.735] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:36.735] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:96:0) after 1m0s
[23:24:36.731] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:96:0)
[23:24:36.731] Timer tick, asking Buildbucket for the build status
[23:24:36.809] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:24:36.809] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:97:0) after 1m0s
[23:25:36.829] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:97:0)
[23:25:36.829] Timer tick, asking Buildbucket for the build status
[23:25:37.423] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:37.423] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:98:0) after 1m0s
[23:26:37.440] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:98:0)
[23:26:37.440] Timer tick, asking Buildbucket for the build status
[23:26:37.518] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:37.518] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:99:0) after 1m0s
[23:27:37.503] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:99:0)
[23:27:37.503] Timer tick, asking Buildbucket for the build status
[23:27:37.559] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:37.559] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:100:0) after 1m0s
[23:28:37.588] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:100:0)
[23:28:37.588] Timer tick, asking Buildbucket for the build status
[23:28:37.648] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:37.648] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:101:0) after 1m0s
[23:29:37.671] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:101:0)
[23:29:37.671] Timer tick, asking Buildbucket for the build status
[23:29:37.803] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:37.803] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:102:0) after 1m0s
[23:30:37.852] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:102:0)
[23:30:37.852] Timer tick, asking Buildbucket for the build status
[23:30:37.904] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:37.904] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:103:0) after 1m0s
[23:31:37.893] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:103:0)
[23:31:37.893] Timer tick, asking Buildbucket for the build status
[23:31:38.059] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:38.059] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:104:0) after 1m0s
[23:32:38.084] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:104:0)
[23:32:38.084] Timer tick, asking Buildbucket for the build status
[23:32:38.236] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:38.236] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:105:0) after 1m0s
[23:33:38.264] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:105:0)
[23:33:38.264] Timer tick, asking Buildbucket for the build status
[23:33:38.371] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:38.371] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:106:0) after 1m0s
[23:34:38.392] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:106:0)
[23:34:38.392] Timer tick, asking Buildbucket for the build status
[23:34:38.442] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:34:38.442] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:107:0) after 1m0s
[23:35:38.463] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:107:0)
[23:35:38.463] Timer tick, asking Buildbucket for the build status
[23:35:38.514] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:38.514] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:108:0) after 1m0s
[23:36:38.546] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:108:0)
[23:36:38.546] Timer tick, asking Buildbucket for the build status
[23:36:38.599] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:38.599] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:109:0) after 1m0s
[23:37:38.605] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:109:0)
[23:37:38.605] Timer tick, asking Buildbucket for the build status
[23:37:38.687] Build 8918612059178515600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:38.687] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:110:0) after 1m0s
[23:38:12.553] Received PubSub notification, asking Buildbucket for the build status
[23:38:12.607] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:38.717] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:110:0)
[23:38:38.717] Timer tick, asking Buildbucket for the build status
[23:38:38.895] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:38.895] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:112:0) after 1m0s
[23:39:38.915] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:112:0)
[23:39:38.915] Timer tick, asking Buildbucket for the build status
[23:39:38.993] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:38.993] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:113:0) after 1m0s
[23:40:39.003] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:113:0)
[23:40:39.003] Timer tick, asking Buildbucket for the build status
[23:40:39.095] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:39.095] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:114:0) after 1m0s
[23:41:39.119] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:114:0)
[23:41:39.119] Timer tick, asking Buildbucket for the build status
[23:41:39.213] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:39.213] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:115:0) after 1m0s
[23:42:39.208] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:115:0)
[23:42:39.208] Timer tick, asking Buildbucket for the build status
[23:42:39.322] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:39.322] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:116:0) after 1m0s
[23:43:39.377] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:116:0)
[23:43:39.377] Timer tick, asking Buildbucket for the build status
[23:43:39.456] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:39.456] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:117:0) after 1m0s
[23:44:39.437] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:117:0)
[23:44:39.437] Timer tick, asking Buildbucket for the build status
[23:44:39.532] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:39.532] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:118:0) after 1m0s
[23:45:39.533] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:118:0)
[23:45:39.533] Timer tick, asking Buildbucket for the build status
[23:45:39.569] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:39.569] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:119:0) after 1m0s
[23:46:39.588] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:119:0)
[23:46:39.588] Timer tick, asking Buildbucket for the build status
[23:46:39.673] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:39.673] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:120:0) after 1m0s
[23:47:39.692] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:120:0)
[23:47:39.692] Timer tick, asking Buildbucket for the build status
[23:47:39.769] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:39.769] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:121:0) after 1m0s
[23:48:39.791] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:121:0)
[23:48:39.791] Timer tick, asking Buildbucket for the build status
[23:48:39.886] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:39.886] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:122:0) after 1m0s
[23:49:39.902] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:122:0)
[23:49:39.902] Timer tick, asking Buildbucket for the build status
[23:49:39.945] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:39.945] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:123:0) after 1m0s
[23:50:39.968] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:123:0)
[23:50:39.968] Timer tick, asking Buildbucket for the build status
[23:50:40.047] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:40.047] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:124:0) after 1m0s
[23:51:40.033] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:124:0)
[23:51:40.033] Timer tick, asking Buildbucket for the build status
[23:51:40.078] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:40.078] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:125:0) after 1m0s
[23:52:40.085] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:125:0)
[23:52:40.085] Timer tick, asking Buildbucket for the build status
[23:52:40.133] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:40.133] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:126:0) after 1m0s
[23:53:40.106] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:126:0)
[23:53:40.106] Timer tick, asking Buildbucket for the build status
[23:53:40.156] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:40.156] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:127:0) after 1m0s
[23:54:40.206] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:127:0)
[23:54:40.206] Timer tick, asking Buildbucket for the build status
[23:54:40.299] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:40.299] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:128:0) after 1m0s
[23:55:40.326] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:128:0)
[23:55:40.326] Timer tick, asking Buildbucket for the build status
[23:55:40.413] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:40.413] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:129:0) after 1m0s
[23:56:40.410] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:129:0)
[23:56:40.410] Timer tick, asking Buildbucket for the build status
[23:56:40.446] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:40.446] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:130:0) after 1m0s
[23:57:40.476] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:130:0)
[23:57:40.476] Timer tick, asking Buildbucket for the build status
[23:57:40.542] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:40.542] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:131:0) after 1m0s
[23:58:40.581] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:131:0)
[23:58:40.581] Timer tick, asking Buildbucket for the build status
[23:58:40.729] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:40.729] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:132:0) after 1m0s
[23:59:40.749] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:132:0)
[23:59:40.749] Timer tick, asking Buildbucket for the build status
[23:59:40.936] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:40.936] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:133:0) after 1m0s
[00:00:41.024] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:133:0)
[00:00:41.024] Timer tick, asking Buildbucket for the build status
[00:00:41.258] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:41.258] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:134:0) after 1m0s
[00:01:41.285] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:134:0)
[00:01:41.285] Timer tick, asking Buildbucket for the build status
[00:01:41.664] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:41.664] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:135:0) after 1m0s
[00:02:41.721] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:135:0)
[00:02:41.721] Timer tick, asking Buildbucket for the build status
[00:02:41.898] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:41.898] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:136:0) after 1m0s
[00:03:41.901] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:136:0)
[00:03:41.901] Timer tick, asking Buildbucket for the build status
[00:03:42.061] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:42.061] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:137:0) after 1m0s
[00:04:42.090] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:137:0)
[00:04:42.090] Timer tick, asking Buildbucket for the build status
[00:04:42.312] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:42.312] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:138:0) after 1m0s
[00:05:42.302] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:138:0)
[00:05:42.302] Timer tick, asking Buildbucket for the build status
[00:05:42.423] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:42.423] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:139:0) after 1m0s
[00:06:42.423] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:139:0)
[00:06:42.423] Timer tick, asking Buildbucket for the build status
[00:06:42.591] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:42.591] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:140:0) after 1m0s
[00:07:42.618] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:140:0)
[00:07:42.618] Timer tick, asking Buildbucket for the build status
[00:07:42.707] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:42.707] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:141:0) after 1m0s
[00:08:42.730] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:141:0)
[00:08:42.730] Timer tick, asking Buildbucket for the build status
[00:08:42.837] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:42.837] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:142:0) after 1m0s
[00:09:42.858] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:142:0)
[00:09:42.858] Timer tick, asking Buildbucket for the build status
[00:09:42.968] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:42.968] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:143:0) after 1m0s
[00:10:43.004] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:143:0)
[00:10:43.004] Timer tick, asking Buildbucket for the build status
[00:10:43.086] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:43.086] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:144:0) after 1m0s
[00:11:43.114] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:144:0)
[00:11:43.114] Timer tick, asking Buildbucket for the build status
[00:11:43.157] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:43.157] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:145:0) after 1m0s
[00:12:43.161] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:145:0)
[00:12:43.161] Timer tick, asking Buildbucket for the build status
[00:12:43.212] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:43.212] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:146:0) after 1m0s
[00:13:43.241] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:146:0)
[00:13:43.241] Timer tick, asking Buildbucket for the build status
[00:13:43.478] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:43.478] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:147:0) after 1m0s
[00:14:43.502] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:147:0)
[00:14:43.502] Timer tick, asking Buildbucket for the build status
[00:14:43.552] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:43.552] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:148:0) after 1m0s
[00:15:43.577] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:148:0)
[00:15:43.577] Timer tick, asking Buildbucket for the build status
[00:15:43.620] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:43.620] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:149:0) after 1m0s
[00:16:43.684] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:149:0)
[00:16:43.684] Timer tick, asking Buildbucket for the build status
[00:16:43.772] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:43.772] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:150:0) after 1m0s
[00:17:43.749] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:150:0)
[00:17:43.749] Timer tick, asking Buildbucket for the build status
[00:17:43.791] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:43.791] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:151:0) after 1m0s
[00:18:43.808] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:151:0)
[00:18:43.808] Timer tick, asking Buildbucket for the build status
[00:18:43.856] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:43.857] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:152:0) after 1m0s
[00:19:43.835] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:152:0)
[00:19:43.835] Timer tick, asking Buildbucket for the build status
[00:19:43.885] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:43.885] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:153:0) after 1m0s
[00:20:43.909] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:153:0)
[00:20:43.909] Timer tick, asking Buildbucket for the build status
[00:20:43.956] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:43.956] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:154:0) after 1m0s
[00:21:43.994] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:154:0)
[00:21:43.994] Timer tick, asking Buildbucket for the build status
[00:21:44.114] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:44.114] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:155:0) after 1m0s
[00:22:44.161] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:155:0)
[00:22:44.161] Timer tick, asking Buildbucket for the build status
[00:22:44.218] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:44.218] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:156:0) after 1m0s
[00:23:44.262] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:156:0)
[00:23:44.262] Timer tick, asking Buildbucket for the build status
[00:23:44.326] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:44.326] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:157:0) after 1m0s
[00:24:44.351] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:157:0)
[00:24:44.351] Timer tick, asking Buildbucket for the build status
[00:24:44.502] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:44.502] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:158:0) after 1m0s
[00:25:44.527] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:158:0)
[00:25:44.527] Timer tick, asking Buildbucket for the build status
[00:25:44.591] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:44.591] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:159:0) after 1m0s
[00:26:44.590] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:159:0)
[00:26:44.591] Timer tick, asking Buildbucket for the build status
[00:26:44.643] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:44.643] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:160:0) after 1m0s
[00:27:44.681] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:160:0)
[00:27:44.681] Timer tick, asking Buildbucket for the build status
[00:27:44.771] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:44.771] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:161:0) after 1m0s
[00:28:44.794] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:161:0)
[00:28:44.794] Timer tick, asking Buildbucket for the build status
[00:28:44.856] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:44.856] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:162:0) after 1m0s
[00:29:44.963] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:162:0)
[00:29:44.963] Timer tick, asking Buildbucket for the build status
[00:29:45.217] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:45.217] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:163:0) after 1m0s
[00:30:45.259] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:163:0)
[00:30:45.259] Timer tick, asking Buildbucket for the build status
[00:30:45.318] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:45.318] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:164:0) after 1m0s
[00:31:45.345] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:164:0)
[00:31:45.345] Timer tick, asking Buildbucket for the build status
[00:31:45.420] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:45.420] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:165:0) after 1m0s
[00:32:45.441] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:165:0)
[00:32:45.441] Timer tick, asking Buildbucket for the build status
[00:32:45.501] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:45.501] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:166:0) after 1m0s
[00:33:45.506] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:166:0)
[00:33:45.506] Timer tick, asking Buildbucket for the build status
[00:33:45.594] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:45.594] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:167:0) after 1m0s
[00:34:45.587] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:167:0)
[00:34:45.587] Timer tick, asking Buildbucket for the build status
[00:34:45.627] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:45.627] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:168:0) after 1m0s
[00:35:45.665] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:168:0)
[00:35:45.665] Timer tick, asking Buildbucket for the build status
[00:35:45.707] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:45.707] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:169:0) after 1m0s
[00:36:45.700] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:169:0)
[00:36:45.700] Timer tick, asking Buildbucket for the build status
[00:36:45.874] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:45.874] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:170:0) after 1m0s
[00:37:45.905] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:170:0)
[00:37:45.905] Timer tick, asking Buildbucket for the build status
[00:37:46.078] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:46.078] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:171:0) after 1m0s
[00:38:46.072] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:171:0)
[00:38:46.072] Timer tick, asking Buildbucket for the build status
[00:38:46.178] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:46.178] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:172:0) after 1m0s
[00:39:46.203] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:172:0)
[00:39:46.203] Timer tick, asking Buildbucket for the build status
[00:39:46.240] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:46.240] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:173:0) after 1m0s
[00:40:46.251] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:173:0)
[00:40:46.251] Timer tick, asking Buildbucket for the build status
[00:40:46.329] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:46.329] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:174:0) after 1m0s
[00:41:46.329] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:174:0)
[00:41:46.329] Timer tick, asking Buildbucket for the build status
[00:41:46.404] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:46.404] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:175:0) after 1m0s
[00:42:46.428] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:175:0)
[00:42:46.428] Timer tick, asking Buildbucket for the build status
[00:42:46.537] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:46.537] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:176:0) after 1m0s
[00:43:46.528] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:176:0)
[00:43:46.528] Timer tick, asking Buildbucket for the build status
[00:43:46.666] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:46.666] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:177:0) after 1m0s
[00:44:46.661] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:177:0)
[00:44:46.661] Timer tick, asking Buildbucket for the build status
[00:44:46.859] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:46.859] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:178:0) after 1m0s
[00:45:46.939] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:178:0)
[00:45:46.939] Timer tick, asking Buildbucket for the build status
[00:45:46.979] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:46.979] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:179:0) after 1m0s
[00:46:47.027] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:179:0)
[00:46:47.027] Timer tick, asking Buildbucket for the build status
[00:46:47.087] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:47.087] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:180:0) after 1m0s
[00:47:47.076] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:180:0)
[00:47:47.076] Timer tick, asking Buildbucket for the build status
[00:47:47.142] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:47.142] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:181:0) after 1m0s
[00:48:47.129] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:181:0)
[00:48:47.129] Timer tick, asking Buildbucket for the build status
[00:48:47.180] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:47.180] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:182:0) after 1m0s
[00:49:47.209] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:182:0)
[00:49:47.209] Timer tick, asking Buildbucket for the build status
[00:49:47.269] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:47.269] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:183:0) after 1m0s
[00:50:47.285] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:183:0)
[00:50:47.285] Timer tick, asking Buildbucket for the build status
[00:50:47.337] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:47.337] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:184:0) after 1m0s
[00:51:47.355] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:184:0)
[00:51:47.355] Timer tick, asking Buildbucket for the build status
[00:51:47.407] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:47.407] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:185:0) after 1m0s
[00:52:47.431] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:185:0)
[00:52:47.431] Timer tick, asking Buildbucket for the build status
[00:52:47.491] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:47.491] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:186:0) after 1m0s
[00:53:47.507] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:186:0)
[00:53:47.507] Timer tick, asking Buildbucket for the build status
[00:53:47.569] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:47.569] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:187:0) after 1m0s
[00:54:47.575] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:187:0)
[00:54:47.575] Timer tick, asking Buildbucket for the build status
[00:54:47.678] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:47.678] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:188:0) after 1m0s
[00:55:48.404] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:188:0)
[00:55:48.404] Timer tick, asking Buildbucket for the build status
[00:55:48.498] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:48.498] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:189:0) after 1m0s
[00:56:48.491] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:189:0)
[00:56:48.491] Timer tick, asking Buildbucket for the build status
[00:56:48.564] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:48.564] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:190:0) after 1m0s
[00:57:48.584] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:190:0)
[00:57:48.584] Timer tick, asking Buildbucket for the build status
[00:57:48.643] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:48.643] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:191:0) after 1m0s
[00:58:48.658] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:191:0)
[00:58:48.658] Timer tick, asking Buildbucket for the build status
[00:58:48.741] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:48.741] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:192:0) after 1m0s
[00:59:48.770] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:192:0)
[00:59:48.770] Timer tick, asking Buildbucket for the build status
[00:59:48.832] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:48.832] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:193:0) after 1m0s
[01:00:48.860] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:193:0)
[01:00:48.860] Timer tick, asking Buildbucket for the build status
[01:00:48.898] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:48.898] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:194:0) after 1m0s
[01:01:48.897] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:194:0)
[01:01:48.897] Timer tick, asking Buildbucket for the build status
[01:01:49.074] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:49.074] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:195:0) after 1m0s
[01:02:49.107] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:195:0)
[01:02:49.107] Timer tick, asking Buildbucket for the build status
[01:02:49.178] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:49.178] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:196:0) after 1m0s
[01:03:49.252] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:196:0)
[01:03:49.252] Timer tick, asking Buildbucket for the build status
[01:03:49.398] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:49.398] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:197:0) after 1m0s
[01:04:49.444] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:197:0)
[01:04:49.444] Timer tick, asking Buildbucket for the build status
[01:04:49.487] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:49.487] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:198:0) after 1m0s
[01:05:50.146] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:198:0)
[01:05:50.146] Timer tick, asking Buildbucket for the build status
[01:05:50.218] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:50.218] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:199:0) after 1m0s
[01:06:50.252] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:199:0)
[01:06:50.252] Timer tick, asking Buildbucket for the build status
[01:06:50.318] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:50.318] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:200:0) after 1m0s
[01:07:50.299] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:200:0)
[01:07:50.299] Timer tick, asking Buildbucket for the build status
[01:07:50.471] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:50.471] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:201:0) after 1m0s
[01:08:50.465] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:201:0)
[01:08:50.465] Timer tick, asking Buildbucket for the build status
[01:08:50.582] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:50.582] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:202:0) after 1m0s
[01:09:50.607] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:202:0)
[01:09:50.607] Timer tick, asking Buildbucket for the build status
[01:09:50.672] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:50.672] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:203:0) after 1m0s
[01:10:50.696] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:203:0)
[01:10:50.696] Timer tick, asking Buildbucket for the build status
[01:10:50.753] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:50.753] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:204:0) after 1m0s
[01:11:50.795] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:204:0)
[01:11:50.795] Timer tick, asking Buildbucket for the build status
[01:11:50.862] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:50.862] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:205:0) after 1m0s
[01:12:50.839] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:205:0)
[01:12:50.839] Timer tick, asking Buildbucket for the build status
[01:12:50.888] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:50.888] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:206:0) after 1m0s
[01:13:50.931] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:206:0)
[01:13:50.931] Timer tick, asking Buildbucket for the build status
[01:13:50.992] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:50.992] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:207:0) after 1m0s
[01:14:51.004] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:207:0)
[01:14:51.004] Timer tick, asking Buildbucket for the build status
[01:14:51.175] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:51.175] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:208:0) after 1m0s
[01:15:51.252] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:208:0)
[01:15:51.252] Timer tick, asking Buildbucket for the build status
[01:15:51.411] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:51.411] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:209:0) after 1m0s
[01:16:51.437] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:209:0)
[01:16:51.437] Timer tick, asking Buildbucket for the build status
[01:16:51.499] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:51.499] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:210:0) after 1m0s
[01:17:51.507] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:210:0)
[01:17:51.507] Timer tick, asking Buildbucket for the build status
[01:17:51.673] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:51.673] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:211:0) after 1m0s
[01:18:51.701] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:211:0)
[01:18:51.701] Timer tick, asking Buildbucket for the build status
[01:18:52.435] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:52.435] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:212:0) after 1m0s
[01:19:52.528] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:212:0)
[01:19:52.528] Timer tick, asking Buildbucket for the build status
[01:19:52.588] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:52.588] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:213:0) after 1m0s
[01:20:52.609] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:213:0)
[01:20:52.609] Timer tick, asking Buildbucket for the build status
[01:20:52.688] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:52.688] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:214:0) after 1m0s
[01:21:52.696] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:214:0)
[01:21:52.696] Timer tick, asking Buildbucket for the build status
[01:21:52.769] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:52.769] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:215:0) after 1m0s
[01:22:52.787] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:215:0)
[01:22:52.787] Timer tick, asking Buildbucket for the build status
[01:22:52.872] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:52.872] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:216:0) after 1m0s
[01:23:52.909] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:216:0)
[01:23:52.909] Timer tick, asking Buildbucket for the build status
[01:23:52.973] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:52.973] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:217:0) after 1m0s
[01:24:52.985] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:217:0)
[01:24:52.985] Timer tick, asking Buildbucket for the build status
[01:24:53.083] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:53.083] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:218:0) after 1m0s
[01:25:53.116] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:218:0)
[01:25:53.116] Timer tick, asking Buildbucket for the build status
[01:25:53.189] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:53.189] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:219:0) after 1m0s
[01:26:53.210] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:219:0)
[01:26:53.210] Timer tick, asking Buildbucket for the build status
[01:26:53.250] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:53.250] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:220:0) after 1m0s
[01:27:53.244] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:220:0)
[01:27:53.244] Timer tick, asking Buildbucket for the build status
[01:27:53.290] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:53.290] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:221:0) after 1m0s
[01:28:53.288] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:221:0)
[01:28:53.288] Timer tick, asking Buildbucket for the build status
[01:28:53.354] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:53.355] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:222:0) after 1m0s
[01:29:53.353] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:222:0)
[01:29:53.353] Timer tick, asking Buildbucket for the build status
[01:29:53.400] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:53.400] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:223:0) after 1m0s
[01:30:53.386] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:223:0)
[01:30:53.386] Timer tick, asking Buildbucket for the build status
[01:30:53.460] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:53.460] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:224:0) after 1m0s
[01:31:53.484] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:224:0)
[01:31:53.484] Timer tick, asking Buildbucket for the build status
[01:31:54.925] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:54.926] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:225:0) after 1m0s
[01:32:54.926] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:225:0)
[01:32:54.926] Timer tick, asking Buildbucket for the build status
[01:32:54.988] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:54.988] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:226:0) after 1m0s
[01:33:55.012] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:226:0)
[01:33:55.012] Timer tick, asking Buildbucket for the build status
[01:33:55.055] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:55.055] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:227:0) after 1m0s
[01:34:55.082] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:227:0)
[01:34:55.082] Timer tick, asking Buildbucket for the build status
[01:34:55.166] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:55.166] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:228:0) after 1m0s
[01:35:55.141] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:228:0)
[01:35:55.141] Timer tick, asking Buildbucket for the build status
[01:35:55.180] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:55.180] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:229:0) after 1m0s
[01:36:55.190] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:229:0)
[01:36:55.190] Timer tick, asking Buildbucket for the build status
[01:36:55.316] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:55.316] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:230:0) after 1m0s
[01:37:55.350] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:230:0)
[01:37:55.350] Timer tick, asking Buildbucket for the build status
[01:37:55.488] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:55.488] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:231:0) after 1m0s
[01:38:55.528] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:231:0)
[01:38:55.528] Timer tick, asking Buildbucket for the build status
[01:38:55.579] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:55.579] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:232:0) after 1m0s
[01:39:55.578] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:232:0)
[01:39:55.578] Timer tick, asking Buildbucket for the build status
[01:39:55.624] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:55.624] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:233:0) after 1m0s
[01:40:55.652] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:233:0)
[01:40:55.652] Timer tick, asking Buildbucket for the build status
[01:40:55.760] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:55.760] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:234:0) after 1m0s
[01:41:55.743] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:234:0)
[01:41:55.743] Timer tick, asking Buildbucket for the build status
[01:41:55.800] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:55.800] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:235:0) after 1m0s
[01:42:55.824] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:235:0)
[01:42:55.824] Timer tick, asking Buildbucket for the build status
[01:42:55.885] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:55.885] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:236:0) after 1m0s
[01:43:55.897] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:236:0)
[01:43:55.897] Timer tick, asking Buildbucket for the build status
[01:43:55.958] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:55.958] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:237:0) after 1m0s
[01:44:55.974] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:237:0)
[01:44:55.974] Timer tick, asking Buildbucket for the build status
[01:44:56.039] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:56.039] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:238:0) after 1m0s
[01:45:56.065] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:238:0)
[01:45:56.065] Timer tick, asking Buildbucket for the build status
[01:45:56.109] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:56.109] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:239:0) after 1m0s
[01:46:56.094] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:239:0)
[01:46:56.094] Timer tick, asking Buildbucket for the build status
[01:46:56.135] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:56.135] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:240:0) after 1m0s
[01:47:56.189] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:240:0)
[01:47:56.189] Timer tick, asking Buildbucket for the build status
[01:47:56.237] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:56.237] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:241:0) after 1m0s
[01:48:56.253] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:241:0)
[01:48:56.253] Timer tick, asking Buildbucket for the build status
[01:48:56.354] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:56.354] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:242:0) after 1m0s
[01:49:56.360] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:242:0)
[01:49:56.360] Timer tick, asking Buildbucket for the build status
[01:49:56.403] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:56.403] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:243:0) after 1m0s
[01:50:56.411] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:243:0)
[01:50:56.411] Timer tick, asking Buildbucket for the build status
[01:50:56.546] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:56.546] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:244:0) after 1m0s
[01:51:56.546] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:244:0)
[01:51:56.546] Timer tick, asking Buildbucket for the build status
[01:51:56.590] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:56.590] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:245:0) after 1m0s
[01:52:56.575] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:245:0)
[01:52:56.575] Timer tick, asking Buildbucket for the build status
[01:52:56.646] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:56.646] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:246:0) after 1m0s
[01:53:56.727] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:246:0)
[01:53:56.727] Timer tick, asking Buildbucket for the build status
[01:53:56.816] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:56.816] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:247:0) after 1m0s
[01:54:56.842] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:247:0)
[01:54:56.842] Timer tick, asking Buildbucket for the build status
[01:54:56.899] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:56.899] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:248:0) after 1m0s
[01:55:56.923] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:248:0)
[01:55:56.924] Timer tick, asking Buildbucket for the build status
[01:55:57.088] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:57.088] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:249:0) after 1m0s
[01:56:57.116] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:249:0)
[01:56:57.116] Timer tick, asking Buildbucket for the build status
[01:56:57.198] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:57.198] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:250:0) after 1m0s
[01:57:57.218] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:250:0)
[01:57:57.218] Timer tick, asking Buildbucket for the build status
[01:57:57.331] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:57.331] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:251:0) after 1m0s
[01:58:57.338] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:251:0)
[01:58:57.338] Timer tick, asking Buildbucket for the build status
[01:58:57.376] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:57.376] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:252:0) after 1m0s
[01:59:57.406] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:252:0)
[01:59:57.406] Timer tick, asking Buildbucket for the build status
[01:59:57.459] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:57.459] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:253:0) after 1m0s
[02:00:57.483] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:253:0)
[02:00:57.483] Timer tick, asking Buildbucket for the build status
[02:00:57.523] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:57.523] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:254:0) after 1m0s
[02:01:57.551] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:254:0)
[02:01:57.551] Timer tick, asking Buildbucket for the build status
[02:01:58.289] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:58.289] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:255:0) after 1m0s
[02:02:58.273] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:255:0)
[02:02:58.273] Timer tick, asking Buildbucket for the build status
[02:02:58.400] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:58.400] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:256:0) after 1m0s
[02:03:58.397] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:256:0)
[02:03:58.397] Timer tick, asking Buildbucket for the build status
[02:03:58.487] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:58.487] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:257:0) after 1m0s
[02:04:58.499] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:257:0)
[02:04:58.499] Timer tick, asking Buildbucket for the build status
[02:04:58.558] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:58.558] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:258:0) after 1m0s
[02:05:58.570] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:258:0)
[02:05:58.570] Timer tick, asking Buildbucket for the build status
[02:05:58.652] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:58.652] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:259:0) after 1m0s
[02:06:58.665] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:259:0)
[02:06:58.665] Timer tick, asking Buildbucket for the build status
[02:06:58.766] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:58.766] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:260:0) after 1m0s
[02:07:58.788] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:260:0)
[02:07:58.788] Timer tick, asking Buildbucket for the build status
[02:07:59.060] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:59.060] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:261:0) after 1m0s
[02:08:59.045] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:261:0)
[02:08:59.045] Timer tick, asking Buildbucket for the build status
[02:08:59.084] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:59.084] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:262:0) after 1m0s
[02:09:59.116] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:262:0)
[02:09:59.116] Timer tick, asking Buildbucket for the build status
[02:09:59.154] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:59.154] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:263:0) after 1m0s
[02:10:59.202] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:263:0)
[02:10:59.202] Timer tick, asking Buildbucket for the build status
[02:10:59.284] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:59.284] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:264:0) after 1m0s
[02:11:59.295] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:264:0)
[02:11:59.296] Timer tick, asking Buildbucket for the build status
[02:11:59.484] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:59.484] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:265:0) after 1m0s
[02:12:59.580] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:265:0)
[02:12:59.580] Timer tick, asking Buildbucket for the build status
[02:12:59.660] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:59.660] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:266:0) after 1m0s
[02:13:59.696] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:266:0)
[02:13:59.696] Timer tick, asking Buildbucket for the build status
[02:13:59.745] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:59.745] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:267:0) after 1m0s
[02:14:59.772] Handling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:267:0)
[02:14:59.772] Timer tick, asking Buildbucket for the build status
[02:14:59.854] Build 8918612059178515600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:59.854] Scheduling timer "check-buildbucket-build-status" (nacl/linux-pnacl-x86_64-tests-arm:9084042121789426048:268:0) after 1m0s
[02:15:17.843] Received PubSub notification, asking Buildbucket for the build status
[02:15:17.892] Build 8918612059178515600: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[02:15:17.892] Buildbucket build:
{
"build": {
"bucket": "luci.nacl.toolchain",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1552961716896724",
"created_by": "project:nacl",
"created_ts": "1552945763378283",
"failure_reason": "BUILD_FAILURE",
"id": "8918612059178515600",
"parameters_json": "{\"builder_name\": \"linux-pnacl-x86_64-tests-arm\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"revision\": \"7d24fb15d81f95ed30dde0c04b68584749709657\"}}",
"project": "nacl",
"result": "FAILURE",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"4201-b04f7f8\"], \"machine_type\": [\"n1-standard-16\"], \"gpu\": [\"none\"], \"cores\": [\"16\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-16-145e5bab-us-west1-c-141h\"], \"pool\": [\"luci.nacl.toolchain\"], \"caches\": [\"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"Failure annotated steps\\nFailure torture_tests_clang arm\\nFailure llvm-test-suite arm --opt O3f --opt O2b\"}, \"properties\": {\"slavetype\": \"BuilderTester\", \"buildnumber\": 4703, \"repository\": \"https://chromium.googlesource.com/native_client/src/native_client.git\", \"buildername\": \"linux-pnacl-x86_64-tests-arm\", \"got_revision\": \"7d24fb15d81f95ed30dde0c04b68584749709657\", \"recipe\": \"nacl\", \"mastername\": \"client.nacl.toolchain\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"7d24fb15d81f95ed30dde0c04b68584749709657\", \"bot_id\": \"gce-trusty-16-145e5bab-us-west1-c-141h\"}}",
"service_account": "nacl-toolchain-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1552952292016319",
"status": "COMPLETED",
"status_changed_ts": "1552961717599812",
"tags": [
"build_address:luci.nacl.toolchain/linux-pnacl-x86_64-tests-arm/4703",
"builder:linux-pnacl-x86_64-tests-arm",
"buildset:commit/git/7d24fb15d81f95ed30dde0c04b68584749709657",
"buildset:commit/gitiles/chromium.googlesource.com/native_client/src/native_client/+/7d24fb15d81f95ed30dde0c04b68584749709657",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084042121789426048",
"scheduler_job_id:nacl/linux-pnacl-x86_64-tests-arm",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/nacl/buildbucket/cr-buildbucket.appspot.com/8918612059178515600/+/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:43ab995daa45d810",
"user_agent:luci-scheduler"
],
"updated_ts": "1552961717603560",
"url": "https://ci.chromium.org/b/8918612059178515600",
"utcnow_ts": "1552961717881545"
}
}
[02:15:17.892] Invocation finished in 4h25m56.387904288s with status FAILED