[08:46:35.352] New invocation is queued and will start shortly
[08:46:36.414] Starting the invocation (attempt 1)
[08:46:36.453] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:46:36.453] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:46:36.454] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023753789765661488",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"16873ee94176f188f43540c70b8924a9a0e3c9d5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023753789765661488",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/16873ee94176f188f43540c70b8924a9a0e3c9d5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/16873ee94176f188f43540c70b8924a9a0e3c9d5",
"gitiles_ref:refs/heads/master"
]
}
[08:46:38.571] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610441196600444",
"id": "8858323727791897824",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"16873ee94176f188f43540c70b8924a9a0e3c9d5\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610441197374090",
"tags": [
"build_address:luci.chromium.ci/win-asan/16773",
"builder:win-asan",
"buildset:commit/git/16873ee94176f188f43540c70b8924a9a0e3c9d5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/16873ee94176f188f43540c70b8924a9a0e3c9d5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023753789765661488",
"scheduler_job_id:chromium/win-asan",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8858323727791897824/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1610441197374207",
"url": "https://ci.chromium.org/b/8858323727791897824",
"utcnow_ts": "1610441198563542"
}
}
[08:46:38.572] Task URL: https://ci.chromium.org/b/8858323727791897824
[08:46:38.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:2:0) after 1m0s
[08:47:38.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:2:0)
[08:47:38.660] Timer tick, asking Buildbucket for the build status
[08:47:39.214] Build 8858323727791897824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:47:39.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:3:0) after 1m0s
[08:48:39.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:3:0)
[08:48:39.257] Timer tick, asking Buildbucket for the build status
[08:48:39.329] Build 8858323727791897824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:48:39.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:4:0) after 1m0s
[08:49:39.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:4:0)
[08:49:39.386] Timer tick, asking Buildbucket for the build status
[08:49:39.644] Build 8858323727791897824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:49:39.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:5:0) after 1m0s
[08:50:39.630] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:5:0)
[08:50:39.630] Timer tick, asking Buildbucket for the build status
[08:50:39.787] Build 8858323727791897824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:50:39.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:6:0) after 1m0s
[08:51:30.224] Received PubSub notification, asking Buildbucket for the build status
[08:51:30.501] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:39.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:6:0)
[08:51:39.814] Timer tick, asking Buildbucket for the build status
[08:51:39.947] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:39.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:8:0) after 1m0s
[08:52:39.954] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:8:0)
[08:52:39.954] Timer tick, asking Buildbucket for the build status
[08:52:40.071] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:40.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:9:0) after 1m0s
[08:53:40.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:9:0)
[08:53:40.106] Timer tick, asking Buildbucket for the build status
[08:53:40.226] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:40.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:10:0) after 1m0s
[08:54:40.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:10:0)
[08:54:40.274] Timer tick, asking Buildbucket for the build status
[08:54:40.422] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:40.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:11:0) after 1m0s
[08:55:40.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:11:0)
[08:55:40.471] Timer tick, asking Buildbucket for the build status
[08:55:40.626] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:40.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:12:0) after 1m0s
[08:56:40.695] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:12:0)
[08:56:40.695] Timer tick, asking Buildbucket for the build status
[08:56:41.065] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:41.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:13:0) after 1m0s
[08:57:41.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:13:0)
[08:57:41.104] Timer tick, asking Buildbucket for the build status
[08:57:41.196] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:41.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:14:0) after 1m0s
[08:58:41.297] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:14:0)
[08:58:41.297] Timer tick, asking Buildbucket for the build status
[08:58:41.374] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:41.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:15:0) after 1m0s
[08:59:41.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:15:0)
[08:59:41.472] Timer tick, asking Buildbucket for the build status
[08:59:41.538] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:41.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:16:0) after 1m0s
[09:00:41.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:16:0)
[09:00:41.578] Timer tick, asking Buildbucket for the build status
[09:00:41.708] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:41.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:17:0) after 1m0s
[09:01:41.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:17:0)
[09:01:41.736] Timer tick, asking Buildbucket for the build status
[09:01:41.853] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:41.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:18:0) after 1m0s
[09:02:41.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:18:0)
[09:02:41.952] Timer tick, asking Buildbucket for the build status
[09:02:42.093] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:42.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:19:0) after 1m0s
[09:03:42.189] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:19:0)
[09:03:42.189] Timer tick, asking Buildbucket for the build status
[09:03:42.335] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:42.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:20:0) after 1m0s
[09:04:42.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:20:0)
[09:04:42.400] Timer tick, asking Buildbucket for the build status
[09:04:42.846] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:42.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:21:0) after 1m0s
[09:05:42.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:21:0)
[09:05:42.888] Timer tick, asking Buildbucket for the build status
[09:05:43.966] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:43.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:22:0) after 1m0s
[09:06:44.026] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:22:0)
[09:06:44.026] Timer tick, asking Buildbucket for the build status
[09:06:44.452] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:44.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:23:0) after 1m0s
[09:07:44.590] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:23:0)
[09:07:44.590] Timer tick, asking Buildbucket for the build status
[09:07:44.770] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:44.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:24:0) after 1m0s
[09:08:44.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:24:0)
[09:08:44.863] Timer tick, asking Buildbucket for the build status
[09:08:44.982] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:44.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:25:0) after 1m0s
[09:09:45.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:25:0)
[09:09:45.043] Timer tick, asking Buildbucket for the build status
[09:09:45.460] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:45.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:26:0) after 1m0s
[09:10:45.543] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:26:0)
[09:10:45.543] Timer tick, asking Buildbucket for the build status
[09:10:45.670] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:45.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:27:0) after 1m0s
[09:11:45.731] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:27:0)
[09:11:45.731] Timer tick, asking Buildbucket for the build status
[09:11:45.843] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:45.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:28:0) after 1m0s
[09:12:45.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:28:0)
[09:12:45.896] Timer tick, asking Buildbucket for the build status
[09:12:46.150] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:46.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:29:0) after 1m0s
[09:13:46.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:29:0)
[09:13:46.256] Timer tick, asking Buildbucket for the build status
[09:13:46.354] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:46.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:30:0) after 1m0s
[09:14:46.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:30:0)
[09:14:46.424] Timer tick, asking Buildbucket for the build status
[09:14:46.492] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:46.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:31:0) after 1m0s
[09:15:46.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:31:0)
[09:15:46.564] Timer tick, asking Buildbucket for the build status
[09:15:46.712] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:46.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:32:0) after 1m0s
[09:16:46.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:32:0)
[09:16:46.805] Timer tick, asking Buildbucket for the build status
[09:16:46.932] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:46.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:33:0) after 1m0s
[09:17:46.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:33:0)
[09:17:46.964] Timer tick, asking Buildbucket for the build status
[09:17:47.043] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:47.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:34:0) after 1m0s
[09:18:47.171] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:34:0)
[09:18:47.171] Timer tick, asking Buildbucket for the build status
[09:18:47.288] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:47.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:35:0) after 1m0s
[09:19:47.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:35:0)
[09:19:47.365] Timer tick, asking Buildbucket for the build status
[09:19:47.480] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:47.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:36:0) after 1m0s
[09:20:47.537] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:36:0)
[09:20:47.537] Timer tick, asking Buildbucket for the build status
[09:20:47.810] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:47.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:37:0) after 1m0s
[09:21:47.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:37:0)
[09:21:47.836] Timer tick, asking Buildbucket for the build status
[09:21:47.952] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:47.952] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:38:0) after 1m0s
[09:22:48.041] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:38:0)
[09:22:48.041] Timer tick, asking Buildbucket for the build status
[09:22:48.200] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:48.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:39:0) after 1m0s
[09:23:48.192] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:39:0)
[09:23:48.192] Timer tick, asking Buildbucket for the build status
[09:23:48.704] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:48.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:40:0) after 1m0s
[09:24:48.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:40:0)
[09:24:48.785] Timer tick, asking Buildbucket for the build status
[09:24:48.863] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:48.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:41:0) after 1m0s
[09:25:48.838] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:41:0)
[09:25:48.838] Timer tick, asking Buildbucket for the build status
[09:25:48.914] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:48.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:42:0) after 1m0s
[09:26:48.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:42:0)
[09:26:48.925] Timer tick, asking Buildbucket for the build status
[09:26:49.159] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:49.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:43:0) after 1m0s
[09:27:49.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:43:0)
[09:27:49.188] Timer tick, asking Buildbucket for the build status
[09:27:49.324] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:49.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:44:0) after 1m0s
[09:28:49.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:44:0)
[09:28:49.378] Timer tick, asking Buildbucket for the build status
[09:28:49.486] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:49.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:45:0) after 1m0s
[09:29:49.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:45:0)
[09:29:49.565] Timer tick, asking Buildbucket for the build status
[09:29:49.929] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:49.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:46:0) after 1m0s
[09:30:49.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:46:0)
[09:30:49.917] Timer tick, asking Buildbucket for the build status
[09:30:50.218] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:50.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:47:0) after 1m0s
[09:31:50.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:47:0)
[09:31:50.222] Timer tick, asking Buildbucket for the build status
[09:31:50.470] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:50.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:48:0) after 1m0s
[09:32:50.500] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:48:0)
[09:32:50.500] Timer tick, asking Buildbucket for the build status
[09:32:50.574] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:50.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:49:0) after 1m0s
[09:33:50.689] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:49:0)
[09:33:50.689] Timer tick, asking Buildbucket for the build status
[09:33:50.829] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:50.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:50:0) after 1m0s
[09:34:50.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:50:0)
[09:34:50.855] Timer tick, asking Buildbucket for the build status
[09:34:51.013] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:51.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:51:0) after 1m0s
[09:35:51.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:51:0)
[09:35:51.035] Timer tick, asking Buildbucket for the build status
[09:35:51.114] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:51.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:52:0) after 1m0s
[09:36:51.136] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:52:0)
[09:36:51.136] Timer tick, asking Buildbucket for the build status
[09:36:51.293] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:51.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:53:0) after 1m0s
[09:37:51.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:53:0)
[09:37:51.320] Timer tick, asking Buildbucket for the build status
[09:37:51.412] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:51.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:54:0) after 1m0s
[09:38:51.432] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:54:0)
[09:38:51.432] Timer tick, asking Buildbucket for the build status
[09:38:51.527] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:51.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:55:0) after 1m0s
[09:39:51.552] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:55:0)
[09:39:51.552] Timer tick, asking Buildbucket for the build status
[09:39:51.634] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:51.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:56:0) after 1m0s
[09:40:51.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:56:0)
[09:40:51.658] Timer tick, asking Buildbucket for the build status
[09:40:51.747] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:51.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:57:0) after 1m0s
[09:41:51.806] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:57:0)
[09:41:51.806] Timer tick, asking Buildbucket for the build status
[09:41:52.021] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:52.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:58:0) after 1m0s
[09:42:52.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:58:0)
[09:42:52.057] Timer tick, asking Buildbucket for the build status
[09:42:52.164] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:52.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:59:0) after 1m0s
[09:43:52.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:59:0)
[09:43:52.255] Timer tick, asking Buildbucket for the build status
[09:43:52.456] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:52.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:60:0) after 1m0s
[09:44:52.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:60:0)
[09:44:52.489] Timer tick, asking Buildbucket for the build status
[09:44:52.559] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:52.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:61:0) after 1m0s
[09:45:52.612] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:61:0)
[09:45:52.612] Timer tick, asking Buildbucket for the build status
[09:45:52.754] Build 8858323727791897824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:52.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023753789765661488:62:0) after 1m0s
[09:46:11.982] Received PubSub notification, asking Buildbucket for the build status
[09:46:12.091] Build 8858323727791897824: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:46:12.091] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610444770971595",
"created_by": "project:chromium",
"created_ts": "1610441196600444",
"id": "8858323727791897824",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"16873ee94176f188f43540c70b8924a9a0e3c9d5\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"16873ee94176f188f43540c70b8924a9a0e3c9d5\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-us-central1-b-32-1-ervs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16773, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"2c65da28a2ee7c56e5aab44aeb3557e163410d94\", \"got_dawn_revision\": \"2bd95f1cf45451119b6644e41919b7bef443d912\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"16873ee94176f188f43540c70b8924a9a0e3c9d5\", \"got_revision_cp\": \"refs/heads/master@{#842434}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"3549479dc4ccd92ef3b9e179080dcec1a430b01d\", \"got_v8_revision\": \"4281a3f21b66396bc4c204e2590df597e1dfebd1\", \"got_v8_revision_cp\": \"refs/heads/8.9.242@{#1}\", \"got_webrtc_revision\": \"1c5e63e5451374783aaf0259f5c23d3688a5b2ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32938}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"16873ee94176f188f43540c70b8924a9a0e3c9d5\", \"swarm_hashes_refs/heads/master(at){#842434}_without_patch\": {\"absl_hardening_tests\": \"39947578cad4b4944d238c2e290f470ecd715e06\", \"accessibility_unittests\": \"4ce1c5727a5969460ee157ad4a353f840fdaaa5b\", \"angle_unittests\": \"031f81ea1baf2300190ed94586042c8fa7999d73\", \"app_shell_unittests\": \"95a6f9f64b9fe9528c8e527b481ef2777ae25faa\", \"aura_unittests\": \"0cfc9cfcd9ab7afb16c527174155dd6749c91277\", \"base_unittests\": \"d80cf3383e5fa318f984f3818fcc0e7cd066a560\", \"base_util_unittests\": \"800ba2cc0e6a277e72c249fe37a7d999f3929c7e\", \"blink_common_unittests\": \"85d80e8aba4c12477f13890c1519e3379f5e189a\", \"blink_fuzzer_unittests\": \"5726bff8db7dd59228e7e85655a4ca62a07edb5f\", \"blink_heap_unittests\": \"3a0b0cae815036de8936768523a796b3fad241e3\", \"blink_platform_unittests\": \"0544b0eb5f5823a3aeb358c5dc8ccf1c6d9fbb17\", \"blink_unittests\": \"9153c720e35cd6308f0af556a0d82534d0aa7d30\", \"boringssl_crypto_tests\": \"170dbbe5a69c12612507a65ff9eab6a6d9a94e6e\", \"boringssl_ssl_tests\": \"846d68e10ac80200edc8c8161b0f05e14a1cedb5\", \"browser_switcher_bho_unittests\": \"ebba3240b94c1ff09a05e3d559f51d8d76023665\", \"browser_tests\": \"734c385b64db4e78793f73c1447d51a95627a5ee\", \"capture_unittests\": \"0014c1b6dd70f7e4cc1870de89b84c751788360a\", \"cast_unittests\": \"4c89e6d0927ffc4f1c7ad582304056196c5c119f\", \"cc_unittests\": \"5437f592ec88fe251d11916223be244efd3a189f\", \"chrome_app_unittests\": \"b34d872c5abc8dd3159c21ee6169800c85d9f1b7\", \"chrome_cleaner_unittests\": \"11a2c38695dd08ea15ad9c34b1911687a7ae4b2b\", \"chrome_elf_unittests\": \"3cdfa2317cc20bcccbcc04d241cf1fb6d8aa8bb0\", \"chromedriver_unittests\": \"8c2559d4af75161ad62bde7227a7fa44f038a0a4\", \"components_browsertests\": \"74791f93226e50d871d37deb44419691b8f8f5e0\", \"components_unittests\": \"9dea56ae8e66d8c7942b9400af1a47f2486b181c\", \"compositor_unittests\": \"7bc4ed6486c67b16b7c47c16652fab11d43415d9\", \"content_browsertests\": \"0cc5096e2f15956c315c16de44a8dc9d0cea7be3\", \"content_unittests\": \"bbb73c28784c9cfcbae19e1ff229b91b46036d22\", \"courgette_unittests\": \"762ca72eb01e26642a15fac137d2d1da13646abd\", \"crashpad_tests\": \"ab26af0352985d1a4f58c4afb2b8ba2026f2d6ec\", \"cronet_tests\": \"433c66bb2812fbc312a928645c7f229d8b33ef80\", \"cronet_unittests\": \"8dc99d4c9856be6337af4ad5f94bf5215f9bd6f4\", \"crypto_unittests\": \"a5fca417f6faef9bc3ccaee3d292f3e62a371e55\", \"delayloads_unittests\": \"d6cc1b3a6d7719310ebe10ba0176b5018ae2e808\", \"device_unittests\": \"43fd3e897f2955b342559feba6440b85201feafd\", \"display_unittests\": \"2a04d21a97b6355676361daf9aff0cea3d58e5ef\", \"elevation_service_unittests\": \"f1be6382c28025403b67aa3e3c071d94e46f8335\", \"events_unittests\": \"8b93a49727685f2ca8ee4f39b70cbf1d8b949981\", \"extensions_browsertests\": \"c783f031e464c8d575489a20b44691c9f1fc724c\", \"extensions_unittests\": \"24f47f8fe4320af88ec5068082a5affc4ba7e337\", \"filesystem_service_unittests\": \"149fded819ea8a085e99d2c39c5b0c0de7a7a7fa\", \"gcm_unit_tests\": \"17256b6279a1303bd00cc839974ded1b64362e6c\", \"gcp_unittests\": \"af4bb5fcf7b3691e7a6bb2f7bc27145bebc0066d\", \"gfx_unittests\": \"4f417eb17ea9814be95d2456f2ae91df8b2279bc\", \"gin_unittests\": \"1baf03e3c13f42f526a59e21885dfc5bbfb2da26\", \"google_apis_unittests\": \"0929a6fd6bd6f52a87f55fd5aa7a7c86417e7fb0\", \"gpu_unittests\": \"d0393c09603d1ba2752182417f696957a6cfa79e\", \"gwp_asan_unittests\": \"b5335895f33b4abb0188adec793609987f65d04b\", \"headless_browsertests\": \"b508b12599922b5d4c6149f1265b50512c4cf46d\", \"headless_unittests\": \"853b8f669a2471e8596e1c5c01171a665970eefb\", \"install_static_unittests\": \"9e64838934cecf142619169de6faa581ec4ded18\", \"installer_util_unittests\": \"58d0b30067dc22cbcf533f52a047f01ee87bc9cc\", \"interactive_ui_tests\": \"1320fb50765e4a10b71325fb28092b372d7bfcf1\", \"ipc_tests\": \"86c7479f2d11d8da5e4bba64a751350491b3ccaf\", \"jingle_unittests\": \"62c764ff02c9465ced12414303cb728ba2abf0a1\", \"latency_unittests\": \"0e3f99af361be3733d294878f63aee5cf8d3eba2\", \"libjingle_xmpp_unittests\": \"8017221b78009953fbae8f1b2a01302dc3a23176\", \"liburlpattern_unittests\": \"75ba19a0dea304bd6eb561e6f0a96c023b8b2cce\", \"media_blink_unittests\": \"bda1062097762dfeb06bd60ac925116c5830eaa3\", \"media_unittests\": \"85f79f9ee3956a51bd593a58abdb05135af2c239\", \"message_center_unittests\": \"270d6464b4c492bec004d8f2ee7d344a8828d41c\", \"midi_unittests\": \"f3b308cd75a8b85fac5f83f0fbc4ada6d7883933\", \"mojo_core_unittests\": \"fa9f3e30eb7f093b0e37f82e20b063d2c535648a\", \"mojo_unittests\": \"362d7fce0fef1d8c7a5b443b9cba6e52dad51f79\", \"nacl_loader_unittests\": \"df1afe3343efcddaf7153615a94b70e08d9651e5\", \"native_theme_unittests\": \"872e30f69031707cc83af6230063b6b99fdd1589\", \"net_unittests\": \"5baf97e060f6c039861c7fe2ec1f01b8f203a99c\", \"notification_helper_unittests\": \"1b8ce91d81bce47b26660bcba1a9771bc960e378\", \"pdf_unittests\": \"44d71678dfd843b3f21ea11479b4bbea374b0ff7\", \"perfetto_unittests\": \"d742e30d9bd4c65c866853c91ac11c62feb159f3\", \"ppapi_unittests\": \"d0dc6294b52f75ac0862ca4e912eba2464d5104e\", \"printing_unittests\": \"74c0d47e4853757236b82efe756cd3e5ca2d76cc\", \"remoting_unittests\": \"766d3f06e4af1ef2b6212fc04b07d329e257d89e\", \"sbox_integration_tests\": \"af187132fcc3e20595faa3ffd11695ff956baa5a\", \"sbox_unittests\": \"fdc8c01dae39594e6d62e1ebeff3ca88215207e0\", \"sbox_validation_tests\": \"c68bb41cf5722446eaf3229b3283b967ae6e7a82\", \"service_manager_unittests\": \"c8bb8f212b041ef0ccba819d173897faab0073e4\", \"services_unittests\": \"15cbdfb38f58fcd70b0cdef6c3e1041e181db8bc\", \"setup_unittests\": \"b96240b99cb62a658b3dee808ad862c487918cd6\", \"shell_dialogs_unittests\": \"f77ee0c0ece62ee7e2c76643f77c8d352c8466f3\", \"skia_unittests\": \"ce17b75f6ef9e58b57f22ef39dae808bda956efb\", \"snapshot_unittests\": \"0620fb672b6236100de0fac16b4a04d3dd952610\", \"sql_unittests\": \"574b2fae6b73b55f822c7ba09b50ee3cf35565db\", \"storage_unittests\": \"ceeb8d7b4fa6e6738244560816cc9958c300bde4\", \"sync_integration_tests\": \"ed5909c19b9dde0d4f7ff2508dc18984b2f1b6fc\", \"traffic_annotation_auditor_unittests\": \"b17b353112748e4e6f24fe8631b52dffeb90082b\", \"ui_base_unittests\": \"6ac996d6e57e7f903e3c24ac8dc89ff1f4578684\", \"ui_touch_selection_unittests\": \"9f21e934bd0994944258b92027d121bb45b3e7a1\", \"unit_tests\": \"30563d2f373e5a3b4dc619c0157d6d6d2d9a3688\", \"updater_tests\": \"0aa70c77c87976d556014e5df3957ef6728b5f9a\", \"url_unittests\": \"3cf39c8679f37c7a057460d382d26ee9a28b17a1\", \"views_unittests\": \"13d56a0954d1b388d7f312edff2bd4ba7b9d8af1\", \"viz_unittests\": \"1fefbba7c4c0f7055acd5098fcd3eeebe26b8a79\", \"vr_common_unittests\": \"c458d5d2693dc58e286fdc54cfd684ec9d25aece\", \"vr_pixeltests\": \"6a35cffd47aa0e7dfa7acc2fa982f9840f35b056\", \"weblayer_browsertests\": \"8c180dc26a8076ec344359a012cce056227129ab\", \"weblayer_unittests\": \"7984ca878da8904dd87630004310389d37c928f8\", \"wm_unittests\": \"37e9bf8356fc990a3af42d98b49b72497091fa52\", \"wtf_unittests\": \"3ae5c53d21857c0153c9748b3b87ab468ffffffe\", \"zlib_unittests\": \"d327ec5ddfe03de3724d7af6cbca38cb5bcad0de\", \"zucchini_unittests\": \"5b4a35d76a5156d73f0269d42d294a2eb211fcfd\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-ervs\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5661-c6f6250\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610441489524407",
"status": "COMPLETED",
"status_changed_ts": "1610444771355458",
"tags": [
"build_address:luci.chromium.ci/win-asan/16773",
"builder:win-asan",
"buildset:commit/git/16873ee94176f188f43540c70b8924a9a0e3c9d5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/16873ee94176f188f43540c70b8924a9a0e3c9d5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023753789765661488",
"scheduler_job_id:chromium/win-asan",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8858323727791897824/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:510e981462656e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1610444771359552",
"url": "https://ci.chromium.org/b/8858323727791897824",
"utcnow_ts": "1610444772070791"
}
}
[09:46:12.091] Invocation finished in 59m36.756631561s with status SUCCEEDED