[14:23:10.107] New invocation is queued and will start shortly
[14:23:11.152] Starting the invocation (attempt 1)
[14:23:11.175] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/973c7a908d8da3dd49bba8e39cd884847a773833"
[14:23:11.175] Popped gitiles commit info from properties and tags
[14:23:11.175] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:23:11.176] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:23:11.176] Buildbucket request:
{
"requestId": "8858846135168938784",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "linux-rbe-trusted-test"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "973c7a908d8da3dd49bba8e39cd884847a773833",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8858846135168938784"
},
{
"key": "scheduler_job_id",
"value": "chromium/linux-rbe-trusted-test"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:23:11.631] Scheduled build:
{
"id": "8693416073319780001",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "linux-rbe-trusted-test"
},
"number": 4093,
"createdBy": "project:chromium",
"createTime": "2026-01-06T14:23:11.249076415Z",
"updateTime": "2026-01-06T14:23:11.249076415Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "973c7a908d8da3dd49bba8e39cd884847a773833",
"ref": "refs/heads/main"
}
}
}
[14:23:11.631] Task URL: https://cr-buildbucket.appspot.com/build/8693416073319780001
[14:23:11.631] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:2:0) after 6m25s
[14:23:33.901] Received PubSub notification, asking Buildbucket for the build status
[14:23:33.919] Build status: STARTED
[14:23:44.082] Received PubSub notification, asking Buildbucket for the build status
[14:23:44.125] Build status: STARTED
[14:29:36.645] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:2:0)
[14:29:36.698] Build status: STARTED
[14:29:36.698] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:5:0) after 6m14s
[14:35:50.755] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:5:0)
[14:35:50.794] Build status: STARTED
[14:35:50.794] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:6:0) after 7m6s
[14:42:56.811] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:6:0)
[14:42:56.902] Build status: STARTED
[14:42:56.902] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:7:0) after 4m19s
[14:47:15.920] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:7:0)
[14:47:15.940] Build status: STARTED
[14:47:15.940] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:8:0) after 9m5s
[14:56:21.086] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:8:0)
[14:56:21.103] Build status: STARTED
[14:56:21.103] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:9:0) after 3m34s
[14:59:55.111] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:9:0)
[14:59:55.134] Build status: STARTED
[14:59:55.134] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:10:0) after 6m47s
[15:06:42.159] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:10:0)
[15:06:42.237] Build status: STARTED
[15:06:42.237] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:11:0) after 9m55s
[15:16:37.342] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:11:0)
[15:16:37.373] Build status: STARTED
[15:16:37.373] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:12:0) after 4m7s
[15:20:44.406] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:12:0)
[15:20:44.421] Build status: STARTED
[15:20:44.421] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:13:0) after 3m50s
[15:24:34.437] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:13:0)
[15:24:34.452] Build status: STARTED
[15:24:34.452] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:14:0) after 3m34s
[15:28:08.467] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:14:0)
[15:28:08.485] Build status: STARTED
[15:28:08.485] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:15:0) after 7m51s
[15:35:59.514] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:15:0)
[15:35:59.536] Build status: STARTED
[15:35:59.536] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:16:0) after 4m0s
[15:39:59.524] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:16:0)
[15:39:59.551] Build status: STARTED
[15:39:59.551] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:17:0) after 3m47s
[15:43:46.588] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:17:0)
[15:43:46.613] Build status: STARTED
[15:43:46.613] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:18:0) after 5m32s
[15:49:18.633] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:18:0)
[15:49:18.699] Build status: STARTED
[15:49:18.699] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:19:0) after 6m47s
[15:56:05.740] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:19:0)
[15:56:05.759] Build status: STARTED
[15:56:05.759] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:20:0) after 1m12s
[15:57:17.768] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:20:0)
[15:57:17.810] Build status: STARTED
[15:57:17.810] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:21:0) after 4m55s
[16:02:12.827] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:21:0)
[16:02:12.843] Build status: STARTED
[16:02:12.843] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:22:0) after 5m3s
[16:07:15.915] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:22:0)
[16:07:15.936] Build status: STARTED
[16:07:15.936] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:23:0) after 3m30s
[16:10:45.948] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:23:0)
[16:10:45.986] Build status: STARTED
[16:10:45.986] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:24:0) after 3m32s
[16:14:18.083] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:24:0)
[16:14:18.146] Build status: STARTED
[16:14:18.146] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:25:0) after 2m34s
[16:16:52.347] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:25:0)
[16:16:52.381] Build status: STARTED
[16:16:52.382] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:26:0) after 7m54s
[16:24:46.728] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:26:0)
[16:24:46.744] Build status: STARTED
[16:24:46.744] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:27:0) after 4m13s
[16:28:59.773] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:27:0)
[16:28:59.793] Build status: STARTED
[16:28:59.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:28:0) after 8m0s
[16:36:59.909] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:28:0)
[16:36:59.967] Build status: STARTED
[16:36:59.967] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:29:0) after 2m54s
[16:39:53.985] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:29:0)
[16:39:54.000] Build status: STARTED
[16:39:54.000] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:30:0) after 4m13s
[16:44:07.025] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:30:0)
[16:44:07.049] Build status: STARTED
[16:44:07.049] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:31:0) after 2m45s
[16:46:52.065] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:31:0)
[16:46:52.099] Build status: STARTED
[16:46:52.099] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:32:0) after 2m23s
[16:49:15.117] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:32:0)
[16:49:15.135] Build status: STARTED
[16:49:15.135] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:33:0) after 8m57s
[16:58:12.131] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:33:0)
[16:58:12.146] Build status: STARTED
[16:58:12.146] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:34:0) after 4m39s
[17:02:51.135] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:34:0)
[17:02:51.157] Build status: STARTED
[17:02:51.157] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:35:0) after 7m40s
[17:10:31.237] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:35:0)
[17:10:31.253] Build status: STARTED
[17:10:31.253] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-trusted-test:8858846135168938784:36:0) after 6m18s
[17:13:09.773] Received PubSub notification, asking Buildbucket for the build status
[17:13:09.793] Build:
{
"id": "8693416073319780001",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "linux-rbe-trusted-test"
},
"number": 4093,
"createdBy": "project:chromium",
"createTime": "2026-01-06T14:23:11.249076415Z",
"startTime": "2026-01-06T14:23:15.334849558Z",
"endTime": "2026-01-06T17:13:06.975205677Z",
"updateTime": "2026-01-06T17:13:06.975205677Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "973c7a908d8da3dd49bba8e39cd884847a773833",
"ref": "refs/heads/main"
}
}
}
[17:13:09.793] Invocation finished in 2h49m59.691188026s with status SUCCEEDED