[01:05:25.004] New invocation is queued and will start shortly
[01:05:26.190] Starting the invocation (attempt 1)
[01:05:26.216] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8b3b57edac15f1ca3269ba67be3c96c7b7ff3826"
[01:05:26.216] Popped gitiles commit info from properties and tags
[01:05:26.216] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:05:26.216] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:05:26.216] Buildbucket request:
{
"requestId": "8879914821581089920",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Fuchsia (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8b3b57edac15f1ca3269ba67be3c96c7b7ff3826",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8879914821581089920"
},
{
"key": "scheduler_job_id",
"value": "chromium/Deterministic Fuchsia (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:05:26.827] Scheduled build:
{
"id": "8714484759600854497",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Fuchsia (dbg)"
},
"number": 23869,
"createdBy": "project:chromium",
"createTime": "2025-05-19T01:05:26.262662914Z",
"updateTime": "2025-05-19T01:05:26.262662914Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8b3b57edac15f1ca3269ba67be3c96c7b7ff3826",
"ref": "refs/heads/main"
}
}
}
[01:05:26.828] Task URL: https://cr-buildbucket.appspot.com/build/8714484759600854497
[01:05:26.828] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:2:0) after 4m27s
[01:05:27.741] Received PubSub notification, asking Buildbucket for the build status
[01:05:27.766] Build status: SCHEDULED
[01:09:53.850] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:2:0)
[01:09:53.910] Build status: SCHEDULED
[01:09:53.910] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:4:0) after 4m26s
[01:14:20.180] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:4:0)
[01:14:20.265] Build status: SCHEDULED
[01:14:20.265] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:5:0) after 5m39s
[01:19:59.651] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:5:0)
[01:19:59.666] Build status: SCHEDULED
[01:19:59.666] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:6:0) after 1m12s
[01:21:11.789] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:6:0)
[01:21:11.830] Build status: SCHEDULED
[01:21:11.830] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:7:0) after 9m14s
[01:27:21.485] Received PubSub notification, asking Buildbucket for the build status
[01:27:21.528] Build status: STARTED
[01:30:26.359] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:7:0)
[01:30:26.384] Build status: STARTED
[01:30:26.384] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:9:0) after 3m41s
[01:34:07.614] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:9:0)
[01:34:07.634] Build status: STARTED
[01:34:07.634] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:10:0) after 1m40s
[01:35:47.675] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:10:0)
[01:35:47.697] Build status: STARTED
[01:35:47.697] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:11:0) after 6m50s
[01:42:37.720] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:11:0)
[01:42:37.821] Build status: STARTED
[01:42:37.821] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:12:0) after 8m14s
[01:50:51.840] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:12:0)
[01:50:51.867] Build status: STARTED
[01:50:51.867] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:13:0) after 5m18s
[01:56:09.925] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:13:0)
[01:56:09.954] Build status: STARTED
[01:56:09.954] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:14:0) after 3m26s
[01:59:35.980] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:14:0)
[01:59:35.997] Build status: STARTED
[01:59:35.997] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:15:0) after 4m5s
[02:03:41.226] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:15:0)
[02:03:41.307] Build status: STARTED
[02:03:41.307] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:16:0) after 3m31s
[02:07:12.330] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:16:0)
[02:07:12.354] Build status: STARTED
[02:07:12.354] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:17:0) after 1m35s
[02:08:47.622] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:17:0)
[02:08:47.646] Build status: STARTED
[02:08:47.646] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:18:0) after 7m0s
[02:15:47.780] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:18:0)
[02:15:47.804] Build status: STARTED
[02:15:47.804] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:19:0) after 7m48s
[02:23:35.906] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:19:0)
[02:23:35.931] Build status: STARTED
[02:23:35.931] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:20:0) after 9m46s
[02:33:21.948] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:20:0)
[02:33:21.976] Build status: STARTED
[02:33:21.976] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:21:0) after 1m14s
[02:34:36.190] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:21:0)
[02:34:36.223] Build status: STARTED
[02:34:36.223] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:22:0) after 2m27s
[02:37:03.506] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:22:0)
[02:37:03.527] Build status: STARTED
[02:37:03.527] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:23:0) after 2m8s
[02:39:11.565] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:23:0)
[02:39:11.583] Build status: STARTED
[02:39:11.583] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:24:0) after 9m3s
[02:48:14.786] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:24:0)
[02:48:14.800] Build status: STARTED
[02:48:14.800] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:25:0) after 1m43s
[02:49:57.826] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:25:0)
[02:49:57.879] Build status: STARTED
[02:49:57.879] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8879914821581089920:26:0) after 6m37s
[02:52:04.736] Received PubSub notification, asking Buildbucket for the build status
[02:52:04.855] Build:
{
"id": "8714484759600854497",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Fuchsia (dbg)"
},
"number": 23869,
"createdBy": "project:chromium",
"createTime": "2025-05-19T01:05:26.262662914Z",
"startTime": "2025-05-19T01:27:21.223983739Z",
"endTime": "2025-05-19T02:52:04.331809153Z",
"updateTime": "2025-05-19T02:52:04.331809153Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8b3b57edac15f1ca3269ba67be3c96c7b7ff3826",
"ref": "refs/heads/main"
}
}
}
[02:52:04.855] Invocation finished in 1h46m39.864621818s with status SUCCEEDED