[13:59:00.266] New invocation is queued and will start shortly
[13:59:01.520] Starting the invocation (attempt 1)
[13:59:01.610] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:59:01.610] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:59:01.610] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9089507531907868144",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"13ddc47e527b9c6b3f6fee027de0c1c7ff0d7cc7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9089507531907868144",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/13ddc47e527b9c6b3f6fee027de0c1c7ff0d7cc7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/13ddc47e527b9c6b3f6fee027de0c1c7ff0d7cc7",
"gitiles_ref:refs/heads/master"
]
}
[13:59:04.212] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1547733541690965",
"id": "8924077469747280992",
"lease_expiration_ts": "1547852343859717",
"lease_key": "86632182",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"13ddc47e527b9c6b3f6fee027de0c1c7ff0d7cc7\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1547733544044382",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11640",
"builder:Windows deterministic",
"buildset:commit/git/13ddc47e527b9c6b3f6fee027de0c1c7ff0d7cc7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/13ddc47e527b9c6b3f6fee027de0c1c7ff0d7cc7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9089507531907868144",
"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/11640",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8924077469747280992",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:feda358a66696ce4d784e587c3445231e8b652b0",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/13ddc47e527b9c6b3f6fee027de0c1c7ff0d7cc7",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/13ddc47e527b9c6b3f6fee027de0c1c7ff0d7cc7",
"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/8924077469747280992/+/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:9089507531907868144",
"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:d99553b4df90932d76efa1b5b99b5ddaff8e531f",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4274ed30c1257510",
"user_agent:luci-scheduler"
],
"updated_ts": "1547733544044515",
"url": "https://ci.chromium.org/b/8924077469747280992",
"utcnow_ts": "1547733544202228"
}
}
[13:59:04.212] Task URL: https://ci.chromium.org/b/8924077469747280992
[13:59:04.212] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:2:0) after 1m0s
[13:59:05.146] Received PubSub notification, asking Buildbucket for the build status
[13:59:05.181] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:04.287] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:2:0)
[14:00:04.287] Timer tick, asking Buildbucket for the build status
[14:00:04.345] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:04.345] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:4:0) after 1m0s
[14:01:04.362] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:4:0)
[14:01:04.363] Timer tick, asking Buildbucket for the build status
[14:01:04.521] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:04.521] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:5:0) after 1m0s
[14:02:04.555] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:5:0)
[14:02:04.555] Timer tick, asking Buildbucket for the build status
[14:02:04.857] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:04.857] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:6:0) after 1m0s
[14:03:04.938] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:6:0)
[14:03:04.938] Timer tick, asking Buildbucket for the build status
[14:03:05.063] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:05.063] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:7:0) after 1m0s
[14:04:05.079] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:7:0)
[14:04:05.079] Timer tick, asking Buildbucket for the build status
[14:04:05.150] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:05.150] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:8:0) after 1m0s
[14:05:05.177] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:8:0)
[14:05:05.177] Timer tick, asking Buildbucket for the build status
[14:05:05.211] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:05.211] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:9:0) after 1m0s
[14:06:05.226] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:9:0)
[14:06:05.226] Timer tick, asking Buildbucket for the build status
[14:06:05.278] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:05.278] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:10:0) after 1m0s
[14:07:05.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:10:0)
[14:07:05.377] Timer tick, asking Buildbucket for the build status
[14:07:05.492] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:05.492] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:11:0) after 1m0s
[14:08:05.648] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:11:0)
[14:08:05.648] Timer tick, asking Buildbucket for the build status
[14:08:05.780] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:05.780] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:12:0) after 1m0s
[14:09:05.807] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:12:0)
[14:09:05.807] Timer tick, asking Buildbucket for the build status
[14:09:05.860] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:05.860] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:13:0) after 1m0s
[14:10:05.930] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:13:0)
[14:10:05.930] Timer tick, asking Buildbucket for the build status
[14:10:06.038] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:06.038] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:14:0) after 1m0s
[14:11:06.056] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:14:0)
[14:11:06.056] Timer tick, asking Buildbucket for the build status
[14:11:06.579] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:06.579] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:15:0) after 1m0s
[14:12:06.620] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:15:0)
[14:12:06.620] Timer tick, asking Buildbucket for the build status
[14:12:06.658] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:06.658] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:16:0) after 1m0s
[14:13:06.725] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:16:0)
[14:13:06.725] Timer tick, asking Buildbucket for the build status
[14:13:06.802] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:06.802] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:17:0) after 1m0s
[14:14:06.829] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:17:0)
[14:14:06.829] Timer tick, asking Buildbucket for the build status
[14:14:07.048] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:07.048] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:18:0) after 1m0s
[14:15:07.089] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:18:0)
[14:15:07.089] Timer tick, asking Buildbucket for the build status
[14:15:07.168] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:07.168] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:19:0) after 1m0s
[14:16:07.186] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:19:0)
[14:16:07.186] Timer tick, asking Buildbucket for the build status
[14:16:07.274] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:07.274] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:20:0) after 1m0s
[14:17:07.296] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:20:0)
[14:17:07.296] Timer tick, asking Buildbucket for the build status
[14:17:07.350] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:07.350] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:21:0) after 1m0s
[14:18:07.367] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:21:0)
[14:18:07.367] Timer tick, asking Buildbucket for the build status
[14:18:07.457] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:07.457] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:22:0) after 1m0s
[14:19:07.478] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:22:0)
[14:19:07.478] Timer tick, asking Buildbucket for the build status
[14:19:07.557] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:07.557] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:23:0) after 1m0s
[14:20:07.580] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:23:0)
[14:20:07.580] Timer tick, asking Buildbucket for the build status
[14:20:07.636] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:07.636] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:24:0) after 1m0s
[14:21:07.658] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:24:0)
[14:21:07.658] Timer tick, asking Buildbucket for the build status
[14:21:07.771] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:07.771] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:25:0) after 1m0s
[14:22:07.792] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:25:0)
[14:22:07.792] Timer tick, asking Buildbucket for the build status
[14:22:07.903] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:07.903] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:26:0) after 1m0s
[14:23:07.923] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:26:0)
[14:23:07.923] Timer tick, asking Buildbucket for the build status
[14:23:08.040] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:08.040] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:27:0) after 1m0s
[14:24:08.066] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:27:0)
[14:24:08.066] Timer tick, asking Buildbucket for the build status
[14:24:08.117] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:08.117] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:28:0) after 1m0s
[14:25:08.604] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:28:0)
[14:25:08.604] Timer tick, asking Buildbucket for the build status
[14:25:08.762] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:08.762] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:29:0) after 1m0s
[14:26:08.787] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:29:0)
[14:26:08.787] Timer tick, asking Buildbucket for the build status
[14:26:08.868] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:08.868] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:30:0) after 1m0s
[14:27:08.900] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:30:0)
[14:27:08.900] Timer tick, asking Buildbucket for the build status
[14:27:09.168] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:09.168] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:31:0) after 1m0s
[14:28:09.207] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:31:0)
[14:28:09.207] Timer tick, asking Buildbucket for the build status
[14:28:09.329] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:09.329] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:32:0) after 1m0s
[14:29:09.349] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:32:0)
[14:29:09.349] Timer tick, asking Buildbucket for the build status
[14:29:09.452] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:09.452] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:33:0) after 1m0s
[14:30:09.469] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:33:0)
[14:30:09.469] Timer tick, asking Buildbucket for the build status
[14:30:09.680] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:09.680] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:34:0) after 1m0s
[14:31:09.703] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:34:0)
[14:31:09.703] Timer tick, asking Buildbucket for the build status
[14:31:09.770] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:09.770] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:35:0) after 1m0s
[14:32:09.791] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:35:0)
[14:32:09.791] Timer tick, asking Buildbucket for the build status
[14:32:09.925] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:09.925] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:36:0) after 1m0s
[14:33:09.944] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:36:0)
[14:33:09.944] Timer tick, asking Buildbucket for the build status
[14:33:10.007] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:10.007] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:37:0) after 1m0s
[14:34:10.024] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:37:0)
[14:34:10.024] Timer tick, asking Buildbucket for the build status
[14:34:10.126] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:10.126] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:38:0) after 1m0s
[14:35:10.166] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:38:0)
[14:35:10.166] Timer tick, asking Buildbucket for the build status
[14:35:10.277] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:10.277] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:39:0) after 1m0s
[14:36:10.294] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:39:0)
[14:36:10.294] Timer tick, asking Buildbucket for the build status
[14:36:10.335] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:10.335] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:40:0) after 1m0s
[14:37:10.352] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:40:0)
[14:37:10.352] Timer tick, asking Buildbucket for the build status
[14:37:10.550] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:10.550] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:41:0) after 1m0s
[14:38:10.567] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:41:0)
[14:38:10.567] Timer tick, asking Buildbucket for the build status
[14:38:10.664] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:10.664] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:42:0) after 1m0s
[14:39:10.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:42:0)
[14:39:10.683] Timer tick, asking Buildbucket for the build status
[14:39:10.838] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:10.838] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:43:0) after 1m0s
[14:40:10.867] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:43:0)
[14:40:10.867] Timer tick, asking Buildbucket for the build status
[14:40:10.941] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:10.941] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:44:0) after 1m0s
[14:41:10.957] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:44:0)
[14:41:10.957] Timer tick, asking Buildbucket for the build status
[14:41:11.018] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:11.018] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:45:0) after 1m0s
[14:42:11.037] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:45:0)
[14:42:11.037] Timer tick, asking Buildbucket for the build status
[14:42:11.071] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:11.071] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:46:0) after 1m0s
[14:43:11.092] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:46:0)
[14:43:11.092] Timer tick, asking Buildbucket for the build status
[14:43:11.242] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:11.243] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:47:0) after 1m0s
[14:44:11.406] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:47:0)
[14:44:11.406] Timer tick, asking Buildbucket for the build status
[14:44:11.532] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:11.532] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:48:0) after 1m0s
[14:45:11.551] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:48:0)
[14:45:11.551] Timer tick, asking Buildbucket for the build status
[14:45:11.653] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:11.653] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:49:0) after 1m0s
[14:46:11.673] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:49:0)
[14:46:11.673] Timer tick, asking Buildbucket for the build status
[14:46:11.729] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:11.729] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:50:0) after 1m0s
[14:47:11.757] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:50:0)
[14:47:11.757] Timer tick, asking Buildbucket for the build status
[14:47:11.831] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:11.831] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:51:0) after 1m0s
[14:48:11.876] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:51:0)
[14:48:11.876] Timer tick, asking Buildbucket for the build status
[14:48:11.920] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:11.920] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:52:0) after 1m0s
[14:49:11.990] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:52:0)
[14:49:11.990] Timer tick, asking Buildbucket for the build status
[14:49:12.180] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:12.180] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:53:0) after 1m0s
[14:50:12.221] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:53:0)
[14:50:12.221] Timer tick, asking Buildbucket for the build status
[14:50:12.256] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:12.256] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:54:0) after 1m0s
[14:51:12.273] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:54:0)
[14:51:12.273] Timer tick, asking Buildbucket for the build status
[14:51:12.335] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:12.335] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:55:0) after 1m0s
[14:52:12.403] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:55:0)
[14:52:12.403] Timer tick, asking Buildbucket for the build status
[14:52:12.443] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:12.443] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:56:0) after 1m0s
[14:53:12.580] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:56:0)
[14:53:12.580] Timer tick, asking Buildbucket for the build status
[14:53:12.672] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:12.672] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:57:0) after 1m0s
[14:54:12.696] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:57:0)
[14:54:12.696] Timer tick, asking Buildbucket for the build status
[14:54:12.735] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:12.735] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:58:0) after 1m0s
[14:55:12.761] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:58:0)
[14:55:12.761] Timer tick, asking Buildbucket for the build status
[14:55:12.815] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:12.815] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:59:0) after 1m0s
[14:56:12.838] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:59:0)
[14:56:12.838] Timer tick, asking Buildbucket for the build status
[14:56:12.923] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:12.923] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:60:0) after 1m0s
[14:57:12.957] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:60:0)
[14:57:12.957] Timer tick, asking Buildbucket for the build status
[14:57:13.155] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:13.155] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:61:0) after 1m0s
[14:58:13.182] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:61:0)
[14:58:13.182] Timer tick, asking Buildbucket for the build status
[14:58:13.243] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:13.243] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:62:0) after 1m0s
[14:59:13.262] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:62:0)
[14:59:13.262] Timer tick, asking Buildbucket for the build status
[14:59:13.340] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:13.340] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:63:0) after 1m0s
[15:00:13.360] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:63:0)
[15:00:13.360] Timer tick, asking Buildbucket for the build status
[15:00:13.433] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:13.433] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:64:0) after 1m0s
[15:01:13.454] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:64:0)
[15:01:13.454] Timer tick, asking Buildbucket for the build status
[15:01:13.515] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:13.515] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:65:0) after 1m0s
[15:02:13.538] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:65:0)
[15:02:13.538] Timer tick, asking Buildbucket for the build status
[15:02:13.574] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:13.574] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:66:0) after 1m0s
[15:03:13.594] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:66:0)
[15:03:13.594] Timer tick, asking Buildbucket for the build status
[15:03:13.681] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:13.681] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:67:0) after 1m0s
[15:04:13.701] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:67:0)
[15:04:13.701] Timer tick, asking Buildbucket for the build status
[15:04:13.772] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:13.772] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:68:0) after 1m0s
[15:05:13.793] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:68:0)
[15:05:13.793] Timer tick, asking Buildbucket for the build status
[15:05:13.854] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:13.854] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:69:0) after 1m0s
[15:06:13.876] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:69:0)
[15:06:13.876] Timer tick, asking Buildbucket for the build status
[15:06:13.984] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:13.984] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:70:0) after 1m0s
[15:07:14.006] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:70:0)
[15:07:14.006] Timer tick, asking Buildbucket for the build status
[15:07:14.462] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:14.462] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:71:0) after 1m0s
[15:08:14.541] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:71:0)
[15:08:14.541] Timer tick, asking Buildbucket for the build status
[15:08:14.655] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:14.655] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:72:0) after 1m0s
[15:09:14.675] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:72:0)
[15:09:14.675] Timer tick, asking Buildbucket for the build status
[15:09:14.767] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:14.767] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:73:0) after 1m0s
[15:10:14.787] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:73:0)
[15:10:14.787] Timer tick, asking Buildbucket for the build status
[15:10:14.823] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:14.823] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:74:0) after 1m0s
[15:11:14.852] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:74:0)
[15:11:14.852] Timer tick, asking Buildbucket for the build status
[15:11:15.102] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:15.102] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:75:0) after 1m0s
[15:12:15.125] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:75:0)
[15:12:15.125] Timer tick, asking Buildbucket for the build status
[15:12:15.177] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:15.177] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:76:0) after 1m0s
[15:13:15.201] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:76:0)
[15:13:15.201] Timer tick, asking Buildbucket for the build status
[15:13:15.252] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:15.252] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:77:0) after 1m0s
[15:14:15.271] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:77:0)
[15:14:15.271] Timer tick, asking Buildbucket for the build status
[15:14:15.347] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:15.347] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:78:0) after 1m0s
[15:15:15.373] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:78:0)
[15:15:15.373] Timer tick, asking Buildbucket for the build status
[15:15:15.438] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:15.438] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:79:0) after 1m0s
[15:16:15.495] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:79:0)
[15:16:15.495] Timer tick, asking Buildbucket for the build status
[15:16:15.663] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:15.663] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:80:0) after 1m0s
[15:17:15.687] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:80:0)
[15:17:15.687] Timer tick, asking Buildbucket for the build status
[15:17:15.731] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:15.731] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:81:0) after 1m0s
[15:18:15.757] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:81:0)
[15:18:15.757] Timer tick, asking Buildbucket for the build status
[15:18:15.849] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:15.849] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:82:0) after 1m0s
[15:19:15.873] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:82:0)
[15:19:15.873] Timer tick, asking Buildbucket for the build status
[15:19:15.925] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:15.926] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:83:0) after 1m0s
[15:20:15.968] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:83:0)
[15:20:15.968] Timer tick, asking Buildbucket for the build status
[15:20:16.049] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:16.049] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:84:0) after 1m0s
[15:21:16.073] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:84:0)
[15:21:16.073] Timer tick, asking Buildbucket for the build status
[15:21:16.131] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:16.131] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:85:0) after 1m0s
[15:22:16.151] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:85:0)
[15:22:16.151] Timer tick, asking Buildbucket for the build status
[15:22:16.216] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:16.216] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:86:0) after 1m0s
[15:23:16.236] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:86:0)
[15:23:16.236] Timer tick, asking Buildbucket for the build status
[15:23:16.507] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:16.507] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:87:0) after 1m0s
[15:24:16.538] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:87:0)
[15:24:16.539] Timer tick, asking Buildbucket for the build status
[15:24:16.619] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:16.619] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:88:0) after 1m0s
[15:25:16.638] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:88:0)
[15:25:16.638] Timer tick, asking Buildbucket for the build status
[15:25:16.803] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:16.803] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:89:0) after 1m0s
[15:26:16.822] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:89:0)
[15:26:16.822] Timer tick, asking Buildbucket for the build status
[15:26:16.863] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:16.863] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:90:0) after 1m0s
[15:27:16.903] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:90:0)
[15:27:16.903] Timer tick, asking Buildbucket for the build status
[15:27:16.946] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:16.946] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:91:0) after 1m0s
[15:28:16.992] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:91:0)
[15:28:16.992] Timer tick, asking Buildbucket for the build status
[15:28:17.054] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:17.054] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:92:0) after 1m0s
[15:29:17.078] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:92:0)
[15:29:17.078] Timer tick, asking Buildbucket for the build status
[15:29:17.204] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:17.204] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:93:0) after 1m0s
[15:30:17.258] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:93:0)
[15:30:17.258] Timer tick, asking Buildbucket for the build status
[15:30:17.438] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:17.438] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:94:0) after 1m0s
[15:31:17.544] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:94:0)
[15:31:17.544] Timer tick, asking Buildbucket for the build status
[15:31:17.607] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:17.607] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:95:0) after 1m0s
[15:32:17.632] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:95:0)
[15:32:17.632] Timer tick, asking Buildbucket for the build status
[15:32:17.690] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:17.690] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:96:0) after 1m0s
[15:33:17.712] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:96:0)
[15:33:17.712] Timer tick, asking Buildbucket for the build status
[15:33:17.849] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:17.849] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:97:0) after 1m0s
[15:34:17.877] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:97:0)
[15:34:17.877] Timer tick, asking Buildbucket for the build status
[15:34:18.020] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:18.020] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:98:0) after 1m0s
[15:35:18.162] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:98:0)
[15:35:18.162] Timer tick, asking Buildbucket for the build status
[15:35:18.195] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:18.195] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:99:0) after 1m0s
[15:36:18.221] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:99:0)
[15:36:18.221] Timer tick, asking Buildbucket for the build status
[15:36:18.334] Build 8924077469747280992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:18.334] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089507531907868144:100:0) after 1m0s
[15:36:38.172] Received PubSub notification, asking Buildbucket for the build status
[15:36:38.215] Build 8924077469747280992: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:36:38.215] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1547739397084765",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1547733541690965",
"id": "8924077469747280992",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"13ddc47e527b9c6b3f6fee027de0c1c7ff0d7cc7\"}}",
"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\": [\"3996-e2326f7\"], \"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\": \"ff15c51d20785fa51c0ef03ccb480b87299acbeb\", \"got_swarming_client_revision\": \"0e3e1c4dc4e79f25a5b58fcbc135dc93183c0c54\", \"got_revision\": \"13ddc47e527b9c6b3f6fee027de0c1c7ff0d7cc7\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"799cddcb83fda2911491fd653023c38a28bba1bd\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26288}\", \"$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@{#623672}\", \"branch\": \"refs/heads/master\", \"revision\": \"13ddc47e527b9c6b3f6fee027de0c1c7ff0d7cc7\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"ad8719442b269dae3a4f95c274eb46f1e718e504\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"94d8a9a445bef9b7978f513f7f8d449696716296\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1547733541690965, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/13ddc47e527b9c6b3f6fee027de0c1c7ff0d7cc7\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/13ddc47e527b9c6b3f6fee027de0c1c7ff0d7cc7\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9089507531907868144\", \"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\": \"8924077469747280992\"}}, \"got_v8_revision\": \"618715d849baa01d191342033992be22b0debb6d\", \"got_v8_revision_cp\": \"refs/heads/7.3.430@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 11640, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"40194ab03962d8183b2796fa58a39ec8a9e83a41\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1547733543813976",
"status": "COMPLETED",
"status_changed_ts": "1547739397823102",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11640",
"builder:Windows deterministic",
"buildset:commit/git/13ddc47e527b9c6b3f6fee027de0c1c7ff0d7cc7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/13ddc47e527b9c6b3f6fee027de0c1c7ff0d7cc7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9089507531907868144",
"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/11640",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8924077469747280992",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:feda358a66696ce4d784e587c3445231e8b652b0",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/13ddc47e527b9c6b3f6fee027de0c1c7ff0d7cc7",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/13ddc47e527b9c6b3f6fee027de0c1c7ff0d7cc7",
"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/8924077469747280992/+/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:9089507531907868144",
"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:d99553b4df90932d76efa1b5b99b5ddaff8e531f",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4274ed30c1257510",
"user_agent:luci-scheduler"
],
"updated_ts": "1547739397824421",
"url": "https://ci.chromium.org/b/8924077469747280992",
"utcnow_ts": "1547739398203084"
}
}
[15:36:38.217] Invocation finished in 1h37m37.97092198s with status SUCCEEDED