[11:56:22.704] New invocation is queued and will start shortly
[11:56:23.773] Starting the invocation (attempt 1)
[11:56:23.799] Popped buildset tag "buildset:commit/gitiles/android.googlesource.com/platform/libcore/+/f1dec0e615003949347c8fc84d9b3b30cfee8d9c"
[11:56:23.799] Popped gitiles commit info from properties and tags
[11:56:23.799] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:56:23.799] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:56:23.800] Buildbucket request:
{
"requestId": "8895003560004739968",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "host.x86.ndebug.32"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8895003560004739968",
"job": "art/host.x86.ndebug.32",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/libcore",
"revision": "d597e2e63ca35b7ee3f2984e7d372d01cfa6103e"
},
"id": "https://android.googlesource.com/platform/libcore/+/refs/heads/master@d597e2e63ca35b7ee3f2984e7d372d01cfa6103e",
"title": "d597e2e63ca35b7ee3f2984e7d372d01cfa6103e",
"url": "https://android.googlesource.com/platform/libcore/+/d597e2e63ca35b7ee3f2984e7d372d01cfa6103e"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/libcore",
"revision": "db100ec2719301ed7cdb4b98b5d7f5c3141d72cd"
},
"id": "https://android.googlesource.com/platform/libcore/+/refs/heads/master@db100ec2719301ed7cdb4b98b5d7f5c3141d72cd",
"title": "db100ec2719301ed7cdb4b98b5d7f5c3141d72cd",
"url": "https://android.googlesource.com/platform/libcore/+/db100ec2719301ed7cdb4b98b5d7f5c3141d72cd"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/libcore",
"revision": "52d545f49a09639b48de0fd4909b3b56248d8046"
},
"id": "https://android.googlesource.com/platform/libcore/+/refs/heads/master@52d545f49a09639b48de0fd4909b3b56248d8046",
"title": "52d545f49a09639b48de0fd4909b3b56248d8046",
"url": "https://android.googlesource.com/platform/libcore/+/52d545f49a09639b48de0fd4909b3b56248d8046"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/libcore",
"revision": "ecd9162881753ce89d512727acc25766ab3d1977"
},
"id": "https://android.googlesource.com/platform/libcore/+/refs/heads/master@ecd9162881753ce89d512727acc25766ab3d1977",
"title": "ecd9162881753ce89d512727acc25766ab3d1977",
"url": "https://android.googlesource.com/platform/libcore/+/ecd9162881753ce89d512727acc25766ab3d1977"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/libcore",
"revision": "c11343eff9eb6c515cdf57fd096e9f934a7f46a5"
},
"id": "https://android.googlesource.com/platform/libcore/+/refs/heads/master@c11343eff9eb6c515cdf57fd096e9f934a7f46a5",
"title": "c11343eff9eb6c515cdf57fd096e9f934a7f46a5",
"url": "https://android.googlesource.com/platform/libcore/+/c11343eff9eb6c515cdf57fd096e9f934a7f46a5"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/libcore",
"revision": "f1dec0e615003949347c8fc84d9b3b30cfee8d9c"
},
"id": "https://android.googlesource.com/platform/libcore/+/refs/heads/master@f1dec0e615003949347c8fc84d9b3b30cfee8d9c",
"title": "f1dec0e615003949347c8fc84d9b3b30cfee8d9c",
"url": "https://android.googlesource.com/platform/libcore/+/f1dec0e615003949347c8fc84d9b3b30cfee8d9c"
}
]
}
},
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/libcore",
"id": "f1dec0e615003949347c8fc84d9b3b30cfee8d9c",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8895003560004739968"
},
{
"key": "scheduler_job_id",
"value": "art/host.x86.ndebug.32"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[11:56:24.244] Scheduled build:
{
"id": "8729573498129757473",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "host.x86.ndebug.32"
},
"number": 271,
"createdBy": "project:art",
"createTime": "2024-12-03T11:56:23.860605423Z",
"updateTime": "2024-12-03T11:56:23.860605423Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/libcore",
"id": "f1dec0e615003949347c8fc84d9b3b30cfee8d9c",
"ref": "refs/heads/master"
}
}
}
[11:56:24.244] Task URL: https://cr-buildbucket.appspot.com/build/8729573498129757473
[11:56:24.244] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:2:0) after 1m27s
[11:56:25.242] Received PubSub notification, asking Buildbucket for the build status
[11:56:25.261] Build status: SCHEDULED
[11:56:31.294] Received PubSub notification, asking Buildbucket for the build status
[11:56:31.322] Build status: STARTED
[11:57:51.314] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:2:0)
[11:57:51.339] Build status: STARTED
[11:57:51.339] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:5:0) after 9m42s
[12:07:33.352] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:5:0)
[12:07:33.379] Build status: STARTED
[12:07:33.379] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:6:0) after 3m41s
[12:11:14.383] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:6:0)
[12:11:14.426] Build status: STARTED
[12:11:14.426] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:7:0) after 2m14s
[12:13:28.458] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:7:0)
[12:13:28.485] Build status: STARTED
[12:13:28.485] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:8:0) after 1m31s
[12:14:59.523] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:8:0)
[12:14:59.549] Build status: STARTED
[12:14:59.549] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:9:0) after 6m28s
[12:21:27.615] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:9:0)
[12:21:27.636] Build status: STARTED
[12:21:27.636] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:10:0) after 9m55s
[12:31:22.688] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:10:0)
[12:31:22.712] Build status: STARTED
[12:31:22.712] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:11:0) after 3m57s
[12:35:19.879] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:11:0)
[12:35:19.899] Build status: STARTED
[12:35:19.899] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:12:0) after 1m33s
[12:36:52.974] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:12:0)
[12:36:52.990] Build status: STARTED
[12:36:52.990] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:13:0) after 8m14s
[12:45:07.058] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:13:0)
[12:45:07.075] Build status: STARTED
[12:45:07.075] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:14:0) after 7m56s
[12:53:03.091] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:14:0)
[12:53:03.119] Build status: STARTED
[12:53:03.119] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:15:0) after 7m44s
[13:00:47.144] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:15:0)
[13:00:47.205] Build status: STARTED
[13:00:47.205] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:16:0) after 2m20s
[13:03:07.236] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:16:0)
[13:03:07.266] Build status: STARTED
[13:03:07.266] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:17:0) after 7m12s
[13:10:19.307] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:17:0)
[13:10:19.332] Build status: STARTED
[13:10:19.332] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:18:0) after 4m11s
[13:14:30.339] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:18:0)
[13:14:30.361] Build status: STARTED
[13:14:30.361] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:19:0) after 9m50s
[13:24:20.533] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:19:0)
[13:24:20.549] Build status: STARTED
[13:24:20.549] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:20:0) after 3m56s
[13:28:16.571] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:20:0)
[13:28:16.606] Build status: STARTED
[13:28:16.606] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:21:0) after 2m19s
[13:30:35.959] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:21:0)
[13:30:36.007] Build status: STARTED
[13:30:36.007] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:22:0) after 4m18s
[13:34:54.121] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:22:0)
[13:34:54.165] Build status: STARTED
[13:34:54.165] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:23:0) after 1m38s
[13:36:32.189] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:23:0)
[13:36:32.207] Build status: STARTED
[13:36:32.207] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:24:0) after 4m23s
[13:40:55.226] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:24:0)
[13:40:55.286] Build status: STARTED
[13:40:55.286] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:25:0) after 2m13s
[13:43:08.301] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:25:0)
[13:43:08.320] Build status: STARTED
[13:43:08.320] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:26:0) after 7m21s
[13:50:29.339] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:26:0)
[13:50:29.358] Build status: STARTED
[13:50:29.358] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8895003560004739968:27:0) after 6m51s
[13:52:20.009] Received PubSub notification, asking Buildbucket for the build status
[13:52:20.027] Build:
{
"id": "8729573498129757473",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "host.x86.ndebug.32"
},
"number": 271,
"createdBy": "project:art",
"createTime": "2024-12-03T11:56:23.860605423Z",
"startTime": "2024-12-03T11:56:31.126191208Z",
"endTime": "2024-12-03T13:52:19.828693741Z",
"updateTime": "2024-12-03T13:52:19.828693741Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/libcore",
"id": "f1dec0e615003949347c8fc84d9b3b30cfee8d9c",
"ref": "refs/heads/master"
}
}
}
[13:52:20.027] Invocation finished in 1h55m57.338551748s with status SUCCEEDED