[01:36:15.475] New invocation is queued and will start shortly
[01:36:16.715] Starting the invocation (attempt 1)
[01:36:16.741] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/46f0407b0f5d410b0a362c63a20889d48d295931"
[01:36:16.741] Popped gitiles commit info from properties and tags
[01:36:16.741] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:36:16.741] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:36:16.742] Buildbucket request:
{
"requestId": "8866595127155644976",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "46f0407b0f5d410b0a362c63a20889d48d295931",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8866595127155644976"
},
{
"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"
}
}
[01:36:17.535] Scheduled build:
{
"id": "8701165065091291553",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 30620,
"createdBy": "project:chromium",
"createTime": "2025-10-13T01:36:16.819940335Z",
"updateTime": "2025-10-13T01:36:16.819940335Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "46f0407b0f5d410b0a362c63a20889d48d295931",
"ref": "refs/heads/main"
}
},
"canary": true
}
[01:36:17.535] Task URL: https://cr-buildbucket.appspot.com/build/8701165065091291553
[01:36:17.535] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:2:0) after 7m51s
[01:36:18.469] Received PubSub notification, asking Buildbucket for the build status
[01:36:18.499] Build status: SCHEDULED
[01:37:12.690] Received PubSub notification, asking Buildbucket for the build status
[01:37:12.713] Build status: STARTED
[01:44:08.526] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:2:0)
[01:44:08.591] Build status: STARTED
[01:44:08.591] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:5:0) after 4m11s
[01:48:19.606] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:5:0)
[01:48:19.630] Build status: STARTED
[01:48:19.630] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:6:0) after 9m43s
[01:58:02.740] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:6:0)
[01:58:02.759] Build status: STARTED
[01:58:02.759] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:7:0) after 5m4s
[02:03:06.846] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:7:0)
[02:03:06.866] Build status: STARTED
[02:03:06.866] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:8:0) after 1m18s
[02:04:24.869] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:8:0)
[02:04:24.888] Build status: STARTED
[02:04:24.889] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:9:0) after 2m58s
[02:07:22.854] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:9:0)
[02:07:22.884] Build status: STARTED
[02:07:22.884] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:10:0) after 8m47s
[02:16:09.978] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:10:0)
[02:16:10.005] Build status: STARTED
[02:16:10.005] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:11:0) after 2m6s
[02:18:16.293] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:11:0)
[02:18:16.306] Build status: STARTED
[02:18:16.306] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:12:0) after 3m14s
[02:21:30.320] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:12:0)
[02:21:30.334] Build status: STARTED
[02:21:30.334] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:13:0) after 5m57s
[02:27:27.311] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:13:0)
[02:27:27.325] Build status: STARTED
[02:27:27.325] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:14:0) after 2m25s
[02:29:52.609] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:14:0)
[02:29:52.626] Build status: STARTED
[02:29:52.626] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:15:0) after 2m26s
[02:32:18.638] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:15:0)
[02:32:18.661] Build status: STARTED
[02:32:18.661] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:16:0) after 7m48s
[02:40:06.934] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:16:0)
[02:40:06.947] Build status: STARTED
[02:40:06.947] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:17:0) after 9m31s
[02:49:37.990] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:17:0)
[02:49:38.016] Build status: STARTED
[02:49:38.016] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:18:0) after 8m42s
[02:58:20.032] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:18:0)
[02:58:20.045] Build status: STARTED
[02:58:20.045] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:19:0) after 4m6s
[03:02:26.143] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:19:0)
[03:02:26.192] Build status: STARTED
[03:02:26.192] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:20:0) after 1m30s
[03:03:56.205] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:20:0)
[03:03:56.233] Build status: STARTED
[03:03:56.233] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:21:0) after 8m24s
[03:12:20.342] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:21:0)
[03:12:20.369] Build status: STARTED
[03:12:20.369] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:22:0) after 7m12s
[03:19:32.383] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:22:0)
[03:19:32.420] Build status: STARTED
[03:19:32.420] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:23:0) after 3m3s
[03:22:35.433] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:23:0)
[03:22:35.457] Build status: STARTED
[03:22:35.457] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:24:0) after 4m32s
[03:27:07.471] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:24:0)
[03:27:07.535] Build status: STARTED
[03:27:07.535] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:25:0) after 5m4s
[03:32:11.553] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:25:0)
[03:32:11.571] Build status: STARTED
[03:32:11.571] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:26:0) after 7m14s
[03:39:25.585] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:26:0)
[03:39:25.606] Build status: STARTED
[03:39:25.606] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:27:0) after 6m27s
[03:45:52.624] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:27:0)
[03:45:52.652] Build status: STARTED
[03:45:52.652] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:28:0) after 6m19s
[03:52:11.858] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:28:0)
[03:52:11.884] Build status: STARTED
[03:52:11.885] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:29:0) after 8m27s
[04:00:39.166] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:29:0)
[04:00:39.195] Build status: STARTED
[04:00:39.195] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:30:0) after 3m54s
[04:04:33.215] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:30:0)
[04:04:33.246] Build status: STARTED
[04:04:33.246] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:31:0) after 2m7s
[04:06:40.349] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:31:0)
[04:06:40.365] Build status: STARTED
[04:06:40.365] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:32:0) after 1m23s
[04:08:03.563] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:32:0)
[04:08:03.583] Build status: STARTED
[04:08:03.583] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:33:0) after 8m25s
[04:16:28.653] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:33:0)
[04:16:28.670] Build status: STARTED
[04:16:28.670] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8866595127155644976:34:0) after 6m42s
[04:18:47.083] Received PubSub notification, asking Buildbucket for the build status
[04:18:47.105] Build:
{
"id": "8701165065091291553",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 30620,
"createdBy": "project:chromium",
"createTime": "2025-10-13T01:36:16.819940335Z",
"startTime": "2025-10-13T01:37:12.206779810Z",
"endTime": "2025-10-13T04:18:46.604861341Z",
"updateTime": "2025-10-13T04:18:46.604861341Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "46f0407b0f5d410b0a362c63a20889d48d295931",
"ref": "refs/heads/main"
}
},
"canary": true
}
[04:18:47.105] Invocation finished in 2h42m31.638942323s with status SUCCEEDED