[02:47:16.729] New invocation is queued and will start shortly
[02:47:17.971] Starting the invocation (attempt 1)
[02:47:17.997] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0f4b69a2d7ce7b49445104e42d517ef975db4503"
[02:47:17.997] Popped gitiles commit info from properties and tags
[02:47:17.997] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:47:17.997] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:47:17.997] Buildbucket request:
{
"requestId": "8879636622063937952",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "Android x64 Builder All Targets (dbg)"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8879636622063937952",
"job": "chromium-m137/Android x64 Builder All Targets (dbg)",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "20814a3ddd5d6798d3be8e9265ca84dbb8bda7e1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@20814a3ddd5d6798d3be8e9265ca84dbb8bda7e1",
"title": "20814a3ddd5d6798d3be8e9265ca84dbb8bda7e1",
"url": "https://chromium.googlesource.com/chromium/src/+/20814a3ddd5d6798d3be8e9265ca84dbb8bda7e1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0f4b69a2d7ce7b49445104e42d517ef975db4503"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@0f4b69a2d7ce7b49445104e42d517ef975db4503",
"title": "0f4b69a2d7ce7b49445104e42d517ef975db4503",
"url": "https://chromium.googlesource.com/chromium/src/+/0f4b69a2d7ce7b49445104e42d517ef975db4503"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0f4b69a2d7ce7b49445104e42d517ef975db4503",
"ref": "refs/branch-heads/7151"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8879636622063937952"
},
{
"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"
}
}
[02:47:18.459] Scheduled build:
{
"id": "8714206560027713169",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "Android x64 Builder All Targets (dbg)"
},
"number": 195,
"createdBy": "project:chromium-m137",
"createTime": "2025-05-22T02:47:18.050699815Z",
"updateTime": "2025-05-22T02:47:18.050699815Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0f4b69a2d7ce7b49445104e42d517ef975db4503",
"ref": "refs/branch-heads/7151"
}
}
}
[02:47:18.459] Task URL: https://cr-buildbucket.appspot.com/build/8714206560027713169
[02:47:18.459] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:2:0) after 5m19s
[02:47:19.459] Received PubSub notification, asking Buildbucket for the build status
[02:47:19.476] Build status: SCHEDULED
[02:52:37.511] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:2:0)
[02:52:37.610] Build status: SCHEDULED
[02:52:37.610] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:4:0) after 1m3s
[02:53:40.623] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:4:0)
[02:53:40.654] Build status: SCHEDULED
[02:53:40.654] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:5:0) after 9m58s
[02:56:41.453] Received PubSub notification, asking Buildbucket for the build status
[02:56:41.481] Build status: STARTED
[03:03:38.724] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:5:0)
[03:03:38.747] Build status: STARTED
[03:03:38.747] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:7:0) after 2m38s
[03:06:16.818] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:7:0)
[03:06:16.924] Build status: STARTED
[03:06:16.924] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:8:0) after 2m55s
[03:09:11.981] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:8:0)
[03:09:12.004] Build status: STARTED
[03:09:12.005] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:9:0) after 8m3s
[03:17:15.072] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:9:0)
[03:17:15.104] Build status: STARTED
[03:17:15.104] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:10:0) after 2m31s
[03:19:46.174] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:10:0)
[03:19:46.195] Build status: STARTED
[03:19:46.195] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:11:0) after 7m0s
[03:26:46.276] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:11:0)
[03:26:46.326] Build status: STARTED
[03:26:46.326] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:12:0) after 1m2s
[03:27:48.383] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:12:0)
[03:27:48.411] Build status: STARTED
[03:27:48.411] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:13:0) after 7m43s
[03:35:31.435] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:13:0)
[03:35:31.457] Build status: STARTED
[03:35:31.457] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:14:0) after 5m9s
[03:40:40.469] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:14:0)
[03:40:40.510] Build status: STARTED
[03:40:40.510] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:15:0) after 6m4s
[03:46:44.674] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:15:0)
[03:46:44.701] Build status: STARTED
[03:46:44.701] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:16:0) after 7m59s
[03:54:43.763] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:16:0)
[03:54:43.786] Build status: STARTED
[03:54:43.786] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:17:0) after 5m40s
[04:00:23.861] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:17:0)
[04:00:23.879] Build status: STARTED
[04:00:23.879] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:18:0) after 1m21s
[04:01:44.906] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:18:0)
[04:01:44.949] Build status: STARTED
[04:01:44.949] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:19:0) after 5m8s
[04:06:53.025] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:19:0)
[04:06:53.052] Build status: STARTED
[04:06:53.052] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:20:0) after 4m2s
[04:10:55.076] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:20:0)
[04:10:55.097] Build status: STARTED
[04:10:55.097] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:21:0) after 7m37s
[04:18:32.122] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:21:0)
[04:18:32.152] Build status: STARTED
[04:18:32.152] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:22:0) after 7m51s
[04:26:23.193] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:22:0)
[04:26:23.209] Build status: STARTED
[04:26:23.209] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:23:0) after 4m52s
[04:31:15.243] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:23:0)
[04:31:15.260] Build status: STARTED
[04:31:15.260] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:24:0) after 5m37s
[04:36:52.321] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:24:0)
[04:36:52.349] Build status: STARTED
[04:36:52.349] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:25:0) after 1m3s
[04:37:55.444] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:25:0)
[04:37:55.497] Build status: STARTED
[04:37:55.497] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:26:0) after 1m50s
[04:39:45.511] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:26:0)
[04:39:45.529] Build status: STARTED
[04:39:45.529] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:27:0) after 4m32s
[04:44:17.554] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:27:0)
[04:44:17.574] Build status: STARTED
[04:44:17.574] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:28:0) after 4m55s
[04:49:12.637] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:28:0)
[04:49:12.659] Build status: STARTED
[04:49:12.659] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:29:0) after 7m11s
[04:56:23.735] Handling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:29:0)
[04:56:23.754] Build status: STARTED
[04:56:23.754] Scheduling timer "check-buildbucket-build-status" (chromium-m137/Android x64 Builder All Targets (dbg):8879636622063937952:30:0) after 2m0s
[04:56:37.467] Received PubSub notification, asking Buildbucket for the build status
[04:56:37.487] Build:
{
"id": "8714206560027713169",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "Android x64 Builder All Targets (dbg)"
},
"number": 195,
"createdBy": "project:chromium-m137",
"createTime": "2025-05-22T02:47:18.050699815Z",
"startTime": "2025-05-22T02:56:41.274914842Z",
"endTime": "2025-05-22T04:56:37.142729464Z",
"updateTime": "2025-05-22T04:56:37.142729464Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0f4b69a2d7ce7b49445104e42d517ef975db4503",
"ref": "refs/branch-heads/7151"
}
}
}
[04:56:37.487] Invocation finished in 2h9m20.762351187s with status SUCCEEDED