[20:50:01.003] New invocation is queued and will start shortly
[20:50:02.219] Starting the invocation (attempt 1)
[20:50:02.245] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4e0ad7fb97cc8d40c8c1be3872e3ecfdc600db0a"
[20:50:02.245] Popped gitiles commit info from properties and tags
[20:50:02.245] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:50:02.245] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:50:02.245] Buildbucket request:
{
"requestId": "8861449108806510704",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8861449108806510704",
"job": "chromium-m144/linux-chromeos-dbg",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "33fb3f2d20525f8c3dbb364d044b20fc90249bad"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@33fb3f2d20525f8c3dbb364d044b20fc90249bad",
"title": "33fb3f2d20525f8c3dbb364d044b20fc90249bad",
"url": "https://chromium.googlesource.com/chromium/src/+/33fb3f2d20525f8c3dbb364d044b20fc90249bad"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ee7d67447060233589febd254b49e6d3034cb0e7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@ee7d67447060233589febd254b49e6d3034cb0e7",
"title": "ee7d67447060233589febd254b49e6d3034cb0e7",
"url": "https://chromium.googlesource.com/chromium/src/+/ee7d67447060233589febd254b49e6d3034cb0e7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "924736bf248a7bbb4ec19d381b366d73717b17cb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@924736bf248a7bbb4ec19d381b366d73717b17cb",
"title": "924736bf248a7bbb4ec19d381b366d73717b17cb",
"url": "https://chromium.googlesource.com/chromium/src/+/924736bf248a7bbb4ec19d381b366d73717b17cb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "82805e6f685923d6123ff5f332169b329c8da5e6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@82805e6f685923d6123ff5f332169b329c8da5e6",
"title": "82805e6f685923d6123ff5f332169b329c8da5e6",
"url": "https://chromium.googlesource.com/chromium/src/+/82805e6f685923d6123ff5f332169b329c8da5e6"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cc176de5e26caf39c135e243edc46ea1c48bb930"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@cc176de5e26caf39c135e243edc46ea1c48bb930",
"title": "cc176de5e26caf39c135e243edc46ea1c48bb930",
"url": "https://chromium.googlesource.com/chromium/src/+/cc176de5e26caf39c135e243edc46ea1c48bb930"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5dc31257073bcd24051515663dfa563ff6a726e3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@5dc31257073bcd24051515663dfa563ff6a726e3",
"title": "5dc31257073bcd24051515663dfa563ff6a726e3",
"url": "https://chromium.googlesource.com/chromium/src/+/5dc31257073bcd24051515663dfa563ff6a726e3"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2581d6a4d0f78483ee6a77229eab5acadf5d6591"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@2581d6a4d0f78483ee6a77229eab5acadf5d6591",
"title": "2581d6a4d0f78483ee6a77229eab5acadf5d6591",
"url": "https://chromium.googlesource.com/chromium/src/+/2581d6a4d0f78483ee6a77229eab5acadf5d6591"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "047dba85fc9fd5f41a637b9436692c2e1b315afc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@047dba85fc9fd5f41a637b9436692c2e1b315afc",
"title": "047dba85fc9fd5f41a637b9436692c2e1b315afc",
"url": "https://chromium.googlesource.com/chromium/src/+/047dba85fc9fd5f41a637b9436692c2e1b315afc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "90dcebb1ffffab180fdfaa3b014419a39075cc99"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@90dcebb1ffffab180fdfaa3b014419a39075cc99",
"title": "90dcebb1ffffab180fdfaa3b014419a39075cc99",
"url": "https://chromium.googlesource.com/chromium/src/+/90dcebb1ffffab180fdfaa3b014419a39075cc99"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4e0ad7fb97cc8d40c8c1be3872e3ecfdc600db0a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@4e0ad7fb97cc8d40c8c1be3872e3ecfdc600db0a",
"title": "4e0ad7fb97cc8d40c8c1be3872e3ecfdc600db0a",
"url": "https://chromium.googlesource.com/chromium/src/+/4e0ad7fb97cc8d40c8c1be3872e3ecfdc600db0a"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4e0ad7fb97cc8d40c8c1be3872e3ecfdc600db0a",
"ref": "refs/branch-heads/7559"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8861449108806510704"
},
{
"key": "scheduler_job_id",
"value": "chromium-m144/linux-chromeos-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:50:02.787] Scheduled build:
{
"id": "8696019046739768129",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"number": 87,
"createdBy": "project:chromium-m144",
"createTime": "2025-12-08T20:50:02.352616720Z",
"updateTime": "2025-12-08T20:50:02.352616720Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4e0ad7fb97cc8d40c8c1be3872e3ecfdc600db0a",
"ref": "refs/branch-heads/7559"
}
}
}
[20:50:02.787] Task URL: https://cr-buildbucket.appspot.com/build/8696019046739768129
[20:50:02.787] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:2:0) after 2m24s
[20:50:02.869] Received PubSub notification, asking Buildbucket for the build status
[20:50:02.884] Build status: SCHEDULED
[20:50:58.489] Received PubSub notification, asking Buildbucket for the build status
[20:50:58.506] Build status: STARTED
[20:52:26.779] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:2:0)
[20:52:26.827] Build status: STARTED
[20:52:26.827] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:5:0) after 2m28s
[20:54:55.006] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:5:0)
[20:54:55.383] Build status: STARTED
[20:54:55.384] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:6:0) after 6m46s
[21:01:41.529] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:6:0)
[21:01:41.546] Build status: STARTED
[21:01:41.546] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:7:0) after 8m5s
[21:09:46.549] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:7:0)
[21:09:46.569] Build status: STARTED
[21:09:46.570] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:8:0) after 1m54s
[21:11:40.605] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:8:0)
[21:11:40.626] Build status: STARTED
[21:11:40.626] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:9:0) after 2m27s
[21:14:07.789] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:9:0)
[21:14:07.812] Build status: STARTED
[21:14:07.812] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:10:0) after 4m38s
[21:18:45.857] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:10:0)
[21:18:45.874] Build status: STARTED
[21:18:45.874] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:11:0) after 6m17s
[21:25:02.863] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:11:0)
[21:25:02.884] Build status: STARTED
[21:25:02.884] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:12:0) after 2m22s
[21:27:24.947] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:12:0)
[21:27:24.961] Build status: STARTED
[21:27:24.961] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:13:0) after 5m42s
[21:33:06.975] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:13:0)
[21:33:07.007] Build status: STARTED
[21:33:07.007] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:14:0) after 6m2s
[21:39:09.019] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:14:0)
[21:39:09.042] Build status: STARTED
[21:39:09.042] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:15:0) after 1m51s
[21:41:00.058] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:15:0)
[21:41:00.123] Build status: STARTED
[21:41:00.123] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:16:0) after 6m44s
[21:47:44.137] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:16:0)
[21:47:44.172] Build status: STARTED
[21:47:44.172] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:17:0) after 4m46s
[21:52:30.187] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:17:0)
[21:52:30.200] Build status: STARTED
[21:52:30.201] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:18:0) after 6m10s
[21:58:40.216] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:18:0)
[21:58:40.230] Build status: STARTED
[21:58:40.230] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:19:0) after 8m43s
[22:07:23.350] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:19:0)
[22:07:23.380] Build status: STARTED
[22:07:23.380] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:20:0) after 5m20s
[22:12:43.390] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:20:0)
[22:12:43.405] Build status: STARTED
[22:12:43.405] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:21:0) after 8m32s
[22:21:15.417] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:21:0)
[22:21:15.434] Build status: STARTED
[22:21:15.434] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:22:0) after 6m54s
[22:28:09.447] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:22:0)
[22:28:09.575] Build status: STARTED
[22:28:09.575] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:23:0) after 3m6s
[22:31:15.757] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:23:0)
[22:31:15.775] Build status: STARTED
[22:31:15.775] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:24:0) after 4m11s
[22:35:26.782] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:24:0)
[22:35:26.798] Build status: STARTED
[22:35:26.798] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:25:0) after 1m22s
[22:36:48.877] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:25:0)
[22:36:48.897] Build status: STARTED
[22:36:48.897] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:26:0) after 6m18s
[22:43:06.938] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:26:0)
[22:43:06.955] Build status: STARTED
[22:43:06.955] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:27:0) after 8m3s
[22:51:09.996] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:27:0)
[22:51:10.517] Build status: STARTED
[22:51:10.517] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:28:0) after 5m14s
[22:56:24.806] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:28:0)
[22:56:24.837] Build status: STARTED
[22:56:24.837] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:29:0) after 7m9s
[23:03:33.878] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:29:0)
[23:03:33.904] Build status: STARTED
[23:03:33.904] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:30:0) after 7m29s
[23:11:02.956] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:30:0)
[23:11:03.295] Build status: STARTED
[23:11:03.295] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:31:0) after 3m26s
[23:14:29.363] Handling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:31:0)
[23:14:29.410] Build status: STARTED
[23:14:29.410] Scheduling timer "check-buildbucket-build-status" (chromium-m144/linux-chromeos-dbg:8861449108806510704:32:0) after 4m21s
[23:15:11.602] Received PubSub notification, asking Buildbucket for the build status
[23:15:11.618] Build:
{
"id": "8696019046739768129",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"number": 87,
"createdBy": "project:chromium-m144",
"createTime": "2025-12-08T20:50:02.352616720Z",
"startTime": "2025-12-08T20:50:58.308760676Z",
"endTime": "2025-12-08T23:15:11.112776659Z",
"updateTime": "2025-12-08T23:15:11.112776659Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4e0ad7fb97cc8d40c8c1be3872e3ecfdc600db0a",
"ref": "refs/branch-heads/7559"
}
}
}
[23:15:11.618] Invocation finished in 2h25m10.619968369s with status FAILED