[18:31:18.444] New invocation is queued and will start shortly
[18:31:19.512] Starting the invocation (attempt 1)
[18:31:19.622] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d42f3722d4cef84b20f2094b7329355baba9aabf"
[18:31:19.622] Popped gitiles commit info from properties and tags
[18:31:19.622] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:31:19.622] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:31:19.622] Buildbucket request:
{
"requestId": "8987659409847054016",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-asan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d42f3722d4cef84b20f2094b7329355baba9aabf",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8987659409847054016"
},
{
"key": "scheduler_job_id",
"value": "chromium/win-asan"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:31:20.149] Scheduled build:
{
"id": "8822229347877007649",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-asan"
},
"number": 21189,
"createdBy": "project:chromium",
"createTime": "2022-02-14T18:31:19.692736363Z",
"updateTime": "2022-02-14T18:31:19.692736363Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d42f3722d4cef84b20f2094b7329355baba9aabf",
"ref": "refs/heads/main"
}
}
}
[18:31:20.149] Task URL: https://cr-buildbucket.appspot.com/build/8822229347877007649
[18:31:20.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:2:0) after 7m51s
[18:39:11.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:2:0)
[18:39:12.002] Build status: SCHEDULED
[18:39:12.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:3:0) after 4m32s
[18:39:25.827] Received PubSub notification, asking Buildbucket for the build status
[18:39:25.847] Build status: STARTED
[18:43:44.486] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:3:0)
[18:43:44.558] Build status: STARTED
[18:43:44.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:5:0) after 1m3s
[18:44:47.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:5:0)
[18:44:47.897] Build status: STARTED
[18:44:47.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:6:0) after 1m41s
[18:46:28.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:6:0)
[18:46:28.947] Build status: STARTED
[18:46:28.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:7:0) after 8m45s
[18:55:14.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:7:0)
[18:55:14.116] Build status: STARTED
[18:55:14.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:8:0) after 8m17s
[19:03:31.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:8:0)
[19:03:31.302] Build status: STARTED
[19:03:31.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:9:0) after 8m31s
[19:12:02.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:9:0)
[19:12:02.484] Build status: STARTED
[19:12:02.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:10:0) after 6m28s
[19:18:30.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:10:0)
[19:18:30.627] Build status: STARTED
[19:18:30.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:11:0) after 5m3s
[19:23:33.782] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:11:0)
[19:23:33.802] Build status: STARTED
[19:23:33.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:12:0) after 7m32s
[19:31:05.890] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:12:0)
[19:31:05.910] Build status: STARTED
[19:31:05.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:13:0) after 1m26s
[19:32:32.014] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:13:0)
[19:32:32.071] Build status: STARTED
[19:32:32.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:14:0) after 6m32s
[19:39:04.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:14:0)
[19:39:04.420] Build status: STARTED
[19:39:04.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:15:0) after 6m44s
[19:45:48.560] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:15:0)
[19:45:48.588] Build status: STARTED
[19:45:48.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:16:0) after 5m57s
[19:51:45.688] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:16:0)
[19:51:45.729] Build status: STARTED
[19:51:45.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:17:0) after 6m39s
[19:58:24.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:17:0)
[19:58:24.771] Build status: STARTED
[19:58:24.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:18:0) after 6m43s
[20:05:07.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:18:0)
[20:05:07.984] Build status: STARTED
[20:05:07.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:19:0) after 2m33s
[20:07:41.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:19:0)
[20:07:41.818] Build status: STARTED
[20:07:41.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:20:0) after 9m30s
[20:17:11.891] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:20:0)
[20:17:11.925] Build status: STARTED
[20:17:11.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:21:0) after 3m51s
[20:21:02.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:21:0)
[20:21:03.016] Build status: STARTED
[20:21:03.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:22:0) after 6m12s
[20:27:15.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:22:0)
[20:27:15.080] Build status: STARTED
[20:27:15.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:23:0) after 1m47s
[20:29:02.130] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:23:0)
[20:29:02.159] Build status: STARTED
[20:29:02.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:24:0) after 1m58s
[20:31:00.191] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:24:0)
[20:31:00.214] Build status: STARTED
[20:31:00.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:25:0) after 7m57s
[20:38:57.240] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:25:0)
[20:38:57.263] Build status: STARTED
[20:38:57.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:26:0) after 4m22s
[20:43:19.343] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:26:0)
[20:43:19.379] Build status: STARTED
[20:43:19.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:27:0) after 3m41s
[20:47:00.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:27:0)
[20:47:00.458] Build status: STARTED
[20:47:00.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8987659409847054016:28:0) after 6m7s
[20:49:44.322] Received PubSub notification, asking Buildbucket for the build status
[20:49:44.343] Build:
{
"id": "8822229347877007649",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-asan"
},
"number": 21189,
"createdBy": "project:chromium",
"createTime": "2022-02-14T18:31:19.692736363Z",
"startTime": "2022-02-14T18:39:25.273433Z",
"endTime": "2022-02-14T20:49:43.751854178Z",
"updateTime": "2022-02-14T20:49:43.751854178Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d42f3722d4cef84b20f2094b7329355baba9aabf",
"ref": "refs/heads/main"
}
}
}
[20:49:44.343] Invocation finished in 2h18m25.914323419s with status SUCCEEDED