[11:10:47.524] New invocation is queued and will start shortly
[11:10:48.719] Starting the invocation (attempt 1)
[11:10:48.749] Popped buildset tag "buildset:commit/gitiles/android.googlesource.com/platform/libcore/+/12ae96fc0d5bf8fb84520a6f3ca8feaf6158d896"
[11:10:48.749] Popped gitiles commit info from properties and tags
[11:10:48.749] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:10:48.749] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:10:48.749] Buildbucket request:
{
"requestId": "8896818367367766688",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "target.arm.ndebug.64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8896818367367766688",
"job": "art/target.arm.ndebug.64",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/libcore",
"revision": "624acb36a1f45a409481033bf7da092dfe52793a"
},
"id": "https://android.googlesource.com/platform/libcore/+/refs/heads/master@624acb36a1f45a409481033bf7da092dfe52793a",
"title": "624acb36a1f45a409481033bf7da092dfe52793a",
"url": "https://android.googlesource.com/platform/libcore/+/624acb36a1f45a409481033bf7da092dfe52793a"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/art",
"revision": "6713bfa5b4d627a69df4ae6659a4761117a74399"
},
"id": "https://android.googlesource.com/platform/art/+/refs/heads/master@6713bfa5b4d627a69df4ae6659a4761117a74399",
"title": "6713bfa5b4d627a69df4ae6659a4761117a74399",
"url": "https://android.googlesource.com/platform/art/+/6713bfa5b4d627a69df4ae6659a4761117a74399"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/libcore",
"revision": "c00522168c0ee3c62d5da6558b9646093917e2ea"
},
"id": "https://android.googlesource.com/platform/libcore/+/refs/heads/master@c00522168c0ee3c62d5da6558b9646093917e2ea",
"title": "c00522168c0ee3c62d5da6558b9646093917e2ea",
"url": "https://android.googlesource.com/platform/libcore/+/c00522168c0ee3c62d5da6558b9646093917e2ea"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/art",
"revision": "c2ad788c311fc144582a07d4ae1654d39079511c"
},
"id": "https://android.googlesource.com/platform/art/+/refs/heads/master@c2ad788c311fc144582a07d4ae1654d39079511c",
"title": "c2ad788c311fc144582a07d4ae1654d39079511c",
"url": "https://android.googlesource.com/platform/art/+/c2ad788c311fc144582a07d4ae1654d39079511c"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/libcore",
"revision": "48b009725413dd3bf85126de3b1483b5dbc13e4c"
},
"id": "https://android.googlesource.com/platform/libcore/+/refs/heads/master@48b009725413dd3bf85126de3b1483b5dbc13e4c",
"title": "48b009725413dd3bf85126de3b1483b5dbc13e4c",
"url": "https://android.googlesource.com/platform/libcore/+/48b009725413dd3bf85126de3b1483b5dbc13e4c"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/libcore",
"revision": "a32b817caa635d7ed6bc37efc224173cc4c72f30"
},
"id": "https://android.googlesource.com/platform/libcore/+/refs/heads/master@a32b817caa635d7ed6bc37efc224173cc4c72f30",
"title": "a32b817caa635d7ed6bc37efc224173cc4c72f30",
"url": "https://android.googlesource.com/platform/libcore/+/a32b817caa635d7ed6bc37efc224173cc4c72f30"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/libcore",
"revision": "12ae96fc0d5bf8fb84520a6f3ca8feaf6158d896"
},
"id": "https://android.googlesource.com/platform/libcore/+/refs/heads/master@12ae96fc0d5bf8fb84520a6f3ca8feaf6158d896",
"title": "12ae96fc0d5bf8fb84520a6f3ca8feaf6158d896",
"url": "https://android.googlesource.com/platform/libcore/+/12ae96fc0d5bf8fb84520a6f3ca8feaf6158d896"
}
]
}
},
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/libcore",
"id": "12ae96fc0d5bf8fb84520a6f3ca8feaf6158d896",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8896818367367766688"
},
{
"key": "scheduler_job_id",
"value": "art/target.arm.ndebug.64"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[11:10:49.224] Scheduled build:
{
"id": "8731388305328871937",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "target.arm.ndebug.64"
},
"number": 19,
"createdBy": "project:art",
"createTime": "2024-11-13T11:10:48.845426510Z",
"updateTime": "2024-11-13T11:10:48.845426510Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/libcore",
"id": "12ae96fc0d5bf8fb84520a6f3ca8feaf6158d896",
"ref": "refs/heads/master"
}
}
}
[11:10:49.224] Task URL: https://cr-buildbucket.appspot.com/build/8731388305328871937
[11:10:49.224] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:2:0) after 8m8s
[11:10:50.191] Received PubSub notification, asking Buildbucket for the build status
[11:10:50.209] Build status: SCHEDULED
[11:11:06.053] Received PubSub notification, asking Buildbucket for the build status
[11:11:06.119] Build status: STARTED
[11:18:57.276] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:2:0)
[11:18:57.294] Build status: STARTED
[11:18:57.294] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:5:0) after 1m47s
[11:20:44.311] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:5:0)
[11:20:44.331] Build status: STARTED
[11:20:44.331] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:6:0) after 1m24s
[11:22:08.347] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:6:0)
[11:22:08.367] Build status: STARTED
[11:22:08.367] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:7:0) after 9m47s
[11:31:55.369] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:7:0)
[11:31:55.390] Build status: STARTED
[11:31:55.390] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:8:0) after 8m41s
[11:40:36.527] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:8:0)
[11:40:36.596] Build status: STARTED
[11:40:36.596] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:9:0) after 7m51s
[11:48:28.025] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:9:0)
[11:48:28.109] Build status: STARTED
[11:48:28.109] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:10:0) after 7m56s
[11:56:24.154] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:10:0)
[11:56:24.170] Build status: STARTED
[11:56:24.170] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:11:0) after 6m2s
[12:02:26.229] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:11:0)
[12:02:26.250] Build status: STARTED
[12:02:26.250] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:12:0) after 4m36s
[12:07:02.315] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:12:0)
[12:07:02.352] Build status: STARTED
[12:07:02.352] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:13:0) after 4m31s
[12:11:33.365] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:13:0)
[12:11:33.383] Build status: STARTED
[12:11:33.383] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:14:0) after 3m32s
[12:15:05.370] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:14:0)
[12:15:05.389] Build status: STARTED
[12:15:05.389] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:15:0) after 5m9s
[12:20:14.406] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:15:0)
[12:20:14.431] Build status: STARTED
[12:20:14.431] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:16:0) after 5m41s
[12:25:55.444] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:16:0)
[12:25:55.461] Build status: STARTED
[12:25:55.461] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:17:0) after 2m3s
[12:27:58.526] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:17:0)
[12:27:58.553] Build status: STARTED
[12:27:58.553] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:18:0) after 3m16s
[12:31:14.651] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:18:0)
[12:31:14.671] Build status: STARTED
[12:31:14.671] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:19:0) after 4m42s
[12:35:56.691] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:19:0)
[12:35:56.711] Build status: STARTED
[12:35:56.711] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:20:0) after 2m12s
[12:38:08.688] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:20:0)
[12:38:08.705] Build status: STARTED
[12:38:08.705] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:21:0) after 5m36s
[12:43:44.755] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:21:0)
[12:43:44.776] Build status: STARTED
[12:43:44.776] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:22:0) after 6m24s
[12:50:08.832] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:22:0)
[12:50:08.885] Build status: STARTED
[12:50:08.885] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:23:0) after 2m33s
[12:52:41.950] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:23:0)
[12:52:42.008] Build status: STARTED
[12:52:42.008] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:24:0) after 8m14s
[13:00:56.206] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:24:0)
[13:00:56.294] Build status: STARTED
[13:00:56.294] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:25:0) after 7m7s
[13:08:03.359] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:25:0)
[13:08:03.377] Build status: STARTED
[13:08:03.377] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:26:0) after 4m57s
[13:13:00.395] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:26:0)
[13:13:00.440] Build status: STARTED
[13:13:00.440] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:27:0) after 8m42s
[13:21:42.455] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:27:0)
[13:21:42.476] Build status: STARTED
[13:21:42.476] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:28:0) after 1m46s
[13:23:28.593] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:28:0)
[13:23:28.622] Build status: STARTED
[13:23:28.622] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:29:0) after 8m48s
[13:32:17.668] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:29:0)
[13:32:17.686] Build status: STARTED
[13:32:17.686] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:30:0) after 5m5s
[13:37:22.791] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:30:0)
[13:37:22.853] Build status: STARTED
[13:37:22.853] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:31:0) after 6m57s
[13:44:19.878] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:31:0)
[13:44:19.920] Build status: STARTED
[13:44:19.920] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:32:0) after 5m9s
[13:49:29.025] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:32:0)
[13:49:29.145] Build status: STARTED
[13:49:29.145] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:33:0) after 3m30s
[13:52:59.176] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:33:0)
[13:52:59.194] Build status: STARTED
[13:52:59.194] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:34:0) after 1m19s
[13:54:18.221] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:34:0)
[13:54:18.242] Build status: STARTED
[13:54:18.242] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:35:0) after 2m38s
[13:56:56.317] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:35:0)
[13:56:56.338] Build status: STARTED
[13:56:56.338] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:36:0) after 2m37s
[13:59:33.382] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:36:0)
[13:59:33.426] Build status: STARTED
[13:59:33.426] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:37:0) after 9m20s
[14:08:53.455] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:37:0)
[14:08:53.508] Build status: STARTED
[14:08:53.508] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:38:0) after 4m3s
[14:12:56.781] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:38:0)
[14:12:56.837] Build status: STARTED
[14:12:56.837] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:39:0) after 4m57s
[14:17:53.917] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:39:0)
[14:17:53.978] Build status: STARTED
[14:17:53.978] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:40:0) after 1m42s
[14:19:36.364] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:40:0)
[14:19:36.382] Build status: STARTED
[14:19:36.382] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:41:0) after 4m19s
[14:23:55.801] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:41:0)
[14:23:55.830] Build status: STARTED
[14:23:55.830] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:42:0) after 4m8s
[14:28:03.927] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:42:0)
[14:28:03.970] Build status: STARTED
[14:28:03.970] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:43:0) after 1m42s
[14:29:46.605] Handling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:43:0)
[14:29:46.624] Build status: STARTED
[14:29:46.625] Scheduling timer "check-buildbucket-build-status" (art/target.arm.ndebug.64:8896818367367766688:44:0) after 7m59s
[14:34:42.057] Received PubSub notification, asking Buildbucket for the build status
[14:34:42.078] Build:
{
"id": "8731388305328871937",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "target.arm.ndebug.64"
},
"number": 19,
"createdBy": "project:art",
"createTime": "2024-11-13T11:10:48.845426510Z",
"startTime": "2024-11-13T11:11:05.575600221Z",
"endTime": "2024-11-13T14:34:24.034665483Z",
"updateTime": "2024-11-13T14:34:24.034665483Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/libcore",
"id": "12ae96fc0d5bf8fb84520a6f3ca8feaf6158d896",
"ref": "refs/heads/master"
}
}
}
[14:34:42.078] Invocation finished in 3h23m54.560554989s with status SUCCEEDED