[10:11:37.298] New invocation is queued and will start shortly
[10:11:38.967] Starting the invocation (attempt 1)
[10:11:38.992] Popped buildset tag "buildset:commit/gitiles/android.googlesource.com/platform/art/+/840bf2f160ca8757ecee0f9c02005fe56634622e"
[10:11:38.992] Popped gitiles commit info from properties and tags
[10:11:38.992] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:11:38.992] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:11:38.992] Buildbucket request:
{
"requestId": "8917659392325557488",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "walleye-armv8-poison-ndebug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8917659392325557488",
"job": "art/walleye-armv8-poison-ndebug",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/art",
"revision": "840bf2f160ca8757ecee0f9c02005fe56634622e"
},
"id": "https://android.googlesource.com/platform/art/+/refs/heads/master@840bf2f160ca8757ecee0f9c02005fe56634622e",
"title": "840bf2f160ca8757ecee0f9c02005fe56634622e",
"url": "https://android.googlesource.com/platform/art/+/840bf2f160ca8757ecee0f9c02005fe56634622e"
}
]
}
},
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "840bf2f160ca8757ecee0f9c02005fe56634622e",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8917659392325557488"
},
{
"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"
}
}
[10:11:39.542] Scheduled build:
{
"id": "8752229329820366897",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "walleye-armv8-poison-ndebug"
},
"number": 4441,
"createdBy": "project:art",
"createTime": "2024-03-28T10:11:39.060003801Z",
"updateTime": "2024-03-28T10:11:39.060003801Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "840bf2f160ca8757ecee0f9c02005fe56634622e",
"ref": "refs/heads/master"
}
},
"canary": true
}
[10:11:39.543] Task URL: https://cr-buildbucket.appspot.com/build/8752229329820366897
[10:11:39.543] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:2:0) after 8m45s
[10:11:40.550] Received PubSub notification, asking Buildbucket for the build status
[10:11:40.569] Build status: SCHEDULED
[10:11:49.887] Received PubSub notification, asking Buildbucket for the build status
[10:11:49.926] Build status: STARTED
[10:20:25.035] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:2:0)
[10:20:25.055] Build status: STARTED
[10:20:25.055] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:5:0) after 3m47s
[10:24:12.153] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:5:0)
[10:24:12.198] Build status: STARTED
[10:24:12.198] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:6:0) after 3m28s
[10:27:40.396] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:6:0)
[10:27:40.425] Build status: STARTED
[10:27:40.425] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:7:0) after 8m28s
[10:36:08.483] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:7:0)
[10:36:08.531] Build status: STARTED
[10:36:08.531] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:8:0) after 3m58s
[10:40:06.581] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:8:0)
[10:40:06.607] Build status: STARTED
[10:40:06.607] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:9:0) after 8m2s
[10:48:08.641] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:9:0)
[10:48:08.723] Build status: STARTED
[10:48:08.723] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:10:0) after 2m41s
[10:50:49.738] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:10:0)
[10:50:49.857] Build status: STARTED
[10:50:49.857] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:11:0) after 8m36s
[10:59:25.879] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:11:0)
[10:59:25.898] Build status: STARTED
[10:59:25.898] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:12:0) after 6m38s
[11:06:03.925] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:12:0)
[11:06:03.944] Build status: STARTED
[11:06:03.944] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:13:0) after 4m59s
[11:11:03.174] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:13:0)
[11:11:03.365] Build status: STARTED
[11:11:03.365] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:14:0) after 1m27s
[11:12:30.730] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:14:0)
[11:12:30.754] Build status: STARTED
[11:12:30.754] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:15:0) after 6m49s
[11:19:19.875] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:15:0)
[11:19:19.956] Build status: STARTED
[11:19:19.956] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:16:0) after 4m46s
[11:24:06.071] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:16:0)
[11:24:06.520] Build status: STARTED
[11:24:06.520] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:17:0) after 7m24s
[11:31:30.620] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:17:0)
[11:31:30.646] Build status: STARTED
[11:31:30.646] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:18:0) after 5m54s
[11:37:25.087] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:18:0)
[11:37:25.121] Build status: STARTED
[11:37:25.121] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:19:0) after 4m21s
[11:41:46.138] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:19:0)
[11:41:46.193] Build status: STARTED
[11:41:46.193] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:20:0) after 2m39s
[11:44:25.237] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:20:0)
[11:44:25.300] Build status: STARTED
[11:44:25.300] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:21:0) after 2m38s
[11:47:03.563] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:21:0)
[11:47:03.587] Build status: STARTED
[11:47:03.587] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:22:0) after 8m52s
[11:55:55.645] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:22:0)
[11:55:55.673] Build status: STARTED
[11:55:55.673] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:23:0) after 3m12s
[11:59:07.665] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:23:0)
[11:59:07.690] Build status: STARTED
[11:59:07.690] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:24:0) after 9m15s
[12:08:22.901] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:24:0)
[12:08:22.970] Build status: STARTED
[12:08:22.972] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:25:0) after 7m17s
[12:15:40.047] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:25:0)
[12:15:40.098] Build status: STARTED
[12:15:40.099] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:26:0) after 9m50s
[12:25:30.122] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:26:0)
[12:25:30.152] Build status: STARTED
[12:25:30.152] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:27:0) after 5m8s
[12:30:38.173] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:27:0)
[12:30:38.214] Build status: STARTED
[12:30:38.214] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:28:0) after 3m14s
[12:33:52.230] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:28:0)
[12:33:52.256] Build status: STARTED
[12:33:52.256] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:29:0) after 7m45s
[12:41:37.559] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:29:0)
[12:41:37.597] Build status: STARTED
[12:41:37.597] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:30:0) after 2m1s
[12:43:38.616] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:30:0)
[12:43:38.693] Build status: STARTED
[12:43:38.693] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:31:0) after 5m37s
[12:49:15.909] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:31:0)
[12:49:15.939] Build status: STARTED
[12:49:15.939] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:32:0) after 6m55s
[12:56:11.060] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:32:0)
[12:56:11.116] Build status: STARTED
[12:56:11.117] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:33:0) after 6m12s
[13:02:23.321] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:33:0)
[13:02:23.355] Build status: STARTED
[13:02:23.355] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:34:0) after 2m39s
[13:05:02.385] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:34:0)
[13:05:02.518] Build status: STARTED
[13:05:02.518] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:35:0) after 2m20s
[13:07:22.536] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:35:0)
[13:07:22.879] Build status: STARTED
[13:07:22.879] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:36:0) after 8m47s
[13:16:10.070] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:36:0)
[13:16:10.260] Build status: STARTED
[13:16:10.260] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:37:0) after 2m45s
[13:18:55.280] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:37:0)
[13:18:55.338] Build status: STARTED
[13:18:55.338] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:38:0) after 6m16s
[13:25:11.911] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:38:0)
[13:25:12.119] Build status: STARTED
[13:25:12.119] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:39:0) after 4m3s
[13:29:15.197] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:39:0)
[13:29:15.217] Build status: STARTED
[13:29:15.217] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:40:0) after 2m15s
[13:31:30.367] Handling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:40:0)
[13:31:30.456] Build status: STARTED
[13:31:30.456] Scheduling timer "check-buildbucket-build-status" (art/walleye-armv8-poison-ndebug:8917659392325557488:41:0) after 5m42s
[13:32:55.015] Received PubSub notification, asking Buildbucket for the build status
[13:32:55.070] Build:
{
"id": "8752229329820366897",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "walleye-armv8-poison-ndebug"
},
"number": 4441,
"createdBy": "project:art",
"createTime": "2024-03-28T10:11:39.060003801Z",
"startTime": "2024-03-28T10:11:49.689925825Z",
"endTime": "2024-03-28T13:32:54.594415436Z",
"updateTime": "2024-03-28T13:32:54.594415436Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "840bf2f160ca8757ecee0f9c02005fe56634622e",
"ref": "refs/heads/master"
}
},
"canary": true
}
[13:32:55.070] Invocation finished in 3h21m17.781526203s with status SUCCEEDED