[09:42:59.181] New invocation is queued and will start shortly
[09:43:00.419] Starting the invocation (attempt 1)
[09:43:00.452] Popped buildset tag "buildset:commit/gitiles/r8.googlesource.com/r8/+/3191d91bd817fded60c594add822c433ac2df396"
[09:43:00.452] Popped gitiles commit info from properties and tags
[09:43:00.452] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:43:00.452] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:43:00.452] Buildbucket request:
{
"requestId": "8904615230740842048",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-android-13"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8904615230740842048",
"job": "r8/linux-android-13",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "c5b4401ee155593ea2d2f8517efd300d4fae18ad"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@c5b4401ee155593ea2d2f8517efd300d4fae18ad",
"title": "c5b4401ee155593ea2d2f8517efd300d4fae18ad",
"url": "https://r8.googlesource.com/r8/+/c5b4401ee155593ea2d2f8517efd300d4fae18ad"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "734c154615c1e98faf34f8d2a098ae869fd3d7fa"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@734c154615c1e98faf34f8d2a098ae869fd3d7fa",
"title": "734c154615c1e98faf34f8d2a098ae869fd3d7fa",
"url": "https://r8.googlesource.com/r8/+/734c154615c1e98faf34f8d2a098ae869fd3d7fa"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "418c5456d8d2c2ff40ca7e536d48cf13347e76bf"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@418c5456d8d2c2ff40ca7e536d48cf13347e76bf",
"title": "418c5456d8d2c2ff40ca7e536d48cf13347e76bf",
"url": "https://r8.googlesource.com/r8/+/418c5456d8d2c2ff40ca7e536d48cf13347e76bf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://r8.googlesource.com/r8",
"revision": "3191d91bd817fded60c594add822c433ac2df396"
},
"id": "https://r8.googlesource.com/r8/+/refs/heads/main@3191d91bd817fded60c594add822c433ac2df396",
"title": "3191d91bd817fded60c594add822c433ac2df396",
"url": "https://r8.googlesource.com/r8/+/3191d91bd817fded60c594add822c433ac2df396"
}
]
}
},
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "3191d91bd817fded60c594add822c433ac2df396",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8904615230740842048"
},
{
"key": "scheduler_job_id",
"value": "r8/linux-android-13"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[09:43:01.105] Scheduled build:
{
"id": "8739185168695106097",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-android-13"
},
"number": 149,
"createdBy": "project:r8",
"createTime": "2024-08-19T09:43:00.505159765Z",
"updateTime": "2024-08-19T09:43:00.505159765Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "3191d91bd817fded60c594add822c433ac2df396",
"ref": "refs/heads/main"
}
}
}
[09:43:01.105] Task URL: https://cr-buildbucket.appspot.com/build/8739185168695106097
[09:43:01.105] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:2:0) after 6m22s
[09:43:01.870] Received PubSub notification, asking Buildbucket for the build status
[09:43:01.931] Build status: SCHEDULED
[09:49:23.133] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:2:0)
[09:49:23.156] Build status: SCHEDULED
[09:49:23.156] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:4:0) after 3m2s
[09:52:25.593] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:4:0)
[09:52:25.618] Build status: SCHEDULED
[09:52:25.618] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:5:0) after 1m20s
[09:53:45.632] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:5:0)
[09:53:45.650] Build status: SCHEDULED
[09:53:45.650] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:6:0) after 7m26s
[10:01:11.872] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:6:0)
[10:01:11.958] Build status: SCHEDULED
[10:01:11.958] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:7:0) after 6m47s
[10:07:58.952] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:7:0)
[10:07:58.982] Build status: SCHEDULED
[10:07:58.982] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:8:0) after 5m35s
[10:13:34.093] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:8:0)
[10:13:34.118] Build status: SCHEDULED
[10:13:34.118] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:9:0) after 4m12s
[10:17:46.143] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:9:0)
[10:17:46.161] Build status: SCHEDULED
[10:17:46.161] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:10:0) after 4m10s
[10:21:56.275] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:10:0)
[10:21:56.303] Build status: SCHEDULED
[10:21:56.303] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:11:0) after 5m45s
[10:27:41.824] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:11:0)
[10:27:41.842] Build status: SCHEDULED
[10:27:41.842] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:12:0) after 4m33s
[10:32:14.858] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:12:0)
[10:32:14.909] Build status: SCHEDULED
[10:32:14.909] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:13:0) after 3m15s
[10:35:29.964] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:13:0)
[10:35:29.982] Build status: SCHEDULED
[10:35:29.982] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:14:0) after 8m58s
[10:44:28.848] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:14:0)
[10:44:28.868] Build status: SCHEDULED
[10:44:28.868] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:15:0) after 1m27s
[10:45:55.893] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:15:0)
[10:45:56.067] Build status: SCHEDULED
[10:45:56.067] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:16:0) after 8m3s
[10:53:59.083] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:16:0)
[10:53:59.106] Build status: SCHEDULED
[10:53:59.106] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:17:0) after 4m1s
[10:58:00.701] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:17:0)
[10:58:00.729] Build status: SCHEDULED
[10:58:00.729] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:18:0) after 3m55s
[11:01:55.929] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:18:0)
[11:01:55.952] Build status: SCHEDULED
[11:01:55.952] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:19:0) after 7m50s
[11:09:46.030] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:19:0)
[11:09:46.133] Build status: SCHEDULED
[11:09:46.133] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:20:0) after 1m2s
[11:10:48.279] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:20:0)
[11:10:48.297] Build status: SCHEDULED
[11:10:48.297] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:21:0) after 5m31s
[11:16:19.956] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:21:0)
[11:16:19.978] Build status: SCHEDULED
[11:16:19.978] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:22:0) after 1m35s
[11:17:54.998] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:22:0)
[11:17:55.237] Build status: SCHEDULED
[11:17:55.237] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:23:0) after 6m31s
[11:24:26.260] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:23:0)
[11:24:26.278] Build status: SCHEDULED
[11:24:26.278] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:24:0) after 5m5s
[11:29:31.304] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:24:0)
[11:29:31.328] Build status: SCHEDULED
[11:29:31.328] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:25:0) after 9m31s
[11:39:02.502] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:25:0)
[11:39:02.520] Build status: SCHEDULED
[11:39:02.520] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:26:0) after 9m54s
[11:48:56.756] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:26:0)
[11:48:56.773] Build status: SCHEDULED
[11:48:56.773] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:27:0) after 5m19s
[11:54:15.844] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:27:0)
[11:54:15.866] Build status: SCHEDULED
[11:54:15.866] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:28:0) after 4m20s
[11:58:35.909] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:28:0)
[11:58:35.929] Build status: SCHEDULED
[11:58:35.929] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:29:0) after 9m26s
[12:08:01.985] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:29:0)
[12:08:02.010] Build status: SCHEDULED
[12:08:02.010] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:30:0) after 6m47s
[12:12:26.730] Received PubSub notification, asking Buildbucket for the build status
[12:12:26.750] Build status: STARTED
[12:14:49.008] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:30:0)
[12:14:49.025] Build status: STARTED
[12:14:49.025] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:32:0) after 3m58s
[12:18:47.039] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:32:0)
[12:18:47.055] Build status: STARTED
[12:18:47.055] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:33:0) after 2m46s
[12:21:33.198] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:33:0)
[12:21:33.214] Build status: STARTED
[12:21:33.214] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:34:0) after 8m41s
[12:30:14.309] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:34:0)
[12:30:14.330] Build status: STARTED
[12:30:14.330] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:35:0) after 9m21s
[12:39:35.471] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:35:0)
[12:39:35.507] Build status: STARTED
[12:39:35.507] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:36:0) after 6m53s
[12:46:28.525] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:36:0)
[12:46:30.857] Build status: STARTED
[12:46:30.857] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:37:0) after 9m13s
[12:55:44.130] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:37:0)
[12:55:44.155] Build status: STARTED
[12:55:44.155] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:38:0) after 2m9s
[12:57:53.426] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:38:0)
[12:57:53.449] Build status: STARTED
[12:57:53.449] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:39:0) after 7m33s
[13:05:26.800] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:39:0)
[13:05:26.817] Build status: STARTED
[13:05:26.817] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:40:0) after 2m39s
[13:08:06.056] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:40:0)
[13:08:06.075] Build status: STARTED
[13:08:06.075] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:41:0) after 8m34s
[13:16:40.102] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:41:0)
[13:16:40.166] Build status: STARTED
[13:16:40.166] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:42:0) after 9m52s
[13:26:32.415] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:42:0)
[13:26:32.443] Build status: STARTED
[13:26:32.443] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:43:0) after 7m30s
[13:34:02.548] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:43:0)
[13:34:02.569] Build status: STARTED
[13:34:02.569] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:44:0) after 1m23s
[13:35:25.582] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:44:0)
[13:35:25.644] Build status: STARTED
[13:35:25.644] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:45:0) after 6m49s
[13:42:14.662] Handling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:45:0)
[13:42:14.967] Build status: STARTED
[13:42:14.967] Scheduling timer "check-buildbucket-build-status" (r8/linux-android-13:8904615230740842048:46:0) after 6m26s
[13:47:27.650] Received PubSub notification, asking Buildbucket for the build status
[13:47:27.676] Build:
{
"id": "8739185168695106097",
"builder": {
"project": "r8",
"bucket": "ci",
"builder": "linux-android-13"
},
"number": 149,
"createdBy": "project:r8",
"createTime": "2024-08-19T09:43:00.505159765Z",
"startTime": "2024-08-19T12:12:26.562107339Z",
"endTime": "2024-08-19T13:47:27.405380554Z",
"updateTime": "2024-08-19T13:47:27.405380554Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "r8.googlesource.com",
"project": "r8",
"id": "3191d91bd817fded60c594add822c433ac2df396",
"ref": "refs/heads/main"
}
}
}
[13:47:27.676] Invocation finished in 4h4m28.506233935s with status SUCCEEDED