[23:03:04.172] New invocation is queued and will start shortly
[23:03:05.775] Starting the invocation (attempt 1)
[23:03:05.805] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/538bb4db6c78b2622b69b6f93efbdc6c86880736"
[23:03:05.806] Popped gitiles commit info from properties and tags
[23:03:05.806] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:03:05.806] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:03:05.806] Buildbucket request:
{
"requestId": "8881100279557315952",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "Android x64 Builder All Targets (dbg)"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8881100279557315952",
"job": "chromium-m136/Android x64 Builder All Targets (dbg)",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "007693da6e07862df7c7f827bf5d4cd88a9f6ec0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@007693da6e07862df7c7f827bf5d4cd88a9f6ec0",
"title": "007693da6e07862df7c7f827bf5d4cd88a9f6ec0",
"url": "https://chromium.googlesource.com/chromium/src/+/007693da6e07862df7c7f827bf5d4cd88a9f6ec0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cb81a4cc5087a8303a6c8827ee2b80b71d26e334"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@cb81a4cc5087a8303a6c8827ee2b80b71d26e334",
"title": "cb81a4cc5087a8303a6c8827ee2b80b71d26e334",
"url": "https://chromium.googlesource.com/chromium/src/+/cb81a4cc5087a8303a6c8827ee2b80b71d26e334"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d15f7e0b7b458c1502136e8aee33a8187c49a489"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@d15f7e0b7b458c1502136e8aee33a8187c49a489",
"title": "d15f7e0b7b458c1502136e8aee33a8187c49a489",
"url": "https://chromium.googlesource.com/chromium/src/+/d15f7e0b7b458c1502136e8aee33a8187c49a489"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fa0be0b33debeb378a8e6ad9c599be34e2dc3b37"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@fa0be0b33debeb378a8e6ad9c599be34e2dc3b37",
"title": "fa0be0b33debeb378a8e6ad9c599be34e2dc3b37",
"url": "https://chromium.googlesource.com/chromium/src/+/fa0be0b33debeb378a8e6ad9c599be34e2dc3b37"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ab6b2627b2767050197afe577afad5648d7e9cb6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@ab6b2627b2767050197afe577afad5648d7e9cb6",
"title": "ab6b2627b2767050197afe577afad5648d7e9cb6",
"url": "https://chromium.googlesource.com/chromium/src/+/ab6b2627b2767050197afe577afad5648d7e9cb6"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "538bb4db6c78b2622b69b6f93efbdc6c86880736"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@538bb4db6c78b2622b69b6f93efbdc6c86880736",
"title": "538bb4db6c78b2622b69b6f93efbdc6c86880736",
"url": "https://chromium.googlesource.com/chromium/src/+/538bb4db6c78b2622b69b6f93efbdc6c86880736"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "538bb4db6c78b2622b69b6f93efbdc6c86880736",
"ref": "refs/branch-heads/7103"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8881100279557315952"
},
{
"key": "scheduler_job_id",
"value": "chromium-m136/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"
}
}
[23:03:06.761] Scheduled build:
{
"id": "8715670217126690449",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "Android x64 Builder All Targets (dbg)"
},
"number": 210,
"createdBy": "project:chromium-m136",
"createTime": "2025-05-05T23:03:05.867804031Z",
"updateTime": "2025-05-05T23:03:05.867804031Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "538bb4db6c78b2622b69b6f93efbdc6c86880736",
"ref": "refs/branch-heads/7103"
}
},
"canary": true
}
[23:03:06.761] Task URL: https://cr-buildbucket.appspot.com/build/8715670217126690449
[23:03:06.761] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:2:0) after 2m20s
[23:03:07.097] Received PubSub notification, asking Buildbucket for the build status
[23:03:07.125] Build status: SCHEDULED
[23:05:26.841] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:2:0)
[23:05:26.865] Build status: SCHEDULED
[23:05:26.865] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:4:0) after 7m16s
[23:12:42.882] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:4:0)
[23:12:42.910] Build status: SCHEDULED
[23:12:42.910] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:5:0) after 1m52s
[23:14:34.925] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:5:0)
[23:14:34.946] Build status: SCHEDULED
[23:14:34.946] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:6:0) after 7m6s
[23:21:40.922] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:6:0)
[23:21:40.945] Build status: SCHEDULED
[23:21:40.945] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:7:0) after 7m55s
[23:29:36.011] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:7:0)
[23:29:36.034] Build status: SCHEDULED
[23:29:36.034] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:8:0) after 9m40s
[23:39:16.063] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:8:0)
[23:39:16.092] Build status: SCHEDULED
[23:39:16.092] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:9:0) after 2m34s
[23:41:50.118] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:9:0)
[23:41:50.146] Build status: SCHEDULED
[23:41:50.146] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:10:0) after 2m49s
[23:44:39.159] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:10:0)
[23:44:39.182] Build status: SCHEDULED
[23:44:39.182] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:11:0) after 3m55s
[23:45:51.430] Received PubSub notification, asking Buildbucket for the build status
[23:45:51.456] Build status: STARTED
[23:48:34.229] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:11:0)
[23:48:34.254] Build status: STARTED
[23:48:34.254] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:13:0) after 6m5s
[23:54:39.266] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:13:0)
[23:54:39.306] Build status: STARTED
[23:54:39.306] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:14:0) after 7m4s
[00:01:43.380] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:14:0)
[00:01:43.698] Build status: STARTED
[00:01:43.698] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:15:0) after 7m6s
[00:08:49.750] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:15:0)
[00:08:49.771] Build status: STARTED
[00:08:49.772] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:16:0) after 4m30s
[00:13:19.840] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:16:0)
[00:13:19.867] Build status: STARTED
[00:13:19.867] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:17:0) after 8m48s
[00:22:07.872] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:17:0)
[00:22:07.889] Build status: STARTED
[00:22:07.889] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:18:0) after 9m9s
[00:31:16.955] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:18:0)
[00:31:16.971] Build status: STARTED
[00:31:16.971] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:19:0) after 1m37s
[00:32:54.071] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:19:0)
[00:32:54.145] Build status: STARTED
[00:32:54.145] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:20:0) after 9m26s
[00:42:20.171] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:20:0)
[00:42:20.323] Build status: STARTED
[00:42:20.323] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:21:0) after 6m55s
[00:49:15.340] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:21:0)
[00:49:15.357] Build status: STARTED
[00:49:15.357] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:22:0) after 1m44s
[00:50:59.374] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:22:0)
[00:50:59.390] Build status: STARTED
[00:50:59.390] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:23:0) after 9m41s
[01:00:40.409] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:23:0)
[01:00:40.431] Build status: STARTED
[01:00:40.431] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:24:0) after 2m26s
[01:03:06.427] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:24:0)
[01:03:06.449] Build status: STARTED
[01:03:06.450] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:25:0) after 2m43s
[01:05:49.502] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:25:0)
[01:05:49.552] Build status: STARTED
[01:05:49.552] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:26:0) after 2m20s
[01:08:09.577] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:26:0)
[01:08:09.590] Build status: STARTED
[01:08:09.590] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:27:0) after 1m42s
[01:09:51.562] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:27:0)
[01:09:51.581] Build status: STARTED
[01:09:51.581] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:28:0) after 6m0s
[01:15:51.698] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:28:0)
[01:15:51.723] Build status: STARTED
[01:15:51.724] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:29:0) after 1m20s
[01:17:11.743] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:29:0)
[01:17:11.762] Build status: STARTED
[01:17:11.762] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:30:0) after 4m25s
[01:21:36.979] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:30:0)
[01:21:36.998] Build status: STARTED
[01:21:36.998] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:31:0) after 7m43s
[01:29:20.058] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:31:0)
[01:29:20.081] Build status: STARTED
[01:29:20.081] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:32:0) after 8m37s
[01:37:57.082] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:32:0)
[01:37:57.133] Build status: STARTED
[01:37:57.133] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:33:0) after 3m51s
[01:41:48.171] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:33:0)
[01:41:48.209] Build status: STARTED
[01:41:48.209] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:34:0) after 9m4s
[01:50:52.193] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:34:0)
[01:50:52.208] Build status: STARTED
[01:50:52.208] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:35:0) after 8m10s
[01:59:02.277] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:35:0)
[01:59:02.304] Build status: STARTED
[01:59:02.304] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:36:0) after 2m14s
[02:01:16.374] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:36:0)
[02:01:16.453] Build status: STARTED
[02:01:16.453] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:37:0) after 6m37s
[02:07:53.507] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:37:0)
[02:07:53.536] Build status: STARTED
[02:07:53.536] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:38:0) after 2m21s
[02:10:14.594] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:38:0)
[02:10:14.611] Build status: STARTED
[02:10:14.611] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:39:0) after 3m27s
[02:13:41.631] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:39:0)
[02:13:41.650] Build status: STARTED
[02:13:41.651] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:40:0) after 8m55s
[02:22:36.766] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:40:0)
[02:22:36.785] Build status: STARTED
[02:22:36.785] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:41:0) after 2m27s
[02:25:03.851] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:41:0)
[02:25:03.869] Build status: STARTED
[02:25:03.869] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:42:0) after 9m18s
[02:34:21.913] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:42:0)
[02:34:21.949] Build status: STARTED
[02:34:21.950] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:43:0) after 9m33s
[02:43:54.966] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:43:0)
[02:43:54.987] Build status: STARTED
[02:43:54.987] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:44:0) after 3m23s
[02:47:18.032] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:44:0)
[02:47:18.062] Build status: STARTED
[02:47:18.062] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:45:0) after 1m8s
[02:48:26.193] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:45:0)
[02:48:26.220] Build status: STARTED
[02:48:26.220] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:46:0) after 3m15s
[02:51:41.242] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:46:0)
[02:51:41.340] Build status: STARTED
[02:51:41.340] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:47:0) after 9m25s
[03:01:06.461] Handling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:47:0)
[03:01:06.497] Build status: STARTED
[03:01:06.497] Scheduling timer "check-buildbucket-build-status" (chromium-m136/Android x64 Builder All Targets (dbg):8881100279557315952:48:0) after 8m44s
[03:03:59.294] Received PubSub notification, asking Buildbucket for the build status
[03:03:59.317] Build:
{
"id": "8715670217126690449",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "Android x64 Builder All Targets (dbg)"
},
"number": 210,
"createdBy": "project:chromium-m136",
"createTime": "2025-05-05T23:03:05.867804031Z",
"startTime": "2025-05-05T23:44:47.391835583Z",
"endTime": "2025-05-06T03:03:59.011028341Z",
"updateTime": "2025-05-06T03:03:59.011028341Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "538bb4db6c78b2622b69b6f93efbdc6c86880736",
"ref": "refs/branch-heads/7103"
}
},
"canary": true
}
[03:03:59.317] Invocation finished in 4h0m55.152124429s with status SUCCEEDED