[19:44:58.208] New invocation is queued and will start shortly
[19:44:59.412] Starting the invocation (attempt 1)
[19:44:59.448] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:44:59.448] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:44:59.448] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9108782919468933008",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"607c9b732393d3ba6c3de8c7b552ff3145527655\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9108782919468933008",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/607c9b732393d3ba6c3de8c7b552ff3145527655",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/607c9b732393d3ba6c3de8c7b552ff3145527655",
"gitiles_ref:refs/heads/master"
]
}
[19:45:00.527] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1529351099656881",
"experimental": true,
"id": "8943352857285730256",
"lease_expiration_ts": "1529387100245477",
"lease_key": "112221543",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"607c9b732393d3ba6c3de8c7b552ff3145527655\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1529351100435444",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/62",
"builder:Windows deterministic",
"buildset:commit/git/607c9b732393d3ba6c3de8c7b552ff3145527655",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/607c9b732393d3ba6c3de8c7b552ff3145527655",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9108782919468933008",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Windows deterministic/62",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8943352857285730256",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:2a8c11ce5d733cead8b6ce80c31bfed950c5e864",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/607c9b732393d3ba6c3de8c7b552ff3145527655",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/607c9b732393d3ba6c3de8c7b552ff3145527655",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8943352857285730256/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows-10",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:60",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9108782919468933008",
"swarming_tag:scheduler_job_id:chromium/Windows deterministic",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:b7b54ba3eb083449ee9e558beebd546d22f2dbcf",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3e2d3f5ecea4d310",
"user_agent:luci-scheduler"
],
"updated_ts": "1529351100435559",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/62",
"utcnow_ts": "1529351100514677"
}
}
[19:45:00.527] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/62
[19:45:00.527] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:2:0) after 1m0s
[19:46:00.697] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:2:0)
[19:46:00.697] Timer tick, asking Buildbucket for the build status
[19:46:00.799] Build 8943352857285730256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:46:00.799] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:3:0) after 1m0s
[19:47:00.986] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:3:0)
[19:47:00.986] Timer tick, asking Buildbucket for the build status
[19:47:01.053] Build 8943352857285730256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:47:01.053] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:4:0) after 1m0s
[19:48:01.173] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:4:0)
[19:48:01.173] Timer tick, asking Buildbucket for the build status
[19:48:01.438] Build 8943352857285730256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:48:01.438] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:5:0) after 1m0s
[19:49:01.472] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:5:0)
[19:49:01.472] Timer tick, asking Buildbucket for the build status
[19:49:01.562] Build 8943352857285730256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:49:01.562] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:6:0) after 1m0s
[19:50:01.601] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:6:0)
[19:50:01.601] Timer tick, asking Buildbucket for the build status
[19:50:03.283] Build 8943352857285730256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:50:03.283] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:7:0) after 1m0s
[19:51:03.303] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:7:0)
[19:51:03.303] Timer tick, asking Buildbucket for the build status
[19:51:03.340] Build 8943352857285730256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:51:03.340] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:8:0) after 1m0s
[19:52:03.394] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:8:0)
[19:52:03.394] Timer tick, asking Buildbucket for the build status
[19:52:05.271] Build 8943352857285730256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:52:05.271] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:9:0) after 1m0s
[19:53:05.290] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:9:0)
[19:53:05.290] Timer tick, asking Buildbucket for the build status
[19:53:05.328] Build 8943352857285730256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:53:05.328] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:10:0) after 1m0s
[19:54:05.372] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:10:0)
[19:54:05.372] Timer tick, asking Buildbucket for the build status
[19:54:05.409] Build 8943352857285730256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:54:05.409] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:11:0) after 1m0s
[19:55:05.429] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:11:0)
[19:55:05.429] Timer tick, asking Buildbucket for the build status
[19:55:05.704] Build 8943352857285730256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:55:05.704] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:12:0) after 1m0s
[19:56:05.726] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:12:0)
[19:56:05.726] Timer tick, asking Buildbucket for the build status
[19:56:05.785] Build 8943352857285730256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:56:05.785] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:13:0) after 1m0s
[19:57:05.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:13:0)
[19:57:05.815] Timer tick, asking Buildbucket for the build status
[19:57:05.850] Build 8943352857285730256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:57:05.850] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:14:0) after 1m0s
[19:58:05.881] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:14:0)
[19:58:05.881] Timer tick, asking Buildbucket for the build status
[19:58:05.996] Build 8943352857285730256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:58:05.996] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:15:0) after 1m0s
[19:59:06.023] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:15:0)
[19:59:06.023] Timer tick, asking Buildbucket for the build status
[19:59:06.056] Build 8943352857285730256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:59:06.056] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:16:0) after 1m0s
[20:00:06.114] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:16:0)
[20:00:06.114] Timer tick, asking Buildbucket for the build status
[20:00:06.180] Build 8943352857285730256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:00:06.180] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:17:0) after 1m0s
[20:01:06.309] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:17:0)
[20:01:06.309] Timer tick, asking Buildbucket for the build status
[20:01:06.342] Build 8943352857285730256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:01:06.342] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:18:0) after 1m0s
[20:02:06.363] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:18:0)
[20:02:06.363] Timer tick, asking Buildbucket for the build status
[20:02:06.429] Build 8943352857285730256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:02:06.429] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:19:0) after 1m0s
[20:03:06.456] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:19:0)
[20:03:06.456] Timer tick, asking Buildbucket for the build status
[20:03:06.579] Build 8943352857285730256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:03:06.579] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:20:0) after 1m0s
[20:04:06.618] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:20:0)
[20:04:06.618] Timer tick, asking Buildbucket for the build status
[20:04:06.676] Build 8943352857285730256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:04:06.676] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:21:0) after 1m0s
[20:05:06.697] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:21:0)
[20:05:06.697] Timer tick, asking Buildbucket for the build status
[20:05:06.747] Build 8943352857285730256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:05:06.747] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:22:0) after 1m0s
[20:06:06.793] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:22:0)
[20:06:06.793] Timer tick, asking Buildbucket for the build status
[20:06:06.858] Build 8943352857285730256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:06:06.858] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:23:0) after 1m0s
[20:07:06.883] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:23:0)
[20:07:06.883] Timer tick, asking Buildbucket for the build status
[20:07:06.962] Build 8943352857285730256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:07:06.962] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:24:0) after 1m0s
[20:08:06.984] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:24:0)
[20:08:06.984] Timer tick, asking Buildbucket for the build status
[20:08:07.022] Build 8943352857285730256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:08:07.022] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:25:0) after 1m0s
[20:09:07.064] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:25:0)
[20:09:07.064] Timer tick, asking Buildbucket for the build status
[20:09:07.134] Build 8943352857285730256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:09:07.134] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:26:0) after 1m0s
[20:10:03.346] Received PubSub notification, asking Buildbucket for the build status
[20:10:03.386] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:07.159] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:26:0)
[20:10:07.159] Timer tick, asking Buildbucket for the build status
[20:10:07.210] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:07.210] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:28:0) after 1m0s
[20:11:07.253] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:28:0)
[20:11:07.253] Timer tick, asking Buildbucket for the build status
[20:11:07.289] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:07.289] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:29:0) after 1m0s
[20:12:07.365] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:29:0)
[20:12:07.365] Timer tick, asking Buildbucket for the build status
[20:12:07.566] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:07.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:30:0) after 1m0s
[20:13:07.594] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:30:0)
[20:13:07.594] Timer tick, asking Buildbucket for the build status
[20:13:07.668] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:07.668] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:31:0) after 1m0s
[20:14:07.691] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:31:0)
[20:14:07.691] Timer tick, asking Buildbucket for the build status
[20:14:07.992] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:07.992] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:32:0) after 1m0s
[20:15:08.013] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:32:0)
[20:15:08.013] Timer tick, asking Buildbucket for the build status
[20:15:08.048] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:08.048] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:33:0) after 1m0s
[20:16:08.084] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:33:0)
[20:16:08.084] Timer tick, asking Buildbucket for the build status
[20:16:08.217] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:08.217] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:34:0) after 1m0s
[20:17:08.253] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:34:0)
[20:17:08.253] Timer tick, asking Buildbucket for the build status
[20:17:08.300] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:08.300] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:35:0) after 1m0s
[20:18:08.323] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:35:0)
[20:18:08.323] Timer tick, asking Buildbucket for the build status
[20:18:08.362] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:08.362] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:36:0) after 1m0s
[20:19:08.392] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:36:0)
[20:19:08.392] Timer tick, asking Buildbucket for the build status
[20:19:08.460] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:08.460] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:37:0) after 1m0s
[20:20:08.483] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:37:0)
[20:20:08.483] Timer tick, asking Buildbucket for the build status
[20:20:08.529] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:08.529] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:38:0) after 1m0s
[20:21:08.557] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:38:0)
[20:21:08.557] Timer tick, asking Buildbucket for the build status
[20:21:08.629] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:08.629] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:39:0) after 1m0s
[20:22:08.656] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:39:0)
[20:22:08.656] Timer tick, asking Buildbucket for the build status
[20:22:08.748] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:08.748] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:40:0) after 1m0s
[20:23:08.780] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:40:0)
[20:23:08.780] Timer tick, asking Buildbucket for the build status
[20:23:08.839] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:08.839] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:41:0) after 1m0s
[20:24:08.865] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:41:0)
[20:24:08.865] Timer tick, asking Buildbucket for the build status
[20:24:08.899] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:08.899] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:42:0) after 1m0s
[20:25:08.927] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:42:0)
[20:25:08.927] Timer tick, asking Buildbucket for the build status
[20:25:08.960] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:08.960] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:43:0) after 1m0s
[20:26:08.981] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:43:0)
[20:26:08.981] Timer tick, asking Buildbucket for the build status
[20:26:09.158] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:09.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:44:0) after 1m0s
[20:27:09.207] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:44:0)
[20:27:09.207] Timer tick, asking Buildbucket for the build status
[20:27:09.241] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:09.241] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:45:0) after 1m0s
[20:28:09.262] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:45:0)
[20:28:09.262] Timer tick, asking Buildbucket for the build status
[20:28:09.435] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:09.435] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:46:0) after 1m0s
[20:29:09.544] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:46:0)
[20:29:09.544] Timer tick, asking Buildbucket for the build status
[20:29:09.646] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:09.646] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:47:0) after 1m0s
[20:30:09.668] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:47:0)
[20:30:09.668] Timer tick, asking Buildbucket for the build status
[20:30:09.896] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:09.896] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:48:0) after 1m0s
[20:31:09.931] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:48:0)
[20:31:09.931] Timer tick, asking Buildbucket for the build status
[20:31:09.972] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:09.972] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:49:0) after 1m0s
[20:32:10.040] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:49:0)
[20:32:10.040] Timer tick, asking Buildbucket for the build status
[20:32:10.137] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:10.137] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:50:0) after 1m0s
[20:33:10.173] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:50:0)
[20:33:10.173] Timer tick, asking Buildbucket for the build status
[20:33:10.229] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:10.229] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:51:0) after 1m0s
[20:34:10.255] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:51:0)
[20:34:10.255] Timer tick, asking Buildbucket for the build status
[20:34:10.289] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:10.289] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:52:0) after 1m0s
[20:35:10.315] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:52:0)
[20:35:10.315] Timer tick, asking Buildbucket for the build status
[20:35:10.387] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:10.387] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:53:0) after 1m0s
[20:36:10.483] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:53:0)
[20:36:10.483] Timer tick, asking Buildbucket for the build status
[20:36:10.523] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:10.523] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:54:0) after 1m0s
[20:37:10.566] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:54:0)
[20:37:10.566] Timer tick, asking Buildbucket for the build status
[20:37:10.620] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:10.620] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:55:0) after 1m0s
[20:38:10.647] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:55:0)
[20:38:10.647] Timer tick, asking Buildbucket for the build status
[20:38:10.752] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:10.752] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:56:0) after 1m0s
[20:39:10.779] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:56:0)
[20:39:10.779] Timer tick, asking Buildbucket for the build status
[20:39:11.002] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:11.002] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:57:0) after 1m0s
[20:40:11.025] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:57:0)
[20:40:11.025] Timer tick, asking Buildbucket for the build status
[20:40:11.089] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:11.089] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:58:0) after 1m0s
[20:41:11.177] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:58:0)
[20:41:11.177] Timer tick, asking Buildbucket for the build status
[20:41:11.339] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:11.339] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:59:0) after 1m0s
[20:42:11.360] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:59:0)
[20:42:11.360] Timer tick, asking Buildbucket for the build status
[20:42:11.425] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:11.425] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:60:0) after 1m0s
[20:43:11.446] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:60:0)
[20:43:11.446] Timer tick, asking Buildbucket for the build status
[20:43:11.533] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:11.533] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:61:0) after 1m0s
[20:44:11.553] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:61:0)
[20:44:11.553] Timer tick, asking Buildbucket for the build status
[20:44:11.632] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:11.632] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:62:0) after 1m0s
[20:45:11.655] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:62:0)
[20:45:11.655] Timer tick, asking Buildbucket for the build status
[20:45:12.034] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:12.034] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:63:0) after 1m0s
[20:46:12.086] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:63:0)
[20:46:12.086] Timer tick, asking Buildbucket for the build status
[20:46:12.180] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:12.180] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:64:0) after 1m0s
[20:47:12.292] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:64:0)
[20:47:12.292] Timer tick, asking Buildbucket for the build status
[20:47:12.388] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:12.388] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:65:0) after 1m0s
[20:48:12.444] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:65:0)
[20:48:12.444] Timer tick, asking Buildbucket for the build status
[20:48:12.481] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:12.481] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:66:0) after 1m0s
[20:49:12.534] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:66:0)
[20:49:12.534] Timer tick, asking Buildbucket for the build status
[20:49:12.593] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:12.593] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:67:0) after 1m0s
[20:50:12.645] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:67:0)
[20:50:12.645] Timer tick, asking Buildbucket for the build status
[20:50:12.915] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:12.915] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:68:0) after 1m0s
[20:51:12.995] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:68:0)
[20:51:12.995] Timer tick, asking Buildbucket for the build status
[20:51:13.030] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:13.030] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:69:0) after 1m0s
[20:52:13.053] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:69:0)
[20:52:13.053] Timer tick, asking Buildbucket for the build status
[20:52:13.153] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:13.153] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:70:0) after 1m0s
[20:53:13.195] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:70:0)
[20:53:13.195] Timer tick, asking Buildbucket for the build status
[20:53:13.232] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:13.232] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:71:0) after 1m0s
[20:54:13.274] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:71:0)
[20:54:13.274] Timer tick, asking Buildbucket for the build status
[20:54:13.337] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:13.337] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:72:0) after 1m0s
[20:55:13.466] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:72:0)
[20:55:13.466] Timer tick, asking Buildbucket for the build status
[20:55:13.542] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:13.542] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:73:0) after 1m0s
[20:56:13.568] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:73:0)
[20:56:13.568] Timer tick, asking Buildbucket for the build status
[20:56:13.713] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:13.713] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:74:0) after 1m0s
[20:57:13.737] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:74:0)
[20:57:13.737] Timer tick, asking Buildbucket for the build status
[20:57:13.952] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:13.952] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:75:0) after 1m0s
[20:58:13.975] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:75:0)
[20:58:13.975] Timer tick, asking Buildbucket for the build status
[20:58:14.012] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:14.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:76:0) after 1m0s
[20:59:14.044] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:76:0)
[20:59:14.044] Timer tick, asking Buildbucket for the build status
[20:59:14.105] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:14.105] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:77:0) after 1m0s
[21:00:14.246] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:77:0)
[21:00:14.246] Timer tick, asking Buildbucket for the build status
[21:00:14.359] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:14.359] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:78:0) after 1m0s
[21:01:14.398] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:78:0)
[21:01:14.398] Timer tick, asking Buildbucket for the build status
[21:01:14.465] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:14.465] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:79:0) after 1m0s
[21:02:14.492] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:79:0)
[21:02:14.492] Timer tick, asking Buildbucket for the build status
[21:02:14.543] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:14.543] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:80:0) after 1m0s
[21:03:14.584] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:80:0)
[21:03:14.584] Timer tick, asking Buildbucket for the build status
[21:03:14.640] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:14.640] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:81:0) after 1m0s
[21:04:14.772] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:81:0)
[21:04:14.772] Timer tick, asking Buildbucket for the build status
[21:04:16.227] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:16.227] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:82:0) after 1m0s
[21:05:16.252] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:82:0)
[21:05:16.252] Timer tick, asking Buildbucket for the build status
[21:05:16.436] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:16.436] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:83:0) after 1m0s
[21:06:16.461] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:83:0)
[21:06:16.461] Timer tick, asking Buildbucket for the build status
[21:06:16.561] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:16.561] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:84:0) after 1m0s
[21:07:16.627] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:84:0)
[21:07:16.627] Timer tick, asking Buildbucket for the build status
[21:07:16.774] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:16.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:85:0) after 1m0s
[21:08:16.851] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:85:0)
[21:08:16.851] Timer tick, asking Buildbucket for the build status
[21:08:16.958] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:16.958] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:86:0) after 1m0s
[21:09:16.988] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:86:0)
[21:09:16.988] Timer tick, asking Buildbucket for the build status
[21:09:17.048] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:17.048] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:87:0) after 1m0s
[21:10:17.076] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:87:0)
[21:10:17.076] Timer tick, asking Buildbucket for the build status
[21:10:17.151] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:17.151] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:88:0) after 1m0s
[21:11:17.178] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:88:0)
[21:11:17.178] Timer tick, asking Buildbucket for the build status
[21:11:17.259] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:17.259] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:89:0) after 1m0s
[21:12:17.350] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:89:0)
[21:12:17.350] Timer tick, asking Buildbucket for the build status
[21:12:17.480] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:17.480] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:90:0) after 1m0s
[21:13:17.547] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:90:0)
[21:13:17.547] Timer tick, asking Buildbucket for the build status
[21:13:17.698] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:17.698] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:91:0) after 1m0s
[21:14:17.722] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:91:0)
[21:14:17.722] Timer tick, asking Buildbucket for the build status
[21:14:17.792] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:17.792] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:92:0) after 1m0s
[21:15:17.830] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:92:0)
[21:15:17.830] Timer tick, asking Buildbucket for the build status
[21:15:17.923] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:17.923] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:93:0) after 1m0s
[21:16:17.990] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:93:0)
[21:16:17.990] Timer tick, asking Buildbucket for the build status
[21:16:18.100] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:18.100] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:94:0) after 1m0s
[21:17:18.124] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:94:0)
[21:17:18.124] Timer tick, asking Buildbucket for the build status
[21:17:18.211] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:18.211] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:95:0) after 1m0s
[21:18:18.241] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:95:0)
[21:18:18.241] Timer tick, asking Buildbucket for the build status
[21:18:18.280] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:18.280] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:96:0) after 1m0s
[21:19:18.325] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:96:0)
[21:19:18.325] Timer tick, asking Buildbucket for the build status
[21:19:18.394] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:18.394] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:97:0) after 1m0s
[21:20:18.450] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:97:0)
[21:20:18.450] Timer tick, asking Buildbucket for the build status
[21:20:18.510] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:18.510] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:98:0) after 1m0s
[21:21:18.539] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:98:0)
[21:21:18.539] Timer tick, asking Buildbucket for the build status
[21:21:18.575] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:18.575] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:99:0) after 1m0s
[21:22:18.617] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:99:0)
[21:22:18.617] Timer tick, asking Buildbucket for the build status
[21:22:18.743] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:18.743] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:100:0) after 1m0s
[21:23:18.770] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:100:0)
[21:23:18.770] Timer tick, asking Buildbucket for the build status
[21:23:18.952] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:18.952] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:101:0) after 1m0s
[21:24:18.973] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:101:0)
[21:24:18.973] Timer tick, asking Buildbucket for the build status
[21:24:19.104] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:19.104] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:102:0) after 1m0s
[21:25:19.197] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:102:0)
[21:25:19.197] Timer tick, asking Buildbucket for the build status
[21:25:19.227] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:19.227] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:103:0) after 1m0s
[21:26:19.269] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:103:0)
[21:26:19.269] Timer tick, asking Buildbucket for the build status
[21:26:19.343] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:19.343] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:104:0) after 1m0s
[21:27:19.367] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:104:0)
[21:27:19.367] Timer tick, asking Buildbucket for the build status
[21:27:19.439] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:19.439] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:105:0) after 1m0s
[21:28:19.466] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:105:0)
[21:28:19.466] Timer tick, asking Buildbucket for the build status
[21:28:19.644] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:19.644] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:106:0) after 1m0s
[21:29:19.679] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:106:0)
[21:29:19.679] Timer tick, asking Buildbucket for the build status
[21:29:19.784] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:19.784] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:107:0) after 1m0s
[21:30:19.814] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:107:0)
[21:30:19.814] Timer tick, asking Buildbucket for the build status
[21:30:19.860] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:19.860] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:108:0) after 1m0s
[21:31:19.886] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:108:0)
[21:31:19.886] Timer tick, asking Buildbucket for the build status
[21:31:19.921] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:19.921] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:109:0) after 1m0s
[21:32:19.945] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:109:0)
[21:32:19.945] Timer tick, asking Buildbucket for the build status
[21:32:19.978] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:19.978] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:110:0) after 1m0s
[21:33:20.010] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:110:0)
[21:33:20.010] Timer tick, asking Buildbucket for the build status
[21:33:20.061] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:20.061] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:111:0) after 1m0s
[21:34:20.284] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:111:0)
[21:34:20.284] Timer tick, asking Buildbucket for the build status
[21:34:20.375] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:20.375] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:112:0) after 1m0s
[21:35:20.460] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:112:0)
[21:35:20.460] Timer tick, asking Buildbucket for the build status
[21:35:20.527] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:20.527] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:113:0) after 1m0s
[21:36:20.563] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:113:0)
[21:36:20.563] Timer tick, asking Buildbucket for the build status
[21:36:20.736] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:20.736] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:114:0) after 1m0s
[21:37:20.769] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:114:0)
[21:37:20.769] Timer tick, asking Buildbucket for the build status
[21:37:20.839] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:20.839] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:115:0) after 1m0s
[21:38:20.869] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:115:0)
[21:38:20.869] Timer tick, asking Buildbucket for the build status
[21:38:21.034] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:21.034] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:116:0) after 1m0s
[21:39:21.064] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:116:0)
[21:39:21.064] Timer tick, asking Buildbucket for the build status
[21:39:21.101] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:21.102] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:117:0) after 1m0s
[21:40:21.128] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:117:0)
[21:40:21.128] Timer tick, asking Buildbucket for the build status
[21:40:21.181] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:21.181] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:118:0) after 1m0s
[21:41:21.212] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:118:0)
[21:41:21.212] Timer tick, asking Buildbucket for the build status
[21:41:21.291] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:21.291] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:119:0) after 1m0s
[21:42:21.321] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:119:0)
[21:42:21.321] Timer tick, asking Buildbucket for the build status
[21:42:21.358] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:21.358] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:120:0) after 1m0s
[21:43:21.390] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:120:0)
[21:43:21.390] Timer tick, asking Buildbucket for the build status
[21:43:21.504] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:21.504] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:121:0) after 1m0s
[21:44:21.726] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:121:0)
[21:44:21.726] Timer tick, asking Buildbucket for the build status
[21:44:22.232] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:22.232] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:122:0) after 1m0s
[21:45:22.316] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:122:0)
[21:45:22.316] Timer tick, asking Buildbucket for the build status
[21:45:22.374] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:22.374] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:123:0) after 1m0s
[21:46:22.504] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:123:0)
[21:46:22.504] Timer tick, asking Buildbucket for the build status
[21:46:22.742] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:22.742] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:124:0) after 1m0s
[21:47:22.766] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:124:0)
[21:47:22.766] Timer tick, asking Buildbucket for the build status
[21:47:22.822] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:22.822] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:125:0) after 1m0s
[21:48:22.867] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:125:0)
[21:48:22.867] Timer tick, asking Buildbucket for the build status
[21:48:22.941] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:22.941] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:126:0) after 1m0s
[21:49:22.969] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:126:0)
[21:49:22.969] Timer tick, asking Buildbucket for the build status
[21:49:23.006] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:23.006] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:127:0) after 1m0s
[21:50:23.047] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:127:0)
[21:50:23.047] Timer tick, asking Buildbucket for the build status
[21:50:23.203] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:23.203] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:128:0) after 1m0s
[21:51:23.231] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:128:0)
[21:51:23.231] Timer tick, asking Buildbucket for the build status
[21:51:23.333] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:23.333] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:129:0) after 1m0s
[21:52:23.361] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:129:0)
[21:52:23.361] Timer tick, asking Buildbucket for the build status
[21:52:23.439] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:23.439] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:130:0) after 1m0s
[21:53:23.507] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:130:0)
[21:53:23.507] Timer tick, asking Buildbucket for the build status
[21:53:23.553] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:23.553] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:131:0) after 1m0s
[21:54:23.618] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:131:0)
[21:54:23.618] Timer tick, asking Buildbucket for the build status
[21:54:23.699] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:23.699] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:132:0) after 1m0s
[21:55:23.794] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:132:0)
[21:55:23.794] Timer tick, asking Buildbucket for the build status
[21:55:23.866] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:23.866] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:133:0) after 1m0s
[21:56:24.126] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:133:0)
[21:56:24.126] Timer tick, asking Buildbucket for the build status
[21:56:24.204] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:24.204] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:134:0) after 1m0s
[21:57:24.265] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:134:0)
[21:57:24.265] Timer tick, asking Buildbucket for the build status
[21:57:24.459] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:24.459] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:135:0) after 1m0s
[21:58:24.523] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:135:0)
[21:58:24.523] Timer tick, asking Buildbucket for the build status
[21:58:24.594] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:24.594] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:136:0) after 1m0s
[21:59:24.621] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:136:0)
[21:59:24.621] Timer tick, asking Buildbucket for the build status
[21:59:24.657] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:24.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:137:0) after 1m0s
[22:00:24.694] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:137:0)
[22:00:24.694] Timer tick, asking Buildbucket for the build status
[22:00:24.848] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:24.848] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:138:0) after 1m0s
[22:01:24.895] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:138:0)
[22:01:24.896] Timer tick, asking Buildbucket for the build status
[22:01:24.932] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:24.932] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:139:0) after 1m0s
[22:02:25.001] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:139:0)
[22:02:25.001] Timer tick, asking Buildbucket for the build status
[22:02:25.065] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:25.065] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:140:0) after 1m0s
[22:03:25.120] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:140:0)
[22:03:25.120] Timer tick, asking Buildbucket for the build status
[22:03:25.422] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:25.422] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:141:0) after 1m0s
[22:04:25.689] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:141:0)
[22:04:25.689] Timer tick, asking Buildbucket for the build status
[22:04:25.766] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:25.766] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:142:0) after 1m0s
[22:05:25.792] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:142:0)
[22:05:25.792] Timer tick, asking Buildbucket for the build status
[22:05:25.872] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:25.872] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:143:0) after 1m0s
[22:06:25.896] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:143:0)
[22:06:25.896] Timer tick, asking Buildbucket for the build status
[22:06:25.975] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:25.975] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:144:0) after 1m0s
[22:07:26.007] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:144:0)
[22:07:26.007] Timer tick, asking Buildbucket for the build status
[22:07:26.154] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:26.154] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:145:0) after 1m0s
[22:08:26.184] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:145:0)
[22:08:26.184] Timer tick, asking Buildbucket for the build status
[22:08:26.272] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:26.272] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:146:0) after 1m0s
[22:09:26.313] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:146:0)
[22:09:26.313] Timer tick, asking Buildbucket for the build status
[22:09:26.354] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:26.354] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:147:0) after 1m0s
[22:10:26.486] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:147:0)
[22:10:26.486] Timer tick, asking Buildbucket for the build status
[22:10:26.754] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:26.754] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:148:0) after 1m0s
[22:11:26.786] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:148:0)
[22:11:26.786] Timer tick, asking Buildbucket for the build status
[22:11:26.892] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:26.892] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:149:0) after 1m0s
[22:12:26.920] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:149:0)
[22:12:26.920] Timer tick, asking Buildbucket for the build status
[22:12:26.954] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:26.954] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:150:0) after 1m0s
[22:13:26.996] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:150:0)
[22:13:26.996] Timer tick, asking Buildbucket for the build status
[22:13:27.096] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:27.096] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:151:0) after 1m0s
[22:14:27.123] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:151:0)
[22:14:27.123] Timer tick, asking Buildbucket for the build status
[22:14:27.155] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:27.155] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:152:0) after 1m0s
[22:15:27.179] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:152:0)
[22:15:27.179] Timer tick, asking Buildbucket for the build status
[22:15:27.270] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:27.270] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:153:0) after 1m0s
[22:16:27.296] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:153:0)
[22:16:27.296] Timer tick, asking Buildbucket for the build status
[22:16:27.337] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:27.337] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:154:0) after 1m0s
[22:17:27.366] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:154:0)
[22:17:27.366] Timer tick, asking Buildbucket for the build status
[22:17:27.593] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:27.593] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:155:0) after 1m0s
[22:18:27.634] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:155:0)
[22:18:27.634] Timer tick, asking Buildbucket for the build status
[22:18:28.290] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:28.290] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:156:0) after 1m0s
[22:19:28.324] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:156:0)
[22:19:28.324] Timer tick, asking Buildbucket for the build status
[22:19:28.362] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:28.362] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:157:0) after 1m0s
[22:20:28.414] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:157:0)
[22:20:28.415] Timer tick, asking Buildbucket for the build status
[22:20:28.465] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:28.465] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:158:0) after 1m0s
[22:21:28.497] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:158:0)
[22:21:28.497] Timer tick, asking Buildbucket for the build status
[22:21:28.674] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:28.674] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:159:0) after 1m0s
[22:22:28.702] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:159:0)
[22:22:28.702] Timer tick, asking Buildbucket for the build status
[22:22:28.869] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:28.869] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:160:0) after 1m0s
[22:23:28.894] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:160:0)
[22:23:28.894] Timer tick, asking Buildbucket for the build status
[22:23:28.930] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:28.930] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:161:0) after 1m0s
[22:24:29.002] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:161:0)
[22:24:29.002] Timer tick, asking Buildbucket for the build status
[22:24:29.073] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:29.073] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:162:0) after 1m0s
[22:25:29.104] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:162:0)
[22:25:29.104] Timer tick, asking Buildbucket for the build status
[22:25:30.044] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:30.044] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:163:0) after 1m0s
[22:26:30.113] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:163:0)
[22:26:30.113] Timer tick, asking Buildbucket for the build status
[22:26:30.207] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:30.207] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:164:0) after 1m0s
[22:27:30.240] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:164:0)
[22:27:30.240] Timer tick, asking Buildbucket for the build status
[22:27:30.290] Build 8943352857285730256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:30.290] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9108782919468933008:165:0) after 1m0s
[22:27:32.703] Received PubSub notification, asking Buildbucket for the build status
[22:27:32.765] Build 8943352857285730256: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[22:27:32.766] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1529360851315631",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1529351099656881",
"experimental": true,
"failure_reason": "INFRA_FAILURE",
"id": "8943352857285730256",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"607c9b732393d3ba6c3de8c7b552ff3145527655\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"ui\": {\"info\": \"Failure compare_build_artifacts\\nFailure Uncaught Exception\"}, \"build_run_result\": {\"infraFailure\": {\"text\": \"recipe infra failure: Uncaught Exception: AttributeError(\\\"'NoneType' object has no attribute 'itervalues'\\\",)\", \"bootstrapperCallStack\": [], \"type\": \"RECIPE_INFRA_FAILURE\"}, \"recipeResult\": {\"failure\": {\"exception\": {\"traceback\": [\"Traceback (most recent call last):\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\recipe_engine\\\\recipe_engine\\\\run.py\\\", line 287, in _new_run\", \" recipe_result = recipe_script.run(api, self.properties, self.environ)\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\recipe_engine\\\\recipe_engine\\\\loader.py\\\", line 98, in run\", \" self.run_steps, properties, environ, self.PROPERTIES, api=api)\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\recipe_engine\\\\recipe_engine\\\\loader.py\\\", line 626, in invoke_with_properties\", \" arg_names, **additional_args)\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\recipe_engine\\\\recipe_engine\\\\loader.py\\\", line 589, in _invoke_with_properties\", \" return callable_obj(*props, **additional_args)\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\build\\\\scripts/slave\\\\recipes\\\\swarming/deterministic_build.py\\\", line 194, in RunSteps\", \" str(api.chromium.output_dir).rstrip('\\\\\\\\/') + '.2')\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\recipe_engine\\\\recipe_engine\\\\recipe_api.py\\\", line 759, in _inner\", \" return func(*a, **kw)\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\build\\\\scripts/slave\\\\recipe_modules\\\\isolate\\\\api.py\\\", line 350, in compare_build_artifacts\", \" self.archive_differences(first_dir, second_dir, step_result.json.output)\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\recipe_engine\\\\recipe_engine\\\\recipe_api.py\\\", line 759, in _inner\", \" return func(*a, **kw)\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\build\\\\scripts/slave\\\\recipe_modules\\\\isolate\\\\api.py\\\", line 302, in archive_differences\", \" diffs = list(itertools.chain.from_iterable(values.itervalues()))\", \"AttributeError: 'NoneType' object has no attribute 'itervalues'\"]}, \"humanReason\": \"Uncaught Exception: AttributeError(\\\"'NoneType' object has no attribute 'itervalues'\\\",)\"}}, \"recipeExitCode\": {\"value\": 1}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"repository\": \"\", \"revision\": \"\"}, \"annotationUrl\": \"logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8943352857285730256/+/annotations\"}, \"swarming\": {\"task_result\": {\"cipd_pins\": {\"packages\": [{\"path\": \".\", \"version\": \"737e54de0d944161894df5c03259949dc83ca7af\", \"package_name\": \"infra/tools/luci/kitchen/windows-amd64\"}, {\"path\": \"cipd_bin_packages\", \"version\": \"74e5c42ab36419642b61f6bf55295e28914a3c74\", \"package_name\": \"infra/git/windows-amd64\"}, {\"path\": \"cipd_bin_packages\", \"version\": \"1ba7d485930b05eb07f6bc7724447d6a7c22a6b6\", \"package_name\": \"infra/python/cpython/windows-amd64\"}, {\"path\": \"cipd_bin_packages\", \"version\": \"9bee379abad8754a844954e1c09c8650966fad16\", \"package_name\": \"infra/tools/buildbucket/windows-amd64\"}, {\"path\": \"cipd_bin_packages\", \"version\": \"be3093550d0bb153833ede8b1df3609d059281a4\", \"package_name\": \"infra/tools/cloudtail/windows-amd64\"}, {\"path\": \"cipd_bin_packages\", \"version\": \"38514ce7ccccd8463e0a5d8dc9deb12d4bbfb626\", \"package_name\": \"infra/tools/git/windows-amd64\"}, {\"path\": \"cipd_bin_packages\", \"version\": \"c65821d10ef0a90b9acc83c49b06a306cb93f11c\", \"package_name\": \"infra/tools/luci-auth/windows-amd64\"}, {\"path\": \"cipd_bin_packages\", \"version\": \"64841ce7fe1d2be5e1bcd524df6d75cebd800151\", \"package_name\": \"infra/tools/luci/git-credential-luci/windows-amd64\"}, {\"path\": \"cipd_bin_packages\", \"version\": \"4990bd574ffa85d3f1d6df38c74e4299bf287c43\", \"package_name\": \"infra/tools/luci/vpython-native/windows-amd64\"}, {\"path\": \"cipd_bin_packages\", \"version\": \"643ba4d60cb5fdc001cee39aa4e72f5ffa0ea372\", \"package_name\": \"infra/tools/luci/vpython/windows-amd64\"}, {\"path\": \"cipd_bin_packages\", \"version\": \"3d0ba226713b83e96b84eaf7bc4ea2efd8efa7c0\", \"package_name\": \"infra/tools/prpc/windows-amd64\"}, {\"path\": \"kitchen-checkout\", \"version\": \"bc1353fb9c5d735236f7487712b55427f47e2ef3\", \"package_name\": \"infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\"}], \"client_package\": {\"version\": \"1789e3c701749c433dd36dd976813901885e3f74\", \"package_name\": \"infra/tools/cipd/windows-amd64\"}}, \"run_id\": \"3e2d3f5ecea4d311\", \"outputs_ref\": {\"isolatedserver\": \"https://isolateserver.appspot.com\", \"namespace\": \"default-gzip\", \"isolated\": \"253dbd1fbd278e15d0a0358e9880f41212280072\"}, \"server_versions\": [\"3653-6b6fa79\"], \"duration\": 7273.606000185013, \"completed_ts\": \"2018-06-18T22:27:31.315631\", \"started_ts\": \"2018-06-18T20:10:01.845543\", \"internal_failure\": false, \"exit_code\": \"1\", \"state\": \"COMPLETED\", \"bot_version\": \"ee7ca792a84a6dedc6b3cd5e754a5efde9ed6733da61d5744e21c8394fa79d1e\", \"tags\": [\"build_address:luci.chromium.ci/Windows deterministic/62\", \"buildbucket_bucket:luci.chromium.ci\", \"buildbucket_build_id:8943352857285730256\", \"buildbucket_hostname:cr-buildbucket.appspot.com\", \"buildbucket_template_canary:0\", \"buildbucket_template_revision:2a8c11ce5d733cead8b6ce80c31bfed950c5e864\", \"builder:Windows deterministic\", \"buildset:commit/git/607c9b732393d3ba6c3de8c7b552ff3145527655\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/607c9b732393d3ba6c3de8c7b552ff3145527655\", \"cores:8\", \"cpu:x86-64\", \"gitiles_ref:refs/heads/master\", \"log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8943352857285730256/+/annotations\", \"luci_project:chromium\", \"os:Windows-10\", \"pool:luci.chromium.ci\", \"priority:60\", \"recipe_name:swarming/deterministic_build\", \"recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\", \"scheduler_invocation_id:9108782919468933008\", \"scheduler_job_id:chromium/Windows deterministic\", \"service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com\", \"swarming.pool.template:skip\", \"swarming.pool.version:b7b54ba3eb083449ee9e558beebd546d22f2dbcf\", \"user:None\", \"user_agent:luci-scheduler\", \"vpython:native-python-wrapper\"], \"failure\": true, \"modified_ts\": \"2018-06-18T22:27:31.315631\", \"user\": \"\", \"created_ts\": \"2018-06-18T19:44:59.974569\", \"name\": \"bb-8943352857285730256-chromium-Windows deterministic\", \"task_id\": \"3e2d3f5ecea4d310\", \"bot_dimensions\": [{\"value\": [\"Windows deterministic\"], \"key\": \"builder\"}, {\"value\": [\"8\"], \"key\": \"cores\"}, {\"value\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"key\": \"cpu\"}, {\"value\": [\"none\"], \"key\": \"gpu\"}, {\"value\": [\"swarm2173-c4\"], \"key\": \"id\"}, {\"value\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"key\": \"image\"}, {\"value\": [\"high\"], \"key\": \"integrity\"}, {\"value\": [\"en_US.cp1252\"], \"key\": \"locale\"}, {\"value\": [\"n1-standard-8\"], \"key\": \"machine_type\"}, {\"value\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"key\": \"os\"}, {\"value\": [\"luci.chromium.ci\"], \"key\": \"pool\"}, {\"value\": [\"2.7.6\"], \"key\": \"python\"}, {\"value\": [\"3653-6b6fa79\"], \"key\": \"server_version\"}, {\"value\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"key\": \"zone\"}], \"try_number\": \"1\", \"current_task_slice\": \"0\", \"costs_usd\": [1.6837835135917067], \"bot_id\": \"swarm2173-c4\"}}, \"properties\": {\"got_nacl_revision\": \"030b88a205c9ce9a82d68798bfaa059fd55216cd\", \"got_swarming_client_revision\": \"9a518d097dca20b7b00ce3bdfc5d418ccc79893a\", \"got_revision\": \"607c9b732393d3ba6c3de8c7b552ff3145527655\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#23638}\", \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"got_revision_cp\": \"refs/heads/master@{#568113}\", \"branch\": \"refs/heads/master\", \"revision\": \"607c9b732393d3ba6c3de8c7b552ff3145527655\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"00c71836144bebc1ffc61565f3bbb2048b7f4402\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"f6fd48fd329f65b7406755de405eae87a93c429c\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1529351099656881, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/607c9b732393d3ba6c3de8c7b552ff3145527655\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/607c9b732393d3ba6c3de8c7b552ff3145527655\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9108782919468933008\", \"scheduler_job_id:chromium/Windows deterministic\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.chromium.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"chromium\", \"id\": \"8943352857285730256\"}}, \"got_v8_revision\": \"743d013bee11554786b6b66fa53d29acc793886c\", \"got_v8_revision_cp\": \"refs/heads/6.9.164@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": true, \"is_luci\": true}, \"buildnumber\": 62, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"5941c1b3df96c1db756a2834343533335c394c4a\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1529352601845543",
"status": "COMPLETED",
"status_changed_ts": "1529360852143605",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/62",
"builder:Windows deterministic",
"buildset:commit/git/607c9b732393d3ba6c3de8c7b552ff3145527655",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/607c9b732393d3ba6c3de8c7b552ff3145527655",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9108782919468933008",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Windows deterministic/62",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8943352857285730256",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:2a8c11ce5d733cead8b6ce80c31bfed950c5e864",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/607c9b732393d3ba6c3de8c7b552ff3145527655",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/607c9b732393d3ba6c3de8c7b552ff3145527655",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8943352857285730256/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows-10",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:60",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9108782919468933008",
"swarming_tag:scheduler_job_id:chromium/Windows deterministic",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:b7b54ba3eb083449ee9e558beebd546d22f2dbcf",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3e2d3f5ecea4d310",
"user_agent:luci-scheduler"
],
"updated_ts": "1529360852145434",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/62",
"utcnow_ts": "1529360852735791"
}
}
[22:27:32.766] Invocation finished in 2h42m34.575476912s with status FAILED