[00:58:54.153] New invocation is queued and will start shortly
[00:58:55.405] Starting the invocation (attempt 1)
[00:58:55.500] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:58:55.500] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:58:55.500] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9085660942118685968",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"9b251c599b428e90c9e7f7cedf750913f1c9ff69\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9085660942118685968",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/9b251c599b428e90c9e7f7cedf750913f1c9ff69",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9b251c599b428e90c9e7f7cedf750913f1c9ff69",
"gitiles_ref:refs/heads/master"
]
}
[00:58:57.737] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551401935665170",
"id": "8920230879866129824",
"lease_expiration_ts": "1551520737423476",
"lease_key": "429534219",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"9b251c599b428e90c9e7f7cedf750913f1c9ff69\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1551401937626236",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12246",
"builder:Windows deterministic",
"buildset:commit/git/9b251c599b428e90c9e7f7cedf750913f1c9ff69",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9b251c599b428e90c9e7f7cedf750913f1c9ff69",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085660942118685968",
"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/8920230879866129824/+/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:434f9470e5493e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1551401937626325",
"url": "https://ci.chromium.org/b/8920230879866129824",
"utcnow_ts": "1551401937728208"
}
}
[00:58:57.737] Task URL: https://ci.chromium.org/b/8920230879866129824
[00:58:57.737] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:2:0) after 1m0s
[00:58:59.008] Received PubSub notification, asking Buildbucket for the build status
[00:58:59.073] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:57.756] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:2:0)
[00:59:57.756] Timer tick, asking Buildbucket for the build status
[00:59:58.110] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:58.110] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:4:0) after 1m0s
[01:00:58.130] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:4:0)
[01:00:58.130] Timer tick, asking Buildbucket for the build status
[01:00:58.484] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:58.484] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:5:0) after 1m0s
[01:01:58.503] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:5:0)
[01:01:58.503] Timer tick, asking Buildbucket for the build status
[01:01:58.577] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:58.577] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:6:0) after 1m0s
[01:02:58.599] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:6:0)
[01:02:58.599] Timer tick, asking Buildbucket for the build status
[01:02:58.641] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:58.641] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:7:0) after 1m0s
[01:03:58.650] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:7:0)
[01:03:58.650] Timer tick, asking Buildbucket for the build status
[01:03:58.800] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:58.800] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:8:0) after 1m0s
[01:04:58.789] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:8:0)
[01:04:58.789] Timer tick, asking Buildbucket for the build status
[01:04:59.116] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:59.116] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:9:0) after 1m0s
[01:05:59.137] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:9:0)
[01:05:59.137] Timer tick, asking Buildbucket for the build status
[01:05:59.208] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:59.208] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:10:0) after 1m0s
[01:06:59.226] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:10:0)
[01:06:59.226] Timer tick, asking Buildbucket for the build status
[01:06:59.302] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:59.302] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:11:0) after 1m0s
[01:07:59.296] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:11:0)
[01:07:59.296] Timer tick, asking Buildbucket for the build status
[01:07:59.362] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:59.362] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:12:0) after 1m0s
[01:08:59.340] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:12:0)
[01:08:59.340] Timer tick, asking Buildbucket for the build status
[01:08:59.481] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:59.481] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:13:0) after 1m0s
[01:09:59.509] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:13:0)
[01:09:59.509] Timer tick, asking Buildbucket for the build status
[01:09:59.548] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:59.548] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:14:0) after 1m0s
[01:10:59.531] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:14:0)
[01:10:59.531] Timer tick, asking Buildbucket for the build status
[01:10:59.681] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:59.681] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:15:0) after 1m0s
[01:11:59.728] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:15:0)
[01:11:59.728] Timer tick, asking Buildbucket for the build status
[01:11:59.785] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:59.785] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:16:0) after 1m0s
[01:12:59.768] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:16:0)
[01:12:59.768] Timer tick, asking Buildbucket for the build status
[01:13:00.175] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:00.175] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:17:0) after 1m0s
[01:14:00.217] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:17:0)
[01:14:00.217] Timer tick, asking Buildbucket for the build status
[01:14:00.295] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:00.295] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:18:0) after 1m0s
[01:15:00.273] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:18:0)
[01:15:00.273] Timer tick, asking Buildbucket for the build status
[01:15:00.335] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:00.335] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:19:0) after 1m0s
[01:16:00.355] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:19:0)
[01:16:00.355] Timer tick, asking Buildbucket for the build status
[01:16:00.506] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:00.506] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:20:0) after 1m0s
[01:17:00.525] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:20:0)
[01:17:00.525] Timer tick, asking Buildbucket for the build status
[01:17:00.644] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:00.644] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:21:0) after 1m0s
[01:18:00.709] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:21:0)
[01:18:00.709] Timer tick, asking Buildbucket for the build status
[01:18:01.048] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:01.048] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:22:0) after 1m0s
[01:19:01.142] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:22:0)
[01:19:01.142] Timer tick, asking Buildbucket for the build status
[01:19:01.259] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:01.259] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:23:0) after 1m0s
[01:20:01.271] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:23:0)
[01:20:01.271] Timer tick, asking Buildbucket for the build status
[01:20:01.426] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:01.426] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:24:0) after 1m0s
[01:21:01.455] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:24:0)
[01:21:01.455] Timer tick, asking Buildbucket for the build status
[01:21:01.583] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:01.583] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:25:0) after 1m0s
[01:22:01.609] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:25:0)
[01:22:01.609] Timer tick, asking Buildbucket for the build status
[01:22:01.774] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:01.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:26:0) after 1m0s
[01:23:01.756] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:26:0)
[01:23:01.756] Timer tick, asking Buildbucket for the build status
[01:23:01.802] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:01.802] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:27:0) after 1m0s
[01:24:01.830] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:27:0)
[01:24:01.830] Timer tick, asking Buildbucket for the build status
[01:24:02.158] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:02.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:28:0) after 1m0s
[01:25:02.173] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:28:0)
[01:25:02.173] Timer tick, asking Buildbucket for the build status
[01:25:02.230] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:02.230] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:29:0) after 1m0s
[01:26:02.400] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:29:0)
[01:26:02.400] Timer tick, asking Buildbucket for the build status
[01:26:02.587] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:02.587] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:30:0) after 1m0s
[01:27:02.660] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:30:0)
[01:27:02.660] Timer tick, asking Buildbucket for the build status
[01:27:02.858] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:02.858] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:31:0) after 1m0s
[01:28:02.931] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:31:0)
[01:28:02.931] Timer tick, asking Buildbucket for the build status
[01:28:02.987] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:02.987] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:32:0) after 1m0s
[01:29:03.014] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:32:0)
[01:29:03.014] Timer tick, asking Buildbucket for the build status
[01:29:03.184] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:03.184] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:33:0) after 1m0s
[01:30:03.250] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:33:0)
[01:30:03.250] Timer tick, asking Buildbucket for the build status
[01:30:03.311] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:03.311] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:34:0) after 1m0s
[01:31:03.332] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:34:0)
[01:31:03.332] Timer tick, asking Buildbucket for the build status
[01:31:03.397] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:03.397] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:35:0) after 1m0s
[01:32:03.519] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:35:0)
[01:32:03.519] Timer tick, asking Buildbucket for the build status
[01:32:03.890] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:03.890] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:36:0) after 1m0s
[01:33:03.927] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:36:0)
[01:33:03.927] Timer tick, asking Buildbucket for the build status
[01:33:04.004] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:04.004] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:37:0) after 1m0s
[01:34:04.035] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:37:0)
[01:34:04.035] Timer tick, asking Buildbucket for the build status
[01:34:04.097] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:04.097] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:38:0) after 1m0s
[01:35:04.109] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:38:0)
[01:35:04.109] Timer tick, asking Buildbucket for the build status
[01:35:04.199] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:04.199] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:39:0) after 1m0s
[01:36:04.237] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:39:0)
[01:36:04.237] Timer tick, asking Buildbucket for the build status
[01:36:04.375] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:04.375] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:40:0) after 1m0s
[01:37:04.399] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:40:0)
[01:37:04.399] Timer tick, asking Buildbucket for the build status
[01:37:04.508] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:04.508] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:41:0) after 1m0s
[01:38:04.481] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:41:0)
[01:38:04.481] Timer tick, asking Buildbucket for the build status
[01:38:04.645] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:04.645] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:42:0) after 1m0s
[01:39:04.682] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:42:0)
[01:39:04.682] Timer tick, asking Buildbucket for the build status
[01:39:04.739] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:04.739] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:43:0) after 1m0s
[01:40:04.721] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:43:0)
[01:40:04.721] Timer tick, asking Buildbucket for the build status
[01:40:04.813] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:04.813] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:44:0) after 1m0s
[01:41:04.832] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:44:0)
[01:41:04.832] Timer tick, asking Buildbucket for the build status
[01:41:04.889] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:04.889] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:45:0) after 1m0s
[01:42:04.931] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:45:0)
[01:42:04.931] Timer tick, asking Buildbucket for the build status
[01:42:05.070] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:05.070] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:46:0) after 1m0s
[01:43:05.159] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:46:0)
[01:43:05.159] Timer tick, asking Buildbucket for the build status
[01:43:05.269] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:05.269] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:47:0) after 1m0s
[01:44:05.276] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:47:0)
[01:44:05.276] Timer tick, asking Buildbucket for the build status
[01:44:05.325] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:05.325] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:48:0) after 1m0s
[01:45:05.349] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:48:0)
[01:45:05.349] Timer tick, asking Buildbucket for the build status
[01:45:05.542] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:05.542] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:49:0) after 1m0s
[01:46:05.554] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:49:0)
[01:46:05.554] Timer tick, asking Buildbucket for the build status
[01:46:05.775] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:05.775] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:50:0) after 1m0s
[01:47:05.791] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:50:0)
[01:47:05.791] Timer tick, asking Buildbucket for the build status
[01:47:05.889] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:05.889] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:51:0) after 1m0s
[01:48:05.899] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:51:0)
[01:48:05.899] Timer tick, asking Buildbucket for the build status
[01:48:05.989] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:05.989] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:52:0) after 1m0s
[01:49:06.013] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:52:0)
[01:49:06.013] Timer tick, asking Buildbucket for the build status
[01:49:06.081] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:06.081] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:53:0) after 1m0s
[01:50:06.103] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:53:0)
[01:50:06.103] Timer tick, asking Buildbucket for the build status
[01:50:06.204] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:06.204] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:54:0) after 1m0s
[01:51:06.226] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:54:0)
[01:51:06.226] Timer tick, asking Buildbucket for the build status
[01:51:06.293] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:06.293] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:55:0) after 1m0s
[01:52:06.290] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:55:0)
[01:52:06.290] Timer tick, asking Buildbucket for the build status
[01:52:06.333] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:06.333] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:56:0) after 1m0s
[01:53:06.316] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:56:0)
[01:53:06.316] Timer tick, asking Buildbucket for the build status
[01:53:06.361] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:06.361] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:57:0) after 1m0s
[01:54:06.388] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:57:0)
[01:54:06.388] Timer tick, asking Buildbucket for the build status
[01:54:06.498] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:06.498] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:58:0) after 1m0s
[01:55:06.533] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:58:0)
[01:55:06.533] Timer tick, asking Buildbucket for the build status
[01:55:06.581] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:06.581] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:59:0) after 1m0s
[01:56:06.664] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:59:0)
[01:56:06.664] Timer tick, asking Buildbucket for the build status
[01:56:06.755] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:06.755] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:60:0) after 1m0s
[01:57:06.802] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:60:0)
[01:57:06.802] Timer tick, asking Buildbucket for the build status
[01:57:06.845] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:06.845] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:61:0) after 1m0s
[01:58:06.834] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:61:0)
[01:58:06.834] Timer tick, asking Buildbucket for the build status
[01:58:06.893] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:06.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:62:0) after 1m0s
[01:59:06.916] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:62:0)
[01:59:06.916] Timer tick, asking Buildbucket for the build status
[01:59:06.964] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:06.964] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:63:0) after 1m0s
[02:00:06.988] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:63:0)
[02:00:06.988] Timer tick, asking Buildbucket for the build status
[02:00:07.036] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:07.036] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:64:0) after 1m0s
[02:01:07.060] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:64:0)
[02:01:07.060] Timer tick, asking Buildbucket for the build status
[02:01:07.106] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:07.106] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:65:0) after 1m0s
[02:02:07.127] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:65:0)
[02:02:07.127] Timer tick, asking Buildbucket for the build status
[02:02:07.217] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:07.217] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:66:0) after 1m0s
[02:03:07.192] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:66:0)
[02:03:07.192] Timer tick, asking Buildbucket for the build status
[02:03:07.235] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:07.235] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:67:0) after 1m0s
[02:04:07.265] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:67:0)
[02:04:07.265] Timer tick, asking Buildbucket for the build status
[02:04:07.412] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:07.412] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:68:0) after 1m0s
[02:05:07.438] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:68:0)
[02:05:07.438] Timer tick, asking Buildbucket for the build status
[02:05:07.487] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:07.487] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:69:0) after 1m0s
[02:06:07.470] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:69:0)
[02:06:07.470] Timer tick, asking Buildbucket for the build status
[02:06:07.524] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:07.524] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:70:0) after 1m0s
[02:07:07.529] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:70:0)
[02:07:07.529] Timer tick, asking Buildbucket for the build status
[02:07:08.073] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:08.073] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:71:0) after 1m0s
[02:08:08.095] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:71:0)
[02:08:08.095] Timer tick, asking Buildbucket for the build status
[02:08:08.143] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:08.143] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:72:0) after 1m0s
[02:09:08.132] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:72:0)
[02:09:08.132] Timer tick, asking Buildbucket for the build status
[02:09:08.362] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:08.362] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:73:0) after 1m0s
[02:10:08.371] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:73:0)
[02:10:08.371] Timer tick, asking Buildbucket for the build status
[02:10:08.598] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:08.598] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:74:0) after 1m0s
[02:11:08.609] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:74:0)
[02:11:08.609] Timer tick, asking Buildbucket for the build status
[02:11:08.720] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:08.720] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:75:0) after 1m0s
[02:12:08.748] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:75:0)
[02:12:08.748] Timer tick, asking Buildbucket for the build status
[02:12:08.809] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:08.809] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:76:0) after 1m0s
[02:13:08.802] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:76:0)
[02:13:08.802] Timer tick, asking Buildbucket for the build status
[02:13:09.000] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:09.000] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:77:0) after 1m0s
[02:14:09.000] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:77:0)
[02:14:09.000] Timer tick, asking Buildbucket for the build status
[02:14:09.066] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:09.066] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:78:0) after 1m0s
[02:15:09.063] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:78:0)
[02:15:09.063] Timer tick, asking Buildbucket for the build status
[02:15:09.190] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:09.190] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:79:0) after 1m0s
[02:16:09.176] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:79:0)
[02:16:09.176] Timer tick, asking Buildbucket for the build status
[02:16:09.388] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:09.389] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:80:0) after 1m0s
[02:17:09.413] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:80:0)
[02:17:09.413] Timer tick, asking Buildbucket for the build status
[02:17:09.501] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:09.501] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:81:0) after 1m0s
[02:18:09.504] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:81:0)
[02:18:09.504] Timer tick, asking Buildbucket for the build status
[02:18:09.574] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:09.574] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:82:0) after 1m0s
[02:19:09.603] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:82:0)
[02:19:09.603] Timer tick, asking Buildbucket for the build status
[02:19:09.697] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:09.697] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:83:0) after 1m0s
[02:20:09.702] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:83:0)
[02:20:09.702] Timer tick, asking Buildbucket for the build status
[02:20:09.743] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:09.743] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:84:0) after 1m0s
[02:21:09.772] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:84:0)
[02:21:09.772] Timer tick, asking Buildbucket for the build status
[02:21:09.966] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:09.966] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:85:0) after 1m0s
[02:22:09.986] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:85:0)
[02:22:09.986] Timer tick, asking Buildbucket for the build status
[02:22:10.027] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:10.027] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:86:0) after 1m0s
[02:23:10.008] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:86:0)
[02:23:10.008] Timer tick, asking Buildbucket for the build status
[02:23:10.274] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:10.274] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:87:0) after 1m0s
[02:24:10.345] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:87:0)
[02:24:10.345] Timer tick, asking Buildbucket for the build status
[02:24:10.472] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:10.472] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:88:0) after 1m0s
[02:25:10.500] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:88:0)
[02:25:10.500] Timer tick, asking Buildbucket for the build status
[02:25:10.584] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:10.584] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:89:0) after 1m0s
[02:26:10.574] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:89:0)
[02:26:10.574] Timer tick, asking Buildbucket for the build status
[02:26:10.716] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:10.716] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:90:0) after 1m0s
[02:27:10.743] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:90:0)
[02:27:10.743] Timer tick, asking Buildbucket for the build status
[02:27:10.787] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:10.787] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:91:0) after 1m0s
[02:28:10.810] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:91:0)
[02:28:10.810] Timer tick, asking Buildbucket for the build status
[02:28:10.854] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:10.854] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:92:0) after 1m0s
[02:29:10.836] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:92:0)
[02:29:10.836] Timer tick, asking Buildbucket for the build status
[02:29:10.967] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:10.967] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:93:0) after 1m0s
[02:30:11.043] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:93:0)
[02:30:11.043] Timer tick, asking Buildbucket for the build status
[02:30:11.103] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:11.103] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:94:0) after 1m0s
[02:31:11.115] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:94:0)
[02:31:11.115] Timer tick, asking Buildbucket for the build status
[02:31:11.171] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:11.171] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:95:0) after 1m0s
[02:32:11.193] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:95:0)
[02:32:11.193] Timer tick, asking Buildbucket for the build status
[02:32:11.303] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:11.303] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:96:0) after 1m0s
[02:33:11.325] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:96:0)
[02:33:11.325] Timer tick, asking Buildbucket for the build status
[02:33:11.406] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:11.406] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:97:0) after 1m0s
[02:34:11.408] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:97:0)
[02:34:11.408] Timer tick, asking Buildbucket for the build status
[02:34:11.550] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:11.550] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:98:0) after 1m0s
[02:35:11.572] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:98:0)
[02:35:11.572] Timer tick, asking Buildbucket for the build status
[02:35:11.726] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:11.726] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:99:0) after 1m0s
[02:36:11.723] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:99:0)
[02:36:11.723] Timer tick, asking Buildbucket for the build status
[02:36:12.016] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:12.016] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:100:0) after 1m0s
[02:37:12.044] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:100:0)
[02:37:12.044] Timer tick, asking Buildbucket for the build status
[02:37:12.185] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:12.185] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:101:0) after 1m0s
[02:38:12.233] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:101:0)
[02:38:12.233] Timer tick, asking Buildbucket for the build status
[02:38:12.364] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:12.364] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:102:0) after 1m0s
[02:39:12.394] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:102:0)
[02:39:12.394] Timer tick, asking Buildbucket for the build status
[02:39:12.447] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:12.447] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:103:0) after 1m0s
[02:40:12.482] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:103:0)
[02:40:12.482] Timer tick, asking Buildbucket for the build status
[02:40:12.565] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:12.565] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:104:0) after 1m0s
[02:41:12.562] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:104:0)
[02:41:12.562] Timer tick, asking Buildbucket for the build status
[02:41:12.618] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:12.618] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:105:0) after 1m0s
[02:42:12.637] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:105:0)
[02:42:12.637] Timer tick, asking Buildbucket for the build status
[02:42:12.790] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:12.790] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:106:0) after 1m0s
[02:43:12.833] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:106:0)
[02:43:12.833] Timer tick, asking Buildbucket for the build status
[02:43:12.902] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:12.902] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:107:0) after 1m0s
[02:44:12.919] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:107:0)
[02:44:12.919] Timer tick, asking Buildbucket for the build status
[02:44:12.979] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:12.979] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:108:0) after 1m0s
[02:45:13.003] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:108:0)
[02:45:13.003] Timer tick, asking Buildbucket for the build status
[02:45:13.186] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:13.186] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:109:0) after 1m0s
[02:46:13.166] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:109:0)
[02:46:13.166] Timer tick, asking Buildbucket for the build status
[02:46:13.300] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:13.300] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:110:0) after 1m0s
[02:47:13.288] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:110:0)
[02:47:13.288] Timer tick, asking Buildbucket for the build status
[02:47:13.375] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:13.375] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:111:0) after 1m0s
[02:48:13.362] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:111:0)
[02:48:13.362] Timer tick, asking Buildbucket for the build status
[02:48:13.570] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:13.570] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:112:0) after 1m0s
[02:49:13.594] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:112:0)
[02:49:13.594] Timer tick, asking Buildbucket for the build status
[02:49:13.654] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:13.654] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:113:0) after 1m0s
[02:50:13.653] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:113:0)
[02:50:13.653] Timer tick, asking Buildbucket for the build status
[02:50:13.731] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:13.731] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:114:0) after 1m0s
[02:51:13.752] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:114:0)
[02:51:13.752] Timer tick, asking Buildbucket for the build status
[02:51:13.924] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:13.924] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:115:0) after 1m0s
[02:52:13.916] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:115:0)
[02:52:13.916] Timer tick, asking Buildbucket for the build status
[02:52:14.055] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:14.055] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:116:0) after 1m0s
[02:53:14.097] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:116:0)
[02:53:14.097] Timer tick, asking Buildbucket for the build status
[02:53:14.142] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:14.142] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:117:0) after 1m0s
[02:54:14.185] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:117:0)
[02:54:14.186] Timer tick, asking Buildbucket for the build status
[02:54:14.389] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:14.389] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:118:0) after 1m0s
[02:55:14.415] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:118:0)
[02:55:14.415] Timer tick, asking Buildbucket for the build status
[02:55:14.499] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:14.499] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:119:0) after 1m0s
[02:56:14.545] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:119:0)
[02:56:14.545] Timer tick, asking Buildbucket for the build status
[02:56:14.686] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:14.686] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:120:0) after 1m0s
[02:57:14.677] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:120:0)
[02:57:14.677] Timer tick, asking Buildbucket for the build status
[02:57:14.719] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:14.719] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:121:0) after 1m0s
[02:58:14.751] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:121:0)
[02:58:14.751] Timer tick, asking Buildbucket for the build status
[02:58:14.887] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:14.887] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:122:0) after 1m0s
[02:59:14.894] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:122:0)
[02:59:14.894] Timer tick, asking Buildbucket for the build status
[02:59:14.943] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:14.943] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:123:0) after 1m0s
[03:00:14.948] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:123:0)
[03:00:14.948] Timer tick, asking Buildbucket for the build status
[03:00:15.048] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:15.048] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:124:0) after 1m0s
[03:01:15.049] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:124:0)
[03:01:15.049] Timer tick, asking Buildbucket for the build status
[03:01:15.119] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:15.119] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:125:0) after 1m0s
[03:02:15.150] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:125:0)
[03:02:15.150] Timer tick, asking Buildbucket for the build status
[03:02:15.286] Build 8920230879866129824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:15.286] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085660942118685968:126:0) after 1m0s
[03:02:35.213] Received PubSub notification, asking Buildbucket for the build status
[03:02:35.261] Build 8920230879866129824: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:02:35.261] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1551409354382138",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551401935665170",
"id": "8920230879866129824",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"9b251c599b428e90c9e7f7cedf750913f1c9ff69\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"id\": [\"win10-727f49a0-us-west1-c-gw0h\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4139-ace6083\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"got_nacl_revision\": \"1ed9878b8eb68c1e0d0268754dafcf83e747fa8f\", \"got_swarming_client_revision\": \"d50a88f50782ba29076061b94c7b9d08a6c7e424\", \"got_revision\": \"9b251c599b428e90c9e7f7cedf750913f1c9ff69\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"2dfb3f01e77d1dff4ce611fe8d00c9a11b235f78\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26906}\", \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"got_revision_cp\": \"refs/heads/master@{#636627}\", \"branch\": \"refs/heads/master\", \"revision\": \"9b251c599b428e90c9e7f7cedf750913f1c9ff69\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"b8a4d688f90780f5cbc1a0250f926db648feb772\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"5de69e91bd939f8c2acc3ccab095d770bf378481\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1551401935665170, \"tags\": [\"build_address:luci.chromium.ci/Windows deterministic/12246\", \"builder:Windows deterministic\", \"buildset:commit/git/9b251c599b428e90c9e7f7cedf750913f1c9ff69\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9b251c599b428e90c9e7f7cedf750913f1c9ff69\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9085660942118685968\", \"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\": \"8920230879866129824\"}}, \"got_v8_revision\": \"96fc7ac109d13d86d67572349a5eec7f1540abdd\", \"got_v8_revision_cp\": \"refs/heads/7.4.255@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 12246, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Windows deterministic\", \"key\": \"builder\"}, {\"value\": \"commit/git/9b251c599b428e90c9e7f7cedf750913f1c9ff69\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/chromium/src/+/9b251c599b428e90c9e7f7cedf750913f1c9ff69\", \"key\": \"buildset\"}, {\"value\": \"9085660942118685968\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"chromium/Windows deterministic\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"chromium\", \"builder\": \"Windows deterministic\", \"bucket\": \"ci\"}, \"number\": 12246, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"ca0db2d762966d4b32d49d86567d9d7697438caf\", \"canary\": true}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8920230879866129824\", \"input\": {\"gitilesCommit\": {\"project\": \"chromium/src\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"9b251c599b428e90c9e7f7cedf750913f1c9ff69\"}}, \"createTime\": \"2019-03-01T00:58:55.665170Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"win10-727f49a0-us-west1-c-gw0h\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1551401938176199",
"status": "COMPLETED",
"status_changed_ts": "1551409354930799",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12246",
"builder:Windows deterministic",
"buildset:commit/git/9b251c599b428e90c9e7f7cedf750913f1c9ff69",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9b251c599b428e90c9e7f7cedf750913f1c9ff69",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085660942118685968",
"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/8920230879866129824/+/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:434f9470e5493e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1551409354931854",
"url": "https://ci.chromium.org/b/8920230879866129824",
"utcnow_ts": "1551409355249779"
}
}
[03:02:35.261] Invocation finished in 2h3m41.127937706s with status SUCCEEDED