[23:45:24.646] New invocation is queued and will start shortly
[23:45:25.747] Starting the invocation (attempt 1)
[23:45:25.774] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fbb149519bca52a97fe61e3a45d966c5850771d2"
[23:45:25.774] Popped gitiles commit info from properties and tags
[23:45:25.774] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:45:25.774] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:45:25.775] Buildbucket request:
{
"requestId": "8875752394666707888",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "Android x64 Builder All Targets (dbg)"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8875752394666707888",
"job": "chromium-m137/Android x64 Builder All Targets (dbg)",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "34c60241680e359e34f83810a87ca203e3f02238"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@34c60241680e359e34f83810a87ca203e3f02238",
"title": "34c60241680e359e34f83810a87ca203e3f02238",
"url": "https://chromium.googlesource.com/chromium/src/+/34c60241680e359e34f83810a87ca203e3f02238"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "801c9c91d65d98cdf298a2d9ea3f1439fbb2c396"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@801c9c91d65d98cdf298a2d9ea3f1439fbb2c396",
"title": "801c9c91d65d98cdf298a2d9ea3f1439fbb2c396",
"url": "https://chromium.googlesource.com/chromium/src/+/801c9c91d65d98cdf298a2d9ea3f1439fbb2c396"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "68e49f0b5dac5cceefa122ed8c3c892cf1b46146"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@68e49f0b5dac5cceefa122ed8c3c892cf1b46146",
"title": "68e49f0b5dac5cceefa122ed8c3c892cf1b46146",
"url": "https://chromium.googlesource.com/chromium/src/+/68e49f0b5dac5cceefa122ed8c3c892cf1b46146"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8052cedfa2f6c2592bc092a6f1a6df4495d76a17"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@8052cedfa2f6c2592bc092a6f1a6df4495d76a17",
"title": "8052cedfa2f6c2592bc092a6f1a6df4495d76a17",
"url": "https://chromium.googlesource.com/chromium/src/+/8052cedfa2f6c2592bc092a6f1a6df4495d76a17"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ec57133f0b203ff9252504ac4085ed45622bbac5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@ec57133f0b203ff9252504ac4085ed45622bbac5",
"title": "ec57133f0b203ff9252504ac4085ed45622bbac5",
"url": "https://chromium.googlesource.com/chromium/src/+/ec57133f0b203ff9252504ac4085ed45622bbac5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "90b277db64221d66d0cc8fd57d946df5db575fb3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@90b277db64221d66d0cc8fd57d946df5db575fb3",
"title": "90b277db64221d66d0cc8fd57d946df5db575fb3",
"url": "https://chromium.googlesource.com/chromium/src/+/90b277db64221d66d0cc8fd57d946df5db575fb3"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4ded086cd65ea4a28231f4126a050437a006c74c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@4ded086cd65ea4a28231f4126a050437a006c74c",
"title": "4ded086cd65ea4a28231f4126a050437a006c74c",
"url": "https://chromium.googlesource.com/chromium/src/+/4ded086cd65ea4a28231f4126a050437a006c74c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cf131eed787389182ae8eee5a083d64a55d4fa10"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@cf131eed787389182ae8eee5a083d64a55d4fa10",
"title": "cf131eed787389182ae8eee5a083d64a55d4fa10",
"url": "https://chromium.googlesource.com/chromium/src/+/cf131eed787389182ae8eee5a083d64a55d4fa10"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fbb149519bca52a97fe61e3a45d966c5850771d2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@fbb149519bca52a97fe61e3a45d966c5850771d2",
"title": "fbb149519bca52a97fe61e3a45d966c5850771d2",
"url": "https://chromium.googlesource.com/chromium/src/+/fbb149519bca52a97fe61e3a45d966c5850771d2"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fbb149519bca52a97fe61e3a45d966c5850771d2",
"ref": "refs/branch-heads/7151"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8875752394666707888"
},
{
"key": "scheduler_job_id",
"value": "chromium-m137/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:45:26.299] Scheduled build:
{
"id": "8710322332777972177",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "Android x64 Builder All Targets (dbg)"
},
"number": 506,
"createdBy": "project:chromium-m137",
"createTime": "2025-07-03T23:45:25.817505341Z",
"updateTime": "2025-07-03T23:45:25.817505341Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fbb149519bca52a97fe61e3a45d966c5850771d2",
"ref": "refs/branch-heads/7151"
}
}
}
[23:45:26.299] Task URL: https://cr-buildbucket.appspot.com/build/8710322332777972177
[23:45:26.299] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:2:0) after 6m26s
[23:45:27.215] Received PubSub notification, asking Buildbucket for the build status
[23:45:27.237] Build status: SCHEDULED
[23:51:52.301] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:2:0)
[23:51:52.314] Build status: SCHEDULED
[23:51:52.314] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:4:0) after 1m19s
[23:53:11.336] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:4:0)
[23:53:11.403] Build status: SCHEDULED
[23:53:11.403] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:5:0) after 9m47s
[00:02:58.426] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:5:0)
[00:02:58.451] Build status: SCHEDULED
[00:02:58.451] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:6:0) after 1m57s
[00:04:55.476] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:6:0)
[00:04:55.521] Build status: SCHEDULED
[00:04:55.521] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:7:0) after 2m29s
[00:07:24.966] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:7:0)
[00:07:24.989] Build status: SCHEDULED
[00:07:24.989] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:8:0) after 2m18s
[00:09:43.012] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:8:0)
[00:09:43.082] Build status: SCHEDULED
[00:09:43.083] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:9:0) after 3m14s
[00:12:57.083] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:9:0)
[00:12:57.110] Build status: SCHEDULED
[00:12:57.110] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:10:0) after 6m43s
[00:17:35.906] Received PubSub notification, asking Buildbucket for the build status
[00:17:35.945] Build status: STARTED
[00:19:40.136] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:10:0)
[00:19:40.214] Build status: STARTED
[00:19:40.214] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:12:0) after 5m22s
[00:25:02.424] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:12:0)
[00:25:02.453] Build status: STARTED
[00:25:02.453] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:13:0) after 2m41s
[00:27:43.712] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:13:0)
[00:27:43.773] Build status: STARTED
[00:27:43.773] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:14:0) after 8m44s
[00:36:27.842] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:14:0)
[00:36:27.896] Build status: STARTED
[00:36:27.896] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:15:0) after 3m12s
[00:39:39.909] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:15:0)
[00:39:39.937] Build status: STARTED
[00:39:39.937] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:16:0) after 4m23s
[00:44:02.961] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:16:0)
[00:44:02.997] Build status: STARTED
[00:44:02.997] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:17:0) after 1m12s
[00:45:15.198] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:17:0)
[00:45:15.291] Build status: STARTED
[00:45:15.291] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:18:0) after 7m23s
[00:52:38.310] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:18:0)
[00:52:38.337] Build status: STARTED
[00:52:38.337] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:19:0) after 9m10s
[01:01:48.480] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:19:0)
[01:01:48.512] Build status: STARTED
[01:01:48.512] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:20:0) after 8m2s
[01:09:50.581] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:20:0)
[01:09:50.669] Build status: STARTED
[01:09:50.669] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:21:0) after 8m47s
[01:18:38.238] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:21:0)
[01:18:38.256] Build status: STARTED
[01:18:38.256] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:22:0) after 4m28s
[01:23:06.542] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:22:0)
[01:23:06.701] Build status: STARTED
[01:23:06.701] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:23:0) after 5m42s
[01:28:48.747] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:23:0)
[01:28:48.763] Build status: STARTED
[01:28:48.763] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:24:0) after 4m59s
[01:33:47.982] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:24:0)
[01:33:48.006] Build status: STARTED
[01:33:48.006] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:25:0) after 7m52s
[01:41:40.025] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:25:0)
[01:41:40.065] Build status: STARTED
[01:41:40.065] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:26:0) after 7m5s
[01:48:45.078] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:26:0)
[01:48:45.096] Build status: STARTED
[01:48:45.096] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:27:0) after 4m36s
[01:53:21.116] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:27:0)
[01:53:21.145] Build status: STARTED
[01:53:21.145] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:28:0) after 3m40s
[01:57:01.169] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:28:0)
[01:57:01.205] Build status: STARTED
[01:57:01.205] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:29:0) after 9m28s
[02:06:29.396] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:29:0)
[02:06:29.418] Build status: STARTED
[02:06:29.418] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:30:0) after 9m6s
[02:15:35.594] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:30:0)
[02:15:35.615] Build status: STARTED
[02:15:35.615] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:31:0) after 6m50s
[02:22:25.671] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:31:0)
[02:22:25.689] Build status: STARTED
[02:22:25.689] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:32:0) after 6m49s
[02:29:14.815] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:32:0)
[02:29:14.837] Build status: STARTED
[02:29:14.837] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:33:0) after 9m20s
[02:38:35.207] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:33:0)
[02:38:35.227] Build status: STARTED
[02:38:35.227] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:34:0) after 6m9s
[02:44:44.381] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:34:0)
[02:44:44.407] Build status: STARTED
[02:44:44.407] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:35:0) after 6m50s
[02:51:34.427] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:35:0)
[02:51:34.465] Build status: STARTED
[02:51:34.465] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:36:0) after 6m47s
[02:58:21.610] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:36:0)
[02:58:21.764] Build status: STARTED
[02:58:21.764] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:37:0) after 2m1s
[03:00:23.094] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:37:0)
[03:00:23.112] Build status: STARTED
[03:00:23.112] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:38:0) after 7m2s
[03:07:25.241] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:38:0)
[03:07:25.266] Build status: STARTED
[03:07:25.267] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:39:0) after 7m27s
[03:14:52.305] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:39:0)
[03:14:52.704] Build status: STARTED
[03:14:52.704] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:40:0) after 6m6s
[03:20:58.726] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:40:0)
[03:20:58.744] Build status: STARTED
[03:20:58.744] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8875752394666707888:41:0) after 6m13s
[03:26:20.314] Received PubSub notification, asking Buildbucket for the build status
[03:26:20.332] Build:
{
"id": "8710322332777972177",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "Android x64 Builder All Targets (dbg)"
},
"number": 506,
"createdBy": "project:chromium-m137",
"createTime": "2025-07-03T23:45:25.817505341Z",
"startTime": "2025-07-04T00:17:35.131932357Z",
"endTime": "2025-07-04T03:26:19.934533780Z",
"updateTime": "2025-07-04T03:26:19.934533780Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fbb149519bca52a97fe61e3a45d966c5850771d2",
"ref": "refs/branch-heads/7151"
}
}
}
[03:26:20.332] Invocation finished in 3h40m55.698482906s with status SUCCEEDED