[15:10:04.327] New invocation is queued and will start shortly
[15:10:05.593] Starting the invocation (attempt 1)
[15:10:05.637] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:10:05.637] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:10:05.637] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9087962912286890064",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"793ce40e44defcac99ab698a28d901624adec00d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9087962912286890064",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/793ce40e44defcac99ab698a28d901624adec00d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/793ce40e44defcac99ab698a28d901624adec00d",
"gitiles_ref:refs/heads/master"
]
}
[15:10:07.003] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1549206605733428",
"id": "8922532850145563712",
"lease_expiration_ts": "1549325406656457",
"lease_key": "615369364",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"793ce40e44defcac99ab698a28d901624adec00d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1549206606777822",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11824",
"builder:Windows deterministic",
"buildset:commit/git/793ce40e44defcac99ab698a28d901624adec00d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/793ce40e44defcac99ab698a28d901624adec00d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9087962912286890064",
"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/8922532850145563712/+/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:42ccba5a3fc81a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1549206606777919",
"url": "https://ci.chromium.org/b/8922532850145563712",
"utcnow_ts": "1549206606988600"
}
}
[15:10:07.003] Task URL: https://ci.chromium.org/b/8922532850145563712
[15:10:07.003] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:2:0) after 1m0s
[15:10:10.108] Received PubSub notification, asking Buildbucket for the build status
[15:10:10.474] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:06.999] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:2:0)
[15:11:06.999] Timer tick, asking Buildbucket for the build status
[15:11:07.074] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:07.074] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:4:0) after 1m0s
[15:12:07.098] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:4:0)
[15:12:07.098] Timer tick, asking Buildbucket for the build status
[15:12:07.186] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:07.186] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:5:0) after 1m0s
[15:13:07.204] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:5:0)
[15:13:07.204] Timer tick, asking Buildbucket for the build status
[15:13:07.268] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:07.268] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:6:0) after 1m0s
[15:14:07.300] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:6:0)
[15:14:07.300] Timer tick, asking Buildbucket for the build status
[15:14:07.364] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:07.364] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:7:0) after 1m0s
[15:15:07.396] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:7:0)
[15:15:07.396] Timer tick, asking Buildbucket for the build status
[15:15:07.449] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:07.449] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:8:0) after 1m0s
[15:16:07.535] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:8:0)
[15:16:07.536] Timer tick, asking Buildbucket for the build status
[15:16:07.853] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:07.853] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:9:0) after 1m0s
[15:17:07.895] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:9:0)
[15:17:07.895] Timer tick, asking Buildbucket for the build status
[15:17:07.956] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:07.956] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:10:0) after 1m0s
[15:18:08.060] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:10:0)
[15:18:08.060] Timer tick, asking Buildbucket for the build status
[15:18:08.104] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:08.104] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:11:0) after 1m0s
[15:19:08.124] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:11:0)
[15:19:08.124] Timer tick, asking Buildbucket for the build status
[15:19:08.276] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:08.276] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:12:0) after 1m0s
[15:20:08.298] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:12:0)
[15:20:08.298] Timer tick, asking Buildbucket for the build status
[15:20:08.341] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:08.341] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:13:0) after 1m0s
[15:21:08.359] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:13:0)
[15:21:08.359] Timer tick, asking Buildbucket for the build status
[15:21:08.809] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:08.809] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:14:0) after 1m0s
[15:22:08.827] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:14:0)
[15:22:08.827] Timer tick, asking Buildbucket for the build status
[15:22:09.007] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:09.007] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:15:0) after 1m0s
[15:23:09.026] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:15:0)
[15:23:09.026] Timer tick, asking Buildbucket for the build status
[15:23:09.175] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:09.175] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:16:0) after 1m0s
[15:24:09.246] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:16:0)
[15:24:09.246] Timer tick, asking Buildbucket for the build status
[15:24:09.317] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:09.317] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:17:0) after 1m0s
[15:25:09.337] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:17:0)
[15:25:09.337] Timer tick, asking Buildbucket for the build status
[15:25:09.520] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:09.520] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:18:0) after 1m0s
[15:26:09.560] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:18:0)
[15:26:09.560] Timer tick, asking Buildbucket for the build status
[15:26:09.610] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:09.610] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:19:0) after 1m0s
[15:27:09.633] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:19:0)
[15:27:09.633] Timer tick, asking Buildbucket for the build status
[15:27:10.008] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:10.008] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:20:0) after 1m0s
[15:28:10.062] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:20:0)
[15:28:10.062] Timer tick, asking Buildbucket for the build status
[15:28:10.216] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:10.217] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:21:0) after 1m0s
[15:29:10.253] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:21:0)
[15:29:10.253] Timer tick, asking Buildbucket for the build status
[15:29:10.338] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:10.338] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:22:0) after 1m0s
[15:30:10.355] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:22:0)
[15:30:10.355] Timer tick, asking Buildbucket for the build status
[15:30:10.512] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:10.513] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:23:0) after 1m0s
[15:31:10.538] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:23:0)
[15:31:10.538] Timer tick, asking Buildbucket for the build status
[15:31:10.639] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:10.639] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:24:0) after 1m0s
[15:32:10.657] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:24:0)
[15:32:10.657] Timer tick, asking Buildbucket for the build status
[15:32:10.791] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:10.791] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:25:0) after 1m0s
[15:33:10.820] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:25:0)
[15:33:10.820] Timer tick, asking Buildbucket for the build status
[15:33:10.886] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:10.886] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:26:0) after 1m0s
[15:34:10.911] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:26:0)
[15:34:10.911] Timer tick, asking Buildbucket for the build status
[15:34:10.968] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:10.968] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:27:0) after 1m0s
[15:35:10.984] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:27:0)
[15:35:10.984] Timer tick, asking Buildbucket for the build status
[15:35:11.155] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:11.155] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:28:0) after 1m0s
[15:36:11.199] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:28:0)
[15:36:11.199] Timer tick, asking Buildbucket for the build status
[15:36:11.481] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:11.481] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:29:0) after 1m0s
[15:37:11.498] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:29:0)
[15:37:11.498] Timer tick, asking Buildbucket for the build status
[15:37:11.812] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:11.812] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:30:0) after 1m0s
[15:38:11.834] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:30:0)
[15:38:11.834] Timer tick, asking Buildbucket for the build status
[15:38:12.200] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:12.200] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:31:0) after 1m0s
[15:39:12.249] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:31:0)
[15:39:12.249] Timer tick, asking Buildbucket for the build status
[15:39:12.291] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:12.291] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:32:0) after 1m0s
[15:40:12.311] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:32:0)
[15:40:12.311] Timer tick, asking Buildbucket for the build status
[15:40:12.435] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:12.435] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:33:0) after 1m0s
[15:41:12.451] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:33:0)
[15:41:12.451] Timer tick, asking Buildbucket for the build status
[15:41:12.514] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:12.514] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:34:0) after 1m0s
[15:42:12.590] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:34:0)
[15:42:12.590] Timer tick, asking Buildbucket for the build status
[15:42:12.796] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:12.796] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:35:0) after 1m0s
[15:43:12.829] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:35:0)
[15:43:12.829] Timer tick, asking Buildbucket for the build status
[15:43:12.916] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:12.916] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:36:0) after 1m0s
[15:44:12.938] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:36:0)
[15:44:12.938] Timer tick, asking Buildbucket for the build status
[15:44:12.992] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:12.992] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:37:0) after 1m0s
[15:45:13.009] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:37:0)
[15:45:13.009] Timer tick, asking Buildbucket for the build status
[15:45:13.118] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:13.118] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:38:0) after 1m0s
[15:46:13.156] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:38:0)
[15:46:13.156] Timer tick, asking Buildbucket for the build status
[15:46:13.245] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:13.245] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:39:0) after 1m0s
[15:47:13.267] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:39:0)
[15:47:13.267] Timer tick, asking Buildbucket for the build status
[15:47:13.318] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:13.318] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:40:0) after 1m0s
[15:48:13.336] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:40:0)
[15:48:13.336] Timer tick, asking Buildbucket for the build status
[15:48:13.402] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:13.402] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:41:0) after 1m0s
[15:49:13.421] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:41:0)
[15:49:13.421] Timer tick, asking Buildbucket for the build status
[15:49:13.591] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:13.592] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:42:0) after 1m0s
[15:50:13.609] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:42:0)
[15:50:13.610] Timer tick, asking Buildbucket for the build status
[15:50:13.687] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:13.687] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:43:0) after 1m0s
[15:51:13.766] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:43:0)
[15:51:13.766] Timer tick, asking Buildbucket for the build status
[15:51:13.860] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:13.860] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:44:0) after 1m0s
[15:52:13.883] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:44:0)
[15:52:13.883] Timer tick, asking Buildbucket for the build status
[15:52:13.964] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:13.964] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:45:0) after 1m0s
[15:53:13.982] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:45:0)
[15:53:13.982] Timer tick, asking Buildbucket for the build status
[15:53:14.196] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:14.196] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:46:0) after 1m0s
[15:54:14.215] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:46:0)
[15:54:14.215] Timer tick, asking Buildbucket for the build status
[15:54:14.542] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:14.542] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:47:0) after 1m0s
[15:55:14.564] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:47:0)
[15:55:14.564] Timer tick, asking Buildbucket for the build status
[15:55:14.879] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:14.879] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:48:0) after 1m0s
[15:56:14.897] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:48:0)
[15:56:14.897] Timer tick, asking Buildbucket for the build status
[15:56:14.997] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:14.997] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:49:0) after 1m0s
[15:57:15.015] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:49:0)
[15:57:15.015] Timer tick, asking Buildbucket for the build status
[15:57:15.152] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:15.152] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:50:0) after 1m0s
[15:58:15.173] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:50:0)
[15:58:15.173] Timer tick, asking Buildbucket for the build status
[15:58:15.285] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:15.285] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:51:0) after 1m0s
[15:59:15.302] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:51:0)
[15:59:15.302] Timer tick, asking Buildbucket for the build status
[15:59:15.396] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:15.396] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:52:0) after 1m0s
[16:00:15.412] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:52:0)
[16:00:15.412] Timer tick, asking Buildbucket for the build status
[16:00:15.471] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:15.471] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:53:0) after 1m0s
[16:01:15.495] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:53:0)
[16:01:15.495] Timer tick, asking Buildbucket for the build status
[16:01:15.649] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:15.649] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:54:0) after 1m0s
[16:02:15.666] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:54:0)
[16:02:15.666] Timer tick, asking Buildbucket for the build status
[16:02:15.758] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:15.758] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:55:0) after 1m0s
[16:03:15.804] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:55:0)
[16:03:15.804] Timer tick, asking Buildbucket for the build status
[16:03:15.905] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:15.905] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:56:0) after 1m0s
[16:04:15.929] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:56:0)
[16:04:15.929] Timer tick, asking Buildbucket for the build status
[16:04:16.381] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:16.381] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:57:0) after 1m0s
[16:05:16.412] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:57:0)
[16:05:16.412] Timer tick, asking Buildbucket for the build status
[16:05:16.543] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:16.543] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:58:0) after 1m0s
[16:06:16.637] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:58:0)
[16:06:16.637] Timer tick, asking Buildbucket for the build status
[16:06:16.737] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:16.738] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:59:0) after 1m0s
[16:07:16.757] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:59:0)
[16:07:16.757] Timer tick, asking Buildbucket for the build status
[16:07:16.965] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:16.965] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:60:0) after 1m0s
[16:08:16.984] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:60:0)
[16:08:16.984] Timer tick, asking Buildbucket for the build status
[16:08:17.107] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:17.107] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:61:0) after 1m0s
[16:09:17.187] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:61:0)
[16:09:17.187] Timer tick, asking Buildbucket for the build status
[16:09:17.575] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:17.575] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:62:0) after 1m0s
[16:10:17.597] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:62:0)
[16:10:17.597] Timer tick, asking Buildbucket for the build status
[16:10:18.144] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:18.144] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:63:0) after 1m0s
[16:11:18.173] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:63:0)
[16:11:18.173] Timer tick, asking Buildbucket for the build status
[16:11:18.286] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:18.286] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:64:0) after 1m0s
[16:12:18.310] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:64:0)
[16:12:18.310] Timer tick, asking Buildbucket for the build status
[16:12:18.383] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:18.383] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:65:0) after 1m0s
[16:13:18.404] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:65:0)
[16:13:18.404] Timer tick, asking Buildbucket for the build status
[16:13:18.468] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:18.468] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:66:0) after 1m0s
[16:14:18.485] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:66:0)
[16:14:18.485] Timer tick, asking Buildbucket for the build status
[16:14:18.697] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:18.697] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:67:0) after 1m0s
[16:15:18.727] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:67:0)
[16:15:18.727] Timer tick, asking Buildbucket for the build status
[16:15:18.863] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:18.863] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:68:0) after 1m0s
[16:16:18.883] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:68:0)
[16:16:18.883] Timer tick, asking Buildbucket for the build status
[16:16:18.950] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:18.950] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:69:0) after 1m0s
[16:17:18.969] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:69:0)
[16:17:18.969] Timer tick, asking Buildbucket for the build status
[16:17:19.154] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:19.154] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:70:0) after 1m0s
[16:18:19.182] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:70:0)
[16:18:19.182] Timer tick, asking Buildbucket for the build status
[16:18:19.307] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:19.307] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:71:0) after 1m0s
[16:19:19.331] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:71:0)
[16:19:19.331] Timer tick, asking Buildbucket for the build status
[16:19:19.392] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:19.392] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:72:0) after 1m0s
[16:20:19.415] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:72:0)
[16:20:19.415] Timer tick, asking Buildbucket for the build status
[16:20:19.502] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:19.502] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:73:0) after 1m0s
[16:21:19.545] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:73:0)
[16:21:19.545] Timer tick, asking Buildbucket for the build status
[16:21:19.753] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:19.753] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:74:0) after 1m0s
[16:22:19.777] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:74:0)
[16:22:19.777] Timer tick, asking Buildbucket for the build status
[16:22:19.837] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:19.837] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:75:0) after 1m0s
[16:23:19.871] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:75:0)
[16:23:19.871] Timer tick, asking Buildbucket for the build status
[16:23:19.949] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:19.949] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:76:0) after 1m0s
[16:24:19.971] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:76:0)
[16:24:19.971] Timer tick, asking Buildbucket for the build status
[16:24:20.033] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:20.033] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:77:0) after 1m0s
[16:25:20.055] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:77:0)
[16:25:20.055] Timer tick, asking Buildbucket for the build status
[16:25:20.142] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:20.142] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:78:0) after 1m0s
[16:26:20.161] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:78:0)
[16:26:20.161] Timer tick, asking Buildbucket for the build status
[16:26:20.398] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:20.398] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:79:0) after 1m0s
[16:27:20.419] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:79:0)
[16:27:20.419] Timer tick, asking Buildbucket for the build status
[16:27:20.488] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:20.488] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:80:0) after 1m0s
[16:28:20.516] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:80:0)
[16:28:20.516] Timer tick, asking Buildbucket for the build status
[16:28:20.618] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:20.618] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:81:0) after 1m0s
[16:29:20.649] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:81:0)
[16:29:20.649] Timer tick, asking Buildbucket for the build status
[16:29:20.707] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:20.707] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:82:0) after 1m0s
[16:30:20.727] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:82:0)
[16:30:20.727] Timer tick, asking Buildbucket for the build status
[16:30:20.773] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:20.773] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:83:0) after 1m0s
[16:31:20.796] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:83:0)
[16:31:20.796] Timer tick, asking Buildbucket for the build status
[16:31:20.919] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:20.919] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:84:0) after 1m0s
[16:32:20.938] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:84:0)
[16:32:20.938] Timer tick, asking Buildbucket for the build status
[16:32:20.985] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:20.985] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:85:0) after 1m0s
[16:33:21.013] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:85:0)
[16:33:21.013] Timer tick, asking Buildbucket for the build status
[16:33:21.101] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:21.101] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:86:0) after 1m0s
[16:34:21.244] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:86:0)
[16:34:21.244] Timer tick, asking Buildbucket for the build status
[16:34:21.353] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:21.353] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:87:0) after 1m0s
[16:35:21.453] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:87:0)
[16:35:21.453] Timer tick, asking Buildbucket for the build status
[16:35:21.526] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:21.526] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:88:0) after 1m0s
[16:36:21.613] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:88:0)
[16:36:21.613] Timer tick, asking Buildbucket for the build status
[16:36:21.756] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:21.756] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:89:0) after 1m0s
[16:37:21.776] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:89:0)
[16:37:21.776] Timer tick, asking Buildbucket for the build status
[16:37:21.963] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:21.963] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:90:0) after 1m0s
[16:38:22.065] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:90:0)
[16:38:22.065] Timer tick, asking Buildbucket for the build status
[16:38:22.149] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:22.149] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:91:0) after 1m0s
[16:39:22.247] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:91:0)
[16:39:22.247] Timer tick, asking Buildbucket for the build status
[16:39:22.311] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:22.311] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:92:0) after 1m0s
[16:40:22.327] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:92:0)
[16:40:22.327] Timer tick, asking Buildbucket for the build status
[16:40:22.396] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:22.396] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:93:0) after 1m0s
[16:41:22.421] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:93:0)
[16:41:22.421] Timer tick, asking Buildbucket for the build status
[16:41:22.903] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:22.903] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:94:0) after 1m0s
[16:42:22.925] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:94:0)
[16:42:22.925] Timer tick, asking Buildbucket for the build status
[16:42:23.009] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:23.009] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:95:0) after 1m0s
[16:43:23.065] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:95:0)
[16:43:23.065] Timer tick, asking Buildbucket for the build status
[16:43:23.135] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:23.135] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:96:0) after 1m0s
[16:44:23.206] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:96:0)
[16:44:23.206] Timer tick, asking Buildbucket for the build status
[16:44:23.317] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:23.317] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:97:0) after 1m0s
[16:45:23.349] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:97:0)
[16:45:23.349] Timer tick, asking Buildbucket for the build status
[16:45:23.487] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:23.491] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:98:0) after 1m0s
[16:46:23.613] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:98:0)
[16:46:23.613] Timer tick, asking Buildbucket for the build status
[16:46:23.675] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:23.675] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:99:0) after 1m0s
[16:47:23.697] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:99:0)
[16:47:23.697] Timer tick, asking Buildbucket for the build status
[16:47:23.786] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:23.786] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:100:0) after 1m0s
[16:48:23.910] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:100:0)
[16:48:23.910] Timer tick, asking Buildbucket for the build status
[16:48:23.954] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:23.954] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:101:0) after 1m0s
[16:49:23.977] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:101:0)
[16:49:23.977] Timer tick, asking Buildbucket for the build status
[16:49:24.159] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:24.159] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:102:0) after 1m0s
[16:50:24.208] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:102:0)
[16:50:24.208] Timer tick, asking Buildbucket for the build status
[16:50:24.479] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:24.479] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:103:0) after 1m0s
[16:51:24.554] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:103:0)
[16:51:24.554] Timer tick, asking Buildbucket for the build status
[16:51:24.604] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:24.604] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:104:0) after 1m0s
[16:52:24.661] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:104:0)
[16:52:24.661] Timer tick, asking Buildbucket for the build status
[16:52:24.901] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:24.901] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:105:0) after 1m0s
[16:53:24.927] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:105:0)
[16:53:24.927] Timer tick, asking Buildbucket for the build status
[16:53:24.991] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:24.991] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:106:0) after 1m0s
[16:54:25.014] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:106:0)
[16:54:25.014] Timer tick, asking Buildbucket for the build status
[16:54:25.346] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:25.346] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:107:0) after 1m0s
[16:55:25.374] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:107:0)
[16:55:25.374] Timer tick, asking Buildbucket for the build status
[16:55:25.439] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:25.439] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:108:0) after 1m0s
[16:56:25.467] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:108:0)
[16:56:25.467] Timer tick, asking Buildbucket for the build status
[16:56:25.771] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:25.771] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:109:0) after 1m0s
[16:57:25.790] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:109:0)
[16:57:25.790] Timer tick, asking Buildbucket for the build status
[16:57:25.872] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:25.872] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:110:0) after 1m0s
[16:58:25.899] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:110:0)
[16:58:25.899] Timer tick, asking Buildbucket for the build status
[16:58:26.005] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:26.005] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:111:0) after 1m0s
[16:59:26.027] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:111:0)
[16:59:26.027] Timer tick, asking Buildbucket for the build status
[16:59:26.087] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:26.087] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:112:0) after 1m0s
[17:00:26.174] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:112:0)
[17:00:26.174] Timer tick, asking Buildbucket for the build status
[17:00:26.561] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:26.561] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:113:0) after 1m0s
[17:01:26.691] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:113:0)
[17:01:26.691] Timer tick, asking Buildbucket for the build status
[17:01:27.064] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:27.064] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:114:0) after 1m0s
[17:02:27.092] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:114:0)
[17:02:27.092] Timer tick, asking Buildbucket for the build status
[17:02:27.374] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:27.374] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:115:0) after 1m0s
[17:03:27.412] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:115:0)
[17:03:27.412] Timer tick, asking Buildbucket for the build status
[17:03:27.483] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:27.483] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:116:0) after 1m0s
[17:04:27.502] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:116:0)
[17:04:27.502] Timer tick, asking Buildbucket for the build status
[17:04:27.651] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:27.651] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:117:0) after 1m0s
[17:05:27.681] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:117:0)
[17:05:27.681] Timer tick, asking Buildbucket for the build status
[17:05:27.751] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:27.751] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:118:0) after 1m0s
[17:06:27.774] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:118:0)
[17:06:27.774] Timer tick, asking Buildbucket for the build status
[17:06:27.857] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:27.857] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:119:0) after 1m0s
[17:07:27.880] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:119:0)
[17:07:27.880] Timer tick, asking Buildbucket for the build status
[17:07:28.021] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:28.021] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:120:0) after 1m0s
[17:08:28.055] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:120:0)
[17:08:28.055] Timer tick, asking Buildbucket for the build status
[17:08:28.137] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:28.137] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:121:0) after 1m0s
[17:09:28.159] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:121:0)
[17:09:28.159] Timer tick, asking Buildbucket for the build status
[17:09:28.254] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:28.254] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:122:0) after 1m0s
[17:10:28.275] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:122:0)
[17:10:28.275] Timer tick, asking Buildbucket for the build status
[17:10:28.326] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:28.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:123:0) after 1m0s
[17:11:28.406] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:123:0)
[17:11:28.406] Timer tick, asking Buildbucket for the build status
[17:11:28.510] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:28.510] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:124:0) after 1m0s
[17:12:28.547] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:124:0)
[17:12:28.547] Timer tick, asking Buildbucket for the build status
[17:12:28.704] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:28.704] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:125:0) after 1m0s
[17:13:28.757] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:125:0)
[17:13:28.757] Timer tick, asking Buildbucket for the build status
[17:13:28.820] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:28.820] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:126:0) after 1m0s
[17:14:28.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:126:0)
[17:14:28.905] Timer tick, asking Buildbucket for the build status
[17:14:28.964] Build 8922532850145563712: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:28.964] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9087962912286890064:127:0) after 1m0s
[17:14:50.420] Received PubSub notification, asking Buildbucket for the build status
[17:14:50.475] Build 8922532850145563712: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:14:50.476] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1549214073181546",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1549206605733428",
"id": "8922532850145563712",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"793ce40e44defcac99ab698a28d901624adec00d\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"id\": [\"swarm2173-c4\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4050-0cfc6e2\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"got_nacl_revision\": \"ff15c51d20785fa51c0ef03ccb480b87299acbeb\", \"got_swarming_client_revision\": \"0e3e1c4dc4e79f25a5b58fcbc135dc93183c0c54\", \"got_revision\": \"793ce40e44defcac99ab698a28d901624adec00d\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"baa3741c9fbe88e83e6d8cbbea1ca44d53e1da24\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26516}\", \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"got_revision_cp\": \"refs/heads/master@{#628624}\", \"branch\": \"refs/heads/master\", \"revision\": \"793ce40e44defcac99ab698a28d901624adec00d\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"a34d7766c56ae5e648c1db91edd380069f2300d6\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"52d861bd49cc29fa19ae03c248cad122d77f4703\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1549206605733428, \"tags\": [\"build_address:luci.chromium.ci/Windows deterministic/11824\", \"builder:Windows deterministic\", \"buildset:commit/git/793ce40e44defcac99ab698a28d901624adec00d\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/793ce40e44defcac99ab698a28d901624adec00d\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9087962912286890064\", \"scheduler_job_id:chromium/Windows deterministic\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.chromium.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"chromium\", \"id\": \"8922532850145563712\"}}, \"got_v8_revision\": \"a8b1c4ca6107d1b3d4330a840c781d5289720626\", \"got_v8_revision_cp\": \"refs/heads/7.4.57@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 11824, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Windows deterministic\", \"key\": \"builder\"}, {\"value\": \"commit/git/793ce40e44defcac99ab698a28d901624adec00d\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/chromium/src/+/793ce40e44defcac99ab698a28d901624adec00d\", \"key\": \"buildset\"}, {\"value\": \"9087962912286890064\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"chromium/Windows deterministic\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"chromium\", \"builder\": \"Windows deterministic\", \"bucket\": \"ci\"}, \"number\": 11824, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"fac723e58b3bfb6839db07130deafc9ca5897acc\"}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8922532850145563712\", \"input\": {\"gitilesCommit\": {\"project\": \"chromium/src\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"793ce40e44defcac99ab698a28d901624adec00d\"}}, \"createTime\": \"2019-02-03T15:10:05.733428Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"6fbda1b24c1893a893b17aa219b765b9e7c801d8\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1549206609181708",
"status": "COMPLETED",
"status_changed_ts": "1549214089786929",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11824",
"builder:Windows deterministic",
"buildset:commit/git/793ce40e44defcac99ab698a28d901624adec00d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/793ce40e44defcac99ab698a28d901624adec00d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9087962912286890064",
"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/8922532850145563712/+/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:42ccba5a3fc81a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1549214089789467",
"url": "https://ci.chromium.org/b/8922532850145563712",
"utcnow_ts": "1549214090447849"
}
}
[17:14:50.476] Invocation finished in 2h4m46.168644224s with status SUCCEEDED