[08:22:41.390] New invocation is queued and will start shortly
[08:22:42.650] Starting the invocation (attempt 1)
[08:22:42.681] Popped buildset tag "buildset:commit/gitiles/android.googlesource.com/platform/art/+/c554a91c2a35313df1f9a75375e8761da503386a"
[08:22:42.681] Popped gitiles commit info from properties and tags
[08:22:42.681] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:22:42.681] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:22:42.682] Buildbucket request:
{
"requestId": "8952546077785568752",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "walleye-armv8-poison-ndebug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8952546077785568752",
"job": "art/walleye-armv8-poison-ndebug",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/art",
"revision": "c554a91c2a35313df1f9a75375e8761da503386a"
},
"id": "https://android.googlesource.com/platform/art/+/refs/heads/master@c554a91c2a35313df1f9a75375e8761da503386a",
"title": "c554a91c2a35313df1f9a75375e8761da503386a",
"url": "https://android.googlesource.com/platform/art/+/c554a91c2a35313df1f9a75375e8761da503386a"
}
]
}
},
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "c554a91c2a35313df1f9a75375e8761da503386a",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8952546077785568752"
},
{
"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"
}
}
[08:22:43.097] Scheduled build:
{
"id": "8787116015677200289",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "walleye-armv8-poison-ndebug"
},
"number": 3210,
"createdBy": "project:art",
"createTime": "2023-03-09T08:22:42.774425694Z",
"updateTime": "2023-03-09T08:22:42.774425694Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "c554a91c2a35313df1f9a75375e8761da503386a",
"ref": "refs/heads/master"
}
}
}
[08:22:43.097] Task URL: https://cr-buildbucket.appspot.com/build/8787116015677200289
[08:22:43.097] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:2:0) after 9m3s
[08:23:35.533] Received PubSub notification, asking Buildbucket for the build status
[08:23:35.583] Build status: STARTED
[08:31:46.123] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:2:0)
[08:31:46.149] Build status: STARTED
[08:31:46.149] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:4:0) after 1m11s
[08:32:57.217] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:4:0)
[08:32:57.239] Build status: STARTED
[08:32:57.239] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:5:0) after 8m26s
[08:41:23.396] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:5:0)
[08:41:23.423] Build status: STARTED
[08:41:23.423] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:6:0) after 6m49s
[08:48:12.645] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:6:0)
[08:48:12.666] Build status: STARTED
[08:48:12.666] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:7:0) after 1m55s
[08:50:07.667] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:7:0)
[08:50:07.689] Build status: STARTED
[08:50:07.689] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:8:0) after 1m20s
[08:51:27.706] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:8:0)
[08:51:27.870] Build status: STARTED
[08:51:27.870] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:9:0) after 1m32s
[08:52:59.860] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:9:0)
[08:53:00.024] Build status: STARTED
[08:53:00.024] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:10:0) after 5m4s
[08:58:04.041] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:10:0)
[08:58:04.069] Build status: STARTED
[08:58:04.069] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:11:0) after 9m58s
[09:08:02.084] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:11:0)
[09:08:02.105] Build status: STARTED
[09:08:02.105] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:12:0) after 8m13s
[09:16:15.144] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:12:0)
[09:16:15.171] Build status: STARTED
[09:16:15.171] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:13:0) after 9m3s
[09:25:18.616] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:13:0)
[09:25:18.640] Build status: STARTED
[09:25:18.640] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:14:0) after 9m37s
[09:34:55.676] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:14:0)
[09:34:55.705] Build status: STARTED
[09:34:55.705] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:15:0) after 6m26s
[09:41:21.813] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:15:0)
[09:41:21.843] Build status: STARTED
[09:41:21.843] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:16:0) after 4m2s
[09:45:23.961] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:16:0)
[09:45:23.982] Build status: STARTED
[09:45:23.982] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:17:0) after 6m58s
[09:52:21.999] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:17:0)
[09:52:22.026] Build status: STARTED
[09:52:22.026] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:18:0) after 7m4s
[09:59:26.021] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:18:0)
[09:59:26.054] Build status: STARTED
[09:59:26.054] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:19:0) after 5m48s
[10:05:14.275] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:19:0)
[10:05:14.297] Build status: STARTED
[10:05:14.297] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:20:0) after 7m10s
[10:12:24.344] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:20:0)
[10:12:24.381] Build status: STARTED
[10:12:24.381] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:21:0) after 6m41s
[10:19:05.987] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:21:0)
[10:19:06.009] Build status: STARTED
[10:19:06.009] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:22:0) after 8m47s
[10:27:53.039] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:22:0)
[10:27:53.072] Build status: STARTED
[10:27:53.072] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:23:0) after 2m17s
[10:30:10.094] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:23:0)
[10:30:10.172] Build status: STARTED
[10:30:10.172] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:24:0) after 8m24s
[10:38:34.244] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:24:0)
[10:38:34.265] Build status: STARTED
[10:38:34.265] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:25:0) after 9m2s
[10:47:36.903] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:25:0)
[10:47:36.928] Build status: STARTED
[10:47:36.928] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:26:0) after 7m43s
[10:55:20.020] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:26:0)
[10:55:20.044] Build status: STARTED
[10:55:20.044] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:27:0) after 4m3s
[10:59:23.085] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:27:0)
[10:59:23.108] Build status: STARTED
[10:59:23.108] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:28:0) after 9m25s
[11:08:48.136] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:28:0)
[11:08:48.156] Build status: STARTED
[11:08:48.156] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:29:0) after 1m9s
[11:09:57.673] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:29:0)
[11:09:57.711] Build status: STARTED
[11:09:57.711] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:30:0) after 3m47s
[11:13:44.739] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:30:0)
[11:13:44.769] Build status: STARTED
[11:13:44.769] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:31:0) after 6m24s
[11:20:08.789] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:31:0)
[11:20:08.859] Build status: STARTED
[11:20:08.859] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:32:0) after 5m56s
[11:26:05.250] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:32:0)
[11:26:05.272] Build status: STARTED
[11:26:05.272] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:33:0) after 8m18s
[11:34:23.372] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:33:0)
[11:34:23.402] Build status: STARTED
[11:34:23.402] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:34:0) after 3m33s
[11:37:56.618] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:34:0)
[11:37:56.652] Build status: STARTED
[11:37:56.652] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:35:0) after 1m3s
[11:38:59.825] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:35:0)
[11:38:59.876] Build status: STARTED
[11:38:59.876] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:36:0) after 9m35s
[11:48:34.871] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:36:0)
[11:48:34.895] Build status: STARTED
[11:48:34.895] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:37:0) after 3m22s
[11:51:57.026] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:37:0)
[11:51:57.047] Build status: STARTED
[11:51:57.047] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:38:0) after 8m45s
[12:00:42.074] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:38:0)
[12:00:42.097] Build status: STARTED
[12:00:42.097] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:39:0) after 4m3s
[12:04:45.236] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:39:0)
[12:04:45.259] Build status: STARTED
[12:04:45.259] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:40:0) after 9m25s
[12:14:10.609] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:40:0)
[12:14:10.641] Build status: STARTED
[12:14:10.641] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:41:0) after 9m25s
[12:23:35.708] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:41:0)
[12:23:35.734] Build status: STARTED
[12:23:35.734] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:42:0) after 3m56s
[12:27:31.853] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:42:0)
[12:27:31.888] Build status: STARTED
[12:27:31.888] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:43:0) after 9m7s
[12:36:38.917] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:43:0)
[12:36:38.948] Build status: STARTED
[12:36:38.948] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:44:0) after 3m16s
[12:39:55.211] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:44:0)
[12:39:55.542] Build status: STARTED
[12:39:55.542] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:45:0) after 2m34s
[12:42:29.569] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:45:0)
[12:42:29.618] Build status: STARTED
[12:42:29.618] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:46:0) after 3m58s
[12:46:27.757] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:46:0)
[12:46:27.780] Build status: STARTED
[12:46:27.780] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:47:0) after 3m51s
[12:50:19.146] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:47:0)
[12:50:19.174] Build status: STARTED
[12:50:19.174] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:48:0) after 4m33s
[12:54:52.202] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:48:0)
[12:54:52.229] Build status: STARTED
[12:54:52.229] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:49:0) after 5m3s
[12:59:55.330] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:49:0)
[12:59:55.356] Build status: STARTED
[12:59:55.356] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:50:0) after 6m50s
[13:06:45.398] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:50:0)
[13:06:45.518] Build status: STARTED
[13:06:45.518] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:51:0) after 1m6s
[13:07:51.538] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:51:0)
[13:07:51.561] Build status: STARTED
[13:07:51.561] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:52:0) after 4m20s
[13:12:11.625] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:52:0)
[13:12:11.658] Build status: STARTED
[13:12:11.659] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8952546077785568752:53:0) after 5m29s
[13:17:04.315] Received PubSub notification, asking Buildbucket for the build status
[13:17:04.339] Build:
{
"id": "8787116015677200289",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "walleye-armv8-poison-ndebug"
},
"number": 3210,
"createdBy": "project:art",
"createTime": "2023-03-09T08:22:42.774425694Z",
"startTime": "2023-03-09T08:23:34.925669Z",
"endTime": "2023-03-09T13:17:04.021865545Z",
"updateTime": "2023-03-09T13:17:04.021865545Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "c554a91c2a35313df1f9a75375e8761da503386a",
"ref": "refs/heads/master"
}
}
}
[13:17:04.339] Invocation finished in 4h54m22.958796375s with status SUCCEEDED