[17:34:28.152] New invocation is queued and will start shortly
[17:34:29.432] Starting the invocation (attempt 1)
[17:34:29.465] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:34:29.465] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:34:29.465] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9098825463571555552",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"6516c5894d655d132c3e8669ab25029ad55912b1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9098825463571555552",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/6516c5894d655d132c3e8669ab25029ad55912b1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6516c5894d655d132c3e8669ab25029ad55912b1",
"gitiles_ref:refs/heads/master"
]
}
[17:34:31.204] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1538847269565747",
"id": "8933395401426546240",
"lease_expiration_ts": "1538991270899091",
"lease_key": "338842464",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"6516c5894d655d132c3e8669ab25029ad55912b1\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1538847271035194",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10318",
"builder:Windows deterministic",
"buildset:commit/git/6516c5894d655d132c3e8669ab25029ad55912b1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6516c5894d655d132c3e8669ab25029ad55912b1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9098825463571555552",
"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/10318",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8933395401426546240",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4c790cbde9342069ed9b59b6709c8807b14f2913",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/6516c5894d655d132c3e8669ab25029ad55912b1",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6516c5894d655d132c3e8669ab25029ad55912b1",
"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/8933395401426546240/+/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:9098825463571555552",
"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:7a2c6a280a68ea7a8c9976d934a89c38009336b9",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4063436e6753ab10",
"user_agent:luci-scheduler"
],
"updated_ts": "1538847271035288",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10318",
"utcnow_ts": "1538847271188053"
}
}
[17:34:31.204] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10318
[17:34:31.204] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:2:0) after 1m0s
[17:34:34.388] Received PubSub notification, asking Buildbucket for the build status
[17:34:34.442] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:31.241] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:2:0)
[17:35:31.241] Timer tick, asking Buildbucket for the build status
[17:35:31.330] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:31.330] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:4:0) after 1m0s
[17:36:31.407] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:4:0)
[17:36:31.407] Timer tick, asking Buildbucket for the build status
[17:36:31.483] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:31.483] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:5:0) after 1m0s
[17:37:31.500] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:5:0)
[17:37:31.500] Timer tick, asking Buildbucket for the build status
[17:37:31.611] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:31.611] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:6:0) after 1m0s
[17:38:31.681] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:6:0)
[17:38:31.681] Timer tick, asking Buildbucket for the build status
[17:38:31.739] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:31.739] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:7:0) after 1m0s
[17:39:31.757] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:7:0)
[17:39:31.757] Timer tick, asking Buildbucket for the build status
[17:39:31.893] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:31.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:8:0) after 1m0s
[17:40:31.912] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:8:0)
[17:40:31.912] Timer tick, asking Buildbucket for the build status
[17:40:32.063] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:32.063] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:9:0) after 1m0s
[17:41:32.168] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:9:0)
[17:41:32.168] Timer tick, asking Buildbucket for the build status
[17:41:32.275] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:32.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:10:0) after 1m0s
[17:42:32.355] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:10:0)
[17:42:32.355] Timer tick, asking Buildbucket for the build status
[17:42:32.432] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:32.432] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:11:0) after 1m0s
[17:43:32.477] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:11:0)
[17:43:32.477] Timer tick, asking Buildbucket for the build status
[17:43:32.541] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:32.541] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:12:0) after 1m0s
[17:44:32.584] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:12:0)
[17:44:32.584] Timer tick, asking Buildbucket for the build status
[17:44:32.679] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:32.679] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:13:0) after 1m0s
[17:45:32.699] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:13:0)
[17:45:32.699] Timer tick, asking Buildbucket for the build status
[17:45:32.773] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:32.773] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:14:0) after 1m0s
[17:46:32.793] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:14:0)
[17:46:32.793] Timer tick, asking Buildbucket for the build status
[17:46:32.923] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:32.923] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:15:0) after 1m0s
[17:47:33.022] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:15:0)
[17:47:33.022] Timer tick, asking Buildbucket for the build status
[17:47:33.092] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:33.092] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:16:0) after 1m0s
[17:48:33.203] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:16:0)
[17:48:33.203] Timer tick, asking Buildbucket for the build status
[17:48:33.491] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:33.491] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:17:0) after 1m0s
[17:49:33.543] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:17:0)
[17:49:33.543] Timer tick, asking Buildbucket for the build status
[17:49:33.613] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:33.613] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:18:0) after 1m0s
[17:50:33.723] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:18:0)
[17:50:33.723] Timer tick, asking Buildbucket for the build status
[17:50:33.914] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:33.914] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:19:0) after 1m0s
[17:51:34.025] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:19:0)
[17:51:34.025] Timer tick, asking Buildbucket for the build status
[17:51:34.085] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:34.085] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:20:0) after 1m0s
[17:52:34.114] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:20:0)
[17:52:34.114] Timer tick, asking Buildbucket for the build status
[17:52:34.165] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:34.165] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:21:0) after 1m0s
[17:53:34.183] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:21:0)
[17:53:34.183] Timer tick, asking Buildbucket for the build status
[17:53:34.268] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:34.268] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:22:0) after 1m0s
[17:54:34.291] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:22:0)
[17:54:34.291] Timer tick, asking Buildbucket for the build status
[17:54:34.359] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:34.359] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:23:0) after 1m0s
[17:55:34.378] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:23:0)
[17:55:34.378] Timer tick, asking Buildbucket for the build status
[17:55:34.663] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:34.663] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:24:0) after 1m0s
[17:56:34.681] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:24:0)
[17:56:34.681] Timer tick, asking Buildbucket for the build status
[17:56:34.846] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:34.846] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:25:0) after 1m0s
[17:57:34.865] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:25:0)
[17:57:34.865] Timer tick, asking Buildbucket for the build status
[17:57:34.947] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:34.947] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:26:0) after 1m0s
[17:58:34.968] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:26:0)
[17:58:34.968] Timer tick, asking Buildbucket for the build status
[17:58:35.184] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:35.184] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:27:0) after 1m0s
[17:59:35.202] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:27:0)
[17:59:35.202] Timer tick, asking Buildbucket for the build status
[17:59:35.271] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:35.271] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:28:0) after 1m0s
[18:00:35.292] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:28:0)
[18:00:35.292] Timer tick, asking Buildbucket for the build status
[18:00:35.370] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:35.370] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:29:0) after 1m0s
[18:01:35.392] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:29:0)
[18:01:35.392] Timer tick, asking Buildbucket for the build status
[18:01:35.449] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:35.449] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:30:0) after 1m0s
[18:02:35.546] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:30:0)
[18:02:35.546] Timer tick, asking Buildbucket for the build status
[18:02:35.762] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:35.762] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:31:0) after 1m0s
[18:03:35.799] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:31:0)
[18:03:35.799] Timer tick, asking Buildbucket for the build status
[18:03:36.067] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:36.067] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:32:0) after 1m0s
[18:04:36.086] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:32:0)
[18:04:36.086] Timer tick, asking Buildbucket for the build status
[18:04:36.167] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:36.167] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:33:0) after 1m0s
[18:05:36.191] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:33:0)
[18:05:36.191] Timer tick, asking Buildbucket for the build status
[18:05:36.261] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:36.261] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:34:0) after 1m0s
[18:06:36.281] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:34:0)
[18:06:36.281] Timer tick, asking Buildbucket for the build status
[18:06:36.671] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:36.672] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:35:0) after 1m0s
[18:07:36.720] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:35:0)
[18:07:36.720] Timer tick, asking Buildbucket for the build status
[18:07:36.835] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:36.835] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:36:0) after 1m0s
[18:08:36.854] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:36:0)
[18:08:36.854] Timer tick, asking Buildbucket for the build status
[18:08:36.953] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:36.953] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:37:0) after 1m0s
[18:09:36.972] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:37:0)
[18:09:36.972] Timer tick, asking Buildbucket for the build status
[18:09:37.051] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:37.051] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:38:0) after 1m0s
[18:10:37.084] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:38:0)
[18:10:37.084] Timer tick, asking Buildbucket for the build status
[18:10:37.336] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:37.336] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:39:0) after 1m0s
[18:11:37.360] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:39:0)
[18:11:37.360] Timer tick, asking Buildbucket for the build status
[18:11:37.512] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:37.512] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:40:0) after 1m0s
[18:12:37.601] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:40:0)
[18:12:37.601] Timer tick, asking Buildbucket for the build status
[18:12:37.653] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:37.653] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:41:0) after 1m0s
[18:13:37.672] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:41:0)
[18:13:37.672] Timer tick, asking Buildbucket for the build status
[18:13:37.829] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:37.829] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:42:0) after 1m0s
[18:14:37.850] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:42:0)
[18:14:37.850] Timer tick, asking Buildbucket for the build status
[18:14:37.926] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:37.926] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:43:0) after 1m0s
[18:15:37.961] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:43:0)
[18:15:37.961] Timer tick, asking Buildbucket for the build status
[18:15:38.037] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:38.037] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:44:0) after 1m0s
[18:16:38.057] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:44:0)
[18:16:38.057] Timer tick, asking Buildbucket for the build status
[18:16:38.196] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:38.196] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:45:0) after 1m0s
[18:17:38.230] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:45:0)
[18:17:38.230] Timer tick, asking Buildbucket for the build status
[18:17:38.285] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:38.285] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:46:0) after 1m0s
[18:18:38.310] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:46:0)
[18:18:38.310] Timer tick, asking Buildbucket for the build status
[18:18:38.389] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:38.389] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:47:0) after 1m0s
[18:19:38.414] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:47:0)
[18:19:38.414] Timer tick, asking Buildbucket for the build status
[18:19:38.460] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:38.460] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:48:0) after 1m0s
[18:20:38.481] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:48:0)
[18:20:38.481] Timer tick, asking Buildbucket for the build status
[18:20:38.589] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:38.589] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:49:0) after 1m0s
[18:21:38.608] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:49:0)
[18:21:38.608] Timer tick, asking Buildbucket for the build status
[18:21:38.803] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:38.803] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:50:0) after 1m0s
[18:22:38.826] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:50:0)
[18:22:38.826] Timer tick, asking Buildbucket for the build status
[18:22:38.995] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:38.995] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:51:0) after 1m0s
[18:23:39.131] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:51:0)
[18:23:39.131] Timer tick, asking Buildbucket for the build status
[18:23:39.212] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:39.212] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:52:0) after 1m0s
[18:24:39.322] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:52:0)
[18:24:39.322] Timer tick, asking Buildbucket for the build status
[18:24:39.619] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:39.619] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:53:0) after 1m0s
[18:25:39.641] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:53:0)
[18:25:39.641] Timer tick, asking Buildbucket for the build status
[18:25:39.745] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:39.745] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:54:0) after 1m0s
[18:26:39.765] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:54:0)
[18:26:39.765] Timer tick, asking Buildbucket for the build status
[18:26:39.825] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:39.825] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:55:0) after 1m0s
[18:27:39.845] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:55:0)
[18:27:39.845] Timer tick, asking Buildbucket for the build status
[18:27:40.124] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:40.124] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:56:0) after 1m0s
[18:28:40.181] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:56:0)
[18:28:40.181] Timer tick, asking Buildbucket for the build status
[18:28:40.230] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:40.231] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:57:0) after 1m0s
[18:29:40.317] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:57:0)
[18:29:40.317] Timer tick, asking Buildbucket for the build status
[18:29:40.473] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:40.473] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:58:0) after 1m0s
[18:30:40.497] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:58:0)
[18:30:40.497] Timer tick, asking Buildbucket for the build status
[18:30:40.689] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:40.689] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:59:0) after 1m0s
[18:31:40.734] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:59:0)
[18:31:40.734] Timer tick, asking Buildbucket for the build status
[18:31:40.789] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:40.789] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:60:0) after 1m0s
[18:32:40.850] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:60:0)
[18:32:40.850] Timer tick, asking Buildbucket for the build status
[18:32:41.232] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:41.232] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:61:0) after 1m0s
[18:33:41.250] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:61:0)
[18:33:41.250] Timer tick, asking Buildbucket for the build status
[18:33:41.305] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:41.305] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:62:0) after 1m0s
[18:34:41.325] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:62:0)
[18:34:41.325] Timer tick, asking Buildbucket for the build status
[18:34:41.427] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:41.427] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:63:0) after 1m0s
[18:35:41.445] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:63:0)
[18:35:41.445] Timer tick, asking Buildbucket for the build status
[18:35:41.553] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:41.553] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:64:0) after 1m0s
[18:36:41.572] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:64:0)
[18:36:41.572] Timer tick, asking Buildbucket for the build status
[18:36:41.664] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:41.664] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:65:0) after 1m0s
[18:37:41.694] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:65:0)
[18:37:41.694] Timer tick, asking Buildbucket for the build status
[18:37:41.784] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:41.784] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:66:0) after 1m0s
[18:38:41.804] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:66:0)
[18:38:41.804] Timer tick, asking Buildbucket for the build status
[18:38:42.073] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:42.073] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:67:0) after 1m0s
[18:39:42.093] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:67:0)
[18:39:42.093] Timer tick, asking Buildbucket for the build status
[18:39:42.195] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:42.195] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:68:0) after 1m0s
[18:40:42.237] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:68:0)
[18:40:42.237] Timer tick, asking Buildbucket for the build status
[18:40:42.523] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:42.523] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:69:0) after 1m0s
[18:41:42.543] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:69:0)
[18:41:42.543] Timer tick, asking Buildbucket for the build status
[18:41:42.712] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:42.712] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:70:0) after 1m0s
[18:42:42.735] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:70:0)
[18:42:42.735] Timer tick, asking Buildbucket for the build status
[18:42:42.879] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:42.879] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:71:0) after 1m0s
[18:43:42.898] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:71:0)
[18:43:42.898] Timer tick, asking Buildbucket for the build status
[18:43:42.970] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:42.970] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:72:0) after 1m0s
[18:44:43.083] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:72:0)
[18:44:43.083] Timer tick, asking Buildbucket for the build status
[18:44:43.203] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:43.203] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:73:0) after 1m0s
[18:45:43.223] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:73:0)
[18:45:43.223] Timer tick, asking Buildbucket for the build status
[18:45:43.345] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:43.345] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:74:0) after 1m0s
[18:46:43.368] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:74:0)
[18:46:43.368] Timer tick, asking Buildbucket for the build status
[18:46:43.416] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:43.416] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:75:0) after 1m0s
[18:47:43.439] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:75:0)
[18:47:43.439] Timer tick, asking Buildbucket for the build status
[18:47:43.660] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:43.660] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:76:0) after 1m0s
[18:48:43.682] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:76:0)
[18:48:43.682] Timer tick, asking Buildbucket for the build status
[18:48:43.848] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:43.848] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:77:0) after 1m0s
[18:49:43.868] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:77:0)
[18:49:43.868] Timer tick, asking Buildbucket for the build status
[18:49:44.049] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:44.049] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:78:0) after 1m0s
[18:50:44.086] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:78:0)
[18:50:44.086] Timer tick, asking Buildbucket for the build status
[18:50:44.151] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:44.151] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:79:0) after 1m0s
[18:51:44.228] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:79:0)
[18:51:44.228] Timer tick, asking Buildbucket for the build status
[18:51:44.307] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:44.307] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:80:0) after 1m0s
[18:52:44.329] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:80:0)
[18:52:44.329] Timer tick, asking Buildbucket for the build status
[18:52:44.374] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:44.374] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:81:0) after 1m0s
[18:53:44.519] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:81:0)
[18:53:44.519] Timer tick, asking Buildbucket for the build status
[18:53:44.721] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:44.721] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:82:0) after 1m0s
[18:54:44.743] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:82:0)
[18:54:44.743] Timer tick, asking Buildbucket for the build status
[18:54:44.836] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:44.836] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:83:0) after 1m0s
[18:55:44.867] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:83:0)
[18:55:44.867] Timer tick, asking Buildbucket for the build status
[18:55:44.915] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:44.915] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:84:0) after 1m0s
[18:56:44.984] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:84:0)
[18:56:44.984] Timer tick, asking Buildbucket for the build status
[18:56:45.330] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:45.330] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:85:0) after 1m0s
[18:57:45.433] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:85:0)
[18:57:45.433] Timer tick, asking Buildbucket for the build status
[18:57:45.635] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:45.635] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:86:0) after 1m0s
[18:58:45.671] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:86:0)
[18:58:45.671] Timer tick, asking Buildbucket for the build status
[18:58:45.743] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:45.743] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:87:0) after 1m0s
[18:59:45.765] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:87:0)
[18:59:45.765] Timer tick, asking Buildbucket for the build status
[18:59:45.842] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:45.842] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:88:0) after 1m0s
[19:00:45.871] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:88:0)
[19:00:45.871] Timer tick, asking Buildbucket for the build status
[19:00:45.946] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:45.946] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:89:0) after 1m0s
[19:01:45.977] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:89:0)
[19:01:45.977] Timer tick, asking Buildbucket for the build status
[19:01:46.121] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:46.121] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:90:0) after 1m0s
[19:02:46.143] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:90:0)
[19:02:46.144] Timer tick, asking Buildbucket for the build status
[19:02:46.215] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:46.215] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:91:0) after 1m0s
[19:03:46.240] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:91:0)
[19:03:46.240] Timer tick, asking Buildbucket for the build status
[19:03:46.419] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:46.419] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:92:0) after 1m0s
[19:04:46.470] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:92:0)
[19:04:46.470] Timer tick, asking Buildbucket for the build status
[19:04:46.534] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:46.534] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:93:0) after 1m0s
[19:05:46.559] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:93:0)
[19:05:46.559] Timer tick, asking Buildbucket for the build status
[19:05:46.686] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:46.686] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:94:0) after 1m0s
[19:06:46.730] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:94:0)
[19:06:46.730] Timer tick, asking Buildbucket for the build status
[19:06:46.794] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:46.794] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:95:0) after 1m0s
[19:07:46.831] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:95:0)
[19:07:46.831] Timer tick, asking Buildbucket for the build status
[19:07:46.914] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:46.914] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:96:0) after 1m0s
[19:08:46.935] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:96:0)
[19:08:46.935] Timer tick, asking Buildbucket for the build status
[19:08:47.020] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:47.020] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:97:0) after 1m0s
[19:09:47.044] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:97:0)
[19:09:47.044] Timer tick, asking Buildbucket for the build status
[19:09:47.418] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:47.418] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:98:0) after 1m0s
[19:10:47.485] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:98:0)
[19:10:47.485] Timer tick, asking Buildbucket for the build status
[19:10:47.553] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:47.553] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:99:0) after 1m0s
[19:11:47.580] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:99:0)
[19:11:47.580] Timer tick, asking Buildbucket for the build status
[19:11:47.701] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:47.701] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:100:0) after 1m0s
[19:12:47.742] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:100:0)
[19:12:47.742] Timer tick, asking Buildbucket for the build status
[19:12:47.801] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:47.801] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:101:0) after 1m0s
[19:13:47.824] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:101:0)
[19:13:47.824] Timer tick, asking Buildbucket for the build status
[19:13:47.863] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:47.863] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:102:0) after 1m0s
[19:14:47.883] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:102:0)
[19:14:47.883] Timer tick, asking Buildbucket for the build status
[19:14:47.972] Build 8933395401426546240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:47.972] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098825463571555552:103:0) after 1m0s
[19:15:39.338] Received PubSub notification, asking Buildbucket for the build status
[19:15:39.422] Build 8933395401426546240: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:15:39.422] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1538853337669221",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1538847269565747",
"id": "8933395401426546240",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"6516c5894d655d132c3e8669ab25029ad55912b1\"}}",
"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/8933395401426546240/+/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\": [\"3801-8761768\"], \"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\": \"2ebbdab4bfb1ed9dfe52ce756b34829207dbe25f\", \"got_swarming_client_revision\": \"486c9b53c4d54dd4b95bb6ce0e31160e600dfc11\", \"got_revision\": \"6516c5894d655d132c3e8669ab25029ad55912b1\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#25030}\", \"$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@{#597436}\", \"branch\": \"refs/heads/master\", \"revision\": \"6516c5894d655d132c3e8669ab25029ad55912b1\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"e53341c1dc6ebf11fc879dfe914a0eb31c29ca7c\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"450bf36f7918d18f3186be8ce570d9eac63b309d\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1538847269565747, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/6516c5894d655d132c3e8669ab25029ad55912b1\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6516c5894d655d132c3e8669ab25029ad55912b1\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9098825463571555552\", \"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\": \"8933395401426546240\"}}, \"got_v8_revision\": \"3c5d491ae09e55918dc954152e61fbcd86d4adb5\", \"got_v8_revision_cp\": \"refs/heads/7.1.275@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 10318, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"2a41e18f4ecd620e16c8f2c3bb21f2c7014f60e9\", \"notification_helper_unittests\": \"db6551c15d83f7f93beb822907291aebe6d4104c\", \"gpu_perftests\": \"160b58c0da90fee83aff4caaebc20be323b8862b\", \"vr_common_unittests\": \"804dbd2c7d099f9128d35a19d7d227d7cafd7cf7\", \"telemetry_unittests\": \"b71b302d4429efe5e908bcdaac747ebe63b554b1\", \"load_library_perf_tests\": \"7b5f68a450f018c5c6ec5a0555916684e334b573\", \"devtools_closure_compile\": \"947ceeb4ceaa909d325ac331beebc7cb1b6ac0a7\", \"chromedriver_py_tests\": \"bb104f21b5fd2f8ed9d223e0260c1006dc41124a\", \"mojo_core_unittests\": \"2e41cce0117970fe692237d7dcf6a29d308bbeab\", \"install_static_unittests\": \"2764fefbfcdd127190771d78535f772b72274f26\", \"leveldb_service_unittests\": \"40e61c80617a2223105c36b5f49dd16aa7a5ad66\", \"mini_installer_tests\": \"9a4744e723c2af4b67807454461580fc63e37f76\", \"jingle_unittests\": \"8356d9ef2c267d83927ee25292445bee26e415e4\", \"sbox_integration_tests\": \"8c1c1c5376dc16d23fd3195d3d49b1a25172f74c\", \"angle_unittests\": \"fdb4facbe3943a6e18c603100d56dc0bbbfd1333\", \"cast_unittests\": \"0b9d676156ab1c4ccd1a95f9950e75c81862895c\", \"cc_unittests\": \"209e74c04f00c6eef19c49097822fed260c39914\", \"blink_common_unittests\": \"ca70ee13f2221bac29865200495b75ee02dbba2b\", \"crashpad_tests\": \"f48764fdb3ad7cd7a7b17608ab088b55b2c50d06\", \"telemetry_perf_tests_without_chrome\": \"7c95e2892f0a913c0e1eb5668991e57bce3e134e\", \"metrics_python_tests\": \"b4e4090d7c42df6b958c4a40710412bed7ee9ad6\", \"message_center_unittests\": \"e22c412fa703a8e7269a2b7d5200b34353bc7b6f\", \"ppapi_unittests\": \"8c2a2b5e2e1709456bbe758e45b88ed2d343a881\", \"extensions_unittests\": \"251f05309f7df40324cbcc96b3a8dbf7bbdd5ece\", \"gl_unittests_ozone\": \"0cfd76a3e1f0d76197409a03085137a4c252315b\", \"app_shell_unittests\": \"7a2c9874bd52423b18fdb74cf03b736ee9268f1c\", \"chrome_app_unittests\": \"55c7ea5713875773c359f6a80a890482ce500a8a\", \"gl_unittests\": \"0cfd76a3e1f0d76197409a03085137a4c252315b\", \"gcm_unit_tests\": \"e126b656cdccde26881919f72b1d4645683ef959\", \"views_unittests\": \"521f8a9630e53ea5e3aed8b7facc6b9ae2759c23\", \"content_shell_crash_test\": \"2a46f5edbdd659fb7413eccb967fa8de57708c24\", \"ui_base_unittests\": \"32b8d68e6dd5be18f430acbc609fb7076caa916f\", \"gles2_conform_test\": \"a7a6cfba9dfbd5055445b1c94e523bb685e3d47e\", \"cronet_unittests\": \"4d4a671414cbff89bb3570e57fc64f2562c97a78\", \"performance_test_suite\": \"b639adb3fa8f2a9dfd00779afd5734d6c93cc987\", \"wm_unittests\": \"e668c40c2226d07c19f260557ad98278c4a0c8c0\", \"boringssl_crypto_tests\": \"76b00aa8b78058f21ddd777e5fc991a9f7faf440\", \"gin_unittests\": \"948a917248428e6ff0517bc163a85721a7aa00d9\", \"performance_browser_tests\": \"b516f18387ce22f2b03d304b606b84ee14e8253e\", \"extensions_browsertests\": \"e150cf54013c3623a14ee60cc23d5e4278ace4e5\", \"webkit_python_tests\": \"1182026ac5ebb0aa298541a05a56d3d287256f41\", \"headless_unittests\": \"0501294717fd2b5c1b06ae54f1ee7a5967b6cc16\", \"webkit_layout_tests\": \"8187f680df1a7f464297827786e664e6491034d2\", \"vr_pixeltests\": \"55082c5a186d6f893910f6f96456c185a71e692c\", \"setup_unittests\": \"fd2000c7aa987293a627d640b3c9978ac30e8184\", \"webkit_unit_tests\": \"323202d120168236e6945abe0f6ab673d55ceb14\", \"content_unittests\": \"372400d9d1afb1165848261a0e3d49185f7a30d4\", \"performance_webview_test_suite\": \"97dd9f9870e014251165d5d7a91b5270fe48b008\", \"wtf_unittests\": \"adea6a8727c5e6a904230e12b634d19691bb8a04\", \"webkit_layout_tests_exparchive\": \"43d49aa3d4cb5a3c7fc7c2c29d0fd04657bc44f3\", \"chrome_cleaner_unittests\": \"d63620bb286f5d51fbb7213902cdbd217fd04b44\", \"traffic_annotation_auditor_unittests\": \"8f616183037e13e45d0da0dbc13bdd0d1f031279\", \"libjingle_xmpp_unittests\": \"5243e0023cc94931975d8a1048e63f1e19eb20ad\", \"remoting_unittests\": \"7f0c2968544a6d9cd07e153970251ef24f39b564\", \"vr_common_perftests\": \"e186e6cf4244f52ca06420e2148a0a5260982560\", \"filesystem_service_unittests\": \"41d97b8acd4c80a5326eadd1e453617324424f63\", \"chrome_elf_import_unittests\": \"aaa075f6ce1690979503305171bb4ce4a7e2b43e\", \"installer_util_unittests\": \"0efb76795ecf0e45dcdfd2631d0dd196bc119f24\", \"gfx_unittests\": \"1b8eb37297e1507bb3afc7c7cd639e620525c97c\", \"headless_browsertests\": \"d5d3c61fe286cf6da7cb6284ef5c28e35028ef6a\", \"capture_unittests\": \"4a37f1ad3a64ee909add2b0f385d7af81e5e1732\", \"chrome_elf_unittests\": \"2609a0dbfe658e51621f6a75eee168bd08194e90\", \"telemetry_gpu_unittests\": \"74deb9a1eacc0a042f05d8bc40d1ee07a6fc879d\", \"command_buffer_perftests\": \"cf4b36f95e1080b5fdc74ae9c7d442357c52414a\", \"media_unittests\": \"24e0d3335b42dea7b46470f55e461b942ee20739\", \"chromedriver_unittests\": \"f4f216951ea8e73c98d8426c6e450c24dcd6bee6\", \"views_mus_interactive_ui_tests\": \"47c9a5dc8a72f52745a807aef7fd61f9b1ac8332\", \"storage_unittests\": \"3532eead94ffde6905c080fcc8f1327cf45892cd\", \"media_service_unittests\": \"fb41b1b1c1b18b81d9dcfbcbe48a697137e7d28f\", \"compositor_unittests\": \"ab60b2544bf7e4acc8df712c2323d2520e765dcd\", \"latency_unittests\": \"bd12e78de8383d5f332ce5d1b061603fd60b28ac\", \"video_decode_accelerator_unittest\": \"38f029eddb78d7f378aa106c0efc02a8ee7dd9f8\", \"swiftshader_unittests\": \"5d7fddbc688528b7f4c07ebd04fc2e61773d1829\", \"telemetry_perf_unittests\": \"c385b53d5a791e273060e04413eae32a19b43614\", \"media_blink_unittests\": \"c00fb91e45a86783c326da7b1825d03b4a1c7045\", \"views_mus_unittests\": \"8d1b1ccb7f12ff06ec46a095858c202adb7c8132\", \"components_unittests\": \"826c5ba9026ead73f5d5c8f0fa80079b78ecd2ca\", \"snapshot_unittests\": \"54d02b7b1e42ad4649c9f4c3553d86c809313731\", \"accessibility_unittests\": \"0072e40fef83e6cc2b59d802adb425adea04df05\", \"net_perftests\": \"63fa1d2ab99296da2e034e7b2818772c8fddafd2\", \"aura_unittests\": \"ce68e0fb15a54ec8739eded2acfcb9da28df4e7e\", \"services_unittests\": \"2badd2e0fdc3efbc534a553265ace7df56e4f27a\", \"components_perftests\": \"926f0d48c2a75cb4c7ae766deb0d5f2be61a0daf\", \"events_unittests\": \"748e6d39ed369ee94b18c6cd782e009757052cca\", \"tracing_perftests\": \"adb32e69723867aa1f257ae85d17e8aad864bfba\", \"chromedriver_replay_unittests\": \"5013a0f078eac50a549885e896698d163bf73605\", \"ui_touch_selection_unittests\": \"2a851f2f03f6f558ac611742029dfb6918d28890\", \"cacheinvalidation_unittests\": \"abe6765495f6b4a7a28bd6c064941c4127771e4d\", \"viz_unittests\": \"bfdd70f94d428edd511347ea28520d20b93a47d0\", \"browser_tests\": \"ec508bc7b9a29f21354bed8f29b4d47d2754b8db\", \"net_unittests\": \"d91b4f01313733dd7f7b87f2a7b0c2b8123b25e0\", \"midi_unittests\": \"31e585719d714b50806a2148d3ee191e8d46e0f2\", \"boringssl_ssl_tests\": \"04a7c75a795271bcf061f279bd666ca2e87e74a5\", \"sbox_validation_tests\": \"69ac120f79c78f51972058640fb5b4d217762f86\", \"elevation_service_unittests\": \"0d6f4af9b181ef372c7cd8ece928c07579c1c76c\", \"gpu_unittests\": \"a78e742e36b918abe7da01aeed0f609470ddcefa\", \"native_theme_unittests\": \"ed00408cc55944221f538c9af3a75ade6cfdbb7a\", \"interactive_ui_tests\": \"25b0249a3737772202b484a75c3c86c09463fd55\", \"mojo_unittests\": \"b5dd206bcc42cc3eee2f2e5827ead0d9d48ca861\", \"url_unittests\": \"0040fd56aeee8bf440880b665f2bbd9525ee4a64\", \"media_perftests\": \"0443b40fc4f87d5fed0b5e9a8a20f20f2c2336b9\", \"cronet_tests\": \"cb4fefe4da4dd51c18e9daa856c7ab2ce0ed3e2a\", \"google_apis_unittests\": \"ad90a4e62978db01a43221b9fda2119b20b14cc8\", \"telemetry_gpu_integration_test\": \"94c9c84292b38470a30869a3b8315983b6e52aa3\", \"blink_heap_unittests\": \"c70e2d0d92c95f972483106e357f3fadecad62fb\", \"base_unittests\": \"a581a8c3498df7252d51af08d0b4a9a48907b258\", \"sync_integration_tests\": \"daeacf54c14fd88ec1594728b3596747d19713d1\", \"sql_unittests\": \"0badaff5a06e6e3956974729ce35b1960d55faec\", \"courgette_unittests\": \"b0b685f3602093638ab944e10dc0bb170c1f5144\", \"blink_fuzzer_unittests\": \"ce81997e1bdb7be87769009a023afce357ea5c79\", \"gl_tests\": \"cd66fc8ad85544d82849e015d4cf567dd8517d99\", \"zucchini_unittests\": \"c8a85a4e06570b0bb0689510c28479f8f3424615\", \"ipc_tests\": \"1cd12a80ce476a167f58ec611a0c2c8ca178dd07\", \"angle_end2end_tests\": \"9042135d1666db3e6dacdca67fe5987ecd5aac9a\", \"blink_platform_unittests\": \"7ac61ca12336fd9e8b7a001fbab256151450647e\", \"components_browsertests\": \"18cf522d17bb6430a18538d05ce1fa7bf179dc9c\", \"views_perftests\": \"babc18b0bab47f8d377ad59443187a5788e10266\", \"angle_perftests\": \"04ab3288c69aad103e1948f85f1a6337b5b995ce\", \"display_unittests\": \"1b8fd6ece5c2c055d384ee6178d3ec2440ca6cf3\", \"sbox_unittests\": \"108659137e672b3c7182355d009b76f9999182d5\", \"service_manager_unittests\": \"5a79fc2a9213f92ff9c6f27d08d9d96dad1a08c0\", \"pdf_unittests\": \"e1c3ebacd2710995edf84508bba7cace8f52b9f1\", \"skia_unittests\": \"352425939224f8f0529d7656da7705d916e034dc\", \"angle_white_box_tests\": \"0eb176a27cccb49efab8ef1d82221d746b85356a\", \"crypto_unittests\": \"d12d2e9e29559748b18d0f28925b03474c16ba48\", \"nacl_loader_unittests\": \"43b6471fad65cc5e89e676958e7473b064489e94\", \"unit_tests\": \"ad443130bd83311ec45d12cb34c50eb228953673\", \"content_browsertests\": \"20fad7cce7019e678235d8a557f91628b13eb4be\", \"printing_unittests\": \"1ab8f6d1279ca80450ac563824ddece58b2593d6\", \"device_unittests\": \"be51b5d6189aa29e9882f445572bff8361dce6cb\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1538847273499883",
"status": "COMPLETED",
"status_changed_ts": "1538853338452176",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10318",
"builder:Windows deterministic",
"buildset:commit/git/6516c5894d655d132c3e8669ab25029ad55912b1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6516c5894d655d132c3e8669ab25029ad55912b1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9098825463571555552",
"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/10318",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8933395401426546240",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4c790cbde9342069ed9b59b6709c8807b14f2913",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/6516c5894d655d132c3e8669ab25029ad55912b1",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6516c5894d655d132c3e8669ab25029ad55912b1",
"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/8933395401426546240/+/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:9098825463571555552",
"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:7a2c6a280a68ea7a8c9976d934a89c38009336b9",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4063436e6753ab10",
"user_agent:luci-scheduler"
],
"updated_ts": "1538853338453775",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10318",
"utcnow_ts": "1538853339407777"
}
}
[19:15:39.422] Invocation finished in 1h41m11.285904851s with status SUCCEEDED