[16:15:23.205] New invocation is queued and will start shortly
[16:15:24.287] Starting the invocation (attempt 1)
[16:15:24.316] Popped buildset tag "buildset:commit/gitiles/android.googlesource.com/platform/art/+/087224aca478e483197a4d28391605cb728b9af4"
[16:15:24.316] Popped gitiles commit info from properties and tags
[16:15:24.316] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:15:24.316] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:15:24.316] Buildbucket request:
{
"requestId": "8939742166007918384",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "walleye-armv8-poison-ndebug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8939742166007918384",
"job": "art/walleye-armv8-poison-ndebug",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/art",
"revision": "2c0caed9e0b39bd934e469da84e86a63b1148412"
},
"id": "https://android.googlesource.com/platform/art/+/refs/heads/master@2c0caed9e0b39bd934e469da84e86a63b1148412",
"title": "2c0caed9e0b39bd934e469da84e86a63b1148412",
"url": "https://android.googlesource.com/platform/art/+/2c0caed9e0b39bd934e469da84e86a63b1148412"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/art",
"revision": "4d04069b20099f6d31a9d1dc7928112ab8950568"
},
"id": "https://android.googlesource.com/platform/art/+/refs/heads/master@4d04069b20099f6d31a9d1dc7928112ab8950568",
"title": "4d04069b20099f6d31a9d1dc7928112ab8950568",
"url": "https://android.googlesource.com/platform/art/+/4d04069b20099f6d31a9d1dc7928112ab8950568"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/art",
"revision": "d348fec56a79e765fe5a5449177e099c028f6060"
},
"id": "https://android.googlesource.com/platform/art/+/refs/heads/master@d348fec56a79e765fe5a5449177e099c028f6060",
"title": "d348fec56a79e765fe5a5449177e099c028f6060",
"url": "https://android.googlesource.com/platform/art/+/d348fec56a79e765fe5a5449177e099c028f6060"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/art",
"revision": "f3ba9564534ceb186eb85a4858a16f318dc2e91c"
},
"id": "https://android.googlesource.com/platform/art/+/refs/heads/master@f3ba9564534ceb186eb85a4858a16f318dc2e91c",
"title": "f3ba9564534ceb186eb85a4858a16f318dc2e91c",
"url": "https://android.googlesource.com/platform/art/+/f3ba9564534ceb186eb85a4858a16f318dc2e91c"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/art",
"revision": "f845767461e13a7d5b41ff3b10636f44eb1c1f87"
},
"id": "https://android.googlesource.com/platform/art/+/refs/heads/master@f845767461e13a7d5b41ff3b10636f44eb1c1f87",
"title": "f845767461e13a7d5b41ff3b10636f44eb1c1f87",
"url": "https://android.googlesource.com/platform/art/+/f845767461e13a7d5b41ff3b10636f44eb1c1f87"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/art",
"revision": "da43e436c6586253144febfb2700c67214c46a62"
},
"id": "https://android.googlesource.com/platform/art/+/refs/heads/master@da43e436c6586253144febfb2700c67214c46a62",
"title": "da43e436c6586253144febfb2700c67214c46a62",
"url": "https://android.googlesource.com/platform/art/+/da43e436c6586253144febfb2700c67214c46a62"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/art",
"revision": "087224aca478e483197a4d28391605cb728b9af4"
},
"id": "https://android.googlesource.com/platform/art/+/refs/heads/master@087224aca478e483197a4d28391605cb728b9af4",
"title": "087224aca478e483197a4d28391605cb728b9af4",
"url": "https://android.googlesource.com/platform/art/+/087224aca478e483197a4d28391605cb728b9af4"
}
]
}
},
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "087224aca478e483197a4d28391605cb728b9af4",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8939742166007918384"
},
{
"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"
}
}
[16:15:25.148] Scheduled build:
{
"id": "8774312104069627361",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "walleye-armv8-poison-ndebug"
},
"number": 3643,
"createdBy": "project:art",
"createTime": "2023-07-28T16:15:24.424668298Z",
"updateTime": "2023-07-28T16:15:24.424668298Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "087224aca478e483197a4d28391605cb728b9af4",
"ref": "refs/heads/master"
}
}
}
[16:15:25.148] Task URL: https://cr-buildbucket.appspot.com/build/8774312104069627361
[16:15:25.148] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:2:0) after 2m53s
[16:15:26.049] Received PubSub notification, asking Buildbucket for the build status
[16:15:26.086] Build status: SCHEDULED
[16:16:01.437] Received PubSub notification, asking Buildbucket for the build status
[16:16:01.470] Build status: STARTED
[16:18:18.229] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:2:0)
[16:18:18.254] Build status: STARTED
[16:18:18.254] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:5:0) after 4m18s
[16:22:36.375] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:5:0)
[16:22:36.393] Build status: STARTED
[16:22:36.393] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:6:0) after 1m24s
[16:24:00.645] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:6:0)
[16:24:00.668] Build status: STARTED
[16:24:00.668] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:7:0) after 5m34s
[16:29:35.131] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:7:0)
[16:29:35.177] Build status: STARTED
[16:29:35.177] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:8:0) after 1m43s
[16:31:18.392] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:8:0)
[16:31:18.422] Build status: STARTED
[16:31:18.422] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:9:0) after 3m5s
[16:34:23.657] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:9:0)
[16:34:23.725] Build status: STARTED
[16:34:23.725] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:10:0) after 8m17s
[16:42:40.798] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:10:0)
[16:42:40.821] Build status: STARTED
[16:42:40.821] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:11:0) after 9m40s
[16:52:20.889] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:11:0)
[16:52:20.908] Build status: STARTED
[16:52:20.908] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:12:0) after 8m26s
[17:00:47.105] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:12:0)
[17:00:47.142] Build status: STARTED
[17:00:47.142] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:13:0) after 3m19s
[17:04:06.410] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:13:0)
[17:04:06.429] Build status: STARTED
[17:04:06.429] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:14:0) after 5m39s
[17:09:45.446] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:14:0)
[17:09:45.480] Build status: STARTED
[17:09:45.480] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:15:0) after 7m2s
[17:16:47.472] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:15:0)
[17:16:47.551] Build status: STARTED
[17:16:47.552] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:16:0) after 4m6s
[17:20:53.577] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:16:0)
[17:20:53.632] Build status: STARTED
[17:20:53.632] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:17:0) after 3m42s
[17:24:35.757] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:17:0)
[17:24:35.781] Build status: STARTED
[17:24:35.781] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:18:0) after 3m46s
[17:28:21.798] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:18:0)
[17:28:21.824] Build status: STARTED
[17:28:21.824] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:19:0) after 9m6s
[17:37:27.858] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:19:0)
[17:37:27.953] Build status: STARTED
[17:37:27.953] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:20:0) after 8m9s
[17:45:36.983] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:20:0)
[17:45:37.009] Build status: STARTED
[17:45:37.009] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:21:0) after 9m11s
[17:54:48.129] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:21:0)
[17:54:48.159] Build status: STARTED
[17:54:48.159] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:22:0) after 5m41s
[18:00:29.199] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:22:0)
[18:00:29.262] Build status: STARTED
[18:00:29.262] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:23:0) after 9m46s
[18:10:15.298] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:23:0)
[18:10:15.319] Build status: STARTED
[18:10:15.320] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:24:0) after 5m0s
[18:15:15.690] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:24:0)
[18:15:15.726] Build status: STARTED
[18:15:15.726] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:25:0) after 7m35s
[18:22:50.747] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:25:0)
[18:22:50.814] Build status: STARTED
[18:22:50.814] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:26:0) after 8m6s
[18:30:56.830] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:26:0)
[18:30:56.875] Build status: STARTED
[18:30:56.875] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:27:0) after 7m19s
[18:38:15.893] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:27:0)
[18:38:15.923] Build status: STARTED
[18:38:15.923] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:28:0) after 4m31s
[18:42:46.948] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:28:0)
[18:42:46.968] Build status: STARTED
[18:42:46.968] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:29:0) after 6m21s
[18:49:07.998] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:29:0)
[18:49:08.031] Build status: STARTED
[18:49:08.031] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:30:0) after 8m32s
[18:57:40.209] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:30:0)
[18:57:40.265] Build status: STARTED
[18:57:40.265] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:31:0) after 7m3s
[19:04:43.366] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:31:0)
[19:04:43.389] Build status: STARTED
[19:04:43.389] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:32:0) after 6m24s
[19:11:07.411] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:32:0)
[19:11:07.449] Build status: STARTED
[19:11:07.449] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:33:0) after 6m1s
[19:17:08.667] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:33:0)
[19:17:08.824] Build status: STARTED
[19:17:08.824] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:34:0) after 2m21s
[19:19:29.844] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:34:0)
[19:19:29.862] Build status: STARTED
[19:19:29.862] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:35:0) after 1m1s
[19:20:30.878] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:35:0)
[19:20:30.907] Build status: STARTED
[19:20:30.907] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:36:0) after 7m10s
[19:27:40.958] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:36:0)
[19:27:40.998] Build status: STARTED
[19:27:40.999] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:37:0) after 2m26s
[19:30:07.117] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:37:0)
[19:30:07.144] Build status: STARTED
[19:30:07.144] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:38:0) after 9m8s
[19:39:15.251] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:38:0)
[19:39:15.280] Build status: STARTED
[19:39:15.280] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8939742166007918384:39:0) after 5m57s
[19:45:11.254] Received PubSub notification, asking Buildbucket for the build status
[19:45:11.288] Build:
{
"id": "8774312104069627361",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "walleye-armv8-poison-ndebug"
},
"number": 3643,
"createdBy": "project:art",
"createTime": "2023-07-28T16:15:24.424668298Z",
"startTime": "2023-07-28T16:16:00.656161Z",
"endTime": "2023-07-28T19:45:11.047332909Z",
"updateTime": "2023-07-28T19:45:11.047332909Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "087224aca478e483197a4d28391605cb728b9af4",
"ref": "refs/heads/master"
}
}
}
[19:45:11.288] Invocation finished in 3h29m48.095544901s with status SUCCEEDED