[03:16:58.819] New invocation is queued and will start shortly
[03:16:59.990] Starting the invocation (attempt 1)
[03:17:00.018] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/94568c7816d4e08847818386ed2e6c97dad9bdaa"
[03:17:00.018] Popped gitiles commit info from properties and tags
[03:17:00.018] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:17:00.018] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:17:00.018] Buildbucket request:
{
"requestId": "8856623123947319008",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "linux-rbe-untrusted-test"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "94568c7816d4e08847818386ed2e6c97dad9bdaa",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8856623123947319008"
},
{
"key": "scheduler_job_id",
"value": "chromium/linux-rbe-untrusted-test"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[03:17:00.720] Scheduled build:
{
"id": "8691193061972486241",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "linux-rbe-untrusted-test"
},
"number": 6402,
"createdBy": "project:chromium",
"createTime": "2026-01-31T03:17:00.076287735Z",
"updateTime": "2026-01-31T03:17:00.076287735Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "94568c7816d4e08847818386ed2e6c97dad9bdaa",
"ref": "refs/heads/main"
}
}
}
[03:17:00.720] Task URL: https://cr-buildbucket.appspot.com/build/8691193061972486241
[03:17:00.720] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:2:0) after 2m58s
[03:17:01.672] Received PubSub notification, asking Buildbucket for the build status
[03:17:01.699] Build status: SCHEDULED
[03:19:58.771] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:2:0)
[03:19:58.798] Build status: SCHEDULED
[03:19:58.798] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:4:0) after 6m16s
[03:21:16.295] Received PubSub notification, asking Buildbucket for the build status
[03:21:16.313] Build status: STARTED
[03:26:15.058] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:4:0)
[03:26:15.095] Build status: STARTED
[03:26:15.096] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:6:0) after 4m5s
[03:30:20.127] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:6:0)
[03:30:20.178] Build status: STARTED
[03:30:20.178] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:7:0) after 6m35s
[03:36:55.244] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:7:0)
[03:36:55.277] Build status: STARTED
[03:36:55.277] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:8:0) after 9m33s
[03:46:28.277] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:8:0)
[03:46:28.302] Build status: STARTED
[03:46:28.302] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:9:0) after 2m20s
[03:48:48.313] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:9:0)
[03:48:48.391] Build status: STARTED
[03:48:48.391] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:10:0) after 7m57s
[03:56:45.787] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:10:0)
[03:56:45.804] Build status: STARTED
[03:56:45.804] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:11:0) after 6m32s
[04:03:17.822] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:11:0)
[04:03:17.837] Build status: STARTED
[04:03:17.837] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:12:0) after 6m34s
[04:09:51.888] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:12:0)
[04:09:51.925] Build status: STARTED
[04:09:51.925] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:13:0) after 6m3s
[04:15:55.024] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:13:0)
[04:15:55.049] Build status: STARTED
[04:15:55.049] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:14:0) after 5m22s
[04:21:17.100] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:14:0)
[04:21:17.130] Build status: STARTED
[04:21:17.131] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:15:0) after 3m44s
[04:25:01.207] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:15:0)
[04:25:01.229] Build status: STARTED
[04:25:01.229] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:16:0) after 5m42s
[04:30:43.277] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:16:0)
[04:30:43.354] Build status: STARTED
[04:30:43.354] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:17:0) after 3m25s
[04:34:08.372] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:17:0)
[04:34:08.390] Build status: STARTED
[04:34:08.390] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:18:0) after 6m0s
[04:40:08.451] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:18:0)
[04:40:08.489] Build status: STARTED
[04:40:08.489] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:19:0) after 7m7s
[04:47:15.505] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:19:0)
[04:47:15.532] Build status: STARTED
[04:47:15.532] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:20:0) after 5m1s
[04:52:16.601] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:20:0)
[04:52:16.651] Build status: STARTED
[04:52:16.651] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:21:0) after 8m28s
[05:00:44.789] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:21:0)
[05:00:44.812] Build status: STARTED
[05:00:44.812] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:22:0) after 3m30s
[05:04:14.852] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:22:0)
[05:04:14.920] Build status: STARTED
[05:04:14.920] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:23:0) after 3m12s
[05:07:26.938] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:23:0)
[05:07:27.137] Build status: STARTED
[05:07:27.137] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:24:0) after 4m57s
[05:12:24.158] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:24:0)
[05:12:24.177] Build status: STARTED
[05:12:24.177] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:25:0) after 2m41s
[05:15:05.239] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:25:0)
[05:15:05.257] Build status: STARTED
[05:15:05.258] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:26:0) after 5m15s
[05:20:20.254] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:26:0)
[05:20:20.274] Build status: STARTED
[05:20:20.275] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:27:0) after 5m41s
[05:26:01.338] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:27:0)
[05:26:01.359] Build status: STARTED
[05:26:01.359] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:28:0) after 9m47s
[05:35:48.966] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:28:0)
[05:35:48.985] Build status: STARTED
[05:35:48.985] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:29:0) after 3m5s
[05:38:54.040] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:29:0)
[05:38:54.055] Build status: STARTED
[05:38:54.055] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:30:0) after 8m42s
[05:47:36.220] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:30:0)
[05:47:36.239] Build status: STARTED
[05:47:36.239] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:31:0) after 4m57s
[05:52:33.451] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:31:0)
[05:52:33.468] Build status: STARTED
[05:52:33.468] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:32:0) after 2m52s
[05:55:25.603] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:32:0)
[05:55:25.633] Build status: STARTED
[05:55:25.633] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:33:0) after 8m59s
[06:04:24.651] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:33:0)
[06:04:24.731] Build status: STARTED
[06:04:24.731] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:34:0) after 8m47s
[06:13:12.045] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:34:0)
[06:13:12.130] Build status: STARTED
[06:13:12.130] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:35:0) after 9m20s
[06:22:32.243] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:35:0)
[06:22:32.317] Build status: STARTED
[06:22:32.317] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:36:0) after 2m51s
[06:25:23.790] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:36:0)
[06:25:23.823] Build status: STARTED
[06:25:23.823] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:37:0) after 7m8s
[06:32:32.125] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:37:0)
[06:32:32.186] Build status: STARTED
[06:32:32.186] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:38:0) after 7m41s
[06:40:13.204] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:38:0)
[06:40:13.224] Build status: STARTED
[06:40:13.224] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:39:0) after 3m51s
[06:44:04.339] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:39:0)
[06:44:04.362] Build status: STARTED
[06:44:04.362] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:40:0) after 9m17s
[06:53:21.602] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:40:0)
[06:53:21.646] Build status: STARTED
[06:53:21.647] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:41:0) after 1m34s
[06:54:55.663] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:41:0)
[06:54:55.789] Build status: STARTED
[06:54:55.789] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:42:0) after 3m0s
[06:57:56.524] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:42:0)
[06:57:56.542] Build status: STARTED
[06:57:56.542] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:43:0) after 1m40s
[06:59:36.554] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:43:0)
[06:59:36.580] Build status: STARTED
[06:59:36.580] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:44:0) after 7m48s
[07:07:24.696] Handling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:44:0)
[07:07:24.728] Build status: STARTED
[07:07:24.728] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rbe-untrusted-test:8856623123947319008:45:0) after 7m12s
[07:08:11.319] Received PubSub notification, asking Buildbucket for the build status
[07:08:11.350] Build:
{
"id": "8691193061972486241",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "linux-rbe-untrusted-test"
},
"number": 6402,
"createdBy": "project:chromium",
"createTime": "2026-01-31T03:17:00.076287735Z",
"startTime": "2026-01-31T03:21:13.988904873Z",
"endTime": "2026-01-31T07:08:11.033996311Z",
"updateTime": "2026-01-31T07:08:11.033996311Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "94568c7816d4e08847818386ed2e6c97dad9bdaa",
"ref": "refs/heads/main"
}
}
}
[07:08:11.350] Invocation finished in 3h51m12.541132825s with status SUCCEEDED