[21:42:18.163] New invocation is queued and will start shortly
[21:42:19.465] Starting the invocation (attempt 1)
[21:42:19.498] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:42:19.498] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:42:19.498] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9102886734722048960",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"d714188ad980100d09bcd334b3b4a43b3cdbcd3a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9102886734722048960",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/d714188ad980100d09bcd334b3b4a43b3cdbcd3a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d714188ad980100d09bcd334b3b4a43b3cdbcd3a",
"gitiles_ref:refs/heads/master"
]
}
[21:42:21.012] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1534974139572620",
"id": "8937456672582702352",
"lease_expiration_ts": "1535118140619300",
"lease_key": "919294309",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"d714188ad980100d09bcd334b3b4a43b3cdbcd3a\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1534974140827160",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9825",
"builder:Windows deterministic",
"buildset:commit/git/d714188ad980100d09bcd334b3b4a43b3cdbcd3a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d714188ad980100d09bcd334b3b4a43b3cdbcd3a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9102886734722048960",
"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/9825",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8937456672582702352",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:ca2c7a741e04d0385e2bf62941ac932dc0521af3",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/d714188ad980100d09bcd334b3b4a43b3cdbcd3a",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d714188ad980100d09bcd334b3b4a43b3cdbcd3a",
"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/8937456672582702352/+/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:9102886734722048960",
"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:5abbd2c02d77dd5ecaac06e6e87a8ea54fbb8de4",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3f7c6826d9696710",
"user_agent:luci-scheduler"
],
"updated_ts": "1534974140836270",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9825",
"utcnow_ts": "1534974140996340"
}
}
[21:42:21.012] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9825
[21:42:21.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:2:0) after 1m0s
[21:43:21.032] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:2:0)
[21:43:21.032] Timer tick, asking Buildbucket for the build status
[21:43:21.113] Build 8937456672582702352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:43:21.113] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:3:0) after 1m0s
[21:43:59.857] Received PubSub notification, asking Buildbucket for the build status
[21:43:59.893] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:21.148] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:3:0)
[21:44:21.148] Timer tick, asking Buildbucket for the build status
[21:44:21.222] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:21.222] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:5:0) after 1m0s
[21:45:21.254] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:5:0)
[21:45:21.254] Timer tick, asking Buildbucket for the build status
[21:45:21.469] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:21.469] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:6:0) after 1m0s
[21:46:21.493] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:6:0)
[21:46:21.493] Timer tick, asking Buildbucket for the build status
[21:46:21.531] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:21.531] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:7:0) after 1m0s
[21:47:21.549] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:7:0)
[21:47:21.549] Timer tick, asking Buildbucket for the build status
[21:47:21.591] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:21.591] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:8:0) after 1m0s
[21:48:21.721] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:8:0)
[21:48:21.721] Timer tick, asking Buildbucket for the build status
[21:48:21.893] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:21.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:9:0) after 1m0s
[21:49:21.921] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:9:0)
[21:49:21.921] Timer tick, asking Buildbucket for the build status
[21:49:21.991] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:21.991] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:10:0) after 1m0s
[21:50:22.013] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:10:0)
[21:50:22.013] Timer tick, asking Buildbucket for the build status
[21:50:24.392] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:24.392] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:11:0) after 1m0s
[21:51:24.414] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:11:0)
[21:51:24.414] Timer tick, asking Buildbucket for the build status
[21:51:24.448] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:24.448] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:12:0) after 1m0s
[21:52:24.470] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:12:0)
[21:52:24.470] Timer tick, asking Buildbucket for the build status
[21:52:24.701] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:24.701] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:13:0) after 1m0s
[21:53:24.722] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:13:0)
[21:53:24.722] Timer tick, asking Buildbucket for the build status
[21:53:24.794] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:24.794] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:14:0) after 1m0s
[21:54:24.816] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:14:0)
[21:54:24.816] Timer tick, asking Buildbucket for the build status
[21:54:24.851] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:24.851] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:15:0) after 1m0s
[21:55:24.875] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:15:0)
[21:55:24.875] Timer tick, asking Buildbucket for the build status
[21:55:25.074] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:25.074] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:16:0) after 1m0s
[21:56:25.093] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:16:0)
[21:56:25.093] Timer tick, asking Buildbucket for the build status
[21:56:25.162] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:25.162] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:17:0) after 1m0s
[21:57:25.304] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:17:0)
[21:57:25.304] Timer tick, asking Buildbucket for the build status
[21:57:25.352] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:25.352] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:18:0) after 1m0s
[21:58:25.374] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:18:0)
[21:58:25.374] Timer tick, asking Buildbucket for the build status
[21:58:25.413] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:25.413] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:19:0) after 1m0s
[21:59:25.434] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:19:0)
[21:59:25.434] Timer tick, asking Buildbucket for the build status
[21:59:25.623] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:25.623] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:20:0) after 1m0s
[22:00:25.642] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:20:0)
[22:00:25.642] Timer tick, asking Buildbucket for the build status
[22:00:25.690] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:25.690] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:21:0) after 1m0s
[22:01:25.710] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:21:0)
[22:01:25.710] Timer tick, asking Buildbucket for the build status
[22:01:25.865] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:25.865] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:22:0) after 1m0s
[22:02:26.109] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:22:0)
[22:02:26.109] Timer tick, asking Buildbucket for the build status
[22:02:26.194] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:26.194] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:23:0) after 1m0s
[22:03:26.216] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:23:0)
[22:03:26.216] Timer tick, asking Buildbucket for the build status
[22:03:26.315] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:26.315] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:24:0) after 1m0s
[22:04:26.431] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:24:0)
[22:04:26.431] Timer tick, asking Buildbucket for the build status
[22:04:26.527] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:26.527] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:25:0) after 1m0s
[22:05:26.546] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:25:0)
[22:05:26.546] Timer tick, asking Buildbucket for the build status
[22:05:26.699] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:26.699] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:26:0) after 1m0s
[22:06:26.781] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:26:0)
[22:06:26.781] Timer tick, asking Buildbucket for the build status
[22:06:26.825] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:26.825] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:27:0) after 1m0s
[22:07:26.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:27:0)
[22:07:26.980] Timer tick, asking Buildbucket for the build status
[22:07:27.053] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:27.053] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:28:0) after 1m0s
[22:08:27.105] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:28:0)
[22:08:27.105] Timer tick, asking Buildbucket for the build status
[22:08:27.162] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:27.162] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:29:0) after 1m0s
[22:09:27.204] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:29:0)
[22:09:27.204] Timer tick, asking Buildbucket for the build status
[22:09:27.268] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:27.268] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:30:0) after 1m0s
[22:10:27.292] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:30:0)
[22:10:27.292] Timer tick, asking Buildbucket for the build status
[22:10:27.328] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:27.328] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:31:0) after 1m0s
[22:11:27.350] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:31:0)
[22:11:27.350] Timer tick, asking Buildbucket for the build status
[22:11:27.662] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:27.662] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:32:0) after 1m0s
[22:12:27.680] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:32:0)
[22:12:27.680] Timer tick, asking Buildbucket for the build status
[22:12:28.469] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:28.469] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:33:0) after 1m0s
[22:13:28.491] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:33:0)
[22:13:28.491] Timer tick, asking Buildbucket for the build status
[22:13:29.112] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:29.112] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:34:0) after 1m0s
[22:14:29.133] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:34:0)
[22:14:29.133] Timer tick, asking Buildbucket for the build status
[22:14:29.257] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:29.257] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:35:0) after 1m0s
[22:15:29.280] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:35:0)
[22:15:29.280] Timer tick, asking Buildbucket for the build status
[22:15:29.384] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:29.384] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:36:0) after 1m0s
[22:16:29.425] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:36:0)
[22:16:29.425] Timer tick, asking Buildbucket for the build status
[22:16:29.462] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:29.462] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:37:0) after 1m0s
[22:17:29.501] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:37:0)
[22:17:29.502] Timer tick, asking Buildbucket for the build status
[22:17:29.558] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:29.558] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:38:0) after 1m0s
[22:18:29.583] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:38:0)
[22:18:29.583] Timer tick, asking Buildbucket for the build status
[22:18:29.637] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:29.637] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:39:0) after 1m0s
[22:19:29.660] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:39:0)
[22:19:29.660] Timer tick, asking Buildbucket for the build status
[22:19:29.726] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:29.726] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:40:0) after 1m0s
[22:20:29.746] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:40:0)
[22:20:29.746] Timer tick, asking Buildbucket for the build status
[22:20:29.802] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:29.802] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:41:0) after 1m0s
[22:21:29.887] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:41:0)
[22:21:29.887] Timer tick, asking Buildbucket for the build status
[22:21:29.944] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:29.944] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:42:0) after 1m0s
[22:22:29.967] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:42:0)
[22:22:29.967] Timer tick, asking Buildbucket for the build status
[22:22:30.022] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:30.022] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:43:0) after 1m0s
[22:23:30.042] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:43:0)
[22:23:30.042] Timer tick, asking Buildbucket for the build status
[22:23:30.078] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:30.078] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:44:0) after 1m0s
[22:24:30.097] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:44:0)
[22:24:30.097] Timer tick, asking Buildbucket for the build status
[22:24:30.153] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:30.153] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:45:0) after 1m0s
[22:25:30.201] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:45:0)
[22:25:30.201] Timer tick, asking Buildbucket for the build status
[22:25:30.299] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:30.299] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:46:0) after 1m0s
[22:26:30.340] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:46:0)
[22:26:30.340] Timer tick, asking Buildbucket for the build status
[22:26:30.380] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:30.380] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:47:0) after 1m0s
[22:27:30.399] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:47:0)
[22:27:30.399] Timer tick, asking Buildbucket for the build status
[22:27:30.583] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:30.583] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:48:0) after 1m0s
[22:28:30.604] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:48:0)
[22:28:30.604] Timer tick, asking Buildbucket for the build status
[22:28:30.680] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:30.680] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:49:0) after 1m0s
[22:29:30.701] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:49:0)
[22:29:30.701] Timer tick, asking Buildbucket for the build status
[22:29:30.832] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:30.832] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:50:0) after 1m0s
[22:30:30.857] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:50:0)
[22:30:30.857] Timer tick, asking Buildbucket for the build status
[22:30:31.031] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:31.031] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:51:0) after 1m0s
[22:31:31.053] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:51:0)
[22:31:31.053] Timer tick, asking Buildbucket for the build status
[22:31:31.109] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:31.109] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:52:0) after 1m0s
[22:32:31.216] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:52:0)
[22:32:31.216] Timer tick, asking Buildbucket for the build status
[22:32:31.260] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:31.260] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:53:0) after 1m0s
[22:33:31.286] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:53:0)
[22:33:31.286] Timer tick, asking Buildbucket for the build status
[22:33:31.349] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:31.349] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:54:0) after 1m0s
[22:34:31.370] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:54:0)
[22:34:31.370] Timer tick, asking Buildbucket for the build status
[22:34:31.422] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:31.422] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:55:0) after 1m0s
[22:35:31.443] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:55:0)
[22:35:31.443] Timer tick, asking Buildbucket for the build status
[22:35:31.480] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:31.480] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:56:0) after 1m0s
[22:36:31.502] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:56:0)
[22:36:31.502] Timer tick, asking Buildbucket for the build status
[22:36:31.589] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:31.589] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:57:0) after 1m0s
[22:37:31.611] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:57:0)
[22:37:31.611] Timer tick, asking Buildbucket for the build status
[22:37:31.793] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:31.793] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:58:0) after 1m0s
[22:38:31.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:58:0)
[22:38:31.815] Timer tick, asking Buildbucket for the build status
[22:38:31.903] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:31.903] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:59:0) after 1m0s
[22:39:31.925] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:59:0)
[22:39:31.925] Timer tick, asking Buildbucket for the build status
[22:39:31.975] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:31.975] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:60:0) after 1m0s
[22:40:32.028] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:60:0)
[22:40:32.028] Timer tick, asking Buildbucket for the build status
[22:40:32.143] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:32.143] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:61:0) after 1m0s
[22:41:32.212] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:61:0)
[22:41:32.212] Timer tick, asking Buildbucket for the build status
[22:41:32.332] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:32.332] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:62:0) after 1m0s
[22:42:32.356] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:62:0)
[22:42:32.356] Timer tick, asking Buildbucket for the build status
[22:42:32.487] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:32.487] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:63:0) after 1m0s
[22:43:32.511] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:63:0)
[22:43:32.511] Timer tick, asking Buildbucket for the build status
[22:43:32.590] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:32.590] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:64:0) after 1m0s
[22:44:32.614] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:64:0)
[22:44:32.614] Timer tick, asking Buildbucket for the build status
[22:44:32.650] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:32.650] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:65:0) after 1m0s
[22:45:32.672] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:65:0)
[22:45:32.672] Timer tick, asking Buildbucket for the build status
[22:45:32.729] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:32.729] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:66:0) after 1m0s
[22:46:32.755] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:66:0)
[22:46:32.755] Timer tick, asking Buildbucket for the build status
[22:46:32.872] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:32.872] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:67:0) after 1m0s
[22:47:32.896] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:67:0)
[22:47:32.896] Timer tick, asking Buildbucket for the build status
[22:47:33.122] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:33.122] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:68:0) after 1m0s
[22:48:33.156] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:68:0)
[22:48:33.156] Timer tick, asking Buildbucket for the build status
[22:48:33.251] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:33.251] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:69:0) after 1m0s
[22:49:33.274] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:69:0)
[22:49:33.274] Timer tick, asking Buildbucket for the build status
[22:49:33.347] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:33.347] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:70:0) after 1m0s
[22:50:33.367] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:70:0)
[22:50:33.367] Timer tick, asking Buildbucket for the build status
[22:50:33.468] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:33.468] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:71:0) after 1m0s
[22:51:33.489] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:71:0)
[22:51:33.489] Timer tick, asking Buildbucket for the build status
[22:51:33.560] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:33.560] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:72:0) after 1m0s
[22:52:33.582] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:72:0)
[22:52:33.582] Timer tick, asking Buildbucket for the build status
[22:52:33.618] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:33.618] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:73:0) after 1m0s
[22:53:33.637] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:73:0)
[22:53:33.637] Timer tick, asking Buildbucket for the build status
[22:53:33.692] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:33.692] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:74:0) after 1m0s
[22:54:33.723] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:74:0)
[22:54:33.723] Timer tick, asking Buildbucket for the build status
[22:54:33.768] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:33.768] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:75:0) after 1m0s
[22:55:33.790] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:75:0)
[22:55:33.790] Timer tick, asking Buildbucket for the build status
[22:55:33.854] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:33.854] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:76:0) after 1m0s
[22:56:33.879] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:76:0)
[22:56:33.879] Timer tick, asking Buildbucket for the build status
[22:56:33.992] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:33.992] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:77:0) after 1m0s
[22:57:34.028] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:77:0)
[22:57:34.028] Timer tick, asking Buildbucket for the build status
[22:57:34.130] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:34.130] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:78:0) after 1m0s
[22:58:34.149] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:78:0)
[22:58:34.150] Timer tick, asking Buildbucket for the build status
[22:58:34.229] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:34.229] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:79:0) after 1m0s
[22:59:34.250] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:79:0)
[22:59:34.250] Timer tick, asking Buildbucket for the build status
[22:59:34.310] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:34.310] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:80:0) after 1m0s
[23:00:34.334] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:80:0)
[23:00:34.334] Timer tick, asking Buildbucket for the build status
[23:00:34.372] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:34.372] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:81:0) after 1m0s
[23:01:34.429] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:81:0)
[23:01:34.429] Timer tick, asking Buildbucket for the build status
[23:01:34.492] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:34.492] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:82:0) after 1m0s
[23:02:34.525] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:82:0)
[23:02:34.525] Timer tick, asking Buildbucket for the build status
[23:02:34.615] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:34.615] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:83:0) after 1m0s
[23:03:34.640] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:83:0)
[23:03:34.640] Timer tick, asking Buildbucket for the build status
[23:03:34.847] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:34.847] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:84:0) after 1m0s
[23:04:34.877] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:84:0)
[23:04:34.877] Timer tick, asking Buildbucket for the build status
[23:04:34.975] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:34.975] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:85:0) after 1m0s
[23:05:34.998] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:85:0)
[23:05:34.998] Timer tick, asking Buildbucket for the build status
[23:05:35.097] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:35.097] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:86:0) after 1m0s
[23:06:35.118] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:86:0)
[23:06:35.118] Timer tick, asking Buildbucket for the build status
[23:06:35.210] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:35.210] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:87:0) after 1m0s
[23:07:35.262] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:87:0)
[23:07:35.263] Timer tick, asking Buildbucket for the build status
[23:07:35.353] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:35.353] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:88:0) after 1m0s
[23:08:35.387] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:88:0)
[23:08:35.387] Timer tick, asking Buildbucket for the build status
[23:08:35.429] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:35.429] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:89:0) after 1m0s
[23:09:35.457] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:89:0)
[23:09:35.457] Timer tick, asking Buildbucket for the build status
[23:09:35.593] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:35.594] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:90:0) after 1m0s
[23:10:35.617] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:90:0)
[23:10:35.617] Timer tick, asking Buildbucket for the build status
[23:10:35.650] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:35.650] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:91:0) after 1m0s
[23:11:35.677] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:91:0)
[23:11:35.677] Timer tick, asking Buildbucket for the build status
[23:11:35.741] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:35.741] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:92:0) after 1m0s
[23:12:35.806] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:92:0)
[23:12:35.806] Timer tick, asking Buildbucket for the build status
[23:12:35.952] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:35.952] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:93:0) after 1m0s
[23:13:35.982] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:93:0)
[23:13:35.982] Timer tick, asking Buildbucket for the build status
[23:13:36.043] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:36.043] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:94:0) after 1m0s
[23:14:36.069] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:94:0)
[23:14:36.069] Timer tick, asking Buildbucket for the build status
[23:14:36.183] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:36.184] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:95:0) after 1m0s
[23:15:36.228] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:95:0)
[23:15:36.228] Timer tick, asking Buildbucket for the build status
[23:15:36.475] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:36.475] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:96:0) after 1m0s
[23:16:36.500] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:96:0)
[23:16:36.500] Timer tick, asking Buildbucket for the build status
[23:16:36.547] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:36.547] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:97:0) after 1m0s
[23:17:36.570] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:97:0)
[23:17:36.570] Timer tick, asking Buildbucket for the build status
[23:17:36.734] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:36.734] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:98:0) after 1m0s
[23:18:36.759] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:98:0)
[23:18:36.759] Timer tick, asking Buildbucket for the build status
[23:18:36.948] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:36.948] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:99:0) after 1m0s
[23:19:36.972] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:99:0)
[23:19:36.972] Timer tick, asking Buildbucket for the build status
[23:19:37.321] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:37.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:100:0) after 1m0s
[23:20:37.352] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:100:0)
[23:20:37.352] Timer tick, asking Buildbucket for the build status
[23:20:37.422] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:37.422] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:101:0) after 1m0s
[23:21:37.443] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:101:0)
[23:21:37.443] Timer tick, asking Buildbucket for the build status
[23:21:37.548] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:37.548] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:102:0) after 1m0s
[23:22:37.570] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:102:0)
[23:22:37.570] Timer tick, asking Buildbucket for the build status
[23:22:37.686] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:37.686] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:103:0) after 1m0s
[23:23:37.716] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:103:0)
[23:23:37.716] Timer tick, asking Buildbucket for the build status
[23:23:37.881] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:37.881] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:104:0) after 1m0s
[23:24:37.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:104:0)
[23:24:37.905] Timer tick, asking Buildbucket for the build status
[23:24:38.297] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:38.297] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:105:0) after 1m0s
[23:25:38.356] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:105:0)
[23:25:38.356] Timer tick, asking Buildbucket for the build status
[23:25:38.421] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:38.421] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:106:0) after 1m0s
[23:26:38.561] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:106:0)
[23:26:38.561] Timer tick, asking Buildbucket for the build status
[23:26:38.704] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:38.704] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:107:0) after 1m0s
[23:27:38.813] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:107:0)
[23:27:38.813] Timer tick, asking Buildbucket for the build status
[23:27:38.954] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:38.954] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:108:0) after 1m0s
[23:28:38.975] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:108:0)
[23:28:38.975] Timer tick, asking Buildbucket for the build status
[23:28:39.007] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:39.007] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:109:0) after 1m0s
[23:29:39.028] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:109:0)
[23:29:39.028] Timer tick, asking Buildbucket for the build status
[23:29:39.103] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:39.103] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:110:0) after 1m0s
[23:30:39.136] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:110:0)
[23:30:39.136] Timer tick, asking Buildbucket for the build status
[23:30:40.669] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:40.669] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:111:0) after 1m0s
[23:31:40.693] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:111:0)
[23:31:40.693] Timer tick, asking Buildbucket for the build status
[23:31:40.743] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:40.743] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:112:0) after 1m0s
[23:32:40.767] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:112:0)
[23:32:40.767] Timer tick, asking Buildbucket for the build status
[23:32:40.860] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:40.860] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:113:0) after 1m0s
[23:33:40.883] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:113:0)
[23:33:40.883] Timer tick, asking Buildbucket for the build status
[23:33:40.919] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:40.919] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:114:0) after 1m0s
[23:34:40.965] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:114:0)
[23:34:40.965] Timer tick, asking Buildbucket for the build status
[23:34:41.020] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:41.020] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:115:0) after 1m0s
[23:35:41.045] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:115:0)
[23:35:41.045] Timer tick, asking Buildbucket for the build status
[23:35:41.119] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:41.119] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:116:0) after 1m0s
[23:36:41.207] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:116:0)
[23:36:41.207] Timer tick, asking Buildbucket for the build status
[23:36:41.277] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:41.277] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:117:0) after 1m0s
[23:37:41.328] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:117:0)
[23:37:41.328] Timer tick, asking Buildbucket for the build status
[23:37:41.381] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:41.381] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:118:0) after 1m0s
[23:38:41.405] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:118:0)
[23:38:41.405] Timer tick, asking Buildbucket for the build status
[23:38:41.472] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:41.472] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:119:0) after 1m0s
[23:39:41.504] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:119:0)
[23:39:41.504] Timer tick, asking Buildbucket for the build status
[23:39:41.835] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:41.835] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:120:0) after 1m0s
[23:40:41.860] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:120:0)
[23:40:41.860] Timer tick, asking Buildbucket for the build status
[23:40:41.954] Build 8937456672582702352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:41.954] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102886734722048960:121:0) after 1m0s
[23:41:38.367] Received PubSub notification, asking Buildbucket for the build status
[23:41:38.526] Build 8937456672582702352: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:41:38.526] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1534981297562700",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1534974139572620",
"id": "8937456672582702352",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"d714188ad980100d09bcd334b3b4a43b3cdbcd3a\"}}",
"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/8937456672582702352/+/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\": [\"3740-90b257a\"], \"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\": \"9a87d6b9de35325efb08321640879e79ed0dfcc9\", \"got_swarming_client_revision\": \"486c9b53c4d54dd4b95bb6ce0e31160e600dfc11\", \"got_revision\": \"d714188ad980100d09bcd334b3b4a43b3cdbcd3a\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#24373}\", \"$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@{#585256}\", \"branch\": \"refs/heads/master\", \"revision\": \"d714188ad980100d09bcd334b3b4a43b3cdbcd3a\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"efbcb31cb67e3090b82c09ed5aabc4bbc53f37be\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"9259fd0d4f4ca335d2b0f99edb2a9e47ec053944\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1534974139572620, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/d714188ad980100d09bcd334b3b4a43b3cdbcd3a\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d714188ad980100d09bcd334b3b4a43b3cdbcd3a\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9102886734722048960\", \"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\": \"8937456672582702352\"}}, \"got_v8_revision\": \"0c05bf73b422ca9c849cec08fa23cf4589b0b508\", \"got_v8_revision_cp\": \"refs/heads/7.0.247@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 9825, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1534974239083060",
"status": "COMPLETED",
"status_changed_ts": "1534981298080190",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9825",
"builder:Windows deterministic",
"buildset:commit/git/d714188ad980100d09bcd334b3b4a43b3cdbcd3a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d714188ad980100d09bcd334b3b4a43b3cdbcd3a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9102886734722048960",
"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/9825",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8937456672582702352",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:ca2c7a741e04d0385e2bf62941ac932dc0521af3",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/d714188ad980100d09bcd334b3b4a43b3cdbcd3a",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d714188ad980100d09bcd334b3b4a43b3cdbcd3a",
"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/8937456672582702352/+/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:9102886734722048960",
"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:5abbd2c02d77dd5ecaac06e6e87a8ea54fbb8de4",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3f7c6826d9696710",
"user_agent:luci-scheduler"
],
"updated_ts": "1534981298081910",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9825",
"utcnow_ts": "1534981298462880"
}
}
[23:41:38.526] Invocation finished in 1h59m20.378470221s with status SUCCEEDED