[03:48:24.104] New invocation is queued and will start shortly
[03:48:25.315] Starting the invocation (attempt 1)
[03:48:25.386] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:48:25.386] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:48:25.387] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9087734008377580592",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"601a21091cd6458740ae455c75ac25b0fc40e3db\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9087734008377580592",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/601a21091cd6458740ae455c75ac25b0fc40e3db",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/601a21091cd6458740ae455c75ac25b0fc40e3db",
"gitiles_ref:refs/heads/master"
]
}
[03:48:27.104] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1549424905479439",
"id": "8922303946270589664",
"lease_expiration_ts": "1549543706526261",
"lease_key": "1845390651",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"601a21091cd6458740ae455c75ac25b0fc40e3db\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1549424906827135",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11859",
"builder:Windows deterministic",
"buildset:commit/git/601a21091cd6458740ae455c75ac25b0fc40e3db",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/601a21091cd6458740ae455c75ac25b0fc40e3db",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9087734008377580592",
"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/8922303946270589664/+/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:42d9bd57479aca10",
"user_agent:luci-scheduler"
],
"updated_ts": "1549424906827368",
"url": "https://ci.chromium.org/b/8922303946270589664",
"utcnow_ts": "1549424907089591"
}
}
[03:48:27.104] Task URL: https://ci.chromium.org/b/8922303946270589664
[03:48:27.104] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:2:0) after 1m0s
[03:49:27.191] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:2:0)
[03:49:27.191] Timer tick, asking Buildbucket for the build status
[03:49:27.312] Build 8922303946270589664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:49:27.312] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:3:0) after 1m0s
[03:50:23.206] Received PubSub notification, asking Buildbucket for the build status
[03:50:23.303] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:27.348] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:3:0)
[03:50:27.348] Timer tick, asking Buildbucket for the build status
[03:50:27.409] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:27.409] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:5:0) after 1m0s
[03:51:27.481] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:5:0)
[03:51:27.481] Timer tick, asking Buildbucket for the build status
[03:51:27.831] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:27.831] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:6:0) after 1m0s
[03:52:27.886] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:6:0)
[03:52:27.886] Timer tick, asking Buildbucket for the build status
[03:52:28.215] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:28.215] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:7:0) after 1m0s
[03:53:28.234] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:7:0)
[03:53:28.234] Timer tick, asking Buildbucket for the build status
[03:53:28.278] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:28.278] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:8:0) after 1m0s
[03:54:28.306] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:8:0)
[03:54:28.306] Timer tick, asking Buildbucket for the build status
[03:54:28.384] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:28.384] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:9:0) after 1m0s
[03:55:28.407] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:9:0)
[03:55:28.407] Timer tick, asking Buildbucket for the build status
[03:55:28.444] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:28.444] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:10:0) after 1m0s
[03:56:28.578] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:10:0)
[03:56:28.578] Timer tick, asking Buildbucket for the build status
[03:56:28.629] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:28.629] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:11:0) after 1m0s
[03:57:28.657] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:11:0)
[03:57:28.657] Timer tick, asking Buildbucket for the build status
[03:57:28.765] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:28.765] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:12:0) after 1m0s
[03:58:28.784] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:12:0)
[03:58:28.784] Timer tick, asking Buildbucket for the build status
[03:58:28.954] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:28.954] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:13:0) after 1m0s
[03:59:28.978] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:13:0)
[03:59:28.978] Timer tick, asking Buildbucket for the build status
[03:59:29.108] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:29.108] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:14:0) after 1m0s
[04:00:29.143] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:14:0)
[04:00:29.143] Timer tick, asking Buildbucket for the build status
[04:00:29.198] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:29.198] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:15:0) after 1m0s
[04:01:29.232] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:15:0)
[04:01:29.232] Timer tick, asking Buildbucket for the build status
[04:01:29.380] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:29.380] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:16:0) after 1m0s
[04:02:29.405] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:16:0)
[04:02:29.405] Timer tick, asking Buildbucket for the build status
[04:02:29.455] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:29.455] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:17:0) after 1m0s
[04:03:29.475] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:17:0)
[04:03:29.475] Timer tick, asking Buildbucket for the build status
[04:03:29.584] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:29.584] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:18:0) after 1m0s
[04:04:29.669] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:18:0)
[04:04:29.669] Timer tick, asking Buildbucket for the build status
[04:04:29.773] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:29.773] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:19:0) after 1m0s
[04:05:29.793] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:19:0)
[04:05:29.793] Timer tick, asking Buildbucket for the build status
[04:05:29.988] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:29.988] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:20:0) after 1m0s
[04:06:30.009] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:20:0)
[04:06:30.009] Timer tick, asking Buildbucket for the build status
[04:06:30.153] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:30.153] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:21:0) after 1m0s
[04:07:30.197] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:21:0)
[04:07:30.197] Timer tick, asking Buildbucket for the build status
[04:07:30.338] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:30.338] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:22:0) after 1m0s
[04:08:30.368] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:22:0)
[04:08:30.368] Timer tick, asking Buildbucket for the build status
[04:08:30.420] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:30.420] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:23:0) after 1m0s
[04:09:30.441] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:23:0)
[04:09:30.441] Timer tick, asking Buildbucket for the build status
[04:09:30.678] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:30.678] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:24:0) after 1m0s
[04:10:30.728] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:24:0)
[04:10:30.728] Timer tick, asking Buildbucket for the build status
[04:10:30.819] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:30.819] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:25:0) after 1m0s
[04:11:30.839] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:25:0)
[04:11:30.839] Timer tick, asking Buildbucket for the build status
[04:11:31.098] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:31.098] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:26:0) after 1m0s
[04:12:31.117] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:26:0)
[04:12:31.117] Timer tick, asking Buildbucket for the build status
[04:12:31.250] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:31.250] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:27:0) after 1m0s
[04:13:31.279] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:27:0)
[04:13:31.279] Timer tick, asking Buildbucket for the build status
[04:13:31.343] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:31.343] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:28:0) after 1m0s
[04:14:31.362] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:28:0)
[04:14:31.362] Timer tick, asking Buildbucket for the build status
[04:14:31.451] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:31.451] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:29:0) after 1m0s
[04:15:31.536] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:29:0)
[04:15:31.536] Timer tick, asking Buildbucket for the build status
[04:15:31.621] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:31.621] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:30:0) after 1m0s
[04:16:31.693] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:30:0)
[04:16:31.693] Timer tick, asking Buildbucket for the build status
[04:16:31.922] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:31.922] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:31:0) after 1m0s
[04:17:31.942] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:31:0)
[04:17:31.942] Timer tick, asking Buildbucket for the build status
[04:17:32.059] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:32.059] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:32:0) after 1m0s
[04:18:32.089] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:32:0)
[04:18:32.089] Timer tick, asking Buildbucket for the build status
[04:18:32.283] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:32.283] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:33:0) after 1m0s
[04:19:32.308] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:33:0)
[04:19:32.308] Timer tick, asking Buildbucket for the build status
[04:19:32.356] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:32.356] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:34:0) after 1m0s
[04:20:32.379] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:34:0)
[04:20:32.379] Timer tick, asking Buildbucket for the build status
[04:20:32.440] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:32.440] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:35:0) after 1m0s
[04:21:32.462] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:35:0)
[04:21:32.462] Timer tick, asking Buildbucket for the build status
[04:21:32.508] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:32.508] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:36:0) after 1m0s
[04:22:32.527] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:36:0)
[04:22:32.527] Timer tick, asking Buildbucket for the build status
[04:22:32.581] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:32.581] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:37:0) after 1m0s
[04:23:32.599] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:37:0)
[04:23:32.599] Timer tick, asking Buildbucket for the build status
[04:23:32.639] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:32.639] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:38:0) after 1m0s
[04:24:32.663] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:38:0)
[04:24:32.663] Timer tick, asking Buildbucket for the build status
[04:24:32.863] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:32.863] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:39:0) after 1m0s
[04:25:32.886] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:39:0)
[04:25:32.886] Timer tick, asking Buildbucket for the build status
[04:25:32.935] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:32.935] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:40:0) after 1m0s
[04:26:32.963] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:40:0)
[04:26:32.963] Timer tick, asking Buildbucket for the build status
[04:26:33.180] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:33.181] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:41:0) after 1m0s
[04:27:33.205] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:41:0)
[04:27:33.205] Timer tick, asking Buildbucket for the build status
[04:27:33.435] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:33.435] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:42:0) after 1m0s
[04:28:33.481] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:42:0)
[04:28:33.481] Timer tick, asking Buildbucket for the build status
[04:28:33.538] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:33.538] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:43:0) after 1m0s
[04:29:33.564] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:43:0)
[04:29:33.564] Timer tick, asking Buildbucket for the build status
[04:29:33.723] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:33.723] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:44:0) after 1m0s
[04:30:33.770] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:44:0)
[04:30:33.770] Timer tick, asking Buildbucket for the build status
[04:30:33.816] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:33.816] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:45:0) after 1m0s
[04:31:33.837] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:45:0)
[04:31:33.837] Timer tick, asking Buildbucket for the build status
[04:31:33.915] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:33.915] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:46:0) after 1m0s
[04:32:33.940] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:46:0)
[04:32:33.940] Timer tick, asking Buildbucket for the build status
[04:32:34.295] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:34.295] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:47:0) after 1m0s
[04:33:34.386] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:47:0)
[04:33:34.386] Timer tick, asking Buildbucket for the build status
[04:33:34.519] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:34.522] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:48:0) after 1m0s
[04:34:34.554] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:48:0)
[04:34:34.554] Timer tick, asking Buildbucket for the build status
[04:34:34.603] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:34.603] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:49:0) after 1m0s
[04:35:34.623] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:49:0)
[04:35:34.623] Timer tick, asking Buildbucket for the build status
[04:35:34.740] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:34.740] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:50:0) after 1m0s
[04:36:34.763] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:50:0)
[04:36:34.763] Timer tick, asking Buildbucket for the build status
[04:36:34.854] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:34.854] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:51:0) after 1m0s
[04:37:34.901] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:51:0)
[04:37:34.901] Timer tick, asking Buildbucket for the build status
[04:37:35.007] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:35.007] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:52:0) after 1m0s
[04:38:35.029] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:52:0)
[04:38:35.029] Timer tick, asking Buildbucket for the build status
[04:38:35.079] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:35.079] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:53:0) after 1m0s
[04:39:35.100] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:53:0)
[04:39:35.100] Timer tick, asking Buildbucket for the build status
[04:39:35.270] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:35.270] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:54:0) after 1m0s
[04:40:35.290] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:54:0)
[04:40:35.290] Timer tick, asking Buildbucket for the build status
[04:40:35.336] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:35.336] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:55:0) after 1m0s
[04:41:35.367] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:55:0)
[04:41:35.367] Timer tick, asking Buildbucket for the build status
[04:41:35.432] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:35.432] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:56:0) after 1m0s
[04:42:35.455] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:56:0)
[04:42:35.455] Timer tick, asking Buildbucket for the build status
[04:42:35.506] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:35.506] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:57:0) after 1m0s
[04:43:35.530] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:57:0)
[04:43:35.530] Timer tick, asking Buildbucket for the build status
[04:43:35.674] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:35.674] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:58:0) after 1m0s
[04:44:35.704] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:58:0)
[04:44:35.705] Timer tick, asking Buildbucket for the build status
[04:44:35.751] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:35.751] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:59:0) after 1m0s
[04:45:35.771] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:59:0)
[04:45:35.771] Timer tick, asking Buildbucket for the build status
[04:45:35.877] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:35.877] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:60:0) after 1m0s
[04:46:35.908] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:60:0)
[04:46:35.908] Timer tick, asking Buildbucket for the build status
[04:46:35.994] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:35.994] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:61:0) after 1m0s
[04:47:36.013] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:61:0)
[04:47:36.013] Timer tick, asking Buildbucket for the build status
[04:47:36.094] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:36.094] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:62:0) after 1m0s
[04:48:36.124] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:62:0)
[04:48:36.124] Timer tick, asking Buildbucket for the build status
[04:48:36.178] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:36.178] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:63:0) after 1m0s
[04:49:36.228] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:63:0)
[04:49:36.228] Timer tick, asking Buildbucket for the build status
[04:49:36.290] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:36.290] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:64:0) after 1m0s
[04:50:36.310] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:64:0)
[04:50:36.310] Timer tick, asking Buildbucket for the build status
[04:50:36.371] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:36.371] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:65:0) after 1m0s
[04:51:36.395] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:65:0)
[04:51:36.395] Timer tick, asking Buildbucket for the build status
[04:51:36.437] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:36.437] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:66:0) after 1m0s
[04:52:36.477] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:66:0)
[04:52:36.477] Timer tick, asking Buildbucket for the build status
[04:52:36.700] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:36.700] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:67:0) after 1m0s
[04:53:36.726] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:67:0)
[04:53:36.726] Timer tick, asking Buildbucket for the build status
[04:53:36.854] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:36.854] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:68:0) after 1m0s
[04:54:36.895] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:68:0)
[04:54:36.895] Timer tick, asking Buildbucket for the build status
[04:54:36.967] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:36.967] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:69:0) after 1m0s
[04:55:36.994] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:69:0)
[04:55:36.994] Timer tick, asking Buildbucket for the build status
[04:55:37.053] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:37.053] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:70:0) after 1m0s
[04:56:37.097] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:70:0)
[04:56:37.097] Timer tick, asking Buildbucket for the build status
[04:56:37.142] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:37.142] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:71:0) after 1m0s
[04:57:37.170] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:71:0)
[04:57:37.170] Timer tick, asking Buildbucket for the build status
[04:57:37.246] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:37.246] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:72:0) after 1m0s
[04:58:37.280] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:72:0)
[04:58:37.280] Timer tick, asking Buildbucket for the build status
[04:58:37.479] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:37.479] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:73:0) after 1m0s
[04:59:37.511] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:73:0)
[04:59:37.511] Timer tick, asking Buildbucket for the build status
[04:59:37.631] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:37.631] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:74:0) after 1m0s
[05:00:37.658] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:74:0)
[05:00:37.658] Timer tick, asking Buildbucket for the build status
[05:00:37.983] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:37.983] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:75:0) after 1m0s
[05:01:38.006] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:75:0)
[05:01:38.006] Timer tick, asking Buildbucket for the build status
[05:01:38.093] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:38.093] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:76:0) after 1m0s
[05:02:38.115] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:76:0)
[05:02:38.115] Timer tick, asking Buildbucket for the build status
[05:02:38.172] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:38.172] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:77:0) after 1m0s
[05:03:38.192] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:77:0)
[05:03:38.192] Timer tick, asking Buildbucket for the build status
[05:03:38.251] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:38.251] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:78:0) after 1m0s
[05:04:38.283] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:78:0)
[05:04:38.283] Timer tick, asking Buildbucket for the build status
[05:04:38.365] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:38.365] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:79:0) after 1m0s
[05:05:38.386] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:79:0)
[05:05:38.386] Timer tick, asking Buildbucket for the build status
[05:05:38.446] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:38.446] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:80:0) after 1m0s
[05:06:38.471] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:80:0)
[05:06:38.471] Timer tick, asking Buildbucket for the build status
[05:06:38.525] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:38.525] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:81:0) after 1m0s
[05:07:38.549] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:81:0)
[05:07:38.549] Timer tick, asking Buildbucket for the build status
[05:07:38.774] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:38.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:82:0) after 1m0s
[05:08:38.798] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:82:0)
[05:08:38.798] Timer tick, asking Buildbucket for the build status
[05:08:38.945] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:38.945] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:83:0) after 1m0s
[05:09:38.974] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:83:0)
[05:09:38.974] Timer tick, asking Buildbucket for the build status
[05:09:39.027] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:39.027] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:84:0) after 1m0s
[05:10:39.053] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:84:0)
[05:10:39.053] Timer tick, asking Buildbucket for the build status
[05:10:39.189] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:39.189] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:85:0) after 1m0s
[05:11:39.214] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:85:0)
[05:11:39.214] Timer tick, asking Buildbucket for the build status
[05:11:39.307] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:39.307] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:86:0) after 1m0s
[05:12:39.338] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:86:0)
[05:12:39.338] Timer tick, asking Buildbucket for the build status
[05:12:39.619] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:39.619] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:87:0) after 1m0s
[05:13:39.641] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:87:0)
[05:13:39.641] Timer tick, asking Buildbucket for the build status
[05:13:39.768] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:39.768] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:88:0) after 1m0s
[05:14:39.800] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:88:0)
[05:14:39.800] Timer tick, asking Buildbucket for the build status
[05:14:39.982] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:39.982] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:89:0) after 1m0s
[05:15:40.019] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:89:0)
[05:15:40.019] Timer tick, asking Buildbucket for the build status
[05:15:40.088] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:40.088] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:90:0) after 1m0s
[05:16:40.125] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:90:0)
[05:16:40.125] Timer tick, asking Buildbucket for the build status
[05:16:40.204] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:40.204] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:91:0) after 1m0s
[05:17:40.273] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:91:0)
[05:17:40.273] Timer tick, asking Buildbucket for the build status
[05:17:40.564] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:40.564] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:92:0) after 1m0s
[05:18:40.590] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:92:0)
[05:18:40.590] Timer tick, asking Buildbucket for the build status
[05:18:40.760] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:40.760] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:93:0) after 1m0s
[05:19:40.790] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:93:0)
[05:19:40.790] Timer tick, asking Buildbucket for the build status
[05:19:40.846] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:40.846] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:94:0) after 1m0s
[05:20:40.896] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:94:0)
[05:20:40.896] Timer tick, asking Buildbucket for the build status
[05:20:40.941] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:40.941] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:95:0) after 1m0s
[05:21:40.968] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:95:0)
[05:21:40.968] Timer tick, asking Buildbucket for the build status
[05:21:41.036] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:41.036] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:96:0) after 1m0s
[05:22:41.063] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:96:0)
[05:22:41.063] Timer tick, asking Buildbucket for the build status
[05:22:41.109] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:41.109] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:97:0) after 1m0s
[05:23:41.156] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:97:0)
[05:23:41.156] Timer tick, asking Buildbucket for the build status
[05:23:41.213] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:41.213] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:98:0) after 1m0s
[05:24:41.274] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:98:0)
[05:24:41.274] Timer tick, asking Buildbucket for the build status
[05:24:41.330] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:41.330] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:99:0) after 1m0s
[05:25:41.356] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:99:0)
[05:25:41.356] Timer tick, asking Buildbucket for the build status
[05:25:41.657] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:41.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:100:0) after 1m0s
[05:26:41.697] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:100:0)
[05:26:41.697] Timer tick, asking Buildbucket for the build status
[05:26:42.019] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:42.019] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:101:0) after 1m0s
[05:27:42.045] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:101:0)
[05:27:42.045] Timer tick, asking Buildbucket for the build status
[05:27:42.193] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:42.193] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:102:0) after 1m0s
[05:28:42.219] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:102:0)
[05:28:42.219] Timer tick, asking Buildbucket for the build status
[05:28:42.371] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:42.372] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:103:0) after 1m0s
[05:29:42.415] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:103:0)
[05:29:42.415] Timer tick, asking Buildbucket for the build status
[05:29:42.468] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:42.468] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:104:0) after 1m0s
[05:30:42.502] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:104:0)
[05:30:42.502] Timer tick, asking Buildbucket for the build status
[05:30:42.710] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:42.710] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:105:0) after 1m0s
[05:31:42.751] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:105:0)
[05:31:42.751] Timer tick, asking Buildbucket for the build status
[05:31:42.958] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:42.958] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:106:0) after 1m0s
[05:32:42.985] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:106:0)
[05:32:42.985] Timer tick, asking Buildbucket for the build status
[05:32:43.045] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:43.045] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:107:0) after 1m0s
[05:33:43.087] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:107:0)
[05:33:43.087] Timer tick, asking Buildbucket for the build status
[05:33:43.265] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:43.265] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:108:0) after 1m0s
[05:34:43.291] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:108:0)
[05:34:43.291] Timer tick, asking Buildbucket for the build status
[05:34:43.379] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:43.379] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:109:0) after 1m0s
[05:35:43.405] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:109:0)
[05:35:43.405] Timer tick, asking Buildbucket for the build status
[05:35:43.456] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:43.456] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:110:0) after 1m0s
[05:36:43.485] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:110:0)
[05:36:43.485] Timer tick, asking Buildbucket for the build status
[05:36:43.878] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:43.878] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:111:0) after 1m0s
[05:37:43.945] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:111:0)
[05:37:43.945] Timer tick, asking Buildbucket for the build status
[05:37:43.987] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:43.987] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:112:0) after 1m0s
[05:38:44.016] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:112:0)
[05:38:44.016] Timer tick, asking Buildbucket for the build status
[05:38:44.104] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:44.104] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:113:0) after 1m0s
[05:39:44.143] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:113:0)
[05:39:44.143] Timer tick, asking Buildbucket for the build status
[05:39:44.277] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:44.277] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:114:0) after 1m0s
[05:40:44.323] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:114:0)
[05:40:44.323] Timer tick, asking Buildbucket for the build status
[05:40:44.376] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:44.376] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:115:0) after 1m0s
[05:41:44.408] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:115:0)
[05:41:44.408] Timer tick, asking Buildbucket for the build status
[05:41:44.469] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:44.469] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:116:0) after 1m0s
[05:42:44.510] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:116:0)
[05:42:44.510] Timer tick, asking Buildbucket for the build status
[05:42:44.573] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:44.573] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:117:0) after 1m0s
[05:43:44.597] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:117:0)
[05:43:44.597] Timer tick, asking Buildbucket for the build status
[05:43:44.637] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:44.637] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:118:0) after 1m0s
[05:44:44.687] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:118:0)
[05:44:44.687] Timer tick, asking Buildbucket for the build status
[05:44:44.754] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:44.754] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:119:0) after 1m0s
[05:45:44.778] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:119:0)
[05:45:44.778] Timer tick, asking Buildbucket for the build status
[05:45:44.902] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:44.902] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:120:0) after 1m0s
[05:46:44.994] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:120:0)
[05:46:44.994] Timer tick, asking Buildbucket for the build status
[05:46:45.059] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:45.059] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:121:0) after 1m0s
[05:47:45.085] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:121:0)
[05:47:45.085] Timer tick, asking Buildbucket for the build status
[05:47:45.167] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:45.167] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:122:0) after 1m0s
[05:48:45.192] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:122:0)
[05:48:45.192] Timer tick, asking Buildbucket for the build status
[05:48:45.256] Build 8922303946270589664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:45.256] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087734008377580592:123:0) after 1m0s
[05:49:44.486] Received PubSub notification, asking Buildbucket for the build status
[05:49:44.546] Build 8922303946270589664: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:49:44.546] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1549432183486537",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1549424905479439",
"id": "8922303946270589664",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"601a21091cd6458740ae455c75ac25b0fc40e3db\"}}",
"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\": [\"4055-721ffb4\"], \"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\": \"601a21091cd6458740ae455c75ac25b0fc40e3db\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"978fa65a2cc5e73f70b4d5c4021e49654b7c61b1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26557}\", \"$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@{#629456}\", \"branch\": \"refs/heads/master\", \"revision\": \"601a21091cd6458740ae455c75ac25b0fc40e3db\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"65835be72273fc0f6016f24ad2344efd54ff1091\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"bf5dbd8d67feb3e1eaa939ac24839fbc414cc611\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1549424905479439, \"tags\": [\"build_address:luci.chromium.ci/Windows deterministic/11859\", \"builder:Windows deterministic\", \"buildset:commit/git/601a21091cd6458740ae455c75ac25b0fc40e3db\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/601a21091cd6458740ae455c75ac25b0fc40e3db\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9087734008377580592\", \"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\": \"8922303946270589664\"}}, \"got_v8_revision\": \"fa9e50642d1c610f8555bd0bc378485521fdc44e\", \"got_v8_revision_cp\": \"refs/heads/7.4.83@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 11859, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Windows deterministic\", \"key\": \"builder\"}, {\"value\": \"commit/git/601a21091cd6458740ae455c75ac25b0fc40e3db\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/chromium/src/+/601a21091cd6458740ae455c75ac25b0fc40e3db\", \"key\": \"buildset\"}, {\"value\": \"9087734008377580592\", \"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\": 11859, \"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\": \"8922303946270589664\", \"input\": {\"gitilesCommit\": {\"project\": \"chromium/src\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"601a21091cd6458740ae455c75ac25b0fc40e3db\"}}, \"createTime\": \"2019-02-06T03:48:25.479439Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1549425022539867",
"status": "COMPLETED",
"status_changed_ts": "1549432184036473",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11859",
"builder:Windows deterministic",
"buildset:commit/git/601a21091cd6458740ae455c75ac25b0fc40e3db",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/601a21091cd6458740ae455c75ac25b0fc40e3db",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9087734008377580592",
"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/8922303946270589664/+/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:42d9bd57479aca10",
"user_agent:luci-scheduler"
],
"updated_ts": "1549432184037905",
"url": "https://ci.chromium.org/b/8922303946270589664",
"utcnow_ts": "1549432184517993"
}
}
[05:49:44.546] Invocation finished in 2h1m20.459757514s with status SUCCEEDED