[22:37:34.253] New invocation is queued and will start shortly
[22:37:35.330] Starting the invocation (attempt 1)
[22:37:35.362] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:37:35.362] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:37:35.363] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9076972525165556928",
"parameters_json": "{\"builder_name\":\"ToTWin64(dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"0e492b8730ab27b37676ffe5eb166e963e4e30e6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTWin64(dbg)",
"scheduler_invocation_id:9076972525165556928",
"scheduler_job_id:chromium/ToTWin64(dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/0e492b8730ab27b37676ffe5eb166e963e4e30e6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0e492b8730ab27b37676ffe5eb166e963e4e30e6",
"gitiles_ref:refs/heads/master"
]
}
[22:37:37.452] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1559687855829376",
"id": "8911542462844686864",
"parameters_json": "{\"builder_name\": \"ToTWin64(dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"0e492b8730ab27b37676ffe5eb166e963e4e30e6\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1559687857085904",
"tags": [
"build_address:luci.chromium.ci/ToTWin64(dbg)/3252",
"builder:ToTWin64(dbg)",
"buildset:commit/git/0e492b8730ab27b37676ffe5eb166e963e4e30e6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0e492b8730ab27b37676ffe5eb166e963e4e30e6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9076972525165556928",
"scheduler_job_id:chromium/ToTWin64(dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8911542462844686864/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1559687857086054",
"url": "https://ci.chromium.org/b/8911542462844686864",
"utcnow_ts": "1559687857442274"
}
}
[22:37:37.452] Task URL: https://ci.chromium.org/b/8911542462844686864
[22:37:37.452] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:2:0) after 1m0s
[22:38:25.787] Received PubSub notification, asking Buildbucket for the build status
[22:38:29.801] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:37.467] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:2:0)
[22:38:37.467] Timer tick, asking Buildbucket for the build status
[22:38:40.185] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:40.185] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:4:0) after 1m0s
[22:39:40.186] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:4:0)
[22:39:40.186] Timer tick, asking Buildbucket for the build status
[22:39:40.507] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:40.507] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:5:0) after 1m0s
[22:40:40.513] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:5:0)
[22:40:40.513] Timer tick, asking Buildbucket for the build status
[22:40:40.821] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:40.821] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:6:0) after 1m0s
[22:41:40.826] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:6:0)
[22:41:40.826] Timer tick, asking Buildbucket for the build status
[22:41:41.061] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:41.061] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:7:0) after 1m0s
[22:42:41.104] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:7:0)
[22:42:41.104] Timer tick, asking Buildbucket for the build status
[22:42:41.491] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:41.491] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:8:0) after 1m0s
[22:43:41.743] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:8:0)
[22:43:41.743] Timer tick, asking Buildbucket for the build status
[22:43:42.148] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:42.148] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:9:0) after 1m0s
[22:44:42.139] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:9:0)
[22:44:42.139] Timer tick, asking Buildbucket for the build status
[22:44:45.102] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:45.102] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:10:0) after 1m0s
[22:45:45.152] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:10:0)
[22:45:45.152] Timer tick, asking Buildbucket for the build status
[22:45:45.555] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:45.555] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:11:0) after 1m0s
[22:46:45.531] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:11:0)
[22:46:45.531] Timer tick, asking Buildbucket for the build status
[22:46:46.013] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:46.013] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:12:0) after 1m0s
[22:47:46.036] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:12:0)
[22:47:46.036] Timer tick, asking Buildbucket for the build status
[22:47:47.526] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:47.526] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:13:0) after 1m0s
[22:48:47.507] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:13:0)
[22:48:47.507] Timer tick, asking Buildbucket for the build status
[22:48:47.612] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:47.612] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:14:0) after 1m0s
[22:49:47.828] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:14:0)
[22:49:47.828] Timer tick, asking Buildbucket for the build status
[22:49:48.507] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:48.507] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:15:0) after 1m0s
[22:50:48.485] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:15:0)
[22:50:48.485] Timer tick, asking Buildbucket for the build status
[22:50:48.930] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:48.930] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:16:0) after 1m0s
[22:51:49.033] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:16:0)
[22:51:49.033] Timer tick, asking Buildbucket for the build status
[22:51:49.207] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:49.207] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:17:0) after 1m0s
[22:52:49.249] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:17:0)
[22:52:49.249] Timer tick, asking Buildbucket for the build status
[22:52:49.710] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:49.710] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:18:0) after 1m0s
[22:53:49.787] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:18:0)
[22:53:49.787] Timer tick, asking Buildbucket for the build status
[22:53:49.968] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:49.968] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:19:0) after 1m0s
[22:54:49.985] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:19:0)
[22:54:49.985] Timer tick, asking Buildbucket for the build status
[22:54:50.319] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:50.319] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:20:0) after 1m0s
[22:55:50.390] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:20:0)
[22:55:50.390] Timer tick, asking Buildbucket for the build status
[22:55:50.704] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:50.704] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:21:0) after 1m0s
[22:56:50.782] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:21:0)
[22:56:50.782] Timer tick, asking Buildbucket for the build status
[22:56:53.148] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:53.148] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:22:0) after 1m0s
[22:57:53.199] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:22:0)
[22:57:53.199] Timer tick, asking Buildbucket for the build status
[22:57:53.705] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:53.705] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:23:0) after 1m0s
[22:58:53.762] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:23:0)
[22:58:53.762] Timer tick, asking Buildbucket for the build status
[22:58:55.389] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:55.389] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:24:0) after 1m0s
[22:59:55.423] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:24:0)
[22:59:55.423] Timer tick, asking Buildbucket for the build status
[22:59:55.891] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:55.891] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:25:0) after 1m0s
[23:00:55.979] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:25:0)
[23:00:55.980] Timer tick, asking Buildbucket for the build status
[23:00:56.094] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:56.094] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:26:0) after 1m0s
[23:01:56.176] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:26:0)
[23:01:56.176] Timer tick, asking Buildbucket for the build status
[23:01:56.313] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:56.313] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:27:0) after 1m0s
[23:02:56.328] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:27:0)
[23:02:56.328] Timer tick, asking Buildbucket for the build status
[23:02:58.530] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:58.530] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:28:0) after 1m0s
[23:03:58.636] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:28:0)
[23:03:58.636] Timer tick, asking Buildbucket for the build status
[23:03:58.773] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:58.773] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:29:0) after 1m0s
[23:04:58.841] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:29:0)
[23:04:58.841] Timer tick, asking Buildbucket for the build status
[23:04:58.965] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:58.965] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:30:0) after 1m0s
[23:05:59.029] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:30:0)
[23:05:59.029] Timer tick, asking Buildbucket for the build status
[23:06:00.264] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:00.264] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:31:0) after 1m0s
[23:07:00.304] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:31:0)
[23:07:00.305] Timer tick, asking Buildbucket for the build status
[23:07:00.793] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:00.793] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:32:0) after 1m0s
[23:08:00.852] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:32:0)
[23:08:00.852] Timer tick, asking Buildbucket for the build status
[23:08:01.311] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:01.311] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:33:0) after 1m0s
[23:09:01.294] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:33:0)
[23:09:01.294] Timer tick, asking Buildbucket for the build status
[23:09:02.105] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:02.105] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:34:0) after 1m0s
[23:10:02.174] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:34:0)
[23:10:02.174] Timer tick, asking Buildbucket for the build status
[23:10:02.313] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:02.313] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:35:0) after 1m0s
[23:11:02.375] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:35:0)
[23:11:02.375] Timer tick, asking Buildbucket for the build status
[23:11:02.758] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:02.758] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:36:0) after 1m0s
[23:12:02.775] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:36:0)
[23:12:02.775] Timer tick, asking Buildbucket for the build status
[23:12:03.351] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:03.351] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:37:0) after 1m0s
[23:13:03.348] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:37:0)
[23:13:03.348] Timer tick, asking Buildbucket for the build status
[23:13:03.751] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:03.751] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:38:0) after 1m0s
[23:14:03.865] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:38:0)
[23:14:03.865] Timer tick, asking Buildbucket for the build status
[23:14:04.466] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:04.466] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:39:0) after 1m0s
[23:15:04.525] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:39:0)
[23:15:04.525] Timer tick, asking Buildbucket for the build status
[23:15:04.991] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:04.991] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:40:0) after 1m0s
[23:16:05.011] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:40:0)
[23:16:05.011] Timer tick, asking Buildbucket for the build status
[23:16:06.456] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:06.456] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:41:0) after 1m0s
[23:17:06.477] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:41:0)
[23:17:06.477] Timer tick, asking Buildbucket for the build status
[23:17:08.261] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:08.261] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:42:0) after 1m0s
[23:18:08.376] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:42:0)
[23:18:08.376] Timer tick, asking Buildbucket for the build status
[23:18:09.110] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:09.110] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:43:0) after 1m0s
[23:19:09.193] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:43:0)
[23:19:09.193] Timer tick, asking Buildbucket for the build status
[23:19:09.540] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:09.540] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:44:0) after 1m0s
[23:20:09.553] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:44:0)
[23:20:09.553] Timer tick, asking Buildbucket for the build status
[23:20:09.854] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:09.854] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:45:0) after 1m0s
[23:21:09.872] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:45:0)
[23:21:09.872] Timer tick, asking Buildbucket for the build status
[23:21:11.449] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:11.449] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:46:0) after 1m0s
[23:22:11.476] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:46:0)
[23:22:11.476] Timer tick, asking Buildbucket for the build status
[23:22:12.971] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:12.971] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:47:0) after 1m0s
[23:23:13.045] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:47:0)
[23:23:13.045] Timer tick, asking Buildbucket for the build status
[23:23:13.380] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:13.380] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:48:0) after 1m0s
[23:24:13.421] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:48:0)
[23:24:13.421] Timer tick, asking Buildbucket for the build status
[23:24:14.067] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:14.067] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:49:0) after 1m0s
[23:25:14.109] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:49:0)
[23:25:14.109] Timer tick, asking Buildbucket for the build status
[23:25:14.302] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:14.302] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:50:0) after 1m0s
[23:26:14.364] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:50:0)
[23:26:14.364] Timer tick, asking Buildbucket for the build status
[23:26:16.029] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:16.029] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:51:0) after 1m0s
[23:27:16.138] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:51:0)
[23:27:16.138] Timer tick, asking Buildbucket for the build status
[23:27:17.018] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:17.018] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:52:0) after 1m0s
[23:28:17.055] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:52:0)
[23:28:17.055] Timer tick, asking Buildbucket for the build status
[23:28:19.153] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:19.153] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:53:0) after 1m0s
[23:29:19.173] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:53:0)
[23:29:19.174] Timer tick, asking Buildbucket for the build status
[23:29:21.093] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:21.093] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:54:0) after 1m0s
[23:30:21.130] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:54:0)
[23:30:21.130] Timer tick, asking Buildbucket for the build status
[23:30:23.145] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:23.145] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:55:0) after 1m0s
[23:31:23.276] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:55:0)
[23:31:23.276] Timer tick, asking Buildbucket for the build status
[23:31:28.158] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:28.158] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:56:0) after 1m0s
[23:32:28.232] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:56:0)
[23:32:28.232] Timer tick, asking Buildbucket for the build status
[23:32:28.496] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:28.496] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:57:0) after 1m0s
[23:33:28.504] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:57:0)
[23:33:28.504] Timer tick, asking Buildbucket for the build status
[23:33:29.401] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:29.401] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:58:0) after 1m0s
[23:34:29.571] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:58:0)
[23:34:29.571] Timer tick, asking Buildbucket for the build status
[23:34:29.820] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:29.820] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:59:0) after 1m0s
[23:35:29.885] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:59:0)
[23:35:29.885] Timer tick, asking Buildbucket for the build status
[23:35:30.290] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:30.290] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:60:0) after 1m0s
[23:36:30.364] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:60:0)
[23:36:30.364] Timer tick, asking Buildbucket for the build status
[23:36:30.983] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:30.983] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:61:0) after 1m0s
[23:37:31.171] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:61:0)
[23:37:31.171] Timer tick, asking Buildbucket for the build status
[23:37:31.626] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:31.626] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:62:0) after 1m0s
[23:38:31.683] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:62:0)
[23:38:31.683] Timer tick, asking Buildbucket for the build status
[23:38:32.076] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:32.076] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:63:0) after 1m0s
[23:39:32.214] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:63:0)
[23:39:32.214] Timer tick, asking Buildbucket for the build status
[23:39:32.464] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:32.464] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:64:0) after 1m0s
[23:40:32.537] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:64:0)
[23:40:32.537] Timer tick, asking Buildbucket for the build status
[23:40:32.643] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:32.643] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:65:0) after 1m0s
[23:41:32.630] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:65:0)
[23:41:32.630] Timer tick, asking Buildbucket for the build status
[23:41:32.773] Build 8911542462844686864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:32.773] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64(dbg):9076972525165556928:66:0) after 1m0s
[23:41:47.366] Received PubSub notification, asking Buildbucket for the build status
[23:41:47.457] Build 8911542462844686864: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[23:41:47.457] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1559691706969686",
"created_by": "project:chromium",
"created_ts": "1559687855829376",
"failure_reason": "BUILD_FAILURE",
"id": "8911542462844686864",
"parameters_json": "{\"builder_name\": \"ToTWin64(dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"0e492b8730ab27b37676ffe5eb166e963e4e30e6\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step('compile') (retcode: 1)\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-4-zo80\", \"branch\": \"refs/heads/master\", \"buildername\": \"ToTWin64(dbg)\", \"buildnumber\": 3252, \"got_angle_revision\": \"4a75741655d330e257b2f20d7404cce1e51ef72e\", \"got_clang_revision\": \"362557-c93b9958-0\", \"got_dawn_revision\": \"b632bc58ed373eb2020af65e0fdeaf07d46d1a9c\", \"got_nacl_revision\": \"0ddc033406886a709b901e0c312872529f9705e8\", \"got_revision\": \"0e492b8730ab27b37676ffe5eb166e963e4e30e6\", \"got_revision_cp\": \"refs/heads/master@{#666000}\", \"got_swarming_client_revision\": \"779c4f0f8488c64587b75dbb001d18c3c0c4cda9\", \"got_v8_revision\": \"9672bf6d0b325ae9a9157ca217db965bca7e76f9\", \"got_v8_revision_cp\": \"refs/heads/7.7.15@{#1}\", \"got_webrtc_revision\": \"479a3c0f92b957a9ba1c242e7fe6e4d37b49ce32\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28154}\", \"mastername\": \"chromium.clang\", \"path_config\": \"generic\", \"perf_dashboard_machine_group\": \"ChromiumClang\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"0e492b8730ab27b37676ffe5eb166e963e4e30e6\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_a4ee364c4b5996d1649f16c77d7186222b75eb5b75762fbbe3baeec97aac12d9_v2\", \"builder_a820300c9e65cc745d70affd2be7bdb9e0da8337bfe61b74452f33419656c42a_v2\", \"git\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-4-zo80\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4316-effbcfc\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"Step('compile') (retcode: 1)\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1559687904340314",
"status": "COMPLETED",
"status_changed_ts": "1559687905255398",
"tags": [
"build_address:luci.chromium.ci/ToTWin64(dbg)/3252",
"builder:ToTWin64(dbg)",
"buildset:commit/git/0e492b8730ab27b37676ffe5eb166e963e4e30e6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0e492b8730ab27b37676ffe5eb166e963e4e30e6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9076972525165556928",
"scheduler_job_id:chromium/ToTWin64(dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8911542462844686864/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:453d758f67608510",
"user_agent:luci-scheduler"
],
"updated_ts": "1559691707080217",
"url": "https://ci.chromium.org/b/8911542462844686864",
"utcnow_ts": "1559691707451050"
}
}
[23:41:47.457] Invocation finished in 1h4m13.225335712s with status FAILED