[11:51:17.003] New invocation is queued and will start shortly
[11:51:18.484] Starting the invocation (attempt 1)
[11:51:18.509] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6a24fbdd833f4d4dc213c635503240c94090e6ad"
[11:51:18.509] Popped gitiles commit info from properties and tags
[11:51:18.510] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:51:18.510] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:51:18.510] Buildbucket request:
{
"requestId": "8868730760334705552",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6a24fbdd833f4d4dc213c635503240c94090e6ad",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8868730760334705552"
},
{
"key": "scheduler_job_id",
"value": "chromium/linux-chromeos-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[11:51:19.327] Scheduled build:
{
"id": "8703300697970239329",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"number": 42335,
"createdBy": "project:chromium",
"createTime": "2025-09-19T11:51:18.593318419Z",
"updateTime": "2025-09-19T11:51:18.593318419Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6a24fbdd833f4d4dc213c635503240c94090e6ad",
"ref": "refs/heads/main"
}
}
}
[11:51:19.327] Task URL: https://cr-buildbucket.appspot.com/build/8703300697970239329
[11:51:19.327] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:2:0) after 2m34s
[11:51:20.227] Received PubSub notification, asking Buildbucket for the build status
[11:51:20.250] Build status: SCHEDULED
[11:51:28.628] Received PubSub notification, asking Buildbucket for the build status
[11:51:28.643] Build status: STARTED
[11:53:53.655] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:2:0)
[11:53:53.677] Build status: STARTED
[11:53:53.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:5:0) after 2m45s
[11:56:39.051] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:5:0)
[11:56:39.087] Build status: STARTED
[11:56:39.087] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:6:0) after 3m40s
[12:00:19.081] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:6:0)
[12:00:19.113] Build status: STARTED
[12:00:19.113] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:7:0) after 8m25s
[12:08:44.149] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:7:0)
[12:08:44.182] Build status: STARTED
[12:08:44.182] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:8:0) after 5m18s
[12:14:02.201] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:8:0)
[12:14:02.331] Build status: STARTED
[12:14:02.331] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:9:0) after 8m12s
[12:22:14.847] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:9:0)
[12:22:14.860] Build status: STARTED
[12:22:14.861] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:10:0) after 3m9s
[12:25:23.889] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:10:0)
[12:25:23.932] Build status: STARTED
[12:25:23.932] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:11:0) after 2m33s
[12:27:56.947] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:11:0)
[12:27:56.976] Build status: STARTED
[12:27:56.976] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:12:0) after 3m2s
[12:30:59.040] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:12:0)
[12:30:59.110] Build status: STARTED
[12:30:59.110] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:13:0) after 1m56s
[12:32:55.078] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:13:0)
[12:32:55.112] Build status: STARTED
[12:32:55.112] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:14:0) after 6m44s
[12:39:39.233] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:14:0)
[12:39:39.261] Build status: STARTED
[12:39:39.261] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:15:0) after 5m45s
[12:45:24.329] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:15:0)
[12:45:24.353] Build status: STARTED
[12:45:24.353] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:16:0) after 9m20s
[12:54:44.372] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:16:0)
[12:54:44.439] Build status: STARTED
[12:54:44.439] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:17:0) after 4m2s
[12:58:46.453] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:17:0)
[12:58:46.471] Build status: STARTED
[12:58:46.471] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:18:0) after 3m29s
[13:02:15.490] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:18:0)
[13:02:15.507] Build status: STARTED
[13:02:15.507] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:19:0) after 8m48s
[13:11:03.553] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:19:0)
[13:11:03.571] Build status: STARTED
[13:11:03.571] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:20:0) after 8m34s
[13:19:37.589] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:20:0)
[13:19:37.604] Build status: STARTED
[13:19:37.605] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8868730760334705552:21:0) after 7m35s
[13:21:23.375] Received PubSub notification, asking Buildbucket for the build status
[13:21:23.425] Build:
{
"id": "8703300697970239329",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"number": 42335,
"createdBy": "project:chromium",
"createTime": "2025-09-19T11:51:18.593318419Z",
"startTime": "2025-09-19T11:51:28.443531957Z",
"endTime": "2025-09-19T13:21:22.761099747Z",
"updateTime": "2025-09-19T13:21:22.761099747Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6a24fbdd833f4d4dc213c635503240c94090e6ad",
"ref": "refs/heads/main"
}
}
}
[13:21:23.425] Invocation finished in 1h30m6.470289525s with status SUCCEEDED