[07:41:11.117] New invocation is queued and will start shortly
[07:41:12.392] Starting the invocation (attempt 1)
[07:41:12.421] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/53659062732fdbc829c6de25db8567abe309d547"
[07:41:12.421] Popped gitiles commit info from properties and tags
[07:41:12.421] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:41:12.421] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:41:12.421] Buildbucket request:
{
"requestId": "8918846617520476384",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-asan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "53659062732fdbc829c6de25db8567abe309d547",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8918846617520476384"
},
{
"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"
}
}
[07:41:13.033] Scheduled build:
{
"id": "8753416555409536097",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-asan"
},
"number": 30265,
"createdBy": "project:chromium",
"createTime": "2024-03-15T07:41:12.508080322Z",
"updateTime": "2024-03-15T07:41:12.508080322Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "53659062732fdbc829c6de25db8567abe309d547",
"ref": "refs/heads/main"
}
}
}
[07:41:13.033] Task URL: https://cr-buildbucket.appspot.com/build/8753416555409536097
[07:41:13.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:2:0) after 5m43s
[07:41:14.072] Received PubSub notification, asking Buildbucket for the build status
[07:41:14.099] Build status: SCHEDULED
[07:43:09.278] Received PubSub notification, asking Buildbucket for the build status
[07:43:09.308] Build status: STARTED
[07:46:56.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:2:0)
[07:46:56.264] Build status: STARTED
[07:46:56.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:5:0) after 5m12s
[07:52:08.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:5:0)
[07:52:08.403] Build status: STARTED
[07:52:08.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:6:0) after 8m17s
[08:00:25.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:6:0)
[08:00:25.483] Build status: STARTED
[08:00:25.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:7:0) after 8m13s
[08:08:38.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:7:0)
[08:08:38.666] Build status: STARTED
[08:08:38.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:8:0) after 5m56s
[08:14:35.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:8:0)
[08:14:35.177] Build status: STARTED
[08:14:35.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:9:0) after 6m40s
[08:21:15.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:9:0)
[08:21:15.471] Build status: STARTED
[08:21:15.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:10:0) after 3m23s
[08:24:38.558] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:10:0)
[08:24:38.600] Build status: STARTED
[08:24:38.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:11:0) after 6m19s
[08:30:57.957] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:11:0)
[08:30:57.980] Build status: STARTED
[08:30:57.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:12:0) after 8m38s
[08:39:36.000] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:12:0)
[08:39:36.030] Build status: STARTED
[08:39:36.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:13:0) after 7m38s
[08:47:14.221] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:13:0)
[08:47:14.245] Build status: STARTED
[08:47:14.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:14:0) after 1m21s
[08:48:35.267] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:14:0)
[08:48:35.313] Build status: STARTED
[08:48:35.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:15:0) after 4m8s
[08:52:43.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:15:0)
[08:52:43.356] Build status: STARTED
[08:52:43.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:16:0) after 5m44s
[08:58:27.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:16:0)
[08:58:27.390] Build status: STARTED
[08:58:27.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:17:0) after 8m39s
[09:07:06.559] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:17:0)
[09:07:06.613] Build status: STARTED
[09:07:06.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:18:0) after 4m21s
[09:11:27.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:18:0)
[09:11:27.806] Build status: STARTED
[09:11:27.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:19:0) after 1m14s
[09:12:42.056] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:19:0)
[09:12:42.074] Build status: STARTED
[09:12:42.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:20:0) after 3m25s
[09:16:07.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:20:0)
[09:16:07.121] Build status: STARTED
[09:16:07.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:21:0) after 7m46s
[09:23:53.279] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:21:0)
[09:23:53.312] Build status: STARTED
[09:23:53.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:22:0) after 1m2s
[09:24:55.333] Handling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:22:0)
[09:24:55.438] Build status: STARTED
[09:24:55.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:8918846617520476384:23:0) after 8m18s
[09:29:52.532] Received PubSub notification, asking Buildbucket for the build status
[09:29:52.551] Build:
{
"id": "8753416555409536097",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-asan"
},
"number": 30265,
"createdBy": "project:chromium",
"createTime": "2024-03-15T07:41:12.508080322Z",
"startTime": "2024-03-15T07:43:08.733215Z",
"endTime": "2024-03-15T09:29:51.716760Z",
"updateTime": "2024-03-15T09:29:51.716760Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "53659062732fdbc829c6de25db8567abe309d547",
"ref": "refs/heads/main"
}
}
}
[09:29:52.551] Invocation finished in 1h48m41.440088061s with status SUCCEEDED