[23:39:08.596] New invocation is queued and will start shortly
[23:39:09.727] Starting the invocation (attempt 1)
[23:39:09.758] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4fa6f68256658ed82b5d407d32c1d8b5fb1d65a8"
[23:39:09.758] Popped gitiles commit info from properties and tags
[23:39:09.758] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:39:09.758] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:39:09.759] Buildbucket request:
{
"requestId": "8895140539795247536",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4fa6f68256658ed82b5d407d32c1d8b5fb1d65a8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8895140539795247536"
},
{
"key": "scheduler_job_id",
"value": "chromium/Linux CFI"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[23:39:10.582] Scheduled build:
{
"id": "8729710477782386801",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 28750,
"createdBy": "project:chromium",
"createTime": "2024-12-01T23:39:09.884113195Z",
"updateTime": "2024-12-01T23:39:09.884113195Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4fa6f68256658ed82b5d407d32c1d8b5fb1d65a8",
"ref": "refs/heads/main"
}
}
}
[23:39:10.582] Task URL: https://cr-buildbucket.appspot.com/build/8729710477782386801
[23:39:10.582] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:2:0) after 7m40s
[23:39:11.530] Received PubSub notification, asking Buildbucket for the build status
[23:39:11.547] Build status: SCHEDULED
[23:39:18.183] Received PubSub notification, asking Buildbucket for the build status
[23:39:18.203] Build status: STARTED
[23:46:50.600] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:2:0)
[23:46:50.637] Build status: STARTED
[23:46:50.637] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:5:0) after 1m2s
[23:47:52.655] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:5:0)
[23:47:52.674] Build status: STARTED
[23:47:52.674] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:6:0) after 8m55s
[23:56:47.691] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:6:0)
[23:56:47.722] Build status: STARTED
[23:56:47.722] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:7:0) after 3m50s
[00:00:37.739] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:7:0)
[00:00:37.916] Build status: STARTED
[00:00:37.916] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:8:0) after 2m49s
[00:03:27.176] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:8:0)
[00:03:27.201] Build status: STARTED
[00:03:27.201] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:9:0) after 5m24s
[00:08:52.224] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:9:0)
[00:08:52.348] Build status: STARTED
[00:08:52.348] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:10:0) after 8m32s
[00:17:24.583] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:10:0)
[00:17:24.616] Build status: STARTED
[00:17:24.616] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:11:0) after 4m6s
[00:21:30.632] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:11:0)
[00:21:30.650] Build status: STARTED
[00:21:30.650] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:12:0) after 6m5s
[00:27:35.919] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:12:0)
[00:27:36.006] Build status: STARTED
[00:27:36.006] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:13:0) after 8m9s
[00:35:45.234] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:13:0)
[00:35:45.315] Build status: STARTED
[00:35:45.315] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:14:0) after 9m49s
[00:45:34.329] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:14:0)
[00:45:34.371] Build status: STARTED
[00:45:34.371] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:15:0) after 6m45s
[00:52:19.419] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:15:0)
[00:52:19.444] Build status: STARTED
[00:52:19.444] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:16:0) after 9m29s
[01:01:48.502] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:16:0)
[01:01:48.520] Build status: STARTED
[01:01:48.520] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:17:0) after 6m3s
[01:07:51.736] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:17:0)
[01:07:51.759] Build status: STARTED
[01:07:51.759] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:18:0) after 8m25s
[01:16:16.752] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:18:0)
[01:16:16.792] Build status: STARTED
[01:16:16.792] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:19:0) after 3m5s
[01:19:21.890] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:19:0)
[01:19:21.913] Build status: STARTED
[01:19:21.913] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:20:0) after 6m45s
[01:26:06.930] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:20:0)
[01:26:06.946] Build status: STARTED
[01:26:06.946] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:21:0) after 6m57s
[01:33:03.965] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:21:0)
[01:33:03.984] Build status: STARTED
[01:33:03.984] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:22:0) after 3m50s
[01:36:54.003] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:22:0)
[01:36:54.045] Build status: STARTED
[01:36:54.045] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:23:0) after 3m52s
[01:40:46.070] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:23:0)
[01:40:46.089] Build status: STARTED
[01:40:46.089] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:24:0) after 5m24s
[01:46:10.105] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:24:0)
[01:46:10.121] Build status: STARTED
[01:46:10.121] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:25:0) after 3m53s
[01:50:03.156] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:25:0)
[01:50:03.174] Build status: STARTED
[01:50:03.174] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:26:0) after 1m24s
[01:51:27.363] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:26:0)
[01:51:27.389] Build status: STARTED
[01:51:27.389] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:27:0) after 9m18s
[02:00:45.406] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:27:0)
[02:00:45.443] Build status: STARTED
[02:00:45.443] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:28:0) after 3m7s
[02:03:52.544] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:28:0)
[02:03:52.560] Build status: STARTED
[02:03:52.560] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:29:0) after 3m16s
[02:07:08.622] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:29:0)
[02:07:08.642] Build status: STARTED
[02:07:08.642] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:30:0) after 2m21s
[02:09:29.867] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:30:0)
[02:09:29.882] Build status: STARTED
[02:09:29.882] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:31:0) after 2m47s
[02:12:16.870] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:31:0)
[02:12:16.888] Build status: STARTED
[02:12:16.888] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:32:0) after 7m26s
[02:19:43.022] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:32:0)
[02:19:43.137] Build status: STARTED
[02:19:43.137] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:33:0) after 3m17s
[02:23:00.307] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:33:0)
[02:23:00.332] Build status: STARTED
[02:23:00.332] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:34:0) after 5m14s
[02:28:14.622] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:34:0)
[02:28:14.638] Build status: STARTED
[02:28:14.638] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:35:0) after 7m34s
[02:35:48.712] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:35:0)
[02:35:48.729] Build status: STARTED
[02:35:48.729] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:36:0) after 2m2s
[02:37:50.930] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:36:0)
[02:37:50.952] Build status: STARTED
[02:37:50.952] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:37:0) after 3m30s
[02:41:21.036] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:37:0)
[02:41:21.144] Build status: STARTED
[02:41:21.144] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:38:0) after 6m45s
[02:48:06.160] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:38:0)
[02:48:06.176] Build status: STARTED
[02:48:06.176] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:39:0) after 4m28s
[02:52:34.191] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:39:0)
[02:52:34.208] Build status: STARTED
[02:52:34.208] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:40:0) after 3m19s
[02:55:53.325] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:40:0)
[02:55:53.343] Build status: STARTED
[02:55:53.343] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:41:0) after 7m49s
[03:03:42.363] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:41:0)
[03:03:42.382] Build status: STARTED
[03:03:42.382] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:42:0) after 9m48s
[03:13:30.399] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:42:0)
[03:13:30.425] Build status: STARTED
[03:13:30.425] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:43:0) after 9m12s
[03:22:42.559] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:43:0)
[03:22:42.576] Build status: STARTED
[03:22:42.576] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8895140539795247536:44:0) after 6m24s
[03:24:06.666] Received PubSub notification, asking Buildbucket for the build status
[03:24:06.681] Build:
{
"id": "8729710477782386801",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 28750,
"createdBy": "project:chromium",
"createTime": "2024-12-01T23:39:09.884113195Z",
"startTime": "2024-12-01T23:39:17.877370708Z",
"endTime": "2024-12-02T03:24:05.845737573Z",
"updateTime": "2024-12-02T03:24:05.845737573Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4fa6f68256658ed82b5d407d32c1d8b5fb1d65a8",
"ref": "refs/heads/main"
}
}
}
[03:24:06.681] Invocation finished in 3h44m58.099887123s with status SUCCEEDED