[20:24:04.803] New invocation is queued and will start shortly
[20:24:05.876] Starting the invocation (attempt 1)
[20:24:05.920] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:24:05.920] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:24:05.920] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9060764066694849584",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"bb1bd77944bcda71e22bf59e7b58b79d0aaeee63\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9060764066694849584",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/bb1bd77944bcda71e22bf59e7b58b79d0aaeee63",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bb1bd77944bcda71e22bf59e7b58b79d0aaeee63",
"gitiles_ref:refs/heads/master"
]
}
[20:24:06.577] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1575145446049586",
"id": "8895334004721800608",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bb1bd77944bcda71e22bf59e7b58b79d0aaeee63\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1575145446472309",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/15343",
"builder:Windows deterministic",
"buildset:commit/git/bb1bd77944bcda71e22bf59e7b58b79d0aaeee63",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bb1bd77944bcda71e22bf59e7b58b79d0aaeee63",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9060764066694849584",
"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/8895334004721800608/+/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": "1575145446472409",
"url": "https://ci.chromium.org/b/8895334004721800608",
"utcnow_ts": "1575145446566510"
}
}
[20:24:06.577] Task URL: https://ci.chromium.org/b/8895334004721800608
[20:24:06.577] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:2:0) after 1m0s
[20:24:14.966] Received PubSub notification, asking Buildbucket for the build status
[20:24:15.305] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:06.614] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:2:0)
[20:25:06.614] Timer tick, asking Buildbucket for the build status
[20:25:08.026] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:08.026] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:4:0) after 1m0s
[20:26:08.055] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:4:0)
[20:26:08.055] Timer tick, asking Buildbucket for the build status
[20:26:08.741] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:08.741] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:5:0) after 1m0s
[20:27:08.767] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:5:0)
[20:27:08.767] Timer tick, asking Buildbucket for the build status
[20:27:08.935] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:08.935] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:6:0) after 1m0s
[20:28:08.954] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:6:0)
[20:28:08.954] Timer tick, asking Buildbucket for the build status
[20:28:09.126] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:09.126] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:7:0) after 1m0s
[20:29:09.148] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:7:0)
[20:29:09.148] Timer tick, asking Buildbucket for the build status
[20:29:09.392] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:09.392] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:8:0) after 1m0s
[20:30:09.412] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:8:0)
[20:30:09.412] Timer tick, asking Buildbucket for the build status
[20:30:09.674] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:09.674] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:9:0) after 1m0s
[20:31:09.694] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:9:0)
[20:31:09.694] Timer tick, asking Buildbucket for the build status
[20:31:09.924] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:09.924] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:10:0) after 1m0s
[20:32:09.968] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:10:0)
[20:32:09.968] Timer tick, asking Buildbucket for the build status
[20:32:10.127] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:10.127] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:11:0) after 1m0s
[20:33:10.302] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:11:0)
[20:33:10.302] Timer tick, asking Buildbucket for the build status
[20:33:10.745] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:10.745] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:12:0) after 1m0s
[20:34:10.965] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:12:0)
[20:34:10.965] Timer tick, asking Buildbucket for the build status
[20:34:11.920] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:11.920] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:13:0) after 1m0s
[20:35:11.951] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:13:0)
[20:35:11.951] Timer tick, asking Buildbucket for the build status
[20:35:12.238] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:12.238] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:14:0) after 1m0s
[20:36:12.258] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:14:0)
[20:36:12.258] Timer tick, asking Buildbucket for the build status
[20:36:12.611] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:12.611] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:15:0) after 1m0s
[20:37:12.639] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:15:0)
[20:37:12.639] Timer tick, asking Buildbucket for the build status
[20:37:13.263] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:13.263] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:16:0) after 1m0s
[20:38:13.379] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:16:0)
[20:38:13.379] Timer tick, asking Buildbucket for the build status
[20:38:13.773] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:13.773] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:17:0) after 1m0s
[20:39:13.795] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:17:0)
[20:39:13.795] Timer tick, asking Buildbucket for the build status
[20:39:13.978] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:13.978] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:18:0) after 1m0s
[20:40:14.089] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:18:0)
[20:40:14.089] Timer tick, asking Buildbucket for the build status
[20:40:15.737] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:15.737] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:19:0) after 1m0s
[20:41:15.782] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:19:0)
[20:41:15.782] Timer tick, asking Buildbucket for the build status
[20:41:15.987] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:15.987] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:20:0) after 1m0s
[20:42:16.178] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:20:0)
[20:42:16.178] Timer tick, asking Buildbucket for the build status
[20:42:16.683] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:16.683] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:21:0) after 1m0s
[20:43:16.866] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:21:0)
[20:43:16.866] Timer tick, asking Buildbucket for the build status
[20:43:17.896] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:17.896] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:22:0) after 1m0s
[20:44:17.930] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:22:0)
[20:44:17.930] Timer tick, asking Buildbucket for the build status
[20:44:18.098] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:18.098] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:23:0) after 1m0s
[20:45:18.123] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:23:0)
[20:45:18.123] Timer tick, asking Buildbucket for the build status
[20:45:18.537] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:18.537] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:24:0) after 1m0s
[20:46:18.568] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:24:0)
[20:46:18.568] Timer tick, asking Buildbucket for the build status
[20:46:18.700] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:18.701] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:25:0) after 1m0s
[20:47:18.915] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:25:0)
[20:47:18.915] Timer tick, asking Buildbucket for the build status
[20:47:19.488] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:19.488] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:26:0) after 1m0s
[20:48:19.507] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:26:0)
[20:48:19.507] Timer tick, asking Buildbucket for the build status
[20:48:19.669] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:19.669] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:27:0) after 1m0s
[20:49:19.783] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:27:0)
[20:49:19.783] Timer tick, asking Buildbucket for the build status
[20:49:20.288] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:20.288] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:28:0) after 1m0s
[20:50:20.432] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:28:0)
[20:50:20.432] Timer tick, asking Buildbucket for the build status
[20:50:20.593] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:20.593] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:29:0) after 1m0s
[20:51:20.703] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:29:0)
[20:51:20.703] Timer tick, asking Buildbucket for the build status
[20:51:20.870] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:20.870] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:30:0) after 1m0s
[20:52:20.987] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:30:0)
[20:52:20.987] Timer tick, asking Buildbucket for the build status
[20:52:21.145] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:21.145] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:31:0) after 1m0s
[20:53:21.900] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:31:0)
[20:53:21.900] Timer tick, asking Buildbucket for the build status
[20:53:22.304] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:22.304] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:32:0) after 1m0s
[20:54:22.597] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:32:0)
[20:54:22.597] Timer tick, asking Buildbucket for the build status
[20:54:22.917] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:22.917] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:33:0) after 1m0s
[20:55:23.002] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:33:0)
[20:55:23.002] Timer tick, asking Buildbucket for the build status
[20:55:23.218] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:23.218] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:34:0) after 1m0s
[20:56:23.440] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:34:0)
[20:56:23.440] Timer tick, asking Buildbucket for the build status
[20:56:23.580] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:23.580] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:35:0) after 1m0s
[20:57:23.747] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:35:0)
[20:57:23.747] Timer tick, asking Buildbucket for the build status
[20:57:24.675] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:24.675] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:36:0) after 1m0s
[20:58:24.720] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:36:0)
[20:58:24.720] Timer tick, asking Buildbucket for the build status
[20:58:24.947] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:24.947] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:37:0) after 1m0s
[20:59:25.373] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:37:0)
[20:59:25.373] Timer tick, asking Buildbucket for the build status
[20:59:25.529] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:25.529] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:38:0) after 1m0s
[21:00:25.887] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:38:0)
[21:00:25.887] Timer tick, asking Buildbucket for the build status
[21:00:26.016] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:26.016] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:39:0) after 1m0s
[21:01:26.222] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:39:0)
[21:01:26.222] Timer tick, asking Buildbucket for the build status
[21:01:26.392] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:26.392] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:40:0) after 1m0s
[21:02:26.780] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:40:0)
[21:02:26.780] Timer tick, asking Buildbucket for the build status
[21:02:27.187] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:27.187] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:41:0) after 1m0s
[21:03:27.550] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:41:0)
[21:03:27.550] Timer tick, asking Buildbucket for the build status
[21:03:28.010] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:28.010] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:42:0) after 1m0s
[21:04:28.333] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:42:0)
[21:04:28.333] Timer tick, asking Buildbucket for the build status
[21:04:28.651] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:28.651] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:43:0) after 1m0s
[21:05:28.737] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:43:0)
[21:05:28.737] Timer tick, asking Buildbucket for the build status
[21:05:29.133] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:29.133] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:44:0) after 1m0s
[21:06:29.126] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:44:0)
[21:06:29.126] Timer tick, asking Buildbucket for the build status
[21:06:29.323] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:29.323] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:45:0) after 1m0s
[21:07:29.345] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:45:0)
[21:07:29.345] Timer tick, asking Buildbucket for the build status
[21:07:30.079] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:30.079] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:46:0) after 1m0s
[21:08:30.105] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:46:0)
[21:08:30.105] Timer tick, asking Buildbucket for the build status
[21:08:30.511] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:30.511] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:47:0) after 1m0s
[21:09:30.658] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:47:0)
[21:09:30.658] Timer tick, asking Buildbucket for the build status
[21:09:30.815] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:30.815] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:48:0) after 1m0s
[21:10:30.990] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:48:0)
[21:10:30.990] Timer tick, asking Buildbucket for the build status
[21:10:31.435] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:31.435] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:49:0) after 1m0s
[21:11:31.502] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:49:0)
[21:11:31.502] Timer tick, asking Buildbucket for the build status
[21:11:32.203] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:32.203] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:50:0) after 1m0s
[21:12:32.241] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:50:0)
[21:12:32.241] Timer tick, asking Buildbucket for the build status
[21:12:32.402] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:32.402] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:51:0) after 1m0s
[21:13:32.473] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:51:0)
[21:13:32.473] Timer tick, asking Buildbucket for the build status
[21:13:32.690] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:32.690] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:52:0) after 1m0s
[21:14:32.713] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:52:0)
[21:14:32.713] Timer tick, asking Buildbucket for the build status
[21:14:34.303] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:34.303] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:53:0) after 1m0s
[21:15:34.416] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:53:0)
[21:15:34.416] Timer tick, asking Buildbucket for the build status
[21:15:34.774] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:34.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:54:0) after 1m0s
[21:16:34.813] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:54:0)
[21:16:34.813] Timer tick, asking Buildbucket for the build status
[21:16:34.953] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:34.953] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:55:0) after 1m0s
[21:17:34.962] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:55:0)
[21:17:34.962] Timer tick, asking Buildbucket for the build status
[21:17:35.232] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:35.232] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:56:0) after 1m0s
[21:18:35.259] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:56:0)
[21:18:35.259] Timer tick, asking Buildbucket for the build status
[21:18:35.423] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:35.423] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:57:0) after 1m0s
[21:19:35.563] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:57:0)
[21:19:35.563] Timer tick, asking Buildbucket for the build status
[21:19:35.701] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:35.701] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:58:0) after 1m0s
[21:20:35.779] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:58:0)
[21:20:35.779] Timer tick, asking Buildbucket for the build status
[21:20:35.979] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:35.979] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:59:0) after 1m0s
[21:21:36.142] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:59:0)
[21:21:36.142] Timer tick, asking Buildbucket for the build status
[21:21:36.641] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:36.641] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:60:0) after 1m0s
[21:22:36.676] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:60:0)
[21:22:36.676] Timer tick, asking Buildbucket for the build status
[21:22:37.221] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:37.221] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:61:0) after 1m0s
[21:23:37.263] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:61:0)
[21:23:37.263] Timer tick, asking Buildbucket for the build status
[21:23:37.466] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:37.466] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:62:0) after 1m0s
[21:24:37.489] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:62:0)
[21:24:37.489] Timer tick, asking Buildbucket for the build status
[21:24:37.887] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:37.887] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:63:0) after 1m0s
[21:25:37.954] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:63:0)
[21:25:37.954] Timer tick, asking Buildbucket for the build status
[21:25:38.140] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:38.140] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:64:0) after 1m0s
[21:26:38.179] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:64:0)
[21:26:38.179] Timer tick, asking Buildbucket for the build status
[21:26:39.561] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:39.561] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:65:0) after 1m0s
[21:27:39.757] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:65:0)
[21:27:39.757] Timer tick, asking Buildbucket for the build status
[21:27:40.871] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:40.871] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:66:0) after 1m0s
[21:28:41.039] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:66:0)
[21:28:41.039] Timer tick, asking Buildbucket for the build status
[21:28:41.276] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:41.276] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:67:0) after 1m0s
[21:29:41.296] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:67:0)
[21:29:41.296] Timer tick, asking Buildbucket for the build status
[21:29:42.306] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:42.306] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:68:0) after 1m0s
[21:30:42.394] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:68:0)
[21:30:42.394] Timer tick, asking Buildbucket for the build status
[21:30:42.638] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:42.638] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:69:0) after 1m0s
[21:31:42.684] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:69:0)
[21:31:42.684] Timer tick, asking Buildbucket for the build status
[21:31:42.913] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:42.913] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:70:0) after 1m0s
[21:32:43.109] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:70:0)
[21:32:43.109] Timer tick, asking Buildbucket for the build status
[21:32:43.390] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:43.390] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:71:0) after 1m0s
[21:33:43.412] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:71:0)
[21:33:43.412] Timer tick, asking Buildbucket for the build status
[21:33:43.760] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:43.760] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:72:0) after 1m0s
[21:34:43.872] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:72:0)
[21:34:43.872] Timer tick, asking Buildbucket for the build status
[21:34:44.243] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:44.243] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:73:0) after 1m0s
[21:35:44.386] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:73:0)
[21:35:44.386] Timer tick, asking Buildbucket for the build status
[21:35:44.645] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:44.645] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:74:0) after 1m0s
[21:36:44.673] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:74:0)
[21:36:44.673] Timer tick, asking Buildbucket for the build status
[21:36:44.840] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:44.840] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:75:0) after 1m0s
[21:37:44.863] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:75:0)
[21:37:44.863] Timer tick, asking Buildbucket for the build status
[21:37:45.185] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:45.185] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:76:0) after 1m0s
[21:38:45.267] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:76:0)
[21:38:45.267] Timer tick, asking Buildbucket for the build status
[21:38:46.005] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:46.005] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:77:0) after 1m0s
[21:39:46.046] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:77:0)
[21:39:46.046] Timer tick, asking Buildbucket for the build status
[21:39:46.202] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:46.202] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:78:0) after 1m0s
[21:40:46.323] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:78:0)
[21:40:46.323] Timer tick, asking Buildbucket for the build status
[21:40:48.020] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:48.020] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:79:0) after 1m0s
[21:41:48.048] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:79:0)
[21:41:48.048] Timer tick, asking Buildbucket for the build status
[21:41:48.970] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:48.970] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:80:0) after 1m0s
[21:42:48.968] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:80:0)
[21:42:48.968] Timer tick, asking Buildbucket for the build status
[21:42:49.320] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:49.320] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:81:0) after 1m0s
[21:43:49.324] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:81:0)
[21:43:49.324] Timer tick, asking Buildbucket for the build status
[21:43:49.603] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:49.603] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:82:0) after 1m0s
[21:44:49.655] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:82:0)
[21:44:49.655] Timer tick, asking Buildbucket for the build status
[21:44:51.844] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:51.844] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:83:0) after 1m0s
[21:45:51.869] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:83:0)
[21:45:51.869] Timer tick, asking Buildbucket for the build status
[21:45:52.121] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:52.122] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:84:0) after 1m0s
[21:46:52.189] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:84:0)
[21:46:52.189] Timer tick, asking Buildbucket for the build status
[21:46:54.124] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:54.124] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:85:0) after 1m0s
[21:47:54.171] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:85:0)
[21:47:54.171] Timer tick, asking Buildbucket for the build status
[21:47:54.330] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:54.330] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:86:0) after 1m0s
[21:48:54.380] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:86:0)
[21:48:54.380] Timer tick, asking Buildbucket for the build status
[21:48:54.936] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:54.936] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:87:0) after 1m0s
[21:49:54.967] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:87:0)
[21:49:54.967] Timer tick, asking Buildbucket for the build status
[21:49:57.095] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:57.095] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:88:0) after 1m0s
[21:50:57.168] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:88:0)
[21:50:57.168] Timer tick, asking Buildbucket for the build status
[21:50:59.060] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:59.060] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:89:0) after 1m0s
[21:51:59.409] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:89:0)
[21:51:59.409] Timer tick, asking Buildbucket for the build status
[21:52:00.276] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:00.276] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:90:0) after 1m0s
[21:53:01.279] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:90:0)
[21:53:01.279] Timer tick, asking Buildbucket for the build status
[21:53:01.454] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:01.454] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:91:0) after 1m0s
[21:54:01.537] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:91:0)
[21:54:01.537] Timer tick, asking Buildbucket for the build status
[21:54:01.758] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:01.758] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:92:0) after 1m0s
[21:55:01.934] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:92:0)
[21:55:01.934] Timer tick, asking Buildbucket for the build status
[21:55:02.082] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:02.082] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:93:0) after 1m0s
[21:56:02.076] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:93:0)
[21:56:02.076] Timer tick, asking Buildbucket for the build status
[21:56:02.223] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:02.223] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:94:0) after 1m0s
[21:57:02.286] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:94:0)
[21:57:02.286] Timer tick, asking Buildbucket for the build status
[21:57:02.912] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:02.912] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:95:0) after 1m0s
[21:58:02.984] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:95:0)
[21:58:02.984] Timer tick, asking Buildbucket for the build status
[21:58:03.631] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:03.631] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:96:0) after 1m0s
[21:59:03.712] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:96:0)
[21:59:03.712] Timer tick, asking Buildbucket for the build status
[21:59:03.883] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:03.883] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:97:0) after 1m0s
[22:00:04.144] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:97:0)
[22:00:04.144] Timer tick, asking Buildbucket for the build status
[22:00:04.705] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:04.705] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:98:0) after 1m0s
[22:01:04.968] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:98:0)
[22:01:04.968] Timer tick, asking Buildbucket for the build status
[22:01:06.640] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:06.640] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:99:0) after 1m0s
[22:02:06.865] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:99:0)
[22:02:06.865] Timer tick, asking Buildbucket for the build status
[22:02:07.510] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:07.510] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:100:0) after 1m0s
[22:03:07.549] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:100:0)
[22:03:07.550] Timer tick, asking Buildbucket for the build status
[22:03:07.761] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:07.761] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:101:0) after 1m0s
[22:04:07.850] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:101:0)
[22:04:07.850] Timer tick, asking Buildbucket for the build status
[22:04:08.200] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:08.200] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:102:0) after 1m0s
[22:05:08.319] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:102:0)
[22:05:08.319] Timer tick, asking Buildbucket for the build status
[22:05:08.894] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:08.894] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:103:0) after 1m0s
[22:06:09.008] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:103:0)
[22:06:09.008] Timer tick, asking Buildbucket for the build status
[22:06:09.283] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:09.283] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:104:0) after 1m0s
[22:13:38.857] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:104:0)
[22:13:38.858] Timer tick, asking Buildbucket for the build status
[22:13:39.058] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:39.058] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:105:0) after 1m0s
[22:14:39.103] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:105:0)
[22:14:39.103] Timer tick, asking Buildbucket for the build status
[22:14:39.352] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:39.352] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:106:0) after 1m0s
[22:15:39.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:106:0)
[22:15:39.377] Timer tick, asking Buildbucket for the build status
[22:15:39.525] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:39.525] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:107:0) after 1m0s
[22:16:39.587] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:107:0)
[22:16:39.587] Timer tick, asking Buildbucket for the build status
[22:16:40.229] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:40.229] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:108:0) after 1m0s
[22:17:40.255] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:108:0)
[22:17:40.255] Timer tick, asking Buildbucket for the build status
[22:17:42.968] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:42.968] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:109:0) after 1m0s
[22:18:42.969] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:109:0)
[22:18:42.969] Timer tick, asking Buildbucket for the build status
[22:18:43.214] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:43.214] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:110:0) after 1m0s
[22:19:43.254] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:110:0)
[22:19:43.254] Timer tick, asking Buildbucket for the build status
[22:19:43.618] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:43.618] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:111:0) after 1m0s
[22:20:43.641] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:111:0)
[22:20:43.641] Timer tick, asking Buildbucket for the build status
[22:20:43.797] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:43.797] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:112:0) after 1m0s
[22:21:44.480] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:112:0)
[22:21:44.480] Timer tick, asking Buildbucket for the build status
[22:21:45.244] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:45.244] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:113:0) after 1m0s
[22:22:45.872] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:113:0)
[22:22:45.872] Timer tick, asking Buildbucket for the build status
[22:22:46.634] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:46.634] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:114:0) after 1m0s
[22:23:46.967] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:114:0)
[22:23:46.967] Timer tick, asking Buildbucket for the build status
[22:23:47.477] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:47.477] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:115:0) after 1m0s
[22:24:47.505] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:115:0)
[22:24:47.505] Timer tick, asking Buildbucket for the build status
[22:24:47.864] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:47.864] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:116:0) after 1m0s
[22:25:48.037] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:116:0)
[22:25:48.037] Timer tick, asking Buildbucket for the build status
[22:25:48.865] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:48.865] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:117:0) after 1m0s
[22:26:49.013] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:117:0)
[22:26:49.013] Timer tick, asking Buildbucket for the build status
[22:26:49.443] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:49.444] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:118:0) after 1m0s
[22:27:49.557] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:118:0)
[22:27:49.557] Timer tick, asking Buildbucket for the build status
[22:27:50.192] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:50.192] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:119:0) after 1m0s
[22:28:50.259] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:119:0)
[22:28:50.259] Timer tick, asking Buildbucket for the build status
[22:28:50.642] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:50.642] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:120:0) after 1m0s
[22:29:50.731] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:120:0)
[22:29:50.731] Timer tick, asking Buildbucket for the build status
[22:29:50.992] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:50.992] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:121:0) after 1m0s
[22:30:51.264] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:121:0)
[22:30:51.264] Timer tick, asking Buildbucket for the build status
[22:30:51.779] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:51.779] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:122:0) after 1m0s
[22:31:52.049] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:122:0)
[22:31:52.049] Timer tick, asking Buildbucket for the build status
[22:31:52.447] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:52.447] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:123:0) after 1m0s
[22:32:52.610] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:123:0)
[22:32:52.610] Timer tick, asking Buildbucket for the build status
[22:32:52.770] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:52.770] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:124:0) after 1m0s
[22:33:53.242] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:124:0)
[22:33:53.242] Timer tick, asking Buildbucket for the build status
[22:33:53.703] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:53.703] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:125:0) after 1m0s
[22:34:53.982] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:125:0)
[22:34:53.982] Timer tick, asking Buildbucket for the build status
[22:34:54.129] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:54.130] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:126:0) after 1m0s
[22:35:54.260] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:126:0)
[22:35:54.260] Timer tick, asking Buildbucket for the build status
[22:35:54.454] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:54.454] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:127:0) after 1m0s
[22:36:54.639] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:127:0)
[22:36:54.639] Timer tick, asking Buildbucket for the build status
[22:36:56.893] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:56.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:128:0) after 1m0s
[22:37:57.167] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:128:0)
[22:37:57.167] Timer tick, asking Buildbucket for the build status
[22:37:57.370] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:57.370] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:129:0) after 1m0s
[22:38:57.557] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:129:0)
[22:38:57.557] Timer tick, asking Buildbucket for the build status
[22:38:57.775] Build 8895334004721800608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:57.775] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9060764066694849584:130:0) after 1m0s
[22:39:38.905] Received PubSub notification, asking Buildbucket for the build status
[22:39:39.007] Build 8895334004721800608: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:39:39.007] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1575153578180880",
"created_by": "project:chromium",
"created_ts": "1575145446049586",
"id": "8895334004721800608",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bb1bd77944bcda71e22bf59e7b58b79d0aaeee63\"}}",
"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-n2xb\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 15343, \"got_angle_revision\": \"bc82fb14aab4b1238cdb7291bdd34816863f26b8\", \"got_dawn_revision\": \"969df2b3a21c126b2e0015ce2392c135f32a6a08\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"bb1bd77944bcda71e22bf59e7b58b79d0aaeee63\", \"got_revision_cp\": \"refs/heads/master@{#720328}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"6e2ff45fa936ff77087852d22b1c910d2b3ab487\", \"got_v8_revision\": \"adfb6ba32d881a5bba73f9c7121ca9f8dba881b4\", \"got_v8_revision_cp\": \"refs/heads/8.0.418@{#1}\", \"got_webrtc_revision\": \"89313451d8e2338845716b0ab8760ed0552580f9\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29962}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bb1bd77944bcda71e22bf59e7b58b79d0aaeee63\"}, \"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-n2xb\"], \"image\": [\"chrome-win10-1703-19112100-3085293b610\"], \"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\": [\"4662-e230705\"], \"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": "1575145452897296",
"status": "COMPLETED",
"status_changed_ts": "1575153578491930",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/15343",
"builder:Windows deterministic",
"buildset:commit/git/bb1bd77944bcda71e22bf59e7b58b79d0aaeee63",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bb1bd77944bcda71e22bf59e7b58b79d0aaeee63",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9060764066694849584",
"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/8895334004721800608/+/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:48d6cdaec0508610",
"user_agent:luci-scheduler"
],
"updated_ts": "1575153578496573",
"url": "https://ci.chromium.org/b/8895334004721800608",
"utcnow_ts": "1575153578998729"
}
}
[22:39:39.007] Invocation finished in 2h15m34.223068882s with status SUCCEEDED