[14:34:04.303] New invocation is queued and will start shortly
[14:34:05.589] Starting the invocation (attempt 1)
[14:34:05.633] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:34:05.633] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:34:05.634] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9068396232492927520",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"d053411af5201c7078bc4951e3d42bb59efdfd27\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9068396232492927520",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/d053411af5201c7078bc4951e3d42bb59efdfd27",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d053411af5201c7078bc4951e3d42bb59efdfd27",
"gitiles_ref:refs/heads/master"
]
}
[14:34:06.387] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1567866845819627",
"id": "8902966170237200000",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"d053411af5201c7078bc4951e3d42bb59efdfd27\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1567866846256366",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14657",
"builder:Windows deterministic",
"buildset:commit/git/d053411af5201c7078bc4951e3d42bb59efdfd27",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d053411af5201c7078bc4951e3d42bb59efdfd27",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9068396232492927520",
"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/8902966170237200000/+/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": "1567866846256483",
"url": "https://ci.chromium.org/b/8902966170237200000",
"utcnow_ts": "1567866846378013"
}
}
[14:34:06.387] Task URL: https://ci.chromium.org/b/8902966170237200000
[14:34:06.387] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:2:0) after 1m0s
[14:35:06.603] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:2:0)
[14:35:06.603] Timer tick, asking Buildbucket for the build status
[14:35:06.776] Build 8902966170237200000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:35:06.776] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:3:0) after 1m0s
[14:35:58.702] Received PubSub notification, asking Buildbucket for the build status
[14:35:59.085] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:06.921] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:3:0)
[14:36:06.921] Timer tick, asking Buildbucket for the build status
[14:36:07.190] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:07.190] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:5:0) after 1m0s
[14:37:07.390] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:5:0)
[14:37:07.390] Timer tick, asking Buildbucket for the build status
[14:37:07.741] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:07.741] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:6:0) after 1m0s
[14:38:07.779] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:6:0)
[14:38:07.779] Timer tick, asking Buildbucket for the build status
[14:38:07.919] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:07.919] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:7:0) after 1m0s
[14:39:07.950] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:7:0)
[14:39:07.950] Timer tick, asking Buildbucket for the build status
[14:39:08.455] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:08.455] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:8:0) after 1m0s
[14:40:08.761] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:8:0)
[14:40:08.761] Timer tick, asking Buildbucket for the build status
[14:40:08.984] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:08.984] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:9:0) after 1m0s
[14:41:09.267] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:9:0)
[14:41:09.267] Timer tick, asking Buildbucket for the build status
[14:41:09.772] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:09.772] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:10:0) after 1m0s
[14:42:09.805] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:10:0)
[14:42:09.805] Timer tick, asking Buildbucket for the build status
[14:42:09.992] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:09.993] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:11:0) after 1m0s
[14:43:10.272] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:11:0)
[14:43:10.272] Timer tick, asking Buildbucket for the build status
[14:43:10.433] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:10.433] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:12:0) after 1m0s
[14:44:10.550] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:12:0)
[14:44:10.550] Timer tick, asking Buildbucket for the build status
[14:44:11.135] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:11.135] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:13:0) after 1m0s
[14:45:11.229] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:13:0)
[14:45:11.229] Timer tick, asking Buildbucket for the build status
[14:45:12.195] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:12.195] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:14:0) after 1m0s
[14:46:12.234] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:14:0)
[14:46:12.234] Timer tick, asking Buildbucket for the build status
[14:46:13.008] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:13.008] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:15:0) after 1m0s
[14:47:13.167] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:15:0)
[14:47:13.167] Timer tick, asking Buildbucket for the build status
[14:47:13.905] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:13.905] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:16:0) after 1m0s
[14:48:14.008] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:16:0)
[14:48:14.008] Timer tick, asking Buildbucket for the build status
[14:48:14.332] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:14.332] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:17:0) after 1m0s
[14:49:14.421] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:17:0)
[14:49:14.421] Timer tick, asking Buildbucket for the build status
[14:49:14.647] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:14.647] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:18:0) after 1m0s
[14:50:14.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:18:0)
[14:50:14.683] Timer tick, asking Buildbucket for the build status
[14:50:14.882] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:14.882] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:19:0) after 1m0s
[14:51:14.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:19:0)
[14:51:14.906] Timer tick, asking Buildbucket for the build status
[14:51:15.577] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:15.577] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:20:0) after 1m0s
[14:52:15.567] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:20:0)
[14:52:15.567] Timer tick, asking Buildbucket for the build status
[14:52:15.756] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:15.756] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:21:0) after 1m0s
[14:53:15.781] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:21:0)
[14:53:15.781] Timer tick, asking Buildbucket for the build status
[14:53:15.975] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:15.975] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:22:0) after 1m0s
[14:54:16.003] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:22:0)
[14:54:16.003] Timer tick, asking Buildbucket for the build status
[14:54:16.214] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:16.214] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:23:0) after 1m0s
[14:55:16.267] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:23:0)
[14:55:16.267] Timer tick, asking Buildbucket for the build status
[14:55:16.606] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:16.606] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:24:0) after 1m0s
[14:56:16.639] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:24:0)
[14:56:16.639] Timer tick, asking Buildbucket for the build status
[14:56:17.024] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:17.024] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:25:0) after 1m0s
[14:57:17.136] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:25:0)
[14:57:17.136] Timer tick, asking Buildbucket for the build status
[14:57:17.283] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:17.283] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:26:0) after 1m0s
[14:58:17.410] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:26:0)
[14:58:17.410] Timer tick, asking Buildbucket for the build status
[14:58:18.038] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:18.038] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:27:0) after 1m0s
[14:59:18.072] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:27:0)
[14:59:18.072] Timer tick, asking Buildbucket for the build status
[14:59:18.298] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:18.298] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:28:0) after 1m0s
[15:00:18.346] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:28:0)
[15:00:18.346] Timer tick, asking Buildbucket for the build status
[15:00:19.621] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:19.621] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:29:0) after 1m0s
[15:01:19.643] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:29:0)
[15:01:19.643] Timer tick, asking Buildbucket for the build status
[15:01:20.280] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:20.280] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:30:0) after 1m0s
[15:02:20.370] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:30:0)
[15:02:20.370] Timer tick, asking Buildbucket for the build status
[15:02:20.995] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:20.995] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:31:0) after 1m0s
[15:03:21.019] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:31:0)
[15:03:21.019] Timer tick, asking Buildbucket for the build status
[15:03:21.197] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:21.197] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:32:0) after 1m0s
[15:04:21.221] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:32:0)
[15:04:21.221] Timer tick, asking Buildbucket for the build status
[15:04:21.381] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:21.381] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:33:0) after 1m0s
[15:05:21.406] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:33:0)
[15:05:21.406] Timer tick, asking Buildbucket for the build status
[15:05:21.752] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:21.752] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:34:0) after 1m0s
[15:06:21.777] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:34:0)
[15:06:21.777] Timer tick, asking Buildbucket for the build status
[15:06:23.720] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:23.720] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:35:0) after 1m0s
[15:07:23.747] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:35:0)
[15:07:23.747] Timer tick, asking Buildbucket for the build status
[15:07:23.915] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:23.915] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:36:0) after 1m0s
[15:08:23.970] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:36:0)
[15:08:23.970] Timer tick, asking Buildbucket for the build status
[15:08:25.017] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:25.017] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:37:0) after 1m0s
[15:09:25.057] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:37:0)
[15:09:25.057] Timer tick, asking Buildbucket for the build status
[15:09:25.541] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:25.541] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:38:0) after 1m0s
[15:10:25.567] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:38:0)
[15:10:25.567] Timer tick, asking Buildbucket for the build status
[15:10:25.794] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:25.794] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:39:0) after 1m0s
[15:11:25.856] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:39:0)
[15:11:25.856] Timer tick, asking Buildbucket for the build status
[15:11:26.127] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:26.127] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:40:0) after 1m0s
[15:12:26.158] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:40:0)
[15:12:26.159] Timer tick, asking Buildbucket for the build status
[15:12:26.409] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:26.409] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:41:0) after 1m0s
[15:13:26.536] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:41:0)
[15:13:26.536] Timer tick, asking Buildbucket for the build status
[15:13:26.764] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:26.764] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:42:0) after 1m0s
[15:14:26.873] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:42:0)
[15:14:26.873] Timer tick, asking Buildbucket for the build status
[15:14:27.416] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:27.416] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:43:0) after 1m0s
[15:15:27.488] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:43:0)
[15:15:27.488] Timer tick, asking Buildbucket for the build status
[15:15:27.704] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:27.704] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:44:0) after 1m0s
[15:16:27.719] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:44:0)
[15:16:27.719] Timer tick, asking Buildbucket for the build status
[15:16:27.938] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:27.938] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:45:0) after 1m0s
[15:17:28.004] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:45:0)
[15:17:28.004] Timer tick, asking Buildbucket for the build status
[15:17:28.613] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:28.613] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:46:0) after 1m0s
[15:18:28.758] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:46:0)
[15:18:28.758] Timer tick, asking Buildbucket for the build status
[15:18:28.937] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:28.937] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:47:0) after 1m0s
[15:19:29.008] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:47:0)
[15:19:29.008] Timer tick, asking Buildbucket for the build status
[15:19:29.387] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:29.387] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:48:0) after 1m0s
[15:20:29.486] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:48:0)
[15:20:29.486] Timer tick, asking Buildbucket for the build status
[15:20:30.723] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:30.723] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:49:0) after 1m0s
[15:21:30.772] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:49:0)
[15:21:30.772] Timer tick, asking Buildbucket for the build status
[15:21:31.984] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:31.984] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:50:0) after 1m0s
[15:22:31.982] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:50:0)
[15:22:31.982] Timer tick, asking Buildbucket for the build status
[15:22:32.302] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:32.302] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:51:0) after 1m0s
[15:23:32.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:51:0)
[15:23:32.377] Timer tick, asking Buildbucket for the build status
[15:23:32.921] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:32.921] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:52:0) after 1m0s
[15:24:32.949] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:52:0)
[15:24:32.949] Timer tick, asking Buildbucket for the build status
[15:24:33.630] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:33.630] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:53:0) after 1m0s
[15:25:33.696] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:53:0)
[15:25:33.696] Timer tick, asking Buildbucket for the build status
[15:25:34.751] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:34.751] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:54:0) after 1m0s
[15:26:34.893] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:54:0)
[15:26:34.893] Timer tick, asking Buildbucket for the build status
[15:26:35.613] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:35.613] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:55:0) after 1m0s
[15:27:35.641] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:55:0)
[15:27:35.641] Timer tick, asking Buildbucket for the build status
[15:27:36.052] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:36.052] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:56:0) after 1m0s
[15:28:36.143] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:56:0)
[15:28:36.143] Timer tick, asking Buildbucket for the build status
[15:28:36.419] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:36.419] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:57:0) after 1m0s
[15:29:36.494] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:57:0)
[15:29:36.494] Timer tick, asking Buildbucket for the build status
[15:29:36.654] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:36.654] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:58:0) after 1m0s
[15:30:36.702] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:58:0)
[15:30:36.702] Timer tick, asking Buildbucket for the build status
[15:30:36.874] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:36.874] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:59:0) after 1m0s
[15:31:36.907] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:59:0)
[15:31:36.907] Timer tick, asking Buildbucket for the build status
[15:31:38.479] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:38.479] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:60:0) after 1m0s
[15:32:38.506] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:60:0)
[15:32:38.506] Timer tick, asking Buildbucket for the build status
[15:32:39.244] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:39.244] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:61:0) after 1m0s
[15:33:39.278] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:61:0)
[15:33:39.278] Timer tick, asking Buildbucket for the build status
[15:33:39.463] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:39.463] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:62:0) after 1m0s
[15:34:39.492] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:62:0)
[15:34:39.492] Timer tick, asking Buildbucket for the build status
[15:34:40.561] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:40.561] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:63:0) after 1m0s
[15:35:40.591] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:63:0)
[15:35:40.591] Timer tick, asking Buildbucket for the build status
[15:35:40.747] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:40.747] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:64:0) after 1m0s
[15:36:40.850] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:64:0)
[15:36:40.850] Timer tick, asking Buildbucket for the build status
[15:36:41.340] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:41.340] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:65:0) after 1m0s
[15:37:41.427] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:65:0)
[15:37:41.427] Timer tick, asking Buildbucket for the build status
[15:37:41.600] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:41.600] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:66:0) after 1m0s
[15:38:41.648] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:66:0)
[15:38:41.648] Timer tick, asking Buildbucket for the build status
[15:38:41.775] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:41.775] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:67:0) after 1m0s
[15:39:41.833] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:67:0)
[15:39:41.833] Timer tick, asking Buildbucket for the build status
[15:39:42.012] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:42.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:68:0) after 1m0s
[15:40:42.075] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:68:0)
[15:40:42.075] Timer tick, asking Buildbucket for the build status
[15:40:42.276] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:42.276] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:69:0) after 1m0s
[15:41:42.333] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:69:0)
[15:41:42.333] Timer tick, asking Buildbucket for the build status
[15:41:42.550] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:42.550] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:70:0) after 1m0s
[15:42:42.579] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:70:0)
[15:42:42.579] Timer tick, asking Buildbucket for the build status
[15:42:43.044] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:43.044] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:71:0) after 1m0s
[15:43:43.104] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:71:0)
[15:43:43.104] Timer tick, asking Buildbucket for the build status
[15:43:44.591] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:44.591] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:72:0) after 1m0s
[15:44:44.702] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:72:0)
[15:44:44.702] Timer tick, asking Buildbucket for the build status
[15:44:44.905] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:44.905] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:73:0) after 1m0s
[15:45:44.938] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:73:0)
[15:45:44.938] Timer tick, asking Buildbucket for the build status
[15:45:45.107] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:45.107] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:74:0) after 1m0s
[15:46:45.137] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:74:0)
[15:46:45.137] Timer tick, asking Buildbucket for the build status
[15:46:45.293] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:45.293] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:75:0) after 1m0s
[15:47:45.307] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:75:0)
[15:47:45.307] Timer tick, asking Buildbucket for the build status
[15:47:45.521] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:45.521] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:76:0) after 1m0s
[15:48:45.547] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:76:0)
[15:48:45.547] Timer tick, asking Buildbucket for the build status
[15:48:45.726] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:45.726] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:77:0) after 1m0s
[15:49:45.753] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:77:0)
[15:49:45.753] Timer tick, asking Buildbucket for the build status
[15:49:46.723] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:46.723] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:78:0) after 1m0s
[15:50:46.754] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:78:0)
[15:50:46.754] Timer tick, asking Buildbucket for the build status
[15:50:47.240] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:47.240] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:79:0) after 1m0s
[15:51:47.280] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:79:0)
[15:51:47.280] Timer tick, asking Buildbucket for the build status
[15:51:47.724] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:47.724] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:80:0) after 1m0s
[15:52:47.747] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:80:0)
[15:52:47.747] Timer tick, asking Buildbucket for the build status
[15:52:47.904] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:47.904] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:81:0) after 1m0s
[15:53:47.970] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:81:0)
[15:53:47.970] Timer tick, asking Buildbucket for the build status
[15:53:49.268] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:49.268] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:82:0) after 1m0s
[15:54:49.362] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:82:0)
[15:54:49.362] Timer tick, asking Buildbucket for the build status
[15:54:49.900] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:49.900] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:83:0) after 1m0s
[15:55:49.975] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:83:0)
[15:55:49.975] Timer tick, asking Buildbucket for the build status
[15:55:50.441] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:50.441] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:84:0) after 1m0s
[15:56:50.513] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:84:0)
[15:56:50.513] Timer tick, asking Buildbucket for the build status
[15:56:50.740] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:50.740] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:85:0) after 1m0s
[15:57:50.807] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:85:0)
[15:57:50.807] Timer tick, asking Buildbucket for the build status
[15:57:52.848] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:52.848] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:86:0) after 1m0s
[15:58:52.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:86:0)
[15:58:52.905] Timer tick, asking Buildbucket for the build status
[15:58:53.388] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:53.388] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:87:0) after 1m0s
[15:59:53.388] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:87:0)
[15:59:53.388] Timer tick, asking Buildbucket for the build status
[15:59:53.642] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:53.642] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:88:0) after 1m0s
[16:00:53.700] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:88:0)
[16:00:53.700] Timer tick, asking Buildbucket for the build status
[16:00:54.375] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:54.375] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:89:0) after 1m0s
[16:01:54.472] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:89:0)
[16:01:54.472] Timer tick, asking Buildbucket for the build status
[16:01:54.919] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:54.919] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:90:0) after 1m0s
[16:02:55.005] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:90:0)
[16:02:55.005] Timer tick, asking Buildbucket for the build status
[16:02:55.190] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:55.190] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:91:0) after 1m0s
[16:03:55.255] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:91:0)
[16:03:55.255] Timer tick, asking Buildbucket for the build status
[16:03:55.798] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:55.798] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:92:0) after 1m0s
[16:04:55.893] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:92:0)
[16:04:55.893] Timer tick, asking Buildbucket for the build status
[16:04:56.063] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:56.064] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:93:0) after 1m0s
[16:05:56.140] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:93:0)
[16:05:56.140] Timer tick, asking Buildbucket for the build status
[16:05:56.511] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:56.511] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:94:0) after 1m0s
[16:06:56.579] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:94:0)
[16:06:56.579] Timer tick, asking Buildbucket for the build status
[16:06:56.839] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:56.839] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:95:0) after 1m0s
[16:07:56.874] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:95:0)
[16:07:56.874] Timer tick, asking Buildbucket for the build status
[16:07:57.106] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:57.106] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:96:0) after 1m0s
[16:08:57.132] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:96:0)
[16:08:57.132] Timer tick, asking Buildbucket for the build status
[16:08:57.288] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:57.288] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:97:0) after 1m0s
[16:09:57.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:97:0)
[16:09:57.344] Timer tick, asking Buildbucket for the build status
[16:09:57.942] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:57.942] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:98:0) after 1m0s
[16:10:57.968] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:98:0)
[16:10:57.968] Timer tick, asking Buildbucket for the build status
[16:10:58.621] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:58.621] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:99:0) after 1m0s
[16:11:58.650] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:99:0)
[16:11:58.650] Timer tick, asking Buildbucket for the build status
[16:11:58.814] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:58.814] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:100:0) after 1m0s
[16:12:58.851] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:100:0)
[16:12:58.851] Timer tick, asking Buildbucket for the build status
[16:12:59.890] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:59.890] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:101:0) after 1m0s
[16:13:59.917] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:101:0)
[16:13:59.917] Timer tick, asking Buildbucket for the build status
[16:14:00.351] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:00.351] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:102:0) after 1m0s
[16:15:00.484] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:102:0)
[16:15:00.484] Timer tick, asking Buildbucket for the build status
[16:15:00.915] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:00.915] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:103:0) after 1m0s
[16:16:00.969] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:103:0)
[16:16:00.969] Timer tick, asking Buildbucket for the build status
[16:16:01.252] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:01.252] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:104:0) after 1m0s
[16:17:01.376] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:104:0)
[16:17:01.376] Timer tick, asking Buildbucket for the build status
[16:17:01.616] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:01.616] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:105:0) after 1m0s
[16:18:01.648] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:105:0)
[16:18:01.648] Timer tick, asking Buildbucket for the build status
[16:18:02.595] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:02.595] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:106:0) after 1m0s
[16:19:02.680] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:106:0)
[16:19:02.680] Timer tick, asking Buildbucket for the build status
[16:19:03.134] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:03.134] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:107:0) after 1m0s
[16:20:03.191] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:107:0)
[16:20:03.191] Timer tick, asking Buildbucket for the build status
[16:20:03.447] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:03.447] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:108:0) after 1m0s
[16:21:03.482] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:108:0)
[16:21:03.482] Timer tick, asking Buildbucket for the build status
[16:21:03.671] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:03.671] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:109:0) after 1m0s
[16:22:03.669] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:109:0)
[16:22:03.669] Timer tick, asking Buildbucket for the build status
[16:22:03.866] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:03.866] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:110:0) after 1m0s
[16:23:04.066] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:110:0)
[16:23:04.066] Timer tick, asking Buildbucket for the build status
[16:23:04.260] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:04.260] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:111:0) after 1m0s
[16:24:04.296] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:111:0)
[16:24:04.296] Timer tick, asking Buildbucket for the build status
[16:24:04.512] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:04.512] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:112:0) after 1m0s
[16:25:04.649] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:112:0)
[16:25:04.649] Timer tick, asking Buildbucket for the build status
[16:25:04.974] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:04.974] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:113:0) after 1m0s
[16:26:05.055] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:113:0)
[16:26:05.055] Timer tick, asking Buildbucket for the build status
[16:26:05.664] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:05.664] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:114:0) after 1m0s
[16:27:05.667] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:114:0)
[16:27:05.667] Timer tick, asking Buildbucket for the build status
[16:27:05.921] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:05.921] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:115:0) after 1m0s
[16:28:06.098] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:115:0)
[16:28:06.098] Timer tick, asking Buildbucket for the build status
[16:28:07.021] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:07.021] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:116:0) after 1m0s
[16:29:07.083] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:116:0)
[16:29:07.083] Timer tick, asking Buildbucket for the build status
[16:29:08.195] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:08.195] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:117:0) after 1m0s
[16:30:08.247] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:117:0)
[16:30:08.247] Timer tick, asking Buildbucket for the build status
[16:30:09.129] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:09.129] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:118:0) after 1m0s
[16:31:09.155] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:118:0)
[16:31:09.155] Timer tick, asking Buildbucket for the build status
[16:31:09.623] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:09.623] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:119:0) after 1m0s
[16:32:09.745] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:119:0)
[16:32:09.745] Timer tick, asking Buildbucket for the build status
[16:32:10.187] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:10.187] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:120:0) after 1m0s
[16:33:10.234] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:120:0)
[16:33:10.234] Timer tick, asking Buildbucket for the build status
[16:33:10.433] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:10.433] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:121:0) after 1m0s
[16:34:10.566] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:121:0)
[16:34:10.567] Timer tick, asking Buildbucket for the build status
[16:34:11.092] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:11.092] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:122:0) after 1m0s
[16:35:11.298] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:122:0)
[16:35:11.298] Timer tick, asking Buildbucket for the build status
[16:35:11.648] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:11.649] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:123:0) after 1m0s
[16:36:11.682] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:123:0)
[16:36:11.682] Timer tick, asking Buildbucket for the build status
[16:36:11.945] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:11.945] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:124:0) after 1m0s
[16:37:12.050] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:124:0)
[16:37:12.050] Timer tick, asking Buildbucket for the build status
[16:37:12.919] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:12.919] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:125:0) after 1m0s
[16:38:12.963] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:125:0)
[16:38:12.963] Timer tick, asking Buildbucket for the build status
[16:38:13.214] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:13.214] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:126:0) after 1m0s
[16:39:13.307] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:126:0)
[16:39:13.307] Timer tick, asking Buildbucket for the build status
[16:39:13.603] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:13.603] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:127:0) after 1m0s
[16:40:13.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:127:0)
[16:40:13.683] Timer tick, asking Buildbucket for the build status
[16:40:14.123] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:14.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:128:0) after 1m0s
[16:41:14.366] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:128:0)
[16:41:14.366] Timer tick, asking Buildbucket for the build status
[16:41:14.496] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:14.496] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:129:0) after 1m0s
[16:42:14.805] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:129:0)
[16:42:14.805] Timer tick, asking Buildbucket for the build status
[16:42:15.117] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:15.117] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:130:0) after 1m0s
[16:43:15.211] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:130:0)
[16:43:15.211] Timer tick, asking Buildbucket for the build status
[16:43:15.437] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:15.437] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:131:0) after 1m0s
[16:44:15.604] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:131:0)
[16:44:15.604] Timer tick, asking Buildbucket for the build status
[16:44:15.919] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:15.919] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:132:0) after 1m0s
[16:45:16.160] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:132:0)
[16:45:16.160] Timer tick, asking Buildbucket for the build status
[16:45:16.316] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:16.316] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:133:0) after 1m0s
[16:46:16.677] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:133:0)
[16:46:16.677] Timer tick, asking Buildbucket for the build status
[16:46:16.919] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:16.919] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:134:0) after 1m0s
[16:47:17.143] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:134:0)
[16:47:17.143] Timer tick, asking Buildbucket for the build status
[16:47:17.331] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:17.331] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:135:0) after 1m0s
[16:48:17.436] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:135:0)
[16:48:17.436] Timer tick, asking Buildbucket for the build status
[16:48:17.587] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:17.587] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:136:0) after 1m0s
[16:49:17.618] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:136:0)
[16:49:17.618] Timer tick, asking Buildbucket for the build status
[16:49:17.832] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:17.833] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:137:0) after 1m0s
[16:50:17.961] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:137:0)
[16:50:17.961] Timer tick, asking Buildbucket for the build status
[16:50:18.604] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:18.604] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:138:0) after 1m0s
[16:51:18.694] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:138:0)
[16:51:18.694] Timer tick, asking Buildbucket for the build status
[16:51:18.839] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:18.839] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:139:0) after 1m0s
[16:52:18.994] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:139:0)
[16:52:18.994] Timer tick, asking Buildbucket for the build status
[16:52:19.136] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:19.136] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:140:0) after 1m0s
[16:53:19.273] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:140:0)
[16:53:19.274] Timer tick, asking Buildbucket for the build status
[16:53:19.566] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:19.566] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:141:0) after 1m0s
[16:54:19.655] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:141:0)
[16:54:19.655] Timer tick, asking Buildbucket for the build status
[16:54:21.012] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:21.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:142:0) after 1m0s
[16:55:21.122] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:142:0)
[16:55:21.122] Timer tick, asking Buildbucket for the build status
[16:55:21.266] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:21.266] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:143:0) after 1m0s
[16:56:21.272] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:143:0)
[16:56:21.272] Timer tick, asking Buildbucket for the build status
[16:56:24.255] Build 8902966170237200000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:24.255] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9068396232492927520:144:0) after 1m0s
[16:57:13.015] Received PubSub notification, asking Buildbucket for the build status
[16:57:13.139] Build 8902966170237200000: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:57:13.139] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1567875431177386",
"created_by": "project:chromium",
"created_ts": "1567866845819627",
"id": "8902966170237200000",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"d053411af5201c7078bc4951e3d42bb59efdfd27\"}}",
"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-y5mg\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 14657, \"got_angle_revision\": \"32d6006bf2ef9ea1cc5a705df3493ed7cca16821\", \"got_dawn_revision\": \"a900ccebcf428c99184ca44a3fad6030b2e43fc7\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"d053411af5201c7078bc4951e3d42bb59efdfd27\", \"got_revision_cp\": \"refs/heads/master@{#694583}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"73694fd32996da7f914e05ebc84aa9fbe2cd6b52\", \"got_v8_revision_cp\": \"refs/heads/7.8.279@{#1}\", \"got_webrtc_revision\": \"3146ea01ae59f7b9e75205bae471505407f6316e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29104}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"d053411af5201c7078bc4951e3d42bb59efdfd27\"}, \"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-y5mg\"], \"image\": [\"chrome-win10-1703-19072400-fedda89f1f1\"], \"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\": [\"4417-ad2503b\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1567866958000717",
"status": "COMPLETED",
"status_changed_ts": "1567875432290549",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/14657",
"builder:Windows deterministic",
"buildset:commit/git/d053411af5201c7078bc4951e3d42bb59efdfd27",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d053411af5201c7078bc4951e3d42bb59efdfd27",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9068396232492927520",
"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/8902966170237200000/+/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:4724f705e94d9310",
"user_agent:luci-scheduler"
],
"updated_ts": "1567875432311166",
"url": "https://ci.chromium.org/b/8902966170237200000",
"utcnow_ts": "1567875433129312"
}
}
[16:57:13.139] Invocation finished in 2h23m8.855358223s with status SUCCEEDED