[08:44:31.218] New invocation is queued and will start shortly
[08:44:32.382] Starting the invocation (attempt 1)
[08:44:32.408] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2b0f337f7d85a9f024c23ee1cef6afe621cce574"
[08:44:32.408] Popped gitiles commit info from properties and tags
[08:44:32.408] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:44:32.408] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:44:32.408] Buildbucket request:
{
"requestId": "8892478915603094976",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "Android x64 Builder All Targets (dbg)"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8892478915603094976",
"job": "chromium-m132/Android x64 Builder All Targets (dbg)",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "38108543bea8bea5292c4074d76a203d98073d4d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@38108543bea8bea5292c4074d76a203d98073d4d",
"title": "38108543bea8bea5292c4074d76a203d98073d4d",
"url": "https://chromium.googlesource.com/chromium/src/+/38108543bea8bea5292c4074d76a203d98073d4d"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "947b4d96b9ac50c01b41e1b8967798c0c5aca6ca"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@947b4d96b9ac50c01b41e1b8967798c0c5aca6ca",
"title": "947b4d96b9ac50c01b41e1b8967798c0c5aca6ca",
"url": "https://chromium.googlesource.com/chromium/src/+/947b4d96b9ac50c01b41e1b8967798c0c5aca6ca"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e15248123c45e0eafd71d047f097ff912465e4be"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@e15248123c45e0eafd71d047f097ff912465e4be",
"title": "e15248123c45e0eafd71d047f097ff912465e4be",
"url": "https://chromium.googlesource.com/chromium/src/+/e15248123c45e0eafd71d047f097ff912465e4be"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c5f5fe78745df949cef10b83e77c6f302338a503"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@c5f5fe78745df949cef10b83e77c6f302338a503",
"title": "c5f5fe78745df949cef10b83e77c6f302338a503",
"url": "https://chromium.googlesource.com/chromium/src/+/c5f5fe78745df949cef10b83e77c6f302338a503"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bd1f7d6f7741f862f6f7e7c0ca1eeba185216fb8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@bd1f7d6f7741f862f6f7e7c0ca1eeba185216fb8",
"title": "bd1f7d6f7741f862f6f7e7c0ca1eeba185216fb8",
"url": "https://chromium.googlesource.com/chromium/src/+/bd1f7d6f7741f862f6f7e7c0ca1eeba185216fb8"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2b0f337f7d85a9f024c23ee1cef6afe621cce574"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@2b0f337f7d85a9f024c23ee1cef6afe621cce574",
"title": "2b0f337f7d85a9f024c23ee1cef6afe621cce574",
"url": "https://chromium.googlesource.com/chromium/src/+/2b0f337f7d85a9f024c23ee1cef6afe621cce574"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2b0f337f7d85a9f024c23ee1cef6afe621cce574",
"ref": "refs/branch-heads/6834"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8892478915603094976"
},
{
"key": "scheduler_job_id",
"value": "chromium-m132/Android x64 Builder All Targets (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[08:44:32.867] Scheduled build:
{
"id": "8727048853642960225",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "Android x64 Builder All Targets (dbg)"
},
"number": 266,
"createdBy": "project:chromium-m132",
"createTime": "2024-12-31T08:44:32.466023719Z",
"updateTime": "2024-12-31T08:44:32.466023719Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2b0f337f7d85a9f024c23ee1cef6afe621cce574",
"ref": "refs/branch-heads/6834"
}
}
}
[08:44:32.867] Task URL: https://cr-buildbucket.appspot.com/build/8727048853642960225
[08:44:32.867] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:2:0) after 6m44s
[08:44:33.896] Received PubSub notification, asking Buildbucket for the build status
[08:44:33.927] Build status: SCHEDULED
[08:51:16.999] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:2:0)
[08:51:17.020] Build status: SCHEDULED
[08:51:17.021] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:4:0) after 3m40s
[08:54:57.266] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:4:0)
[08:54:57.291] Build status: SCHEDULED
[08:54:57.291] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:5:0) after 2m19s
[08:57:16.448] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:5:0)
[08:57:16.468] Build status: SCHEDULED
[08:57:16.468] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:6:0) after 9m22s
[09:06:38.557] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:6:0)
[09:06:38.581] Build status: SCHEDULED
[09:06:38.581] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:7:0) after 6m38s
[09:13:16.604] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:7:0)
[09:13:16.632] Build status: SCHEDULED
[09:13:16.633] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:8:0) after 3m40s
[09:16:57.117] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:8:0)
[09:16:57.140] Build status: SCHEDULED
[09:16:57.140] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:9:0) after 5m7s
[09:22:04.479] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:9:0)
[09:22:04.505] Build status: SCHEDULED
[09:22:04.505] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:10:0) after 8m48s
[09:30:52.528] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:10:0)
[09:30:52.558] Build status: SCHEDULED
[09:30:52.558] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:11:0) after 1m59s
[09:32:51.584] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:11:0)
[09:32:51.609] Build status: SCHEDULED
[09:32:51.609] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:12:0) after 4m45s
[09:35:22.232] Received PubSub notification, asking Buildbucket for the build status
[09:35:22.267] Build status: STARTED
[09:37:36.634] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:12:0)
[09:37:36.666] Build status: STARTED
[09:37:36.666] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:14:0) after 2m34s
[09:40:10.680] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:14:0)
[09:40:10.737] Build status: STARTED
[09:40:10.737] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:15:0) after 2m41s
[09:42:51.785] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:15:0)
[09:42:51.807] Build status: STARTED
[09:42:51.807] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:16:0) after 7m55s
[09:50:46.916] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:16:0)
[09:50:46.934] Build status: STARTED
[09:50:46.934] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:17:0) after 4m27s
[09:55:13.958] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:17:0)
[09:55:13.986] Build status: STARTED
[09:55:13.986] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:18:0) after 3m58s
[09:59:12.004] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:18:0)
[09:59:12.027] Build status: STARTED
[09:59:12.027] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:19:0) after 7m24s
[10:06:36.046] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:19:0)
[10:06:36.066] Build status: STARTED
[10:06:36.066] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:20:0) after 6m26s
[10:13:02.140] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:20:0)
[10:13:02.158] Build status: STARTED
[10:13:02.158] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:21:0) after 4m34s
[10:17:36.354] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:21:0)
[10:17:36.380] Build status: STARTED
[10:17:36.380] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:22:0) after 7m2s
[10:24:38.416] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:22:0)
[10:24:38.433] Build status: STARTED
[10:24:38.433] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:23:0) after 5m16s
[10:29:54.455] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:23:0)
[10:29:54.483] Build status: STARTED
[10:29:54.483] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:24:0) after 1m4s
[10:30:58.550] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:24:0)
[10:30:58.573] Build status: STARTED
[10:30:58.573] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:25:0) after 2m53s
[10:33:51.598] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:25:0)
[10:33:51.614] Build status: STARTED
[10:33:51.614] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:26:0) after 9m34s
[10:43:25.642] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:26:0)
[10:43:25.658] Build status: STARTED
[10:43:25.658] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:27:0) after 5m12s
[10:48:37.704] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:27:0)
[10:48:37.721] Build status: STARTED
[10:48:37.721] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:28:0) after 7m24s
[10:56:01.739] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:28:0)
[10:56:01.775] Build status: STARTED
[10:56:01.775] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:29:0) after 8m32s
[11:04:33.803] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:29:0)
[11:04:33.823] Build status: STARTED
[11:04:33.823] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:30:0) after 6m26s
[11:10:59.851] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:30:0)
[11:10:59.870] Build status: STARTED
[11:10:59.870] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:31:0) after 6m30s
[11:17:29.886] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:31:0)
[11:17:29.904] Build status: STARTED
[11:17:29.904] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:32:0) after 1m45s
[11:19:15.019] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:32:0)
[11:19:15.039] Build status: STARTED
[11:19:15.039] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:33:0) after 2m7s
[11:21:22.072] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:33:0)
[11:21:22.097] Build status: STARTED
[11:21:22.097] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:34:0) after 6m49s
[11:28:11.420] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:34:0)
[11:28:11.449] Build status: STARTED
[11:28:11.449] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:35:0) after 3m8s
[11:31:19.555] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:35:0)
[11:31:19.577] Build status: STARTED
[11:31:19.577] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:36:0) after 9m51s
[11:41:10.607] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:36:0)
[11:41:10.626] Build status: STARTED
[11:41:10.626] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:37:0) after 9m0s
[11:50:10.673] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:37:0)
[11:50:10.687] Build status: STARTED
[11:50:10.687] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:38:0) after 5m20s
[11:55:30.747] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:38:0)
[11:55:30.764] Build status: STARTED
[11:55:30.764] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:39:0) after 4m32s
[12:00:02.785] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:39:0)
[12:00:02.802] Build status: STARTED
[12:00:02.803] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:40:0) after 5m58s
[12:06:01.119] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:40:0)
[12:06:01.183] Build status: STARTED
[12:06:01.183] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:41:0) after 9m17s
[12:15:18.331] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:41:0)
[12:15:18.365] Build status: STARTED
[12:15:18.365] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:42:0) after 4m40s
[12:19:58.638] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:42:0)
[12:19:58.723] Build status: STARTED
[12:19:58.723] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:43:0) after 7m24s
[12:27:22.898] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:43:0)
[12:27:22.956] Build status: STARTED
[12:27:22.956] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:44:0) after 3m47s
[12:31:09.996] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:44:0)
[12:31:10.022] Build status: STARTED
[12:31:10.022] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:45:0) after 9m58s
[12:41:08.044] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:45:0)
[12:41:08.060] Build status: STARTED
[12:41:08.060] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:46:0) after 1m51s
[12:42:59.179] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:46:0)
[12:42:59.337] Build status: STARTED
[12:42:59.337] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8892478915603094976:47:0) after 8m0s
[12:49:33.589] Received PubSub notification, asking Buildbucket for the build status
[12:49:33.616] Build:
{
"id": "8727048853642960225",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "Android x64 Builder All Targets (dbg)"
},
"number": 266,
"createdBy": "project:chromium-m132",
"createTime": "2024-12-31T08:44:32.466023719Z",
"startTime": "2024-12-31T09:35:19.723473661Z",
"endTime": "2024-12-31T12:49:33.271030921Z",
"updateTime": "2024-12-31T12:49:33.271030921Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2b0f337f7d85a9f024c23ee1cef6afe621cce574",
"ref": "refs/branch-heads/6834"
}
}
}
[12:49:33.616] Invocation finished in 4h5m2.403059703s with status SUCCEEDED