[10:54:03.516] New invocation is queued and will start shortly
[10:54:04.686] Starting the invocation (attempt 1)
[10:54:04.721] Popped buildset tag "buildset:commit/gitiles/android.googlesource.com/platform/art/+/e6fd56c4c313e07948db38a0985036addcf15a13"
[10:54:04.721] Popped gitiles commit info from properties and tags
[10:54:04.721] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:54:04.721] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:54:04.721] Buildbucket request:
{
"requestId": "8896366435332787168",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "host.x86.ndebug.32"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8896366435332787168",
"job": "art/host.x86.ndebug.32",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/art",
"revision": "c77bb270eea719d59e7a09480b6601f3278bc6f1"
},
"id": "https://android.googlesource.com/platform/art/+/refs/heads/master@c77bb270eea719d59e7a09480b6601f3278bc6f1",
"title": "c77bb270eea719d59e7a09480b6601f3278bc6f1",
"url": "https://android.googlesource.com/platform/art/+/c77bb270eea719d59e7a09480b6601f3278bc6f1"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/art",
"revision": "e6fd56c4c313e07948db38a0985036addcf15a13"
},
"id": "https://android.googlesource.com/platform/art/+/refs/heads/master@e6fd56c4c313e07948db38a0985036addcf15a13",
"title": "e6fd56c4c313e07948db38a0985036addcf15a13",
"url": "https://android.googlesource.com/platform/art/+/e6fd56c4c313e07948db38a0985036addcf15a13"
}
]
}
},
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "e6fd56c4c313e07948db38a0985036addcf15a13",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8896366435332787168"
},
{
"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"
}
}
[10:54:05.189] Scheduled build:
{
"id": "8730936373345462913",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "host.x86.ndebug.32"
},
"number": 214,
"createdBy": "project:art",
"createTime": "2024-11-18T10:54:04.771121767Z",
"updateTime": "2024-11-18T10:54:04.771121767Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "e6fd56c4c313e07948db38a0985036addcf15a13",
"ref": "refs/heads/master"
}
}
}
[10:54:05.189] Task URL: https://cr-buildbucket.appspot.com/build/8730936373345462913
[10:54:05.189] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:2:0) after 6m1s
[10:54:06.178] Received PubSub notification, asking Buildbucket for the build status
[10:54:06.196] Build status: SCHEDULED
[10:54:11.604] Received PubSub notification, asking Buildbucket for the build status
[10:54:11.621] Build status: STARTED
[11:00:06.235] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:2:0)
[11:00:06.271] Build status: STARTED
[11:00:06.271] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:5:0) after 1m17s
[11:01:23.388] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:5:0)
[11:01:23.426] Build status: STARTED
[11:01:23.426] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:6:0) after 6m14s
[11:07:37.445] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:6:0)
[11:07:37.461] Build status: STARTED
[11:07:37.461] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:7:0) after 9m21s
[11:16:58.729] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:7:0)
[11:16:58.751] Build status: STARTED
[11:16:58.751] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:8:0) after 1m47s
[11:18:45.788] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:8:0)
[11:18:45.806] Build status: STARTED
[11:18:45.806] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:9:0) after 4m34s
[11:23:19.874] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:9:0)
[11:23:19.892] Build status: STARTED
[11:23:19.892] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:10:0) after 3m29s
[11:26:49.008] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:10:0)
[11:26:49.045] Build status: STARTED
[11:26:49.045] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:11:0) after 4m12s
[11:31:01.064] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:11:0)
[11:31:01.083] Build status: STARTED
[11:31:01.083] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:12:0) after 9m1s
[11:40:02.096] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:12:0)
[11:40:02.128] Build status: STARTED
[11:40:02.128] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:13:0) after 5m50s
[11:45:52.243] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:13:0)
[11:45:52.268] Build status: STARTED
[11:45:52.268] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:14:0) after 5m27s
[11:51:19.341] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:14:0)
[11:51:19.356] Build status: STARTED
[11:51:19.356] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:15:0) after 1m3s
[11:52:22.395] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:15:0)
[11:52:22.414] Build status: STARTED
[11:52:22.414] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:16:0) after 9m16s
[12:01:38.648] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:16:0)
[12:01:38.687] Build status: STARTED
[12:01:38.687] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:17:0) after 4m11s
[12:05:49.734] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:17:0)
[12:05:49.757] Build status: STARTED
[12:05:49.757] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:18:0) after 3m2s
[12:08:51.773] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:18:0)
[12:08:51.789] Build status: STARTED
[12:08:51.789] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:19:0) after 1m24s
[12:10:15.833] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:19:0)
[12:10:15.851] Build status: STARTED
[12:10:15.851] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:20:0) after 8m28s
[12:18:43.865] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:20:0)
[12:18:43.885] Build status: STARTED
[12:18:43.885] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:21:0) after 3m10s
[12:21:54.020] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:21:0)
[12:21:54.035] Build status: STARTED
[12:21:54.035] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:22:0) after 1m0s
[12:22:54.034] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:22:0)
[12:22:54.054] Build status: STARTED
[12:22:54.054] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:23:0) after 1m9s
[12:24:03.385] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:23:0)
[12:24:03.418] Build status: STARTED
[12:24:03.418] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:24:0) after 4m4s
[12:28:07.433] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:24:0)
[12:28:07.573] Build status: STARTED
[12:28:07.573] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:25:0) after 8m39s
[12:36:46.685] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:25:0)
[12:36:46.755] Build status: STARTED
[12:36:46.755] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:26:0) after 2m57s
[12:39:43.845] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:26:0)
[12:39:43.862] Build status: STARTED
[12:39:43.862] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:27:0) after 1m13s
[12:40:56.886] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:27:0)
[12:40:56.906] Build status: STARTED
[12:40:56.906] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:28:0) after 6m55s
[12:47:52.168] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:28:0)
[12:47:52.187] Build status: STARTED
[12:47:52.187] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:29:0) after 8m33s
[12:56:25.321] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:29:0)
[12:56:25.340] Build status: STARTED
[12:56:25.340] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:30:0) after 3m3s
[12:59:28.434] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:30:0)
[12:59:28.496] Build status: STARTED
[12:59:28.496] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:31:0) after 2m18s
[13:01:46.511] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:31:0)
[13:01:46.530] Build status: STARTED
[13:01:46.531] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:32:0) after 6m31s
[13:08:17.547] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:32:0)
[13:08:17.574] Build status: STARTED
[13:08:17.574] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:33:0) after 4m41s
[13:12:58.848] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:33:0)
[13:12:58.866] Build status: STARTED
[13:12:58.866] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:34:0) after 1m10s
[13:14:08.881] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:34:0)
[13:14:08.919] Build status: STARTED
[13:14:08.919] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:35:0) after 7m55s
[13:22:03.937] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:35:0)
[13:22:03.953] Build status: STARTED
[13:22:03.953] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:36:0) after 2m6s
[13:24:09.974] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:36:0)
[13:24:09.999] Build status: STARTED
[13:24:09.999] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:37:0) after 7m19s
[13:31:29.946] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:37:0)
[13:31:29.967] Build status: STARTED
[13:31:29.967] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:38:0) after 2m33s
[13:34:03.003] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:38:0)
[13:34:03.024] Build status: STARTED
[13:34:03.025] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:39:0) after 4m32s
[13:38:35.084] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:39:0)
[13:38:35.100] Build status: STARTED
[13:38:35.100] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:40:0) after 1m6s
[13:39:41.125] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:40:0)
[13:39:41.156] Build status: STARTED
[13:39:41.156] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:41:0) after 1m52s
[13:41:33.176] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:41:0)
[13:41:33.333] Build status: STARTED
[13:41:33.333] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:42:0) after 6m34s
[13:48:07.352] Handling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:42:0)
[13:48:07.436] Build status: STARTED
[13:48:07.436] Scheduling timer "check-buildbucket-build-status" (art/host.x86.ndebug.32:8896366435332787168:43:0) after 3m43s
[13:50:59.451] Received PubSub notification, asking Buildbucket for the build status
[13:50:59.471] Build:
{
"id": "8730936373345462913",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "host.x86.ndebug.32"
},
"number": 214,
"createdBy": "project:art",
"createTime": "2024-11-18T10:54:04.771121767Z",
"startTime": "2024-11-18T10:54:11.338359933Z",
"endTime": "2024-11-18T13:50:59.268991389Z",
"updateTime": "2024-11-18T13:50:59.268991389Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "e6fd56c4c313e07948db38a0985036addcf15a13",
"ref": "refs/heads/master"
}
}
}
[13:50:59.471] Invocation finished in 2h56m55.978095245s with status SUCCEEDED