[00:36:22.198] New invocation is queued and will start shortly
[00:36:23.453] Starting the invocation (attempt 1)
[00:36:23.482] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:36:23.482] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:36:23.482] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9089195641431921984",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"769fdf3e274e1d4663711cc96373ca5fb776e1ae\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9089195641431921984",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/769fdf3e274e1d4663711cc96373ca5fb776e1ae",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/769fdf3e274e1d4663711cc96373ca5fb776e1ae",
"gitiles_ref:refs/heads/master"
]
}
[00:36:25.482] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1548030983601665",
"id": "8923765579297634160",
"lease_expiration_ts": "1548149785028812",
"lease_key": "2088833573",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"769fdf3e274e1d4663711cc96373ca5fb776e1ae\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1548030985296208",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11688",
"builder:Windows deterministic",
"buildset:commit/git/769fdf3e274e1d4663711cc96373ca5fb776e1ae",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/769fdf3e274e1d4663711cc96373ca5fb776e1ae",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9089195641431921984",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Windows deterministic/11688",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8923765579297634160",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:1",
"swarming_tag:buildbucket_template_revision:01134cf47e8a2d5d7cea5ec1232ca64ed2f16a5c",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/769fdf3e274e1d4663711cc96373ca5fb776e1ae",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/769fdf3e274e1d4663711cc96373ca5fb776e1ae",
"swarming_tag:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8923765579297634160/+/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:9089195641431921984",
"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:5746c1addf0a4c286f58c69ab0c42aedaac6b5ff",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4286a7c9487db410",
"user_agent:luci-scheduler"
],
"updated_ts": "1548030985296288",
"url": "https://ci.chromium.org/b/8923765579297634160",
"utcnow_ts": "1548030985453842"
}
}
[00:36:25.482] Task URL: https://ci.chromium.org/b/8923765579297634160
[00:36:25.482] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:2:0) after 1m0s
[00:36:27.198] Received PubSub notification, asking Buildbucket for the build status
[00:36:27.239] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:25.499] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:2:0)
[00:37:25.499] Timer tick, asking Buildbucket for the build status
[00:37:25.551] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:25.551] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:4:0) after 1m0s
[00:38:25.574] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:4:0)
[00:38:25.574] Timer tick, asking Buildbucket for the build status
[00:38:25.628] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:25.628] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:5:0) after 1m0s
[00:39:25.665] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:5:0)
[00:39:25.665] Timer tick, asking Buildbucket for the build status
[00:39:25.820] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:25.820] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:6:0) after 1m0s
[00:40:25.836] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:6:0)
[00:40:25.836] Timer tick, asking Buildbucket for the build status
[00:40:25.894] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:25.894] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:7:0) after 1m0s
[00:41:25.915] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:7:0)
[00:41:25.915] Timer tick, asking Buildbucket for the build status
[00:41:26.040] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:26.040] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:8:0) after 1m0s
[00:42:26.057] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:8:0)
[00:42:26.057] Timer tick, asking Buildbucket for the build status
[00:42:26.124] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:26.124] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:9:0) after 1m0s
[00:43:26.221] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:9:0)
[00:43:26.221] Timer tick, asking Buildbucket for the build status
[00:43:26.292] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:26.292] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:10:0) after 1m0s
[00:44:26.312] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:10:0)
[00:44:26.312] Timer tick, asking Buildbucket for the build status
[00:44:26.394] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:26.394] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:11:0) after 1m0s
[00:45:26.438] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:11:0)
[00:45:26.438] Timer tick, asking Buildbucket for the build status
[00:45:26.505] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:26.505] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:12:0) after 1m0s
[00:46:26.552] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:12:0)
[00:46:26.552] Timer tick, asking Buildbucket for the build status
[00:46:26.614] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:26.614] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:13:0) after 1m0s
[00:47:26.629] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:13:0)
[00:47:26.629] Timer tick, asking Buildbucket for the build status
[00:47:26.670] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:26.670] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:14:0) after 1m0s
[00:48:26.684] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:14:0)
[00:48:26.684] Timer tick, asking Buildbucket for the build status
[00:48:26.829] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:26.829] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:15:0) after 1m0s
[00:49:26.845] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:15:0)
[00:49:26.845] Timer tick, asking Buildbucket for the build status
[00:49:26.903] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:26.903] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:16:0) after 1m0s
[00:50:26.920] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:16:0)
[00:50:26.920] Timer tick, asking Buildbucket for the build status
[00:50:27.006] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:27.006] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:17:0) after 1m0s
[00:51:27.037] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:17:0)
[00:51:27.037] Timer tick, asking Buildbucket for the build status
[00:51:27.193] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:27.193] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:18:0) after 1m0s
[00:52:27.207] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:18:0)
[00:52:27.207] Timer tick, asking Buildbucket for the build status
[00:52:27.331] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:27.331] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:19:0) after 1m0s
[00:53:27.345] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:19:0)
[00:53:27.345] Timer tick, asking Buildbucket for the build status
[00:53:27.376] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:27.376] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:20:0) after 1m0s
[00:54:27.477] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:20:0)
[00:54:27.477] Timer tick, asking Buildbucket for the build status
[00:54:27.547] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:27.547] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:21:0) after 1m0s
[00:55:27.562] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:21:0)
[00:55:27.562] Timer tick, asking Buildbucket for the build status
[00:55:27.639] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:27.639] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:22:0) after 1m0s
[00:56:27.666] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:22:0)
[00:56:27.666] Timer tick, asking Buildbucket for the build status
[00:56:27.729] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:27.729] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:23:0) after 1m0s
[00:57:27.751] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:23:0)
[00:57:27.751] Timer tick, asking Buildbucket for the build status
[00:57:27.799] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:27.799] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:24:0) after 1m0s
[00:58:27.814] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:24:0)
[00:58:27.814] Timer tick, asking Buildbucket for the build status
[00:58:27.870] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:27.870] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:25:0) after 1m0s
[00:59:27.888] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:25:0)
[00:59:27.888] Timer tick, asking Buildbucket for the build status
[00:59:27.951] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:27.951] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:26:0) after 1m0s
[01:00:27.973] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:26:0)
[01:00:27.973] Timer tick, asking Buildbucket for the build status
[01:00:28.043] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:28.043] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:27:0) after 1m0s
[01:01:28.118] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:27:0)
[01:01:28.118] Timer tick, asking Buildbucket for the build status
[01:01:28.255] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:28.255] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:28:0) after 1m0s
[01:02:28.273] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:28:0)
[01:02:28.273] Timer tick, asking Buildbucket for the build status
[01:02:28.305] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:28.305] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:29:0) after 1m0s
[01:03:28.321] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:29:0)
[01:03:28.321] Timer tick, asking Buildbucket for the build status
[01:03:28.380] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:28.380] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:30:0) after 1m0s
[01:04:28.420] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:30:0)
[01:04:28.420] Timer tick, asking Buildbucket for the build status
[01:04:28.474] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:28.474] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:31:0) after 1m0s
[01:05:28.490] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:31:0)
[01:05:28.490] Timer tick, asking Buildbucket for the build status
[01:05:28.553] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:28.554] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:32:0) after 1m0s
[01:06:28.568] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:32:0)
[01:06:28.568] Timer tick, asking Buildbucket for the build status
[01:06:28.664] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:28.664] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:33:0) after 1m0s
[01:07:28.682] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:33:0)
[01:07:28.682] Timer tick, asking Buildbucket for the build status
[01:07:28.735] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:28.735] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:34:0) after 1m0s
[01:08:28.754] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:34:0)
[01:08:28.754] Timer tick, asking Buildbucket for the build status
[01:08:28.939] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:28.939] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:35:0) after 1m0s
[01:09:28.956] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:35:0)
[01:09:28.956] Timer tick, asking Buildbucket for the build status
[01:09:29.039] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:29.039] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:36:0) after 1m0s
[01:10:29.055] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:36:0)
[01:10:29.055] Timer tick, asking Buildbucket for the build status
[01:10:29.147] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:29.147] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:37:0) after 1m0s
[01:11:29.164] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:37:0)
[01:11:29.164] Timer tick, asking Buildbucket for the build status
[01:11:29.232] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:29.232] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:38:0) after 1m0s
[01:12:29.252] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:38:0)
[01:12:29.252] Timer tick, asking Buildbucket for the build status
[01:12:29.416] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:29.416] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:39:0) after 1m0s
[01:13:29.432] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:39:0)
[01:13:29.432] Timer tick, asking Buildbucket for the build status
[01:13:29.504] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:29.504] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:40:0) after 1m0s
[01:14:29.565] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:40:0)
[01:14:29.565] Timer tick, asking Buildbucket for the build status
[01:14:29.740] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:29.740] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:41:0) after 1m0s
[01:15:29.763] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:41:0)
[01:15:29.763] Timer tick, asking Buildbucket for the build status
[01:15:29.821] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:29.821] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:42:0) after 1m0s
[01:16:29.930] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:42:0)
[01:16:29.930] Timer tick, asking Buildbucket for the build status
[01:16:30.499] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:30.499] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:43:0) after 1m0s
[01:17:30.536] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:43:0)
[01:17:30.536] Timer tick, asking Buildbucket for the build status
[01:17:30.608] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:30.608] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:44:0) after 1m0s
[01:18:30.625] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:44:0)
[01:18:30.625] Timer tick, asking Buildbucket for the build status
[01:18:30.689] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:30.689] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:45:0) after 1m0s
[01:19:30.738] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:45:0)
[01:19:30.738] Timer tick, asking Buildbucket for the build status
[01:19:30.771] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:30.771] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:46:0) after 1m0s
[01:20:30.786] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:46:0)
[01:20:30.786] Timer tick, asking Buildbucket for the build status
[01:20:30.890] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:30.890] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:47:0) after 1m0s
[01:21:30.906] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:47:0)
[01:21:30.906] Timer tick, asking Buildbucket for the build status
[01:21:30.987] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:30.987] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:48:0) after 1m0s
[01:22:31.022] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:48:0)
[01:22:31.022] Timer tick, asking Buildbucket for the build status
[01:22:31.062] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:31.062] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:49:0) after 1m0s
[01:23:31.078] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:49:0)
[01:23:31.078] Timer tick, asking Buildbucket for the build status
[01:23:31.308] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:31.308] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:50:0) after 1m0s
[01:24:31.327] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:50:0)
[01:24:31.327] Timer tick, asking Buildbucket for the build status
[01:24:31.386] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:31.386] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:51:0) after 1m0s
[01:25:31.408] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:51:0)
[01:25:31.408] Timer tick, asking Buildbucket for the build status
[01:25:31.521] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:31.521] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:52:0) after 1m0s
[01:26:31.577] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:52:0)
[01:26:31.577] Timer tick, asking Buildbucket for the build status
[01:26:31.697] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:31.697] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:53:0) after 1m0s
[01:27:31.715] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:53:0)
[01:27:31.715] Timer tick, asking Buildbucket for the build status
[01:27:31.916] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:31.916] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:54:0) after 1m0s
[01:28:31.943] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:54:0)
[01:28:31.943] Timer tick, asking Buildbucket for the build status
[01:28:31.983] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:31.983] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:55:0) after 1m0s
[01:29:31.999] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:55:0)
[01:29:31.999] Timer tick, asking Buildbucket for the build status
[01:29:32.072] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:32.072] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:56:0) after 1m0s
[01:30:32.089] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:56:0)
[01:30:32.089] Timer tick, asking Buildbucket for the build status
[01:30:32.136] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:32.136] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:57:0) after 1m0s
[01:31:32.153] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:57:0)
[01:31:32.153] Timer tick, asking Buildbucket for the build status
[01:31:32.210] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:32.210] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:58:0) after 1m0s
[01:32:32.316] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:58:0)
[01:32:32.316] Timer tick, asking Buildbucket for the build status
[01:32:32.377] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:32.377] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:59:0) after 1m0s
[01:33:32.400] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:59:0)
[01:33:32.400] Timer tick, asking Buildbucket for the build status
[01:33:32.436] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:32.436] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:60:0) after 1m0s
[01:34:32.461] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:60:0)
[01:34:32.461] Timer tick, asking Buildbucket for the build status
[01:34:32.549] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:32.549] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:61:0) after 1m0s
[01:35:32.568] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:61:0)
[01:35:32.568] Timer tick, asking Buildbucket for the build status
[01:35:32.602] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:32.602] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:62:0) after 1m0s
[01:36:32.625] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:62:0)
[01:36:32.625] Timer tick, asking Buildbucket for the build status
[01:36:32.686] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:32.686] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:63:0) after 1m0s
[01:37:32.702] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:63:0)
[01:37:32.702] Timer tick, asking Buildbucket for the build status
[01:37:33.001] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:33.001] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:64:0) after 1m0s
[01:38:33.017] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:64:0)
[01:38:33.017] Timer tick, asking Buildbucket for the build status
[01:38:33.112] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:33.112] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:65:0) after 1m0s
[01:39:33.135] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:65:0)
[01:39:33.135] Timer tick, asking Buildbucket for the build status
[01:39:33.181] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:33.181] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:66:0) after 1m0s
[01:40:33.197] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:66:0)
[01:40:33.197] Timer tick, asking Buildbucket for the build status
[01:40:33.351] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:33.351] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:67:0) after 1m0s
[01:41:33.369] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:67:0)
[01:41:33.369] Timer tick, asking Buildbucket for the build status
[01:41:33.454] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:33.454] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:68:0) after 1m0s
[01:42:33.471] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:68:0)
[01:42:33.471] Timer tick, asking Buildbucket for the build status
[01:42:33.502] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:33.502] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:69:0) after 1m0s
[01:43:33.519] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:69:0)
[01:43:33.519] Timer tick, asking Buildbucket for the build status
[01:43:33.578] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:33.578] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:70:0) after 1m0s
[01:44:33.595] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:70:0)
[01:44:33.595] Timer tick, asking Buildbucket for the build status
[01:44:33.651] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:33.651] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:71:0) after 1m0s
[01:45:33.669] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:71:0)
[01:45:33.669] Timer tick, asking Buildbucket for the build status
[01:45:33.761] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:33.761] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:72:0) after 1m0s
[01:46:33.785] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:72:0)
[01:46:33.785] Timer tick, asking Buildbucket for the build status
[01:46:33.838] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:33.838] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:73:0) after 1m0s
[01:47:33.908] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:73:0)
[01:47:33.908] Timer tick, asking Buildbucket for the build status
[01:47:34.217] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:34.217] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:74:0) after 1m0s
[01:48:34.235] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:74:0)
[01:48:34.235] Timer tick, asking Buildbucket for the build status
[01:48:34.473] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:34.473] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:75:0) after 1m0s
[01:49:34.495] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:75:0)
[01:49:34.495] Timer tick, asking Buildbucket for the build status
[01:49:34.612] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:34.612] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:76:0) after 1m0s
[01:50:34.633] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:76:0)
[01:50:34.633] Timer tick, asking Buildbucket for the build status
[01:50:34.724] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:34.724] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:77:0) after 1m0s
[01:51:34.740] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:77:0)
[01:51:34.740] Timer tick, asking Buildbucket for the build status
[01:51:34.885] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:34.885] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:78:0) after 1m0s
[01:52:34.904] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:78:0)
[01:52:34.904] Timer tick, asking Buildbucket for the build status
[01:52:34.950] Build 8923765579297634160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:34.950] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9089195641431921984:79:0) after 1m0s
[01:52:36.440] Received PubSub notification, asking Buildbucket for the build status
[01:52:36.510] Build 8923765579297634160: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:52:36.510] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1548035555224358",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1548030983601665",
"id": "8923765579297634160",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"769fdf3e274e1d4663711cc96373ca5fb776e1ae\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"ui\": {\"info\": \"\"}, \"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"id\": [\"swarm2173-c4\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4004-c99644c\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"got_nacl_revision\": \"ff15c51d20785fa51c0ef03ccb480b87299acbeb\", \"got_swarming_client_revision\": \"0e3e1c4dc4e79f25a5b58fcbc135dc93183c0c54\", \"got_revision\": \"769fdf3e274e1d4663711cc96373ca5fb776e1ae\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"850c0d9aec6bd3f63cb58ff1838380efc112279a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26298}\", \"$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@{#624497}\", \"branch\": \"refs/heads/master\", \"revision\": \"769fdf3e274e1d4663711cc96373ca5fb776e1ae\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"74ba99062c48b278675cfe52643719202296fddc\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"7403dd2cd3764fe96660fe09892e764e9ae1dbca\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1548030983601665, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/769fdf3e274e1d4663711cc96373ca5fb776e1ae\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/769fdf3e274e1d4663711cc96373ca5fb776e1ae\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9089195641431921984\", \"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\": \"8923765579297634160\"}}, \"got_v8_revision\": \"49d3755f33eb8f2e44b2da7ba21556429d6b2493\", \"got_v8_revision_cp\": \"refs/heads/7.3.451@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 11688, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2f02e1f363b1af2715536f38e239853f04ec1497\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1548030986274763",
"status": "COMPLETED",
"status_changed_ts": "1548035556074315",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11688",
"builder:Windows deterministic",
"buildset:commit/git/769fdf3e274e1d4663711cc96373ca5fb776e1ae",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/769fdf3e274e1d4663711cc96373ca5fb776e1ae",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9089195641431921984",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Windows deterministic/11688",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8923765579297634160",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:1",
"swarming_tag:buildbucket_template_revision:01134cf47e8a2d5d7cea5ec1232ca64ed2f16a5c",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/769fdf3e274e1d4663711cc96373ca5fb776e1ae",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/769fdf3e274e1d4663711cc96373ca5fb776e1ae",
"swarming_tag:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8923765579297634160/+/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:9089195641431921984",
"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:5746c1addf0a4c286f58c69ab0c42aedaac6b5ff",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4286a7c9487db410",
"user_agent:luci-scheduler"
],
"updated_ts": "1548035556077259",
"url": "https://ci.chromium.org/b/8923765579297634160",
"utcnow_ts": "1548035556480884"
}
}
[01:52:36.510] Invocation finished in 1h16m14.328065847s with status SUCCEEDED