[01:25:56.885] New invocation is queued and will start shortly
[01:25:57.981] Starting the invocation (attempt 1)
[01:25:58.017] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/90623b84bcd2edc2a1aa42c13c67693940a48b23"
[01:25:58.017] Popped gitiles commit info from properties and tags
[01:25:58.017] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:25:58.017] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:25:58.018] Buildbucket request:
{
"requestId": "8901837995721758000",
"builder": {
"project": "chromium-m130",
"bucket": "ci",
"builder": "Android arm Builder (dbg)"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8901837995721758000",
"job": "chromium-m130/Android arm Builder (dbg)",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "18553077590f403d2c51792586069cdbbecf6520"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@18553077590f403d2c51792586069cdbbecf6520",
"title": "18553077590f403d2c51792586069cdbbecf6520",
"url": "https://chromium.googlesource.com/chromium/src/+/18553077590f403d2c51792586069cdbbecf6520"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e2f198a8fd96d53cbe0f04d8cce93fce4c9225b1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@e2f198a8fd96d53cbe0f04d8cce93fce4c9225b1",
"title": "e2f198a8fd96d53cbe0f04d8cce93fce4c9225b1",
"url": "https://chromium.googlesource.com/chromium/src/+/e2f198a8fd96d53cbe0f04d8cce93fce4c9225b1"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5ac40897b3caadbea6df8a7a2fd593880b548f20"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@5ac40897b3caadbea6df8a7a2fd593880b548f20",
"title": "5ac40897b3caadbea6df8a7a2fd593880b548f20",
"url": "https://chromium.googlesource.com/chromium/src/+/5ac40897b3caadbea6df8a7a2fd593880b548f20"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5da0027c790febd04f44f67b8bee77606663d7c5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@5da0027c790febd04f44f67b8bee77606663d7c5",
"title": "5da0027c790febd04f44f67b8bee77606663d7c5",
"url": "https://chromium.googlesource.com/chromium/src/+/5da0027c790febd04f44f67b8bee77606663d7c5"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "24a2fa904aed819b6491c5d54cc8d1d8dc686ff4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@24a2fa904aed819b6491c5d54cc8d1d8dc686ff4",
"title": "24a2fa904aed819b6491c5d54cc8d1d8dc686ff4",
"url": "https://chromium.googlesource.com/chromium/src/+/24a2fa904aed819b6491c5d54cc8d1d8dc686ff4"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "74239bbdc23b6efb20a621a311bb79fc02694b00"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@74239bbdc23b6efb20a621a311bb79fc02694b00",
"title": "74239bbdc23b6efb20a621a311bb79fc02694b00",
"url": "https://chromium.googlesource.com/chromium/src/+/74239bbdc23b6efb20a621a311bb79fc02694b00"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e04186923dbbd2ad2f1d3a16226efebcf7707e31"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@e04186923dbbd2ad2f1d3a16226efebcf7707e31",
"title": "e04186923dbbd2ad2f1d3a16226efebcf7707e31",
"url": "https://chromium.googlesource.com/chromium/src/+/e04186923dbbd2ad2f1d3a16226efebcf7707e31"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "286ce15fded46cfa1f69a911e6403eede9e179c3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@286ce15fded46cfa1f69a911e6403eede9e179c3",
"title": "286ce15fded46cfa1f69a911e6403eede9e179c3",
"url": "https://chromium.googlesource.com/chromium/src/+/286ce15fded46cfa1f69a911e6403eede9e179c3"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "82ff309cb3dce162eb6cfac221b258e1d419188e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@82ff309cb3dce162eb6cfac221b258e1d419188e",
"title": "82ff309cb3dce162eb6cfac221b258e1d419188e",
"url": "https://chromium.googlesource.com/chromium/src/+/82ff309cb3dce162eb6cfac221b258e1d419188e"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1b57664b1756300c5f13a386831802ae52e64031"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@1b57664b1756300c5f13a386831802ae52e64031",
"title": "1b57664b1756300c5f13a386831802ae52e64031",
"url": "https://chromium.googlesource.com/chromium/src/+/1b57664b1756300c5f13a386831802ae52e64031"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4ac3c8ac6ce87fa5c64d470bd468a16fd75845b4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@4ac3c8ac6ce87fa5c64d470bd468a16fd75845b4",
"title": "4ac3c8ac6ce87fa5c64d470bd468a16fd75845b4",
"url": "https://chromium.googlesource.com/chromium/src/+/4ac3c8ac6ce87fa5c64d470bd468a16fd75845b4"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "90623b84bcd2edc2a1aa42c13c67693940a48b23"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@90623b84bcd2edc2a1aa42c13c67693940a48b23",
"title": "90623b84bcd2edc2a1aa42c13c67693940a48b23",
"url": "https://chromium.googlesource.com/chromium/src/+/90623b84bcd2edc2a1aa42c13c67693940a48b23"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "90623b84bcd2edc2a1aa42c13c67693940a48b23",
"ref": "refs/branch-heads/6723"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8901837995721758000"
},
{
"key": "scheduler_job_id",
"value": "chromium-m130/Android arm Builder (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:25:58.890] Scheduled build:
{
"id": "8736407933797931425",
"builder": {
"project": "chromium-m130",
"bucket": "ci",
"builder": "Android arm Builder (dbg)"
},
"number": 13,
"createdBy": "project:chromium-m130",
"createTime": "2024-09-19T01:25:58.097315989Z",
"updateTime": "2024-09-19T01:25:58.097315989Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "90623b84bcd2edc2a1aa42c13c67693940a48b23",
"ref": "refs/branch-heads/6723"
}
}
}
[01:25:58.890] Task URL: https://cr-buildbucket.appspot.com/build/8736407933797931425
[01:25:58.890] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:2:0) after 6m6s
[01:25:59.526] Received PubSub notification, asking Buildbucket for the build status
[01:25:59.550] Build status: SCHEDULED
[01:32:04.976] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:2:0)
[01:32:04.995] Build status: SCHEDULED
[01:32:04.995] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:4:0) after 6m33s
[01:38:38.012] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:4:0)
[01:38:38.034] Build status: SCHEDULED
[01:38:38.034] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:5:0) after 6m30s
[01:45:08.053] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:5:0)
[01:45:08.116] Build status: SCHEDULED
[01:45:08.117] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:6:0) after 4m57s
[01:50:05.193] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:6:0)
[01:50:05.222] Build status: SCHEDULED
[01:50:05.222] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:7:0) after 2m33s
[01:52:38.244] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:7:0)
[01:52:38.261] Build status: SCHEDULED
[01:52:38.261] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:8:0) after 9m45s
[02:02:23.623] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:8:0)
[02:02:23.696] Build status: SCHEDULED
[02:02:23.696] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:9:0) after 3m51s
[02:06:14.945] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:9:0)
[02:06:15.028] Build status: SCHEDULED
[02:06:15.028] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:10:0) after 9m21s
[02:15:36.022] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:10:0)
[02:15:36.044] Build status: SCHEDULED
[02:15:36.044] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:11:0) after 3m33s
[02:19:09.069] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:11:0)
[02:19:09.088] Build status: SCHEDULED
[02:19:09.088] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:12:0) after 3m19s
[02:22:28.116] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:12:0)
[02:22:28.138] Build status: SCHEDULED
[02:22:28.138] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:13:0) after 4m5s
[02:26:33.154] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:13:0)
[02:26:33.170] Build status: SCHEDULED
[02:26:33.170] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:14:0) after 8m58s
[02:35:31.201] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:14:0)
[02:35:31.292] Build status: SCHEDULED
[02:35:31.292] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:15:0) after 5m27s
[02:40:58.496] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:15:0)
[02:40:58.646] Build status: SCHEDULED
[02:40:58.646] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:16:0) after 1m32s
[02:42:30.641] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:16:0)
[02:42:30.660] Build status: SCHEDULED
[02:42:30.660] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:17:0) after 4m12s
[02:46:43.059] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:17:0)
[02:46:43.084] Build status: SCHEDULED
[02:46:43.084] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:18:0) after 8m33s
[02:55:17.215] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:18:0)
[02:55:17.237] Build status: SCHEDULED
[02:55:17.237] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:19:0) after 8m21s
[03:03:38.463] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:19:0)
[03:03:38.501] Build status: SCHEDULED
[03:03:38.501] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:20:0) after 6m6s
[03:05:15.236] Received PubSub notification, asking Buildbucket for the build status
[03:05:15.332] Build status: STARTED
[03:09:44.496] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:20:0)
[03:09:44.517] Build status: STARTED
[03:09:44.517] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:22:0) after 4m10s
[03:13:54.509] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:22:0)
[03:13:54.529] Build status: STARTED
[03:13:54.529] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:23:0) after 2m59s
[03:16:53.550] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:23:0)
[03:16:53.570] Build status: STARTED
[03:16:53.570] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:24:0) after 9m50s
[03:26:43.696] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:24:0)
[03:26:43.715] Build status: STARTED
[03:26:43.715] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:25:0) after 4m17s
[03:31:00.927] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:25:0)
[03:31:00.961] Build status: STARTED
[03:31:00.961] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:26:0) after 7m8s
[03:38:08.990] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:26:0)
[03:38:09.008] Build status: STARTED
[03:38:09.008] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:27:0) after 2m38s
[03:40:47.031] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:27:0)
[03:40:47.056] Build status: STARTED
[03:40:47.056] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:28:0) after 8m7s
[03:48:54.073] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:28:0)
[03:48:54.092] Build status: STARTED
[03:48:54.092] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:29:0) after 7m4s
[03:55:58.202] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:29:0)
[03:55:58.221] Build status: STARTED
[03:55:58.221] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:30:0) after 7m5s
[04:03:03.290] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:30:0)
[04:03:03.312] Build status: STARTED
[04:03:03.312] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:31:0) after 9m10s
[04:12:13.517] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:31:0)
[04:12:13.540] Build status: STARTED
[04:12:13.540] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:32:0) after 3m56s
[04:16:09.559] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:32:0)
[04:16:09.583] Build status: STARTED
[04:16:09.583] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:33:0) after 9m58s
[04:26:07.613] Handling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:33:0)
[04:26:07.638] Build status: STARTED
[04:26:07.638] Scheduling timer "check-buildbucket-build-status" (chromium-m130/Android arm Builder (dbg):8901837995721758000:34:0) after 9m14s
[04:29:15.843] Received PubSub notification, asking Buildbucket for the build status
[04:29:15.874] Build:
{
"id": "8736407933797931425",
"builder": {
"project": "chromium-m130",
"bucket": "ci",
"builder": "Android arm Builder (dbg)"
},
"number": 13,
"createdBy": "project:chromium-m130",
"createTime": "2024-09-19T01:25:58.097315989Z",
"startTime": "2024-09-19T03:05:15.006014637Z",
"endTime": "2024-09-19T04:29:15.446919694Z",
"updateTime": "2024-09-19T04:29:15.446919694Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "90623b84bcd2edc2a1aa42c13c67693940a48b23",
"ref": "refs/branch-heads/6723"
}
}
}
[04:29:15.874] Invocation finished in 3h3m18.996790703s with status SUCCEEDED