[15:55:54.599] New invocation is queued and will start shortly
[15:55:55.840] Starting the invocation (attempt 1)
[15:55:55.877] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:55:55.877] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:55:55.877] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9097382112918826112",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"a57c9e94bff6ac35768a226868381ebe3e5c5403\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9097382112918826112",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/a57c9e94bff6ac35768a226868381ebe3e5c5403",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a57c9e94bff6ac35768a226868381ebe3e5c5403",
"gitiles_ref:refs/heads/master"
]
}
[15:55:58.243] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1540223756026142",
"id": "8931952050759972672",
"lease_expiration_ts": "1540367757858295",
"lease_key": "2097563007",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"a57c9e94bff6ac35768a226868381ebe3e5c5403\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1540223758135054",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10473",
"builder:Windows deterministic",
"buildset:commit/git/a57c9e94bff6ac35768a226868381ebe3e5c5403",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a57c9e94bff6ac35768a226868381ebe3e5c5403",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9097382112918826112",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_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/10473",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8931952050759972672",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:c1e4e074f3337ff2dbbfb9aa3b0475a61adb09a6",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/a57c9e94bff6ac35768a226868381ebe3e5c5403",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a57c9e94bff6ac35768a226868381ebe3e5c5403",
"swarming_tag:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_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/8931952050759972672/+/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:9097382112918826112",
"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:5d21b61d8922783e1f86e64740632e2a34b9be92",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:40b54ef4418c3710",
"user_agent:luci-scheduler"
],
"updated_ts": "1540223758135161",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10473",
"utcnow_ts": "1540223758231738"
}
}
[15:55:58.243] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10473
[15:55:58.243] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:2:0) after 1m0s
[15:55:59.952] Received PubSub notification, asking Buildbucket for the build status
[15:56:00.012] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:58.301] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:2:0)
[15:56:58.301] Timer tick, asking Buildbucket for the build status
[15:56:58.356] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:58.356] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:4:0) after 1m0s
[15:57:58.426] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:4:0)
[15:57:58.426] Timer tick, asking Buildbucket for the build status
[15:57:58.474] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:58.474] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:5:0) after 1m0s
[15:58:58.490] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:5:0)
[15:58:58.490] Timer tick, asking Buildbucket for the build status
[15:58:58.522] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:58.522] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:6:0) after 1m0s
[15:59:58.550] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:6:0)
[15:59:58.550] Timer tick, asking Buildbucket for the build status
[15:59:58.699] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:58.699] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:7:0) after 1m0s
[16:00:58.752] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:7:0)
[16:00:58.752] Timer tick, asking Buildbucket for the build status
[16:00:58.841] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:58.841] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:8:0) after 1m0s
[16:01:58.861] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:8:0)
[16:01:58.861] Timer tick, asking Buildbucket for the build status
[16:01:58.976] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:58.976] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:9:0) after 1m0s
[16:02:58.998] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:9:0)
[16:02:58.998] Timer tick, asking Buildbucket for the build status
[16:02:59.101] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:59.101] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:10:0) after 1m0s
[16:03:59.130] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:10:0)
[16:03:59.130] Timer tick, asking Buildbucket for the build status
[16:03:59.183] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:59.183] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:11:0) after 1m0s
[16:04:59.207] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:11:0)
[16:04:59.207] Timer tick, asking Buildbucket for the build status
[16:04:59.343] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:59.343] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:12:0) after 1m0s
[16:05:59.365] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:12:0)
[16:05:59.365] Timer tick, asking Buildbucket for the build status
[16:05:59.430] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:59.430] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:13:0) after 1m0s
[16:06:59.510] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:13:0)
[16:06:59.510] Timer tick, asking Buildbucket for the build status
[16:06:59.614] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:59.614] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:14:0) after 1m0s
[16:07:59.631] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:14:0)
[16:07:59.631] Timer tick, asking Buildbucket for the build status
[16:07:59.697] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:59.697] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:15:0) after 1m0s
[16:08:59.717] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:15:0)
[16:08:59.717] Timer tick, asking Buildbucket for the build status
[16:08:59.825] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:59.825] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:16:0) after 1m0s
[16:09:59.844] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:16:0)
[16:09:59.844] Timer tick, asking Buildbucket for the build status
[16:09:59.881] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:59.881] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:17:0) after 1m0s
[16:10:59.904] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:17:0)
[16:10:59.904] Timer tick, asking Buildbucket for the build status
[16:10:59.955] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:59.955] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:18:0) after 1m0s
[16:12:00.088] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:18:0)
[16:12:00.088] Timer tick, asking Buildbucket for the build status
[16:12:00.179] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:00.179] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:19:0) after 1m0s
[16:13:00.200] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:19:0)
[16:13:00.200] Timer tick, asking Buildbucket for the build status
[16:13:00.248] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:00.248] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:20:0) after 1m0s
[16:14:00.266] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:20:0)
[16:14:00.266] Timer tick, asking Buildbucket for the build status
[16:14:00.479] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:00.479] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:21:0) after 1m0s
[16:15:00.502] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:21:0)
[16:15:00.502] Timer tick, asking Buildbucket for the build status
[16:15:00.594] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:00.595] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:22:0) after 1m0s
[16:16:00.616] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:22:0)
[16:16:00.616] Timer tick, asking Buildbucket for the build status
[16:16:00.660] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:00.660] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:23:0) after 1m0s
[16:17:00.677] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:23:0)
[16:17:00.677] Timer tick, asking Buildbucket for the build status
[16:17:00.742] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:00.742] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:24:0) after 1m0s
[16:18:00.768] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:24:0)
[16:18:00.768] Timer tick, asking Buildbucket for the build status
[16:18:00.965] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:00.965] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:25:0) after 1m0s
[16:19:00.987] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:25:0)
[16:19:00.987] Timer tick, asking Buildbucket for the build status
[16:19:01.147] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:01.147] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:26:0) after 1m0s
[16:20:01.168] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:26:0)
[16:20:01.169] Timer tick, asking Buildbucket for the build status
[16:20:01.241] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:01.241] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:27:0) after 1m0s
[16:21:01.273] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:27:0)
[16:21:01.273] Timer tick, asking Buildbucket for the build status
[16:21:01.326] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:01.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:28:0) after 1m0s
[16:22:01.410] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:28:0)
[16:22:01.410] Timer tick, asking Buildbucket for the build status
[16:22:01.676] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:01.676] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:29:0) after 1m0s
[16:23:01.698] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:29:0)
[16:23:01.698] Timer tick, asking Buildbucket for the build status
[16:23:01.800] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:01.800] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:30:0) after 1m0s
[16:24:02.339] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:30:0)
[16:24:02.339] Timer tick, asking Buildbucket for the build status
[16:24:02.526] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:02.526] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:31:0) after 1m0s
[16:25:02.591] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:31:0)
[16:25:02.591] Timer tick, asking Buildbucket for the build status
[16:25:02.765] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:02.765] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:32:0) after 1m0s
[16:26:02.790] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:32:0)
[16:26:02.790] Timer tick, asking Buildbucket for the build status
[16:26:02.929] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:02.929] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:33:0) after 1m0s
[16:27:02.950] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:33:0)
[16:27:02.950] Timer tick, asking Buildbucket for the build status
[16:27:03.114] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:03.114] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:34:0) after 1m0s
[16:28:03.140] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:34:0)
[16:28:03.140] Timer tick, asking Buildbucket for the build status
[16:28:03.202] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:03.202] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:35:0) after 1m0s
[16:29:03.224] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:35:0)
[16:29:03.224] Timer tick, asking Buildbucket for the build status
[16:29:03.290] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:03.290] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:36:0) after 1m0s
[16:30:03.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:36:0)
[16:30:03.377] Timer tick, asking Buildbucket for the build status
[16:30:03.430] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:03.430] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:37:0) after 1m0s
[16:31:03.461] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:37:0)
[16:31:03.461] Timer tick, asking Buildbucket for the build status
[16:31:03.516] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:03.516] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:38:0) after 1m0s
[16:32:03.539] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:38:0)
[16:32:03.540] Timer tick, asking Buildbucket for the build status
[16:32:03.621] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:03.621] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:39:0) after 1m0s
[16:33:03.693] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:39:0)
[16:33:03.693] Timer tick, asking Buildbucket for the build status
[16:33:03.812] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:03.812] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:40:0) after 1m0s
[16:34:03.887] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:40:0)
[16:34:03.887] Timer tick, asking Buildbucket for the build status
[16:34:03.998] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:03.998] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:41:0) after 1m0s
[16:35:04.023] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:41:0)
[16:35:04.023] Timer tick, asking Buildbucket for the build status
[16:35:04.112] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:04.112] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:42:0) after 1m0s
[16:36:04.166] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:42:0)
[16:36:04.166] Timer tick, asking Buildbucket for the build status
[16:36:04.265] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:04.265] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:43:0) after 1m0s
[16:37:07.920] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:43:0)
[16:37:07.920] Timer tick, asking Buildbucket for the build status
[16:37:08.006] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:08.006] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:44:0) after 1m0s
[16:38:08.069] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:44:0)
[16:38:08.069] Timer tick, asking Buildbucket for the build status
[16:38:08.133] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:08.133] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:45:0) after 1m0s
[16:39:08.167] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:45:0)
[16:39:08.167] Timer tick, asking Buildbucket for the build status
[16:39:08.332] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:08.332] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:46:0) after 1m0s
[16:40:08.372] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:46:0)
[16:40:08.372] Timer tick, asking Buildbucket for the build status
[16:40:08.461] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:08.461] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:47:0) after 1m0s
[16:41:08.538] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:47:0)
[16:41:08.538] Timer tick, asking Buildbucket for the build status
[16:41:08.628] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:08.628] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:48:0) after 1m0s
[16:42:08.648] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:48:0)
[16:42:08.648] Timer tick, asking Buildbucket for the build status
[16:42:08.737] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:08.737] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:49:0) after 1m0s
[16:43:08.757] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:49:0)
[16:43:08.757] Timer tick, asking Buildbucket for the build status
[16:43:08.792] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:08.792] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:50:0) after 1m0s
[16:44:08.812] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:50:0)
[16:44:08.812] Timer tick, asking Buildbucket for the build status
[16:44:09.022] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:09.022] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:51:0) after 1m0s
[16:45:09.045] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:51:0)
[16:45:09.045] Timer tick, asking Buildbucket for the build status
[16:45:09.222] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:09.222] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:52:0) after 1m0s
[16:46:09.243] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:52:0)
[16:46:09.243] Timer tick, asking Buildbucket for the build status
[16:46:09.306] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:09.306] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:53:0) after 1m0s
[16:47:09.326] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:53:0)
[16:47:09.326] Timer tick, asking Buildbucket for the build status
[16:47:09.510] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:09.510] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:54:0) after 1m0s
[16:48:09.532] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:54:0)
[16:48:09.532] Timer tick, asking Buildbucket for the build status
[16:48:09.579] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:09.579] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:55:0) after 1m0s
[16:49:09.599] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:55:0)
[16:49:09.599] Timer tick, asking Buildbucket for the build status
[16:49:09.675] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:09.675] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:56:0) after 1m0s
[16:50:09.701] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:56:0)
[16:50:09.701] Timer tick, asking Buildbucket for the build status
[16:50:09.817] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:09.817] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:57:0) after 1m0s
[16:51:09.838] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:57:0)
[16:51:09.838] Timer tick, asking Buildbucket for the build status
[16:51:09.903] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:09.903] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:58:0) after 1m0s
[16:52:09.948] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:58:0)
[16:52:09.948] Timer tick, asking Buildbucket for the build status
[16:52:10.060] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:10.060] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:59:0) after 1m0s
[16:53:10.089] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:59:0)
[16:53:10.089] Timer tick, asking Buildbucket for the build status
[16:53:10.397] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:10.397] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:60:0) after 1m0s
[16:54:10.418] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:60:0)
[16:54:10.418] Timer tick, asking Buildbucket for the build status
[16:54:10.493] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:10.493] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:61:0) after 1m0s
[16:55:10.732] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:61:0)
[16:55:10.732] Timer tick, asking Buildbucket for the build status
[16:55:10.811] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:10.811] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:62:0) after 1m0s
[16:56:10.859] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:62:0)
[16:56:10.859] Timer tick, asking Buildbucket for the build status
[16:56:10.940] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:10.940] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:63:0) after 1m0s
[16:57:10.977] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:63:0)
[16:57:10.978] Timer tick, asking Buildbucket for the build status
[16:57:11.061] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:11.061] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:64:0) after 1m0s
[16:58:11.087] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:64:0)
[16:58:11.087] Timer tick, asking Buildbucket for the build status
[16:58:11.172] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:11.172] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:65:0) after 1m0s
[16:59:11.194] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:65:0)
[16:59:11.194] Timer tick, asking Buildbucket for the build status
[16:59:11.239] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:11.239] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:66:0) after 1m0s
[17:00:11.333] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:66:0)
[17:00:11.333] Timer tick, asking Buildbucket for the build status
[17:00:11.542] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:11.542] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:67:0) after 1m0s
[17:01:11.651] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:67:0)
[17:01:11.651] Timer tick, asking Buildbucket for the build status
[17:01:11.736] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:11.736] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:68:0) after 1m0s
[17:02:11.763] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:68:0)
[17:02:11.763] Timer tick, asking Buildbucket for the build status
[17:02:11.885] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:11.885] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:69:0) after 1m0s
[17:03:11.985] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:69:0)
[17:03:11.985] Timer tick, asking Buildbucket for the build status
[17:03:12.114] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:12.114] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:70:0) after 1m0s
[17:04:12.195] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:70:0)
[17:04:12.195] Timer tick, asking Buildbucket for the build status
[17:04:12.269] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:12.269] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:71:0) after 1m0s
[17:05:12.312] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:71:0)
[17:05:12.312] Timer tick, asking Buildbucket for the build status
[17:05:12.353] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:12.353] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:72:0) after 1m0s
[17:06:12.384] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:72:0)
[17:06:12.384] Timer tick, asking Buildbucket for the build status
[17:06:12.471] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:12.471] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:73:0) after 1m0s
[17:07:12.543] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:73:0)
[17:07:12.543] Timer tick, asking Buildbucket for the build status
[17:07:12.657] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:12.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:74:0) after 1m0s
[17:08:12.766] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:74:0)
[17:08:12.766] Timer tick, asking Buildbucket for the build status
[17:08:12.879] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:12.879] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:75:0) after 1m0s
[17:09:12.906] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:75:0)
[17:09:12.906] Timer tick, asking Buildbucket for the build status
[17:09:12.978] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:12.978] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:76:0) after 1m0s
[17:10:13.003] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:76:0)
[17:10:13.003] Timer tick, asking Buildbucket for the build status
[17:10:13.097] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:13.097] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:77:0) after 1m0s
[17:11:13.138] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:77:0)
[17:11:13.138] Timer tick, asking Buildbucket for the build status
[17:11:13.234] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:13.234] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:78:0) after 1m0s
[17:12:13.320] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:78:0)
[17:12:13.320] Timer tick, asking Buildbucket for the build status
[17:12:13.443] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:13.443] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:79:0) after 1m0s
[17:13:13.498] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:79:0)
[17:13:13.498] Timer tick, asking Buildbucket for the build status
[17:13:13.662] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:13.662] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:80:0) after 1m0s
[17:14:13.687] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:80:0)
[17:14:13.687] Timer tick, asking Buildbucket for the build status
[17:14:13.732] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:13.732] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:81:0) after 1m0s
[17:15:13.779] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:81:0)
[17:15:13.779] Timer tick, asking Buildbucket for the build status
[17:15:13.894] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:13.894] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:82:0) after 1m0s
[17:16:13.958] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:82:0)
[17:16:13.958] Timer tick, asking Buildbucket for the build status
[17:16:13.995] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:13.995] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:83:0) after 1m0s
[17:17:14.016] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:83:0)
[17:17:14.016] Timer tick, asking Buildbucket for the build status
[17:17:14.096] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:14.096] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:84:0) after 1m0s
[17:18:14.260] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:84:0)
[17:18:14.260] Timer tick, asking Buildbucket for the build status
[17:18:14.369] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:14.369] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:85:0) after 1m0s
[17:19:14.389] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:85:0)
[17:19:14.389] Timer tick, asking Buildbucket for the build status
[17:19:14.503] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:14.503] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:86:0) after 1m0s
[17:20:14.525] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:86:0)
[17:20:14.525] Timer tick, asking Buildbucket for the build status
[17:20:14.607] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:14.607] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:87:0) after 1m0s
[17:21:14.630] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:87:0)
[17:21:14.630] Timer tick, asking Buildbucket for the build status
[17:21:14.786] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:14.786] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:88:0) after 1m0s
[17:22:14.808] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:88:0)
[17:22:14.808] Timer tick, asking Buildbucket for the build status
[17:22:14.925] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:14.925] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:89:0) after 1m0s
[17:23:14.946] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:89:0)
[17:23:14.946] Timer tick, asking Buildbucket for the build status
[17:23:15.174] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:15.174] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:90:0) after 1m0s
[17:24:15.205] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:90:0)
[17:24:15.205] Timer tick, asking Buildbucket for the build status
[17:24:15.270] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:15.270] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:91:0) after 1m0s
[17:25:15.336] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:91:0)
[17:25:15.336] Timer tick, asking Buildbucket for the build status
[17:25:15.471] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:15.471] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:92:0) after 1m0s
[17:26:15.534] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:92:0)
[17:26:15.534] Timer tick, asking Buildbucket for the build status
[17:26:15.567] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:15.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:93:0) after 1m0s
[17:27:15.657] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:93:0)
[17:27:15.657] Timer tick, asking Buildbucket for the build status
[17:27:15.716] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:15.716] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:94:0) after 1m0s
[17:28:15.739] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:94:0)
[17:28:15.739] Timer tick, asking Buildbucket for the build status
[17:28:15.779] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:15.779] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:95:0) after 1m0s
[17:29:15.800] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:95:0)
[17:29:15.800] Timer tick, asking Buildbucket for the build status
[17:29:15.888] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:15.889] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:96:0) after 1m0s
[17:30:15.997] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:96:0)
[17:30:15.997] Timer tick, asking Buildbucket for the build status
[17:30:16.286] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:16.286] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:97:0) after 1m0s
[17:31:16.309] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:97:0)
[17:31:16.309] Timer tick, asking Buildbucket for the build status
[17:31:16.390] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:16.390] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:98:0) after 1m0s
[17:32:16.452] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:98:0)
[17:32:16.452] Timer tick, asking Buildbucket for the build status
[17:32:16.499] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:16.499] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:99:0) after 1m0s
[17:33:16.545] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:99:0)
[17:33:16.545] Timer tick, asking Buildbucket for the build status
[17:33:16.608] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:16.608] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:100:0) after 1m0s
[17:34:16.645] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:100:0)
[17:34:16.645] Timer tick, asking Buildbucket for the build status
[17:34:16.691] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:16.691] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:101:0) after 1m0s
[17:35:16.713] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:101:0)
[17:35:16.713] Timer tick, asking Buildbucket for the build status
[17:35:16.772] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:16.772] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:102:0) after 1m0s
[17:36:16.794] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:102:0)
[17:36:16.794] Timer tick, asking Buildbucket for the build status
[17:36:16.832] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:16.832] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:103:0) after 1m0s
[17:37:16.871] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:103:0)
[17:37:16.871] Timer tick, asking Buildbucket for the build status
[17:37:16.941] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:16.941] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:104:0) after 1m0s
[17:38:17.055] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:104:0)
[17:38:17.055] Timer tick, asking Buildbucket for the build status
[17:38:17.147] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:17.147] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:105:0) after 1m0s
[17:39:17.171] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:105:0)
[17:39:17.171] Timer tick, asking Buildbucket for the build status
[17:39:17.227] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:17.227] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:106:0) after 1m0s
[17:40:17.251] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:106:0)
[17:40:17.251] Timer tick, asking Buildbucket for the build status
[17:40:17.307] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:17.308] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:107:0) after 1m0s
[17:41:17.349] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:107:0)
[17:41:17.349] Timer tick, asking Buildbucket for the build status
[17:41:17.445] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:17.445] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:108:0) after 1m0s
[17:42:17.481] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:108:0)
[17:42:17.481] Timer tick, asking Buildbucket for the build status
[17:42:17.559] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:17.559] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:109:0) after 1m0s
[17:43:17.584] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:109:0)
[17:43:17.584] Timer tick, asking Buildbucket for the build status
[17:43:17.622] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:17.622] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:110:0) after 1m0s
[17:44:17.645] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:110:0)
[17:44:17.645] Timer tick, asking Buildbucket for the build status
[17:44:17.800] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:17.800] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:111:0) after 1m0s
[17:45:17.822] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:111:0)
[17:45:17.822] Timer tick, asking Buildbucket for the build status
[17:45:17.855] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:17.855] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:112:0) after 1m0s
[17:46:17.889] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:112:0)
[17:46:17.889] Timer tick, asking Buildbucket for the build status
[17:46:17.970] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:17.970] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:113:0) after 1m0s
[17:47:18.004] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:113:0)
[17:47:18.004] Timer tick, asking Buildbucket for the build status
[17:47:18.069] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:18.069] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:114:0) after 1m0s
[17:48:18.097] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:114:0)
[17:48:18.097] Timer tick, asking Buildbucket for the build status
[17:48:18.196] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:18.196] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:115:0) after 1m0s
[17:49:18.230] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:115:0)
[17:49:18.230] Timer tick, asking Buildbucket for the build status
[17:49:18.284] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:18.284] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:116:0) after 1m0s
[17:50:18.313] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:116:0)
[17:50:18.313] Timer tick, asking Buildbucket for the build status
[17:50:18.419] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:18.419] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:117:0) after 1m0s
[17:51:18.497] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:117:0)
[17:51:18.497] Timer tick, asking Buildbucket for the build status
[17:51:18.577] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:18.577] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:118:0) after 1m0s
[17:52:18.607] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:118:0)
[17:52:18.607] Timer tick, asking Buildbucket for the build status
[17:52:18.756] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:18.756] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:119:0) after 1m0s
[17:53:18.782] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:119:0)
[17:53:18.782] Timer tick, asking Buildbucket for the build status
[17:53:18.857] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:18.857] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:120:0) after 1m0s
[17:54:18.964] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:120:0)
[17:54:18.964] Timer tick, asking Buildbucket for the build status
[17:54:19.110] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:19.110] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:121:0) after 1m0s
[17:55:19.137] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:121:0)
[17:55:19.137] Timer tick, asking Buildbucket for the build status
[17:55:19.174] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:19.174] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:122:0) after 1m0s
[17:56:19.197] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:122:0)
[17:56:19.197] Timer tick, asking Buildbucket for the build status
[17:56:19.292] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:19.292] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:123:0) after 1m0s
[17:57:19.318] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:123:0)
[17:57:19.318] Timer tick, asking Buildbucket for the build status
[17:57:19.364] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:19.364] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:124:0) after 1m0s
[17:58:19.394] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:124:0)
[17:58:19.394] Timer tick, asking Buildbucket for the build status
[17:58:19.548] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:19.548] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:125:0) after 1m0s
[17:59:19.576] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:125:0)
[17:59:19.576] Timer tick, asking Buildbucket for the build status
[17:59:20.071] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:20.071] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:126:0) after 1m0s
[18:00:20.138] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:126:0)
[18:00:20.138] Timer tick, asking Buildbucket for the build status
[18:00:20.291] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:20.292] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:127:0) after 1m0s
[18:01:20.320] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:127:0)
[18:01:20.320] Timer tick, asking Buildbucket for the build status
[18:01:20.473] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:20.473] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:128:0) after 1m0s
[18:02:20.542] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:128:0)
[18:02:20.542] Timer tick, asking Buildbucket for the build status
[18:02:20.606] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:20.606] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:129:0) after 1m0s
[18:03:20.654] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:129:0)
[18:03:20.654] Timer tick, asking Buildbucket for the build status
[18:03:20.736] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:20.736] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:130:0) after 1m0s
[18:05:47.128] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:130:0)
[18:05:47.128] Timer tick, asking Buildbucket for the build status
[18:05:47.171] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:47.171] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:131:0) after 1m0s
[18:06:47.333] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:131:0)
[18:06:47.333] Timer tick, asking Buildbucket for the build status
[18:06:47.405] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:47.405] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:132:0) after 1m0s
[18:07:47.892] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:132:0)
[18:07:47.892] Timer tick, asking Buildbucket for the build status
[18:07:47.933] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:47.933] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:133:0) after 1m0s
[18:08:48.005] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:133:0)
[18:08:48.005] Timer tick, asking Buildbucket for the build status
[18:08:48.058] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:48.058] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:134:0) after 1m0s
[18:09:48.127] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:134:0)
[18:09:48.127] Timer tick, asking Buildbucket for the build status
[18:09:48.206] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:48.206] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:135:0) after 1m0s
[18:10:48.247] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:135:0)
[18:10:48.247] Timer tick, asking Buildbucket for the build status
[18:10:48.353] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:48.353] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:136:0) after 1m0s
[18:11:48.382] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:136:0)
[18:11:48.382] Timer tick, asking Buildbucket for the build status
[18:11:48.461] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:48.461] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:137:0) after 1m0s
[18:12:48.488] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:137:0)
[18:12:48.488] Timer tick, asking Buildbucket for the build status
[18:12:48.522] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:48.522] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:138:0) after 1m0s
[18:13:48.611] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:138:0)
[18:13:48.611] Timer tick, asking Buildbucket for the build status
[18:13:48.682] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:48.682] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:139:0) after 1m0s
[18:14:50.293] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:139:0)
[18:14:50.293] Timer tick, asking Buildbucket for the build status
[18:14:50.330] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:50.330] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:140:0) after 1m0s
[18:15:50.357] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:140:0)
[18:15:50.357] Timer tick, asking Buildbucket for the build status
[18:15:50.426] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:50.426] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:141:0) after 1m0s
[18:16:50.454] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:141:0)
[18:16:50.454] Timer tick, asking Buildbucket for the build status
[18:16:50.518] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:50.518] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:142:0) after 1m0s
[18:17:50.542] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:142:0)
[18:17:50.542] Timer tick, asking Buildbucket for the build status
[18:17:50.586] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:50.586] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:143:0) after 1m0s
[18:18:50.614] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:143:0)
[18:18:50.614] Timer tick, asking Buildbucket for the build status
[18:18:50.750] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:50.751] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:144:0) after 1m0s
[18:19:50.784] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:144:0)
[18:19:50.784] Timer tick, asking Buildbucket for the build status
[18:19:50.816] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:50.816] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:145:0) after 1m0s
[18:20:50.841] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:145:0)
[18:20:50.841] Timer tick, asking Buildbucket for the build status
[18:20:50.904] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:50.904] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:146:0) after 1m0s
[18:21:50.942] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:146:0)
[18:21:50.942] Timer tick, asking Buildbucket for the build status
[18:21:50.981] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:50.981] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:147:0) after 1m0s
[18:22:51.049] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:147:0)
[18:22:51.049] Timer tick, asking Buildbucket for the build status
[18:22:51.205] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:51.205] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:148:0) after 1m0s
[18:23:51.240] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:148:0)
[18:23:51.240] Timer tick, asking Buildbucket for the build status
[18:23:51.801] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:51.801] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:149:0) after 1m0s
[18:24:51.902] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:149:0)
[18:24:51.902] Timer tick, asking Buildbucket for the build status
[18:24:51.943] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:51.943] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:150:0) after 1m0s
[18:25:51.969] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:150:0)
[18:25:51.969] Timer tick, asking Buildbucket for the build status
[18:25:52.044] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:52.044] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:151:0) after 1m0s
[18:26:52.074] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:151:0)
[18:26:52.074] Timer tick, asking Buildbucket for the build status
[18:26:52.159] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:52.159] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:152:0) after 1m0s
[18:27:52.193] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:152:0)
[18:27:52.193] Timer tick, asking Buildbucket for the build status
[18:27:52.412] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:52.412] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:153:0) after 1m0s
[18:28:52.440] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:153:0)
[18:28:52.440] Timer tick, asking Buildbucket for the build status
[18:28:52.481] Build 8931952050759972672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:52.481] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9097382112918826112:154:0) after 1m0s
[18:29:17.336] Received PubSub notification, asking Buildbucket for the build status
[18:29:17.391] Build 8931952050759972672: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:29:17.391] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1540232955879772",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1540223756026142",
"id": "8931952050759972672",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"a57c9e94bff6ac35768a226868381ebe3e5c5403\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"ui\": {\"info\": \"\"}, \"build_run_result\": {\"infraFailure\": null, \"recipeResult\": {\"jsonResult\": \"null\"}, \"recipeExitCode\": {\"value\": 0}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"repository\": \"\", \"revision\": \"\"}, \"annotationUrl\": \"logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8931952050759972672/+/annotations\"}, \"swarming\": {\"bot_dimensions\": {\"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\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"python\": [\"2.7.6\"], \"server_version\": [\"3817-6aedaff\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"id\": [\"swarm2173-c4\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"got_nacl_revision\": \"99673869a3cd8731d924bd32fa486feebfdc6c4f\", \"got_swarming_client_revision\": \"f78187ab77127de42555afe0ad410bebde6ac6a5\", \"got_revision\": \"a57c9e94bff6ac35768a226868381ebe3e5c5403\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#25289}\", \"$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@{#601591}\", \"branch\": \"refs/heads/master\", \"revision\": \"a57c9e94bff6ac35768a226868381ebe3e5c5403\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"ff292f30d9a4b7a56aea872fe488d342f47202a3\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"7818a85c33d39291248f8f76da78ea13d94c328f\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1540223756026142, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/a57c9e94bff6ac35768a226868381ebe3e5c5403\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a57c9e94bff6ac35768a226868381ebe3e5c5403\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9097382112918826112\", \"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\": \"8931952050759972672\"}}, \"got_v8_revision\": \"e696a0e1e64ac72f81340ddcac6df226812bd014\", \"got_v8_revision_cp\": \"refs/heads/7.2.57@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 10473, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"73f949b6ba62c4938f7739900f9a5e49c068da58\", \"notification_helper_unittests\": \"361393a5a8272f008800d08e18b0a19519af298a\", \"gpu_perftests\": \"181b01f3aaf385ceb6d02381bc7b42058d67e54b\", \"vr_common_unittests\": \"b0b36f46537b5b862e567864d840cab590b5fa3b\", \"telemetry_unittests\": \"46dacd9db783e23dcad74290f916ac602f656af3\", \"load_library_perf_tests\": \"1b118f8b742978470333a17dc39725185dfbbb67\", \"devtools_closure_compile\": \"e4e56ecf341b99135722ee7f746bdcee8d980454\", \"chromedriver_py_tests\": \"3e33d3508284a0fd8675cb86acd719a815289abf\", \"mojo_core_unittests\": \"e1338db2d7a6dddc20b2de4c3d51946bd1785087\", \"install_static_unittests\": \"fb8cf2fffd288a446416bba69458e6723c61076a\", \"leveldb_service_unittests\": \"16feded5cb78be4dadc9edf9bbb26eadff4fd81b\", \"mini_installer_tests\": \"2ce20e00c6ac79f5970ef67665825ff02607cff5\", \"gcp_unittests\": \"5ae2ac6b7095b1ea2e7ebeb92756fd8ee4c05f62\", \"sbox_integration_tests\": \"4352b081cb9e26e25356d5a46da4229f28871ed0\", \"angle_unittests\": \"1f4371c0245eaddd4842b5ffa062fa7dc6ffd8a0\", \"cast_unittests\": \"77092b0683aaf5e50cad5f848c4c739819ad677e\", \"cc_unittests\": \"e2eb230398b47f3b1c83e13f9323bd108ae05632\", \"blink_common_unittests\": \"6c6ac9ac9f302a55eca4c642cb4dbfa3c5dc2dea\", \"crashpad_tests\": \"b48d405c33703a22d90f248a57e36914ac77bc67\", \"telemetry_perf_tests_without_chrome\": \"77a1fc7708996b6bbe559354ffa8585160c25616\", \"metrics_python_tests\": \"a31000cdada7b322bbc3b59093b436676d85f5c1\", \"message_center_unittests\": \"c9ce41b72129ab6f870ffb5e7880705fbb8e0732\", \"ppapi_unittests\": \"49704dca86b9529f0f8df4e4fdb7dbe57117839b\", \"extensions_unittests\": \"b06e77d025f07ccc6d47b7a9caa8a9eeabf08b6f\", \"gl_unittests_ozone\": \"80ec083bee11ca8e58029c4c4e8cd9e10df65d70\", \"app_shell_unittests\": \"4aabfa096a797c5026eca8e75b6f3ad31294b50d\", \"chrome_app_unittests\": \"6d26391b63e2cbb19ac7eb08888492ddb1a36bbb\", \"gl_unittests\": \"80ec083bee11ca8e58029c4c4e8cd9e10df65d70\", \"gcm_unit_tests\": \"fcc0e985edddd347b45e6bfc1a4c7ae5c01a1e27\", \"views_unittests\": \"fa39628c7c70995a6ea1de6188360b3d91167240\", \"content_shell_crash_test\": \"26472feff6e4175f0f9a374fb5169b0f2b81c4e8\", \"ui_base_unittests\": \"b1be64e855acc35f47615a889059ffc3521fec6a\", \"cronet_unittests\": \"6e8f38251acd02f38db25d49d5ea5c1274f13fc8\", \"performance_test_suite\": \"c35b9da724296753193197737e0f37b7ceb72c9b\", \"wm_unittests\": \"7838f587ed052343cd117fd1a0b8ca1bcfcfd0a6\", \"boringssl_crypto_tests\": \"73d10ab97012a349a02c03a00f8ff1207e6901e9\", \"gin_unittests\": \"f153d644d80e91d8659d9da8a89a18828e852d1d\", \"performance_browser_tests\": \"b429ddaecd732304bc8da4fbc9a7cd90fa5ee257\", \"extensions_browsertests\": \"a28eea6ef520a33baa27ad354bedf8f82433c4ba\", \"webkit_python_tests\": \"4eb1b56778eaa0ec231a45fd90783530b0f39d6d\", \"headless_unittests\": \"bb611308f95f0c54f14412a6a19c4533321c9780\", \"webkit_layout_tests\": \"3b714e7f271a0aaddec6625691200b91106094da\", \"vr_pixeltests\": \"7ea2ceec173de373d94d6356ff140b54853f7e18\", \"setup_unittests\": \"a096cff95c010db22a46a65fdcc14a12c1898f09\", \"webkit_unit_tests\": \"1be796fe52f082a6ca299ee2805735e30c6c6c74\", \"content_unittests\": \"fdbf240f981de54f940bb2bb623de5cb22ad7549\", \"performance_webview_test_suite\": \"2692368d60e767973a20486c6b61800bc1ff190b\", \"wtf_unittests\": \"b62af613281b2756900017cac492a0c4e054d138\", \"webkit_layout_tests_exparchive\": \"c07ac774d66ed1354c2a004f6653f336be384baf\", \"chrome_cleaner_unittests\": \"de05e44b469fa87aa85e4b25b027867e3b8fac9f\", \"traffic_annotation_auditor_unittests\": \"1225c5fb3a06b95caacdfa9b75b98ced424338d1\", \"libjingle_xmpp_unittests\": \"97934d719b305b830b0e1a8dc882f2e965b5bcc2\", \"remoting_unittests\": \"05e14a7b61cc41e7aa3cd37e43acab5ab59f6c26\", \"vr_common_perftests\": \"5af3971312506bd548501fb6cb8cca5186261903\", \"filesystem_service_unittests\": \"18083d302c8e24fb8b26958b5e1744c151f2e6b2\", \"chrome_elf_import_unittests\": \"29c994cb12f8e13e62cb826b3985cc258ce231e3\", \"installer_util_unittests\": \"a8ee0773f370c516589f094c34d818af1cae1bdb\", \"gfx_unittests\": \"306736d61cb3fe34d912924d4fb65ab2ab400cea\", \"headless_browsertests\": \"328614b0a0d421629811913f42dd8d7953614047\", \"capture_unittests\": \"a43c6de66ef8ee04a11078209027cf517a1f6709\", \"chrome_elf_unittests\": \"43a1ed11172acb75d04464f84b2e53764032460f\", \"telemetry_gpu_unittests\": \"ef9b0e4b4b5f7b7455156d75303994a15cdf1fce\", \"command_buffer_perftests\": \"19d5510d38fdcb02d6d55e0cfe158c275db7da63\", \"media_unittests\": \"1478536af432f7fa5f0fecee788d1ca4a53fbb8f\", \"chromedriver_unittests\": \"ae60d7f7e5fffde7c76bded1ee8c67696b2adea8\", \"views_mus_interactive_ui_tests\": \"6fecaeabe1961daba46a60ca21ae9d37b90baa73\", \"storage_unittests\": \"2bc13c9557346baef0cba65d99c5701f1eab0a7c\", \"media_service_unittests\": \"afe2a9066aa227f6fac66c2082e992750bb6361a\", \"compositor_unittests\": \"67b387c0708a1e29c2b0123fe9ac2ff0fbccba2e\", \"latency_unittests\": \"b4ca4b659ef65251a4561b19a2eadf8b7c547267\", \"video_decode_accelerator_unittest\": \"6993d0d482811e317bbbfc20a6ab02a492482201\", \"swiftshader_unittests\": \"de819118fae0a15aca56897b4f5e69a608ecba6d\", \"telemetry_perf_unittests\": \"19157612cafcf984d0e975432284a0704e4b551e\", \"media_blink_unittests\": \"3bb9f23738b8c2377d8456c2d1486583fb1df1c2\", \"views_mus_unittests\": \"a577bc33b946b5749c8166b0df6bda2d5a6da1de\", \"components_unittests\": \"8a558bb31e9ec4cfb642d48db8e0abfa121a40d0\", \"snapshot_unittests\": \"b6c61e81f8711576db492aff526f22733142df1d\", \"accessibility_unittests\": \"fb4843488b57d514d6481e0d3ad573142da30ad3\", \"net_perftests\": \"1223483aa2cb25e0a659afe960cfc8c03833a617\", \"aura_unittests\": \"08afeb9c6fb73fbafea6b971822666804871c137\", \"services_unittests\": \"ccf7d41e3b72fb8aeeb0a61ae0f7a6d771c0d5c7\", \"components_perftests\": \"e07640a41e345bd5184f14d479cc9fb5b122e1d4\", \"events_unittests\": \"4cceb5e70691e1c837a9a38b97e01aa70eb04626\", \"tracing_perftests\": \"2d0fd59ea342b633cbe6647ae4dfd8f854bfc151\", \"chromedriver_replay_unittests\": \"5013a0f078eac50a549885e896698d163bf73605\", \"ui_touch_selection_unittests\": \"9c3f3872055af385c4abf9e372a31bde2bb6c0f1\", \"cacheinvalidation_unittests\": \"ed150e53d4a9e6248160c4901880f2269bb2689b\", \"viz_unittests\": \"f5a09d3c8077ad666cdc1b4d218a1b1ff74c553b\", \"browser_tests\": \"20335af0499aa0a956736728ff30b3d337b7e38e\", \"net_unittests\": \"2d892fb7f3537839bdd435fe9ef93480b14fd478\", \"pdf_unittests\": \"d662a44cd363ad782eca3c2f4782bd76b66c624b\", \"boringssl_ssl_tests\": \"d167046115bbf35e2c6e720df25b4ab52a1c8346\", \"sbox_validation_tests\": \"bf887b4972c8843bab290c1ee52c6d3f91dd0763\", \"elevation_service_unittests\": \"0284698da95fc9afff7b828c0468b9c4c96ef068\", \"gpu_unittests\": \"ff2ad070269cd8c97e969f62c60aa475bdf11fe1\", \"native_theme_unittests\": \"8bed3be66f858236af030b70a392d3f0e0ce002c\", \"interactive_ui_tests\": \"e8d759bad64bdc08b3a627772c66a431f1cd1f10\", \"mojo_unittests\": \"e61a3e2c136a6adfacc98bad8f4c5ac14de4c3d2\", \"url_unittests\": \"e4ffc7030b97bb148c4a67782ed07130c1630aac\", \"media_perftests\": \"c9c3e5d407b45d449512184bf63b387366be5b07\", \"cronet_tests\": \"4c691deca5d4f7271d7c82d443dc534d40b5c738\", \"google_apis_unittests\": \"3e87212b9e35d4e9ec0da656cc54b9417a673cb4\", \"telemetry_gpu_integration_test\": \"d7578ee26ee0fe10c50676a533df47d6430dfccd\", \"blink_heap_unittests\": \"9f4c350724c52961f8182f9e51de729f0ea7b3c2\", \"midi_unittests\": \"8deaa8357fafafac3c1e1e4166f07d02cb491785\", \"base_unittests\": \"5afc532a2387e1dfccc6b55d744852a2187d7db0\", \"sync_integration_tests\": \"c7ba47cbc0625de93f4743e668bbca6d8a51844d\", \"sql_unittests\": \"8db2c7f1e46cf4db46bd91499134b3b5a0d44f49\", \"courgette_unittests\": \"0f5f054b85fdc80a730d9cc29884399d05587b03\", \"blink_fuzzer_unittests\": \"f7cac4b6a9174c86dc2e46ac7042317c83b213b3\", \"gl_tests\": \"ccca175abbce783f4cabaedecc95c3780cc6fdcf\", \"zucchini_unittests\": \"27d1ec0ac60b1c99a6dde13f307317ed295304c1\", \"ipc_tests\": \"a55911601b34d3caa77f11220ff3143d5fc1074e\", \"angle_end2end_tests\": \"9ee970b67482d6ffc2656c2535bd587b5cd67545\", \"blink_platform_unittests\": \"3b6165054abf63e8fe1ff17b4f8d93f26b1b4460\", \"components_browsertests\": \"4778ed7d3b5fd2240ab7785e53d69fcd0a7104e4\", \"views_perftests\": \"5ca97c92129c1336d9c36e60f1954e6dbadf576d\", \"angle_perftests\": \"1af3ec1dae79b63032e89d600209f44006489660\", \"display_unittests\": \"be907c1e2082b9acebf43eb51fbb26fb7fe23fe6\", \"sbox_unittests\": \"f2bc134fade0d41f05451385096058964eadba74\", \"service_manager_unittests\": \"524783e08c5f16b86b4322b6bec5d61e1709c973\", \"gles2_conform_test\": \"375bcbf3c046fc4062b2b8fd9d992efdacd0fb14\", \"jingle_unittests\": \"62b5315648cd0226d4a8d1c9d86a93d7272a1f4d\", \"skia_unittests\": \"77d118c13931a849b69d429567b4676e5021974b\", \"angle_white_box_tests\": \"d479fa364e168edb9f050baa0da59ef70cb3f1de\", \"crypto_unittests\": \"745b605ba1fd4fa2f75876147ecd7f2d92c67dfd\", \"nacl_loader_unittests\": \"74dc8d75028f00092d00f8aa831b20dec9b32322\", \"unit_tests\": \"4ff3dc57a44c51b27c77ea44cfbb7194bd34281c\", \"content_browsertests\": \"716e4d30bee765f324b1faafefa331b2020f9a25\", \"printing_unittests\": \"5c5998fcf8f86729482b997af259bfa475f0f83a\", \"device_unittests\": \"260b75d4311ab9e08b63a2363426e3f219bc3e7f\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"13a00f110ef910a25763346d6538b60f12845656\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1540223758923539",
"status": "COMPLETED",
"status_changed_ts": "1540232956610508",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10473",
"builder:Windows deterministic",
"buildset:commit/git/a57c9e94bff6ac35768a226868381ebe3e5c5403",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a57c9e94bff6ac35768a226868381ebe3e5c5403",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9097382112918826112",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_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/10473",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8931952050759972672",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:c1e4e074f3337ff2dbbfb9aa3b0475a61adb09a6",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/a57c9e94bff6ac35768a226868381ebe3e5c5403",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a57c9e94bff6ac35768a226868381ebe3e5c5403",
"swarming_tag:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_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/8931952050759972672/+/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:9097382112918826112",
"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:5d21b61d8922783e1f86e64740632e2a34b9be92",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:40b54ef4418c3710",
"user_agent:luci-scheduler"
],
"updated_ts": "1540232956615910",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10473",
"utcnow_ts": "1540232957378500"
}
}
[18:29:17.391] Invocation finished in 2h33m22.808376332s with status SUCCEEDED