[13:18:58.905] New invocation is queued and will start shortly
[13:18:59.997] Starting the invocation (attempt 1)
[13:19:00.027] Popped buildset tag "buildset:commit/gitiles/android.googlesource.com/platform/art/+/9c9312ad159ba621f0348aaab5d5828c14a964cd"
[13:19:00.027] Popped gitiles commit info from properties and tags
[13:19:00.027] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:19:00.027] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:19:00.027] Buildbucket request:
{
"requestId": "8931871328367030192",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "walleye-armv8-poison-ndebug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8931871328367030192",
"job": "art/walleye-armv8-poison-ndebug",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/art",
"revision": "ae41d97ff89e09fd3a8e220b896953db7ef928cf"
},
"id": "https://android.googlesource.com/platform/art/+/refs/heads/master@ae41d97ff89e09fd3a8e220b896953db7ef928cf",
"title": "ae41d97ff89e09fd3a8e220b896953db7ef928cf",
"url": "https://android.googlesource.com/platform/art/+/ae41d97ff89e09fd3a8e220b896953db7ef928cf"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/libcore",
"revision": "2b93b273fbfb5dbc0831253044513d3419846336"
},
"id": "https://android.googlesource.com/platform/libcore/+/refs/heads/master@2b93b273fbfb5dbc0831253044513d3419846336",
"title": "2b93b273fbfb5dbc0831253044513d3419846336",
"url": "https://android.googlesource.com/platform/libcore/+/2b93b273fbfb5dbc0831253044513d3419846336"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/art",
"revision": "26c8ca42a0437bb84d340dddf751250775be41e7"
},
"id": "https://android.googlesource.com/platform/art/+/refs/heads/master@26c8ca42a0437bb84d340dddf751250775be41e7",
"title": "26c8ca42a0437bb84d340dddf751250775be41e7",
"url": "https://android.googlesource.com/platform/art/+/26c8ca42a0437bb84d340dddf751250775be41e7"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/art",
"revision": "80f9e9832c09dd64fd2157309af9bd53c336ca65"
},
"id": "https://android.googlesource.com/platform/art/+/refs/heads/master@80f9e9832c09dd64fd2157309af9bd53c336ca65",
"title": "80f9e9832c09dd64fd2157309af9bd53c336ca65",
"url": "https://android.googlesource.com/platform/art/+/80f9e9832c09dd64fd2157309af9bd53c336ca65"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/libcore",
"revision": "b142264f417450ce2b357d7f6064988a53ab6141"
},
"id": "https://android.googlesource.com/platform/libcore/+/refs/heads/master@b142264f417450ce2b357d7f6064988a53ab6141",
"title": "b142264f417450ce2b357d7f6064988a53ab6141",
"url": "https://android.googlesource.com/platform/libcore/+/b142264f417450ce2b357d7f6064988a53ab6141"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/art",
"revision": "9c9312ad159ba621f0348aaab5d5828c14a964cd"
},
"id": "https://android.googlesource.com/platform/art/+/refs/heads/master@9c9312ad159ba621f0348aaab5d5828c14a964cd",
"title": "9c9312ad159ba621f0348aaab5d5828c14a964cd",
"url": "https://android.googlesource.com/platform/art/+/9c9312ad159ba621f0348aaab5d5828c14a964cd"
}
]
}
},
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "9c9312ad159ba621f0348aaab5d5828c14a964cd",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8931871328367030192"
},
{
"key": "scheduler_job_id",
"value": "art/walleye-armv8-poison-ndebug"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[13:19:00.835] Scheduled build:
{
"id": "8766441266385655953",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "walleye-armv8-poison-ndebug"
},
"number": 3906,
"createdBy": "project:art",
"createTime": "2023-10-23T13:19:00.172488681Z",
"updateTime": "2023-10-23T13:19:00.172488681Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "9c9312ad159ba621f0348aaab5d5828c14a964cd",
"ref": "refs/heads/master"
}
}
}
[13:19:00.835] Task URL: https://cr-buildbucket.appspot.com/build/8766441266385655953
[13:19:00.835] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:2:0) after 9m27s
[13:19:01.738] Received PubSub notification, asking Buildbucket for the build status
[13:19:01.775] Build status: SCHEDULED
[13:19:03.030] Received PubSub notification, asking Buildbucket for the build status
[13:19:03.070] Build status: STARTED
[13:28:27.941] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:2:0)
[13:28:28.095] Build status: STARTED
[13:28:28.095] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:5:0) after 7m15s
[13:35:43.113] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:5:0)
[13:35:43.133] Build status: STARTED
[13:35:43.133] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:6:0) after 5m22s
[13:41:05.217] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:6:0)
[13:41:05.291] Build status: STARTED
[13:41:05.291] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:7:0) after 6m26s
[13:47:31.418] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:7:0)
[13:47:31.440] Build status: STARTED
[13:47:31.440] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:8:0) after 3m40s
[13:51:11.573] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:8:0)
[13:51:11.598] Build status: STARTED
[13:51:11.598] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:9:0) after 9m4s
[14:00:15.789] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:9:0)
[14:00:15.811] Build status: STARTED
[14:00:15.812] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:10:0) after 8m42s
[14:08:58.037] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:10:0)
[14:08:58.082] Build status: STARTED
[14:08:58.082] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:11:0) after 4m16s
[14:13:14.149] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:11:0)
[14:13:14.182] Build status: STARTED
[14:13:14.182] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:12:0) after 4m44s
[14:17:58.205] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:12:0)
[14:17:58.222] Build status: STARTED
[14:17:58.222] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:13:0) after 9m1s
[14:26:59.783] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:13:0)
[14:26:59.811] Build status: STARTED
[14:26:59.811] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:14:0) after 5m40s
[14:32:39.934] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:14:0)
[14:32:39.998] Build status: STARTED
[14:32:39.998] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:15:0) after 5m19s
[14:37:59.099] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:15:0)
[14:37:59.128] Build status: STARTED
[14:37:59.128] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:16:0) after 1m41s
[14:39:40.141] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:16:0)
[14:39:40.163] Build status: STARTED
[14:39:40.163] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:17:0) after 8m22s
[14:48:02.191] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:17:0)
[14:48:02.278] Build status: STARTED
[14:48:02.278] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:18:0) after 4m8s
[14:52:10.415] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:18:0)
[14:52:10.497] Build status: STARTED
[14:52:10.497] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:19:0) after 9m27s
[15:01:37.517] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:19:0)
[15:01:37.629] Build status: STARTED
[15:01:37.629] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:20:0) after 7m56s
[15:09:33.892] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:20:0)
[15:09:33.909] Build status: STARTED
[15:09:33.909] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:21:0) after 3m18s
[15:12:52.449] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:21:0)
[15:12:52.476] Build status: STARTED
[15:12:52.476] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:22:0) after 5m53s
[15:18:45.498] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:22:0)
[15:18:45.603] Build status: STARTED
[15:18:45.603] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:23:0) after 9m46s
[15:28:31.846] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:23:0)
[15:28:31.865] Build status: STARTED
[15:28:31.865] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:24:0) after 9m34s
[15:38:05.861] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:24:0)
[15:38:05.882] Build status: STARTED
[15:38:05.882] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:25:0) after 8m7s
[15:46:13.024] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:25:0)
[15:46:13.047] Build status: STARTED
[15:46:13.047] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:26:0) after 8m21s
[15:54:34.812] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:26:0)
[15:54:34.837] Build status: STARTED
[15:54:34.837] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:27:0) after 1m38s
[15:56:13.367] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:27:0)
[15:56:13.393] Build status: STARTED
[15:56:13.393] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:28:0) after 1m1s
[15:57:14.954] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:28:0)
[15:57:15.013] Build status: STARTED
[15:57:15.013] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:29:0) after 7m22s
[16:04:37.040] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:29:0)
[16:04:37.115] Build status: STARTED
[16:04:37.115] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:30:0) after 7m46s
[16:12:23.146] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:30:0)
[16:12:23.176] Build status: STARTED
[16:12:23.176] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:31:0) after 8m31s
[16:20:54.234] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:31:0)
[16:20:54.258] Build status: STARTED
[16:20:54.258] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:32:0) after 3m29s
[16:24:24.124] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:32:0)
[16:24:24.213] Build status: STARTED
[16:24:24.213] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:33:0) after 2m1s
[16:26:25.225] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:33:0)
[16:26:25.256] Build status: STARTED
[16:26:25.256] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8931871328367030192:34:0) after 3m57s
[16:28:23.807] Received PubSub notification, asking Buildbucket for the build status
[16:28:23.835] Build:
{
"id": "8766441266385655953",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "walleye-armv8-poison-ndebug"
},
"number": 3906,
"createdBy": "project:art",
"createTime": "2023-10-23T13:19:00.172488681Z",
"startTime": "2023-10-23T13:19:01.946154Z",
"endTime": "2023-10-23T16:28:23.122544616Z",
"updateTime": "2023-10-23T16:28:23.122544616Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "9c9312ad159ba621f0348aaab5d5828c14a964cd",
"ref": "refs/heads/master"
}
}
}
[16:28:23.835] Invocation finished in 3h9m24.936659651s with status SUCCEEDED