[23:16:48.549] New invocation is queued and will start shortly
[23:16:49.620] Starting the invocation (attempt 1)
[23:16:49.661] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:16:49.661] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:16:49.661] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9083311844149863312",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"ea05b0b6122119d1c09d83d4adeae809b21c7180\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9083311844149863312",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/ea05b0b6122119d1c09d83d4adeae809b21c7180",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ea05b0b6122119d1c09d83d4adeae809b21c7180",
"gitiles_ref:refs/heads/master"
]
}
[23:16:52.967] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1553642210288886",
"id": "8917881781663344112",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"ea05b0b6122119d1c09d83d4adeae809b21c7180\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553642212250629",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12587",
"builder:Windows deterministic",
"buildset:commit/git/ea05b0b6122119d1c09d83d4adeae809b21c7180",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ea05b0b6122119d1c09d83d4adeae809b21c7180",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083311844149863312",
"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/8917881781663344112/+/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": "1553642212250761",
"url": "https://ci.chromium.org/b/8917881781663344112",
"utcnow_ts": "1553642212957012"
}
}
[23:16:52.967] Task URL: https://ci.chromium.org/b/8917881781663344112
[23:16:52.967] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:2:0) after 1m0s
[23:17:52.989] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:2:0)
[23:17:52.989] Timer tick, asking Buildbucket for the build status
[23:17:53.515] Build 8917881781663344112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:53.515] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:3:0) after 1m0s
[23:18:50.312] Received PubSub notification, asking Buildbucket for the build status
[23:18:50.406] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:53.538] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:3:0)
[23:18:53.538] Timer tick, asking Buildbucket for the build status
[23:18:54.392] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:54.392] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:5:0) after 1m0s
[23:19:54.412] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:5:0)
[23:19:54.412] Timer tick, asking Buildbucket for the build status
[23:19:54.537] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:54.537] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:6:0) after 1m0s
[23:20:54.558] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:6:0)
[23:20:54.558] Timer tick, asking Buildbucket for the build status
[23:20:54.660] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:54.660] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:7:0) after 1m0s
[23:21:54.684] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:7:0)
[23:21:54.684] Timer tick, asking Buildbucket for the build status
[23:21:54.817] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:54.817] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:8:0) after 1m0s
[23:22:54.840] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:8:0)
[23:22:54.840] Timer tick, asking Buildbucket for the build status
[23:22:55.218] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:55.218] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:9:0) after 1m0s
[23:23:55.204] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:9:0)
[23:23:55.204] Timer tick, asking Buildbucket for the build status
[23:23:55.826] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:55.826] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:10:0) after 1m0s
[23:24:55.827] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:10:0)
[23:24:55.827] Timer tick, asking Buildbucket for the build status
[23:24:56.087] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:56.087] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:11:0) after 1m0s
[23:25:56.108] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:11:0)
[23:25:56.108] Timer tick, asking Buildbucket for the build status
[23:25:56.205] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:56.205] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:12:0) after 1m0s
[23:26:56.229] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:12:0)
[23:26:56.229] Timer tick, asking Buildbucket for the build status
[23:26:56.607] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:56.607] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:13:0) after 1m0s
[23:27:56.604] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:13:0)
[23:27:56.604] Timer tick, asking Buildbucket for the build status
[23:27:56.769] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:56.769] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:14:0) after 1m0s
[23:28:56.789] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:14:0)
[23:28:56.789] Timer tick, asking Buildbucket for the build status
[23:28:57.708] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:57.708] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:15:0) after 1m0s
[23:29:57.717] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:15:0)
[23:29:57.717] Timer tick, asking Buildbucket for the build status
[23:29:57.870] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:57.870] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:16:0) after 1m0s
[23:30:57.846] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:16:0)
[23:30:57.846] Timer tick, asking Buildbucket for the build status
[23:30:57.967] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:57.967] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:17:0) after 1m0s
[23:31:57.953] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:17:0)
[23:31:57.953] Timer tick, asking Buildbucket for the build status
[23:31:58.087] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:58.087] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:18:0) after 1m0s
[23:32:58.096] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:18:0)
[23:32:58.096] Timer tick, asking Buildbucket for the build status
[23:32:58.237] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:58.237] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:19:0) after 1m0s
[23:33:58.233] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:19:0)
[23:33:58.233] Timer tick, asking Buildbucket for the build status
[23:33:58.416] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:58.416] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:20:0) after 1m0s
[23:34:58.413] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:20:0)
[23:34:58.413] Timer tick, asking Buildbucket for the build status
[23:34:58.559] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:58.559] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:21:0) after 1m0s
[23:35:58.578] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:21:0)
[23:35:58.578] Timer tick, asking Buildbucket for the build status
[23:35:59.314] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:59.314] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:22:0) after 1m0s
[23:36:59.379] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:22:0)
[23:36:59.379] Timer tick, asking Buildbucket for the build status
[23:36:59.527] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:59.528] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:23:0) after 1m0s
[23:37:59.524] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:23:0)
[23:37:59.524] Timer tick, asking Buildbucket for the build status
[23:37:59.830] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:59.830] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:24:0) after 1m0s
[23:38:59.850] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:24:0)
[23:38:59.850] Timer tick, asking Buildbucket for the build status
[23:38:59.958] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:59.958] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:25:0) after 1m0s
[23:39:59.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:25:0)
[23:39:59.980] Timer tick, asking Buildbucket for the build status
[23:40:00.300] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:00.300] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:26:0) after 1m0s
[23:41:00.324] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:26:0)
[23:41:00.324] Timer tick, asking Buildbucket for the build status
[23:41:00.523] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:00.523] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:27:0) after 1m0s
[23:42:00.537] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:27:0)
[23:42:00.537] Timer tick, asking Buildbucket for the build status
[23:42:00.627] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:00.627] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:28:0) after 1m0s
[23:43:00.646] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:28:0)
[23:43:00.646] Timer tick, asking Buildbucket for the build status
[23:43:01.273] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:01.273] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:29:0) after 1m0s
[23:44:01.282] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:29:0)
[23:44:01.282] Timer tick, asking Buildbucket for the build status
[23:44:01.403] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:01.403] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:30:0) after 1m0s
[23:45:01.418] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:30:0)
[23:45:01.418] Timer tick, asking Buildbucket for the build status
[23:45:01.585] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:01.585] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:31:0) after 1m0s
[23:46:01.609] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:31:0)
[23:46:01.609] Timer tick, asking Buildbucket for the build status
[23:46:02.096] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:02.096] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:32:0) after 1m0s
[23:47:02.128] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:32:0)
[23:47:02.128] Timer tick, asking Buildbucket for the build status
[23:47:02.311] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:02.311] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:33:0) after 1m0s
[23:48:02.332] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:33:0)
[23:48:02.332] Timer tick, asking Buildbucket for the build status
[23:48:02.423] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:02.423] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:34:0) after 1m0s
[23:49:02.484] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:34:0)
[23:49:02.484] Timer tick, asking Buildbucket for the build status
[23:49:02.796] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:02.796] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:35:0) after 1m0s
[23:50:02.785] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:35:0)
[23:50:02.785] Timer tick, asking Buildbucket for the build status
[23:50:03.295] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:03.295] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:36:0) after 1m0s
[23:51:03.315] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:36:0)
[23:51:03.315] Timer tick, asking Buildbucket for the build status
[23:51:04.098] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:04.098] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:37:0) after 1m0s
[23:52:04.126] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:37:0)
[23:52:04.126] Timer tick, asking Buildbucket for the build status
[23:52:04.263] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:04.263] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:38:0) after 1m0s
[23:53:04.288] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:38:0)
[23:53:04.288] Timer tick, asking Buildbucket for the build status
[23:53:04.419] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:04.419] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:39:0) after 1m0s
[23:54:04.414] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:39:0)
[23:54:04.414] Timer tick, asking Buildbucket for the build status
[23:54:04.946] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:04.946] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:40:0) after 1m0s
[23:55:04.923] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:40:0)
[23:55:04.923] Timer tick, asking Buildbucket for the build status
[23:55:05.036] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:05.036] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:41:0) after 1m0s
[23:56:05.100] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:41:0)
[23:56:05.100] Timer tick, asking Buildbucket for the build status
[23:56:05.177] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:05.177] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:42:0) after 1m0s
[23:57:05.188] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:42:0)
[23:57:05.188] Timer tick, asking Buildbucket for the build status
[23:57:05.275] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:05.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:43:0) after 1m0s
[23:58:05.263] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:43:0)
[23:58:05.263] Timer tick, asking Buildbucket for the build status
[23:58:05.707] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:05.707] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:44:0) after 1m0s
[23:59:05.729] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:44:0)
[23:59:05.729] Timer tick, asking Buildbucket for the build status
[23:59:05.815] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:05.815] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:45:0) after 1m0s
[00:00:05.835] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:45:0)
[00:00:05.835] Timer tick, asking Buildbucket for the build status
[00:00:06.192] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:06.192] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:46:0) after 1m0s
[00:01:06.215] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:46:0)
[00:01:06.215] Timer tick, asking Buildbucket for the build status
[00:01:08.033] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:08.033] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:47:0) after 1m0s
[00:02:08.056] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:47:0)
[00:02:08.056] Timer tick, asking Buildbucket for the build status
[00:02:08.308] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:08.308] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:48:0) after 1m0s
[00:03:08.322] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:48:0)
[00:03:08.322] Timer tick, asking Buildbucket for the build status
[00:03:08.573] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:08.573] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:49:0) after 1m0s
[00:04:08.551] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:49:0)
[00:04:08.551] Timer tick, asking Buildbucket for the build status
[00:04:08.779] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:08.779] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:50:0) after 1m0s
[00:05:08.802] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:50:0)
[00:05:08.802] Timer tick, asking Buildbucket for the build status
[00:05:09.031] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:09.031] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:51:0) after 1m0s
[00:06:09.032] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:51:0)
[00:06:09.032] Timer tick, asking Buildbucket for the build status
[00:06:09.311] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:09.311] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:52:0) after 1m0s
[00:07:09.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:52:0)
[00:07:09.344] Timer tick, asking Buildbucket for the build status
[00:07:09.474] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:09.474] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:53:0) after 1m0s
[00:08:09.453] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:53:0)
[00:08:09.453] Timer tick, asking Buildbucket for the build status
[00:08:09.569] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:09.569] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:54:0) after 1m0s
[00:09:09.593] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:54:0)
[00:09:09.593] Timer tick, asking Buildbucket for the build status
[00:09:09.727] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:09.727] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:55:0) after 1m0s
[00:10:09.754] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:55:0)
[00:10:09.754] Timer tick, asking Buildbucket for the build status
[00:10:09.875] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:09.875] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:56:0) after 1m0s
[00:11:09.885] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:56:0)
[00:11:09.885] Timer tick, asking Buildbucket for the build status
[00:11:10.377] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:10.377] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:57:0) after 1m0s
[00:12:10.350] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:57:0)
[00:12:10.350] Timer tick, asking Buildbucket for the build status
[00:12:10.894] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:10.894] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:58:0) after 1m0s
[00:13:10.916] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:58:0)
[00:13:10.916] Timer tick, asking Buildbucket for the build status
[00:13:11.040] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:11.040] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:59:0) after 1m0s
[00:14:11.062] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:59:0)
[00:14:11.062] Timer tick, asking Buildbucket for the build status
[00:14:12.392] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:12.392] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:60:0) after 1m0s
[00:15:12.416] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:60:0)
[00:15:12.416] Timer tick, asking Buildbucket for the build status
[00:15:12.608] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:12.608] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:61:0) after 1m0s
[00:16:12.611] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:61:0)
[00:16:12.611] Timer tick, asking Buildbucket for the build status
[00:16:12.728] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:12.728] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:62:0) after 1m0s
[00:17:12.771] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:62:0)
[00:17:12.771] Timer tick, asking Buildbucket for the build status
[00:17:12.906] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:12.906] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:63:0) after 1m0s
[00:18:12.908] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:63:0)
[00:18:12.908] Timer tick, asking Buildbucket for the build status
[00:18:13.198] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:13.198] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:64:0) after 1m0s
[00:19:13.226] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:64:0)
[00:19:13.226] Timer tick, asking Buildbucket for the build status
[00:19:13.781] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:13.782] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:65:0) after 1m0s
[00:20:13.762] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:65:0)
[00:20:13.762] Timer tick, asking Buildbucket for the build status
[00:20:13.952] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:13.952] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:66:0) after 1m0s
[00:21:13.929] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:66:0)
[00:21:13.929] Timer tick, asking Buildbucket for the build status
[00:21:14.060] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:14.060] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:67:0) after 1m0s
[00:22:14.085] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:67:0)
[00:22:14.085] Timer tick, asking Buildbucket for the build status
[00:22:14.289] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:14.289] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:68:0) after 1m0s
[00:23:14.319] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:68:0)
[00:23:14.319] Timer tick, asking Buildbucket for the build status
[00:23:14.798] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:14.798] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:69:0) after 1m0s
[00:24:14.824] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:69:0)
[00:24:14.824] Timer tick, asking Buildbucket for the build status
[00:24:14.939] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:14.939] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:70:0) after 1m0s
[00:25:14.949] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:70:0)
[00:25:14.949] Timer tick, asking Buildbucket for the build status
[00:25:15.208] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:15.208] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:71:0) after 1m0s
[00:26:15.232] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:71:0)
[00:26:15.232] Timer tick, asking Buildbucket for the build status
[00:26:15.391] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:15.391] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:72:0) after 1m0s
[00:27:15.412] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:72:0)
[00:27:15.412] Timer tick, asking Buildbucket for the build status
[00:27:15.519] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:15.519] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:73:0) after 1m0s
[00:28:15.550] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:73:0)
[00:28:15.550] Timer tick, asking Buildbucket for the build status
[00:28:15.646] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:15.646] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:74:0) after 1m0s
[00:29:15.695] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:74:0)
[00:29:15.695] Timer tick, asking Buildbucket for the build status
[00:29:15.818] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:15.819] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:75:0) after 1m0s
[00:30:15.853] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:75:0)
[00:30:15.853] Timer tick, asking Buildbucket for the build status
[00:30:16.579] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:16.579] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:76:0) after 1m0s
[00:31:16.605] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:76:0)
[00:31:16.605] Timer tick, asking Buildbucket for the build status
[00:31:16.759] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:16.759] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:77:0) after 1m0s
[00:32:16.749] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:77:0)
[00:32:16.749] Timer tick, asking Buildbucket for the build status
[00:32:17.319] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:17.319] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:78:0) after 1m0s
[00:33:17.323] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:78:0)
[00:33:17.323] Timer tick, asking Buildbucket for the build status
[00:33:17.765] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:17.765] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:79:0) after 1m0s
[00:34:17.789] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:79:0)
[00:34:17.789] Timer tick, asking Buildbucket for the build status
[00:34:17.948] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:17.948] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:80:0) after 1m0s
[00:35:17.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:80:0)
[00:35:17.980] Timer tick, asking Buildbucket for the build status
[00:35:18.465] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:18.465] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:81:0) after 1m0s
[00:36:18.498] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:81:0)
[00:36:18.498] Timer tick, asking Buildbucket for the build status
[00:36:18.598] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:18.598] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:82:0) after 1m0s
[00:37:18.601] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:82:0)
[00:37:18.601] Timer tick, asking Buildbucket for the build status
[00:37:18.691] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:18.691] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:83:0) after 1m0s
[00:38:18.717] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:83:0)
[00:38:18.717] Timer tick, asking Buildbucket for the build status
[00:38:18.836] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:18.836] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:84:0) after 1m0s
[00:39:18.861] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:84:0)
[00:39:18.862] Timer tick, asking Buildbucket for the build status
[00:39:19.303] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:19.303] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:85:0) after 1m0s
[00:40:19.327] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:85:0)
[00:40:19.327] Timer tick, asking Buildbucket for the build status
[00:40:19.429] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:19.429] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:86:0) after 1m0s
[00:41:19.422] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:86:0)
[00:41:19.422] Timer tick, asking Buildbucket for the build status
[00:41:19.608] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:19.608] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:87:0) after 1m0s
[00:42:19.631] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:87:0)
[00:42:19.631] Timer tick, asking Buildbucket for the build status
[00:42:19.914] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:19.914] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:88:0) after 1m0s
[00:43:19.921] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:88:0)
[00:43:19.921] Timer tick, asking Buildbucket for the build status
[00:43:20.527] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:20.527] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:89:0) after 1m0s
[00:44:20.543] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:89:0)
[00:44:20.543] Timer tick, asking Buildbucket for the build status
[00:44:20.659] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:20.659] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:90:0) after 1m0s
[00:45:20.647] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:90:0)
[00:45:20.647] Timer tick, asking Buildbucket for the build status
[00:45:20.744] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:20.744] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:91:0) after 1m0s
[00:46:20.796] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:91:0)
[00:46:20.796] Timer tick, asking Buildbucket for the build status
[00:46:20.910] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:20.910] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:92:0) after 1m0s
[00:47:20.937] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:92:0)
[00:47:20.937] Timer tick, asking Buildbucket for the build status
[00:47:21.448] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:21.448] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:93:0) after 1m0s
[00:48:21.430] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:93:0)
[00:48:21.430] Timer tick, asking Buildbucket for the build status
[00:48:21.548] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:21.548] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:94:0) after 1m0s
[00:49:21.540] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:94:0)
[00:49:21.540] Timer tick, asking Buildbucket for the build status
[00:49:21.689] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:21.689] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:95:0) after 1m0s
[00:50:21.684] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:95:0)
[00:50:21.684] Timer tick, asking Buildbucket for the build status
[00:50:22.039] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:22.039] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:96:0) after 1m0s
[00:51:22.064] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:96:0)
[00:51:22.064] Timer tick, asking Buildbucket for the build status
[00:51:22.182] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:22.182] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:97:0) after 1m0s
[00:52:22.194] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:97:0)
[00:52:22.194] Timer tick, asking Buildbucket for the build status
[00:52:22.876] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:22.876] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:98:0) after 1m0s
[00:53:22.903] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:98:0)
[00:53:22.903] Timer tick, asking Buildbucket for the build status
[00:53:22.994] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:22.994] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:99:0) after 1m0s
[00:54:22.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:99:0)
[00:54:22.980] Timer tick, asking Buildbucket for the build status
[00:54:23.090] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:23.090] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:100:0) after 1m0s
[00:55:23.120] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:100:0)
[00:55:23.120] Timer tick, asking Buildbucket for the build status
[00:55:23.428] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:23.428] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:101:0) after 1m0s
[00:56:23.455] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:101:0)
[00:56:23.455] Timer tick, asking Buildbucket for the build status
[00:56:23.656] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:23.656] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:102:0) after 1m0s
[00:57:23.685] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:102:0)
[00:57:23.685] Timer tick, asking Buildbucket for the build status
[00:57:23.929] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:23.929] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:103:0) after 1m0s
[00:58:23.951] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:103:0)
[00:58:23.951] Timer tick, asking Buildbucket for the build status
[00:58:24.129] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:24.129] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:104:0) after 1m0s
[00:59:24.127] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:104:0)
[00:59:24.127] Timer tick, asking Buildbucket for the build status
[00:59:24.230] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:24.230] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:105:0) after 1m0s
[01:00:24.264] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:105:0)
[01:00:24.264] Timer tick, asking Buildbucket for the build status
[01:00:25.067] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:25.067] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:106:0) after 1m0s
[01:01:25.072] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:106:0)
[01:01:25.072] Timer tick, asking Buildbucket for the build status
[01:01:25.507] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:25.507] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:107:0) after 1m0s
[01:02:25.533] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:107:0)
[01:02:25.533] Timer tick, asking Buildbucket for the build status
[01:02:26.297] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:26.297] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:108:0) after 1m0s
[01:03:26.330] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:108:0)
[01:03:26.330] Timer tick, asking Buildbucket for the build status
[01:03:26.616] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:26.616] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:109:0) after 1m0s
[01:04:26.640] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:109:0)
[01:04:26.640] Timer tick, asking Buildbucket for the build status
[01:04:26.746] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:26.746] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:110:0) after 1m0s
[01:05:26.775] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:110:0)
[01:05:26.775] Timer tick, asking Buildbucket for the build status
[01:05:27.290] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:27.290] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:111:0) after 1m0s
[01:06:27.343] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:111:0)
[01:06:27.343] Timer tick, asking Buildbucket for the build status
[01:06:27.491] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:27.491] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:112:0) after 1m0s
[01:07:27.535] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:112:0)
[01:07:27.535] Timer tick, asking Buildbucket for the build status
[01:07:27.675] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:27.675] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:113:0) after 1m0s
[01:08:27.703] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:113:0)
[01:08:27.703] Timer tick, asking Buildbucket for the build status
[01:08:28.034] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:28.034] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:114:0) after 1m0s
[01:09:28.060] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:114:0)
[01:09:28.060] Timer tick, asking Buildbucket for the build status
[01:09:28.213] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:28.213] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:115:0) after 1m0s
[01:10:28.308] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:115:0)
[01:10:28.308] Timer tick, asking Buildbucket for the build status
[01:10:28.687] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:28.687] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:116:0) after 1m0s
[01:11:28.712] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:116:0)
[01:11:28.712] Timer tick, asking Buildbucket for the build status
[01:11:28.800] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:28.800] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:117:0) after 1m0s
[01:12:28.827] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:117:0)
[01:12:28.827] Timer tick, asking Buildbucket for the build status
[01:12:29.275] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:29.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:118:0) after 1m0s
[01:13:29.301] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:118:0)
[01:13:29.301] Timer tick, asking Buildbucket for the build status
[01:13:29.575] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:29.575] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:119:0) after 1m0s
[01:14:29.610] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:119:0)
[01:14:29.610] Timer tick, asking Buildbucket for the build status
[01:14:30.061] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:30.061] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:120:0) after 1m0s
[01:15:30.084] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:120:0)
[01:15:30.084] Timer tick, asking Buildbucket for the build status
[01:15:30.440] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:30.440] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:121:0) after 1m0s
[01:16:30.534] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:121:0)
[01:16:30.534] Timer tick, asking Buildbucket for the build status
[01:16:30.737] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:30.738] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:122:0) after 1m0s
[01:17:30.767] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:122:0)
[01:17:30.767] Timer tick, asking Buildbucket for the build status
[01:17:30.885] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:30.886] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:123:0) after 1m0s
[01:18:30.882] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:123:0)
[01:18:30.882] Timer tick, asking Buildbucket for the build status
[01:18:30.972] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:30.972] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:124:0) after 1m0s
[01:19:30.996] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:124:0)
[01:19:30.996] Timer tick, asking Buildbucket for the build status
[01:19:31.114] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:31.114] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:125:0) after 1m0s
[01:20:31.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:125:0)
[01:20:31.154] Timer tick, asking Buildbucket for the build status
[01:20:31.300] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:31.300] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:126:0) after 1m0s
[01:21:31.332] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:126:0)
[01:21:31.332] Timer tick, asking Buildbucket for the build status
[01:21:31.981] Build 8917881781663344112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:31.981] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083311844149863312:127:0) after 1m0s
[01:22:18.231] Received PubSub notification, asking Buildbucket for the build status
[01:22:18.361] Build 8917881781663344112: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:22:18.361] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1553649737748938",
"created_by": "project:chromium",
"created_ts": "1553642210288886",
"id": "8917881781663344112",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"ea05b0b6122119d1c09d83d4adeae809b21c7180\"}}",
"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-v3qq\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 12587, \"got_angle_revision\": \"4864e0cd196089625a7eda4f9a96f02f597af14f\", \"got_dawn_revision\": \"91e949292f5944020365ab8b5a44a9c940cb3b69\", \"got_nacl_revision\": \"de8da4841bc3d1ed020d798d5a7fd6fb3d817fa1\", \"got_revision\": \"ea05b0b6122119d1c09d83d4adeae809b21c7180\", \"got_revision_cp\": \"refs/heads/master@{#644580}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"8244b4540de0f8ac7e6f92b1ad35d8d9d96d5bc2\", \"got_v8_revision_cp\": \"refs/heads/7.5.119@{#1}\", \"got_webrtc_revision\": \"2547d2cf2b12514151e9a21463ccce1574556f5a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27299}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"ea05b0b6122119d1c09d83d4adeae809b21c7180\"}, \"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-v3qq\"], \"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\": [\"4221-9c50278\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1553642329758456",
"status": "COMPLETED",
"status_changed_ts": "1553649738006103",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12587",
"builder:Windows deterministic",
"buildset:commit/git/ea05b0b6122119d1c09d83d4adeae809b21c7180",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ea05b0b6122119d1c09d83d4adeae809b21c7180",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083311844149863312",
"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/8917881781663344112/+/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:43d51c55b3d29310",
"user_agent:luci-scheduler"
],
"updated_ts": "1553649738013057",
"url": "https://ci.chromium.org/b/8917881781663344112",
"utcnow_ts": "1553649738351795"
}
}
[01:22:18.361] Invocation finished in 2h5m29.828266707s with status SUCCEEDED