[11:40:54.176] New invocation is queued and will start shortly
[11:40:55.250] Starting the invocation (attempt 1)
[11:40:55.292] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:40:55.292] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:40:55.292] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9080909508692911552",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"0fad4bc42165b5c268c10d58ca2a1effd95efcdd\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9080909508692911552",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/0fad4bc42165b5c268c10d58ca2a1effd95efcdd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0fad4bc42165b5c268c10d58ca2a1effd95efcdd",
"gitiles_ref:refs/heads/master"
]
}
[11:40:56.340] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1555933255760266",
"id": "8915479446366608160",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"0fad4bc42165b5c268c10d58ca2a1effd95efcdd\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1555933256168442",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13017",
"builder:Windows deterministic",
"buildset:commit/git/0fad4bc42165b5c268c10d58ca2a1effd95efcdd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0fad4bc42165b5c268c10d58ca2a1effd95efcdd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080909508692911552",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8915479446366608160/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1555933256168545",
"url": "https://ci.chromium.org/b/8915479446366608160",
"utcnow_ts": "1555933256331317"
}
}
[11:40:56.340] Task URL: https://ci.chromium.org/b/8915479446366608160
[11:40:56.340] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:2:0) after 1m0s
[11:41:56.359] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:2:0)
[11:41:56.359] Timer tick, asking Buildbucket for the build status
[11:41:56.582] Build 8915479446366608160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:41:56.582] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:3:0) after 1m0s
[11:42:56.600] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:3:0)
[11:42:56.600] Timer tick, asking Buildbucket for the build status
[11:42:56.711] Build 8915479446366608160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:42:56.711] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:4:0) after 1m0s
[11:43:56.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:4:0)
[11:43:56.683] Timer tick, asking Buildbucket for the build status
[11:43:57.028] Build 8915479446366608160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:43:57.028] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:5:0) after 1m0s
[11:44:57.045] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:5:0)
[11:44:57.045] Timer tick, asking Buildbucket for the build status
[11:44:57.207] Build 8915479446366608160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:44:57.207] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:6:0) after 1m0s
[11:45:57.210] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:6:0)
[11:45:57.210] Timer tick, asking Buildbucket for the build status
[11:45:57.422] Build 8915479446366608160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:45:57.422] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:7:0) after 1m0s
[11:46:57.439] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:7:0)
[11:46:57.439] Timer tick, asking Buildbucket for the build status
[11:46:58.151] Build 8915479446366608160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:46:58.151] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:8:0) after 1m0s
[11:47:58.170] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:8:0)
[11:47:58.170] Timer tick, asking Buildbucket for the build status
[11:47:59.282] Build 8915479446366608160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:47:59.282] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:9:0) after 1m0s
[11:48:59.297] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:9:0)
[11:48:59.297] Timer tick, asking Buildbucket for the build status
[11:48:59.672] Build 8915479446366608160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:48:59.672] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:10:0) after 1m0s
[11:49:59.691] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:10:0)
[11:49:59.691] Timer tick, asking Buildbucket for the build status
[11:49:59.829] Build 8915479446366608160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:49:59.829] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:11:0) after 1m0s
[11:50:59.808] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:11:0)
[11:50:59.808] Timer tick, asking Buildbucket for the build status
[11:50:59.975] Build 8915479446366608160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:50:59.975] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:12:0) after 1m0s
[11:51:16.271] Received PubSub notification, asking Buildbucket for the build status
[11:51:17.344] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:59.994] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:12:0)
[11:51:59.994] Timer tick, asking Buildbucket for the build status
[11:52:00.483] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:00.483] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:14:0) after 1m0s
[11:53:00.499] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:14:0)
[11:53:00.499] Timer tick, asking Buildbucket for the build status
[11:53:00.644] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:00.644] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:15:0) after 1m0s
[11:54:00.671] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:15:0)
[11:54:00.671] Timer tick, asking Buildbucket for the build status
[11:54:01.174] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:01.174] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:16:0) after 1m0s
[11:55:01.188] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:16:0)
[11:55:01.188] Timer tick, asking Buildbucket for the build status
[11:55:01.370] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:01.370] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:17:0) after 1m0s
[11:56:01.389] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:17:0)
[11:56:01.389] Timer tick, asking Buildbucket for the build status
[11:56:01.510] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:01.510] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:18:0) after 1m0s
[11:57:01.529] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:18:0)
[11:57:01.529] Timer tick, asking Buildbucket for the build status
[11:57:02.314] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:02.314] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:19:0) after 1m0s
[11:58:02.331] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:19:0)
[11:58:02.331] Timer tick, asking Buildbucket for the build status
[11:58:02.672] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:02.672] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:20:0) after 1m0s
[11:59:02.691] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:20:0)
[11:59:02.691] Timer tick, asking Buildbucket for the build status
[11:59:03.308] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:03.308] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:21:0) after 1m0s
[12:00:03.327] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:21:0)
[12:00:03.328] Timer tick, asking Buildbucket for the build status
[12:00:03.892] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:03.892] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:22:0) after 1m0s
[12:01:03.911] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:22:0)
[12:01:03.911] Timer tick, asking Buildbucket for the build status
[12:01:04.139] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:04.139] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:23:0) after 1m0s
[12:02:04.157] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:23:0)
[12:02:04.157] Timer tick, asking Buildbucket for the build status
[12:02:04.881] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:04.881] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:24:0) after 1m0s
[12:03:04.903] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:24:0)
[12:03:04.903] Timer tick, asking Buildbucket for the build status
[12:03:05.079] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:05.079] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:25:0) after 1m0s
[12:04:05.181] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:25:0)
[12:04:05.181] Timer tick, asking Buildbucket for the build status
[12:04:05.482] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:05.482] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:26:0) after 1m0s
[12:05:05.501] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:26:0)
[12:05:05.501] Timer tick, asking Buildbucket for the build status
[12:05:05.807] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:05.807] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:27:0) after 1m0s
[12:06:05.880] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:27:0)
[12:06:05.880] Timer tick, asking Buildbucket for the build status
[12:06:06.179] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:06.179] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:28:0) after 1m0s
[12:07:06.196] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:28:0)
[12:07:06.196] Timer tick, asking Buildbucket for the build status
[12:07:08.912] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:08.912] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:29:0) after 1m0s
[12:08:08.941] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:29:0)
[12:08:08.941] Timer tick, asking Buildbucket for the build status
[12:08:09.114] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:09.114] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:30:0) after 1m0s
[12:09:09.173] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:30:0)
[12:09:09.173] Timer tick, asking Buildbucket for the build status
[12:09:09.321] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:09.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:31:0) after 1m0s
[12:10:09.343] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:31:0)
[12:10:09.343] Timer tick, asking Buildbucket for the build status
[12:10:09.951] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:09.951] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:32:0) after 1m0s
[12:11:09.968] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:32:0)
[12:11:09.968] Timer tick, asking Buildbucket for the build status
[12:11:10.955] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:10.955] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:33:0) after 1m0s
[12:12:10.985] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:33:0)
[12:12:10.985] Timer tick, asking Buildbucket for the build status
[12:12:11.191] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:11.191] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:34:0) after 1m0s
[12:13:11.211] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:34:0)
[12:13:11.211] Timer tick, asking Buildbucket for the build status
[12:13:11.365] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:11.365] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:35:0) after 1m0s
[12:14:11.346] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:35:0)
[12:14:11.346] Timer tick, asking Buildbucket for the build status
[12:14:11.472] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:11.472] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:36:0) after 1m0s
[12:15:11.528] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:36:0)
[12:15:11.528] Timer tick, asking Buildbucket for the build status
[12:15:11.796] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:11.796] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:37:0) after 1m0s
[12:16:11.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:37:0)
[12:16:11.815] Timer tick, asking Buildbucket for the build status
[12:16:12.151] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:12.151] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:38:0) after 1m0s
[12:17:12.174] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:38:0)
[12:17:12.174] Timer tick, asking Buildbucket for the build status
[12:17:13.185] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:13.185] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:39:0) after 1m0s
[12:18:13.265] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:39:0)
[12:18:13.265] Timer tick, asking Buildbucket for the build status
[12:18:13.610] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:13.610] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:40:0) after 1m0s
[12:19:13.629] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:40:0)
[12:19:13.629] Timer tick, asking Buildbucket for the build status
[12:19:14.706] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:14.706] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:41:0) after 1m0s
[12:20:14.723] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:41:0)
[12:20:14.723] Timer tick, asking Buildbucket for the build status
[12:20:14.867] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:14.867] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:42:0) after 1m0s
[12:21:14.892] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:42:0)
[12:21:14.892] Timer tick, asking Buildbucket for the build status
[12:21:14.984] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:14.984] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:43:0) after 1m0s
[12:22:14.995] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:43:0)
[12:22:14.995] Timer tick, asking Buildbucket for the build status
[12:22:15.119] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:15.119] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:44:0) after 1m0s
[12:23:15.133] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:44:0)
[12:23:15.133] Timer tick, asking Buildbucket for the build status
[12:23:15.251] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:15.251] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:45:0) after 1m0s
[12:24:15.227] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:45:0)
[12:24:15.227] Timer tick, asking Buildbucket for the build status
[12:24:15.338] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:15.338] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:46:0) after 1m0s
[12:25:15.356] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:46:0)
[12:25:15.356] Timer tick, asking Buildbucket for the build status
[12:25:15.884] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:15.884] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:47:0) after 1m0s
[12:26:15.908] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:47:0)
[12:26:15.908] Timer tick, asking Buildbucket for the build status
[12:26:15.997] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:15.997] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:48:0) after 1m0s
[12:27:16.017] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:48:0)
[12:27:16.017] Timer tick, asking Buildbucket for the build status
[12:27:16.156] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:16.156] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:49:0) after 1m0s
[12:28:16.143] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:49:0)
[12:28:16.143] Timer tick, asking Buildbucket for the build status
[12:28:16.781] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:16.781] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:50:0) after 1m0s
[12:29:16.805] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:50:0)
[12:29:16.805] Timer tick, asking Buildbucket for the build status
[12:29:17.622] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:17.622] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:51:0) after 1m0s
[12:30:17.649] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:51:0)
[12:30:17.649] Timer tick, asking Buildbucket for the build status
[12:30:18.099] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:18.099] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:52:0) after 1m0s
[12:31:18.085] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:52:0)
[12:31:18.085] Timer tick, asking Buildbucket for the build status
[12:31:18.365] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:18.365] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:53:0) after 1m0s
[12:32:18.392] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:53:0)
[12:32:18.392] Timer tick, asking Buildbucket for the build status
[12:32:18.494] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:18.494] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:54:0) after 1m0s
[12:33:18.512] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:54:0)
[12:33:18.512] Timer tick, asking Buildbucket for the build status
[12:33:18.852] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:18.852] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:55:0) after 1m0s
[12:34:18.916] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:55:0)
[12:34:18.916] Timer tick, asking Buildbucket for the build status
[12:34:19.225] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:19.225] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:56:0) after 1m0s
[12:35:19.246] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:56:0)
[12:35:19.246] Timer tick, asking Buildbucket for the build status
[12:35:19.464] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:19.464] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:57:0) after 1m0s
[12:36:19.484] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:57:0)
[12:36:19.484] Timer tick, asking Buildbucket for the build status
[12:36:20.505] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:20.505] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:58:0) after 1m0s
[12:37:20.528] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:58:0)
[12:37:20.528] Timer tick, asking Buildbucket for the build status
[12:37:21.321] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:21.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:59:0) after 1m0s
[12:38:21.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:59:0)
[12:38:21.344] Timer tick, asking Buildbucket for the build status
[12:38:21.505] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:21.506] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:60:0) after 1m0s
[12:39:21.507] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:60:0)
[12:39:21.507] Timer tick, asking Buildbucket for the build status
[12:39:21.636] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:21.636] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:61:0) after 1m0s
[12:40:21.659] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:61:0)
[12:40:21.659] Timer tick, asking Buildbucket for the build status
[12:40:21.758] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:21.758] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:62:0) after 1m0s
[12:41:21.832] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:62:0)
[12:41:21.832] Timer tick, asking Buildbucket for the build status
[12:41:21.955] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:21.955] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:63:0) after 1m0s
[12:42:21.974] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:63:0)
[12:42:21.974] Timer tick, asking Buildbucket for the build status
[12:42:22.231] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:22.231] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:64:0) after 1m0s
[12:43:22.233] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:64:0)
[12:43:22.233] Timer tick, asking Buildbucket for the build status
[12:43:23.088] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:23.088] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:65:0) after 1m0s
[12:44:23.081] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:65:0)
[12:44:23.081] Timer tick, asking Buildbucket for the build status
[12:44:23.457] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:23.457] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:66:0) after 1m0s
[12:45:23.478] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:66:0)
[12:45:23.478] Timer tick, asking Buildbucket for the build status
[12:45:23.686] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:23.686] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:67:0) after 1m0s
[12:46:23.658] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:67:0)
[12:46:23.658] Timer tick, asking Buildbucket for the build status
[12:46:23.981] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:23.981] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:68:0) after 1m0s
[12:47:24.014] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:68:0)
[12:47:24.014] Timer tick, asking Buildbucket for the build status
[12:47:24.114] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:24.114] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:69:0) after 1m0s
[12:48:24.136] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:69:0)
[12:48:24.136] Timer tick, asking Buildbucket for the build status
[12:48:24.241] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:24.241] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:70:0) after 1m0s
[12:49:24.255] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:70:0)
[12:49:24.255] Timer tick, asking Buildbucket for the build status
[12:49:24.365] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:24.365] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:71:0) after 1m0s
[12:50:24.396] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:71:0)
[12:50:24.396] Timer tick, asking Buildbucket for the build status
[12:50:25.069] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:25.069] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:72:0) after 1m0s
[12:51:25.087] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:72:0)
[12:51:25.087] Timer tick, asking Buildbucket for the build status
[12:51:25.270] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:25.270] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:73:0) after 1m0s
[12:52:25.288] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:73:0)
[12:52:25.288] Timer tick, asking Buildbucket for the build status
[12:52:26.209] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:26.209] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:74:0) after 1m0s
[12:53:26.229] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:74:0)
[12:53:26.229] Timer tick, asking Buildbucket for the build status
[12:53:26.750] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:26.750] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:75:0) after 1m0s
[12:54:26.775] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:75:0)
[12:54:26.775] Timer tick, asking Buildbucket for the build status
[12:54:26.877] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:26.877] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:76:0) after 1m0s
[12:55:26.899] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:76:0)
[12:55:26.899] Timer tick, asking Buildbucket for the build status
[12:55:27.501] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:27.501] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:77:0) after 1m0s
[12:56:27.527] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:77:0)
[12:56:27.527] Timer tick, asking Buildbucket for the build status
[12:56:27.805] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:27.805] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:78:0) after 1m0s
[12:57:27.807] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:78:0)
[12:57:27.807] Timer tick, asking Buildbucket for the build status
[12:57:28.074] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:28.074] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:79:0) after 1m0s
[12:58:28.078] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:79:0)
[12:58:28.078] Timer tick, asking Buildbucket for the build status
[12:58:28.521] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:28.521] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:80:0) after 1m0s
[12:59:28.509] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:80:0)
[12:59:28.509] Timer tick, asking Buildbucket for the build status
[12:59:28.633] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:28.633] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:81:0) after 1m0s
[13:00:28.656] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:81:0)
[13:00:28.656] Timer tick, asking Buildbucket for the build status
[13:00:29.308] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:29.308] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:82:0) after 1m0s
[13:01:29.358] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:82:0)
[13:01:29.358] Timer tick, asking Buildbucket for the build status
[13:01:29.468] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:29.468] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:83:0) after 1m0s
[13:02:29.490] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:83:0)
[13:02:29.490] Timer tick, asking Buildbucket for the build status
[13:02:30.374] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:30.374] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:84:0) after 1m0s
[13:03:30.391] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:84:0)
[13:03:30.391] Timer tick, asking Buildbucket for the build status
[13:03:30.668] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:30.668] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:85:0) after 1m0s
[13:04:30.697] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:85:0)
[13:04:30.697] Timer tick, asking Buildbucket for the build status
[13:04:30.895] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:30.895] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:86:0) after 1m0s
[13:05:30.909] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:86:0)
[13:05:30.909] Timer tick, asking Buildbucket for the build status
[13:05:31.149] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:31.149] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:87:0) after 1m0s
[13:06:31.169] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:87:0)
[13:06:31.169] Timer tick, asking Buildbucket for the build status
[13:06:31.283] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:31.283] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:88:0) after 1m0s
[13:07:31.306] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:88:0)
[13:07:31.306] Timer tick, asking Buildbucket for the build status
[13:07:31.859] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:31.859] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:89:0) after 1m0s
[13:08:31.872] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:89:0)
[13:08:31.872] Timer tick, asking Buildbucket for the build status
[13:08:32.193] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:32.193] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:90:0) after 1m0s
[13:09:32.227] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:90:0)
[13:09:32.227] Timer tick, asking Buildbucket for the build status
[13:09:32.310] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:32.310] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:91:0) after 1m0s
[13:10:32.297] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:91:0)
[13:10:32.297] Timer tick, asking Buildbucket for the build status
[13:10:32.411] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:32.411] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:92:0) after 1m0s
[13:11:32.448] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:92:0)
[13:11:32.448] Timer tick, asking Buildbucket for the build status
[13:11:32.525] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:32.525] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:93:0) after 1m0s
[13:12:32.548] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:93:0)
[13:12:32.548] Timer tick, asking Buildbucket for the build status
[13:12:33.876] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:33.876] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:94:0) after 1m0s
[13:13:33.927] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:94:0)
[13:13:33.927] Timer tick, asking Buildbucket for the build status
[13:13:34.045] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:34.045] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:95:0) after 1m0s
[13:14:34.066] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:95:0)
[13:14:34.066] Timer tick, asking Buildbucket for the build status
[13:14:34.369] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:34.369] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:96:0) after 1m0s
[13:15:34.393] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:96:0)
[13:15:34.393] Timer tick, asking Buildbucket for the build status
[13:15:34.851] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:34.851] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:97:0) after 1m0s
[13:16:34.895] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:97:0)
[13:16:34.895] Timer tick, asking Buildbucket for the build status
[13:16:35.576] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:35.576] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:98:0) after 1m0s
[13:17:35.598] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:98:0)
[13:17:35.598] Timer tick, asking Buildbucket for the build status
[13:17:35.740] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:35.740] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:99:0) after 1m0s
[13:18:35.753] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:99:0)
[13:18:35.753] Timer tick, asking Buildbucket for the build status
[13:18:36.307] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:36.307] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:100:0) after 1m0s
[13:19:36.326] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:100:0)
[13:19:36.326] Timer tick, asking Buildbucket for the build status
[13:19:36.816] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:36.816] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:101:0) after 1m0s
[13:20:36.851] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:101:0)
[13:20:36.851] Timer tick, asking Buildbucket for the build status
[13:20:36.950] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:36.950] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:102:0) after 1m0s
[13:21:37.045] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:102:0)
[13:21:37.045] Timer tick, asking Buildbucket for the build status
[13:21:37.158] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:37.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:103:0) after 1m0s
[13:22:37.181] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:103:0)
[13:22:37.181] Timer tick, asking Buildbucket for the build status
[13:22:37.330] Build 8915479446366608160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:37.330] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9080909508692911552:104:0) after 1m0s
[13:23:02.305] Received PubSub notification, asking Buildbucket for the build status
[13:23:02.637] Build 8915479446366608160: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[13:23:02.637] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1555939381691012",
"created_by": "project:chromium",
"created_ts": "1555933255760266",
"failure_reason": "BUILD_FAILURE",
"id": "8915479446366608160",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"0fad4bc42165b5c268c10d58ca2a1effd95efcdd\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step('compare_build_artifacts') failed with return_code 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\": \"windows-deterministic-0-z5tg\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 13017, \"got_angle_revision\": \"cff6f150eae166d0b3aefb11dba11947a6d12a3d\", \"got_dawn_revision\": \"22cee9ae36b23a8908525d668ef4728a26c931c4\", \"got_nacl_revision\": \"6abc006f6760ec49350cd45e8bccbff4809725ac\", \"got_revision\": \"0fad4bc42165b5c268c10d58ca2a1effd95efcdd\", \"got_revision_cp\": \"refs/heads/master@{#652828}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"49d1e6f9b11ed9a1a2e7b6855ae26907ec119713\", \"got_v8_revision_cp\": \"refs/heads/7.6.1@{#1}\", \"got_webrtc_revision\": \"852bffb6063aa36814c89a58390581e59643f1fb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27678}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"0fad4bc42165b5c268c10d58ca2a1effd95efcdd\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Windows deterministic\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"windows-deterministic-0-z5tg\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4267-53b17d5\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}, \"ui\": {\"info\": \"Step('compare_build_artifacts') failed with return_code 1\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1555933875460494",
"status": "COMPLETED",
"status_changed_ts": "1555933875726294",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13017",
"builder:Windows deterministic",
"buildset:commit/git/0fad4bc42165b5c268c10d58ca2a1effd95efcdd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0fad4bc42165b5c268c10d58ca2a1effd95efcdd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9080909508692911552",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8915479446366608160/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:445daae2dcefa410",
"user_agent:luci-scheduler"
],
"updated_ts": "1555939381942605",
"url": "https://ci.chromium.org/b/8915479446366608160",
"utcnow_ts": "1555939382625422"
}
}
[13:23:02.637] Invocation finished in 1h42m8.478377052s with status FAILED