[17:30:02.430] New invocation is queued and will start shortly
[17:30:03.799] Starting the invocation (attempt 1)
[17:30:03.833] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:30:03.833] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:30:03.833] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9074636352174684928",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"e3fbe1574c1094f47c55de3be1760bcdca15238b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9074636352174684928",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/e3fbe1574c1094f47c55de3be1760bcdca15238b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e3fbe1574c1094f47c55de3be1760bcdca15238b",
"gitiles_ref:refs/heads/master"
]
}
[17:30:11.322] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1561915806264783",
"id": "8909206287489298832",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"e3fbe1574c1094f47c55de3be1760bcdca15238b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1561915810318273",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13982",
"builder:Windows deterministic",
"buildset:commit/git/e3fbe1574c1094f47c55de3be1760bcdca15238b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e3fbe1574c1094f47c55de3be1760bcdca15238b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9074636352174684928",
"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/8909206287489298832/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1561915810318457",
"url": "https://ci.chromium.org/b/8909206287489298832",
"utcnow_ts": "1561915811245628"
}
}
[17:30:11.322] Task URL: https://ci.chromium.org/b/8909206287489298832
[17:30:11.322] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:2:0) after 1m0s
[17:30:18.530] Received PubSub notification, asking Buildbucket for the build status
[17:30:19.519] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:11.342] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:2:0)
[17:31:11.342] Timer tick, asking Buildbucket for the build status
[17:31:12.482] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:12.482] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:4:0) after 1m0s
[17:32:12.540] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:4:0)
[17:32:12.540] Timer tick, asking Buildbucket for the build status
[17:32:13.171] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:13.171] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:5:0) after 1m0s
[17:33:13.193] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:5:0)
[17:33:13.193] Timer tick, asking Buildbucket for the build status
[17:33:13.317] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:13.317] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:6:0) after 1m0s
[17:34:13.336] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:6:0)
[17:34:13.336] Timer tick, asking Buildbucket for the build status
[17:34:13.615] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:13.615] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:7:0) after 1m0s
[17:35:13.643] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:7:0)
[17:35:13.643] Timer tick, asking Buildbucket for the build status
[17:35:14.776] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:14.776] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:8:0) after 1m0s
[17:36:14.763] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:8:0)
[17:36:14.763] Timer tick, asking Buildbucket for the build status
[17:36:15.066] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:15.066] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:9:0) after 1m0s
[17:37:15.092] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:9:0)
[17:37:15.092] Timer tick, asking Buildbucket for the build status
[17:37:15.247] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:15.247] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:10:0) after 1m0s
[17:38:15.320] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:10:0)
[17:38:15.320] Timer tick, asking Buildbucket for the build status
[17:38:15.762] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:15.762] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:11:0) after 1m0s
[17:39:15.801] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:11:0)
[17:39:15.802] Timer tick, asking Buildbucket for the build status
[17:39:15.929] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:15.929] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:12:0) after 1m0s
[17:40:15.956] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:12:0)
[17:40:15.956] Timer tick, asking Buildbucket for the build status
[17:40:16.103] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:16.103] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:13:0) after 1m0s
[17:41:16.125] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:13:0)
[17:41:16.125] Timer tick, asking Buildbucket for the build status
[17:41:16.406] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:16.406] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:14:0) after 1m0s
[17:42:16.478] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:14:0)
[17:42:16.478] Timer tick, asking Buildbucket for the build status
[17:42:16.999] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:16.999] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:15:0) after 1m0s
[17:43:17.047] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:15:0)
[17:43:17.047] Timer tick, asking Buildbucket for the build status
[17:43:17.514] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:17.514] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:16:0) after 1m0s
[17:44:17.537] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:16:0)
[17:44:17.537] Timer tick, asking Buildbucket for the build status
[17:44:17.646] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:17.646] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:17:0) after 1m0s
[17:45:17.698] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:17:0)
[17:45:17.698] Timer tick, asking Buildbucket for the build status
[17:45:18.627] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:18.627] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:18:0) after 1m0s
[17:46:18.721] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:18:0)
[17:46:18.721] Timer tick, asking Buildbucket for the build status
[17:46:19.141] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:19.141] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:19:0) after 1m0s
[17:47:19.175] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:19:0)
[17:47:19.175] Timer tick, asking Buildbucket for the build status
[17:47:19.981] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:19.981] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:20:0) after 1m0s
[17:48:20.003] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:20:0)
[17:48:20.003] Timer tick, asking Buildbucket for the build status
[17:48:20.238] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:20.238] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:21:0) after 1m0s
[17:49:20.286] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:21:0)
[17:49:20.286] Timer tick, asking Buildbucket for the build status
[17:49:20.492] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:20.492] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:22:0) after 1m0s
[17:50:20.644] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:22:0)
[17:50:20.644] Timer tick, asking Buildbucket for the build status
[17:50:20.760] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:20.760] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:23:0) after 1m0s
[17:51:20.784] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:23:0)
[17:51:20.784] Timer tick, asking Buildbucket for the build status
[17:51:21.469] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:21.469] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:24:0) after 1m0s
[17:52:21.479] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:24:0)
[17:52:21.479] Timer tick, asking Buildbucket for the build status
[17:52:22.846] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:22.846] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:25:0) after 1m0s
[17:53:22.887] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:25:0)
[17:53:22.887] Timer tick, asking Buildbucket for the build status
[17:53:23.125] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:23.125] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:26:0) after 1m0s
[17:54:23.147] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:26:0)
[17:54:23.147] Timer tick, asking Buildbucket for the build status
[17:54:24.461] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:24.461] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:27:0) after 1m0s
[17:55:24.482] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:27:0)
[17:55:24.482] Timer tick, asking Buildbucket for the build status
[17:55:25.191] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:25.191] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:28:0) after 1m0s
[17:56:26.443] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:28:0)
[17:56:26.443] Timer tick, asking Buildbucket for the build status
[17:56:26.593] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:26.593] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:29:0) after 1m0s
[17:57:26.963] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:29:0)
[17:57:26.963] Timer tick, asking Buildbucket for the build status
[17:57:27.279] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:27.280] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:30:0) after 1m0s
[17:58:28.588] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:30:0)
[17:58:28.588] Timer tick, asking Buildbucket for the build status
[17:58:29.092] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:29.092] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:31:0) after 1m0s
[17:59:29.389] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:31:0)
[17:59:29.389] Timer tick, asking Buildbucket for the build status
[17:59:31.082] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:31.082] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:32:0) after 1m0s
[18:00:31.285] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:32:0)
[18:00:31.285] Timer tick, asking Buildbucket for the build status
[18:00:31.823] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:31.823] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:33:0) after 1m0s
[18:01:32.048] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:33:0)
[18:01:32.048] Timer tick, asking Buildbucket for the build status
[18:01:32.255] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:32.255] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:34:0) after 1m0s
[18:02:32.312] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:34:0)
[18:02:32.312] Timer tick, asking Buildbucket for the build status
[18:02:32.454] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:32.454] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:35:0) after 1m0s
[18:03:34.045] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:35:0)
[18:03:34.045] Timer tick, asking Buildbucket for the build status
[18:03:34.451] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:34.451] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:36:0) after 1m0s
[18:04:34.535] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:36:0)
[18:04:34.535] Timer tick, asking Buildbucket for the build status
[18:04:36.445] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:36.445] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:37:0) after 1m0s
[18:05:36.489] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:37:0)
[18:05:36.489] Timer tick, asking Buildbucket for the build status
[18:05:36.629] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:36.629] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:38:0) after 1m0s
[18:06:36.657] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:38:0)
[18:06:36.657] Timer tick, asking Buildbucket for the build status
[18:06:37.375] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:37.375] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:39:0) after 1m0s
[18:07:37.385] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:39:0)
[18:07:37.385] Timer tick, asking Buildbucket for the build status
[18:07:37.981] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:37.981] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:40:0) after 1m0s
[18:08:38.090] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:40:0)
[18:08:38.090] Timer tick, asking Buildbucket for the build status
[18:08:38.234] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:38.234] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:41:0) after 1m0s
[18:09:38.265] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:41:0)
[18:09:38.265] Timer tick, asking Buildbucket for the build status
[18:09:38.628] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:38.628] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:42:0) after 1m0s
[18:10:38.701] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:42:0)
[18:10:38.701] Timer tick, asking Buildbucket for the build status
[18:10:38.852] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:38.852] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:43:0) after 1m0s
[18:11:38.878] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:43:0)
[18:11:38.878] Timer tick, asking Buildbucket for the build status
[18:11:39.008] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:39.008] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:44:0) after 1m0s
[18:12:39.044] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:44:0)
[18:12:39.044] Timer tick, asking Buildbucket for the build status
[18:12:39.184] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:39.184] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:45:0) after 1m0s
[18:13:39.507] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:45:0)
[18:13:39.507] Timer tick, asking Buildbucket for the build status
[18:13:39.671] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:39.672] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:46:0) after 1m0s
[18:14:39.795] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:46:0)
[18:14:39.795] Timer tick, asking Buildbucket for the build status
[18:14:40.680] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:40.680] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:47:0) after 1m0s
[18:15:40.842] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:47:0)
[18:15:40.842] Timer tick, asking Buildbucket for the build status
[18:15:41.040] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:41.040] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:48:0) after 1m0s
[18:16:41.015] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:48:0)
[18:16:41.015] Timer tick, asking Buildbucket for the build status
[18:16:41.308] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:41.308] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:49:0) after 1m0s
[18:17:41.461] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:49:0)
[18:17:41.461] Timer tick, asking Buildbucket for the build status
[18:17:41.798] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:41.798] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:50:0) after 1m0s
[18:18:41.793] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:50:0)
[18:18:41.793] Timer tick, asking Buildbucket for the build status
[18:18:42.377] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:42.377] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:51:0) after 1m0s
[18:19:46.069] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:51:0)
[18:19:46.069] Timer tick, asking Buildbucket for the build status
[18:19:46.712] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:46.712] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:52:0) after 1m0s
[18:20:47.988] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:52:0)
[18:20:47.988] Timer tick, asking Buildbucket for the build status
[18:20:48.440] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:48.440] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:53:0) after 1m0s
[18:21:48.696] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:53:0)
[18:21:48.696] Timer tick, asking Buildbucket for the build status
[18:21:48.826] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:48.826] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:54:0) after 1m0s
[18:22:49.599] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:54:0)
[18:22:49.599] Timer tick, asking Buildbucket for the build status
[18:22:49.871] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:49.871] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:55:0) after 1m0s
[18:23:58.630] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:55:0)
[18:23:58.630] Timer tick, asking Buildbucket for the build status
[18:23:58.813] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:58.813] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:56:0) after 1m0s
[18:24:58.836] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:56:0)
[18:24:58.836] Timer tick, asking Buildbucket for the build status
[18:24:59.130] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:59.130] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:57:0) after 1m0s
[18:25:59.223] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:57:0)
[18:25:59.223] Timer tick, asking Buildbucket for the build status
[18:25:59.421] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:59.421] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:58:0) after 1m0s
[18:26:59.492] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:58:0)
[18:26:59.492] Timer tick, asking Buildbucket for the build status
[18:26:59.749] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:59.749] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:59:0) after 1m0s
[18:27:59.768] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:59:0)
[18:27:59.768] Timer tick, asking Buildbucket for the build status
[18:28:00.209] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:00.209] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:60:0) after 1m0s
[18:29:00.291] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:60:0)
[18:29:00.291] Timer tick, asking Buildbucket for the build status
[18:29:00.583] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:00.583] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:61:0) after 1m0s
[18:30:00.611] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:61:0)
[18:30:00.611] Timer tick, asking Buildbucket for the build status
[18:30:01.111] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:01.111] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:62:0) after 1m0s
[18:31:01.132] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:62:0)
[18:31:01.132] Timer tick, asking Buildbucket for the build status
[18:31:02.113] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:02.113] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:63:0) after 1m0s
[18:32:02.161] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:63:0)
[18:32:02.161] Timer tick, asking Buildbucket for the build status
[18:32:02.338] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:02.338] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:64:0) after 1m0s
[18:33:02.399] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:64:0)
[18:33:02.399] Timer tick, asking Buildbucket for the build status
[18:33:02.932] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:02.932] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:65:0) after 1m0s
[18:34:02.953] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:65:0)
[18:34:02.953] Timer tick, asking Buildbucket for the build status
[18:34:03.192] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:03.192] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:66:0) after 1m0s
[18:35:03.223] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:66:0)
[18:35:03.223] Timer tick, asking Buildbucket for the build status
[18:35:08.764] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:08.764] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:67:0) after 1m0s
[18:36:08.834] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:67:0)
[18:36:08.834] Timer tick, asking Buildbucket for the build status
[18:36:09.055] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:09.055] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:68:0) after 1m0s
[18:37:09.126] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:68:0)
[18:37:09.126] Timer tick, asking Buildbucket for the build status
[18:37:09.285] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:09.286] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:69:0) after 1m0s
[18:38:09.359] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:69:0)
[18:38:09.359] Timer tick, asking Buildbucket for the build status
[18:38:10.880] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:10.880] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:70:0) after 1m0s
[18:39:10.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:70:0)
[18:39:10.905] Timer tick, asking Buildbucket for the build status
[18:39:11.006] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:11.006] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:71:0) after 1m0s
[18:40:11.047] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:71:0)
[18:40:11.047] Timer tick, asking Buildbucket for the build status
[18:40:11.233] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:11.233] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:72:0) after 1m0s
[18:41:11.274] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:72:0)
[18:41:11.274] Timer tick, asking Buildbucket for the build status
[18:41:11.601] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:11.601] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:73:0) after 1m0s
[18:42:11.761] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:73:0)
[18:42:11.761] Timer tick, asking Buildbucket for the build status
[18:42:12.063] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:12.063] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:74:0) after 1m0s
[18:43:12.138] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:74:0)
[18:43:12.138] Timer tick, asking Buildbucket for the build status
[18:43:12.796] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:12.796] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:75:0) after 1m0s
[18:44:15.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:75:0)
[18:44:15.154] Timer tick, asking Buildbucket for the build status
[18:44:15.287] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:15.287] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:76:0) after 1m0s
[18:45:15.334] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:76:0)
[18:45:15.334] Timer tick, asking Buildbucket for the build status
[18:45:15.452] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:15.452] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:77:0) after 1m0s
[18:46:15.566] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:77:0)
[18:46:15.566] Timer tick, asking Buildbucket for the build status
[18:46:15.847] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:15.847] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:78:0) after 1m0s
[18:47:15.870] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:78:0)
[18:47:15.870] Timer tick, asking Buildbucket for the build status
[18:47:16.081] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:16.081] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:79:0) after 1m0s
[18:48:16.079] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:79:0)
[18:48:16.079] Timer tick, asking Buildbucket for the build status
[18:48:16.886] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:16.886] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:80:0) after 1m0s
[18:49:16.959] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:80:0)
[18:49:16.959] Timer tick, asking Buildbucket for the build status
[18:49:17.143] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:17.143] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:81:0) after 1m0s
[18:50:17.236] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:81:0)
[18:50:17.236] Timer tick, asking Buildbucket for the build status
[18:50:17.458] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:17.458] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:82:0) after 1m0s
[18:51:17.504] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:82:0)
[18:51:17.504] Timer tick, asking Buildbucket for the build status
[18:51:17.709] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:17.710] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:83:0) after 1m0s
[18:52:17.833] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:83:0)
[18:52:17.833] Timer tick, asking Buildbucket for the build status
[18:52:17.998] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:17.998] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:84:0) after 1m0s
[18:53:18.024] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:84:0)
[18:53:18.024] Timer tick, asking Buildbucket for the build status
[18:53:18.462] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:18.462] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:85:0) after 1m0s
[18:54:18.650] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:85:0)
[18:54:18.650] Timer tick, asking Buildbucket for the build status
[18:54:19.024] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:19.024] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:86:0) after 1m0s
[18:55:19.101] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:86:0)
[18:55:19.101] Timer tick, asking Buildbucket for the build status
[18:55:19.250] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:19.250] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:87:0) after 1m0s
[18:56:19.273] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:87:0)
[18:56:19.273] Timer tick, asking Buildbucket for the build status
[18:56:19.706] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:19.706] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:88:0) after 1m0s
[18:57:19.779] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:88:0)
[18:57:19.779] Timer tick, asking Buildbucket for the build status
[18:57:20.142] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:20.142] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:89:0) after 1m0s
[18:58:20.216] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:89:0)
[18:58:20.216] Timer tick, asking Buildbucket for the build status
[18:58:20.322] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:20.322] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:90:0) after 1m0s
[18:59:20.502] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:90:0)
[18:59:20.502] Timer tick, asking Buildbucket for the build status
[18:59:20.638] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:20.638] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:91:0) after 1m0s
[19:00:20.740] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:91:0)
[19:00:20.740] Timer tick, asking Buildbucket for the build status
[19:00:20.851] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:20.851] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:92:0) after 1m0s
[19:01:20.988] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:92:0)
[19:01:20.988] Timer tick, asking Buildbucket for the build status
[19:01:21.902] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:21.902] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:93:0) after 1m0s
[19:02:22.056] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:93:0)
[19:02:22.056] Timer tick, asking Buildbucket for the build status
[19:02:22.275] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:22.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:94:0) after 1m0s
[19:03:22.653] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:94:0)
[19:03:22.653] Timer tick, asking Buildbucket for the build status
[19:03:23.412] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:23.412] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:95:0) after 1m0s
[19:04:23.608] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:95:0)
[19:04:23.608] Timer tick, asking Buildbucket for the build status
[19:04:23.945] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:23.945] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:96:0) after 1m0s
[19:05:24.032] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:96:0)
[19:05:24.032] Timer tick, asking Buildbucket for the build status
[19:05:24.178] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:24.178] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:97:0) after 1m0s
[19:06:24.400] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:97:0)
[19:06:24.400] Timer tick, asking Buildbucket for the build status
[19:06:24.652] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:24.652] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:98:0) after 1m0s
[19:07:24.912] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:98:0)
[19:07:24.912] Timer tick, asking Buildbucket for the build status
[19:07:25.365] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:25.365] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:99:0) after 1m0s
[19:08:25.440] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:99:0)
[19:08:25.440] Timer tick, asking Buildbucket for the build status
[19:08:25.657] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:25.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:100:0) after 1m0s
[19:09:25.655] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:100:0)
[19:09:25.655] Timer tick, asking Buildbucket for the build status
[19:09:26.350] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:26.350] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:101:0) after 1m0s
[19:10:26.468] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:101:0)
[19:10:26.468] Timer tick, asking Buildbucket for the build status
[19:10:27.852] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:27.852] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:102:0) after 1m0s
[19:11:28.014] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:102:0)
[19:11:28.014] Timer tick, asking Buildbucket for the build status
[19:11:28.165] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:28.165] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:103:0) after 1m0s
[19:12:28.190] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:103:0)
[19:12:28.190] Timer tick, asking Buildbucket for the build status
[19:12:28.502] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:28.502] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:104:0) after 1m0s
[19:13:28.503] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:104:0)
[19:13:28.503] Timer tick, asking Buildbucket for the build status
[19:13:28.716] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:28.716] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:105:0) after 1m0s
[19:14:28.799] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:105:0)
[19:14:28.799] Timer tick, asking Buildbucket for the build status
[19:14:28.929] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:28.929] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:106:0) after 1m0s
[19:15:28.988] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:106:0)
[19:15:28.988] Timer tick, asking Buildbucket for the build status
[19:15:29.638] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:29.638] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:107:0) after 1m0s
[19:16:29.795] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:107:0)
[19:16:29.795] Timer tick, asking Buildbucket for the build status
[19:16:39.618] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:39.619] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:108:0) after 1m0s
[19:17:40.258] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:108:0)
[19:17:40.258] Timer tick, asking Buildbucket for the build status
[19:17:40.868] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:40.868] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:109:0) after 1m0s
[19:18:40.914] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:109:0)
[19:18:40.914] Timer tick, asking Buildbucket for the build status
[19:18:42.218] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:42.218] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:110:0) after 1m0s
[19:19:42.526] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:110:0)
[19:19:42.526] Timer tick, asking Buildbucket for the build status
[19:19:43.275] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:43.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:111:0) after 1m0s
[19:20:43.492] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:111:0)
[19:20:43.492] Timer tick, asking Buildbucket for the build status
[19:20:43.639] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:43.639] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:112:0) after 1m0s
[19:21:43.725] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:112:0)
[19:21:43.725] Timer tick, asking Buildbucket for the build status
[19:21:44.256] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:44.256] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:113:0) after 1m0s
[19:22:44.354] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:113:0)
[19:22:44.354] Timer tick, asking Buildbucket for the build status
[19:22:44.527] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:44.527] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:114:0) after 1m0s
[19:23:44.571] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:114:0)
[19:23:44.571] Timer tick, asking Buildbucket for the build status
[19:23:44.707] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:44.708] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:115:0) after 1m0s
[19:24:44.737] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:115:0)
[19:24:44.737] Timer tick, asking Buildbucket for the build status
[19:24:44.904] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:44.904] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:116:0) after 1m0s
[19:25:44.951] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:116:0)
[19:25:44.951] Timer tick, asking Buildbucket for the build status
[19:25:45.657] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:45.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:117:0) after 1m0s
[19:26:45.687] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:117:0)
[19:26:45.687] Timer tick, asking Buildbucket for the build status
[19:26:45.902] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:45.902] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:118:0) after 1m0s
[19:27:45.985] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:118:0)
[19:27:45.985] Timer tick, asking Buildbucket for the build status
[19:27:46.294] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:46.294] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:119:0) after 1m0s
[19:28:46.342] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:119:0)
[19:28:46.342] Timer tick, asking Buildbucket for the build status
[19:28:46.580] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:46.580] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:120:0) after 1m0s
[19:29:46.724] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:120:0)
[19:29:46.724] Timer tick, asking Buildbucket for the build status
[19:29:47.199] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:47.199] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:121:0) after 1m0s
[19:30:47.285] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:121:0)
[19:30:47.285] Timer tick, asking Buildbucket for the build status
[19:30:47.452] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:47.452] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:122:0) after 1m0s
[19:31:47.512] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:122:0)
[19:31:47.512] Timer tick, asking Buildbucket for the build status
[19:31:47.648] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:47.648] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:123:0) after 1m0s
[19:32:47.754] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:123:0)
[19:32:47.754] Timer tick, asking Buildbucket for the build status
[19:32:47.916] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:47.917] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:124:0) after 1m0s
[19:33:47.944] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:124:0)
[19:33:47.944] Timer tick, asking Buildbucket for the build status
[19:33:48.640] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:48.641] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:125:0) after 1m0s
[19:34:48.780] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:125:0)
[19:34:48.780] Timer tick, asking Buildbucket for the build status
[19:34:48.959] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:48.959] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:126:0) after 1m0s
[19:35:48.995] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:126:0)
[19:35:48.995] Timer tick, asking Buildbucket for the build status
[19:35:49.327] Build 8909206287489298832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:49.327] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9074636352174684928:127:0) after 1m0s
[19:36:15.745] Received PubSub notification, asking Buildbucket for the build status
[19:36:16.221] Build 8909206287489298832: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:36:16.221] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1561923373966262",
"created_by": "project:chromium",
"created_ts": "1561915806264783",
"id": "8909206287489298832",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"e3fbe1574c1094f47c55de3be1760bcdca15238b\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"windows-deterministic-0-xbv6\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 13982, \"got_angle_revision\": \"f116aa9c158b91bc966ef0394d666da4b3e7bcb6\", \"got_dawn_revision\": \"eee3e41c554257a0ece66e70b2b4e1390ffa6ab0\", \"got_nacl_revision\": \"c4cf6f0621b94cca4053b19fa01ef329f5663880\", \"got_revision\": \"e3fbe1574c1094f47c55de3be1760bcdca15238b\", \"got_revision_cp\": \"refs/heads/master@{#673615}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"45ddaacef4d3cd90c58d766380fc1f418f998cd2\", \"got_v8_revision_cp\": \"refs/heads/7.7.166@{#1}\", \"got_webrtc_revision\": \"2ce1da53283cd87219fcd111dcf2c60eb613c79f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28422}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"e3fbe1574c1094f47c55de3be1760bcdca15238b\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Windows deterministic\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"windows-deterministic-0-xbv6\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4351-b85b41c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1561915816904486",
"status": "COMPLETED",
"status_changed_ts": "1561923375134481",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13982",
"builder:Windows deterministic",
"buildset:commit/git/e3fbe1574c1094f47c55de3be1760bcdca15238b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e3fbe1574c1094f47c55de3be1760bcdca15238b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9074636352174684928",
"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/8909206287489298832/+/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:45c2416dfa82cd10",
"user_agent:luci-scheduler"
],
"updated_ts": "1561923375144048",
"url": "https://ci.chromium.org/b/8909206287489298832",
"utcnow_ts": "1561923376213474"
}
}
[19:36:16.221] Invocation finished in 2h6m13.808979348s with status SUCCEEDED