[19:36:04.206] New invocation is queued and will start shortly
[19:36:05.413] Starting the invocation (attempt 1)
[19:36:05.457] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:36:05.457] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:36:05.458] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9089758116465374960",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"5da1d21f332c756024e5cc65f2da9ae8a4ee529f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9089758116465374960",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/5da1d21f332c756024e5cc65f2da9ae8a4ee529f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5da1d21f332c756024e5cc65f2da9ae8a4ee529f",
"gitiles_ref:refs/heads/master"
]
}
[19:36:07.059] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1547494565577070",
"id": "8924328054364113472",
"lease_expiration_ts": "1547613366797744",
"lease_key": "1597754928",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"5da1d21f332c756024e5cc65f2da9ae8a4ee529f\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1547494566959407",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11598",
"builder:Windows deterministic",
"buildset:commit/git/5da1d21f332c756024e5cc65f2da9ae8a4ee529f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5da1d21f332c756024e5cc65f2da9ae8a4ee529f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9089758116465374960",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Windows deterministic/11598",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8924328054364113472",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:78936430c90e535d92b72ffe36f7aa2860b3ad48",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/5da1d21f332c756024e5cc65f2da9ae8a4ee529f",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5da1d21f332c756024e5cc65f2da9ae8a4ee529f",
"swarming_tag:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8924328054364113472/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows-10",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9089758116465374960",
"swarming_tag:scheduler_job_id:chromium/Windows deterministic",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:9f8847cf234cbe6115cbbd25abbb7765c24585c3",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4266aeb1ec1fd010",
"user_agent:luci-scheduler"
],
"updated_ts": "1547494566959500",
"url": "https://ci.chromium.org/b/8924328054364113472",
"utcnow_ts": "1547494567048923"
}
}
[19:36:07.059] Task URL: https://ci.chromium.org/b/8924328054364113472
[19:36:07.059] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:2:0) after 1m0s
[19:37:07.077] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:2:0)
[19:37:07.078] Timer tick, asking Buildbucket for the build status
[19:37:07.206] Build 8924328054364113472: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:37:07.206] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:3:0) after 1m0s
[19:38:07.223] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:3:0)
[19:38:07.223] Timer tick, asking Buildbucket for the build status
[19:38:07.542] Build 8924328054364113472: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:38:07.542] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:4:0) after 1m0s
[19:38:34.988] Received PubSub notification, asking Buildbucket for the build status
[19:38:35.037] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:07.569] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:4:0)
[19:39:07.569] Timer tick, asking Buildbucket for the build status
[19:39:07.600] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:07.600] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:6:0) after 1m0s
[19:40:07.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:6:0)
[19:40:07.683] Timer tick, asking Buildbucket for the build status
[19:40:07.789] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:07.789] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:7:0) after 1m0s
[19:41:07.813] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:7:0)
[19:41:07.813] Timer tick, asking Buildbucket for the build status
[19:41:08.132] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:08.132] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:8:0) after 1m0s
[19:42:08.149] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:8:0)
[19:42:08.149] Timer tick, asking Buildbucket for the build status
[19:42:08.197] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:08.197] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:9:0) after 1m0s
[19:43:08.225] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:9:0)
[19:43:08.225] Timer tick, asking Buildbucket for the build status
[19:43:08.317] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:08.317] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:10:0) after 1m0s
[19:44:08.367] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:10:0)
[19:44:08.367] Timer tick, asking Buildbucket for the build status
[19:44:09.525] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:09.525] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:11:0) after 1m0s
[19:45:09.541] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:11:0)
[19:45:09.541] Timer tick, asking Buildbucket for the build status
[19:45:09.596] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:09.596] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:12:0) after 1m0s
[19:46:09.618] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:12:0)
[19:46:09.618] Timer tick, asking Buildbucket for the build status
[19:46:09.655] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:09.655] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:13:0) after 1m0s
[19:47:09.673] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:13:0)
[19:47:09.673] Timer tick, asking Buildbucket for the build status
[19:47:09.721] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:09.721] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:14:0) after 1m0s
[19:48:09.738] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:14:0)
[19:48:09.738] Timer tick, asking Buildbucket for the build status
[19:48:09.813] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:09.814] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:15:0) after 1m0s
[19:49:09.873] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:15:0)
[19:49:09.873] Timer tick, asking Buildbucket for the build status
[19:49:10.012] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:10.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:16:0) after 1m0s
[19:50:10.032] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:16:0)
[19:50:10.032] Timer tick, asking Buildbucket for the build status
[19:50:10.112] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:10.112] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:17:0) after 1m0s
[19:51:10.134] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:17:0)
[19:51:10.134] Timer tick, asking Buildbucket for the build status
[19:51:10.190] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:10.191] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:18:0) after 1m0s
[19:52:10.210] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:18:0)
[19:52:10.210] Timer tick, asking Buildbucket for the build status
[19:52:10.243] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:10.243] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:19:0) after 1m0s
[19:53:10.264] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:19:0)
[19:53:10.264] Timer tick, asking Buildbucket for the build status
[19:53:10.321] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:10.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:20:0) after 1m0s
[19:54:10.340] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:20:0)
[19:54:10.340] Timer tick, asking Buildbucket for the build status
[19:54:10.449] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:10.449] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:21:0) after 1m0s
[19:55:10.520] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:21:0)
[19:55:10.520] Timer tick, asking Buildbucket for the build status
[19:55:10.567] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:10.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:22:0) after 1m0s
[19:56:11.193] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:22:0)
[19:56:11.193] Timer tick, asking Buildbucket for the build status
[19:56:11.291] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:11.291] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:23:0) after 1m0s
[19:57:11.408] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:23:0)
[19:57:11.408] Timer tick, asking Buildbucket for the build status
[19:57:11.467] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:11.467] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:24:0) after 1m0s
[19:58:11.491] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:24:0)
[19:58:11.491] Timer tick, asking Buildbucket for the build status
[19:58:11.555] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:11.555] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:25:0) after 1m0s
[19:59:11.580] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:25:0)
[19:59:11.580] Timer tick, asking Buildbucket for the build status
[19:59:11.733] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:11.734] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:26:0) after 1m0s
[20:00:11.752] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:26:0)
[20:00:11.752] Timer tick, asking Buildbucket for the build status
[20:00:11.823] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:11.823] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:27:0) after 1m0s
[20:01:11.841] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:27:0)
[20:01:11.841] Timer tick, asking Buildbucket for the build status
[20:01:11.873] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:11.873] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:28:0) after 1m0s
[20:02:11.889] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:28:0)
[20:02:11.889] Timer tick, asking Buildbucket for the build status
[20:02:11.956] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:11.956] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:29:0) after 1m0s
[20:03:11.976] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:29:0)
[20:03:11.976] Timer tick, asking Buildbucket for the build status
[20:03:12.144] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:12.144] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:30:0) after 1m0s
[20:04:12.163] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:30:0)
[20:04:12.163] Timer tick, asking Buildbucket for the build status
[20:04:12.274] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:12.274] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:31:0) after 1m0s
[20:05:12.294] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:31:0)
[20:05:12.294] Timer tick, asking Buildbucket for the build status
[20:05:12.359] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:12.359] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:32:0) after 1m0s
[20:06:12.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:32:0)
[20:06:12.377] Timer tick, asking Buildbucket for the build status
[20:06:12.529] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:12.529] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:33:0) after 1m0s
[20:07:12.559] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:33:0)
[20:07:12.559] Timer tick, asking Buildbucket for the build status
[20:07:13.213] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:13.213] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:34:0) after 1m0s
[20:08:13.232] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:34:0)
[20:08:13.232] Timer tick, asking Buildbucket for the build status
[20:08:13.399] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:13.399] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:35:0) after 1m0s
[20:09:13.420] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:35:0)
[20:09:13.420] Timer tick, asking Buildbucket for the build status
[20:09:13.505] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:13.505] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:36:0) after 1m0s
[20:10:13.525] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:36:0)
[20:10:13.525] Timer tick, asking Buildbucket for the build status
[20:10:13.955] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:13.955] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:37:0) after 1m0s
[20:11:14.049] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:37:0)
[20:11:14.049] Timer tick, asking Buildbucket for the build status
[20:11:14.155] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:14.156] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:38:0) after 1m0s
[20:12:14.242] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:38:0)
[20:12:14.242] Timer tick, asking Buildbucket for the build status
[20:12:14.558] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:14.558] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:39:0) after 1m0s
[20:13:14.644] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:39:0)
[20:13:14.644] Timer tick, asking Buildbucket for the build status
[20:13:14.901] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:14.901] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:40:0) after 1m0s
[20:14:14.950] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:40:0)
[20:14:14.950] Timer tick, asking Buildbucket for the build status
[20:14:15.029] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:15.029] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:41:0) after 1m0s
[20:15:15.115] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:41:0)
[20:15:15.115] Timer tick, asking Buildbucket for the build status
[20:15:15.169] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:15.169] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:42:0) after 1m0s
[20:16:15.188] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:42:0)
[20:16:15.188] Timer tick, asking Buildbucket for the build status
[20:16:15.335] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:15.335] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:43:0) after 1m0s
[20:17:15.495] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:43:0)
[20:17:15.495] Timer tick, asking Buildbucket for the build status
[20:17:15.536] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:15.536] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:44:0) after 1m0s
[20:18:16.055] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:44:0)
[20:18:16.055] Timer tick, asking Buildbucket for the build status
[20:18:16.134] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:16.135] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:45:0) after 1m0s
[20:19:16.161] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:45:0)
[20:19:16.161] Timer tick, asking Buildbucket for the build status
[20:19:16.335] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:16.335] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:46:0) after 1m0s
[20:20:16.355] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:46:0)
[20:20:16.355] Timer tick, asking Buildbucket for the build status
[20:20:16.471] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:16.471] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:47:0) after 1m0s
[20:21:16.491] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:47:0)
[20:21:16.491] Timer tick, asking Buildbucket for the build status
[20:21:16.834] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:16.834] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:48:0) after 1m0s
[20:22:16.853] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:48:0)
[20:22:16.853] Timer tick, asking Buildbucket for the build status
[20:22:16.916] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:16.916] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:49:0) after 1m0s
[20:23:16.943] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:49:0)
[20:23:16.943] Timer tick, asking Buildbucket for the build status
[20:23:17.031] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:17.031] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:50:0) after 1m0s
[20:24:17.059] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:50:0)
[20:24:17.059] Timer tick, asking Buildbucket for the build status
[20:24:17.193] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:17.194] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:51:0) after 1m0s
[20:25:17.232] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:51:0)
[20:25:17.232] Timer tick, asking Buildbucket for the build status
[20:25:17.423] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:17.423] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:52:0) after 1m0s
[20:26:17.442] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:52:0)
[20:26:17.442] Timer tick, asking Buildbucket for the build status
[20:26:17.495] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:17.495] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:53:0) after 1m0s
[20:27:17.539] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:53:0)
[20:27:17.539] Timer tick, asking Buildbucket for the build status
[20:27:17.689] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:17.689] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:54:0) after 1m0s
[20:28:17.725] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:54:0)
[20:28:17.725] Timer tick, asking Buildbucket for the build status
[20:28:17.899] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:17.899] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:55:0) after 1m0s
[20:29:17.925] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:55:0)
[20:29:17.925] Timer tick, asking Buildbucket for the build status
[20:29:17.985] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:17.985] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:56:0) after 1m0s
[20:30:18.008] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:56:0)
[20:30:18.008] Timer tick, asking Buildbucket for the build status
[20:30:18.056] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:18.056] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:57:0) after 1m0s
[20:31:18.079] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:57:0)
[20:31:18.079] Timer tick, asking Buildbucket for the build status
[20:31:19.082] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:19.082] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:58:0) after 1m0s
[20:32:19.119] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:58:0)
[20:32:19.119] Timer tick, asking Buildbucket for the build status
[20:32:19.256] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:19.256] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:59:0) after 1m0s
[20:33:19.277] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:59:0)
[20:33:19.277] Timer tick, asking Buildbucket for the build status
[20:33:19.430] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:19.430] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:60:0) after 1m0s
[20:34:19.496] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:60:0)
[20:34:19.496] Timer tick, asking Buildbucket for the build status
[20:34:19.564] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:19.564] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:61:0) after 1m0s
[20:35:19.585] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:61:0)
[20:35:19.585] Timer tick, asking Buildbucket for the build status
[20:35:19.622] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:19.622] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:62:0) after 1m0s
[20:36:19.640] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:62:0)
[20:36:19.640] Timer tick, asking Buildbucket for the build status
[20:36:19.704] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:19.704] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:63:0) after 1m0s
[20:37:20.146] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:63:0)
[20:37:20.146] Timer tick, asking Buildbucket for the build status
[20:37:20.442] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:20.442] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:64:0) after 1m0s
[20:38:20.573] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:64:0)
[20:38:20.573] Timer tick, asking Buildbucket for the build status
[20:38:20.618] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:20.618] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:65:0) after 1m0s
[20:39:20.681] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:65:0)
[20:39:20.681] Timer tick, asking Buildbucket for the build status
[20:39:20.716] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:20.716] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:66:0) after 1m0s
[20:40:20.736] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:66:0)
[20:40:20.736] Timer tick, asking Buildbucket for the build status
[20:40:20.779] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:20.779] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:67:0) after 1m0s
[20:41:20.824] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:67:0)
[20:41:20.824] Timer tick, asking Buildbucket for the build status
[20:41:20.896] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:20.896] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:68:0) after 1m0s
[20:42:20.918] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:68:0)
[20:42:20.918] Timer tick, asking Buildbucket for the build status
[20:42:20.959] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:20.959] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:69:0) after 1m0s
[20:43:20.986] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:69:0)
[20:43:20.986] Timer tick, asking Buildbucket for the build status
[20:43:21.041] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:21.041] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:70:0) after 1m0s
[20:44:21.375] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:70:0)
[20:44:21.375] Timer tick, asking Buildbucket for the build status
[20:44:21.682] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:21.682] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:71:0) after 1m0s
[20:45:21.755] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:71:0)
[20:45:21.755] Timer tick, asking Buildbucket for the build status
[20:45:21.865] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:21.865] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:72:0) after 1m0s
[20:46:21.884] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:72:0)
[20:46:21.884] Timer tick, asking Buildbucket for the build status
[20:46:21.952] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:21.952] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:73:0) after 1m0s
[20:47:21.972] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:73:0)
[20:47:21.972] Timer tick, asking Buildbucket for the build status
[20:47:22.266] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:22.266] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:74:0) after 1m0s
[20:48:22.293] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:74:0)
[20:48:22.293] Timer tick, asking Buildbucket for the build status
[20:48:22.387] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:22.387] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:75:0) after 1m0s
[20:49:22.442] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:75:0)
[20:49:22.442] Timer tick, asking Buildbucket for the build status
[20:49:22.614] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:22.614] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:76:0) after 1m0s
[20:50:22.632] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:76:0)
[20:50:22.632] Timer tick, asking Buildbucket for the build status
[20:50:22.784] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:22.784] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:77:0) after 1m0s
[20:51:22.849] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:77:0)
[20:51:22.849] Timer tick, asking Buildbucket for the build status
[20:51:23.767] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:23.767] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:78:0) after 1m0s
[20:52:23.858] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:78:0)
[20:52:23.859] Timer tick, asking Buildbucket for the build status
[20:52:23.926] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:23.926] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:79:0) after 1m0s
[20:53:24.156] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:79:0)
[20:53:24.156] Timer tick, asking Buildbucket for the build status
[20:53:24.191] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:24.191] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:80:0) after 1m0s
[20:54:24.225] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:80:0)
[20:54:24.225] Timer tick, asking Buildbucket for the build status
[20:54:24.406] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:24.406] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:81:0) after 1m0s
[20:55:24.428] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:81:0)
[20:55:24.428] Timer tick, asking Buildbucket for the build status
[20:55:24.583] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:24.583] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:82:0) after 1m0s
[20:56:24.617] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:82:0)
[20:56:24.617] Timer tick, asking Buildbucket for the build status
[20:56:24.674] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:24.674] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:83:0) after 1m0s
[20:57:24.707] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:83:0)
[20:57:24.707] Timer tick, asking Buildbucket for the build status
[20:57:24.777] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:24.777] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:84:0) after 1m0s
[20:58:24.802] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:84:0)
[20:58:24.802] Timer tick, asking Buildbucket for the build status
[20:58:24.888] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:24.888] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:85:0) after 1m0s
[20:59:24.910] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:85:0)
[20:59:24.910] Timer tick, asking Buildbucket for the build status
[20:59:25.615] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:25.615] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:86:0) after 1m0s
[21:00:25.675] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:86:0)
[21:00:25.675] Timer tick, asking Buildbucket for the build status
[21:00:25.738] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:25.738] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:87:0) after 1m0s
[21:01:25.757] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:87:0)
[21:01:25.757] Timer tick, asking Buildbucket for the build status
[21:01:25.875] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:25.875] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:88:0) after 1m0s
[21:02:25.896] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:88:0)
[21:02:25.896] Timer tick, asking Buildbucket for the build status
[21:02:26.303] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:26.303] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:89:0) after 1m0s
[21:03:26.323] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:89:0)
[21:03:26.323] Timer tick, asking Buildbucket for the build status
[21:03:26.378] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:26.378] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:90:0) after 1m0s
[21:04:26.417] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:90:0)
[21:04:26.417] Timer tick, asking Buildbucket for the build status
[21:04:26.450] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:26.450] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:91:0) after 1m0s
[21:05:26.493] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:91:0)
[21:05:26.493] Timer tick, asking Buildbucket for the build status
[21:05:26.559] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:26.559] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:92:0) after 1m0s
[21:06:26.581] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:92:0)
[21:06:26.581] Timer tick, asking Buildbucket for the build status
[21:06:26.627] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:26.627] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:93:0) after 1m0s
[21:07:26.648] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:93:0)
[21:07:26.648] Timer tick, asking Buildbucket for the build status
[21:07:26.766] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:26.766] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:94:0) after 1m0s
[21:08:26.786] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:94:0)
[21:08:26.786] Timer tick, asking Buildbucket for the build status
[21:08:26.859] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:26.859] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:95:0) after 1m0s
[21:09:26.881] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:95:0)
[21:09:26.881] Timer tick, asking Buildbucket for the build status
[21:09:27.220] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:27.220] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:96:0) after 1m0s
[21:10:27.242] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:96:0)
[21:10:27.242] Timer tick, asking Buildbucket for the build status
[21:10:27.295] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:27.295] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:97:0) after 1m0s
[21:11:27.315] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:97:0)
[21:11:27.315] Timer tick, asking Buildbucket for the build status
[21:11:27.410] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:27.410] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:98:0) after 1m0s
[21:12:27.432] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:98:0)
[21:12:27.432] Timer tick, asking Buildbucket for the build status
[21:12:27.919] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:27.919] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:99:0) after 1m0s
[21:13:27.941] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:99:0)
[21:13:27.941] Timer tick, asking Buildbucket for the build status
[21:13:27.979] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:27.979] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:100:0) after 1m0s
[21:14:28.003] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:100:0)
[21:14:28.003] Timer tick, asking Buildbucket for the build status
[21:14:28.165] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:28.166] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:101:0) after 1m0s
[21:15:28.250] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:101:0)
[21:15:28.250] Timer tick, asking Buildbucket for the build status
[21:15:28.295] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:28.295] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:102:0) after 1m0s
[21:16:28.336] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:102:0)
[21:16:28.336] Timer tick, asking Buildbucket for the build status
[21:16:28.437] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:28.437] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:103:0) after 1m0s
[21:17:28.465] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:103:0)
[21:17:28.465] Timer tick, asking Buildbucket for the build status
[21:17:28.501] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:28.501] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:104:0) after 1m0s
[21:18:28.523] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:104:0)
[21:18:28.523] Timer tick, asking Buildbucket for the build status
[21:18:28.561] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:28.561] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:105:0) after 1m0s
[21:19:28.589] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:105:0)
[21:19:28.589] Timer tick, asking Buildbucket for the build status
[21:19:28.714] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:28.714] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:106:0) after 1m0s
[21:20:28.736] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:106:0)
[21:20:28.736] Timer tick, asking Buildbucket for the build status
[21:20:28.783] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:28.783] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:107:0) after 1m0s
[21:21:28.804] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:107:0)
[21:21:28.804] Timer tick, asking Buildbucket for the build status
[21:21:28.852] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:28.853] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:108:0) after 1m0s
[21:22:28.889] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:108:0)
[21:22:28.889] Timer tick, asking Buildbucket for the build status
[21:22:28.960] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:28.960] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:109:0) after 1m0s
[21:23:28.982] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:109:0)
[21:23:28.982] Timer tick, asking Buildbucket for the build status
[21:23:29.401] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:29.401] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:110:0) after 1m0s
[21:24:29.424] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:110:0)
[21:24:29.424] Timer tick, asking Buildbucket for the build status
[21:24:29.647] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:29.647] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:111:0) after 1m0s
[21:25:29.668] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:111:0)
[21:25:29.668] Timer tick, asking Buildbucket for the build status
[21:25:29.710] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:29.710] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:112:0) after 1m0s
[21:26:29.731] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:112:0)
[21:26:29.731] Timer tick, asking Buildbucket for the build status
[21:26:29.798] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:29.798] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:113:0) after 1m0s
[21:27:29.823] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:113:0)
[21:27:29.823] Timer tick, asking Buildbucket for the build status
[21:27:29.867] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:29.867] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:114:0) after 1m0s
[21:28:29.892] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:114:0)
[21:28:29.892] Timer tick, asking Buildbucket for the build status
[21:28:29.987] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:29.987] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:115:0) after 1m0s
[21:29:30.013] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:115:0)
[21:29:30.013] Timer tick, asking Buildbucket for the build status
[21:29:30.051] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:30.051] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:116:0) after 1m0s
[21:30:30.078] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:116:0)
[21:30:30.078] Timer tick, asking Buildbucket for the build status
[21:30:30.109] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:30.109] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:117:0) after 1m0s
[21:31:30.153] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:117:0)
[21:31:30.153] Timer tick, asking Buildbucket for the build status
[21:31:30.221] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:30.221] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:118:0) after 1m0s
[21:32:30.243] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:118:0)
[21:32:30.243] Timer tick, asking Buildbucket for the build status
[21:32:30.301] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:30.301] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:119:0) after 1m0s
[21:33:30.350] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:119:0)
[21:33:30.350] Timer tick, asking Buildbucket for the build status
[21:33:30.723] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:30.723] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:120:0) after 1m0s
[21:34:31.136] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:120:0)
[21:34:31.136] Timer tick, asking Buildbucket for the build status
[21:34:31.182] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:31.182] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:121:0) after 1m0s
[21:35:31.205] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:121:0)
[21:35:31.205] Timer tick, asking Buildbucket for the build status
[21:35:31.296] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:31.296] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:122:0) after 1m0s
[21:36:31.374] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:122:0)
[21:36:31.374] Timer tick, asking Buildbucket for the build status
[21:36:31.410] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:31.410] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:123:0) after 1m0s
[21:37:31.536] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:123:0)
[21:37:31.536] Timer tick, asking Buildbucket for the build status
[21:37:31.575] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:31.575] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:124:0) after 1m0s
[21:38:31.692] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:124:0)
[21:38:31.692] Timer tick, asking Buildbucket for the build status
[21:38:31.823] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:31.823] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:125:0) after 1m0s
[21:39:31.992] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:125:0)
[21:39:31.992] Timer tick, asking Buildbucket for the build status
[21:39:32.447] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:32.447] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:126:0) after 1m0s
[21:40:32.480] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:126:0)
[21:40:32.480] Timer tick, asking Buildbucket for the build status
[21:40:32.594] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:32.594] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:127:0) after 1m0s
[21:41:32.617] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:127:0)
[21:41:32.617] Timer tick, asking Buildbucket for the build status
[21:41:32.688] Build 8924328054364113472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:32.688] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089758116465374960:128:0) after 1m0s
[21:42:13.378] Received PubSub notification, asking Buildbucket for the build status
[21:42:13.411] Build 8924328054364113472: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:42:13.411] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1547502131410940",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1547494565577070",
"id": "8924328054364113472",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"5da1d21f332c756024e5cc65f2da9ae8a4ee529f\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"ui\": {\"info\": \"\"}, \"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\": [\"3986-3c043d8\"], \"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\"]}}, \"properties\": {\"got_nacl_revision\": \"1ea07c56ac9b57c30eb784ab2af582af0cdd4b08\", \"got_swarming_client_revision\": \"0e3e1c4dc4e79f25a5b58fcbc135dc93183c0c54\", \"got_revision\": \"5da1d21f332c756024e5cc65f2da9ae8a4ee529f\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"03f64292ad554697026316a55ace9022eff4b3b0\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26232}\", \"$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@{#622549}\", \"branch\": \"refs/heads/master\", \"revision\": \"5da1d21f332c756024e5cc65f2da9ae8a4ee529f\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"b2e21b014c3df0be85e03b2768fbec5fd8ee8e50\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"c759b8b4388b1859b80ab7495074e8e9e7eef756\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1547494565577070, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/5da1d21f332c756024e5cc65f2da9ae8a4ee529f\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5da1d21f332c756024e5cc65f2da9ae8a4ee529f\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9089758116465374960\", \"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\": \"8924328054364113472\"}}, \"got_v8_revision\": \"ee9d016b49b77bffad36590f2be0d1cd97f16950\", \"got_v8_revision_cp\": \"refs/heads/7.3.387@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 11598, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"0e1cbc4eab6861b0c84bf2ed9a3c4b7aa2063819\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1547494707825084",
"status": "COMPLETED",
"status_changed_ts": "1547502132833524",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11598",
"builder:Windows deterministic",
"buildset:commit/git/5da1d21f332c756024e5cc65f2da9ae8a4ee529f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5da1d21f332c756024e5cc65f2da9ae8a4ee529f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9089758116465374960",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Windows deterministic/11598",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8924328054364113472",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:78936430c90e535d92b72ffe36f7aa2860b3ad48",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/5da1d21f332c756024e5cc65f2da9ae8a4ee529f",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5da1d21f332c756024e5cc65f2da9ae8a4ee529f",
"swarming_tag:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8924328054364113472/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows-10",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9089758116465374960",
"swarming_tag:scheduler_job_id:chromium/Windows deterministic",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:9f8847cf234cbe6115cbbd25abbb7765c24585c3",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4266aeb1ec1fd010",
"user_agent:luci-scheduler"
],
"updated_ts": "1547502132834582",
"url": "https://ci.chromium.org/b/8924328054364113472",
"utcnow_ts": "1547502133401721"
}
}
[21:42:13.411] Invocation finished in 2h6m9.221895801s with status SUCCEEDED