[06:09:56.401] New invocation is queued and will start shortly
[06:09:57.674] Starting the invocation (attempt 1)
[06:09:57.759] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:09:57.759] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:09:57.760] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9087181521758705344",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"85548824b14a09d1bfb3717638b65364eaaaaf82\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9087181521758705344",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/85548824b14a09d1bfb3717638b65364eaaaaf82",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/85548824b14a09d1bfb3717638b65364eaaaaf82",
"gitiles_ref:refs/heads/master"
]
}
[06:09:59.011] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1549951797861589",
"id": "8921751459564106352",
"lease_expiration_ts": "1550070598789157",
"lease_key": "662595206",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"85548824b14a09d1bfb3717638b65364eaaaaf82\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1549951798914216",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11939",
"builder:Windows deterministic",
"buildset:commit/git/85548824b14a09d1bfb3717638b65364eaaaaf82",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/85548824b14a09d1bfb3717638b65364eaaaaf82",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9087181521758705344",
"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/8921751459564106352/+/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:42f9251535346010",
"user_agent:luci-scheduler"
],
"updated_ts": "1549951798914329",
"url": "https://ci.chromium.org/b/8921751459564106352",
"utcnow_ts": "1549951799001831"
}
}
[06:09:59.011] Task URL: https://ci.chromium.org/b/8921751459564106352
[06:09:59.011] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:2:0) after 1m0s
[06:10:01.001] Received PubSub notification, asking Buildbucket for the build status
[06:10:01.081] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:59.048] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:2:0)
[06:10:59.048] Timer tick, asking Buildbucket for the build status
[06:10:59.125] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:59.125] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:4:0) after 1m0s
[06:11:59.159] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:4:0)
[06:11:59.159] Timer tick, asking Buildbucket for the build status
[06:11:59.277] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:59.277] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:5:0) after 1m0s
[06:12:59.295] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:5:0)
[06:12:59.295] Timer tick, asking Buildbucket for the build status
[06:12:59.352] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:59.352] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:6:0) after 1m0s
[06:13:59.372] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:6:0)
[06:13:59.372] Timer tick, asking Buildbucket for the build status
[06:13:59.416] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:59.416] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:7:0) after 1m0s
[06:14:59.455] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:7:0)
[06:14:59.455] Timer tick, asking Buildbucket for the build status
[06:14:59.522] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:59.522] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:8:0) after 1m0s
[06:15:59.542] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:8:0)
[06:15:59.542] Timer tick, asking Buildbucket for the build status
[06:15:59.590] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:59.590] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:9:0) after 1m0s
[06:16:59.608] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:9:0)
[06:16:59.608] Timer tick, asking Buildbucket for the build status
[06:16:59.653] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:59.653] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:10:0) after 1m0s
[06:17:59.668] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:10:0)
[06:17:59.668] Timer tick, asking Buildbucket for the build status
[06:17:59.873] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:59.873] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:11:0) after 1m0s
[06:18:59.900] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:11:0)
[06:18:59.900] Timer tick, asking Buildbucket for the build status
[06:19:00.110] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:00.110] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:12:0) after 1m0s
[06:20:00.129] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:12:0)
[06:20:00.129] Timer tick, asking Buildbucket for the build status
[06:20:00.206] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:00.207] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:13:0) after 1m0s
[06:21:00.230] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:13:0)
[06:21:00.230] Timer tick, asking Buildbucket for the build status
[06:21:00.309] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:00.309] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:14:0) after 1m0s
[06:22:00.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:14:0)
[06:22:00.344] Timer tick, asking Buildbucket for the build status
[06:22:00.803] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:00.803] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:15:0) after 1m0s
[06:23:00.829] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:15:0)
[06:23:00.829] Timer tick, asking Buildbucket for the build status
[06:23:01.331] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:01.331] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:16:0) after 1m0s
[06:24:01.406] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:16:0)
[06:24:01.406] Timer tick, asking Buildbucket for the build status
[06:24:01.563] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:01.563] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:17:0) after 1m0s
[06:25:01.581] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:17:0)
[06:25:01.581] Timer tick, asking Buildbucket for the build status
[06:25:01.895] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:01.895] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:18:0) after 1m0s
[06:26:01.961] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:18:0)
[06:26:01.961] Timer tick, asking Buildbucket for the build status
[06:26:02.927] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:02.927] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:19:0) after 1m0s
[06:27:02.970] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:19:0)
[06:27:02.970] Timer tick, asking Buildbucket for the build status
[06:27:03.076] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:03.076] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:20:0) after 1m0s
[06:28:03.096] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:20:0)
[06:28:03.096] Timer tick, asking Buildbucket for the build status
[06:28:03.223] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:03.223] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:21:0) after 1m0s
[06:29:03.240] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:21:0)
[06:29:03.240] Timer tick, asking Buildbucket for the build status
[06:29:03.339] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:03.339] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:22:0) after 1m0s
[06:30:03.357] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:22:0)
[06:30:03.357] Timer tick, asking Buildbucket for the build status
[06:30:03.447] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:03.447] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:23:0) after 1m0s
[06:31:03.466] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:23:0)
[06:31:03.466] Timer tick, asking Buildbucket for the build status
[06:31:04.256] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:04.256] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:24:0) after 1m0s
[06:32:04.274] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:24:0)
[06:32:04.274] Timer tick, asking Buildbucket for the build status
[06:32:04.339] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:04.339] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:25:0) after 1m0s
[06:33:04.362] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:25:0)
[06:33:04.362] Timer tick, asking Buildbucket for the build status
[06:33:04.429] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:04.429] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:26:0) after 1m0s
[06:34:04.501] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:26:0)
[06:34:04.501] Timer tick, asking Buildbucket for the build status
[06:34:04.722] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:04.722] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:27:0) after 1m0s
[06:35:04.742] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:27:0)
[06:35:04.742] Timer tick, asking Buildbucket for the build status
[06:35:04.788] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:04.788] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:28:0) after 1m0s
[06:36:04.807] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:28:0)
[06:36:04.807] Timer tick, asking Buildbucket for the build status
[06:36:04.885] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:04.885] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:29:0) after 1m0s
[06:37:04.912] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:29:0)
[06:37:04.912] Timer tick, asking Buildbucket for the build status
[06:37:05.470] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:05.470] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:30:0) after 1m0s
[06:38:05.489] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:30:0)
[06:38:05.489] Timer tick, asking Buildbucket for the build status
[06:38:05.533] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:05.533] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:31:0) after 1m0s
[06:39:05.543] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:31:0)
[06:39:05.543] Timer tick, asking Buildbucket for the build status
[06:39:05.596] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:05.596] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:32:0) after 1m0s
[06:40:05.615] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:32:0)
[06:40:05.615] Timer tick, asking Buildbucket for the build status
[06:40:05.685] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:05.685] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:33:0) after 1m0s
[06:41:05.710] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:33:0)
[06:41:05.710] Timer tick, asking Buildbucket for the build status
[06:41:05.988] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:05.988] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:34:0) after 1m0s
[06:42:06.035] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:34:0)
[06:42:06.035] Timer tick, asking Buildbucket for the build status
[06:42:06.156] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:06.156] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:35:0) after 1m0s
[06:43:06.195] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:35:0)
[06:43:06.195] Timer tick, asking Buildbucket for the build status
[06:43:06.250] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:06.250] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:36:0) after 1m0s
[06:44:06.321] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:36:0)
[06:44:06.321] Timer tick, asking Buildbucket for the build status
[06:44:06.411] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:06.411] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:37:0) after 1m0s
[06:45:06.431] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:37:0)
[06:45:06.431] Timer tick, asking Buildbucket for the build status
[06:45:06.509] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:06.509] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:38:0) after 1m0s
[06:46:06.528] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:38:0)
[06:46:06.528] Timer tick, asking Buildbucket for the build status
[06:46:06.573] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:06.573] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:39:0) after 1m0s
[06:47:06.590] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:39:0)
[06:47:06.590] Timer tick, asking Buildbucket for the build status
[06:47:06.709] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:06.709] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:40:0) after 1m0s
[06:48:06.727] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:40:0)
[06:48:06.727] Timer tick, asking Buildbucket for the build status
[06:48:07.205] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:07.205] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:41:0) after 1m0s
[06:49:07.240] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:41:0)
[06:49:07.240] Timer tick, asking Buildbucket for the build status
[06:49:07.448] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:07.448] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:42:0) after 1m0s
[06:50:07.494] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:42:0)
[06:50:07.494] Timer tick, asking Buildbucket for the build status
[06:50:07.600] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:07.600] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:43:0) after 1m0s
[06:51:07.641] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:43:0)
[06:51:07.641] Timer tick, asking Buildbucket for the build status
[06:51:07.760] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:07.760] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:44:0) after 1m0s
[06:52:07.779] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:44:0)
[06:52:07.779] Timer tick, asking Buildbucket for the build status
[06:52:07.845] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:07.846] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:45:0) after 1m0s
[06:53:07.864] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:45:0)
[06:53:07.864] Timer tick, asking Buildbucket for the build status
[06:53:08.193] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:08.193] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:46:0) after 1m0s
[06:54:08.212] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:46:0)
[06:54:08.212] Timer tick, asking Buildbucket for the build status
[06:54:08.275] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:08.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:47:0) after 1m0s
[06:55:08.302] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:47:0)
[06:55:08.302] Timer tick, asking Buildbucket for the build status
[06:55:08.366] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:08.366] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:48:0) after 1m0s
[06:56:08.384] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:48:0)
[06:56:08.384] Timer tick, asking Buildbucket for the build status
[06:56:08.465] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:08.465] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:49:0) after 1m0s
[06:57:08.484] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:49:0)
[06:57:08.484] Timer tick, asking Buildbucket for the build status
[06:57:08.546] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:08.546] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:50:0) after 1m0s
[06:58:08.564] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:50:0)
[06:58:08.564] Timer tick, asking Buildbucket for the build status
[06:58:08.610] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:08.610] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:51:0) after 1m0s
[06:59:08.644] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:51:0)
[06:59:08.644] Timer tick, asking Buildbucket for the build status
[06:59:08.902] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:08.902] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:52:0) after 1m0s
[07:00:08.930] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:52:0)
[07:00:08.930] Timer tick, asking Buildbucket for the build status
[07:00:08.985] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:08.985] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:53:0) after 1m0s
[07:01:09.007] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:53:0)
[07:01:09.008] Timer tick, asking Buildbucket for the build status
[07:01:09.080] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:09.080] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:54:0) after 1m0s
[07:02:09.113] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:54:0)
[07:02:09.113] Timer tick, asking Buildbucket for the build status
[07:02:09.189] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:09.189] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:55:0) after 1m0s
[07:03:09.233] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:55:0)
[07:03:09.233] Timer tick, asking Buildbucket for the build status
[07:03:09.279] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:09.279] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:56:0) after 1m0s
[07:04:09.298] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:56:0)
[07:04:09.298] Timer tick, asking Buildbucket for the build status
[07:04:09.341] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:09.341] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:57:0) after 1m0s
[07:05:09.364] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:57:0)
[07:05:09.364] Timer tick, asking Buildbucket for the build status
[07:05:09.439] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:09.439] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:58:0) after 1m0s
[07:06:09.460] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:58:0)
[07:06:09.460] Timer tick, asking Buildbucket for the build status
[07:06:09.508] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:09.508] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:59:0) after 1m0s
[07:07:09.539] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:59:0)
[07:07:09.539] Timer tick, asking Buildbucket for the build status
[07:07:09.684] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:09.684] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:60:0) after 1m0s
[07:08:09.709] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:60:0)
[07:08:09.709] Timer tick, asking Buildbucket for the build status
[07:08:09.857] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:09.857] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:61:0) after 1m0s
[07:09:09.878] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:61:0)
[07:09:09.878] Timer tick, asking Buildbucket for the build status
[07:09:10.081] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:10.081] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:62:0) after 1m0s
[07:10:10.125] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:62:0)
[07:10:10.125] Timer tick, asking Buildbucket for the build status
[07:10:10.369] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:10.369] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:63:0) after 1m0s
[07:11:10.391] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:63:0)
[07:11:10.391] Timer tick, asking Buildbucket for the build status
[07:11:10.484] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:10.484] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:64:0) after 1m0s
[07:12:10.513] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:64:0)
[07:12:10.513] Timer tick, asking Buildbucket for the build status
[07:12:10.879] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:10.879] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:65:0) after 1m0s
[07:13:10.901] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:65:0)
[07:13:10.901] Timer tick, asking Buildbucket for the build status
[07:13:10.952] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:10.952] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:66:0) after 1m0s
[07:14:10.981] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:66:0)
[07:14:10.981] Timer tick, asking Buildbucket for the build status
[07:14:11.036] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:11.036] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:67:0) after 1m0s
[07:15:11.123] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:67:0)
[07:15:11.123] Timer tick, asking Buildbucket for the build status
[07:15:11.452] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:11.452] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:68:0) after 1m0s
[07:16:11.470] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:68:0)
[07:16:11.470] Timer tick, asking Buildbucket for the build status
[07:16:11.514] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:11.515] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:69:0) after 1m0s
[07:17:11.533] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:69:0)
[07:17:11.533] Timer tick, asking Buildbucket for the build status
[07:17:12.464] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:12.464] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:70:0) after 1m0s
[07:18:12.483] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:70:0)
[07:18:12.483] Timer tick, asking Buildbucket for the build status
[07:18:12.527] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:12.527] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:71:0) after 1m0s
[07:19:12.546] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:71:0)
[07:19:12.546] Timer tick, asking Buildbucket for the build status
[07:19:12.665] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:12.665] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:72:0) after 1m0s
[07:20:12.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:72:0)
[07:20:12.683] Timer tick, asking Buildbucket for the build status
[07:20:12.772] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:12.772] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:73:0) after 1m0s
[07:21:12.820] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:73:0)
[07:21:12.820] Timer tick, asking Buildbucket for the build status
[07:21:12.914] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:12.914] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:74:0) after 1m0s
[07:22:13.015] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:74:0)
[07:22:13.015] Timer tick, asking Buildbucket for the build status
[07:22:13.114] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:13.114] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:75:0) after 1m0s
[07:23:13.134] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:75:0)
[07:23:13.134] Timer tick, asking Buildbucket for the build status
[07:23:13.201] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:13.201] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:76:0) after 1m0s
[07:24:13.228] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:76:0)
[07:24:13.228] Timer tick, asking Buildbucket for the build status
[07:24:13.315] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:13.315] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:77:0) after 1m0s
[07:25:13.335] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:77:0)
[07:25:13.335] Timer tick, asking Buildbucket for the build status
[07:25:13.672] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:13.672] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:78:0) after 1m0s
[07:26:13.690] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:78:0)
[07:26:13.690] Timer tick, asking Buildbucket for the build status
[07:26:13.736] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:13.736] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:79:0) after 1m0s
[07:27:13.757] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:79:0)
[07:27:13.757] Timer tick, asking Buildbucket for the build status
[07:27:13.837] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:13.837] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:80:0) after 1m0s
[07:28:13.856] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:80:0)
[07:28:13.856] Timer tick, asking Buildbucket for the build status
[07:28:14.113] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:14.113] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:81:0) after 1m0s
[07:29:14.141] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:81:0)
[07:29:14.141] Timer tick, asking Buildbucket for the build status
[07:29:14.216] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:14.216] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:82:0) after 1m0s
[07:30:14.239] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:82:0)
[07:30:14.239] Timer tick, asking Buildbucket for the build status
[07:30:14.315] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:14.315] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:83:0) after 1m0s
[07:31:14.367] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:83:0)
[07:31:14.367] Timer tick, asking Buildbucket for the build status
[07:31:14.492] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:14.492] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:84:0) after 1m0s
[07:32:14.516] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:84:0)
[07:32:14.516] Timer tick, asking Buildbucket for the build status
[07:32:14.561] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:14.561] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:85:0) after 1m0s
[07:33:14.592] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:85:0)
[07:33:14.592] Timer tick, asking Buildbucket for the build status
[07:33:14.769] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:14.769] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:86:0) after 1m0s
[07:34:14.790] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:86:0)
[07:34:14.790] Timer tick, asking Buildbucket for the build status
[07:34:14.832] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:14.832] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:87:0) after 1m0s
[07:35:14.896] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:87:0)
[07:35:14.896] Timer tick, asking Buildbucket for the build status
[07:35:14.941] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:14.941] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:88:0) after 1m0s
[07:36:14.962] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:88:0)
[07:36:14.962] Timer tick, asking Buildbucket for the build status
[07:36:15.009] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:15.009] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:89:0) after 1m0s
[07:37:15.033] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:89:0)
[07:37:15.033] Timer tick, asking Buildbucket for the build status
[07:37:15.103] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:15.103] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:90:0) after 1m0s
[07:38:15.123] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:90:0)
[07:38:15.123] Timer tick, asking Buildbucket for the build status
[07:38:15.191] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:15.191] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:91:0) after 1m0s
[07:39:15.242] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:91:0)
[07:39:15.242] Timer tick, asking Buildbucket for the build status
[07:39:15.288] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:15.288] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:92:0) after 1m0s
[07:40:15.310] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:92:0)
[07:40:15.310] Timer tick, asking Buildbucket for the build status
[07:40:15.375] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:15.375] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:93:0) after 1m0s
[07:41:15.396] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:93:0)
[07:41:15.396] Timer tick, asking Buildbucket for the build status
[07:41:15.491] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:15.491] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:94:0) after 1m0s
[07:42:15.513] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:94:0)
[07:42:15.513] Timer tick, asking Buildbucket for the build status
[07:42:15.630] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:15.630] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:95:0) after 1m0s
[07:43:15.652] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:95:0)
[07:43:15.652] Timer tick, asking Buildbucket for the build status
[07:43:15.763] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:15.763] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:96:0) after 1m0s
[07:44:15.788] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:96:0)
[07:44:15.788] Timer tick, asking Buildbucket for the build status
[07:44:15.904] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:15.904] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:97:0) after 1m0s
[07:45:16.038] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:97:0)
[07:45:16.038] Timer tick, asking Buildbucket for the build status
[07:45:16.143] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:16.143] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:98:0) after 1m0s
[07:46:16.204] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:98:0)
[07:46:16.204] Timer tick, asking Buildbucket for the build status
[07:46:16.267] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:16.267] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:99:0) after 1m0s
[07:47:16.300] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:99:0)
[07:47:16.300] Timer tick, asking Buildbucket for the build status
[07:47:16.353] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:16.353] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:100:0) after 1m0s
[07:48:16.372] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:100:0)
[07:48:16.372] Timer tick, asking Buildbucket for the build status
[07:48:16.426] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:16.426] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:101:0) after 1m0s
[07:49:16.455] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:101:0)
[07:49:16.455] Timer tick, asking Buildbucket for the build status
[07:49:16.569] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:16.569] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:102:0) after 1m0s
[07:50:16.601] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:102:0)
[07:50:16.601] Timer tick, asking Buildbucket for the build status
[07:50:16.711] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:16.711] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:103:0) after 1m0s
[07:51:16.734] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:103:0)
[07:51:16.734] Timer tick, asking Buildbucket for the build status
[07:51:16.782] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:16.782] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:104:0) after 1m0s
[07:52:16.809] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:104:0)
[07:52:16.809] Timer tick, asking Buildbucket for the build status
[07:52:16.877] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:16.877] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:105:0) after 1m0s
[07:53:16.902] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:105:0)
[07:53:16.902] Timer tick, asking Buildbucket for the build status
[07:53:17.182] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:17.182] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:106:0) after 1m0s
[07:54:17.208] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:106:0)
[07:54:17.208] Timer tick, asking Buildbucket for the build status
[07:54:17.451] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:17.451] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:107:0) after 1m0s
[07:55:17.473] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:107:0)
[07:55:17.473] Timer tick, asking Buildbucket for the build status
[07:55:17.598] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:17.598] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:108:0) after 1m0s
[07:56:17.619] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:108:0)
[07:56:17.619] Timer tick, asking Buildbucket for the build status
[07:56:17.685] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:17.685] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:109:0) after 1m0s
[07:57:17.708] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:109:0)
[07:57:17.708] Timer tick, asking Buildbucket for the build status
[07:57:17.818] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:17.818] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:110:0) after 1m0s
[07:58:17.841] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:110:0)
[07:58:17.841] Timer tick, asking Buildbucket for the build status
[07:58:17.917] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:17.917] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:111:0) after 1m0s
[07:59:17.940] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:111:0)
[07:59:17.940] Timer tick, asking Buildbucket for the build status
[07:59:18.054] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:18.055] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:112:0) after 1m0s
[08:00:18.078] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:112:0)
[08:00:18.078] Timer tick, asking Buildbucket for the build status
[08:00:18.132] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:18.132] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:113:0) after 1m0s
[08:01:18.164] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:113:0)
[08:01:18.164] Timer tick, asking Buildbucket for the build status
[08:01:18.208] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:18.208] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:114:0) after 1m0s
[08:02:18.232] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:114:0)
[08:02:18.232] Timer tick, asking Buildbucket for the build status
[08:02:18.284] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:18.284] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:115:0) after 1m0s
[08:03:18.318] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:115:0)
[08:03:18.318] Timer tick, asking Buildbucket for the build status
[08:03:18.596] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:18.596] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:116:0) after 1m0s
[08:04:18.736] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:116:0)
[08:04:18.736] Timer tick, asking Buildbucket for the build status
[08:04:18.808] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:18.808] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:117:0) after 1m0s
[08:05:18.870] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:117:0)
[08:05:18.870] Timer tick, asking Buildbucket for the build status
[08:05:18.918] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:18.918] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:118:0) after 1m0s
[08:06:18.943] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:118:0)
[08:06:18.943] Timer tick, asking Buildbucket for the build status
[08:06:19.000] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:19.001] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:119:0) after 1m0s
[08:07:19.033] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:119:0)
[08:07:19.033] Timer tick, asking Buildbucket for the build status
[08:07:19.098] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:19.098] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:120:0) after 1m0s
[08:08:19.133] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:120:0)
[08:08:19.133] Timer tick, asking Buildbucket for the build status
[08:08:19.201] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:19.201] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:121:0) after 1m0s
[08:09:19.220] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:121:0)
[08:09:19.220] Timer tick, asking Buildbucket for the build status
[08:09:19.285] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:19.285] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:122:0) after 1m0s
[08:10:19.310] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:122:0)
[08:10:19.310] Timer tick, asking Buildbucket for the build status
[08:10:19.361] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:19.361] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:123:0) after 1m0s
[08:11:19.411] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:123:0)
[08:11:19.411] Timer tick, asking Buildbucket for the build status
[08:11:19.450] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:19.450] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:124:0) after 1m0s
[08:12:19.474] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:124:0)
[08:12:19.474] Timer tick, asking Buildbucket for the build status
[08:12:19.683] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:19.683] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:125:0) after 1m0s
[08:13:19.705] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:125:0)
[08:13:19.705] Timer tick, asking Buildbucket for the build status
[08:13:20.619] Build 8921751459564106352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:20.619] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087181521758705344:126:0) after 1m0s
[08:13:34.322] Received PubSub notification, asking Buildbucket for the build status
[08:13:34.393] Build 8921751459564106352: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:13:34.393] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1549959213492503",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1549951797861589",
"id": "8921751459564106352",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"85548824b14a09d1bfb3717638b65364eaaaaf82\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"id\": [\"swarm2173-c4\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4085-c81638b\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"got_nacl_revision\": \"ff15c51d20785fa51c0ef03ccb480b87299acbeb\", \"got_swarming_client_revision\": \"0e3e1c4dc4e79f25a5b58fcbc135dc93183c0c54\", \"got_revision\": \"85548824b14a09d1bfb3717638b65364eaaaaf82\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"5987c4e8395aed194c987c051b56d35485180b46\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26626}\", \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"got_revision_cp\": \"refs/heads/master@{#631127}\", \"branch\": \"refs/heads/master\", \"revision\": \"85548824b14a09d1bfb3717638b65364eaaaaf82\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"f13c2cd9ee41f4ca572232a4e397b05449474632\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"c66fb571be1a1599791a6d96d4055b3b84534a14\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1549951797861589, \"tags\": [\"build_address:luci.chromium.ci/Windows deterministic/11939\", \"builder:Windows deterministic\", \"buildset:commit/git/85548824b14a09d1bfb3717638b65364eaaaaf82\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/85548824b14a09d1bfb3717638b65364eaaaaf82\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9087181521758705344\", \"scheduler_job_id:chromium/Windows deterministic\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.chromium.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"chromium\", \"id\": \"8921751459564106352\"}}, \"got_v8_revision\": \"1a2e8b8dfd4006959f17c0145ec936117eb3808d\", \"got_v8_revision_cp\": \"refs/heads/7.4.139@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 11939, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Windows deterministic\", \"key\": \"builder\"}, {\"value\": \"commit/git/85548824b14a09d1bfb3717638b65364eaaaaf82\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/chromium/src/+/85548824b14a09d1bfb3717638b65364eaaaaf82\", \"key\": \"buildset\"}, {\"value\": \"9087181521758705344\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"chromium/Windows deterministic\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"chromium\", \"builder\": \"Windows deterministic\", \"bucket\": \"ci\"}, \"number\": 11939, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"2acf9ddf085124300131f0a719a64564504c97a6\"}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8921751459564106352\", \"input\": {\"gitilesCommit\": {\"project\": \"chromium/src\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"85548824b14a09d1bfb3717638b65364eaaaaf82\"}}, \"createTime\": \"2019-02-12T06:09:57.861589Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1549951800182139",
"status": "COMPLETED",
"status_changed_ts": "1549959213966399",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11939",
"builder:Windows deterministic",
"buildset:commit/git/85548824b14a09d1bfb3717638b65364eaaaaf82",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/85548824b14a09d1bfb3717638b65364eaaaaf82",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9087181521758705344",
"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/8921751459564106352/+/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:42f9251535346010",
"user_agent:luci-scheduler"
],
"updated_ts": "1549959213967731",
"url": "https://ci.chromium.org/b/8921751459564106352",
"utcnow_ts": "1549959214380600"
}
}
[08:13:34.393] Invocation finished in 2h3m38.008385903s with status SUCCEEDED