[12:44:45.068] New invocation is queued and will start shortly
[12:44:46.212] Starting the invocation (attempt 1)
[12:44:46.244] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/29d1cea19e3f0e7e54e5a686b118d7271457efab"
[12:44:46.244] Popped gitiles commit info from properties and tags
[12:44:46.244] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:44:46.244] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:44:46.244] Buildbucket request:
{
"requestId": "8951895411546455232",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "29d1cea19e3f0e7e54e5a686b118d7271457efab",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8951895411546455232"
},
{
"key": "scheduler_job_id",
"value": "chromium/linux-chromeos-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[12:44:46.683] Scheduled build:
{
"id": "8786465349588703233",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"number": 32224,
"createdBy": "project:chromium",
"createTime": "2023-03-16T12:44:46.311344269Z",
"updateTime": "2023-03-16T12:44:46.311344269Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "29d1cea19e3f0e7e54e5a686b118d7271457efab",
"ref": "refs/heads/main"
}
}
}
[12:44:46.683] Task URL: https://cr-buildbucket.appspot.com/build/8786465349588703233
[12:44:46.683] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:2:0) after 9m36s
[12:47:45.685] Received PubSub notification, asking Buildbucket for the build status
[12:47:45.702] Build status: STARTED
[12:54:22.740] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:2:0)
[12:54:22.760] Build status: STARTED
[12:54:22.760] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:4:0) after 3m1s
[12:57:23.878] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:4:0)
[12:57:23.895] Build status: STARTED
[12:57:23.896] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:5:0) after 7m12s
[13:04:36.442] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:5:0)
[13:04:36.463] Build status: STARTED
[13:04:36.463] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:6:0) after 5m39s
[13:10:15.479] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:6:0)
[13:10:15.547] Build status: STARTED
[13:10:15.547] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:7:0) after 4m40s
[13:14:55.756] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:7:0)
[13:14:55.808] Build status: STARTED
[13:14:55.808] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:8:0) after 5m11s
[13:20:06.976] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:8:0)
[13:20:06.999] Build status: STARTED
[13:20:06.999] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:9:0) after 3m35s
[13:23:42.027] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:9:0)
[13:23:42.047] Build status: STARTED
[13:23:42.047] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:10:0) after 3m31s
[13:27:13.259] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:10:0)
[13:27:13.277] Build status: STARTED
[13:27:13.277] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:11:0) after 5m47s
[13:33:00.334] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:11:0)
[13:33:00.352] Build status: STARTED
[13:33:00.352] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:12:0) after 5m8s
[13:38:08.343] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:12:0)
[13:38:08.365] Build status: STARTED
[13:38:08.365] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:13:0) after 3m0s
[13:41:08.604] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:13:0)
[13:41:08.636] Build status: STARTED
[13:41:08.636] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:14:0) after 9m55s
[13:51:03.699] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:14:0)
[13:51:03.725] Build status: STARTED
[13:51:03.725] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:15:0) after 4m54s
[13:55:57.754] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:15:0)
[13:55:57.791] Build status: STARTED
[13:55:57.791] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:16:0) after 6m42s
[14:02:39.811] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:16:0)
[14:02:39.836] Build status: STARTED
[14:02:39.836] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:17:0) after 1m33s
[14:04:12.854] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:17:0)
[14:04:12.877] Build status: STARTED
[14:04:12.877] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:18:0) after 3m31s
[14:07:43.904] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:18:0)
[14:07:43.924] Build status: STARTED
[14:07:43.924] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:19:0) after 1m19s
[14:09:02.997] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:19:0)
[14:09:03.018] Build status: STARTED
[14:09:03.018] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:20:0) after 2m28s
[14:11:31.101] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:20:0)
[14:11:31.128] Build status: STARTED
[14:11:31.128] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:21:0) after 6m19s
[14:17:50.148] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:21:0)
[14:17:50.231] Build status: STARTED
[14:17:50.231] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:22:0) after 3m9s
[14:21:00.004] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:22:0)
[14:21:00.041] Build status: STARTED
[14:21:00.041] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:23:0) after 2m37s
[14:23:37.080] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:23:0)
[14:23:37.114] Build status: STARTED
[14:23:37.114] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:24:0) after 3m21s
[14:26:58.115] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:24:0)
[14:26:58.148] Build status: STARTED
[14:26:58.148] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:25:0) after 5m11s
[14:32:09.212] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:25:0)
[14:32:09.238] Build status: STARTED
[14:32:09.238] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:26:0) after 4m27s
[14:36:36.258] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:26:0)
[14:36:36.276] Build status: STARTED
[14:36:36.276] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:27:0) after 5m1s
[14:41:37.300] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:8951895411546455232:27:0)
[14:41:37.320] Build:
{
"id": "8786465349588703233",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"number": 32224,
"createdBy": "project:chromium",
"createTime": "2023-03-16T12:44:46.311344269Z",
"startTime": "2023-03-16T12:47:43.981740Z",
"endTime": "2023-03-16T14:41:36.912954667Z",
"updateTime": "2023-03-16T14:41:36.912954667Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "29d1cea19e3f0e7e54e5a686b118d7271457efab",
"ref": "refs/heads/main"
}
}
}
[14:41:37.320] Invocation finished in 1h56m52.259666114s with status FAILED