[19:41:52.448] New invocation is queued and will start shortly
[19:41:53.720] Starting the invocation (attempt 1)
[19:41:53.776] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:41:53.776] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:41:53.777] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9087402230181451120",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"32448b333b1b9fb979e7ff1957b2e019c08de9cc\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9087402230181451120",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/32448b333b1b9fb979e7ff1957b2e019c08de9cc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/32448b333b1b9fb979e7ff1957b2e019c08de9cc",
"gitiles_ref:refs/heads/master"
]
}
[19:41:55.452] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1549741313845469",
"id": "8921972168051520848",
"lease_expiration_ts": "1549860115096084",
"lease_key": "869694677",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"32448b333b1b9fb979e7ff1957b2e019c08de9cc\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1549741315242861",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11912",
"builder:Windows deterministic",
"buildset:commit/git/32448b333b1b9fb979e7ff1957b2e019c08de9cc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/32448b333b1b9fb979e7ff1957b2e019c08de9cc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9087402230181451120",
"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/8921972168051520848/+/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:42ec995aebbada10",
"user_agent:luci-scheduler"
],
"updated_ts": "1549741315242953",
"url": "https://ci.chromium.org/b/8921972168051520848",
"utcnow_ts": "1549741315431863"
}
}
[19:41:55.452] Task URL: https://ci.chromium.org/b/8921972168051520848
[19:41:55.452] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:2:0) after 1m0s
[19:41:57.629] Received PubSub notification, asking Buildbucket for the build status
[19:41:57.764] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:55.477] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:2:0)
[19:42:55.477] Timer tick, asking Buildbucket for the build status
[19:42:55.567] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:55.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:4:0) after 1m0s
[19:43:55.601] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:4:0)
[19:43:55.601] Timer tick, asking Buildbucket for the build status
[19:43:55.691] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:55.691] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:5:0) after 1m0s
[19:44:55.712] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:5:0)
[19:44:55.712] Timer tick, asking Buildbucket for the build status
[19:44:55.770] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:55.770] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:6:0) after 1m0s
[19:45:55.785] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:6:0)
[19:45:55.785] Timer tick, asking Buildbucket for the build status
[19:45:55.851] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:55.851] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:7:0) after 1m0s
[19:46:55.880] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:7:0)
[19:46:55.880] Timer tick, asking Buildbucket for the build status
[19:46:55.929] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:55.929] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:8:0) after 1m0s
[19:47:55.946] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:8:0)
[19:47:55.946] Timer tick, asking Buildbucket for the build status
[19:47:55.990] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:55.990] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:9:0) after 1m0s
[19:48:56.006] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:9:0)
[19:48:56.006] Timer tick, asking Buildbucket for the build status
[19:48:56.073] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:56.073] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:10:0) after 1m0s
[19:49:56.090] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:10:0)
[19:49:56.090] Timer tick, asking Buildbucket for the build status
[19:49:56.161] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:56.161] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:11:0) after 1m0s
[19:50:56.179] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:11:0)
[19:50:56.179] Timer tick, asking Buildbucket for the build status
[19:50:56.269] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:56.269] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:12:0) after 1m0s
[19:51:56.285] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:12:0)
[19:51:56.285] Timer tick, asking Buildbucket for the build status
[19:51:56.484] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:56.484] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:13:0) after 1m0s
[19:52:56.506] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:13:0)
[19:52:56.506] Timer tick, asking Buildbucket for the build status
[19:52:56.816] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:56.816] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:14:0) after 1m0s
[19:53:56.833] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:14:0)
[19:53:56.833] Timer tick, asking Buildbucket for the build status
[19:53:56.912] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:56.912] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:15:0) after 1m0s
[19:54:56.929] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:15:0)
[19:54:56.929] Timer tick, asking Buildbucket for the build status
[19:54:57.011] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:57.011] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:16:0) after 1m0s
[19:55:57.029] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:16:0)
[19:55:57.029] Timer tick, asking Buildbucket for the build status
[19:55:57.366] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:57.366] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:17:0) after 1m0s
[19:56:57.385] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:17:0)
[19:56:57.385] Timer tick, asking Buildbucket for the build status
[19:56:57.468] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:57.468] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:18:0) after 1m0s
[19:57:57.486] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:18:0)
[19:57:57.486] Timer tick, asking Buildbucket for the build status
[19:57:57.637] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:57.637] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:19:0) after 1m0s
[19:58:57.670] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:19:0)
[19:58:57.671] Timer tick, asking Buildbucket for the build status
[19:58:57.798] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:57.799] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:20:0) after 1m0s
[19:59:57.824] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:20:0)
[19:59:57.824] Timer tick, asking Buildbucket for the build status
[19:59:57.906] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:57.906] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:21:0) after 1m0s
[20:00:57.924] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:21:0)
[20:00:57.924] Timer tick, asking Buildbucket for the build status
[20:00:57.972] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:57.972] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:22:0) after 1m0s
[20:01:57.989] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:22:0)
[20:01:57.989] Timer tick, asking Buildbucket for the build status
[20:01:58.249] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:58.249] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:23:0) after 1m0s
[20:02:58.265] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:23:0)
[20:02:58.265] Timer tick, asking Buildbucket for the build status
[20:02:58.315] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:58.315] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:24:0) after 1m0s
[20:03:58.312] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:24:0)
[20:03:58.312] Timer tick, asking Buildbucket for the build status
[20:03:58.382] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:58.382] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:25:0) after 1m0s
[20:04:58.398] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:25:0)
[20:04:58.398] Timer tick, asking Buildbucket for the build status
[20:04:58.463] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:58.463] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:26:0) after 1m0s
[20:05:58.481] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:26:0)
[20:05:58.481] Timer tick, asking Buildbucket for the build status
[20:05:58.549] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:58.549] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:27:0) after 1m0s
[20:06:58.566] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:27:0)
[20:06:58.566] Timer tick, asking Buildbucket for the build status
[20:06:58.648] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:58.648] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:28:0) after 1m0s
[20:07:58.669] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:28:0)
[20:07:58.669] Timer tick, asking Buildbucket for the build status
[20:07:58.755] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:58.755] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:29:0) after 1m0s
[20:08:58.774] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:29:0)
[20:08:58.774] Timer tick, asking Buildbucket for the build status
[20:08:58.834] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:58.834] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:30:0) after 1m0s
[20:09:58.853] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:30:0)
[20:09:58.853] Timer tick, asking Buildbucket for the build status
[20:09:59.089] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:59.089] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:31:0) after 1m0s
[20:10:59.108] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:31:0)
[20:10:59.108] Timer tick, asking Buildbucket for the build status
[20:10:59.200] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:59.200] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:32:0) after 1m0s
[20:11:59.307] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:32:0)
[20:11:59.307] Timer tick, asking Buildbucket for the build status
[20:11:59.418] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:59.418] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:33:0) after 1m0s
[20:12:59.467] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:33:0)
[20:12:59.467] Timer tick, asking Buildbucket for the build status
[20:12:59.521] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:59.521] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:34:0) after 1m0s
[20:13:59.545] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:34:0)
[20:13:59.545] Timer tick, asking Buildbucket for the build status
[20:13:59.604] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:59.604] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:35:0) after 1m0s
[20:14:59.654] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:35:0)
[20:14:59.654] Timer tick, asking Buildbucket for the build status
[20:14:59.768] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:59.768] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:36:0) after 1m0s
[20:15:59.858] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:36:0)
[20:15:59.858] Timer tick, asking Buildbucket for the build status
[20:15:59.930] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:59.930] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:37:0) after 1m0s
[20:16:59.951] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:37:0)
[20:16:59.951] Timer tick, asking Buildbucket for the build status
[20:17:00.153] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:00.153] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:38:0) after 1m0s
[20:18:00.233] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:38:0)
[20:18:00.233] Timer tick, asking Buildbucket for the build status
[20:18:00.307] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:00.307] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:39:0) after 1m0s
[20:19:00.326] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:39:0)
[20:19:00.326] Timer tick, asking Buildbucket for the build status
[20:19:00.479] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:00.479] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:40:0) after 1m0s
[20:20:00.508] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:40:0)
[20:20:00.509] Timer tick, asking Buildbucket for the build status
[20:20:00.604] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:00.604] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:41:0) after 1m0s
[20:21:00.632] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:41:0)
[20:21:00.632] Timer tick, asking Buildbucket for the build status
[20:21:00.706] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:00.706] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:42:0) after 1m0s
[20:22:00.726] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:42:0)
[20:22:00.726] Timer tick, asking Buildbucket for the build status
[20:22:00.895] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:00.895] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:43:0) after 1m0s
[20:23:00.914] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:43:0)
[20:23:00.914] Timer tick, asking Buildbucket for the build status
[20:23:00.959] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:00.959] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:44:0) after 1m0s
[20:24:00.978] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:44:0)
[20:24:00.978] Timer tick, asking Buildbucket for the build status
[20:24:01.047] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:01.047] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:45:0) after 1m0s
[20:25:01.068] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:45:0)
[20:25:01.068] Timer tick, asking Buildbucket for the build status
[20:25:01.214] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:01.214] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:46:0) after 1m0s
[20:26:01.236] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:46:0)
[20:26:01.236] Timer tick, asking Buildbucket for the build status
[20:26:01.544] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:01.544] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:47:0) after 1m0s
[20:27:01.591] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:47:0)
[20:27:01.591] Timer tick, asking Buildbucket for the build status
[20:27:01.708] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:01.708] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:48:0) after 1m0s
[20:28:01.807] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:48:0)
[20:28:01.807] Timer tick, asking Buildbucket for the build status
[20:28:02.137] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:02.137] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:49:0) after 1m0s
[20:29:02.168] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:49:0)
[20:29:02.168] Timer tick, asking Buildbucket for the build status
[20:29:02.243] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:02.243] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:50:0) after 1m0s
[20:30:02.264] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:50:0)
[20:30:02.264] Timer tick, asking Buildbucket for the build status
[20:30:02.398] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:02.398] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:51:0) after 1m0s
[20:31:02.501] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:51:0)
[20:31:02.501] Timer tick, asking Buildbucket for the build status
[20:31:02.651] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:02.651] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:52:0) after 1m0s
[20:32:02.675] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:52:0)
[20:32:02.675] Timer tick, asking Buildbucket for the build status
[20:32:02.726] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:02.726] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:53:0) after 1m0s
[20:33:02.743] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:53:0)
[20:33:02.743] Timer tick, asking Buildbucket for the build status
[20:33:02.800] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:02.800] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:54:0) after 1m0s
[20:34:02.921] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:54:0)
[20:34:02.921] Timer tick, asking Buildbucket for the build status
[20:34:03.082] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:03.082] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:55:0) after 1m0s
[20:35:03.106] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:55:0)
[20:35:03.106] Timer tick, asking Buildbucket for the build status
[20:35:03.268] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:03.268] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:56:0) after 1m0s
[20:36:03.285] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:56:0)
[20:36:03.286] Timer tick, asking Buildbucket for the build status
[20:36:03.577] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:03.577] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:57:0) after 1m0s
[20:37:03.595] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:57:0)
[20:37:03.595] Timer tick, asking Buildbucket for the build status
[20:37:03.641] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:03.641] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:58:0) after 1m0s
[20:38:03.659] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:58:0)
[20:38:03.659] Timer tick, asking Buildbucket for the build status
[20:38:03.742] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:03.742] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:59:0) after 1m0s
[20:39:03.761] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:59:0)
[20:39:03.761] Timer tick, asking Buildbucket for the build status
[20:39:03.835] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:03.836] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:60:0) after 1m0s
[20:40:03.863] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:60:0)
[20:40:03.863] Timer tick, asking Buildbucket for the build status
[20:40:04.207] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:04.207] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:61:0) after 1m0s
[20:41:04.226] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:61:0)
[20:41:04.226] Timer tick, asking Buildbucket for the build status
[20:41:04.483] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:04.483] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:62:0) after 1m0s
[20:42:04.613] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:62:0)
[20:42:04.613] Timer tick, asking Buildbucket for the build status
[20:42:04.702] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:04.702] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:63:0) after 1m0s
[20:43:04.733] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:63:0)
[20:43:04.733] Timer tick, asking Buildbucket for the build status
[20:43:04.829] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:04.829] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:64:0) after 1m0s
[20:44:04.858] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:64:0)
[20:44:04.858] Timer tick, asking Buildbucket for the build status
[20:44:05.100] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:05.100] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:65:0) after 1m0s
[20:45:05.133] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:65:0)
[20:45:05.133] Timer tick, asking Buildbucket for the build status
[20:45:05.389] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:05.389] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:66:0) after 1m0s
[20:46:05.406] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:66:0)
[20:46:05.406] Timer tick, asking Buildbucket for the build status
[20:46:05.768] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:05.768] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:67:0) after 1m0s
[20:47:05.787] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:67:0)
[20:47:05.787] Timer tick, asking Buildbucket for the build status
[20:47:05.858] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:05.858] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:68:0) after 1m0s
[20:48:05.883] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:68:0)
[20:48:05.883] Timer tick, asking Buildbucket for the build status
[20:48:05.963] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:05.963] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:69:0) after 1m0s
[20:49:05.987] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:69:0)
[20:49:05.987] Timer tick, asking Buildbucket for the build status
[20:49:06.072] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:06.072] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:70:0) after 1m0s
[20:50:06.092] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:70:0)
[20:50:06.092] Timer tick, asking Buildbucket for the build status
[20:50:06.410] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:06.410] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:71:0) after 1m0s
[20:51:06.456] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:71:0)
[20:51:06.456] Timer tick, asking Buildbucket for the build status
[20:51:06.519] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:06.520] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:72:0) after 1m0s
[20:52:06.589] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:72:0)
[20:52:06.589] Timer tick, asking Buildbucket for the build status
[20:52:06.764] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:06.764] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:73:0) after 1m0s
[20:53:06.792] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:73:0)
[20:53:06.792] Timer tick, asking Buildbucket for the build status
[20:53:07.044] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:07.044] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:74:0) after 1m0s
[20:54:07.065] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:74:0)
[20:54:07.065] Timer tick, asking Buildbucket for the build status
[20:54:07.135] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:07.135] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:75:0) after 1m0s
[20:55:07.191] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:75:0)
[20:55:07.191] Timer tick, asking Buildbucket for the build status
[20:55:07.259] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:07.259] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:76:0) after 1m0s
[20:56:07.286] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:76:0)
[20:56:07.286] Timer tick, asking Buildbucket for the build status
[20:56:07.352] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:07.352] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:77:0) after 1m0s
[20:57:07.428] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:77:0)
[20:57:07.428] Timer tick, asking Buildbucket for the build status
[20:57:07.703] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:07.703] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:78:0) after 1m0s
[20:58:07.722] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:78:0)
[20:58:07.722] Timer tick, asking Buildbucket for the build status
[20:58:07.784] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:07.784] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:79:0) after 1m0s
[20:59:07.805] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:79:0)
[20:59:07.805] Timer tick, asking Buildbucket for the build status
[20:59:07.869] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:07.869] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:80:0) after 1m0s
[21:00:07.888] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:80:0)
[21:00:07.888] Timer tick, asking Buildbucket for the build status
[21:00:07.954] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:07.954] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:81:0) after 1m0s
[21:01:07.975] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:81:0)
[21:01:07.975] Timer tick, asking Buildbucket for the build status
[21:01:08.044] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:08.044] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:82:0) after 1m0s
[21:02:08.062] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:82:0)
[21:02:08.062] Timer tick, asking Buildbucket for the build status
[21:02:08.135] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:08.136] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:83:0) after 1m0s
[21:03:08.156] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:83:0)
[21:03:08.156] Timer tick, asking Buildbucket for the build status
[21:03:08.296] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:08.296] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:84:0) after 1m0s
[21:04:08.317] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:84:0)
[21:04:08.317] Timer tick, asking Buildbucket for the build status
[21:04:08.381] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:08.381] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:85:0) after 1m0s
[21:05:08.400] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:85:0)
[21:05:08.400] Timer tick, asking Buildbucket for the build status
[21:05:08.462] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:08.462] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:86:0) after 1m0s
[21:06:08.481] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:86:0)
[21:06:08.482] Timer tick, asking Buildbucket for the build status
[21:06:08.560] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:08.560] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:87:0) after 1m0s
[21:07:08.578] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:87:0)
[21:07:08.578] Timer tick, asking Buildbucket for the build status
[21:07:08.700] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:08.700] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:88:0) after 1m0s
[21:08:08.773] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:88:0)
[21:08:08.773] Timer tick, asking Buildbucket for the build status
[21:08:08.877] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:08.877] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:89:0) after 1m0s
[21:09:08.906] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:89:0)
[21:09:08.906] Timer tick, asking Buildbucket for the build status
[21:09:09.079] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:09.079] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:90:0) after 1m0s
[21:10:09.098] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:90:0)
[21:10:09.098] Timer tick, asking Buildbucket for the build status
[21:10:09.174] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:09.174] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:91:0) after 1m0s
[21:11:09.196] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:91:0)
[21:11:09.196] Timer tick, asking Buildbucket for the build status
[21:11:09.246] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:09.246] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:92:0) after 1m0s
[21:12:09.267] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:92:0)
[21:12:09.267] Timer tick, asking Buildbucket for the build status
[21:12:09.310] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:09.310] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:93:0) after 1m0s
[21:13:09.329] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:93:0)
[21:13:09.329] Timer tick, asking Buildbucket for the build status
[21:13:09.399] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:09.399] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:94:0) after 1m0s
[21:14:09.417] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:94:0)
[21:14:09.417] Timer tick, asking Buildbucket for the build status
[21:14:09.469] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:09.469] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:95:0) after 1m0s
[21:15:09.491] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:95:0)
[21:15:09.491] Timer tick, asking Buildbucket for the build status
[21:15:09.668] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:09.668] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:96:0) after 1m0s
[21:16:09.686] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:96:0)
[21:16:09.686] Timer tick, asking Buildbucket for the build status
[21:16:09.750] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:09.750] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:97:0) after 1m0s
[21:17:09.775] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:97:0)
[21:17:09.775] Timer tick, asking Buildbucket for the build status
[21:17:09.833] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:09.833] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:98:0) after 1m0s
[21:18:09.853] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:98:0)
[21:18:09.853] Timer tick, asking Buildbucket for the build status
[21:18:10.001] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:10.001] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:99:0) after 1m0s
[21:19:10.068] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:99:0)
[21:19:10.068] Timer tick, asking Buildbucket for the build status
[21:19:10.227] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:10.227] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:100:0) after 1m0s
[21:20:10.248] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:100:0)
[21:20:10.248] Timer tick, asking Buildbucket for the build status
[21:20:10.314] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:10.314] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:101:0) after 1m0s
[21:21:10.364] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:101:0)
[21:21:10.364] Timer tick, asking Buildbucket for the build status
[21:21:10.571] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:10.571] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:102:0) after 1m0s
[21:22:10.591] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:102:0)
[21:22:10.591] Timer tick, asking Buildbucket for the build status
[21:22:10.634] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:10.634] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:103:0) after 1m0s
[21:23:10.739] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:103:0)
[21:23:10.739] Timer tick, asking Buildbucket for the build status
[21:23:10.925] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:10.925] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:104:0) after 1m0s
[21:24:10.946] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:104:0)
[21:24:10.946] Timer tick, asking Buildbucket for the build status
[21:24:11.015] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:11.015] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:105:0) after 1m0s
[21:25:11.083] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:105:0)
[21:25:11.083] Timer tick, asking Buildbucket for the build status
[21:25:11.303] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:11.303] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:106:0) after 1m0s
[21:26:11.326] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:106:0)
[21:26:11.326] Timer tick, asking Buildbucket for the build status
[21:26:11.400] Build 8921972168051520848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:11.400] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087402230181451120:107:0) after 1m0s
[21:26:57.995] Received PubSub notification, asking Buildbucket for the build status
[21:26:58.060] Build 8921972168051520848: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:26:58.060] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1549747617172968",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1549741313845469",
"id": "8921972168051520848",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"32448b333b1b9fb979e7ff1957b2e019c08de9cc\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"id\": [\"swarm2173-c4\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4077-0a4fdfc\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"got_nacl_revision\": \"ff15c51d20785fa51c0ef03ccb480b87299acbeb\", \"got_swarming_client_revision\": \"0e3e1c4dc4e79f25a5b58fcbc135dc93183c0c54\", \"got_revision\": \"32448b333b1b9fb979e7ff1957b2e019c08de9cc\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"3b71e65658cdb3dd5c8455241e3a5b8576b5b1dc\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26623}\", \"$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@{#630640}\", \"branch\": \"refs/heads/master\", \"revision\": \"32448b333b1b9fb979e7ff1957b2e019c08de9cc\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"e556768453ee805a3e2a3a5c27e53be216df5f9a\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"abf6dbbb107b3487480422b6ce37e9662cb8f302\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1549741313845469, \"tags\": [\"build_address:luci.chromium.ci/Windows deterministic/11912\", \"builder:Windows deterministic\", \"buildset:commit/git/32448b333b1b9fb979e7ff1957b2e019c08de9cc\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/32448b333b1b9fb979e7ff1957b2e019c08de9cc\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9087402230181451120\", \"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\": \"8921972168051520848\"}}, \"got_v8_revision\": \"b3e709a8d204ef93262ebeba15f504fbe080facc\", \"got_v8_revision_cp\": \"refs/heads/7.4.119@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 11912, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Windows deterministic\", \"key\": \"builder\"}, {\"value\": \"commit/git/32448b333b1b9fb979e7ff1957b2e019c08de9cc\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/chromium/src/+/32448b333b1b9fb979e7ff1957b2e019c08de9cc\", \"key\": \"buildset\"}, {\"value\": \"9087402230181451120\", \"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\": 11912, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"ef156230f5cd7f8c23f689fa4dc7f92742cd904d\"}, \"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\": \"8921972168051520848\", \"input\": {\"gitilesCommit\": {\"project\": \"chromium/src\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"32448b333b1b9fb979e7ff1957b2e019c08de9cc\"}}, \"createTime\": \"2019-02-09T19:41:53.845469Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1549741316784484",
"status": "COMPLETED",
"status_changed_ts": "1549747617687447",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11912",
"builder:Windows deterministic",
"buildset:commit/git/32448b333b1b9fb979e7ff1957b2e019c08de9cc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/32448b333b1b9fb979e7ff1957b2e019c08de9cc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9087402230181451120",
"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/8921972168051520848/+/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:42ec995aebbada10",
"user_agent:luci-scheduler"
],
"updated_ts": "1549747617688970",
"url": "https://ci.chromium.org/b/8921972168051520848",
"utcnow_ts": "1549747618051683"
}
}
[21:26:58.060] Invocation finished in 1h45m5.629674741s with status SUCCEEDED