[03:25:58.062] New invocation is queued and will start shortly
[03:25:59.154] Starting the invocation (attempt 1)
[03:25:59.209] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:25:59.209] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:25:59.209] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020874858602031680",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1f2f0669de70fe624f4d8e75107ff10a5c4a45ca\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020874858602031680",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/1f2f0669de70fe624f4d8e75107ff10a5c4a45ca",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1f2f0669de70fe624f4d8e75107ff10a5c4a45ca",
"gitiles_ref:refs/heads/master"
]
}
[03:26:00.929] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613186759338439",
"id": "8855444796598760192",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1f2f0669de70fe624f4d8e75107ff10a5c4a45ca\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613186760523615",
"tags": [
"build_address:luci.chromium.ci/win-asan/17312",
"builder:win-asan",
"buildset:commit/git/1f2f0669de70fe624f4d8e75107ff10a5c4a45ca",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1f2f0669de70fe624f4d8e75107ff10a5c4a45ca",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020874858602031680",
"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/8855444796598760192/+/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": "1613186760523737",
"url": "https://ci.chromium.org/b/8855444796598760192",
"utcnow_ts": "1613186760910023"
}
}
[03:26:00.929] Task URL: https://ci.chromium.org/b/8855444796598760192
[03:26:00.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:2:0) after 1m0s
[03:26:02.949] Received PubSub notification, asking Buildbucket for the build status
[03:26:03.084] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:00.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:2:0)
[03:27:00.978] Timer tick, asking Buildbucket for the build status
[03:27:01.047] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:01.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:4:0) after 1m0s
[03:28:01.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:4:0)
[03:28:01.626] Timer tick, asking Buildbucket for the build status
[03:28:01.713] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:01.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:5:0) after 1m0s
[03:29:01.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:5:0)
[03:29:01.914] Timer tick, asking Buildbucket for the build status
[03:29:01.985] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:01.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:6:0) after 1m0s
[03:30:02.029] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:6:0)
[03:30:02.029] Timer tick, asking Buildbucket for the build status
[03:30:02.796] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:02.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:7:0) after 1m0s
[03:31:02.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:7:0)
[03:31:02.897] Timer tick, asking Buildbucket for the build status
[03:31:03.067] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:03.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:8:0) after 1m0s
[03:32:03.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:8:0)
[03:32:03.084] Timer tick, asking Buildbucket for the build status
[03:32:03.177] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:03.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:9:0) after 1m0s
[03:33:03.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:9:0)
[03:33:03.239] Timer tick, asking Buildbucket for the build status
[03:33:03.516] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:03.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:10:0) after 1m0s
[03:34:03.501] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:10:0)
[03:34:03.501] Timer tick, asking Buildbucket for the build status
[03:34:03.693] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:03.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:11:0) after 1m0s
[03:35:03.711] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:11:0)
[03:35:03.711] Timer tick, asking Buildbucket for the build status
[03:35:03.798] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:03.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:12:0) after 1m0s
[03:36:03.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:12:0)
[03:36:03.986] Timer tick, asking Buildbucket for the build status
[03:36:04.146] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:04.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:13:0) after 1m0s
[03:37:04.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:13:0)
[03:37:04.372] Timer tick, asking Buildbucket for the build status
[03:37:04.442] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:04.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:14:0) after 1m0s
[03:38:04.514] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:14:0)
[03:38:04.514] Timer tick, asking Buildbucket for the build status
[03:38:04.586] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:04.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:15:0) after 1m0s
[03:39:04.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:15:0)
[03:39:04.783] Timer tick, asking Buildbucket for the build status
[03:39:04.987] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:04.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:16:0) after 1m0s
[03:40:05.118] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:16:0)
[03:40:05.118] Timer tick, asking Buildbucket for the build status
[03:40:05.201] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:05.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:17:0) after 1m0s
[03:41:05.557] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:17:0)
[03:41:05.557] Timer tick, asking Buildbucket for the build status
[03:41:05.864] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:05.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:18:0) after 1m0s
[03:42:05.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:18:0)
[03:42:05.965] Timer tick, asking Buildbucket for the build status
[03:42:06.049] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:06.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:19:0) after 1m0s
[03:43:06.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:19:0)
[03:43:06.156] Timer tick, asking Buildbucket for the build status
[03:43:06.229] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:06.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:20:0) after 1m0s
[03:44:06.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:20:0)
[03:44:06.256] Timer tick, asking Buildbucket for the build status
[03:44:06.508] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:06.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:21:0) after 1m0s
[03:45:06.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:21:0)
[03:45:06.572] Timer tick, asking Buildbucket for the build status
[03:45:06.648] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:06.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:22:0) after 1m0s
[03:46:06.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:22:0)
[03:46:06.768] Timer tick, asking Buildbucket for the build status
[03:46:06.911] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:06.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:23:0) after 1m0s
[03:47:06.941] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:23:0)
[03:47:06.941] Timer tick, asking Buildbucket for the build status
[03:47:07.217] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:07.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:24:0) after 1m0s
[03:48:07.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:24:0)
[03:48:07.320] Timer tick, asking Buildbucket for the build status
[03:48:07.412] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:07.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:25:0) after 1m0s
[03:49:07.417] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:25:0)
[03:49:07.417] Timer tick, asking Buildbucket for the build status
[03:49:07.862] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:07.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:26:0) after 1m0s
[03:50:07.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:26:0)
[03:50:07.960] Timer tick, asking Buildbucket for the build status
[03:50:08.065] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:08.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:27:0) after 1m0s
[03:51:08.130] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:27:0)
[03:51:08.130] Timer tick, asking Buildbucket for the build status
[03:51:08.590] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:08.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:28:0) after 1m0s
[03:52:08.610] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:28:0)
[03:52:08.610] Timer tick, asking Buildbucket for the build status
[03:52:08.706] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:08.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:29:0) after 1m0s
[03:53:09.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:29:0)
[03:53:09.313] Timer tick, asking Buildbucket for the build status
[03:53:09.723] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:09.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:30:0) after 1m0s
[03:54:09.735] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:30:0)
[03:54:09.735] Timer tick, asking Buildbucket for the build status
[03:54:10.137] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:10.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:31:0) after 1m0s
[03:55:10.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:31:0)
[03:55:10.124] Timer tick, asking Buildbucket for the build status
[03:55:10.246] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:10.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:32:0) after 1m0s
[03:56:10.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:32:0)
[03:56:10.288] Timer tick, asking Buildbucket for the build status
[03:56:10.394] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:10.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:33:0) after 1m0s
[03:57:10.417] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:33:0)
[03:57:10.417] Timer tick, asking Buildbucket for the build status
[03:57:10.918] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:10.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:34:0) after 1m0s
[03:58:10.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:34:0)
[03:58:10.930] Timer tick, asking Buildbucket for the build status
[03:58:11.546] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:11.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:35:0) after 1m0s
[03:59:11.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:35:0)
[03:59:11.589] Timer tick, asking Buildbucket for the build status
[03:59:11.895] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:11.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:36:0) after 1m0s
[04:00:11.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:36:0)
[04:00:11.965] Timer tick, asking Buildbucket for the build status
[04:00:12.379] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:12.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:37:0) after 1m0s
[04:01:12.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:37:0)
[04:01:12.411] Timer tick, asking Buildbucket for the build status
[04:01:12.517] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:12.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:38:0) after 1m0s
[04:02:12.637] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:38:0)
[04:02:12.637] Timer tick, asking Buildbucket for the build status
[04:02:12.735] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:12.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:39:0) after 1m0s
[04:03:12.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:39:0)
[04:03:12.775] Timer tick, asking Buildbucket for the build status
[04:03:13.225] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:13.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:40:0) after 1m0s
[04:04:13.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:40:0)
[04:04:13.233] Timer tick, asking Buildbucket for the build status
[04:04:13.507] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:13.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:41:0) after 1m0s
[04:05:13.554] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:41:0)
[04:05:13.554] Timer tick, asking Buildbucket for the build status
[04:05:13.678] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:13.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:42:0) after 1m0s
[04:06:13.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:42:0)
[04:06:13.710] Timer tick, asking Buildbucket for the build status
[04:06:13.807] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:13.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:43:0) after 1m0s
[04:07:13.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:43:0)
[04:07:13.877] Timer tick, asking Buildbucket for the build status
[04:07:14.230] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:14.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:44:0) after 1m0s
[04:08:14.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:44:0)
[04:08:14.320] Timer tick, asking Buildbucket for the build status
[04:08:14.437] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:14.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:45:0) after 1m0s
[04:09:14.455] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:45:0)
[04:09:14.455] Timer tick, asking Buildbucket for the build status
[04:09:14.532] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:14.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:46:0) after 1m0s
[04:10:14.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:46:0)
[04:10:14.577] Timer tick, asking Buildbucket for the build status
[04:10:14.673] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:14.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:47:0) after 1m0s
[04:11:14.782] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:47:0)
[04:11:14.782] Timer tick, asking Buildbucket for the build status
[04:11:14.909] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:14.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:48:0) after 1m0s
[04:12:15.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:48:0)
[04:12:15.628] Timer tick, asking Buildbucket for the build status
[04:12:15.722] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:15.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:49:0) after 1m0s
[04:13:15.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:49:0)
[04:13:15.746] Timer tick, asking Buildbucket for the build status
[04:13:15.830] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:15.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:50:0) after 1m0s
[04:14:15.850] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:50:0)
[04:14:15.850] Timer tick, asking Buildbucket for the build status
[04:14:15.955] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:15.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:51:0) after 1m0s
[04:15:15.983] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:51:0)
[04:15:15.983] Timer tick, asking Buildbucket for the build status
[04:15:16.062] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:16.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:52:0) after 1m0s
[04:16:16.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:52:0)
[04:16:16.097] Timer tick, asking Buildbucket for the build status
[04:16:16.204] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:16.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:53:0) after 1m0s
[04:17:16.283] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:53:0)
[04:17:16.283] Timer tick, asking Buildbucket for the build status
[04:17:16.372] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:16.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:54:0) after 1m0s
[04:18:16.420] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:54:0)
[04:18:16.420] Timer tick, asking Buildbucket for the build status
[04:18:16.835] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:16.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:55:0) after 1m0s
[04:19:16.882] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:55:0)
[04:19:16.882] Timer tick, asking Buildbucket for the build status
[04:19:17.505] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:17.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:56:0) after 1m0s
[04:20:17.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:56:0)
[04:20:17.540] Timer tick, asking Buildbucket for the build status
[04:20:17.638] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:17.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:57:0) after 1m0s
[04:21:17.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:57:0)
[04:21:17.708] Timer tick, asking Buildbucket for the build status
[04:21:17.811] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:17.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:58:0) after 1m0s
[04:22:17.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:58:0)
[04:22:17.831] Timer tick, asking Buildbucket for the build status
[04:22:17.924] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:17.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:59:0) after 1m0s
[04:23:17.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:59:0)
[04:23:17.978] Timer tick, asking Buildbucket for the build status
[04:23:18.055] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:18.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:60:0) after 1m0s
[04:24:18.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:60:0)
[04:24:18.145] Timer tick, asking Buildbucket for the build status
[04:24:18.393] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:18.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:61:0) after 1m0s
[04:25:18.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:61:0)
[04:25:18.619] Timer tick, asking Buildbucket for the build status
[04:25:18.872] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:18.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:62:0) after 1m0s
[04:26:19.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:62:0)
[04:26:19.054] Timer tick, asking Buildbucket for the build status
[04:26:19.156] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:19.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:63:0) after 1m0s
[04:27:19.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:63:0)
[04:27:19.299] Timer tick, asking Buildbucket for the build status
[04:27:19.402] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:19.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:64:0) after 1m0s
[04:28:19.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:64:0)
[04:28:19.505] Timer tick, asking Buildbucket for the build status
[04:28:19.617] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:19.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:65:0) after 1m0s
[04:29:19.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:65:0)
[04:29:19.734] Timer tick, asking Buildbucket for the build status
[04:29:19.859] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:19.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:66:0) after 1m0s
[04:30:19.999] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:66:0)
[04:30:19.999] Timer tick, asking Buildbucket for the build status
[04:30:20.105] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:20.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:67:0) after 1m0s
[04:31:20.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:67:0)
[04:31:20.282] Timer tick, asking Buildbucket for the build status
[04:31:20.569] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:20.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:68:0) after 1m0s
[04:32:20.709] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:68:0)
[04:32:20.709] Timer tick, asking Buildbucket for the build status
[04:32:20.821] Build 8855444796598760192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:20.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020874858602031680:69:0) after 1m0s
[04:32:53.376] Received PubSub notification, asking Buildbucket for the build status
[04:32:53.714] Build 8855444796598760192: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:32:53.714] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613190770904153",
"created_by": "project:chromium",
"created_ts": "1613186759338439",
"id": "8855444796598760192",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1f2f0669de70fe624f4d8e75107ff10a5c4a45ca\"}}",
"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\": \"1f2f0669de70fe624f4d8e75107ff10a5c4a45ca\", \"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-ckf5\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17312, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"a8a2a71b3ab5e535def6239997f6f24da918556b\", \"got_dawn_revision\": \"e61c9d86884145733d5763ab23c15bf09acf358c\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"1f2f0669de70fe624f4d8e75107ff10a5c4a45ca\", \"got_revision_cp\": \"refs/heads/master@{#853792}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"539ef8e9426e10d7611d433ee60b94de0737344a\", \"got_v8_revision\": \"554b7ee535b3eca3f041dc45d00de5cbd490729c\", \"got_v8_revision_cp\": \"refs/heads/9.0.172@{#1}\", \"got_webrtc_revision\": \"f28d7e89b7a3c985d83f59d16b9f3c3f45ef7997\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33224}\", \"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\": \"1f2f0669de70fe624f4d8e75107ff10a5c4a45ca\", \"swarm_hashes_refs/heads/master(at){#853792}_without_patch\": {\"absl_hardening_tests\": \"cab65a96b7f9d4018f4c5b420788ad710779aea0\", \"accessibility_unittests\": \"6c5358000c594b03dc9f58caee29f6a8283a9afb\", \"angle_unittests\": \"81f38342b573f337bb1bcc2241f92d65e637f7a6\", \"app_shell_unittests\": \"e6263d01183dff815a7d538351bba1dbe90905e8\", \"aura_unittests\": \"28d8f7fe43c2bdd336333c93604a993077a0fe9e\", \"base_unittests\": \"0f3987b1881e707443b2a5ccd35cec05d1f72fde\", \"base_util_unittests\": \"869ad8a5e329be7c5ffd01448f9ab08c50d46af9\", \"blink_common_unittests\": \"375153e0e66a2e3ce5e62c05c7ca4a6d906821b0\", \"blink_fuzzer_unittests\": \"5f883872d177f6677eb8bb88b5172db196615627\", \"blink_heap_unittests\": \"e1622c8a62d2998bef7bf168aedc296aead59ede\", \"blink_platform_unittests\": \"2a3790b3b6444ce2236bbf780aa1391c7f4b0540\", \"blink_unittests\": \"3f39011114c914c016238af80ca1380ad0fb2914\", \"boringssl_crypto_tests\": \"3947522680a016ab699281db3076d8ac4d78d5ed\", \"boringssl_ssl_tests\": \"56e99ecc4b5f5f095829fccc48b59b904f3d0442\", \"browser_switcher_bho_unittests\": \"a2cd2b5c7affe06c9ff054d80e1f7ea07d1be609\", \"browser_tests\": \"79d633121980289094e5fa0d1d87c0c53914212a\", \"capture_unittests\": \"cbcc0f9e57d036ce41e6607919188db017973617\", \"cast_unittests\": \"ef0c6ed104c09c2c3e68de9ed91017bbdf0ceb0c\", \"cc_unittests\": \"21287c5f3a911e5add4ba05caa2b50cfcb2a5161\", \"chrome_app_unittests\": \"557bfdfb3ccb9e4c2dea41f541a7ac54c4e28377\", \"chrome_cleaner_unittests\": \"418a88d7bc96574a3b8c4305580ce08c1e2e59ac\", \"chrome_elf_unittests\": \"4af9bd5bf79e128953a9ab84dbd438207c23305c\", \"chromedriver_unittests\": \"16eda871fdf1486bf86deb2d453ecfa7d7117a65\", \"components_browsertests\": \"06f01a9ae10e4339aef31c9cf723c86683978365\", \"components_unittests\": \"4481bad2df81133f4c45a1e4f4a517a720b31919\", \"compositor_unittests\": \"df6a8a49121dd3effc21eda7a19ac4c275e6c2f2\", \"content_browsertests\": \"6c42ef3c4a42a05ab9a7f8ef57a452797d7c05d9\", \"content_unittests\": \"02aafe6a507c51e3a44f1c2c79a2f14ea6658459\", \"courgette_unittests\": \"340a0ac146eff99b3b387db5f4f8d0fc746a5269\", \"crashpad_tests\": \"c63ebb3684a1d13f0bf7d2bae96d42296f4f699c\", \"cronet_tests\": \"33b536b39f711e4c67ae7c7578199b57565349d6\", \"cronet_unittests\": \"103472280fa0f6bd9842093303d87bc474403d30\", \"crypto_unittests\": \"d83c9683f7d649e48772501bf3bc695b39390610\", \"delayloads_unittests\": \"f92d0b2bddf697d8537e4a42b33a796dbde2090a\", \"device_unittests\": \"7fe6417800a591dd34ffa0d29a736275d92d8ade\", \"display_unittests\": \"2ff383fa3775178ef7973626025d26681532f053\", \"elevation_service_unittests\": \"940a65c192f98b2ca98bf1e354472b9129aaafd2\", \"events_unittests\": \"8ce651f527a4affe52a7b9db285fca8588a9f23f\", \"extensions_browsertests\": \"19d352dfcd660e669b7810c034470e7125883ede\", \"extensions_unittests\": \"01784252b7b9461c8c03a1c55532ab288f70edc2\", \"filesystem_service_unittests\": \"7889ad105775bda28f4e682bb2f0b592b5fda7bd\", \"gcm_unit_tests\": \"c9f0469d286603e1f6a248db72c82767df394cc4\", \"gcp_unittests\": \"e9fff7edb61f648a9626bedc97434cc9b1e630d2\", \"gfx_unittests\": \"3c964e7e2d005e96001db06a23b92c6aff6a5bea\", \"gin_unittests\": \"d7bab827b9646fcdc2922f637990878386216668\", \"google_apis_unittests\": \"19960faf7aa606cd4dd6298608fb7e277a29d9f2\", \"gpu_unittests\": \"1523bd99d348c91ea095150f1ebd15c7d59aa3ca\", \"gwp_asan_unittests\": \"40cd1238840f09174e246da626b01a72b35b0b3c\", \"headless_browsertests\": \"19aa0bc8aaf80479b3fe0911bf1c9867ac3c3da9\", \"headless_unittests\": \"e3eba6d923cd8207024f019c1673378297b92381\", \"install_static_unittests\": \"70ef8e1f7721862865e80967182cd85963397353\", \"installer_util_unittests\": \"a8886a15717f3e62a64f527388d03f8d2ac732cb\", \"interactive_ui_tests\": \"1bd68908aff30fff7eb63412501f4c1d16ccebcc\", \"ipc_tests\": \"c57640b5b11ea43dba2dfacc542ae878ef9e566c\", \"jingle_unittests\": \"1a6882a63aaa21f61ebe88eb380b08721a58505d\", \"latency_unittests\": \"bd336ebc2e4b8d601cac9065020707dce983b20d\", \"libjingle_xmpp_unittests\": \"044262307c9b2288a96f9753a507fbc699967837\", \"liburlpattern_unittests\": \"e7e1aa39216a7b1f70385bc2c337b339764a9bb5\", \"media_blink_unittests\": \"bed1f97ac42f8dc918a9aa19f27100fa465081ac\", \"media_unittests\": \"14715ca194ed678f8f92aabc0a272b28bd4710bd\", \"message_center_unittests\": \"61ad9acb84d0de330cd0203e6d35c71f262e78e4\", \"midi_unittests\": \"0e5d88eb52d84941520df26929cb6c32758452b6\", \"mojo_core_unittests\": \"5eafe105f3988248dc37d8cbefbe461ae94ca188\", \"mojo_unittests\": \"a29a564321a6571c7a8c967a2bfd7c20a1e0e05a\", \"nacl_loader_unittests\": \"0c8beee81c201da6440f736c0f519dac994be3ed\", \"native_theme_unittests\": \"43cdee9bf0298ce0e3e39cbbe243c60ddc5c2b45\", \"net_unittests\": \"b5bed4d117c15ee9c6791c7b96afac47c5016c2f\", \"notification_helper_unittests\": \"45ef55fbaa52fdbf2e8f71f2b40b23f61702645c\", \"pdf_unittests\": \"02f1885617d449a46992548d8e7306b39bc068f0\", \"perfetto_unittests\": \"df7d5075e5651ac7a1bc70ca8ffe6d2ec194e4ff\", \"ppapi_unittests\": \"133a100405d7956bae2e233fe5cfa9d295a88946\", \"printing_unittests\": \"73fcbad1e629c09e2e500a25e5ac7daf67c9b679\", \"remoting_unittests\": \"da0ced05690189e3f166defdffb44f92cfec6ee7\", \"sbox_integration_tests\": \"8bcf450e6b56b4f763c37f62e0e20fa2656206a4\", \"sbox_unittests\": \"49bf95b9ed2564534fca7af7b772584b52413333\", \"sbox_validation_tests\": \"a18e1003301e407d7a51114e6951f80601e3a34e\", \"service_manager_unittests\": \"a6dc11d0de9c48084738ceca9212fe1fdf831879\", \"services_unittests\": \"c63532960cb97423f749ed305c74119b2f4e21a7\", \"setup_unittests\": \"5adb04363ca67e6e2c423e23ba7dfbfed3cabaa4\", \"shell_dialogs_unittests\": \"91c3648a84f9a830f90c135db9b6f5080bf613c5\", \"skia_unittests\": \"44b301c3ea58cc0e0184e5a72fc3c26ccc61a488\", \"snapshot_unittests\": \"359273de29fdfcede3305b5509de7e0f10aa00ec\", \"sql_unittests\": \"2ee6b0909840c89af14b9392cb83d5f7916022db\", \"storage_unittests\": \"36cb78126bb994541225d760c719f39f1edf9b02\", \"sync_integration_tests\": \"dc1451c8ca1682ebe33293e9e0fa21ff3108a6be\", \"traffic_annotation_auditor_unittests\": \"0884b5e275ce85eb24083dcc1b41e4b1f838959d\", \"ui_base_unittests\": \"4db22cfcd9fcb90c9abb1e7dbcfc75ce11824952\", \"ui_touch_selection_unittests\": \"b67dc58573a207b40ea5b2cbfac01c28c25def59\", \"unit_tests\": \"475b3af84719d717e8ef29912e7822c791e796f7\", \"updater_tests\": \"f73dbe2738bc9b7003969519285007ac7ccc372d\", \"url_unittests\": \"78ddfd0aae8968a349dd0fa92ed3181afc014df9\", \"views_unittests\": \"63f0a80ae05c3d0fdd18c5c68bc908f93a45229b\", \"viz_unittests\": \"4493b6c297f2a650ad7f610f25a6114717173d77\", \"vr_common_unittests\": \"c5d8bb377706de82bd8966d4fe27b054be492f37\", \"vr_pixeltests\": \"18c8b619cd54cf9c276522c40ea2f119d729bdf3\", \"weblayer_browsertests\": \"1f194c73d432180ed12c0a02c4b4234e8c9dec6d\", \"weblayer_unittests\": \"e21f13864894ce3454656498538f60195853ec1d\", \"wm_unittests\": \"bd004b33ca835ad0d41aa2a8c9a55a1725af4ce9\", \"wtf_unittests\": \"d897973aa65e834395e945df3b0dcd93f7375a8c\", \"zlib_unittests\": \"5aebbc5acb17292270dbda2ce9b454a8bd60ae27\", \"zucchini_unittests\": \"eab0bee0f095e9930462801261aadbc18350b556\"}}, \"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-ckf5\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"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\": [\"5690-40331dd\"], \"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": "1613186761980253",
"status": "COMPLETED",
"status_changed_ts": "1613190771371319",
"tags": [
"build_address:luci.chromium.ci/win-asan/17312",
"builder:win-asan",
"buildset:commit/git/1f2f0669de70fe624f4d8e75107ff10a5c4a45ca",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1f2f0669de70fe624f4d8e75107ff10a5c4a45ca",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020874858602031680",
"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/8855444796598760192/+/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:51b23e095cfca210",
"user_agent:luci-scheduler"
],
"updated_ts": "1613190771477942",
"url": "https://ci.chromium.org/b/8855444796598760192",
"utcnow_ts": "1613190773704439"
}
}
[04:32:53.714] Invocation finished in 1h6m55.669981234s with status SUCCEEDED