[17:15:40.636] New invocation is queued and will start shortly
[17:15:41.868] Starting the invocation (attempt 1)
[17:15:41.891] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/916f1756585aab0dac4fc5eb3c29796bb74118c3"
[17:15:41.891] Popped gitiles commit info from properties and tags
[17:15:41.891] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:15:41.892] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:15:41.892] Buildbucket request:
{
"requestId": "8866898411872026800",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMac (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "916f1756585aab0dac4fc5eb3c29796bb74118c3",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8866898411872026800"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTMac (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:15:42.592] Scheduled build:
{
"id": "8701468349837355233",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMac (dbg)"
},
"number": 38117,
"createdBy": "project:chromium",
"createTime": "2025-10-09T17:15:41.946996093Z",
"updateTime": "2025-10-09T17:15:41.946996093Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "916f1756585aab0dac4fc5eb3c29796bb74118c3",
"ref": "refs/heads/main"
}
}
}
[17:15:42.592] Task URL: https://cr-buildbucket.appspot.com/build/8701468349837355233
[17:15:42.592] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:2:0) after 4m31s
[17:15:43.438] Received PubSub notification, asking Buildbucket for the build status
[17:15:43.459] Build status: SCHEDULED
[17:15:46.047] Received PubSub notification, asking Buildbucket for the build status
[17:15:46.067] Build status: STARTED
[17:20:13.647] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:2:0)
[17:20:13.665] Build status: STARTED
[17:20:13.665] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:5:0) after 4m23s
[17:24:36.649] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:5:0)
[17:24:36.688] Build status: STARTED
[17:24:36.688] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:6:0) after 6m42s
[17:31:18.716] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:6:0)
[17:31:18.730] Build status: STARTED
[17:31:18.730] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:7:0) after 5m6s
[17:36:24.800] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:7:0)
[17:36:24.838] Build status: STARTED
[17:36:24.838] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:8:0) after 5m51s
[17:42:15.856] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:8:0)
[17:42:15.870] Build status: STARTED
[17:42:15.870] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:9:0) after 5m14s
[17:47:29.891] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:9:0)
[17:47:29.912] Build status: STARTED
[17:47:29.912] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:10:0) after 4m34s
[17:52:03.934] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:10:0)
[17:52:03.953] Build status: STARTED
[17:52:03.953] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:11:0) after 3m2s
[17:55:06.023] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:11:0)
[17:55:06.048] Build status: STARTED
[17:55:06.048] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:12:0) after 7m50s
[18:02:56.067] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:12:0)
[18:02:56.082] Build status: STARTED
[18:02:56.082] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:13:0) after 9m6s
[18:12:02.204] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:13:0)
[18:12:02.285] Build status: STARTED
[18:12:02.286] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:14:0) after 4m5s
[18:16:07.377] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:14:0)
[18:16:07.392] Build status: STARTED
[18:16:07.392] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:15:0) after 7m49s
[18:23:56.434] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:15:0)
[18:23:56.457] Build status: STARTED
[18:23:56.457] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:16:0) after 3m24s
[18:27:20.532] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:16:0)
[18:27:20.546] Build status: STARTED
[18:27:20.546] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:17:0) after 3m21s
[18:30:41.591] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:17:0)
[18:30:41.617] Build status: STARTED
[18:30:41.617] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:18:0) after 4m52s
[18:35:33.680] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:18:0)
[18:35:33.697] Build status: STARTED
[18:35:33.697] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:19:0) after 2m58s
[18:38:31.714] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:19:0)
[18:38:31.734] Build status: STARTED
[18:38:31.734] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:20:0) after 6m17s
[18:44:48.770] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:20:0)
[18:44:48.791] Build status: STARTED
[18:44:48.791] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:21:0) after 5m28s
[18:50:16.766] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:21:0)
[18:50:16.800] Build status: STARTED
[18:50:16.800] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:22:0) after 8m27s
[18:58:44.002] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:22:0)
[18:58:44.098] Build status: STARTED
[18:58:44.098] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:23:0) after 2m41s
[19:01:25.143] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:23:0)
[19:01:25.161] Build status: STARTED
[19:01:25.161] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:24:0) after 6m22s
[19:07:47.209] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:24:0)
[19:07:47.233] Build status: STARTED
[19:07:47.233] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:25:0) after 9m45s
[19:17:32.251] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:25:0)
[19:17:32.263] Build status: STARTED
[19:17:32.263] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:26:0) after 7m4s
[19:24:36.317] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:26:0)
[19:24:36.332] Build status: STARTED
[19:24:36.332] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:27:0) after 6m3s
[19:30:39.325] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:27:0)
[19:30:39.359] Build status: STARTED
[19:30:39.359] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:28:0) after 4m34s
[19:35:13.379] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:28:0)
[19:35:13.434] Build status: STARTED
[19:35:13.434] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:29:0) after 7m45s
[19:42:58.461] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:29:0)
[19:42:58.478] Build status: STARTED
[19:42:58.478] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:30:0) after 5m40s
[19:48:38.502] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:30:0)
[19:48:38.517] Build status: STARTED
[19:48:38.518] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:31:0) after 6m39s
[19:55:17.584] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:31:0)
[19:55:17.598] Build status: STARTED
[19:55:17.598] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:32:0) after 2m37s
[19:57:54.591] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:32:0)
[19:57:54.622] Build status: STARTED
[19:57:54.622] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:33:0) after 9m58s
[20:07:52.693] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:33:0)
[20:07:52.768] Build status: STARTED
[20:07:52.768] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:34:0) after 4m10s
[20:12:02.903] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:34:0)
[20:12:02.922] Build status: STARTED
[20:12:02.922] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:35:0) after 2m50s
[20:14:52.997] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:35:0)
[20:14:53.018] Build status: STARTED
[20:14:53.018] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:36:0) after 3m18s
[20:18:11.095] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:36:0)
[20:18:11.110] Build status: STARTED
[20:18:11.110] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:37:0) after 7m46s
[20:25:57.136] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:37:0)
[20:25:57.159] Build status: STARTED
[20:25:57.159] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:38:0) after 1m20s
[20:27:17.226] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:38:0)
[20:27:17.242] Build status: STARTED
[20:27:17.242] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:39:0) after 5m57s
[20:33:14.270] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:39:0)
[20:33:14.284] Build status: STARTED
[20:33:14.284] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:40:0) after 9m5s
[20:42:19.379] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:40:0)
[20:42:19.396] Build status: STARTED
[20:42:19.396] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:41:0) after 7m53s
[20:50:12.404] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:41:0)
[20:50:12.419] Build status: STARTED
[20:50:12.419] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:42:0) after 4m34s
[20:54:46.489] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:42:0)
[20:54:46.506] Build status: STARTED
[20:54:46.506] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:43:0) after 1m45s
[20:56:31.527] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:43:0)
[20:56:31.541] Build status: STARTED
[20:56:31.541] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:44:0) after 5m38s
[21:02:09.631] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:44:0)
[21:02:09.647] Build status: STARTED
[21:02:09.647] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:45:0) after 6m23s
[21:08:32.699] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:45:0)
[21:08:32.716] Build status: STARTED
[21:08:32.716] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:46:0) after 7m26s
[21:15:58.743] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:46:0)
[21:15:58.763] Build status: STARTED
[21:15:58.763] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:47:0) after 8m39s
[21:24:37.761] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:47:0)
[21:24:37.776] Build status: STARTED
[21:24:37.776] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:48:0) after 8m33s
[21:33:10.800] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:48:0)
[21:33:10.822] Build status: STARTED
[21:33:10.822] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:49:0) after 9m38s
[21:42:48.896] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:49:0)
[21:42:48.924] Build status: STARTED
[21:42:48.924] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:50:0) after 4m3s
[21:46:51.997] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:50:0)
[21:46:52.015] Build status: STARTED
[21:46:52.015] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:51:0) after 3m11s
[21:50:03.049] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:51:0)
[21:50:03.078] Build status: STARTED
[21:50:03.078] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:52:0) after 4m2s
[21:54:05.146] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:52:0)
[21:54:05.164] Build status: STARTED
[21:54:05.164] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:53:0) after 9m27s
[22:03:32.242] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:53:0)
[22:03:32.260] Build status: STARTED
[22:03:32.260] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:54:0) after 3m41s
[22:07:13.260] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:54:0)
[22:07:13.276] Build status: STARTED
[22:07:13.276] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:55:0) after 7m12s
[22:14:25.347] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:55:0)
[22:14:25.365] Build status: STARTED
[22:14:25.365] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:56:0) after 4m7s
[22:18:32.388] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:56:0)
[22:18:32.409] Build status: STARTED
[22:18:32.409] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:57:0) after 9m12s
[22:27:44.911] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:57:0)
[22:27:44.926] Build status: STARTED
[22:27:44.926] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:58:0) after 1m9s
[22:28:53.946] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:58:0)
[22:28:53.987] Build status: STARTED
[22:28:53.987] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:59:0) after 2m59s
[22:31:53.009] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:59:0)
[22:31:53.045] Build status: STARTED
[22:31:53.045] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:60:0) after 6m2s
[22:37:55.099] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:60:0)
[22:37:55.120] Build status: STARTED
[22:37:55.120] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:61:0) after 9m40s
[22:47:35.221] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:61:0)
[22:47:35.237] Build status: STARTED
[22:47:35.237] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:62:0) after 1m44s
[22:49:19.254] Handling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:62:0)
[22:49:19.295] Build status: STARTED
[22:49:19.296] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMac (dbg):8866898411872026800:63:0) after 5m20s
[22:53:57.294] Received PubSub notification, asking Buildbucket for the build status
[22:53:57.307] Build:
{
"id": "8701468349837355233",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMac (dbg)"
},
"number": 38117,
"createdBy": "project:chromium",
"createTime": "2025-10-09T17:15:41.946996093Z",
"startTime": "2025-10-09T17:15:45.844790241Z",
"endTime": "2025-10-09T22:53:57.036709480Z",
"updateTime": "2025-10-09T22:53:57.036709480Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "916f1756585aab0dac4fc5eb3c29796bb74118c3",
"ref": "refs/heads/main"
}
}
}
[22:53:57.307] Invocation finished in 5h38m16.684493945s with status SUCCEEDED