[15:36:06.062] New invocation is queued and will start shortly
[15:36:07.184] Starting the invocation (attempt 1)
[15:36:07.214] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fa97d36442e1a51a174249bd125fc2c496dad6c8"
[15:36:07.214] Popped gitiles commit info from properties and tags
[15:36:07.214] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:36:07.214] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:36:07.214] Buildbucket request:
{
"requestId": "8895623915019880768",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-rel-cft"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fa97d36442e1a51a174249bd125fc2c496dad6c8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8895623915019880768"
},
{
"key": "scheduler_job_id",
"value": "chromium/linux-rel-cft"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[15:36:07.851] Scheduled build:
{
"id": "8730193853048875601",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-rel-cft"
},
"number": 9904,
"createdBy": "project:chromium",
"createTime": "2024-11-26T15:36:07.307641267Z",
"updateTime": "2024-11-26T15:36:07.307641267Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fa97d36442e1a51a174249bd125fc2c496dad6c8",
"ref": "refs/heads/main"
}
}
}
[15:36:07.851] Task URL: https://cr-buildbucket.appspot.com/build/8730193853048875601
[15:36:07.851] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:2:0) after 8m22s
[15:36:08.795] Received PubSub notification, asking Buildbucket for the build status
[15:36:08.870] Build status: SCHEDULED
[15:44:29.867] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:2:0)
[15:44:29.890] Build status: SCHEDULED
[15:44:29.890] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:4:0) after 7m53s
[15:50:25.551] Received PubSub notification, asking Buildbucket for the build status
[15:50:25.574] Build status: STARTED
[15:52:23.021] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:4:0)
[15:52:23.051] Build status: STARTED
[15:52:23.051] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:6:0) after 8m44s
[16:01:07.066] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:6:0)
[16:01:07.102] Build status: STARTED
[16:01:07.102] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:7:0) after 1m9s
[16:02:16.411] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:7:0)
[16:02:16.467] Build status: STARTED
[16:02:16.467] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:8:0) after 5m43s
[16:07:59.614] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:8:0)
[16:07:59.643] Build status: STARTED
[16:07:59.643] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:9:0) after 7m31s
[16:15:30.660] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:9:0)
[16:15:30.697] Build status: STARTED
[16:15:30.697] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:10:0) after 7m18s
[16:22:48.681] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:10:0)
[16:22:48.702] Build status: STARTED
[16:22:48.702] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:11:0) after 7m26s
[16:30:14.816] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:11:0)
[16:30:14.841] Build status: STARTED
[16:30:14.841] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:12:0) after 3m45s
[16:33:59.906] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:12:0)
[16:33:59.933] Build status: STARTED
[16:33:59.934] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:13:0) after 8m39s
[16:42:39.070] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:13:0)
[16:42:39.153] Build status: STARTED
[16:42:39.153] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:14:0) after 1m42s
[16:44:21.177] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:14:0)
[16:44:21.216] Build status: STARTED
[16:44:21.216] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:15:0) after 2m58s
[16:47:19.419] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:15:0)
[16:47:19.452] Build status: STARTED
[16:47:19.452] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:16:0) after 1m31s
[16:48:50.535] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:16:0)
[16:48:50.566] Build status: STARTED
[16:48:50.566] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:17:0) after 7m32s
[16:56:22.604] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:17:0)
[16:56:22.628] Build status: STARTED
[16:56:22.628] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:18:0) after 7m51s
[17:04:13.644] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:18:0)
[17:04:13.664] Build status: STARTED
[17:04:13.664] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:19:0) after 4m0s
[17:08:13.723] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:19:0)
[17:08:13.751] Build status: STARTED
[17:08:13.751] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:20:0) after 1m27s
[17:09:40.820] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:20:0)
[17:09:40.838] Build status: STARTED
[17:09:40.839] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:21:0) after 1m32s
[17:11:13.021] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:21:0)
[17:11:13.040] Build status: STARTED
[17:11:13.040] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:22:0) after 6m53s
[17:18:06.022] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:22:0)
[17:18:06.081] Build status: STARTED
[17:18:06.081] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:23:0) after 9m10s
[17:27:16.135] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:23:0)
[17:27:16.152] Build status: STARTED
[17:27:16.152] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:24:0) after 6m8s
[17:33:24.168] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:24:0)
[17:33:24.199] Build status: STARTED
[17:33:24.199] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:25:0) after 6m32s
[17:39:56.216] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:25:0)
[17:39:56.233] Build status: STARTED
[17:39:56.234] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:26:0) after 9m48s
[17:49:44.253] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:26:0)
[17:49:44.273] Build status: STARTED
[17:49:44.273] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:27:0) after 1m20s
[17:51:04.334] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:27:0)
[17:51:04.355] Build status: STARTED
[17:51:04.355] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:28:0) after 3m15s
[17:54:19.372] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:28:0)
[17:54:19.390] Build status: STARTED
[17:54:19.390] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:29:0) after 9m22s
[18:03:41.414] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:29:0)
[18:03:41.431] Build status: STARTED
[18:03:41.431] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:30:0) after 3m34s
[18:07:15.508] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:30:0)
[18:07:15.712] Build status: STARTED
[18:07:15.712] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:31:0) after 7m18s
[18:14:33.741] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:31:0)
[18:14:33.762] Build status: STARTED
[18:14:33.762] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:32:0) after 6m49s
[18:21:22.777] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:32:0)
[18:21:22.796] Build status: STARTED
[18:21:22.796] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:33:0) after 6m44s
[18:28:06.814] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:33:0)
[18:28:06.832] Build status: STARTED
[18:28:06.832] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:34:0) after 8m3s
[18:36:09.851] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:34:0)
[18:36:09.870] Build status: STARTED
[18:36:09.870] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:35:0) after 4m10s
[18:40:19.938] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:35:0)
[18:40:19.958] Build status: STARTED
[18:40:19.958] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-cft:8895623915019880768:36:0) after 6m9s
[18:40:37.562] Received PubSub notification, asking Buildbucket for the build status
[18:40:37.581] Build:
{
"id": "8730193853048875601",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-rel-cft"
},
"number": 9904,
"createdBy": "project:chromium",
"createTime": "2024-11-26T15:36:07.307641267Z",
"startTime": "2024-11-26T15:50:25.352553562Z",
"endTime": "2024-11-26T18:40:37.016363467Z",
"updateTime": "2024-11-26T18:40:37.016363467Z",
"status": "INFRA_FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fa97d36442e1a51a174249bd125fc2c496dad6c8",
"ref": "refs/heads/main"
}
}
}
[18:40:37.581] Invocation finished in 3h4m31.537904035s with status FAILED