[09:11:16.180] New invocation is queued and will start shortly
[09:11:17.291] Starting the invocation (attempt 1)
[09:11:17.330] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:11:17.330] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:11:17.330] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9072402807959774416",
"parameters_json": "{\"builder_name\":\"ToTiOS\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"39deed19b11d75b713ba8dac1c9fa6d4b323cca8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTiOS",
"scheduler_invocation_id:9072402807959774416",
"scheduler_job_id:chromium/ToTiOS",
"user_agent:luci-scheduler",
"buildset:commit/git/39deed19b11d75b713ba8dac1c9fa6d4b323cca8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/39deed19b11d75b713ba8dac1c9fa6d4b323cca8",
"gitiles_ref:refs/heads/master"
]
}
[09:11:18.578] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1564045877528332",
"id": "8906972745883662944",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"39deed19b11d75b713ba8dac1c9fa6d4b323cca8\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1564045878322866",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/8786",
"builder:ToTiOS",
"buildset:commit/git/39deed19b11d75b713ba8dac1c9fa6d4b323cca8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/39deed19b11d75b713ba8dac1c9fa6d4b323cca8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9072402807959774416",
"scheduler_job_id:chromium/ToTiOS",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8906972745883662944/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:ios/unified_builder_tester",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1564045878322999",
"url": "https://ci.chromium.org/b/8906972745883662944",
"utcnow_ts": "1564045878563829"
}
}
[09:11:18.578] Task URL: https://ci.chromium.org/b/8906972745883662944
[09:11:18.578] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:2:0) after 1m0s
[09:11:22.538] Received PubSub notification, asking Buildbucket for the build status
[09:11:22.723] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:18.654] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:2:0)
[09:12:18.654] Timer tick, asking Buildbucket for the build status
[09:12:18.971] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:18.971] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:4:0) after 1m0s
[09:13:18.969] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:4:0)
[09:13:18.969] Timer tick, asking Buildbucket for the build status
[09:13:19.432] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:19.432] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:5:0) after 1m0s
[09:14:19.553] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:5:0)
[09:14:19.553] Timer tick, asking Buildbucket for the build status
[09:14:22.350] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:22.350] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:6:0) after 1m0s
[09:15:22.435] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:6:0)
[09:15:22.435] Timer tick, asking Buildbucket for the build status
[09:15:22.707] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:22.707] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:7:0) after 1m0s
[09:16:22.866] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:7:0)
[09:16:22.866] Timer tick, asking Buildbucket for the build status
[09:16:23.815] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:23.815] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:8:0) after 1m0s
[09:17:23.844] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:8:0)
[09:17:23.844] Timer tick, asking Buildbucket for the build status
[09:17:24.207] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:24.207] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:9:0) after 1m0s
[09:18:24.272] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:9:0)
[09:18:24.272] Timer tick, asking Buildbucket for the build status
[09:18:24.509] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:24.509] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:10:0) after 1m0s
[09:19:24.594] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:10:0)
[09:19:24.594] Timer tick, asking Buildbucket for the build status
[09:19:24.773] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:24.773] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:11:0) after 1m0s
[09:20:24.897] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:11:0)
[09:20:24.897] Timer tick, asking Buildbucket for the build status
[09:20:25.609] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:25.609] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:12:0) after 1m0s
[09:21:25.665] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:12:0)
[09:21:25.665] Timer tick, asking Buildbucket for the build status
[09:21:26.106] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:26.106] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:13:0) after 1m0s
[09:22:26.334] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:13:0)
[09:22:26.334] Timer tick, asking Buildbucket for the build status
[09:22:26.811] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:26.811] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:14:0) after 1m0s
[09:23:26.961] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:14:0)
[09:23:26.961] Timer tick, asking Buildbucket for the build status
[09:23:27.607] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:27.607] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:15:0) after 1m0s
[09:24:27.608] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:15:0)
[09:24:27.608] Timer tick, asking Buildbucket for the build status
[09:24:28.242] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:28.242] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:16:0) after 1m0s
[09:25:28.264] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:16:0)
[09:25:28.264] Timer tick, asking Buildbucket for the build status
[09:25:29.089] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:29.089] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:17:0) after 1m0s
[09:26:29.115] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:17:0)
[09:26:29.115] Timer tick, asking Buildbucket for the build status
[09:26:30.152] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:30.152] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:18:0) after 1m0s
[09:27:30.174] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:18:0)
[09:27:30.174] Timer tick, asking Buildbucket for the build status
[09:27:30.659] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:30.659] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:19:0) after 1m0s
[09:28:30.726] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:19:0)
[09:28:30.726] Timer tick, asking Buildbucket for the build status
[09:28:31.157] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:31.157] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:20:0) after 1m0s
[09:29:31.186] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:20:0)
[09:29:31.186] Timer tick, asking Buildbucket for the build status
[09:29:31.676] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:31.676] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:21:0) after 1m0s
[09:30:31.795] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:21:0)
[09:30:31.795] Timer tick, asking Buildbucket for the build status
[09:30:32.225] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:32.225] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:22:0) after 1m0s
[09:31:32.285] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:22:0)
[09:31:32.285] Timer tick, asking Buildbucket for the build status
[09:31:32.558] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:32.558] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:23:0) after 1m0s
[09:32:32.611] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:23:0)
[09:32:32.611] Timer tick, asking Buildbucket for the build status
[09:32:32.785] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:32.785] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:24:0) after 1m0s
[09:33:32.823] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:24:0)
[09:33:32.823] Timer tick, asking Buildbucket for the build status
[09:33:33.547] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:33.547] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:25:0) after 1m0s
[09:34:33.572] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:25:0)
[09:34:33.572] Timer tick, asking Buildbucket for the build status
[09:34:35.052] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:35.052] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:26:0) after 1m0s
[09:35:35.061] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:26:0)
[09:35:35.061] Timer tick, asking Buildbucket for the build status
[09:35:35.627] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:35.627] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:27:0) after 1m0s
[09:36:35.653] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:27:0)
[09:36:35.653] Timer tick, asking Buildbucket for the build status
[09:36:36.048] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:36.048] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:28:0) after 1m0s
[09:37:36.081] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:28:0)
[09:37:36.082] Timer tick, asking Buildbucket for the build status
[09:37:37.208] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:37.208] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:29:0) after 1m0s
[09:38:37.278] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:29:0)
[09:38:37.278] Timer tick, asking Buildbucket for the build status
[09:38:37.870] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:37.871] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:30:0) after 1m0s
[09:39:37.917] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:30:0)
[09:39:37.917] Timer tick, asking Buildbucket for the build status
[09:39:39.673] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:39.673] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:31:0) after 1m0s
[09:40:39.710] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:31:0)
[09:40:39.710] Timer tick, asking Buildbucket for the build status
[09:40:39.877] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:39.877] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:32:0) after 1m0s
[09:41:39.921] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:32:0)
[09:41:39.921] Timer tick, asking Buildbucket for the build status
[09:41:40.164] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:40.164] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:33:0) after 1m0s
[09:42:40.176] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:33:0)
[09:42:40.176] Timer tick, asking Buildbucket for the build status
[09:42:41.104] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:41.104] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:34:0) after 1m0s
[09:43:41.161] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:34:0)
[09:43:41.161] Timer tick, asking Buildbucket for the build status
[09:43:41.783] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:41.783] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:35:0) after 1m0s
[09:44:41.893] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:35:0)
[09:44:41.893] Timer tick, asking Buildbucket for the build status
[09:44:42.942] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:42.942] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:36:0) after 1m0s
[09:45:42.971] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:36:0)
[09:45:42.971] Timer tick, asking Buildbucket for the build status
[09:45:43.255] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:43.255] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:37:0) after 1m0s
[09:46:43.242] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:37:0)
[09:46:43.242] Timer tick, asking Buildbucket for the build status
[09:46:44.531] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:44.531] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:38:0) after 1m0s
[09:47:44.652] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:38:0)
[09:47:44.652] Timer tick, asking Buildbucket for the build status
[09:47:45.789] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:45.789] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:39:0) after 1m0s
[09:48:45.797] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:39:0)
[09:48:45.797] Timer tick, asking Buildbucket for the build status
[09:48:45.992] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:45.992] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:40:0) after 1m0s
[09:49:46.050] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:40:0)
[09:49:46.050] Timer tick, asking Buildbucket for the build status
[09:49:47.008] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:47.008] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:41:0) after 1m0s
[09:50:47.053] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:41:0)
[09:50:47.053] Timer tick, asking Buildbucket for the build status
[09:50:47.271] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:47.271] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:42:0) after 1m0s
[09:51:47.338] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:42:0)
[09:51:47.338] Timer tick, asking Buildbucket for the build status
[09:51:47.573] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:47.573] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:43:0) after 1m0s
[09:52:47.644] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:43:0)
[09:52:47.644] Timer tick, asking Buildbucket for the build status
[09:52:47.941] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:47.941] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:44:0) after 1m0s
[09:53:48.016] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:44:0)
[09:53:48.016] Timer tick, asking Buildbucket for the build status
[09:53:48.225] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:48.225] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:45:0) after 1m0s
[09:54:48.305] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:45:0)
[09:54:48.305] Timer tick, asking Buildbucket for the build status
[09:54:49.096] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:49.096] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:46:0) after 1m0s
[09:55:49.145] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:46:0)
[09:55:49.145] Timer tick, asking Buildbucket for the build status
[09:55:49.411] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:49.411] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:47:0) after 1m0s
[09:56:49.452] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:47:0)
[09:56:49.452] Timer tick, asking Buildbucket for the build status
[09:56:51.004] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:51.004] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:48:0) after 1m0s
[09:57:51.182] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:48:0)
[09:57:51.182] Timer tick, asking Buildbucket for the build status
[09:57:51.717] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:51.717] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:49:0) after 1m0s
[09:58:51.838] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:49:0)
[09:58:51.838] Timer tick, asking Buildbucket for the build status
[09:58:51.985] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:51.985] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:50:0) after 1m0s
[09:59:52.168] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:50:0)
[09:59:52.168] Timer tick, asking Buildbucket for the build status
[09:59:52.507] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:52.507] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:51:0) after 1m0s
[10:00:52.563] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:51:0)
[10:00:52.563] Timer tick, asking Buildbucket for the build status
[10:00:52.762] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:52.762] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:52:0) after 1m0s
[10:01:52.811] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:52:0)
[10:01:52.811] Timer tick, asking Buildbucket for the build status
[10:01:54.377] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:54.377] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:53:0) after 1m0s
[10:02:54.461] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:53:0)
[10:02:54.461] Timer tick, asking Buildbucket for the build status
[10:02:56.169] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:56.169] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:54:0) after 1m0s
[10:03:56.274] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:54:0)
[10:03:56.274] Timer tick, asking Buildbucket for the build status
[10:04:01.901] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:01.901] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:55:0) after 1m0s
[10:05:01.982] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:55:0)
[10:05:01.982] Timer tick, asking Buildbucket for the build status
[10:05:02.820] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:02.820] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:56:0) after 1m0s
[10:06:02.927] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:56:0)
[10:06:02.927] Timer tick, asking Buildbucket for the build status
[10:06:04.098] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:04.098] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:57:0) after 1m0s
[10:07:04.187] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:57:0)
[10:07:04.187] Timer tick, asking Buildbucket for the build status
[10:07:06.197] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:06.197] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:58:0) after 1m0s
[10:08:06.283] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:58:0)
[10:08:06.283] Timer tick, asking Buildbucket for the build status
[10:08:06.929] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:06.929] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:59:0) after 1m0s
[10:09:07.027] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:59:0)
[10:09:07.027] Timer tick, asking Buildbucket for the build status
[10:09:07.743] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:07.743] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:60:0) after 1m0s
[10:10:07.816] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:60:0)
[10:10:07.816] Timer tick, asking Buildbucket for the build status
[10:10:09.153] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:09.153] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:61:0) after 1m0s
[10:11:09.171] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:61:0)
[10:11:09.171] Timer tick, asking Buildbucket for the build status
[10:11:12.185] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:12.185] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:62:0) after 1m0s
[10:12:12.268] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:62:0)
[10:12:12.268] Timer tick, asking Buildbucket for the build status
[10:12:13.951] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:13.951] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:63:0) after 1m0s
[10:13:14.066] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:63:0)
[10:13:14.067] Timer tick, asking Buildbucket for the build status
[10:13:16.036] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:16.036] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:64:0) after 1m0s
[10:14:16.278] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:64:0)
[10:14:16.278] Timer tick, asking Buildbucket for the build status
[10:14:16.530] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:16.530] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:65:0) after 1m0s
[10:15:16.599] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:65:0)
[10:15:16.599] Timer tick, asking Buildbucket for the build status
[10:15:17.255] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:17.255] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:66:0) after 1m0s
[10:16:17.373] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:66:0)
[10:16:17.373] Timer tick, asking Buildbucket for the build status
[10:16:19.566] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:19.566] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:67:0) after 1m0s
[10:17:19.770] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:67:0)
[10:17:19.770] Timer tick, asking Buildbucket for the build status
[10:17:20.444] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:20.444] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:68:0) after 1m0s
[10:18:20.571] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:68:0)
[10:18:20.571] Timer tick, asking Buildbucket for the build status
[10:18:21.507] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:21.507] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:69:0) after 1m0s
[10:19:21.671] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:69:0)
[10:19:21.671] Timer tick, asking Buildbucket for the build status
[10:19:22.310] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:22.310] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:70:0) after 1m0s
[10:20:22.435] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:70:0)
[10:20:22.435] Timer tick, asking Buildbucket for the build status
[10:20:23.407] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:23.407] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:71:0) after 1m0s
[10:21:23.457] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:71:0)
[10:21:23.458] Timer tick, asking Buildbucket for the build status
[10:21:25.079] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:25.079] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:72:0) after 1m0s
[10:22:25.163] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:72:0)
[10:22:25.163] Timer tick, asking Buildbucket for the build status
[10:22:26.504] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:26.504] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:73:0) after 1m0s
[10:23:26.560] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:73:0)
[10:23:26.560] Timer tick, asking Buildbucket for the build status
[10:23:27.069] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:27.069] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:74:0) after 1m0s
[10:24:27.261] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:74:0)
[10:24:27.261] Timer tick, asking Buildbucket for the build status
[10:24:27.661] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:27.661] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:75:0) after 1m0s
[10:25:27.706] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:75:0)
[10:25:27.706] Timer tick, asking Buildbucket for the build status
[10:25:27.932] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:27.932] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:76:0) after 1m0s
[10:26:27.960] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:76:0)
[10:26:27.960] Timer tick, asking Buildbucket for the build status
[10:26:28.175] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:28.175] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:77:0) after 1m0s
[10:27:28.218] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:77:0)
[10:27:28.218] Timer tick, asking Buildbucket for the build status
[10:27:28.825] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:28.825] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:78:0) after 1m0s
[10:28:28.866] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:78:0)
[10:28:28.866] Timer tick, asking Buildbucket for the build status
[10:28:29.707] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:29.707] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:79:0) after 1m0s
[10:29:29.793] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:79:0)
[10:29:29.793] Timer tick, asking Buildbucket for the build status
[10:29:30.897] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:30.897] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:80:0) after 1m0s
[10:30:30.907] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:80:0)
[10:30:30.907] Timer tick, asking Buildbucket for the build status
[10:30:31.367] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:31.367] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:81:0) after 1m0s
[10:31:31.503] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:81:0)
[10:31:31.503] Timer tick, asking Buildbucket for the build status
[10:31:31.977] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:31.977] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:82:0) after 1m0s
[10:32:32.206] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:82:0)
[10:32:32.206] Timer tick, asking Buildbucket for the build status
[10:32:32.769] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:32.769] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:83:0) after 1m0s
[10:33:33.034] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:83:0)
[10:33:33.034] Timer tick, asking Buildbucket for the build status
[10:33:33.230] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:33.230] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:84:0) after 1m0s
[10:34:33.336] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:84:0)
[10:34:33.336] Timer tick, asking Buildbucket for the build status
[10:34:35.592] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:35.592] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:85:0) after 1m0s
[10:35:35.864] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:85:0)
[10:35:35.864] Timer tick, asking Buildbucket for the build status
[10:35:36.052] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:36.052] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:86:0) after 1m0s
[10:36:36.077] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:86:0)
[10:36:36.077] Timer tick, asking Buildbucket for the build status
[10:36:38.132] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:38.132] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:87:0) after 1m0s
[10:37:38.242] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:87:0)
[10:37:38.242] Timer tick, asking Buildbucket for the build status
[10:37:38.648] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:38.648] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:88:0) after 1m0s
[10:38:38.773] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:88:0)
[10:38:38.773] Timer tick, asking Buildbucket for the build status
[10:38:39.639] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:39.639] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:89:0) after 1m0s
[10:39:39.802] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:89:0)
[10:39:39.802] Timer tick, asking Buildbucket for the build status
[10:39:41.350] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:41.350] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:90:0) after 1m0s
[10:40:41.493] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:90:0)
[10:40:41.493] Timer tick, asking Buildbucket for the build status
[10:40:46.440] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:46.440] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:91:0) after 1m0s
[10:41:46.613] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:91:0)
[10:41:46.613] Timer tick, asking Buildbucket for the build status
[10:41:46.901] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:46.901] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:92:0) after 1m0s
[10:42:46.945] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:92:0)
[10:42:46.945] Timer tick, asking Buildbucket for the build status
[10:42:47.159] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:47.159] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:93:0) after 1m0s
[10:43:47.188] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:93:0)
[10:43:47.188] Timer tick, asking Buildbucket for the build status
[10:43:47.447] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:47.448] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:94:0) after 1m0s
[10:44:47.541] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:94:0)
[10:44:47.541] Timer tick, asking Buildbucket for the build status
[10:44:47.728] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:47.728] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:95:0) after 1m0s
[10:45:47.902] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:95:0)
[10:45:47.902] Timer tick, asking Buildbucket for the build status
[10:45:48.277] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:48.277] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:96:0) after 1m0s
[10:46:48.484] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:96:0)
[10:46:48.484] Timer tick, asking Buildbucket for the build status
[10:46:48.980] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:48.980] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:97:0) after 1m0s
[10:47:49.007] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:97:0)
[10:47:49.007] Timer tick, asking Buildbucket for the build status
[10:47:49.179] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:49.179] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:98:0) after 1m0s
[10:48:49.224] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:98:0)
[10:48:49.224] Timer tick, asking Buildbucket for the build status
[10:48:49.725] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:49.725] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:99:0) after 1m0s
[10:49:49.943] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:99:0)
[10:49:49.943] Timer tick, asking Buildbucket for the build status
[10:49:50.317] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:50.317] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:100:0) after 1m0s
[10:50:51.226] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:100:0)
[10:50:51.226] Timer tick, asking Buildbucket for the build status
[10:50:51.932] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:51.932] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:101:0) after 1m0s
[10:51:52.234] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:101:0)
[10:51:52.234] Timer tick, asking Buildbucket for the build status
[10:51:52.602] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:52.603] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:102:0) after 1m0s
[10:52:52.705] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:102:0)
[10:52:52.705] Timer tick, asking Buildbucket for the build status
[10:52:52.906] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:52.906] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:103:0) after 1m0s
[10:53:52.988] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:103:0)
[10:53:52.988] Timer tick, asking Buildbucket for the build status
[10:53:53.412] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:53.412] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:104:0) after 1m0s
[10:54:53.476] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:104:0)
[10:54:53.476] Timer tick, asking Buildbucket for the build status
[10:54:53.799] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:53.800] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:105:0) after 1m0s
[10:55:53.892] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:105:0)
[10:55:53.892] Timer tick, asking Buildbucket for the build status
[10:55:54.112] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:54.112] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:106:0) after 1m0s
[10:56:54.198] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:106:0)
[10:56:54.198] Timer tick, asking Buildbucket for the build status
[10:56:54.834] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:54.834] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:107:0) after 1m0s
[10:57:54.887] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:107:0)
[10:57:54.887] Timer tick, asking Buildbucket for the build status
[10:57:55.301] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:55.301] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:108:0) after 1m0s
[10:58:55.326] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:108:0)
[10:58:55.326] Timer tick, asking Buildbucket for the build status
[10:58:55.483] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:55.483] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:109:0) after 1m0s
[10:59:55.588] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:109:0)
[10:59:55.588] Timer tick, asking Buildbucket for the build status
[10:59:55.953] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:55.953] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:110:0) after 1m0s
[11:00:55.986] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:110:0)
[11:00:55.986] Timer tick, asking Buildbucket for the build status
[11:00:56.652] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:56.652] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:111:0) after 1m0s
[11:01:56.732] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:111:0)
[11:01:56.732] Timer tick, asking Buildbucket for the build status
[11:01:56.887] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:56.887] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:112:0) after 1m0s
[11:02:56.932] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:112:0)
[11:02:56.932] Timer tick, asking Buildbucket for the build status
[11:02:57.450] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:57.450] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:113:0) after 1m0s
[11:03:57.642] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:113:0)
[11:03:57.642] Timer tick, asking Buildbucket for the build status
[11:03:58.462] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:58.462] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:114:0) after 1m0s
[11:04:58.723] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:114:0)
[11:04:58.723] Timer tick, asking Buildbucket for the build status
[11:04:59.114] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:59.114] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:115:0) after 1m0s
[11:05:59.313] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:115:0)
[11:05:59.313] Timer tick, asking Buildbucket for the build status
[11:05:59.470] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:59.470] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:116:0) after 1m0s
[11:06:59.555] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:116:0)
[11:06:59.555] Timer tick, asking Buildbucket for the build status
[11:06:59.746] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:59.746] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:117:0) after 1m0s
[11:07:59.847] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:117:0)
[11:07:59.847] Timer tick, asking Buildbucket for the build status
[11:08:00.261] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:00.261] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:118:0) after 1m0s
[11:09:00.311] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:118:0)
[11:09:00.311] Timer tick, asking Buildbucket for the build status
[11:09:00.618] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:00.618] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:119:0) after 1m0s
[11:10:00.751] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:119:0)
[11:10:00.751] Timer tick, asking Buildbucket for the build status
[11:10:00.912] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:00.912] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:120:0) after 1m0s
[11:11:00.986] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:120:0)
[11:11:00.986] Timer tick, asking Buildbucket for the build status
[11:11:01.546] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:01.546] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:121:0) after 1m0s
[11:12:01.568] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:121:0)
[11:12:01.568] Timer tick, asking Buildbucket for the build status
[11:12:01.740] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:01.740] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:122:0) after 1m0s
[11:13:01.858] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:122:0)
[11:13:01.858] Timer tick, asking Buildbucket for the build status
[11:13:02.057] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:02.057] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:123:0) after 1m0s
[11:14:02.111] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:123:0)
[11:14:02.111] Timer tick, asking Buildbucket for the build status
[11:14:02.393] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:02.393] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:124:0) after 1m0s
[11:15:02.493] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:124:0)
[11:15:02.493] Timer tick, asking Buildbucket for the build status
[11:15:02.765] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:02.765] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:125:0) after 1m0s
[11:16:02.880] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:125:0)
[11:16:02.880] Timer tick, asking Buildbucket for the build status
[11:16:03.778] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:03.778] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:126:0) after 1m0s
[11:17:04.091] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:126:0)
[11:17:04.091] Timer tick, asking Buildbucket for the build status
[11:17:04.284] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:04.284] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:127:0) after 1m0s
[11:18:04.368] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:127:0)
[11:18:04.368] Timer tick, asking Buildbucket for the build status
[11:18:04.671] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:04.671] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:128:0) after 1m0s
[11:19:04.762] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:128:0)
[11:19:04.762] Timer tick, asking Buildbucket for the build status
[11:19:05.868] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:05.868] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:129:0) after 1m0s
[11:20:06.030] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:129:0)
[11:20:06.030] Timer tick, asking Buildbucket for the build status
[11:20:06.503] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:06.503] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:130:0) after 1m0s
[11:21:06.529] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:130:0)
[11:21:06.529] Timer tick, asking Buildbucket for the build status
[11:21:08.300] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:08.300] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:131:0) after 1m0s
[11:22:08.389] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:131:0)
[11:22:08.389] Timer tick, asking Buildbucket for the build status
[11:22:08.989] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:08.989] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:132:0) after 1m0s
[11:23:09.113] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:132:0)
[11:23:09.113] Timer tick, asking Buildbucket for the build status
[11:23:09.572] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:09.572] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:133:0) after 1m0s
[11:24:09.599] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:133:0)
[11:24:09.599] Timer tick, asking Buildbucket for the build status
[11:24:10.023] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:10.023] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:134:0) after 1m0s
[11:25:10.025] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:134:0)
[11:25:10.025] Timer tick, asking Buildbucket for the build status
[11:25:10.288] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:10.288] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:135:0) after 1m0s
[11:26:10.301] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:135:0)
[11:26:10.301] Timer tick, asking Buildbucket for the build status
[11:26:11.014] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:11.014] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:136:0) after 1m0s
[11:27:11.044] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:136:0)
[11:27:11.044] Timer tick, asking Buildbucket for the build status
[11:27:11.823] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:11.823] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:137:0) after 1m0s
[11:28:11.887] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:137:0)
[11:28:11.887] Timer tick, asking Buildbucket for the build status
[11:28:12.229] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:12.229] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:138:0) after 1m0s
[11:29:12.248] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:138:0)
[11:29:12.248] Timer tick, asking Buildbucket for the build status
[11:29:12.842] Build 8906972745883662944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:12.842] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9072402807959774416:139:0) after 1m0s
[11:30:10.611] Received PubSub notification, asking Buildbucket for the build status
[11:30:10.852] Build 8906972745883662944: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:30:10.852] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1564054208550027",
"created_by": "project:chromium",
"created_ts": "1564045877528332",
"id": "8906972745883662944",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"39deed19b11d75b713ba8dac1c9fa6d4b323cca8\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"build282-m9\", \"branch\": \"refs/heads/master\", \"buildername\": \"ToTiOS\", \"buildnumber\": 8786, \"got_angle_revision\": \"557e3853da56b9ff72be8ad8ce3643f5cdeb1d4d\", \"got_dawn_revision\": \"9d2ccaf65c2a370c5f203e119e194a44fa039481\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"39deed19b11d75b713ba8dac1c9fa6d4b323cca8\", \"got_revision_cp\": \"refs/heads/master@{#680783}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"6c48f74fdafe303ea4013f443fd4c2a7ab824a85\", \"got_v8_revision_cp\": \"refs/heads/7.7.310@{#1}\", \"got_webrtc_revision\": \"5cd13415882ec8048f3c14c35009e9501603b1c7\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28676}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.clang\", \"path_config\": \"generic\", \"perf_dashboard_machine_group\": \"ChromiumClang\", \"recipe\": \"ios/unified_builder_tester\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"39deed19b11d75b713ba8dac1c9fa6d4b323cca8\", \"swarm_hashes\": {\"0\": \"b4d04c997ff6a806011b9ea7abf5275e9cf774d4\", \"1\": \"808bc5500677a8e199b3e1910ce1c627978b2835\", \"10\": \"d5c2ad10270d8998f63c6d55d7e85d166d3d0f3b\", \"11\": \"82cef67499390184de34119aff408e5f5ef3ff5e\", \"12\": \"5e2330f44dfc2c2dc62b6e0b42875e78310d3460\", \"13\": \"0f4cd7eed58fde838179895aab2e4ac5678cf4b5\", \"14\": \"9a16561b2bc77d06f68cfa125bca0c3851ac9bc2\", \"15\": \"0335b5afe3181302ed88b9fb404153c09ca354d5\", \"16\": \"dae0bbf0bf8ce1b5f7e7d44e4556203c56ec2644\", \"2\": \"aab33f21b23b2a983db76317dc71a5232507d01a\", \"3\": \"ad880eff9ae96b27a6c39966eadde5b7c257cda8\", \"4\": \"e950d22d3ae33c7361217c9aed53bf6a9e00a80f\", \"5\": \"30fbb0d0af408e1c8b7269d3baec76924ca21cd4\", \"6\": \"4aa6fd49ab6a455ef905cfa575e32b5c37083454\", \"7\": \"7265d73fc2508a3ba0eaf512926d00a4aee96c8c\", \"8\": \"76b387d7b9c160045d26b88d0d9c2d10ed8121cf\", \"9\": \"df20fdb704a689cba0a5757070b5c4b059867be5\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0c96c161cf7dd2e1c2b922319da904a0c0164c4adceed5a8844a45f1c516ad13_v2\", \"builder_4a4feefdd7e355061708c01720b06a0ca8c49ffc9968a2ebc674541738676b8f_v2\", \"builder_69caa34b8f39daac28a821cac2619730401d46a6479812cc34796e3bf180418a_v2\", \"builder_ae5debd901c74ee0906916cb62621bceb2a8b657b42c2e2e9e89a0f6a2e5e53b_v2\", \"builder_d42b89eab403033f5a2a8102ec9c436397350cedb439d5e09b11874a5784adb4_v2\"], \"cores\": [\"24\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-E5-2697_v2\"], \"gce\": [\"0\"], \"gpu\": [\"1002\", \"1002:679e\", \"1002:679e-4.0.11-3.2.8\"], \"hidpi\": [\"0\"], \"id\": [\"build282-m9\"], \"mac_model\": [\"MacPro6,1\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Mac\", \"Mac-10.13\", \"Mac-10.13.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.10\"], \"server_version\": [\"4386-31546c0\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-atl\", \"us-atl-golo\", \"us-atl-golo-m9\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1564045881234396",
"status": "COMPLETED",
"status_changed_ts": "1564054209220293",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/8786",
"builder:ToTiOS",
"buildset:commit/git/39deed19b11d75b713ba8dac1c9fa6d4b323cca8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/39deed19b11d75b713ba8dac1c9fa6d4b323cca8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9072402807959774416",
"scheduler_job_id:chromium/ToTiOS",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8906972745883662944/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Mac",
"swarming_tag:recipe_name:ios/unified_builder_tester",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:464137aeb235b410",
"user_agent:luci-scheduler"
],
"updated_ts": "1564054209408585",
"url": "https://ci.chromium.org/b/8906972745883662944",
"utcnow_ts": "1564054210836494"
}
}
[11:30:10.852] Invocation finished in 2h18m54.688457335s with status SUCCEEDED