[18:25:52.451] New invocation is queued and will start shortly
[18:25:53.717] Starting the invocation (attempt 1)
[18:25:53.751] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:25:53.751] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:25:53.751] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9099365811248738912",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"3be9707cd96ffe104f7361d77906611be20b5b0e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9099365811248738912",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/3be9707cd96ffe104f7361d77906611be20b5b0e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3be9707cd96ffe104f7361d77906611be20b5b0e",
"gitiles_ref:refs/heads/master"
]
}
[18:25:54.949] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1538331953841174",
"id": "8933935749127214688",
"lease_expiration_ts": "1538475954726886",
"lease_key": "894791528",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3be9707cd96ffe104f7361d77906611be20b5b0e\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1538331954821361",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10252",
"builder:Windows deterministic",
"buildset:commit/git/3be9707cd96ffe104f7361d77906611be20b5b0e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3be9707cd96ffe104f7361d77906611be20b5b0e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9099365811248738912",
"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/10252",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8933935749127214688",
"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/3be9707cd96ffe104f7361d77906611be20b5b0e",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3be9707cd96ffe104f7361d77906611be20b5b0e",
"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/8933935749127214688/+/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:9099365811248738912",
"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:216ff455080a4f94ea455f4ef2ee62b8417f1092",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:40448c54e5f2d310",
"user_agent:luci-scheduler"
],
"updated_ts": "1538331954821436",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10252",
"utcnow_ts": "1538331954937804"
}
}
[18:25:54.949] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10252
[18:25:54.949] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:2:0) after 1m0s
[18:25:58.063] Received PubSub notification, asking Buildbucket for the build status
[18:25:58.105] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:54.966] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:2:0)
[18:26:54.966] Timer tick, asking Buildbucket for the build status
[18:26:55.040] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:55.040] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:4:0) after 1m0s
[18:27:55.067] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:4:0)
[18:27:55.067] Timer tick, asking Buildbucket for the build status
[18:27:55.118] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:55.118] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:5:0) after 1m0s
[18:28:55.133] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:5:0)
[18:28:55.133] Timer tick, asking Buildbucket for the build status
[18:28:55.193] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:55.193] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:6:0) after 1m0s
[18:29:55.208] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:6:0)
[18:29:55.208] Timer tick, asking Buildbucket for the build status
[18:29:55.263] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:55.263] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:7:0) after 1m0s
[18:30:55.283] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:7:0)
[18:30:55.283] Timer tick, asking Buildbucket for the build status
[18:30:55.344] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:55.344] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:8:0) after 1m0s
[18:31:55.426] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:8:0)
[18:31:55.426] Timer tick, asking Buildbucket for the build status
[18:31:55.620] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:55.620] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:9:0) after 1m0s
[18:32:55.639] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:9:0)
[18:32:55.639] Timer tick, asking Buildbucket for the build status
[18:32:55.706] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:55.706] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:10:0) after 1m0s
[18:33:55.721] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:10:0)
[18:33:55.721] Timer tick, asking Buildbucket for the build status
[18:33:55.868] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:55.868] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:11:0) after 1m0s
[18:34:55.885] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:11:0)
[18:34:55.885] Timer tick, asking Buildbucket for the build status
[18:34:55.994] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:55.994] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:12:0) after 1m0s
[18:35:56.010] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:12:0)
[18:35:56.010] Timer tick, asking Buildbucket for the build status
[18:35:56.065] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:56.065] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:13:0) after 1m0s
[18:36:56.081] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:13:0)
[18:36:56.081] Timer tick, asking Buildbucket for the build status
[18:36:56.150] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:56.150] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:14:0) after 1m0s
[18:37:56.232] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:14:0)
[18:37:56.232] Timer tick, asking Buildbucket for the build status
[18:37:56.390] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:56.390] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:15:0) after 1m0s
[18:38:56.405] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:15:0)
[18:38:56.405] Timer tick, asking Buildbucket for the build status
[18:38:56.458] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:56.458] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:16:0) after 1m0s
[18:39:56.479] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:16:0)
[18:39:56.479] Timer tick, asking Buildbucket for the build status
[18:39:56.586] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:56.586] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:17:0) after 1m0s
[18:40:56.602] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:17:0)
[18:40:56.602] Timer tick, asking Buildbucket for the build status
[18:40:56.661] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:56.661] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:18:0) after 1m0s
[18:41:56.677] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:18:0)
[18:41:56.677] Timer tick, asking Buildbucket for the build status
[18:41:56.748] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:56.748] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:19:0) after 1m0s
[18:42:56.834] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:19:0)
[18:42:56.835] Timer tick, asking Buildbucket for the build status
[18:42:57.018] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:57.018] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:20:0) after 1m0s
[18:43:57.129] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:20:0)
[18:43:57.129] Timer tick, asking Buildbucket for the build status
[18:43:57.168] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:57.169] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:21:0) after 1m0s
[18:44:57.186] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:21:0)
[18:44:57.186] Timer tick, asking Buildbucket for the build status
[18:44:57.247] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:57.247] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:22:0) after 1m0s
[18:45:57.263] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:22:0)
[18:45:57.263] Timer tick, asking Buildbucket for the build status
[18:45:57.335] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:57.335] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:23:0) after 1m0s
[18:46:57.352] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:23:0)
[18:46:57.352] Timer tick, asking Buildbucket for the build status
[18:46:57.404] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:57.404] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:24:0) after 1m0s
[18:47:57.422] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:24:0)
[18:47:57.422] Timer tick, asking Buildbucket for the build status
[18:47:57.567] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:57.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:25:0) after 1m0s
[18:48:57.587] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:25:0)
[18:48:57.587] Timer tick, asking Buildbucket for the build status
[18:48:57.643] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:57.643] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:26:0) after 1m0s
[18:49:57.664] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:26:0)
[18:49:57.664] Timer tick, asking Buildbucket for the build status
[18:49:57.734] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:57.734] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:27:0) after 1m0s
[18:50:57.797] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:27:0)
[18:50:57.797] Timer tick, asking Buildbucket for the build status
[18:50:57.857] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:57.858] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:28:0) after 1m0s
[18:51:58.146] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:28:0)
[18:51:58.146] Timer tick, asking Buildbucket for the build status
[18:51:58.209] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:58.209] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:29:0) after 1m0s
[18:52:58.391] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:29:0)
[18:52:58.391] Timer tick, asking Buildbucket for the build status
[18:52:58.452] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:58.452] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:30:0) after 1m0s
[18:53:58.470] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:30:0)
[18:53:58.470] Timer tick, asking Buildbucket for the build status
[18:53:58.509] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:58.509] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:31:0) after 1m0s
[18:54:58.528] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:31:0)
[18:54:58.528] Timer tick, asking Buildbucket for the build status
[18:54:58.671] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:58.671] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:32:0) after 1m0s
[18:55:58.690] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:32:0)
[18:55:58.690] Timer tick, asking Buildbucket for the build status
[18:55:58.770] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:58.770] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:33:0) after 1m0s
[18:56:58.857] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:33:0)
[18:56:58.857] Timer tick, asking Buildbucket for the build status
[18:56:58.913] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:58.913] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:34:0) after 1m0s
[18:57:58.936] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:34:0)
[18:57:58.936] Timer tick, asking Buildbucket for the build status
[18:57:59.041] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:59.041] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:35:0) after 1m0s
[18:58:59.057] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:35:0)
[18:58:59.057] Timer tick, asking Buildbucket for the build status
[18:58:59.138] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:59.138] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:36:0) after 1m0s
[18:59:59.204] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:36:0)
[18:59:59.204] Timer tick, asking Buildbucket for the build status
[18:59:59.269] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:59.269] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:37:0) after 1m0s
[19:00:59.288] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:37:0)
[19:00:59.288] Timer tick, asking Buildbucket for the build status
[19:00:59.360] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:59.360] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:38:0) after 1m0s
[19:01:59.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:38:0)
[19:01:59.377] Timer tick, asking Buildbucket for the build status
[19:01:59.450] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:59.450] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:39:0) after 1m0s
[19:02:59.470] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:39:0)
[19:02:59.470] Timer tick, asking Buildbucket for the build status
[19:02:59.519] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:59.520] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:40:0) after 1m0s
[19:03:59.539] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:40:0)
[19:03:59.539] Timer tick, asking Buildbucket for the build status
[19:03:59.609] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:59.609] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:41:0) after 1m0s
[19:04:59.638] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:41:0)
[19:04:59.638] Timer tick, asking Buildbucket for the build status
[19:04:59.701] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:59.701] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:42:0) after 1m0s
[19:05:59.721] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:42:0)
[19:05:59.721] Timer tick, asking Buildbucket for the build status
[19:05:59.841] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:59.841] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:43:0) after 1m0s
[19:06:59.963] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:43:0)
[19:06:59.963] Timer tick, asking Buildbucket for the build status
[19:07:00.081] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:00.081] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:44:0) after 1m0s
[19:08:00.096] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:44:0)
[19:08:00.096] Timer tick, asking Buildbucket for the build status
[19:08:00.143] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:00.143] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:45:0) after 1m0s
[19:09:00.163] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:45:0)
[19:09:00.163] Timer tick, asking Buildbucket for the build status
[19:09:00.313] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:00.313] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:46:0) after 1m0s
[19:10:00.339] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:46:0)
[19:10:00.339] Timer tick, asking Buildbucket for the build status
[19:10:00.412] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:00.412] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:47:0) after 1m0s
[19:11:00.446] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:47:0)
[19:11:00.446] Timer tick, asking Buildbucket for the build status
[19:11:00.815] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:00.815] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:48:0) after 1m0s
[19:12:00.843] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:48:0)
[19:12:00.843] Timer tick, asking Buildbucket for the build status
[19:12:01.126] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:01.126] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:49:0) after 1m0s
[19:13:01.150] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:49:0)
[19:13:01.150] Timer tick, asking Buildbucket for the build status
[19:13:01.194] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:01.194] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:50:0) after 1m0s
[19:14:01.219] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:50:0)
[19:14:01.219] Timer tick, asking Buildbucket for the build status
[19:14:01.295] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:01.295] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:51:0) after 1m0s
[19:15:01.325] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:51:0)
[19:15:01.325] Timer tick, asking Buildbucket for the build status
[19:15:01.382] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:01.382] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:52:0) after 1m0s
[19:16:01.402] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:52:0)
[19:16:01.402] Timer tick, asking Buildbucket for the build status
[19:16:01.542] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:01.543] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:53:0) after 1m0s
[19:17:01.562] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:53:0)
[19:17:01.562] Timer tick, asking Buildbucket for the build status
[19:17:01.718] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:01.718] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:54:0) after 1m0s
[19:18:01.735] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:54:0)
[19:18:01.735] Timer tick, asking Buildbucket for the build status
[19:18:01.778] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:01.778] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:55:0) after 1m0s
[19:19:01.809] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:55:0)
[19:19:01.809] Timer tick, asking Buildbucket for the build status
[19:19:02.133] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:02.133] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:56:0) after 1m0s
[19:20:02.153] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:56:0)
[19:20:02.153] Timer tick, asking Buildbucket for the build status
[19:20:02.223] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:02.223] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:57:0) after 1m0s
[19:21:02.248] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:57:0)
[19:21:02.248] Timer tick, asking Buildbucket for the build status
[19:21:02.406] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:02.406] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:58:0) after 1m0s
[19:22:02.426] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:58:0)
[19:22:02.426] Timer tick, asking Buildbucket for the build status
[19:22:02.576] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:02.576] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:59:0) after 1m0s
[19:23:02.595] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:59:0)
[19:23:02.595] Timer tick, asking Buildbucket for the build status
[19:23:02.867] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:02.867] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:60:0) after 1m0s
[19:24:02.888] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:60:0)
[19:24:02.888] Timer tick, asking Buildbucket for the build status
[19:24:03.039] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:03.039] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:61:0) after 1m0s
[19:25:03.058] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:61:0)
[19:25:03.058] Timer tick, asking Buildbucket for the build status
[19:25:03.120] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:03.120] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:62:0) after 1m0s
[19:26:03.147] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:62:0)
[19:26:03.147] Timer tick, asking Buildbucket for the build status
[19:26:03.330] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:03.330] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:63:0) after 1m0s
[19:27:03.354] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:63:0)
[19:27:03.354] Timer tick, asking Buildbucket for the build status
[19:27:03.406] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:03.406] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:64:0) after 1m0s
[19:28:03.432] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:64:0)
[19:28:03.432] Timer tick, asking Buildbucket for the build status
[19:28:03.509] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:03.509] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:65:0) after 1m0s
[19:29:03.528] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:65:0)
[19:29:03.528] Timer tick, asking Buildbucket for the build status
[19:29:03.736] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:03.736] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:66:0) after 1m0s
[19:30:03.755] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:66:0)
[19:30:03.756] Timer tick, asking Buildbucket for the build status
[19:30:03.838] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:03.838] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:67:0) after 1m0s
[19:31:03.870] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:67:0)
[19:31:03.870] Timer tick, asking Buildbucket for the build status
[19:31:03.951] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:03.951] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:68:0) after 1m0s
[19:32:03.976] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:68:0)
[19:32:03.976] Timer tick, asking Buildbucket for the build status
[19:32:04.036] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:04.036] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:69:0) after 1m0s
[19:33:04.058] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:69:0)
[19:33:04.058] Timer tick, asking Buildbucket for the build status
[19:33:04.123] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:04.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:70:0) after 1m0s
[19:34:04.186] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:70:0)
[19:34:04.186] Timer tick, asking Buildbucket for the build status
[19:34:04.314] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:04.314] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:71:0) after 1m0s
[19:35:04.658] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:71:0)
[19:35:04.658] Timer tick, asking Buildbucket for the build status
[19:35:04.715] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:04.715] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:72:0) after 1m0s
[19:36:04.777] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:72:0)
[19:36:04.777] Timer tick, asking Buildbucket for the build status
[19:36:04.870] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:04.870] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:73:0) after 1m0s
[19:37:05.395] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:73:0)
[19:37:05.395] Timer tick, asking Buildbucket for the build status
[19:37:05.466] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:05.466] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:74:0) after 1m0s
[19:38:06.450] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:74:0)
[19:38:06.450] Timer tick, asking Buildbucket for the build status
[19:38:06.504] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:06.504] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:75:0) after 1m0s
[19:39:08.652] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:75:0)
[19:39:08.652] Timer tick, asking Buildbucket for the build status
[19:39:08.724] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:08.724] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:76:0) after 1m0s
[19:40:09.970] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:76:0)
[19:40:09.970] Timer tick, asking Buildbucket for the build status
[19:40:10.046] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:10.046] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:77:0) after 1m0s
[19:41:11.268] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:77:0)
[19:41:11.268] Timer tick, asking Buildbucket for the build status
[19:41:11.307] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:11.307] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:78:0) after 1m0s
[19:42:11.338] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:78:0)
[19:42:11.338] Timer tick, asking Buildbucket for the build status
[19:42:11.497] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:11.498] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:79:0) after 1m0s
[19:43:11.520] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:79:0)
[19:43:11.520] Timer tick, asking Buildbucket for the build status
[19:43:11.639] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:11.639] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:80:0) after 1m0s
[19:44:11.658] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:80:0)
[19:44:11.658] Timer tick, asking Buildbucket for the build status
[19:44:11.709] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:11.709] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:81:0) after 1m0s
[19:45:12.085] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:81:0)
[19:45:12.085] Timer tick, asking Buildbucket for the build status
[19:45:12.140] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:12.140] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:82:0) after 1m0s
[19:46:12.569] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:82:0)
[19:46:12.569] Timer tick, asking Buildbucket for the build status
[19:46:12.626] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:12.626] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:83:0) after 1m0s
[19:47:13.714] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:83:0)
[19:47:13.714] Timer tick, asking Buildbucket for the build status
[19:47:13.767] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:13.767] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:84:0) after 1m0s
[19:48:13.975] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:84:0)
[19:48:13.975] Timer tick, asking Buildbucket for the build status
[19:48:14.036] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:14.036] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:85:0) after 1m0s
[19:49:15.303] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:85:0)
[19:49:15.303] Timer tick, asking Buildbucket for the build status
[19:49:15.375] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:15.375] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:86:0) after 1m0s
[19:50:15.393] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:86:0)
[19:50:15.393] Timer tick, asking Buildbucket for the build status
[19:50:15.432] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:15.432] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:87:0) after 1m0s
[19:51:15.516] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:87:0)
[19:51:15.516] Timer tick, asking Buildbucket for the build status
[19:51:15.693] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:15.693] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:88:0) after 1m0s
[19:52:15.713] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:88:0)
[19:52:15.713] Timer tick, asking Buildbucket for the build status
[19:52:15.800] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:15.800] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:89:0) after 1m0s
[19:53:15.823] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:89:0)
[19:53:15.823] Timer tick, asking Buildbucket for the build status
[19:53:15.880] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:15.880] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:90:0) after 1m0s
[19:54:15.902] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:90:0)
[19:54:15.902] Timer tick, asking Buildbucket for the build status
[19:54:15.953] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:15.953] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:91:0) after 1m0s
[19:55:15.972] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:91:0)
[19:55:15.972] Timer tick, asking Buildbucket for the build status
[19:55:16.032] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:16.032] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:92:0) after 1m0s
[19:56:16.055] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:92:0)
[19:56:16.055] Timer tick, asking Buildbucket for the build status
[19:56:16.196] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:16.196] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:93:0) after 1m0s
[19:57:16.216] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:93:0)
[19:57:16.216] Timer tick, asking Buildbucket for the build status
[19:57:16.342] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:16.343] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:94:0) after 1m0s
[19:58:16.696] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:94:0)
[19:58:16.696] Timer tick, asking Buildbucket for the build status
[19:58:16.826] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:16.826] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:95:0) after 1m0s
[19:59:16.944] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:95:0)
[19:59:16.944] Timer tick, asking Buildbucket for the build status
[19:59:16.998] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:16.998] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:96:0) after 1m0s
[20:00:18.989] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:96:0)
[20:00:18.990] Timer tick, asking Buildbucket for the build status
[20:00:19.027] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:19.027] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:97:0) after 1m0s
[20:01:20.864] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:97:0)
[20:01:20.864] Timer tick, asking Buildbucket for the build status
[20:01:21.048] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:21.048] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:98:0) after 1m0s
[20:02:21.731] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:98:0)
[20:02:21.731] Timer tick, asking Buildbucket for the build status
[20:02:21.792] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:21.792] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:99:0) after 1m0s
[20:03:22.184] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:99:0)
[20:03:22.184] Timer tick, asking Buildbucket for the build status
[20:03:22.249] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:22.249] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:100:0) after 1m0s
[20:04:22.938] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:100:0)
[20:04:22.938] Timer tick, asking Buildbucket for the build status
[20:04:23.005] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:23.005] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:101:0) after 1m0s
[20:05:24.330] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:101:0)
[20:05:24.330] Timer tick, asking Buildbucket for the build status
[20:05:24.552] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:24.552] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:102:0) after 1m0s
[20:06:26.217] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:102:0)
[20:06:26.217] Timer tick, asking Buildbucket for the build status
[20:06:26.300] Build 8933935749127214688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:26.300] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9099365811248738912:103:0) after 1m0s
[20:07:16.511] Received PubSub notification, asking Buildbucket for the build status
[20:07:16.600] Build 8933935749127214688: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:07:16.600] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1538338035156254",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1538331953841174",
"id": "8933935749127214688",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3be9707cd96ffe104f7361d77906611be20b5b0e\"}}",
"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/8933935749127214688/+/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\": [\"3793-c7dcd56\"], \"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\": \"3be9707cd96ffe104f7361d77906611be20b5b0e\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#24875}\", \"$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@{#595354}\", \"branch\": \"refs/heads/master\", \"revision\": \"3be9707cd96ffe104f7361d77906611be20b5b0e\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"1ee9160a2e0bc6381caca2b8c42f7ce5507619bc\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"79207e6e11df06880ff0a9071479b2903d46d1b1\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1538331953841174, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/3be9707cd96ffe104f7361d77906611be20b5b0e\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3be9707cd96ffe104f7361d77906611be20b5b0e\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9099365811248738912\", \"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\": \"8933935749127214688\"}}, \"got_v8_revision\": \"ae37766fa5f1a1c4b57e2905f28664b5f1ae67d6\", \"got_v8_revision_cp\": \"refs/heads/7.1.216@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 10252, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"8fd217f2189e07f619e7512cbc81d9e0cc95f4c5\", \"notification_helper_unittests\": \"a04fb5807ec7397301261e13a71614f44596b3fc\", \"gpu_perftests\": \"9b21bc108fe6100468fc0917fc0300ea277873f6\", \"vr_common_unittests\": \"81d758c0e65711306e0b10cdc9ecabb21493aef3\", \"telemetry_unittests\": \"ac7dc73c39f11e5025e6c7a0ce92fd86e7631dec\", \"load_library_perf_tests\": \"cd9a397025594adfca72920de127a63df16a227f\", \"devtools_closure_compile\": \"47b1e90c378ab4480e13476081e61e888c71bb2f\", \"chromedriver_py_tests\": \"92843791d60d1cf1103f4e58425834ede1f63b23\", \"mojo_core_unittests\": \"ff4e2b7d10b4ce2fc80ff024e1a59df658619e48\", \"install_static_unittests\": \"19a34fa22103daa1d3dd9a53fa16429566a158ba\", \"leveldb_service_unittests\": \"caf8538344d02f14727f20ecc1b2ecc661e47e33\", \"mini_installer_tests\": \"ac18b20b5473aadcddb228a08c254bdb042fef78\", \"jingle_unittests\": \"73755fa1ce38b891eaac273d2d6a22521cde9968\", \"sbox_integration_tests\": \"74115a2b190de5200a1fab348dc3952a262699ce\", \"angle_unittests\": \"7b585b4d4d1e33540dc926a09ac683521a5fe6a2\", \"cast_unittests\": \"912f73a4673b0c0e096555199fbe5b2850f055fb\", \"cc_unittests\": \"88cfab7a8eb89c391807fa6d79c7f330ab42a548\", \"blink_common_unittests\": \"8be34d681b132f13cceaec599846ddd4a8d03af1\", \"crashpad_tests\": \"3817c8acc79062efe73e2a0cef85d54f93786f24\", \"telemetry_perf_tests_without_chrome\": \"caf400f7d35c831aa5c27daa22e23d7adfabccfc\", \"metrics_python_tests\": \"f95b0e49247ef3a6ca3e61b77ec9e90ea94bdf49\", \"message_center_unittests\": \"e4f1639b8f5b483fcac5adab37c19e1eb333a564\", \"ppapi_unittests\": \"43bd2deaa32add365fd3e326c634d24b045648c5\", \"extensions_unittests\": \"d3271b1e038867fca72e3f95e3c758662ee5b856\", \"gl_unittests_ozone\": \"5f832f85d77b7ea808530d00e0da45680c2a8e67\", \"app_shell_unittests\": \"6658d6b3da6c20623adb2c9523e45de7c136fe7f\", \"chrome_app_unittests\": \"540badffb4f1642003c826b7a63b0b3617821142\", \"gl_unittests\": \"5f832f85d77b7ea808530d00e0da45680c2a8e67\", \"gcm_unit_tests\": \"02170e90b2ad62b3da385de3d497ad6f54250d8c\", \"views_unittests\": \"0cad9fd2859b88d75c05bea14ebd3a3cabc99d55\", \"content_shell_crash_test\": \"ee61229fbe06213d4aa61e559e028b3cc5af4104\", \"ui_base_unittests\": \"41f5047834c10eddc4b868ac6669c407ce182ae0\", \"gles2_conform_test\": \"d83e1fe62426e7fb1397def8837b4aba170a2705\", \"cronet_unittests\": \"0db757d5e57c1a790ecb963671a55812264ac708\", \"performance_test_suite\": \"4a9e2134aad16c64b9ab5ede6a6d699839e3c4c3\", \"wm_unittests\": \"742aa148ac00bbf1b39aa00e3b9817cf6c74acb1\", \"boringssl_crypto_tests\": \"9586c7c633ea9bfeb831cb37c1c2da88d26c847b\", \"gin_unittests\": \"174c26eb8a1b63cbaaf02dbe4cb1d26a1ad62dca\", \"performance_browser_tests\": \"1690dbadebd84090e352b19077385f548b2063a2\", \"extensions_browsertests\": \"fd533e6399770109e8b9f7e6089921c0d1a800ee\", \"webkit_python_tests\": \"58bc7fb9a25b1b59be5f58c85ad5f8d0edf17dfe\", \"headless_unittests\": \"d24c5a727d089a17626039f73753c976ab52806e\", \"webkit_layout_tests\": \"220895fe39eebca03a14a500dd89cc8333aaf2a1\", \"vr_pixeltests\": \"49309b7f846f93d1d8597adcc56612012c427b7a\", \"setup_unittests\": \"67c39e30e15297f09d40e5b68d697a672ca22a19\", \"webkit_unit_tests\": \"4f7154029e76452376191574573323864b43f1e8\", \"content_unittests\": \"92b40b03b7282c8495fb50152c87ca973f47ca7e\", \"performance_webview_test_suite\": \"d0c71c6e540533f00e60b364e06db9cbd8eb2a3c\", \"wtf_unittests\": \"aab7f8f0d2ef34ead6b8737ad41d180e4b436fc9\", \"webkit_layout_tests_exparchive\": \"eec3c47484ad2cc11ee2ab0c706f911e26a8f1b0\", \"chrome_cleaner_unittests\": \"8ac69008328d41724648337ee213b6aacc25e4dd\", \"traffic_annotation_auditor_unittests\": \"4a1f1090a07d5655d50b45e85f162e4a52d1af25\", \"libjingle_xmpp_unittests\": \"86959065ea778de1dca8bddb86ae8116796cc2d9\", \"remoting_unittests\": \"03c97e95c91f9518dfbdbbc98d962240d5a24043\", \"vr_common_perftests\": \"bbe96b4856adf54e00909d2ce7c00732a4acb376\", \"filesystem_service_unittests\": \"9da65b026e9bd791e35cfa4f6ca9c47e1d7b34f2\", \"chrome_elf_import_unittests\": \"60e0af2119c58ffa1c11007e3ba396f9951ff1a9\", \"installer_util_unittests\": \"88cb41962b652cea80c4bf48c2dd6fc9e8a11bcf\", \"gfx_unittests\": \"40893f9f4e0e05c460152226d8ba230b3de90a54\", \"headless_browsertests\": \"aed4169f4f2592a6959bd6888c236ae8c28fdd29\", \"capture_unittests\": \"887e0b4d4226f91c1bc90f86aa942ec93085241e\", \"chrome_elf_unittests\": \"0d73faf04bb2c519e338189ddeed1bed30d72e0a\", \"telemetry_gpu_unittests\": \"467b65ba18331a11970ae9c4fbc3524f19666c98\", \"command_buffer_perftests\": \"5674b03092562385c50fa67b87da6b65b4e352c4\", \"media_unittests\": \"8a546cb76a989242a47cae63085826c8fa23d851\", \"chromedriver_unittests\": \"6122f140d98dc7f22fdfc6a8a02e728101623397\", \"views_mus_interactive_ui_tests\": \"ebd74c5f3aed63e92219351d67bf172a579729bb\", \"storage_unittests\": \"a38fabfd8daa68567b4c9b84be9a24029f52d16e\", \"media_service_unittests\": \"6fa793bbf2a162fd6a9fc0ffc04125ced279021b\", \"compositor_unittests\": \"0868f4f56651985f36e05824b853ab81a8dc3351\", \"latency_unittests\": \"fc07cd2d2399f138ae1167f341a8799070f45765\", \"video_decode_accelerator_unittest\": \"175d6599907fe4dc759d9dff73758294eae88a2e\", \"swiftshader_unittests\": \"3215d7f389f1414702f29d93246237cc5ed9956d\", \"telemetry_perf_unittests\": \"3a89b53056c3b6ae9d1b0780318a035b0e125ae3\", \"media_blink_unittests\": \"a35572b138ac4adab089ccd286bde92374fd09c3\", \"views_mus_unittests\": \"70f0400a0bf3f8c526814cf8817a85d9068805e2\", \"components_unittests\": \"20bae4b16136ef652542cdc02b9206a5cb435ff1\", \"snapshot_unittests\": \"25799f153eb43204ee40829d202f2b395d189811\", \"accessibility_unittests\": \"559c303d8f8583c9f7bb01eaa15d9053f7df047e\", \"net_perftests\": \"7c10368ce02f871ba4b6ab2e5f405cc092079f6b\", \"aura_unittests\": \"0d701902ffaf62ffcc00666c403d94690f613f58\", \"services_unittests\": \"2fc613aec84a5e83a44d3619e327fe5637b0e499\", \"components_perftests\": \"793107b8d148aed596ab084a9d3c9d7bbf4e7911\", \"events_unittests\": \"4ac397de127ad40c2a1cf947533a2dce0e7f7b18\", \"tracing_perftests\": \"a73f934a951bb56bf28054e3f8a172ead667b88a\", \"chromedriver_replay_unittests\": \"50c6aef6c493544165526b2b516579428eb4b618\", \"ui_touch_selection_unittests\": \"d7dde74e7885e4110a625a82d0e09512675809c0\", \"cacheinvalidation_unittests\": \"ea660eb18ec0d16c6da9759d386b512742397924\", \"viz_unittests\": \"37095e4a22615ddacf68109017daa8edcdd87751\", \"browser_tests\": \"7a78e7b1385afaea9758c926496975718e21ed52\", \"net_unittests\": \"ed6c6882dfa4d8d0160bfd62731f295aeaef788a\", \"midi_unittests\": \"f74d5c8d0aed098ae1d0eb038990c246e1f555c5\", \"boringssl_ssl_tests\": \"100e3ea281b8cdbcb4128b9a9e30b3afef0f3de0\", \"sbox_validation_tests\": \"a87ee11fb6fc963031d4eb12d07faac6855a2a01\", \"elevation_service_unittests\": \"842029bfc6d5980ccaaad80ac5504beb90179d76\", \"gpu_unittests\": \"da17dc541e91a182321edf70bf2a71321bc3740d\", \"native_theme_unittests\": \"7b9816040ddd12b55589cd76985c089105107351\", \"interactive_ui_tests\": \"0dcd0eeee18ebcbea8e08127f5856215c44c6c17\", \"mojo_unittests\": \"05574427dc7c2308de7fc598b05fcbdd761fb0dd\", \"url_unittests\": \"18f82182a87727c02708ce5e894958281416d43b\", \"media_perftests\": \"7ff228830ed48240379fd6b19060b135a44bafdd\", \"cronet_tests\": \"d800ec6a0c84c175cab03b980faec4084be3b230\", \"google_apis_unittests\": \"59392252036298240e8a13685b9b217f84a2eebc\", \"telemetry_gpu_integration_test\": \"80df594ba837ee49c61bec689c6ec51288b8a470\", \"blink_heap_unittests\": \"bf67961d6091522b88a0cc6c089e9e20dd16315f\", \"base_unittests\": \"585e8d2d1a59f1d7588a0393442cc00309d98b26\", \"sync_integration_tests\": \"25dba7fb0fbbf4ddcdb2ab89144115bd40b4f350\", \"sql_unittests\": \"6ef5ec0b984c77d3244a576d56a7a24ab7c31e7c\", \"courgette_unittests\": \"732de7fc3a356b0b3b8cc7869a65f36721f63965\", \"blink_fuzzer_unittests\": \"7ef9921db321518f25ec90b51afdd07075f4c418\", \"gl_tests\": \"543c2c92c36d81b78ea4f65a6458cdfc2172e453\", \"zucchini_unittests\": \"7f217fd7ac545e379d27baf2fe8cd47afdfb9605\", \"ipc_tests\": \"891bef7439f2873ab8cb746db00ea367a9881c73\", \"angle_end2end_tests\": \"4c63c956d6e4513bc8c416431ed08936a418053a\", \"blink_platform_unittests\": \"e191afb8fe6d005e66c15ee04f2c92672534bdc2\", \"components_browsertests\": \"8c44780824a146334d4310d16d05323879a49ad8\", \"views_perftests\": \"fd0eb60a81cc27d9ee3c6b833955c5d50963d191\", \"angle_perftests\": \"3f1afc9b83d41f03f18def00563724d40ec0510b\", \"display_unittests\": \"8ee31b2d13e4191360e686b910307e9b7b98a2af\", \"sbox_unittests\": \"d77d22016ba8a6d1da09f2c9846422db161699df\", \"service_manager_unittests\": \"a68618fc26abad8f4862b323b99423bca77647b3\", \"pdf_unittests\": \"df4aa64d97264e8e3ea083c9333636889d99bf6f\", \"skia_unittests\": \"84a822736229a60a0dab0a0d7d058047e1feda5d\", \"angle_white_box_tests\": \"cde1848b4b02500343b2ab16fd7eb203d5635d9e\", \"crypto_unittests\": \"1f02511a73c752fa3d805b2aae0dbe01033b8242\", \"nacl_loader_unittests\": \"7b51f0d0ed0f72fbfce11da3f99f9a9adee4290e\", \"unit_tests\": \"366092fc7f1184a81dcd91ce346ae0cd0136f33a\", \"content_browsertests\": \"4f98e2ad34d12d34430aa912a4dac261c1099920\", \"printing_unittests\": \"4c0f960581c0b4357aab22e477be7bfb9e4dd823\", \"device_unittests\": \"31fd24a011858827024f69e9f5882c264d8a05b7\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1538331956995647",
"status": "COMPLETED",
"status_changed_ts": "1538338035935940",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10252",
"builder:Windows deterministic",
"buildset:commit/git/3be9707cd96ffe104f7361d77906611be20b5b0e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3be9707cd96ffe104f7361d77906611be20b5b0e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9099365811248738912",
"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/10252",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8933935749127214688",
"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/3be9707cd96ffe104f7361d77906611be20b5b0e",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3be9707cd96ffe104f7361d77906611be20b5b0e",
"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/8933935749127214688/+/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:9099365811248738912",
"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:216ff455080a4f94ea455f4ef2ee62b8417f1092",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:40448c54e5f2d310",
"user_agent:luci-scheduler"
],
"updated_ts": "1538338035939332",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10252",
"utcnow_ts": "1538338036587698"
}
}
[20:07:16.600] Invocation finished in 1h41m24.165522416s with status SUCCEEDED