[05:40:08.659] New invocation is queued and will start shortly
[05:40:10.524] Starting the invocation (attempt 1)
[05:40:10.557] Popped buildset tag "buildset:commit/gitiles/android.googlesource.com/platform/art/+/926724b88711b86f875756ccdf819269b8fd5773"
[05:40:10.557] Popped gitiles commit info from properties and tags
[05:40:10.557] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:40:10.558] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:40:10.558] Buildbucket request:
{
"requestId": "8960981822138099472",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "walleye-armv8-poison-ndebug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8960981822138099472",
"job": "art/walleye-armv8-poison-ndebug",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/art",
"revision": "926724b88711b86f875756ccdf819269b8fd5773"
},
"id": "https://android.googlesource.com/platform/art/+/refs/heads/master@926724b88711b86f875756ccdf819269b8fd5773",
"title": "926724b88711b86f875756ccdf819269b8fd5773",
"url": "https://android.googlesource.com/platform/art/+/926724b88711b86f875756ccdf819269b8fd5773"
}
]
}
},
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "926724b88711b86f875756ccdf819269b8fd5773",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8960981822138099472"
},
{
"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"
}
}
[05:40:10.933] Scheduled build:
{
"id": "8795551759405021137",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "walleye-armv8-poison-ndebug"
},
"number": 2961,
"createdBy": "project:art",
"createTime": "2022-12-06T05:40:10.641013298Z",
"updateTime": "2022-12-06T05:40:10.641013298Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "926724b88711b86f875756ccdf819269b8fd5773",
"ref": "refs/heads/master"
}
}
}
[05:40:10.933] Task URL: https://cr-buildbucket.appspot.com/build/8795551759405021137
[05:40:10.933] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:2:0) after 6m0s
[05:40:24.744] Received PubSub notification, asking Buildbucket for the build status
[05:40:24.769] Build status: STARTED
[05:46:10.956] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:2:0)
[05:46:10.998] Build status: STARTED
[05:46:10.998] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:4:0) after 8m1s
[05:54:12.182] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:4:0)
[05:54:12.230] Build status: STARTED
[05:54:12.230] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:5:0) after 6m21s
[06:00:33.478] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:5:0)
[06:00:33.506] Build status: STARTED
[06:00:33.506] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:6:0) after 5m41s
[06:06:14.522] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:6:0)
[06:06:14.549] Build status: STARTED
[06:06:14.549] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:7:0) after 8m50s
[06:15:04.556] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:7:0)
[06:15:04.604] Build status: STARTED
[06:15:04.604] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:8:0) after 6m0s
[06:21:04.663] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:8:0)
[06:21:04.688] Build status: STARTED
[06:21:04.688] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:9:0) after 5m15s
[06:26:19.698] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:9:0)
[06:26:19.723] Build status: STARTED
[06:26:19.723] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:10:0) after 4m3s
[06:30:22.770] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:10:0)
[06:30:22.815] Build status: STARTED
[06:30:22.815] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:11:0) after 4m54s
[06:35:16.931] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:11:0)
[06:35:16.963] Build status: STARTED
[06:35:16.963] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:12:0) after 9m10s
[06:44:26.988] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:12:0)
[06:44:27.031] Build status: STARTED
[06:44:27.031] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:13:0) after 2m41s
[06:47:08.361] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:13:0)
[06:47:08.386] Build status: STARTED
[06:47:08.386] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:14:0) after 5m18s
[06:52:26.532] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:14:0)
[06:52:26.553] Build status: STARTED
[06:52:26.553] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:15:0) after 2m42s
[06:55:08.695] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:15:0)
[06:55:08.824] Build status: STARTED
[06:55:08.824] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:16:0) after 6m8s
[07:01:17.012] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:16:0)
[07:01:17.039] Build status: STARTED
[07:01:17.039] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:17:0) after 2m4s
[07:03:21.068] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:17:0)
[07:03:21.091] Build status: STARTED
[07:03:21.091] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:18:0) after 5m41s
[07:09:02.321] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:18:0)
[07:09:02.345] Build status: STARTED
[07:09:02.345] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:19:0) after 3m23s
[07:12:25.378] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:19:0)
[07:12:25.416] Build status: STARTED
[07:12:25.416] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:20:0) after 4m8s
[07:16:33.482] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:20:0)
[07:16:33.507] Build status: STARTED
[07:16:33.507] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:21:0) after 7m53s
[07:24:26.522] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:21:0)
[07:24:26.551] Build status: STARTED
[07:24:26.551] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:22:0) after 9m42s
[07:34:08.570] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:22:0)
[07:34:08.599] Build status: STARTED
[07:34:08.599] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:23:0) after 7m50s
[07:41:58.621] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:23:0)
[07:41:58.656] Build status: STARTED
[07:41:58.656] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:24:0) after 2m58s
[07:44:56.751] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:24:0)
[07:44:56.771] Build status: STARTED
[07:44:56.771] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:25:0) after 6m55s
[07:51:52.089] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:25:0)
[07:51:52.135] Build status: STARTED
[07:51:52.135] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:26:0) after 2m25s
[07:54:17.358] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:26:0)
[07:54:17.384] Build status: STARTED
[07:54:17.384] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:27:0) after 6m54s
[08:01:11.372] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:27:0)
[08:01:11.427] Build status: STARTED
[08:01:11.427] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:28:0) after 2m41s
[08:03:52.571] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:28:0)
[08:03:52.597] Build status: STARTED
[08:03:52.597] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:29:0) after 7m56s
[08:11:48.735] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:29:0)
[08:11:48.790] Build status: STARTED
[08:11:48.790] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:30:0) after 5m42s
[08:17:30.860] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:30:0)
[08:17:30.883] Build status: STARTED
[08:17:30.883] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:31:0) after 8m11s
[08:25:41.906] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:31:0)
[08:25:41.950] Build status: STARTED
[08:25:41.950] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:32:0) after 2m51s
[08:28:33.148] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:32:0)
[08:28:33.178] Build status: STARTED
[08:28:33.178] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:33:0) after 2m2s
[08:30:35.202] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:33:0)
[08:30:35.251] Build status: STARTED
[08:30:35.251] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:34:0) after 3m21s
[08:33:56.380] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:34:0)
[08:33:56.555] Build status: STARTED
[08:33:56.555] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8960981822138099472:35:0) after 3m8s
[08:34:11.449] Received PubSub notification, asking Buildbucket for the build status
[08:34:11.477] Build:
{
"id": "8795551759405021137",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "walleye-armv8-poison-ndebug"
},
"number": 2961,
"createdBy": "project:art",
"createTime": "2022-12-06T05:40:10.641013298Z",
"startTime": "2022-12-06T05:40:24.013202Z",
"endTime": "2022-12-06T08:34:11.194042195Z",
"updateTime": "2022-12-06T08:34:11.194042195Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "926724b88711b86f875756ccdf819269b8fd5773",
"ref": "refs/heads/master"
}
}
}
[08:34:11.477] Invocation finished in 2h54m2.826199016s with status SUCCEEDED