[16:24:40.725] New invocation is queued and will start shortly
[16:24:41.982] Starting the invocation (attempt 1)
[16:24:42.032] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:24:42.032] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:24:42.032] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9073372106850288704",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"3bfa2242eb00e77d73fb8e723c3fbb5b74190985\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9073372106850288704",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/3bfa2242eb00e77d73fb8e723c3fbb5b74190985",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3bfa2242eb00e77d73fb8e723c3fbb5b74190985",
"gitiles_ref:refs/heads/master"
]
}
[16:24:43.011] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1563121482256542",
"id": "8907942044580249520",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3bfa2242eb00e77d73fb8e723c3fbb5b74190985\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1563121482885962",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14130",
"builder:Windows deterministic",
"buildset:commit/git/3bfa2242eb00e77d73fb8e723c3fbb5b74190985",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3bfa2242eb00e77d73fb8e723c3fbb5b74190985",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9073372106850288704",
"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/8907942044580249520/+/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": "1563121482886054",
"url": "https://ci.chromium.org/b/8907942044580249520",
"utcnow_ts": "1563121483002034"
}
}
[16:24:43.011] Task URL: https://ci.chromium.org/b/8907942044580249520
[16:24:43.011] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:2:0) after 1m0s
[16:25:43.000] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:2:0)
[16:25:43.000] Timer tick, asking Buildbucket for the build status
[16:25:43.151] Build 8907942044580249520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:25:43.151] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:3:0) after 1m0s
[16:25:44.420] Received PubSub notification, asking Buildbucket for the build status
[16:25:46.539] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:43.179] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:3:0)
[16:26:43.179] Timer tick, asking Buildbucket for the build status
[16:26:43.320] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:43.320] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:5:0) after 1m0s
[16:27:43.441] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:5:0)
[16:27:43.441] Timer tick, asking Buildbucket for the build status
[16:27:44.680] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:44.680] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:6:0) after 1m0s
[16:28:44.739] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:6:0)
[16:28:44.739] Timer tick, asking Buildbucket for the build status
[16:28:45.019] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:45.019] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:7:0) after 1m0s
[16:29:45.151] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:7:0)
[16:29:45.151] Timer tick, asking Buildbucket for the build status
[16:29:45.315] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:45.315] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:8:0) after 1m0s
[16:30:45.340] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:8:0)
[16:30:45.340] Timer tick, asking Buildbucket for the build status
[16:30:45.557] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:45.557] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:9:0) after 1m0s
[16:31:45.581] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:9:0)
[16:31:45.581] Timer tick, asking Buildbucket for the build status
[16:31:47.015] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:47.015] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:10:0) after 1m0s
[16:32:47.232] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:10:0)
[16:32:47.232] Timer tick, asking Buildbucket for the build status
[16:32:47.388] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:47.388] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:11:0) after 1m0s
[16:33:47.560] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:11:0)
[16:33:47.560] Timer tick, asking Buildbucket for the build status
[16:33:47.707] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:47.707] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:12:0) after 1m0s
[16:34:47.730] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:12:0)
[16:34:47.730] Timer tick, asking Buildbucket for the build status
[16:34:48.205] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:48.205] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:13:0) after 1m0s
[16:35:48.226] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:13:0)
[16:35:48.226] Timer tick, asking Buildbucket for the build status
[16:35:48.464] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:48.464] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:14:0) after 1m0s
[16:36:48.541] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:14:0)
[16:36:48.541] Timer tick, asking Buildbucket for the build status
[16:36:49.144] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:49.145] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:15:0) after 1m0s
[16:37:49.168] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:15:0)
[16:37:49.168] Timer tick, asking Buildbucket for the build status
[16:37:49.717] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:49.717] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:16:0) after 1m0s
[16:38:49.738] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:16:0)
[16:38:49.739] Timer tick, asking Buildbucket for the build status
[16:38:50.216] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:50.216] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:17:0) after 1m0s
[16:39:50.770] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:17:0)
[16:39:50.770] Timer tick, asking Buildbucket for the build status
[16:39:53.010] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:53.010] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:18:0) after 1m0s
[16:40:53.457] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:18:0)
[16:40:53.457] Timer tick, asking Buildbucket for the build status
[16:40:53.577] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:53.577] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:19:0) after 1m0s
[16:41:53.598] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:19:0)
[16:41:53.598] Timer tick, asking Buildbucket for the build status
[16:41:53.737] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:53.737] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:20:0) after 1m0s
[16:42:54.013] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:20:0)
[16:42:54.013] Timer tick, asking Buildbucket for the build status
[16:42:54.894] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:54.894] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:21:0) after 1m0s
[16:43:54.913] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:21:0)
[16:43:54.913] Timer tick, asking Buildbucket for the build status
[16:43:55.139] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:55.139] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:22:0) after 1m0s
[16:44:55.263] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:22:0)
[16:44:55.263] Timer tick, asking Buildbucket for the build status
[16:44:55.528] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:55.528] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:23:0) after 1m0s
[16:45:55.557] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:23:0)
[16:45:55.557] Timer tick, asking Buildbucket for the build status
[16:45:57.720] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:57.720] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:24:0) after 1m0s
[16:46:57.750] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:24:0)
[16:46:57.750] Timer tick, asking Buildbucket for the build status
[16:46:58.846] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:58.846] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:25:0) after 1m0s
[16:47:58.881] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:25:0)
[16:47:58.881] Timer tick, asking Buildbucket for the build status
[16:47:59.116] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:59.116] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:26:0) after 1m0s
[16:48:59.138] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:26:0)
[16:48:59.138] Timer tick, asking Buildbucket for the build status
[16:49:00.045] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:00.045] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:27:0) after 1m0s
[16:50:00.067] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:27:0)
[16:50:00.067] Timer tick, asking Buildbucket for the build status
[16:50:02.083] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:02.083] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:28:0) after 1m0s
[16:51:02.237] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:28:0)
[16:51:02.237] Timer tick, asking Buildbucket for the build status
[16:51:04.527] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:04.527] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:29:0) after 1m0s
[16:52:04.675] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:29:0)
[16:52:04.675] Timer tick, asking Buildbucket for the build status
[16:52:09.811] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:09.811] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:30:0) after 1m0s
[16:53:09.832] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:30:0)
[16:53:09.832] Timer tick, asking Buildbucket for the build status
[16:53:10.109] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:10.109] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:31:0) after 1m0s
[16:54:10.193] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:31:0)
[16:54:10.193] Timer tick, asking Buildbucket for the build status
[16:54:11.679] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:11.679] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:32:0) after 1m0s
[16:55:11.711] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:32:0)
[16:55:11.711] Timer tick, asking Buildbucket for the build status
[16:55:12.069] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:12.069] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:33:0) after 1m0s
[16:56:12.315] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:33:0)
[16:56:12.315] Timer tick, asking Buildbucket for the build status
[16:56:12.709] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:12.709] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:34:0) after 1m0s
[16:57:12.692] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:34:0)
[16:57:12.692] Timer tick, asking Buildbucket for the build status
[16:57:12.907] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:12.907] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:35:0) after 1m0s
[16:58:12.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:35:0)
[16:58:12.980] Timer tick, asking Buildbucket for the build status
[16:58:13.298] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:13.298] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:36:0) after 1m0s
[16:59:13.327] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:36:0)
[16:59:13.327] Timer tick, asking Buildbucket for the build status
[16:59:13.534] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:13.534] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:37:0) after 1m0s
[17:00:13.579] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:37:0)
[17:00:13.579] Timer tick, asking Buildbucket for the build status
[17:00:14.843] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:14.843] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:38:0) after 1m0s
[17:01:14.974] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:38:0)
[17:01:14.974] Timer tick, asking Buildbucket for the build status
[17:01:20.113] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:20.113] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:39:0) after 1m0s
[17:02:20.311] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:39:0)
[17:02:20.311] Timer tick, asking Buildbucket for the build status
[17:02:26.475] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:26.475] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:40:0) after 1m0s
[17:03:27.350] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:40:0)
[17:03:27.350] Timer tick, asking Buildbucket for the build status
[17:03:32.381] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:32.381] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:41:0) after 1m0s
[17:04:32.412] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:41:0)
[17:04:32.412] Timer tick, asking Buildbucket for the build status
[17:04:33.005] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:33.005] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:42:0) after 1m0s
[17:05:33.039] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:42:0)
[17:05:33.039] Timer tick, asking Buildbucket for the build status
[17:05:38.066] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:38.066] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:43:0) after 1m0s
[17:06:38.256] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:43:0)
[17:06:38.256] Timer tick, asking Buildbucket for the build status
[17:06:39.003] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:39.003] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:44:0) after 1m0s
[17:07:39.030] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:44:0)
[17:07:39.030] Timer tick, asking Buildbucket for the build status
[17:07:41.505] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:41.505] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:45:0) after 1m0s
[17:08:41.545] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:45:0)
[17:08:41.545] Timer tick, asking Buildbucket for the build status
[17:08:41.724] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:41.724] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:46:0) after 1m0s
[17:09:41.771] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:46:0)
[17:09:41.771] Timer tick, asking Buildbucket for the build status
[17:09:41.898] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:41.898] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:47:0) after 1m0s
[17:10:41.930] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:47:0)
[17:10:41.930] Timer tick, asking Buildbucket for the build status
[17:10:42.118] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:42.118] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:48:0) after 1m0s
[17:11:42.149] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:48:0)
[17:11:42.149] Timer tick, asking Buildbucket for the build status
[17:11:42.393] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:42.393] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:49:0) after 1m0s
[17:12:42.417] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:49:0)
[17:12:42.417] Timer tick, asking Buildbucket for the build status
[17:12:42.927] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:42.927] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:50:0) after 1m0s
[17:13:42.952] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:50:0)
[17:13:42.952] Timer tick, asking Buildbucket for the build status
[17:13:43.187] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:43.187] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:51:0) after 1m0s
[17:14:43.213] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:51:0)
[17:14:43.213] Timer tick, asking Buildbucket for the build status
[17:14:43.426] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:43.426] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:52:0) after 1m0s
[17:15:43.870] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:52:0)
[17:15:43.870] Timer tick, asking Buildbucket for the build status
[17:15:45.267] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:45.267] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:53:0) after 1m0s
[17:16:45.502] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:53:0)
[17:16:45.502] Timer tick, asking Buildbucket for the build status
[17:16:45.849] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:45.849] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:54:0) after 1m0s
[17:17:46.261] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:54:0)
[17:17:46.261] Timer tick, asking Buildbucket for the build status
[17:17:46.656] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:46.656] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:55:0) after 1m0s
[17:18:47.006] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:55:0)
[17:18:47.006] Timer tick, asking Buildbucket for the build status
[17:18:47.558] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:47.558] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:56:0) after 1m0s
[17:19:47.581] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:56:0)
[17:19:47.581] Timer tick, asking Buildbucket for the build status
[17:19:48.080] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:48.080] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:57:0) after 1m0s
[17:20:48.575] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:57:0)
[17:20:48.575] Timer tick, asking Buildbucket for the build status
[17:20:48.881] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:48.881] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:58:0) after 1m0s
[17:21:49.298] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:58:0)
[17:21:49.298] Timer tick, asking Buildbucket for the build status
[17:21:49.938] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:49.938] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:59:0) after 1m0s
[17:22:50.071] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:59:0)
[17:22:50.071] Timer tick, asking Buildbucket for the build status
[17:22:51.429] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:51.429] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:60:0) after 1m0s
[17:23:51.564] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:60:0)
[17:23:51.564] Timer tick, asking Buildbucket for the build status
[17:23:51.720] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:51.720] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:61:0) after 1m0s
[17:24:52.213] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:61:0)
[17:24:52.213] Timer tick, asking Buildbucket for the build status
[17:24:53.731] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:53.731] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:62:0) after 1m0s
[17:25:53.953] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:62:0)
[17:25:53.953] Timer tick, asking Buildbucket for the build status
[17:25:54.223] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:54.223] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:63:0) after 1m0s
[17:26:54.322] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:63:0)
[17:26:54.322] Timer tick, asking Buildbucket for the build status
[17:26:54.453] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:54.453] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:64:0) after 1m0s
[17:27:54.597] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:64:0)
[17:27:54.597] Timer tick, asking Buildbucket for the build status
[17:27:55.032] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:55.032] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:65:0) after 1m0s
[17:28:55.199] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:65:0)
[17:28:55.199] Timer tick, asking Buildbucket for the build status
[17:28:55.351] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:55.351] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:66:0) after 1m0s
[17:29:55.666] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:66:0)
[17:29:55.666] Timer tick, asking Buildbucket for the build status
[17:29:55.931] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:55.931] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:67:0) after 1m0s
[17:30:55.964] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:67:0)
[17:30:55.965] Timer tick, asking Buildbucket for the build status
[17:30:56.221] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:56.221] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:68:0) after 1m0s
[17:31:56.251] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:68:0)
[17:31:56.251] Timer tick, asking Buildbucket for the build status
[17:31:56.411] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:56.411] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:69:0) after 1m0s
[17:32:56.461] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:69:0)
[17:32:56.461] Timer tick, asking Buildbucket for the build status
[17:32:57.364] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:57.364] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:70:0) after 1m0s
[17:33:57.502] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:70:0)
[17:33:57.502] Timer tick, asking Buildbucket for the build status
[17:33:57.702] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:57.702] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:71:0) after 1m0s
[17:34:57.847] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:71:0)
[17:34:57.847] Timer tick, asking Buildbucket for the build status
[17:34:57.987] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:57.987] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:72:0) after 1m0s
[17:36:01.033] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:72:0)
[17:36:01.033] Timer tick, asking Buildbucket for the build status
[17:36:01.537] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:01.537] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:73:0) after 1m0s
[17:37:01.571] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:73:0)
[17:37:01.571] Timer tick, asking Buildbucket for the build status
[17:37:01.811] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:01.811] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:74:0) after 1m0s
[17:38:01.845] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:74:0)
[17:38:01.845] Timer tick, asking Buildbucket for the build status
[17:38:02.210] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:02.210] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:75:0) after 1m0s
[17:39:02.232] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:75:0)
[17:39:02.232] Timer tick, asking Buildbucket for the build status
[17:39:02.383] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:02.383] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:76:0) after 1m0s
[17:40:02.404] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:76:0)
[17:40:02.404] Timer tick, asking Buildbucket for the build status
[17:40:04.760] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:04.760] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:77:0) after 1m0s
[17:41:04.839] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:77:0)
[17:41:04.839] Timer tick, asking Buildbucket for the build status
[17:41:04.999] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:04.999] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:78:0) after 1m0s
[17:42:05.025] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:78:0)
[17:42:05.025] Timer tick, asking Buildbucket for the build status
[17:42:05.352] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:05.352] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:79:0) after 1m0s
[17:43:05.379] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:79:0)
[17:43:05.379] Timer tick, asking Buildbucket for the build status
[17:43:05.770] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:05.770] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:80:0) after 1m0s
[17:44:05.796] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:80:0)
[17:44:05.796] Timer tick, asking Buildbucket for the build status
[17:44:07.395] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:07.395] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:81:0) after 1m0s
[17:45:07.438] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:81:0)
[17:45:07.438] Timer tick, asking Buildbucket for the build status
[17:45:07.602] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:07.602] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:82:0) after 1m0s
[17:46:07.698] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:82:0)
[17:46:07.698] Timer tick, asking Buildbucket for the build status
[17:46:10.393] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:10.393] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:83:0) after 1m0s
[17:47:10.485] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:83:0)
[17:47:10.485] Timer tick, asking Buildbucket for the build status
[17:47:10.910] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:10.910] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:84:0) after 1m0s
[17:48:10.933] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:84:0)
[17:48:10.933] Timer tick, asking Buildbucket for the build status
[17:48:11.113] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:11.113] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:85:0) after 1m0s
[17:49:11.149] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:85:0)
[17:49:11.149] Timer tick, asking Buildbucket for the build status
[17:49:13.049] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:13.049] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:86:0) after 1m0s
[17:50:13.080] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:86:0)
[17:50:13.080] Timer tick, asking Buildbucket for the build status
[17:50:13.735] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:13.735] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:87:0) after 1m0s
[17:51:13.798] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:87:0)
[17:51:13.798] Timer tick, asking Buildbucket for the build status
[17:51:13.919] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:13.919] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:88:0) after 1m0s
[17:52:13.963] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:88:0)
[17:52:13.963] Timer tick, asking Buildbucket for the build status
[17:52:14.357] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:14.357] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:89:0) after 1m0s
[17:53:14.382] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:89:0)
[17:53:14.383] Timer tick, asking Buildbucket for the build status
[17:53:15.021] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:15.021] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:90:0) after 1m0s
[17:54:15.045] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:90:0)
[17:54:15.045] Timer tick, asking Buildbucket for the build status
[17:54:15.580] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:15.580] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:91:0) after 1m0s
[17:55:15.629] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:91:0)
[17:55:15.629] Timer tick, asking Buildbucket for the build status
[17:55:17.863] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:17.863] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:92:0) after 1m0s
[17:56:17.886] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:92:0)
[17:56:17.886] Timer tick, asking Buildbucket for the build status
[17:56:18.057] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:18.057] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:93:0) after 1m0s
[17:57:18.082] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:93:0)
[17:57:18.082] Timer tick, asking Buildbucket for the build status
[17:57:21.892] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:21.892] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:94:0) after 1m0s
[17:58:21.918] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:94:0)
[17:58:21.918] Timer tick, asking Buildbucket for the build status
[17:58:22.068] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:22.068] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:95:0) after 1m0s
[17:59:22.095] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:95:0)
[17:59:22.095] Timer tick, asking Buildbucket for the build status
[17:59:24.998] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:24.998] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:96:0) after 1m0s
[18:00:25.029] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:96:0)
[18:00:25.029] Timer tick, asking Buildbucket for the build status
[18:00:25.476] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:25.476] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:97:0) after 1m0s
[18:01:25.493] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:97:0)
[18:01:25.493] Timer tick, asking Buildbucket for the build status
[18:01:25.626] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:25.626] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:98:0) after 1m0s
[18:02:25.660] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:98:0)
[18:02:25.660] Timer tick, asking Buildbucket for the build status
[18:02:25.896] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:25.896] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:99:0) after 1m0s
[18:03:26.051] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:99:0)
[18:03:26.051] Timer tick, asking Buildbucket for the build status
[18:03:26.356] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:26.356] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:100:0) after 1m0s
[18:04:26.384] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:100:0)
[18:04:26.384] Timer tick, asking Buildbucket for the build status
[18:04:28.243] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:28.243] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:101:0) after 1m0s
[18:05:28.278] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:101:0)
[18:05:28.278] Timer tick, asking Buildbucket for the build status
[18:05:29.830] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:29.830] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:102:0) after 1m0s
[18:06:29.879] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:102:0)
[18:06:29.879] Timer tick, asking Buildbucket for the build status
[18:06:30.648] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:30.648] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:103:0) after 1m0s
[18:07:30.678] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:103:0)
[18:07:30.678] Timer tick, asking Buildbucket for the build status
[18:07:30.819] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:30.819] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:104:0) after 1m0s
[18:08:30.846] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:104:0)
[18:08:30.846] Timer tick, asking Buildbucket for the build status
[18:08:31.107] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:31.107] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:105:0) after 1m0s
[18:09:31.137] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:105:0)
[18:09:31.137] Timer tick, asking Buildbucket for the build status
[18:09:31.273] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:31.273] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:106:0) after 1m0s
[18:10:31.317] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:106:0)
[18:10:31.317] Timer tick, asking Buildbucket for the build status
[18:10:31.587] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:31.587] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:107:0) after 1m0s
[18:11:31.735] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:107:0)
[18:11:31.735] Timer tick, asking Buildbucket for the build status
[18:11:31.872] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:31.872] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:108:0) after 1m0s
[18:12:32.169] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:108:0)
[18:12:32.169] Timer tick, asking Buildbucket for the build status
[18:12:32.659] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:32.659] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:109:0) after 1m0s
[18:13:32.753] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:109:0)
[18:13:32.753] Timer tick, asking Buildbucket for the build status
[18:13:33.789] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:33.789] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:110:0) after 1m0s
[18:14:33.916] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:110:0)
[18:14:33.916] Timer tick, asking Buildbucket for the build status
[18:14:34.166] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:34.166] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:111:0) after 1m0s
[18:15:34.197] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:111:0)
[18:15:34.197] Timer tick, asking Buildbucket for the build status
[18:15:37.675] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:37.675] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:112:0) after 1m0s
[18:16:37.702] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:112:0)
[18:16:37.702] Timer tick, asking Buildbucket for the build status
[18:16:37.811] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:37.811] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:113:0) after 1m0s
[18:17:37.833] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:113:0)
[18:17:37.833] Timer tick, asking Buildbucket for the build status
[18:17:38.139] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:38.139] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:114:0) after 1m0s
[18:18:38.441] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:114:0)
[18:18:38.441] Timer tick, asking Buildbucket for the build status
[18:18:38.554] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:38.554] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:115:0) after 1m0s
[18:19:38.579] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:115:0)
[18:19:38.579] Timer tick, asking Buildbucket for the build status
[18:19:39.008] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:39.008] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:116:0) after 1m0s
[18:20:39.035] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:116:0)
[18:20:39.035] Timer tick, asking Buildbucket for the build status
[18:20:39.712] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:39.712] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:117:0) after 1m0s
[18:21:39.843] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:117:0)
[18:21:39.843] Timer tick, asking Buildbucket for the build status
[18:21:40.501] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:40.501] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:118:0) after 1m0s
[18:22:40.540] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:118:0)
[18:22:40.540] Timer tick, asking Buildbucket for the build status
[18:22:40.725] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:40.725] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:119:0) after 1m0s
[18:23:40.856] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:119:0)
[18:23:40.856] Timer tick, asking Buildbucket for the build status
[18:23:41.024] Build 8907942044580249520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:41.024] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9073372106850288704:120:0) after 1m0s
[18:23:41.441] Received PubSub notification, asking Buildbucket for the build status
[18:23:41.678] Build 8907942044580249520: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:23:41.678] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1563128598928824",
"created_by": "project:chromium",
"created_ts": "1563121482256542",
"id": "8907942044580249520",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3bfa2242eb00e77d73fb8e723c3fbb5b74190985\"}}",
"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-so9v\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 14130, \"got_angle_revision\": \"c3085ead7055e45b2d24189d5eee86753ea5c2f3\", \"got_dawn_revision\": \"2a1d8c2b1d32f4fe27f4bdc35287e1ebdeb8a86a\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"3bfa2242eb00e77d73fb8e723c3fbb5b74190985\", \"got_revision_cp\": \"refs/heads/master@{#677223}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"c2aa402868de1f205107166656c085bfcbacfa00\", \"got_v8_revision_cp\": \"refs/heads/7.7.228@{#1}\", \"got_webrtc_revision\": \"5a29d526be7589f5ba7fb824a749f9088b305070\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28568}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3bfa2242eb00e77d73fb8e723c3fbb5b74190985\"}, \"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-so9v\"], \"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\": [\"4370-0e704a3\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1563121487593635",
"status": "COMPLETED",
"status_changed_ts": "1563128620817891",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14130",
"builder:Windows deterministic",
"buildset:commit/git/3bfa2242eb00e77d73fb8e723c3fbb5b74190985",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3bfa2242eb00e77d73fb8e723c3fbb5b74190985",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9073372106850288704",
"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/8907942044580249520/+/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:460a1e861c49be10",
"user_agent:luci-scheduler"
],
"updated_ts": "1563128620855774",
"url": "https://ci.chromium.org/b/8907942044580249520",
"utcnow_ts": "1563128621671444"
}
}
[18:23:41.678] Invocation finished in 1h59m0.9700852s with status SUCCEEDED