[12:46:38.127] New invocation is queued and will start shortly
[12:46:39.381] Starting the invocation (attempt 1)
[12:46:39.430] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:46:39.430] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:46:39.430] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030805250739175808",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a609b585b844b47de3870ed40acff98128d44144\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9030805250739175808",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/a609b585b844b47de3870ed40acff98128d44144",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a609b585b844b47de3870ed40acff98128d44144",
"gitiles_ref:refs/heads/master"
]
}
[12:46:40.147] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1603716399540572",
"id": "8865375188594305648",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a609b585b844b47de3870ed40acff98128d44144\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603716399979577",
"tags": [
"build_address:luci.chromium.ci/win-asan/15285",
"builder:win-asan",
"buildset:commit/git/a609b585b844b47de3870ed40acff98128d44144",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a609b585b844b47de3870ed40acff98128d44144",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030805250739175808",
"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/8865375188594305648/+/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": "1603716399979808",
"url": "https://ci.chromium.org/b/8865375188594305648",
"utcnow_ts": "1603716400140240"
}
}
[12:46:40.147] Task URL: https://ci.chromium.org/b/8865375188594305648
[12:46:40.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:2:0) after 1m0s
[12:46:43.842] Received PubSub notification, asking Buildbucket for the build status
[12:46:43.915] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:40.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:2:0)
[12:47:40.198] Timer tick, asking Buildbucket for the build status
[12:47:40.329] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:40.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:4:0) after 1m0s
[12:48:40.365] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:4:0)
[12:48:40.365] Timer tick, asking Buildbucket for the build status
[12:48:40.561] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:40.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:5:0) after 1m0s
[12:49:40.566] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:5:0)
[12:49:40.566] Timer tick, asking Buildbucket for the build status
[12:49:40.673] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:40.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:6:0) after 1m0s
[12:50:40.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:6:0)
[12:50:40.822] Timer tick, asking Buildbucket for the build status
[12:50:40.935] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:40.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:7:0) after 1m0s
[12:51:40.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:7:0)
[12:51:40.926] Timer tick, asking Buildbucket for the build status
[12:51:41.077] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:41.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:8:0) after 1m0s
[12:52:41.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:8:0)
[12:52:41.106] Timer tick, asking Buildbucket for the build status
[12:52:41.276] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:41.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:9:0) after 1m0s
[12:53:41.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:9:0)
[12:53:41.299] Timer tick, asking Buildbucket for the build status
[12:53:41.498] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:41.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:10:0) after 1m0s
[12:54:41.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:10:0)
[12:54:41.518] Timer tick, asking Buildbucket for the build status
[12:54:41.611] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:41.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:11:0) after 1m0s
[12:55:41.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:11:0)
[12:55:41.655] Timer tick, asking Buildbucket for the build status
[12:55:41.731] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:41.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:12:0) after 1m0s
[12:56:41.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:12:0)
[12:56:41.751] Timer tick, asking Buildbucket for the build status
[12:56:42.212] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:42.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:13:0) after 1m0s
[12:57:42.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:13:0)
[12:57:42.285] Timer tick, asking Buildbucket for the build status
[12:57:42.369] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:42.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:14:0) after 1m0s
[12:58:42.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:14:0)
[12:58:42.425] Timer tick, asking Buildbucket for the build status
[12:58:42.584] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:42.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:15:0) after 1m0s
[12:59:42.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:15:0)
[12:59:42.605] Timer tick, asking Buildbucket for the build status
[12:59:42.664] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:42.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:16:0) after 1m0s
[13:00:42.702] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:16:0)
[13:00:42.702] Timer tick, asking Buildbucket for the build status
[13:00:42.784] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:42.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:17:0) after 1m0s
[13:01:42.818] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:17:0)
[13:01:42.818] Timer tick, asking Buildbucket for the build status
[13:01:43.062] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:43.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:18:0) after 1m0s
[13:02:43.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:18:0)
[13:02:43.068] Timer tick, asking Buildbucket for the build status
[13:02:43.148] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:43.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:19:0) after 1m0s
[13:03:43.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:19:0)
[13:03:43.193] Timer tick, asking Buildbucket for the build status
[13:03:43.427] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:43.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:20:0) after 1m0s
[13:04:43.552] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:20:0)
[13:04:43.552] Timer tick, asking Buildbucket for the build status
[13:04:43.779] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:43.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:21:0) after 1m0s
[13:05:43.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:21:0)
[13:05:43.843] Timer tick, asking Buildbucket for the build status
[13:05:43.976] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:43.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:22:0) after 1m0s
[13:06:44.022] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:22:0)
[13:06:44.022] Timer tick, asking Buildbucket for the build status
[13:06:44.247] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:44.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:23:0) after 1m0s
[13:07:44.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:23:0)
[13:07:44.298] Timer tick, asking Buildbucket for the build status
[13:07:44.378] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:44.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:24:0) after 1m0s
[13:08:44.495] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:24:0)
[13:08:44.495] Timer tick, asking Buildbucket for the build status
[13:08:44.888] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:44.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:25:0) after 1m0s
[13:09:44.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:25:0)
[13:09:44.917] Timer tick, asking Buildbucket for the build status
[13:09:45.083] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:45.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:26:0) after 1m0s
[13:10:45.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:26:0)
[13:10:45.145] Timer tick, asking Buildbucket for the build status
[13:10:45.280] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:45.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:27:0) after 1m0s
[13:11:45.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:27:0)
[13:11:45.302] Timer tick, asking Buildbucket for the build status
[13:11:45.393] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:45.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:28:0) after 1m0s
[13:12:45.506] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:28:0)
[13:12:45.506] Timer tick, asking Buildbucket for the build status
[13:12:45.644] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:45.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:29:0) after 1m0s
[13:13:45.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:29:0)
[13:13:45.666] Timer tick, asking Buildbucket for the build status
[13:13:45.839] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:45.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:30:0) after 1m0s
[13:14:45.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:30:0)
[13:14:45.930] Timer tick, asking Buildbucket for the build status
[13:14:46.101] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:46.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:31:0) after 1m0s
[13:15:46.083] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:31:0)
[13:15:46.083] Timer tick, asking Buildbucket for the build status
[13:15:46.228] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:46.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:32:0) after 1m0s
[13:16:46.267] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:32:0)
[13:16:46.267] Timer tick, asking Buildbucket for the build status
[13:16:46.427] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:46.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:33:0) after 1m0s
[13:17:46.445] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:33:0)
[13:17:46.445] Timer tick, asking Buildbucket for the build status
[13:17:46.584] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:46.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:34:0) after 1m0s
[13:18:46.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:34:0)
[13:18:46.634] Timer tick, asking Buildbucket for the build status
[13:18:46.933] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:46.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:35:0) after 1m0s
[13:19:46.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:35:0)
[13:19:46.962] Timer tick, asking Buildbucket for the build status
[13:19:47.090] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:47.090] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:36:0) after 1m0s
[13:20:47.183] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:36:0)
[13:20:47.183] Timer tick, asking Buildbucket for the build status
[13:20:47.337] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:47.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:37:0) after 1m0s
[13:21:47.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:37:0)
[13:21:47.357] Timer tick, asking Buildbucket for the build status
[13:21:47.515] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:47.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:38:0) after 1m0s
[13:22:47.537] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:38:0)
[13:22:47.537] Timer tick, asking Buildbucket for the build status
[13:22:47.608] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:47.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:39:0) after 1m0s
[13:23:47.851] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:39:0)
[13:23:47.851] Timer tick, asking Buildbucket for the build status
[13:23:47.976] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:47.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:40:0) after 1m0s
[13:24:48.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:40:0)
[13:24:48.119] Timer tick, asking Buildbucket for the build status
[13:24:48.615] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:48.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:41:0) after 1m0s
[13:25:48.637] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:41:0)
[13:25:48.637] Timer tick, asking Buildbucket for the build status
[13:25:48.765] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:48.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:42:0) after 1m0s
[13:26:48.788] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:42:0)
[13:26:48.788] Timer tick, asking Buildbucket for the build status
[13:26:49.260] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:49.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:43:0) after 1m0s
[13:27:49.260] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:43:0)
[13:27:49.260] Timer tick, asking Buildbucket for the build status
[13:27:49.327] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:49.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:44:0) after 1m0s
[13:28:49.356] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:44:0)
[13:28:49.356] Timer tick, asking Buildbucket for the build status
[13:28:49.434] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:49.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:45:0) after 1m0s
[13:29:49.456] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:45:0)
[13:29:49.456] Timer tick, asking Buildbucket for the build status
[13:29:49.728] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:49.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:46:0) after 1m0s
[13:30:49.737] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:46:0)
[13:30:49.737] Timer tick, asking Buildbucket for the build status
[13:30:49.926] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:49.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:47:0) after 1m0s
[13:31:50.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:47:0)
[13:31:50.106] Timer tick, asking Buildbucket for the build status
[13:31:50.231] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:50.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:48:0) after 1m0s
[13:32:50.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:48:0)
[13:32:50.254] Timer tick, asking Buildbucket for the build status
[13:32:50.386] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:50.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:49:0) after 1m0s
[13:33:50.437] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:49:0)
[13:33:50.437] Timer tick, asking Buildbucket for the build status
[13:33:50.516] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:50.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:50:0) after 1m0s
[13:34:50.541] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:50:0)
[13:34:50.541] Timer tick, asking Buildbucket for the build status
[13:34:50.932] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:50.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:51:0) after 1m0s
[13:35:50.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:51:0)
[13:35:50.976] Timer tick, asking Buildbucket for the build status
[13:35:51.124] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:51.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:52:0) after 1m0s
[13:36:51.171] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:52:0)
[13:36:51.171] Timer tick, asking Buildbucket for the build status
[13:36:51.298] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:51.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:53:0) after 1m0s
[13:37:51.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:53:0)
[13:37:51.323] Timer tick, asking Buildbucket for the build status
[13:37:51.438] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:51.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:54:0) after 1m0s
[13:38:51.840] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:54:0)
[13:38:51.840] Timer tick, asking Buildbucket for the build status
[13:38:52.228] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:52.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:55:0) after 1m0s
[13:39:52.432] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:55:0)
[13:39:52.432] Timer tick, asking Buildbucket for the build status
[13:39:52.799] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:52.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:56:0) after 1m0s
[13:40:52.862] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:56:0)
[13:40:52.862] Timer tick, asking Buildbucket for the build status
[13:40:52.955] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:52.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:57:0) after 1m0s
[13:41:53.072] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:57:0)
[13:41:53.072] Timer tick, asking Buildbucket for the build status
[13:41:53.366] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:53.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:58:0) after 1m0s
[13:42:53.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:58:0)
[13:42:53.386] Timer tick, asking Buildbucket for the build status
[13:42:53.596] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:53.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:59:0) after 1m0s
[13:43:53.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:59:0)
[13:43:53.646] Timer tick, asking Buildbucket for the build status
[13:43:53.776] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:53.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:60:0) after 1m0s
[13:44:53.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:60:0)
[13:44:53.771] Timer tick, asking Buildbucket for the build status
[13:44:54.133] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:54.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:61:0) after 1m0s
[13:45:54.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:61:0)
[13:45:54.218] Timer tick, asking Buildbucket for the build status
[13:45:54.288] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:54.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:62:0) after 1m0s
[13:46:54.407] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:62:0)
[13:46:54.407] Timer tick, asking Buildbucket for the build status
[13:46:54.978] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:54.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:63:0) after 1m0s
[13:47:55.007] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:63:0)
[13:47:55.007] Timer tick, asking Buildbucket for the build status
[13:47:55.422] Build 8865375188594305648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:55.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030805250739175808:64:0) after 1m0s
[13:48:49.429] Received PubSub notification, asking Buildbucket for the build status
[13:48:49.686] Build 8865375188594305648: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:48:49.686] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1603720128168618",
"created_by": "project:chromium",
"created_ts": "1603716399540572",
"id": "8865375188594305648",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a609b585b844b47de3870ed40acff98128d44144\"}}",
"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\": \"a609b585b844b47de3870ed40acff98128d44144\", \"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-32-1-kqo6\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15285, \"clang_revision\": \"llvmorg-12-init-5627-gf086e85e-2\", \"got_angle_revision\": \"08c34aef96255495d8371a14086f59f2504f32cf\", \"got_dawn_revision\": \"42103bc2e911144745d8d8930b07ba7e58553a34\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"a609b585b844b47de3870ed40acff98128d44144\", \"got_revision_cp\": \"refs/heads/master@{#820721}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"df17a76102dfabb3f1bd6e51449cece9f77b45e3\", \"got_v8_revision\": \"73fe59b55662be0ff07e79da1ece8b9e42415dca\", \"got_v8_revision_cp\": \"refs/heads/8.8.171@{#1}\", \"got_webrtc_revision\": \"e6fa238f76e50a8caa0547074f75443bbfd7b986\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32482}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a609b585b844b47de3870ed40acff98128d44144\", \"swarm_hashes_refs/heads/master(at){#820721}_without_patch\": {\"absl_hardening_tests\": \"14aeec043ab17ab4b1bcd8600e58e8347e021051\", \"accessibility_unittests\": \"5ff157dea27093f940ebea6c71acc39e65dd312f\", \"angle_unittests\": \"488766a10c79b8b50a709d8209917389d7caa8de\", \"app_shell_unittests\": \"57efcdb6e654c63a11a51144b9a36fadff9580ad\", \"aura_unittests\": \"8d8002bf1e8d26d82806b83d789b47544801a292\", \"base_unittests\": \"0432fe382de960acc3fc8e6c19a363aecc76a966\", \"base_util_unittests\": \"a5add9a8502d67806cb0b25d006199fc9659ccbe\", \"blink_common_unittests\": \"b311f540a307d2e53015719d6ad1a7d2bd072e0e\", \"blink_fuzzer_unittests\": \"33a54d53b04a36abcb91c2e1a30f9cc23d77d961\", \"blink_heap_unittests\": \"d244e2981485abb2a1e719193112a500812c042a\", \"blink_platform_unittests\": \"9a2c62a273091bd737bd5474bed641024a07ef9d\", \"blink_unittests\": \"b893d05a2930c3a469b406003a146fcdf6456b33\", \"boringssl_crypto_tests\": \"74e37545d39b7af93f7b3b9d8a8231b44f6f5792\", \"boringssl_ssl_tests\": \"fa7a4971034d2121602668f6b9ba4b989314fc0d\", \"browser_switcher_bho_unittests\": \"24611f1375a012c6f92e16a03bdd233dda42fefc\", \"browser_tests\": \"3b647da3d216a3520b5c13994382621f1106cb93\", \"capture_unittests\": \"e059230ad8edb3fd67aa9d59a2602a2505759659\", \"cast_unittests\": \"9f993736f197dcb333bd23f87c66581b866ddee2\", \"cc_unittests\": \"075d9e53105448e8140852f7663b25a0d5b5d8f4\", \"chrome_app_unittests\": \"3ddf25beb12ed019e2adb6270574ffde46e175ec\", \"chrome_cleaner_unittests\": \"b99c665aaf7532bc585918e5063fed591ad18f77\", \"chrome_elf_unittests\": \"1752f9d45e0461d209cab30a69612c1f21debc8b\", \"chromedriver_unittests\": \"8876f4910a273a04004a1056cb6486b12b99773f\", \"components_browsertests\": \"d96156220680cb1070f8fcbaeafb914bd901ed3c\", \"components_unittests\": \"985c3540fb77086e58944de71543abf23b94a8eb\", \"compositor_unittests\": \"7fe18fb972b236f747fe053e50ad7a74018826fb\", \"content_browsertests\": \"1a8bbf2859d175d68a5a8c430c0a0cd12684e0d5\", \"content_unittests\": \"4eb1a4fb935ad1d0ca23d0eade33b604201ef070\", \"courgette_unittests\": \"abf7651b2ddb95fbbab8b80c44c8e501a704d694\", \"crashpad_tests\": \"8404a68b436ddec1506808a57824c7c9576e5644\", \"cronet_tests\": \"a57a7c4e43b5355a06280bbd1f0d71c123354ff4\", \"cronet_unittests\": \"e295bf614f24f09f1531408219eb972c649895ff\", \"crypto_unittests\": \"5f54de5a7dbc560963341e59037be97d820fca33\", \"delayloads_unittests\": \"bebb6377e00694f8393904b76a685d977fdb93ef\", \"device_unittests\": \"fc62c3b326dffe22441bbb8e7226187026464ce1\", \"display_unittests\": \"cba8e052086498f5250aaf40bdbaa7d7bc00c823\", \"elevation_service_unittests\": \"e63aaa830f9dc7a02bc84207680cfe7185f8b70c\", \"events_unittests\": \"5d893655b1a6801c5ac89edda273bc76b5352363\", \"extensions_browsertests\": \"4a3632f1b231316494ce27227a5b1608b8b27cc7\", \"extensions_unittests\": \"1f35ae9b1c2227fd56a6dc9d188f37070b8f7943\", \"filesystem_service_unittests\": \"3feab0e678ab1c674af8b560aa59c26c9e6d2173\", \"gcm_unit_tests\": \"fadd14b451206a2c831df748a4905de8c82748b8\", \"gcp_unittests\": \"c8815f3c35d05cdd7484c87570fb4f913ff86257\", \"gfx_unittests\": \"73d634bb4d05641c16e737aabebc3ccc27b9480e\", \"gin_unittests\": \"da018adeb1886ee3150a1b1d852f5fb296f2f083\", \"google_apis_unittests\": \"bbeb144357e2b607880967fce4f6a14e81862792\", \"gpu_unittests\": \"6c1d5a1ecde0ddd3dc148c8bceb35fa50730c46b\", \"gwp_asan_unittests\": \"e882272f8c96a61a7df4432623bad490ec79bfc2\", \"headless_browsertests\": \"df4730dbb7d69b5025f469663b268d1513253022\", \"headless_unittests\": \"44d11c2b2817d1d092a9c329eab45e7b0ca77303\", \"install_static_unittests\": \"307111f281d3eb260a01648e63bc306401fb6468\", \"installer_util_unittests\": \"056fc27961302eee3370b5c3110df76708ecbe1f\", \"interactive_ui_tests\": \"04c5379249fc22441d1a26bbe2092ac6ed4652a8\", \"ipc_tests\": \"54fa4f77548daa12da56ff3bd261584207664573\", \"jingle_unittests\": \"db1a0c300c1a78186246ded29b6e2dac3724f3e7\", \"latency_unittests\": \"4bd5fd1bf0ece1cebc8332a214d825e3f70839cc\", \"libjingle_xmpp_unittests\": \"6babca93b29c0764866c019d18611af425a98a17\", \"media_blink_unittests\": \"c43f72d2dec33cc0ca1fc197e696227cd7c2288e\", \"media_unittests\": \"6ead220a5c71a43ec9b9433d6605d6f120faffa0\", \"message_center_unittests\": \"86d0ca88755a603628a6ed239787d75be79b25c7\", \"midi_unittests\": \"b980c910e09842b0cfd250f94027389f355cf18c\", \"mojo_core_unittests\": \"a4b2e8e3f368e3d4a1a22fec2526f5dd766d8f3a\", \"mojo_unittests\": \"915ff179e638330930b3f21d216eb404f75d451a\", \"nacl_loader_unittests\": \"f7a52a1b3456713fe59b8fee05eb4d15d895158c\", \"native_theme_unittests\": \"228ed19795b3984d548195b5f471f12dfe4a3940\", \"net_unittests\": \"5ea95cd9e0746e4e47d1d97e21d178fa6bef7327\", \"notification_helper_unittests\": \"079a8783cf89a414d2eeecac23b2e72c9a19b90d\", \"pdf_unittests\": \"45a62daff9aedd5047f98d7c8ff0c7c8d9758bd5\", \"perfetto_unittests\": \"2cb5f531764ff14f643e667fc1b2c0eafef4cdc0\", \"ppapi_unittests\": \"d582d22bb203ed363395de4c89fdc7740c130cb8\", \"printing_unittests\": \"e4a5921a6fb8276c4663a9a4ac8de2d97941ef09\", \"remoting_unittests\": \"62abcbfaf61b06ce6a3446a5208729c9146a1622\", \"sbox_integration_tests\": \"8a8de9a2844c13923735a424f78bd7bf364658d1\", \"sbox_unittests\": \"bd76f50b4194e46533f3e2dd9fb508cb1e4630cd\", \"sbox_validation_tests\": \"261f5b54735ea67679f9fa7f30c0eab2edd207b2\", \"service_manager_unittests\": \"430342500eead662552b16a42acf1a4cec640e6f\", \"services_unittests\": \"0aa5f37d5362efba8490393d4ac9ea2befb414d8\", \"setup_unittests\": \"372212e7acd2055334ec56fe5e5046317f7ae0e1\", \"shell_dialogs_unittests\": \"caeb89bd4c1beb2c433e0fea1569b5cad84de48d\", \"skia_unittests\": \"fd158c738fe1018a8ca52365c92a2b03cf415993\", \"snapshot_unittests\": \"b593a344b8d2e03cca03280189db795efc47aee3\", \"sql_unittests\": \"bb199c6df0613d6481bc3250d4fe3d3c4c6b071f\", \"storage_unittests\": \"3c0c03e0c89031231ee2a8972cc30398c216c88d\", \"sync_integration_tests\": \"204621d51312f2a00dce1c7e7698791f14823265\", \"traffic_annotation_auditor_unittests\": \"1e087c343d8591bba4149702d1c48b5d6ade00a1\", \"ui_base_unittests\": \"805c26a4676eaa12f90cdba9142d7822322284c5\", \"ui_touch_selection_unittests\": \"c750d26d483ea5022b327724981c88416dff5ae1\", \"unit_tests\": \"d00696f235f15fb8b0be0ad69ef19ba2d1bc19f9\", \"updater_tests\": \"3deba32f68c4949bb6104b6ecb458553da1d9554\", \"url_unittests\": \"ffe1b6363903d565e3c0e7d170ad1b00cdfe1224\", \"views_unittests\": \"9d28f478193400c8285d111fefe69538a9f55b08\", \"viz_unittests\": \"5d4212ac6af2ca5d18d53f4df4a9f2970d8d3294\", \"vr_common_unittests\": \"def26bf6dd84e991da4e6c8e79a59a71f5abbc98\", \"vr_pixeltests\": \"640848ad5161991dc6debc21ac2cd56308945017\", \"weblayer_browsertests\": \"9df0c22bbad5dde7cc2b0fd09af7cd57f11592a8\", \"weblayer_unittests\": \"f9775ff84887f396f355594964b4cdf4d7741845\", \"wm_unittests\": \"b9565078d56f4f8f106014cfea43aecd0fa61452\", \"wtf_unittests\": \"90208e36cf5b02804b1bcdc977d85a0d168287dc\", \"zlib_unittests\": \"618bdeb84de956ef5ae5157ec42c437f5551b424\", \"zucchini_unittests\": \"a50c4e69b77e33de6a5634b9d1b81a9889d71adc\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-1-kqo6\"], \"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\": [\"5612-26d29a7\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1603716402986096",
"status": "COMPLETED",
"status_changed_ts": "1603720128568531",
"tags": [
"build_address:luci.chromium.ci/win-asan/15285",
"builder:win-asan",
"buildset:commit/git/a609b585b844b47de3870ed40acff98128d44144",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a609b585b844b47de3870ed40acff98128d44144",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030805250739175808",
"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/8865375188594305648/+/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:4f7dc3ccaf76af10",
"user_agent:luci-scheduler"
],
"updated_ts": "1603720128586371",
"url": "https://ci.chromium.org/b/8865375188594305648",
"utcnow_ts": "1603720129675066"
}
}
[13:48:49.686] Invocation finished in 1h2m11.574683999s with status SUCCEEDED