[15:49:58.817] New invocation is queued and will start shortly
[15:49:59.929] Starting the invocation (attempt 1)
[15:49:59.971] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:49:59.971] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:49:59.971] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9075729811015246080",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"d39dff09bed394749f9d37c41576d5628a4e7271\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9075729811015246080",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/d39dff09bed394749f9d37c41576d5628a4e7271",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d39dff09bed394749f9d37c41576d5628a4e7271",
"gitiles_ref:refs/heads/master"
]
}
[15:50:00.763] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1560873000108320",
"id": "8910299748996847488",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"d39dff09bed394749f9d37c41576d5628a4e7271\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1560873000641632",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13866",
"builder:Windows deterministic",
"buildset:commit/git/d39dff09bed394749f9d37c41576d5628a4e7271",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d39dff09bed394749f9d37c41576d5628a4e7271",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9075729811015246080",
"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/8910299748996847488/+/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": "1560873000641725",
"url": "https://ci.chromium.org/b/8910299748996847488",
"utcnow_ts": "1560873000754808"
}
}
[15:50:00.763] Task URL: https://ci.chromium.org/b/8910299748996847488
[15:50:00.763] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:2:0) after 1m0s
[15:50:04.115] Received PubSub notification, asking Buildbucket for the build status
[15:50:07.480] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:00.783] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:2:0)
[15:51:00.783] Timer tick, asking Buildbucket for the build status
[15:51:01.839] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:01.839] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:4:0) after 1m0s
[15:52:01.887] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:4:0)
[15:52:01.887] Timer tick, asking Buildbucket for the build status
[15:52:02.247] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:02.247] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:5:0) after 1m0s
[15:53:02.265] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:5:0)
[15:53:02.265] Timer tick, asking Buildbucket for the build status
[15:53:02.650] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:02.650] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:6:0) after 1m0s
[15:54:02.641] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:6:0)
[15:54:02.641] Timer tick, asking Buildbucket for the build status
[15:54:03.061] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:03.061] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:7:0) after 1m0s
[15:55:03.106] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:7:0)
[15:55:03.106] Timer tick, asking Buildbucket for the build status
[15:55:03.282] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:03.282] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:8:0) after 1m0s
[15:56:03.304] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:8:0)
[15:56:03.304] Timer tick, asking Buildbucket for the build status
[15:56:03.923] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:03.923] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:9:0) after 1m0s
[15:57:03.947] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:9:0)
[15:57:03.947] Timer tick, asking Buildbucket for the build status
[15:57:05.324] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:05.324] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:10:0) after 1m0s
[15:58:05.340] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:10:0)
[15:58:05.340] Timer tick, asking Buildbucket for the build status
[15:58:06.299] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:06.299] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:11:0) after 1m0s
[15:59:06.319] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:11:0)
[15:59:06.319] Timer tick, asking Buildbucket for the build status
[15:59:06.744] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:06.744] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:12:0) after 1m0s
[16:00:06.911] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:12:0)
[16:00:06.911] Timer tick, asking Buildbucket for the build status
[16:00:07.215] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:07.215] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:13:0) after 1m0s
[16:01:07.325] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:13:0)
[16:01:07.325] Timer tick, asking Buildbucket for the build status
[16:01:08.028] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:08.028] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:14:0) after 1m0s
[16:02:08.042] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:14:0)
[16:02:08.042] Timer tick, asking Buildbucket for the build status
[16:02:09.474] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:09.474] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:15:0) after 1m0s
[16:03:09.585] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:15:0)
[16:03:09.585] Timer tick, asking Buildbucket for the build status
[16:03:12.283] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:12.283] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:16:0) after 1m0s
[16:04:12.308] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:16:0)
[16:04:12.308] Timer tick, asking Buildbucket for the build status
[16:04:12.614] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:12.614] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:17:0) after 1m0s
[16:05:12.651] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:17:0)
[16:05:12.651] Timer tick, asking Buildbucket for the build status
[16:05:13.147] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:13.147] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:18:0) after 1m0s
[16:06:13.150] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:18:0)
[16:06:13.150] Timer tick, asking Buildbucket for the build status
[16:06:13.590] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:13.590] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:19:0) after 1m0s
[16:07:13.673] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:19:0)
[16:07:13.673] Timer tick, asking Buildbucket for the build status
[16:07:14.290] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:14.290] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:20:0) after 1m0s
[16:08:14.319] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:20:0)
[16:08:14.320] Timer tick, asking Buildbucket for the build status
[16:08:14.583] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:14.583] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:21:0) after 1m0s
[16:09:14.602] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:21:0)
[16:09:14.602] Timer tick, asking Buildbucket for the build status
[16:09:14.720] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:14.720] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:22:0) after 1m0s
[16:10:14.739] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:22:0)
[16:10:14.739] Timer tick, asking Buildbucket for the build status
[16:10:14.860] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:14.860] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:23:0) after 1m0s
[16:11:15.017] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:23:0)
[16:11:15.017] Timer tick, asking Buildbucket for the build status
[16:11:16.603] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:16.603] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:24:0) after 1m0s
[16:12:16.620] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:24:0)
[16:12:16.620] Timer tick, asking Buildbucket for the build status
[16:12:16.723] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:16.723] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:25:0) after 1m0s
[16:13:16.773] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:25:0)
[16:13:16.773] Timer tick, asking Buildbucket for the build status
[16:13:17.063] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:17.063] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:26:0) after 1m0s
[16:14:17.088] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:26:0)
[16:14:17.088] Timer tick, asking Buildbucket for the build status
[16:14:22.351] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:22.351] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:27:0) after 1m0s
[16:15:22.374] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:27:0)
[16:15:22.374] Timer tick, asking Buildbucket for the build status
[16:15:22.736] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:22.736] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:28:0) after 1m0s
[16:16:22.755] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:28:0)
[16:16:22.755] Timer tick, asking Buildbucket for the build status
[16:16:23.102] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:23.103] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:29:0) after 1m0s
[16:17:23.186] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:29:0)
[16:17:23.186] Timer tick, asking Buildbucket for the build status
[16:17:23.690] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:23.690] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:30:0) after 1m0s
[16:18:23.705] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:30:0)
[16:18:23.705] Timer tick, asking Buildbucket for the build status
[16:18:24.022] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:24.022] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:31:0) after 1m0s
[16:19:24.142] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:31:0)
[16:19:24.142] Timer tick, asking Buildbucket for the build status
[16:19:25.345] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:25.345] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:32:0) after 1m0s
[16:20:25.374] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:32:0)
[16:20:25.374] Timer tick, asking Buildbucket for the build status
[16:20:25.837] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:25.837] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:33:0) after 1m0s
[16:21:26.045] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:33:0)
[16:21:26.045] Timer tick, asking Buildbucket for the build status
[16:21:27.757] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:27.757] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:34:0) after 1m0s
[16:22:27.807] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:34:0)
[16:22:27.807] Timer tick, asking Buildbucket for the build status
[16:22:27.937] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:27.937] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:35:0) after 1m0s
[16:23:27.975] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:35:0)
[16:23:27.975] Timer tick, asking Buildbucket for the build status
[16:23:28.229] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:28.229] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:36:0) after 1m0s
[16:24:28.351] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:36:0)
[16:24:28.351] Timer tick, asking Buildbucket for the build status
[16:24:57.017] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:57.017] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:37:0) after 1m0s
[16:25:57.382] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:37:0)
[16:25:57.382] Timer tick, asking Buildbucket for the build status
[16:25:58.534] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:58.534] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:38:0) after 1m0s
[16:26:58.769] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:38:0)
[16:26:58.769] Timer tick, asking Buildbucket for the build status
[16:26:58.914] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:58.914] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:39:0) after 1m0s
[16:27:59.015] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:39:0)
[16:27:59.015] Timer tick, asking Buildbucket for the build status
[16:27:59.799] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:59.800] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:40:0) after 1m0s
[16:28:59.951] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:40:0)
[16:28:59.951] Timer tick, asking Buildbucket for the build status
[16:29:02.470] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:02.470] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:41:0) after 1m0s
[16:30:02.510] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:41:0)
[16:30:02.510] Timer tick, asking Buildbucket for the build status
[16:30:02.781] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:02.781] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:42:0) after 1m0s
[16:31:02.875] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:42:0)
[16:31:02.875] Timer tick, asking Buildbucket for the build status
[16:31:03.177] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:03.177] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:43:0) after 1m0s
[16:32:03.249] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:43:0)
[16:32:03.249] Timer tick, asking Buildbucket for the build status
[16:32:03.440] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:03.440] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:44:0) after 1m0s
[16:33:03.438] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:44:0)
[16:33:03.438] Timer tick, asking Buildbucket for the build status
[16:33:06.350] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:06.350] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:45:0) after 1m0s
[16:34:06.384] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:45:0)
[16:34:06.384] Timer tick, asking Buildbucket for the build status
[16:34:06.537] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:06.537] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:46:0) after 1m0s
[16:35:06.566] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:46:0)
[16:35:06.566] Timer tick, asking Buildbucket for the build status
[16:35:12.348] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:12.348] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:47:0) after 1m0s
[16:36:12.389] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:47:0)
[16:36:12.389] Timer tick, asking Buildbucket for the build status
[16:36:14.299] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:14.299] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:48:0) after 1m0s
[16:37:14.346] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:48:0)
[16:37:14.346] Timer tick, asking Buildbucket for the build status
[16:37:14.550] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:14.550] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:49:0) after 1m0s
[16:38:14.809] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:49:0)
[16:38:14.809] Timer tick, asking Buildbucket for the build status
[16:38:15.319] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:15.319] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:50:0) after 1m0s
[16:39:15.552] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:50:0)
[16:39:15.552] Timer tick, asking Buildbucket for the build status
[16:39:16.372] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:16.372] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:51:0) after 1m0s
[16:40:16.425] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:51:0)
[16:40:16.425] Timer tick, asking Buildbucket for the build status
[16:40:16.740] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:16.740] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:52:0) after 1m0s
[16:41:16.755] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:52:0)
[16:41:16.755] Timer tick, asking Buildbucket for the build status
[16:41:17.634] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:17.634] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:53:0) after 1m0s
[16:42:17.631] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:53:0)
[16:42:17.631] Timer tick, asking Buildbucket for the build status
[16:42:17.931] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:17.931] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:54:0) after 1m0s
[16:43:19.397] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:54:0)
[16:43:19.397] Timer tick, asking Buildbucket for the build status
[16:43:19.546] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:19.546] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:55:0) after 1m0s
[16:44:19.606] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:55:0)
[16:44:19.606] Timer tick, asking Buildbucket for the build status
[16:44:19.845] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:19.845] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:56:0) after 1m0s
[16:45:19.866] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:56:0)
[16:45:19.866] Timer tick, asking Buildbucket for the build status
[16:45:20.827] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:20.827] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:57:0) after 1m0s
[16:46:20.852] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:57:0)
[16:46:20.852] Timer tick, asking Buildbucket for the build status
[16:46:21.667] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:21.667] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:58:0) after 1m0s
[16:47:21.681] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:58:0)
[16:47:21.681] Timer tick, asking Buildbucket for the build status
[16:47:22.718] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:22.718] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:59:0) after 1m0s
[16:48:23.044] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:59:0)
[16:48:23.044] Timer tick, asking Buildbucket for the build status
[16:48:23.350] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:23.350] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:60:0) after 1m0s
[16:49:23.429] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:60:0)
[16:49:23.429] Timer tick, asking Buildbucket for the build status
[16:49:23.543] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:23.543] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:61:0) after 1m0s
[16:50:23.568] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:61:0)
[16:50:23.568] Timer tick, asking Buildbucket for the build status
[16:50:24.866] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:24.866] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:62:0) after 1m0s
[16:51:24.962] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:62:0)
[16:51:24.962] Timer tick, asking Buildbucket for the build status
[16:51:26.228] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:26.228] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:63:0) after 1m0s
[16:52:26.243] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:63:0)
[16:52:26.243] Timer tick, asking Buildbucket for the build status
[16:52:27.544] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:27.544] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:64:0) after 1m0s
[16:53:27.654] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:64:0)
[16:53:27.654] Timer tick, asking Buildbucket for the build status
[16:53:27.819] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:27.819] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:65:0) after 1m0s
[16:54:27.838] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:65:0)
[16:54:27.838] Timer tick, asking Buildbucket for the build status
[16:54:28.810] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:28.810] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:66:0) after 1m0s
[16:55:28.938] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:66:0)
[16:55:28.938] Timer tick, asking Buildbucket for the build status
[16:55:30.627] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:30.627] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:67:0) after 1m0s
[16:56:30.656] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:67:0)
[16:56:30.656] Timer tick, asking Buildbucket for the build status
[16:56:30.815] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:30.815] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:68:0) after 1m0s
[16:57:30.868] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:68:0)
[16:57:30.868] Timer tick, asking Buildbucket for the build status
[16:57:31.240] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:31.240] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:69:0) after 1m0s
[16:58:31.247] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:69:0)
[16:58:31.247] Timer tick, asking Buildbucket for the build status
[16:58:31.339] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:31.339] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:70:0) after 1m0s
[16:59:31.387] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:70:0)
[16:59:31.387] Timer tick, asking Buildbucket for the build status
[16:59:31.494] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:31.494] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:71:0) after 1m0s
[17:00:31.581] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:71:0)
[17:00:31.581] Timer tick, asking Buildbucket for the build status
[17:00:31.681] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:31.681] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:72:0) after 1m0s
[17:01:31.687] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:72:0)
[17:01:31.687] Timer tick, asking Buildbucket for the build status
[17:01:31.798] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:31.798] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:73:0) after 1m0s
[17:02:31.846] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:73:0)
[17:02:31.846] Timer tick, asking Buildbucket for the build status
[17:02:34.761] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:34.761] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:74:0) after 1m0s
[17:03:34.795] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:74:0)
[17:03:34.795] Timer tick, asking Buildbucket for the build status
[17:03:34.943] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:34.943] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:75:0) after 1m0s
[17:04:34.950] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:75:0)
[17:04:34.950] Timer tick, asking Buildbucket for the build status
[17:04:35.094] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:35.094] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:76:0) after 1m0s
[17:05:35.172] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:76:0)
[17:05:35.172] Timer tick, asking Buildbucket for the build status
[17:05:35.267] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:35.267] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:77:0) after 1m0s
[17:06:35.245] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:77:0)
[17:06:35.245] Timer tick, asking Buildbucket for the build status
[17:06:37.660] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:37.660] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:78:0) after 1m0s
[17:07:37.791] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:78:0)
[17:07:37.791] Timer tick, asking Buildbucket for the build status
[17:07:37.966] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:37.966] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:79:0) after 1m0s
[17:08:37.983] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:79:0)
[17:08:37.983] Timer tick, asking Buildbucket for the build status
[17:08:38.111] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:38.111] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:80:0) after 1m0s
[17:09:38.163] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:80:0)
[17:09:38.163] Timer tick, asking Buildbucket for the build status
[17:09:39.755] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:39.755] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:81:0) after 1m0s
[17:10:39.795] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:81:0)
[17:10:39.795] Timer tick, asking Buildbucket for the build status
[17:10:39.967] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:39.967] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:82:0) after 1m0s
[17:11:39.956] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:82:0)
[17:11:39.956] Timer tick, asking Buildbucket for the build status
[17:11:41.729] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:41.729] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:83:0) after 1m0s
[17:12:41.782] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:83:0)
[17:12:41.782] Timer tick, asking Buildbucket for the build status
[17:12:42.157] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:42.157] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:84:0) after 1m0s
[17:13:42.348] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:84:0)
[17:13:42.348] Timer tick, asking Buildbucket for the build status
[17:13:43.322] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:43.322] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:85:0) after 1m0s
[17:14:43.544] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:85:0)
[17:14:43.544] Timer tick, asking Buildbucket for the build status
[17:14:44.336] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:44.336] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:86:0) after 1m0s
[17:15:44.445] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:86:0)
[17:15:44.445] Timer tick, asking Buildbucket for the build status
[17:15:46.386] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:46.386] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:87:0) after 1m0s
[17:16:46.578] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:87:0)
[17:16:46.578] Timer tick, asking Buildbucket for the build status
[17:16:51.765] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:51.765] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:88:0) after 1m0s
[17:17:51.821] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:88:0)
[17:17:51.821] Timer tick, asking Buildbucket for the build status
[17:17:53.785] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:53.785] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:89:0) after 1m0s
[17:18:54.207] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:89:0)
[17:18:54.207] Timer tick, asking Buildbucket for the build status
[17:19:04.228] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:04.228] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:90:0) after 1m0s
[17:20:04.308] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:90:0)
[17:20:04.308] Timer tick, asking Buildbucket for the build status
[17:20:06.885] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:06.885] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:91:0) after 1m0s
[17:21:06.911] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:91:0)
[17:21:06.911] Timer tick, asking Buildbucket for the build status
[17:21:09.142] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:09.142] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:92:0) after 1m0s
[17:22:09.214] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:92:0)
[17:22:09.214] Timer tick, asking Buildbucket for the build status
[17:22:13.838] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:13.838] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:93:0) after 1m0s
[17:23:13.927] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:93:0)
[17:23:13.927] Timer tick, asking Buildbucket for the build status
[17:23:14.615] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:14.615] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:94:0) after 1m0s
[17:24:14.637] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:94:0)
[17:24:14.637] Timer tick, asking Buildbucket for the build status
[17:24:15.669] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:15.669] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:95:0) after 1m0s
[17:25:15.930] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:95:0)
[17:25:15.930] Timer tick, asking Buildbucket for the build status
[17:25:16.321] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:16.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:96:0) after 1m0s
[17:26:16.343] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:96:0)
[17:26:16.343] Timer tick, asking Buildbucket for the build status
[17:26:18.783] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:18.783] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:97:0) after 1m0s
[17:27:18.825] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:97:0)
[17:27:18.825] Timer tick, asking Buildbucket for the build status
[17:27:21.707] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:21.707] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:98:0) after 1m0s
[17:28:21.714] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:98:0)
[17:28:21.714] Timer tick, asking Buildbucket for the build status
[17:28:21.851] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:21.851] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:99:0) after 1m0s
[17:29:21.961] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:99:0)
[17:29:21.961] Timer tick, asking Buildbucket for the build status
[17:29:26.035] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:26.035] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:100:0) after 1m0s
[17:30:26.112] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:100:0)
[17:30:26.115] Timer tick, asking Buildbucket for the build status
[17:30:26.940] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:26.940] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:101:0) after 1m0s
[17:31:26.992] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:101:0)
[17:31:26.992] Timer tick, asking Buildbucket for the build status
[17:31:27.770] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:27.770] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:102:0) after 1m0s
[17:32:27.790] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:102:0)
[17:32:27.790] Timer tick, asking Buildbucket for the build status
[17:32:28.164] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:28.164] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:103:0) after 1m0s
[17:33:28.248] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:103:0)
[17:33:28.248] Timer tick, asking Buildbucket for the build status
[17:33:31.406] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:31.406] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:104:0) after 1m0s
[17:34:31.434] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:104:0)
[17:34:31.434] Timer tick, asking Buildbucket for the build status
[17:34:32.375] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:32.375] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:105:0) after 1m0s
[17:35:32.383] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:105:0)
[17:35:32.383] Timer tick, asking Buildbucket for the build status
[17:35:32.551] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:32.551] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:106:0) after 1m0s
[17:36:32.591] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:106:0)
[17:36:32.591] Timer tick, asking Buildbucket for the build status
[17:36:37.052] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:37.052] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:107:0) after 1m0s
[17:37:37.147] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:107:0)
[17:37:37.147] Timer tick, asking Buildbucket for the build status
[17:37:39.891] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:39.891] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:108:0) after 1m0s
[17:38:39.997] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:108:0)
[17:38:39.997] Timer tick, asking Buildbucket for the build status
[17:38:41.851] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:41.851] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:109:0) after 1m0s
[17:39:41.944] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:109:0)
[17:39:41.944] Timer tick, asking Buildbucket for the build status
[17:39:46.402] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:46.402] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:110:0) after 1m0s
[17:40:46.434] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:110:0)
[17:40:46.434] Timer tick, asking Buildbucket for the build status
[17:40:47.013] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:47.013] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:111:0) after 1m0s
[17:41:47.204] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:111:0)
[17:41:47.204] Timer tick, asking Buildbucket for the build status
[17:41:47.536] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:47.536] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:112:0) after 1m0s
[17:42:47.528] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:112:0)
[17:42:47.528] Timer tick, asking Buildbucket for the build status
[17:42:48.355] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:48.355] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:113:0) after 1m0s
[17:43:48.397] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:113:0)
[17:43:48.397] Timer tick, asking Buildbucket for the build status
[17:43:50.657] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:50.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:114:0) after 1m0s
[17:44:50.680] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:114:0)
[17:44:50.680] Timer tick, asking Buildbucket for the build status
[17:44:50.775] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:50.775] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:115:0) after 1m0s
[17:45:50.814] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:115:0)
[17:45:50.814] Timer tick, asking Buildbucket for the build status
[17:45:52.634] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:52.634] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:116:0) after 1m0s
[17:46:52.666] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:116:0)
[17:46:52.666] Timer tick, asking Buildbucket for the build status
[17:46:54.798] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:54.798] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:117:0) after 1m0s
[17:47:54.840] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:117:0)
[17:47:54.840] Timer tick, asking Buildbucket for the build status
[17:47:55.232] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:55.232] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:118:0) after 1m0s
[17:48:55.256] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:118:0)
[17:48:55.256] Timer tick, asking Buildbucket for the build status
[17:48:57.375] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:57.375] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:119:0) after 1m0s
[17:49:57.425] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:119:0)
[17:49:57.425] Timer tick, asking Buildbucket for the build status
[17:50:00.024] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:00.024] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:120:0) after 1m0s
[17:51:00.048] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:120:0)
[17:51:00.048] Timer tick, asking Buildbucket for the build status
[17:51:01.036] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:01.036] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:121:0) after 1m0s
[17:52:01.205] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:121:0)
[17:52:01.205] Timer tick, asking Buildbucket for the build status
[17:52:02.647] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:02.647] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:122:0) after 1m0s
[17:53:02.757] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:122:0)
[17:53:02.757] Timer tick, asking Buildbucket for the build status
[17:53:04.582] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:04.582] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:123:0) after 1m0s
[17:54:04.587] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:123:0)
[17:54:04.587] Timer tick, asking Buildbucket for the build status
[17:54:07.167] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:07.167] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:124:0) after 1m0s
[17:55:07.292] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:124:0)
[17:55:07.292] Timer tick, asking Buildbucket for the build status
[17:55:07.415] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:07.415] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:125:0) after 1m0s
[17:56:07.527] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:125:0)
[17:56:07.527] Timer tick, asking Buildbucket for the build status
[17:56:07.709] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:07.709] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:126:0) after 1m0s
[17:57:07.736] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:126:0)
[17:57:07.736] Timer tick, asking Buildbucket for the build status
[17:57:09.152] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:09.152] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:127:0) after 1m0s
[17:58:09.435] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:127:0)
[17:58:09.435] Timer tick, asking Buildbucket for the build status
[17:58:09.810] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:09.810] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:128:0) after 1m0s
[17:59:10.061] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:128:0)
[17:59:10.061] Timer tick, asking Buildbucket for the build status
[17:59:10.394] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:10.394] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:129:0) after 1m0s
[18:00:10.521] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:129:0)
[18:00:10.521] Timer tick, asking Buildbucket for the build status
[18:00:12.826] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:12.826] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:130:0) after 1m0s
[18:01:13.454] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:130:0)
[18:01:13.454] Timer tick, asking Buildbucket for the build status
[18:01:13.577] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:13.577] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:131:0) after 1m0s
[18:02:13.964] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:131:0)
[18:02:13.964] Timer tick, asking Buildbucket for the build status
[18:02:14.319] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:14.319] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:132:0) after 1m0s
[18:03:14.355] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:132:0)
[18:03:14.355] Timer tick, asking Buildbucket for the build status
[18:03:14.590] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:14.590] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:133:0) after 1m0s
[18:04:14.641] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:133:0)
[18:04:14.641] Timer tick, asking Buildbucket for the build status
[18:04:18.491] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:18.492] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:134:0) after 1m0s
[18:05:18.656] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:134:0)
[18:05:18.656] Timer tick, asking Buildbucket for the build status
[18:05:21.552] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:21.552] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:135:0) after 1m0s
[18:06:21.741] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:135:0)
[18:06:21.741] Timer tick, asking Buildbucket for the build status
[18:06:21.926] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:21.926] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:136:0) after 1m0s
[18:07:22.042] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:136:0)
[18:07:22.042] Timer tick, asking Buildbucket for the build status
[18:07:22.269] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:22.269] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:137:0) after 1m0s
[18:08:22.375] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:137:0)
[18:08:22.375] Timer tick, asking Buildbucket for the build status
[18:08:22.547] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:22.547] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:138:0) after 1m0s
[18:09:22.675] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:138:0)
[18:09:22.675] Timer tick, asking Buildbucket for the build status
[18:09:24.399] Build 8910299748996847488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:24.399] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9075729811015246080:139:0) after 1m0s
[18:10:02.351] Received PubSub notification, asking Buildbucket for the build status
[18:10:04.401] Build 8910299748996847488: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:10:04.401] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1560881400879848",
"created_by": "project:chromium",
"created_ts": "1560873000108320",
"id": "8910299748996847488",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"d39dff09bed394749f9d37c41576d5628a4e7271\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"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-4mnd\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 13866, \"got_angle_revision\": \"2d223552e3fe454420a8fcd936390104009e0ba5\", \"got_dawn_revision\": \"872c1d7fe98a41359364e96609231f9700160d7a\", \"got_nacl_revision\": \"c4cf6f0621b94cca4053b19fa01ef329f5663880\", \"got_revision\": \"d39dff09bed394749f9d37c41576d5628a4e7271\", \"got_revision_cp\": \"refs/heads/master@{#670087}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"59919fb14ac4ae2ab99563f121a525271b21188d\", \"got_v8_revision_cp\": \"refs/heads/7.7.96@{#1}\", \"got_webrtc_revision\": \"61d8ee10b61171cd4b90ed36c178f223f99256cf\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28301}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"d39dff09bed394749f9d37c41576d5628a4e7271\"}, \"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-4mnd\"], \"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\": [\"4339-6285c57\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1560873003198343",
"status": "COMPLETED",
"status_changed_ts": "1560881401660792",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13866",
"builder:Windows deterministic",
"buildset:commit/git/d39dff09bed394749f9d37c41576d5628a4e7271",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d39dff09bed394749f9d37c41576d5628a4e7271",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9075729811015246080",
"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/8910299748996847488/+/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:458419682ffc6410",
"user_agent:luci-scheduler"
],
"updated_ts": "1560881401746281",
"url": "https://ci.chromium.org/b/8910299748996847488",
"utcnow_ts": "1560881404333542"
}
}
[18:10:04.401] Invocation finished in 2h20m5.601638961s with status SUCCEEDED