[09:51:57.504] New invocation is queued and will start shortly
[09:51:58.614] Starting the invocation (attempt 1)
[09:51:58.648] Popped buildset tag "buildset:commit/gitiles/android.googlesource.com/platform/art/+/44f5dbb1a7ecad7dede499b12fc8efd09f40d4d5"
[09:51:58.648] Popped gitiles commit info from properties and tags
[09:51:58.648] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:51:58.649] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:51:58.649] Buildbucket request:
{
"requestId": "8896732730187616736",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "host.x86.cmc.64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8896732730187616736",
"job": "art/host.x86.cmc.64",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/art",
"revision": "44f5dbb1a7ecad7dede499b12fc8efd09f40d4d5"
},
"id": "https://android.googlesource.com/platform/art/+/refs/heads/master@44f5dbb1a7ecad7dede499b12fc8efd09f40d4d5",
"title": "44f5dbb1a7ecad7dede499b12fc8efd09f40d4d5",
"url": "https://android.googlesource.com/platform/art/+/44f5dbb1a7ecad7dede499b12fc8efd09f40d4d5"
}
]
}
},
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "44f5dbb1a7ecad7dede499b12fc8efd09f40d4d5",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8896732730187616736"
},
{
"key": "scheduler_job_id",
"value": "art/host.x86.cmc.64"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[09:51:59.254] Scheduled build:
{
"id": "8731302668277329489",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "host.x86.cmc.64"
},
"number": 23,
"createdBy": "project:art",
"createTime": "2024-11-14T09:51:58.702103068Z",
"updateTime": "2024-11-14T09:51:58.702103068Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "44f5dbb1a7ecad7dede499b12fc8efd09f40d4d5",
"ref": "refs/heads/master"
}
},
"canary": true
}
[09:51:59.254] Task URL: https://cr-buildbucket.appspot.com/build/8731302668277329489
[09:51:59.254] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:2:0) after 4m40s
[09:51:59.317] Received PubSub notification, asking Buildbucket for the build status
[09:51:59.343] Build status: SCHEDULED
[09:52:06.198] Received PubSub notification, asking Buildbucket for the build status
[09:52:06.253] Build status: STARTED
[09:56:39.512] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:2:0)
[09:56:39.587] Build status: STARTED
[09:56:39.587] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:5:0) after 1m52s
[09:58:31.598] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:5:0)
[09:58:31.674] Build status: STARTED
[09:58:31.674] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:6:0) after 5m52s
[10:04:23.871] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:6:0)
[10:04:23.896] Build status: STARTED
[10:04:23.896] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:7:0) after 5m7s
[10:09:30.984] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:7:0)
[10:09:31.110] Build status: STARTED
[10:09:31.110] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:8:0) after 5m28s
[10:14:59.265] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:8:0)
[10:14:59.300] Build status: STARTED
[10:14:59.300] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:9:0) after 7m4s
[10:22:03.415] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:9:0)
[10:22:03.433] Build status: STARTED
[10:22:03.433] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:10:0) after 9m4s
[10:31:07.452] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:10:0)
[10:31:07.508] Build status: STARTED
[10:31:07.508] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:11:0) after 2m27s
[10:33:34.492] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:11:0)
[10:33:34.522] Build status: STARTED
[10:33:34.522] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:12:0) after 7m56s
[10:41:30.539] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:12:0)
[10:41:30.553] Build status: STARTED
[10:41:30.553] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:13:0) after 1m6s
[10:42:36.671] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:13:0)
[10:42:36.697] Build status: STARTED
[10:42:36.697] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:14:0) after 9m35s
[10:52:11.709] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:14:0)
[10:52:11.727] Build status: STARTED
[10:52:11.727] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:15:0) after 2m38s
[10:54:49.878] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:15:0)
[10:54:49.895] Build status: STARTED
[10:54:49.895] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:16:0) after 7m3s
[11:01:52.928] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:16:0)
[11:01:52.954] Build status: STARTED
[11:01:52.954] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:17:0) after 2m35s
[11:04:28.267] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:17:0)
[11:04:28.312] Build status: STARTED
[11:04:28.312] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:18:0) after 8m25s
[11:12:53.347] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:18:0)
[11:12:53.369] Build status: STARTED
[11:12:53.369] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:19:0) after 1m0s
[11:13:53.360] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:19:0)
[11:13:53.376] Build status: STARTED
[11:13:53.376] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:20:0) after 4m47s
[11:18:40.391] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:20:0)
[11:18:40.409] Build status: STARTED
[11:18:40.409] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:21:0) after 2m37s
[11:21:17.488] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:21:0)
[11:21:17.514] Build status: STARTED
[11:21:17.514] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:22:0) after 1m26s
[11:22:43.531] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:22:0)
[11:22:43.556] Build status: STARTED
[11:22:43.557] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:23:0) after 8m53s
[11:31:36.739] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:23:0)
[11:31:36.817] Build status: STARTED
[11:31:36.818] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:24:0) after 7m2s
[11:38:38.942] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:24:0)
[11:38:38.959] Build status: STARTED
[11:38:38.959] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:25:0) after 7m8s
[11:45:46.974] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:25:0)
[11:45:46.999] Build status: STARTED
[11:45:46.999] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:26:0) after 9m27s
[11:55:14.138] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:26:0)
[11:55:14.161] Build status: STARTED
[11:55:14.161] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:27:0) after 6m33s
[12:01:47.187] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:27:0)
[12:01:47.207] Build status: STARTED
[12:01:47.207] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:28:0) after 3m10s
[12:04:57.228] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:28:0)
[12:04:57.246] Build status: STARTED
[12:04:57.246] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:29:0) after 3m49s
[12:08:46.261] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:29:0)
[12:08:46.294] Build status: STARTED
[12:08:46.295] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:30:0) after 4m16s
[12:13:02.310] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:30:0)
[12:13:02.330] Build status: STARTED
[12:13:02.330] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:31:0) after 9m44s
[12:22:46.346] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:31:0)
[12:22:46.364] Build status: STARTED
[12:22:46.364] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:32:0) after 3m13s
[12:25:59.439] Handling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:32:0)
[12:25:59.465] Build status: STARTED
[12:25:59.465] Scheduling timer "check-buildbucket-build-status" (art/host.x86.cmc.64:8896732730187616736:33:0) after 5m18s
[12:28:26.663] Received PubSub notification, asking Buildbucket for the build status
[12:28:26.743] Build:
{
"id": "8731302668277329489",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "host.x86.cmc.64"
},
"number": 23,
"createdBy": "project:art",
"createTime": "2024-11-14T09:51:58.702103068Z",
"startTime": "2024-11-14T09:52:05.782728700Z",
"endTime": "2024-11-14T12:28:26.393011996Z",
"updateTime": "2024-11-14T12:28:26.393011996Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "44f5dbb1a7ecad7dede499b12fc8efd09f40d4d5",
"ref": "refs/heads/master"
}
},
"canary": true
}
[12:28:26.743] Invocation finished in 2h36m29.245790996s with status SUCCEEDED