[01:10:40.339] New invocation is queued and will start shortly
[01:10:41.522] Starting the invocation (attempt 1)
[01:10:41.553] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a975e252d8c2d4ce0757619eb23039dfe36f00ea"
[01:10:41.553] Popped gitiles commit info from properties and tags
[01:10:41.553] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:10:41.553] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:10:41.553] Buildbucket request:
{
"requestId": "8891963887455373232",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "Android x64 Builder All Targets (dbg)"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8891963887455373232",
"job": "chromium-m132/Android x64 Builder All Targets (dbg)",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "de058037e266c2341d1236cf2e3e6ee0320b77cb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@de058037e266c2341d1236cf2e3e6ee0320b77cb",
"title": "de058037e266c2341d1236cf2e3e6ee0320b77cb",
"url": "https://chromium.googlesource.com/chromium/src/+/de058037e266c2341d1236cf2e3e6ee0320b77cb"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "edddbd4558da6badf1dbf88bad78af09b1d09767"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@edddbd4558da6badf1dbf88bad78af09b1d09767",
"title": "edddbd4558da6badf1dbf88bad78af09b1d09767",
"url": "https://chromium.googlesource.com/chromium/src/+/edddbd4558da6badf1dbf88bad78af09b1d09767"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b2d936cb662e0f3a165e76d9fdbbac8d8282fbf9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@b2d936cb662e0f3a165e76d9fdbbac8d8282fbf9",
"title": "b2d936cb662e0f3a165e76d9fdbbac8d8282fbf9",
"url": "https://chromium.googlesource.com/chromium/src/+/b2d936cb662e0f3a165e76d9fdbbac8d8282fbf9"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9eeb411dc4af431d69e65330b987b9001e05fc2e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@9eeb411dc4af431d69e65330b987b9001e05fc2e",
"title": "9eeb411dc4af431d69e65330b987b9001e05fc2e",
"url": "https://chromium.googlesource.com/chromium/src/+/9eeb411dc4af431d69e65330b987b9001e05fc2e"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "938ba98faa20ce2117b0480fb42e7db72315de61"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@938ba98faa20ce2117b0480fb42e7db72315de61",
"title": "938ba98faa20ce2117b0480fb42e7db72315de61",
"url": "https://chromium.googlesource.com/chromium/src/+/938ba98faa20ce2117b0480fb42e7db72315de61"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4805d7e2c27ac5fc7ac9e8a8617c4bae237fd022"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@4805d7e2c27ac5fc7ac9e8a8617c4bae237fd022",
"title": "4805d7e2c27ac5fc7ac9e8a8617c4bae237fd022",
"url": "https://chromium.googlesource.com/chromium/src/+/4805d7e2c27ac5fc7ac9e8a8617c4bae237fd022"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f98f0d5194f7a812587eea18baa5395379ca4708"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@f98f0d5194f7a812587eea18baa5395379ca4708",
"title": "f98f0d5194f7a812587eea18baa5395379ca4708",
"url": "https://chromium.googlesource.com/chromium/src/+/f98f0d5194f7a812587eea18baa5395379ca4708"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5546bca33cf9afb296cf32d180dc25365a12e3e5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@5546bca33cf9afb296cf32d180dc25365a12e3e5",
"title": "5546bca33cf9afb296cf32d180dc25365a12e3e5",
"url": "https://chromium.googlesource.com/chromium/src/+/5546bca33cf9afb296cf32d180dc25365a12e3e5"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a975e252d8c2d4ce0757619eb23039dfe36f00ea"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@a975e252d8c2d4ce0757619eb23039dfe36f00ea",
"title": "a975e252d8c2d4ce0757619eb23039dfe36f00ea",
"url": "https://chromium.googlesource.com/chromium/src/+/a975e252d8c2d4ce0757619eb23039dfe36f00ea"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a975e252d8c2d4ce0757619eb23039dfe36f00ea",
"ref": "refs/branch-heads/6834"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8891963887455373232"
},
{
"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"
}
}
[01:10:42.943] Scheduled build:
{
"id": "8726533825467739425",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "Android x64 Builder All Targets (dbg)"
},
"number": 299,
"createdBy": "project:chromium-m132",
"createTime": "2025-01-06T01:10:41.609250610Z",
"updateTime": "2025-01-06T01:10:41.609250610Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a975e252d8c2d4ce0757619eb23039dfe36f00ea",
"ref": "refs/branch-heads/6834"
}
}
}
[01:10:42.943] Task URL: https://cr-buildbucket.appspot.com/build/8726533825467739425
[01:10:42.943] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:2:0) after 7m49s
[01:10:43.644] Received PubSub notification, asking Buildbucket for the build status
[01:10:43.666] Build status: SCHEDULED
[01:18:32.186] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:2:0)
[01:18:32.205] Build status: SCHEDULED
[01:18:32.205] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:4:0) after 1m33s
[01:20:05.353] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:4:0)
[01:20:05.379] Build status: SCHEDULED
[01:20:05.379] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:5:0) after 8m35s
[01:28:40.373] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:5:0)
[01:28:40.397] Build status: SCHEDULED
[01:28:40.397] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:6:0) after 8m55s
[01:37:35.419] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:6:0)
[01:37:35.436] Build status: SCHEDULED
[01:37:35.436] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:7:0) after 8m2s
[01:45:37.464] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:7:0)
[01:45:37.507] Build status: SCHEDULED
[01:45:37.507] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:8:0) after 8m19s
[01:53:56.705] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:8:0)
[01:53:56.734] Build status: SCHEDULED
[01:53:56.735] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:9:0) after 9m10s
[02:02:27.138] Received PubSub notification, asking Buildbucket for the build status
[02:02:27.165] Build status: STARTED
[02:03:06.940] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:9:0)
[02:03:07.010] Build status: STARTED
[02:03:07.010] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:11:0) after 9m24s
[02:12:31.178] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:11:0)
[02:12:31.198] Build status: STARTED
[02:12:31.198] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:12:0) after 7m47s
[02:20:18.354] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:12:0)
[02:20:18.376] Build status: STARTED
[02:20:18.376] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:13:0) after 2m11s
[02:22:29.455] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:13:0)
[02:22:29.486] Build status: STARTED
[02:22:29.486] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:14:0) after 3m31s
[02:26:00.504] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:14:0)
[02:26:00.523] Build status: STARTED
[02:26:00.523] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:15:0) after 2m26s
[02:28:26.549] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:15:0)
[02:28:26.573] Build status: STARTED
[02:28:26.573] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:16:0) after 4m12s
[02:32:38.696] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:16:0)
[02:32:38.714] Build status: STARTED
[02:32:38.714] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:17:0) after 3m34s
[02:36:12.698] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:17:0)
[02:36:12.720] Build status: STARTED
[02:36:12.720] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:18:0) after 1m44s
[02:37:56.891] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:18:0)
[02:37:56.923] Build status: STARTED
[02:37:56.923] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:19:0) after 4m4s
[02:42:01.038] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:19:0)
[02:42:01.064] Build status: STARTED
[02:42:01.064] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:20:0) after 2m46s
[02:44:47.091] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:20:0)
[02:44:47.117] Build status: STARTED
[02:44:47.117] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:21:0) after 3m33s
[02:48:20.143] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:21:0)
[02:48:20.171] Build status: STARTED
[02:48:20.171] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:22:0) after 3m39s
[02:51:59.197] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:22:0)
[02:51:59.234] Build status: STARTED
[02:51:59.234] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:23:0) after 7m53s
[02:59:52.252] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:23:0)
[02:59:52.287] Build status: STARTED
[02:59:52.287] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:24:0) after 5m23s
[03:05:15.404] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:24:0)
[03:05:15.420] Build status: STARTED
[03:05:15.420] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:25:0) after 7m21s
[03:12:36.539] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:25:0)
[03:12:36.571] Build status: STARTED
[03:12:36.571] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:26:0) after 6m58s
[03:19:35.140] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:26:0)
[03:19:35.208] Build status: STARTED
[03:19:35.208] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:27:0) after 9m23s
[03:28:58.263] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:27:0)
[03:28:58.293] Build status: STARTED
[03:28:58.293] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:28:0) after 1m34s
[03:30:32.301] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:28:0)
[03:30:32.326] Build status: STARTED
[03:30:32.327] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:29:0) after 7m46s
[03:38:18.573] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:29:0)
[03:38:18.599] Build status: STARTED
[03:38:18.599] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:30:0) after 3m0s
[03:41:18.631] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:30:0)
[03:41:18.685] Build status: STARTED
[03:41:18.685] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:31:0) after 6m0s
[03:47:18.808] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:31:0)
[03:47:18.892] Build status: STARTED
[03:47:18.892] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:32:0) after 3m34s
[03:50:52.925] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:32:0)
[03:50:52.943] Build status: STARTED
[03:50:52.943] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:33:0) after 3m56s
[03:54:49.124] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:33:0)
[03:54:49.149] Build status: STARTED
[03:54:49.149] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:34:0) after 6m29s
[04:01:18.273] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:34:0)
[04:01:18.293] Build status: STARTED
[04:01:18.293] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:35:0) after 9m30s
[04:10:48.653] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:35:0)
[04:10:48.681] Build status: STARTED
[04:10:48.681] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:36:0) after 7m56s
[04:18:45.038] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:36:0)
[04:18:45.064] Build status: STARTED
[04:18:45.064] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:37:0) after 9m46s
[04:28:31.201] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:37:0)
[04:28:31.231] Build status: STARTED
[04:28:31.231] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:38:0) after 1m44s
[04:30:15.395] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:38:0)
[04:30:15.463] Build status: STARTED
[04:30:15.464] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:39:0) after 6m10s
[04:36:25.590] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:39:0)
[04:36:25.607] Build status: STARTED
[04:36:25.607] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:40:0) after 6m30s
[04:42:55.682] Handling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:40:0)
[04:42:55.703] Build status: STARTED
[04:42:55.703] Scheduling timer "check-buildbucket-build-status" (chromium-m132/Android x64 Builder All Targets (dbg):8891963887455373232:41:0) after 6m32s
[04:44:38.641] Received PubSub notification, asking Buildbucket for the build status
[04:44:38.672] Build:
{
"id": "8726533825467739425",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "Android x64 Builder All Targets (dbg)"
},
"number": 299,
"createdBy": "project:chromium-m132",
"createTime": "2025-01-06T01:10:41.609250610Z",
"startTime": "2025-01-06T02:02:26.932553771Z",
"endTime": "2025-01-06T04:44:28.546114902Z",
"updateTime": "2025-01-06T04:44:28.546114902Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a975e252d8c2d4ce0757619eb23039dfe36f00ea",
"ref": "refs/branch-heads/6834"
}
}
}
[04:44:38.672] Invocation finished in 3h33m58.342985237s with status SUCCEEDED