[21:38:12.453] New invocation is queued and will start shortly
[21:38:13.619] Starting the invocation (attempt 1)
[21:38:13.645] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/407b4224b3e90201a44d77837fbba0744d677f1a"
[21:38:13.645] Popped gitiles commit info from properties and tags
[21:38:13.645] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:38:13.646] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:38:13.646] Buildbucket request:
{
"requestId": "8888896957724636064",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "chromeos-octopus-rel"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8888896957724636064",
"job": "chromium-m132/chromeos-octopus-rel",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0f08981eb42d57244a5ff4300176b931d3cdbbb5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@0f08981eb42d57244a5ff4300176b931d3cdbbb5",
"title": "0f08981eb42d57244a5ff4300176b931d3cdbbb5",
"url": "https://chromium.googlesource.com/chromium/src/+/0f08981eb42d57244a5ff4300176b931d3cdbbb5"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4d0603680a9cc4a7f295d49918f9511fe845e52a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@4d0603680a9cc4a7f295d49918f9511fe845e52a",
"title": "4d0603680a9cc4a7f295d49918f9511fe845e52a",
"url": "https://chromium.googlesource.com/chromium/src/+/4d0603680a9cc4a7f295d49918f9511fe845e52a"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cbec9bc0d50d75887aab6d44ddfa51ff6c304b29"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@cbec9bc0d50d75887aab6d44ddfa51ff6c304b29",
"title": "cbec9bc0d50d75887aab6d44ddfa51ff6c304b29",
"url": "https://chromium.googlesource.com/chromium/src/+/cbec9bc0d50d75887aab6d44ddfa51ff6c304b29"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "db14a6439a601b5744269be01c6a0845a5cec0d9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@db14a6439a601b5744269be01c6a0845a5cec0d9",
"title": "db14a6439a601b5744269be01c6a0845a5cec0d9",
"url": "https://chromium.googlesource.com/chromium/src/+/db14a6439a601b5744269be01c6a0845a5cec0d9"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "407b4224b3e90201a44d77837fbba0744d677f1a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@407b4224b3e90201a44d77837fbba0744d677f1a",
"title": "407b4224b3e90201a44d77837fbba0744d677f1a",
"url": "https://chromium.googlesource.com/chromium/src/+/407b4224b3e90201a44d77837fbba0744d677f1a"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "407b4224b3e90201a44d77837fbba0744d677f1a",
"ref": "refs/branch-heads/6834"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8888896957724636064"
},
{
"key": "scheduler_job_id",
"value": "chromium-m132/chromeos-octopus-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:38:14.143] Scheduled build:
{
"id": "8723466895758307793",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "chromeos-octopus-rel"
},
"number": 1386,
"createdBy": "project:chromium-m132",
"createTime": "2025-02-08T21:38:13.703633990Z",
"updateTime": "2025-02-08T21:38:13.703633990Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "407b4224b3e90201a44d77837fbba0744d677f1a",
"ref": "refs/branch-heads/6834"
}
}
}
[21:38:14.143] Task URL: https://cr-buildbucket.appspot.com/build/8723466895758307793
[21:38:14.143] Scheduling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:2:0) after 6m27s
[21:38:15.228] Received PubSub notification, asking Buildbucket for the build status
[21:38:15.258] Build status: SCHEDULED
[21:44:41.240] Handling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:2:0)
[21:44:41.269] Build status: SCHEDULED
[21:44:41.269] Scheduling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:4:0) after 5m59s
[21:48:05.553] Received PubSub notification, asking Buildbucket for the build status
[21:48:05.581] Build status: STARTED
[21:50:40.345] Handling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:4:0)
[21:50:40.363] Build status: STARTED
[21:50:40.363] Scheduling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:6:0) after 8m53s
[21:59:33.390] Handling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:6:0)
[21:59:33.409] Build status: STARTED
[21:59:33.409] Scheduling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:7:0) after 9m41s
[22:09:14.431] Handling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:7:0)
[22:09:14.463] Build status: STARTED
[22:09:14.463] Scheduling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:8:0) after 1m38s
[22:10:52.518] Handling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:8:0)
[22:10:52.541] Build status: STARTED
[22:10:52.541] Scheduling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:9:0) after 6m59s
[22:17:51.560] Handling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:9:0)
[22:17:51.588] Build status: STARTED
[22:17:51.588] Scheduling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:10:0) after 5m9s
[22:23:00.712] Handling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:10:0)
[22:23:00.746] Build status: STARTED
[22:23:00.746] Scheduling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:11:0) after 1m1s
[22:24:01.771] Handling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:11:0)
[22:24:01.791] Build status: STARTED
[22:24:01.791] Scheduling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:12:0) after 5m54s
[22:29:55.810] Handling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:12:0)
[22:29:55.833] Build status: STARTED
[22:29:55.833] Scheduling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:13:0) after 8m45s
[22:38:40.977] Handling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:13:0)
[22:38:40.996] Build status: STARTED
[22:38:40.996] Scheduling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:14:0) after 9m43s
[22:48:24.016] Handling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:14:0)
[22:48:24.136] Build status: STARTED
[22:48:24.136] Scheduling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:15:0) after 3m36s
[22:52:00.153] Handling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:15:0)
[22:52:00.176] Build status: STARTED
[22:52:00.176] Scheduling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:16:0) after 6m49s
[22:58:49.344] Handling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:16:0)
[22:58:49.367] Build status: STARTED
[22:58:49.367] Scheduling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:17:0) after 2m6s
[23:00:55.392] Handling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:17:0)
[23:00:55.411] Build status: STARTED
[23:00:55.411] Scheduling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:18:0) after 9m43s
[23:10:38.931] Handling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:18:0)
[23:10:38.954] Build status: STARTED
[23:10:38.954] Scheduling timer "check-buildbucket-build-status" (chromium-m132/chromeos-octopus-rel:8888896957724636064:19:0) after 8m57s
[23:13:32.281] Received PubSub notification, asking Buildbucket for the build status
[23:13:32.370] Build:
{
"id": "8723466895758307793",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "chromeos-octopus-rel"
},
"number": 1386,
"createdBy": "project:chromium-m132",
"createTime": "2025-02-08T21:38:13.703633990Z",
"startTime": "2025-02-08T21:48:05.358299045Z",
"endTime": "2025-02-08T23:13:31.647818655Z",
"updateTime": "2025-02-08T23:13:31.647818655Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "407b4224b3e90201a44d77837fbba0744d677f1a",
"ref": "refs/branch-heads/6834"
}
}
}
[23:13:32.370] Invocation finished in 1h35m19.926871195s with status SUCCEEDED