[18:49:21.463] New invocation is queued and will start shortly
[18:49:22.481] Starting the invocation (attempt 1)
[18:49:22.508] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/481e659b996b59f6b82df6a9daafdd615b1c9572"
[18:49:22.508] Popped gitiles commit info from properties and tags
[18:49:22.508] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:49:22.508] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:49:22.509] Buildbucket request:
{
"requestId": "8888635789944251680",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "chromeos-octopus-rel"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8888635789944251680",
"job": "chromium-m134/chromeos-octopus-rel",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "702da385a650eb9cd068591ee9037978c74bff1c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@702da385a650eb9cd068591ee9037978c74bff1c",
"title": "702da385a650eb9cd068591ee9037978c74bff1c",
"url": "https://chromium.googlesource.com/chromium/src/+/702da385a650eb9cd068591ee9037978c74bff1c"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2043bf7d5d6ae3fbbb1734ca9aab248421cdfe1e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@2043bf7d5d6ae3fbbb1734ca9aab248421cdfe1e",
"title": "2043bf7d5d6ae3fbbb1734ca9aab248421cdfe1e",
"url": "https://chromium.googlesource.com/chromium/src/+/2043bf7d5d6ae3fbbb1734ca9aab248421cdfe1e"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "89cb0f721a025eba43adbcd7798233d98e689bfd"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@89cb0f721a025eba43adbcd7798233d98e689bfd",
"title": "89cb0f721a025eba43adbcd7798233d98e689bfd",
"url": "https://chromium.googlesource.com/chromium/src/+/89cb0f721a025eba43adbcd7798233d98e689bfd"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e2a08932c288ac0e34834a9495250a436c824e30"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@e2a08932c288ac0e34834a9495250a436c824e30",
"title": "e2a08932c288ac0e34834a9495250a436c824e30",
"url": "https://chromium.googlesource.com/chromium/src/+/e2a08932c288ac0e34834a9495250a436c824e30"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a772050ef2df3ec1bda27249add670ab37f01179"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@a772050ef2df3ec1bda27249add670ab37f01179",
"title": "a772050ef2df3ec1bda27249add670ab37f01179",
"url": "https://chromium.googlesource.com/chromium/src/+/a772050ef2df3ec1bda27249add670ab37f01179"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2381cb1b0ad2c1d25665076f260df38ce7c6329a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@2381cb1b0ad2c1d25665076f260df38ce7c6329a",
"title": "2381cb1b0ad2c1d25665076f260df38ce7c6329a",
"url": "https://chromium.googlesource.com/chromium/src/+/2381cb1b0ad2c1d25665076f260df38ce7c6329a"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "df26853704561bb4e904889f74e20c793f950e5b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@df26853704561bb4e904889f74e20c793f950e5b",
"title": "df26853704561bb4e904889f74e20c793f950e5b",
"url": "https://chromium.googlesource.com/chromium/src/+/df26853704561bb4e904889f74e20c793f950e5b"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ea7772604abc0b4c4f6a5b704084a3f943082fc3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@ea7772604abc0b4c4f6a5b704084a3f943082fc3",
"title": "ea7772604abc0b4c4f6a5b704084a3f943082fc3",
"url": "https://chromium.googlesource.com/chromium/src/+/ea7772604abc0b4c4f6a5b704084a3f943082fc3"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "459940dd4f20a9a8933155253f2425682369e755"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@459940dd4f20a9a8933155253f2425682369e755",
"title": "459940dd4f20a9a8933155253f2425682369e755",
"url": "https://chromium.googlesource.com/chromium/src/+/459940dd4f20a9a8933155253f2425682369e755"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9beee73aad4542c1c9e82762f18fdfa3e00b0b2a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@9beee73aad4542c1c9e82762f18fdfa3e00b0b2a",
"title": "9beee73aad4542c1c9e82762f18fdfa3e00b0b2a",
"url": "https://chromium.googlesource.com/chromium/src/+/9beee73aad4542c1c9e82762f18fdfa3e00b0b2a"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "481e659b996b59f6b82df6a9daafdd615b1c9572"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@481e659b996b59f6b82df6a9daafdd615b1c9572",
"title": "481e659b996b59f6b82df6a9daafdd615b1c9572",
"url": "https://chromium.googlesource.com/chromium/src/+/481e659b996b59f6b82df6a9daafdd615b1c9572"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "481e659b996b59f6b82df6a9daafdd615b1c9572",
"ref": "refs/branch-heads/6998"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8888635789944251680"
},
{
"key": "scheduler_job_id",
"value": "chromium-m134/chromeos-octopus-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:49:23.013] Scheduled build:
{
"id": "8723205728124302945",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "chromeos-octopus-rel"
},
"number": 102,
"createdBy": "project:chromium-m134",
"createTime": "2025-02-11T18:49:22.567948481Z",
"updateTime": "2025-02-11T18:49:22.567948481Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "481e659b996b59f6b82df6a9daafdd615b1c9572",
"ref": "refs/branch-heads/6998"
}
}
}
[18:49:23.013] Task URL: https://cr-buildbucket.appspot.com/build/8723205728124302945
[18:49:23.013] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:2:0) after 1m35s
[18:49:24.029] Received PubSub notification, asking Buildbucket for the build status
[18:49:24.060] Build status: SCHEDULED
[18:50:51.698] Received PubSub notification, asking Buildbucket for the build status
[18:50:51.722] Build status: STARTED
[18:50:58.038] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:2:0)
[18:50:58.068] Build status: STARTED
[18:50:58.068] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:5:0) after 3m5s
[18:54:03.088] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:5:0)
[18:54:03.117] Build status: STARTED
[18:54:03.117] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:6:0) after 3m30s
[18:57:33.159] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:6:0)
[18:57:33.193] Build status: STARTED
[18:57:33.193] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:7:0) after 4m49s
[19:02:22.295] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:7:0)
[19:02:22.320] Build status: STARTED
[19:02:22.320] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:8:0) after 9m5s
[19:11:27.380] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:8:0)
[19:11:27.399] Build status: STARTED
[19:11:27.399] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:9:0) after 8m39s
[19:20:06.474] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:9:0)
[19:20:06.515] Build status: STARTED
[19:20:06.515] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:10:0) after 5m48s
[19:25:54.537] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:10:0)
[19:25:54.561] Build status: STARTED
[19:25:54.561] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:11:0) after 7m58s
[19:33:52.642] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:11:0)
[19:33:52.662] Build status: STARTED
[19:33:52.662] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:12:0) after 9m12s
[19:43:04.771] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:12:0)
[19:43:05.076] Build status: STARTED
[19:43:05.076] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:13:0) after 7m30s
[19:50:35.123] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:13:0)
[19:50:35.148] Build status: STARTED
[19:50:35.148] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:14:0) after 7m52s
[19:58:27.186] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:14:0)
[19:58:27.211] Build status: STARTED
[19:58:27.211] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:15:0) after 8m38s
[20:07:05.250] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:15:0)
[20:07:05.307] Build status: STARTED
[20:07:05.308] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:16:0) after 6m15s
[20:13:20.476] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:16:0)
[20:13:20.494] Build status: STARTED
[20:13:20.494] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:17:0) after 1m21s
[20:14:41.574] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:17:0)
[20:14:41.598] Build status: STARTED
[20:14:41.599] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:18:0) after 8m4s
[20:22:45.614] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:18:0)
[20:22:45.640] Build status: STARTED
[20:22:45.640] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:19:0) after 2m28s
[20:25:13.660] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:19:0)
[20:25:13.683] Build status: STARTED
[20:25:13.683] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:20:0) after 6m43s
[20:31:56.734] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:20:0)
[20:31:56.826] Build status: STARTED
[20:31:56.827] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:21:0) after 7m6s
[20:39:02.913] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:21:0)
[20:39:02.935] Build status: STARTED
[20:39:02.935] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:22:0) after 8m47s
[20:47:49.936] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:22:0)
[20:47:49.954] Build status: STARTED
[20:47:49.954] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:23:0) after 4m53s
[20:52:42.977] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:23:0)
[20:52:42.996] Build status: STARTED
[20:52:42.996] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:24:0) after 1m34s
[20:54:17.027] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:24:0)
[20:54:17.059] Build status: STARTED
[20:54:17.059] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:25:0) after 6m14s
[21:00:31.085] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:25:0)
[21:00:31.104] Build status: STARTED
[21:00:31.104] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:26:0) after 7m21s
[21:07:52.139] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:26:0)
[21:07:52.162] Build status: STARTED
[21:07:52.162] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:27:0) after 3m20s
[21:11:12.189] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:27:0)
[21:11:12.204] Build status: STARTED
[21:11:12.204] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:28:0) after 3m8s
[21:14:20.270] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:28:0)
[21:14:20.293] Build status: STARTED
[21:14:20.293] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:29:0) after 6m58s
[21:21:18.436] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:29:0)
[21:21:18.456] Build status: STARTED
[21:21:18.456] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:30:0) after 1m49s
[21:23:07.503] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:30:0)
[21:23:07.527] Build status: STARTED
[21:23:07.527] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:31:0) after 5m8s
[21:28:15.580] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:31:0)
[21:28:15.597] Build status: STARTED
[21:28:15.597] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:32:0) after 2m23s
[21:30:38.709] Handling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:32:0)
[21:30:38.725] Build status: STARTED
[21:30:38.725] Scheduling timer "check-buildbucket-build-status" (chromium-m134/chromeos-octopus-rel:8888635789944251680:33:0) after 7m13s
[21:32:39.316] Received PubSub notification, asking Buildbucket for the build status
[21:32:39.347] Build:
{
"id": "8723205728124302945",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "chromeos-octopus-rel"
},
"number": 102,
"createdBy": "project:chromium-m134",
"createTime": "2025-02-11T18:49:22.567948481Z",
"startTime": "2025-02-11T18:50:51.155442648Z",
"endTime": "2025-02-11T21:32:39.013602716Z",
"updateTime": "2025-02-11T21:32:39.013602716Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "481e659b996b59f6b82df6a9daafdd615b1c9572",
"ref": "refs/branch-heads/6998"
}
}
}
[21:32:39.347] Invocation finished in 2h43m17.898347641s with status FAILED