[04:24:59.617] New invocation is queued and will start shortly
[04:25:00.918] Starting the invocation (attempt 1)
[04:25:00.952] Popped buildset tag "buildset:commit/gitiles/android.googlesource.com/platform/art/+/e69d3de15ab2f0912926ff9a4f9b0b3b169ffde1"
[04:25:00.952] Popped gitiles commit info from properties and tags
[04:25:00.953] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:25:00.954] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:25:00.954] Buildbucket request:
{
"requestId": "8951745659636323552",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "angler-armv8-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8951745659636323552",
"job": "art/angler-armv8-debug",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/art",
"revision": "e69d3de15ab2f0912926ff9a4f9b0b3b169ffde1"
},
"id": "https://android.googlesource.com/platform/art/+/refs/heads/master@e69d3de15ab2f0912926ff9a4f9b0b3b169ffde1",
"title": "e69d3de15ab2f0912926ff9a4f9b0b3b169ffde1",
"url": "https://android.googlesource.com/platform/art/+/e69d3de15ab2f0912926ff9a4f9b0b3b169ffde1"
}
]
}
},
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "e69d3de15ab2f0912926ff9a4f9b0b3b169ffde1",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8951745659636323552"
},
{
"key": "scheduler_job_id",
"value": "art/angler-armv8-debug"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[04:25:01.407] Scheduled build:
{
"id": "8786315597502946737",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "angler-armv8-debug"
},
"number": 3344,
"createdBy": "project:art",
"createTime": "2023-03-18T04:25:01.029472509Z",
"updateTime": "2023-03-18T04:25:01.029472509Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "e69d3de15ab2f0912926ff9a4f9b0b3b169ffde1",
"ref": "refs/heads/master"
}
}
}
[04:25:01.407] Task URL: https://cr-buildbucket.appspot.com/build/8786315597502946737
[04:25:01.407] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:2:0) after 9m27s
[04:34:28.434] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:2:0)
[04:34:28.453] Build status: SCHEDULED
[04:34:28.453] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:3:0) after 6m39s
[04:41:07.471] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:3:0)
[04:41:07.514] Build status: SCHEDULED
[04:41:07.514] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:4:0) after 5m29s
[04:46:36.550] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:4:0)
[04:46:36.581] Build status: SCHEDULED
[04:46:36.581] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:5:0) after 1m56s
[04:48:32.598] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:5:0)
[04:48:32.621] Build status: SCHEDULED
[04:48:32.621] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:6:0) after 3m40s
[04:52:12.776] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:6:0)
[04:52:12.801] Build status: SCHEDULED
[04:52:12.801] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:7:0) after 3m42s
[04:55:55.098] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:7:0)
[04:55:55.127] Build status: SCHEDULED
[04:55:55.127] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:8:0) after 4m15s
[05:00:10.158] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:8:0)
[05:00:10.196] Build status: SCHEDULED
[05:00:10.196] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:9:0) after 5m53s
[05:06:03.217] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:9:0)
[05:06:03.254] Build status: SCHEDULED
[05:06:03.254] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:10:0) after 9m38s
[05:15:41.269] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:10:0)
[05:15:41.289] Build status: SCHEDULED
[05:15:41.289] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:11:0) after 4m26s
[05:20:07.312] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:11:0)
[05:20:07.337] Build status: SCHEDULED
[05:20:07.337] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:12:0) after 1m32s
[05:21:39.668] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:12:0)
[05:21:39.692] Build status: SCHEDULED
[05:21:39.692] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:13:0) after 6m29s
[05:28:08.706] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:13:0)
[05:28:08.746] Build status: SCHEDULED
[05:28:08.746] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:14:0) after 8m52s
[05:37:00.724] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:14:0)
[05:37:00.768] Build status: SCHEDULED
[05:37:00.768] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:15:0) after 4m48s
[05:41:48.888] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:15:0)
[05:41:48.908] Build status: SCHEDULED
[05:41:48.908] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:16:0) after 3m17s
[05:45:05.927] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:16:0)
[05:45:05.965] Build status: SCHEDULED
[05:45:05.966] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:17:0) after 7m20s
[05:52:26.149] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:17:0)
[05:52:26.169] Build status: SCHEDULED
[05:52:26.169] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:18:0) after 8m48s
[06:01:14.178] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:18:0)
[06:01:14.202] Build status: SCHEDULED
[06:01:14.202] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:19:0) after 9m22s
[06:10:36.297] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:19:0)
[06:10:36.321] Build status: SCHEDULED
[06:10:36.321] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:20:0) after 4m7s
[06:14:43.458] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:20:0)
[06:14:43.480] Build status: SCHEDULED
[06:14:43.480] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:21:0) after 2m22s
[06:17:05.494] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:21:0)
[06:17:05.516] Build status: SCHEDULED
[06:17:05.516] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:22:0) after 8m15s
[06:25:20.702] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:22:0)
[06:25:20.738] Build status: SCHEDULED
[06:25:20.738] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:23:0) after 8m3s
[06:33:23.832] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:23:0)
[06:33:23.852] Build status: SCHEDULED
[06:33:23.852] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:24:0) after 7m51s
[06:41:14.870] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:24:0)
[06:41:14.933] Build status: SCHEDULED
[06:41:14.933] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:25:0) after 1m41s
[06:42:55.950] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:25:0)
[06:42:55.972] Build status: SCHEDULED
[06:42:55.972] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:26:0) after 9m36s
[06:51:10.402] Received PubSub notification, asking Buildbucket for the build status
[06:51:10.422] Build status: STARTED
[06:52:31.989] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:26:0)
[06:52:32.012] Build status: STARTED
[06:52:32.012] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:28:0) after 7m33s
[07:00:05.045] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:28:0)
[07:00:05.066] Build status: STARTED
[07:00:05.066] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:29:0) after 2m10s
[07:02:15.229] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:29:0)
[07:02:15.248] Build status: STARTED
[07:02:15.248] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:30:0) after 4m58s
[07:07:14.438] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:30:0)
[07:07:14.475] Build status: STARTED
[07:07:14.475] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:31:0) after 9m13s
[07:16:27.551] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:31:0)
[07:16:27.570] Build status: STARTED
[07:16:27.570] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:32:0) after 1m31s
[07:17:58.642] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:32:0)
[07:17:58.669] Build status: STARTED
[07:17:58.669] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:33:0) after 8m11s
[07:26:09.827] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:33:0)
[07:26:09.850] Build status: STARTED
[07:26:09.850] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:34:0) after 8m19s
[07:34:28.865] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:34:0)
[07:34:28.907] Build status: STARTED
[07:34:28.907] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:35:0) after 4m48s
[07:39:16.932] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:35:0)
[07:39:16.954] Build status: STARTED
[07:39:16.954] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:36:0) after 8m20s
[07:47:36.971] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:36:0)
[07:47:36.991] Build status: STARTED
[07:47:36.991] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:37:0) after 4m15s
[07:51:52.009] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:37:0)
[07:51:52.041] Build status: STARTED
[07:51:52.041] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:38:0) after 2m48s
[07:54:40.112] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:38:0)
[07:54:40.135] Build status: STARTED
[07:54:40.135] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:39:0) after 7m32s
[08:02:12.162] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:39:0)
[08:02:12.206] Build status: STARTED
[08:02:12.206] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:40:0) after 5m8s
[08:07:20.228] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:40:0)
[08:07:20.265] Build status: STARTED
[08:07:20.265] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:41:0) after 2m43s
[08:10:03.417] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:41:0)
[08:10:03.441] Build status: STARTED
[08:10:03.441] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:42:0) after 9m11s
[08:19:14.673] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:42:0)
[08:19:14.746] Build status: STARTED
[08:19:14.746] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:43:0) after 1m10s
[08:20:24.771] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:43:0)
[08:20:24.792] Build status: STARTED
[08:20:24.792] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:44:0) after 4m56s
[08:25:20.919] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:44:0)
[08:25:20.947] Build status: STARTED
[08:25:20.948] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:45:0) after 8m12s
[08:33:33.083] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:45:0)
[08:33:33.104] Build status: STARTED
[08:33:33.104] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:46:0) after 5m10s
[08:38:43.302] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:46:0)
[08:38:43.324] Build status: STARTED
[08:38:43.324] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:47:0) after 3m45s
[08:42:28.340] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:47:0)
[08:42:28.360] Build status: STARTED
[08:42:28.360] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:48:0) after 7m6s
[08:49:34.376] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:48:0)
[08:49:34.399] Build status: STARTED
[08:49:34.399] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:49:0) after 3m30s
[08:53:04.463] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:49:0)
[08:53:04.485] Build status: STARTED
[08:53:04.485] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:50:0) after 1m29s
[08:54:33.679] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:50:0)
[08:54:33.702] Build status: STARTED
[08:54:33.702] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:51:0) after 4m17s
[08:58:50.727] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:51:0)
[08:58:50.757] Build status: STARTED
[08:58:50.757] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:52:0) after 2m33s
[09:01:23.829] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:52:0)
[09:01:23.852] Build status: STARTED
[09:01:23.852] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:53:0) after 8m17s
[09:09:40.875] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:53:0)
[09:09:40.912] Build status: STARTED
[09:09:40.912] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:54:0) after 1m6s
[09:10:46.938] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:54:0)
[09:10:46.969] Build status: STARTED
[09:10:46.969] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:55:0) after 9m53s
[09:20:39.997] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:55:0)
[09:20:40.015] Build status: STARTED
[09:20:40.015] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:56:0) after 2m3s
[09:22:43.034] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:56:0)
[09:22:43.096] Build status: STARTED
[09:22:43.096] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:57:0) after 2m4s
[09:24:47.135] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:57:0)
[09:24:47.158] Build status: STARTED
[09:24:47.158] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:58:0) after 1m25s
[09:26:12.282] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:58:0)
[09:26:12.303] Build status: STARTED
[09:26:12.303] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:59:0) after 8m17s
[09:34:29.384] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:59:0)
[09:34:29.420] Build status: STARTED
[09:34:29.420] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:60:0) after 7m39s
[09:42:08.536] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:60:0)
[09:42:08.586] Build status: STARTED
[09:42:08.586] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:61:0) after 8m16s
[09:50:25.018] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:61:0)
[09:50:25.051] Build status: STARTED
[09:50:25.051] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:62:0) after 5m59s
[09:56:24.024] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:62:0)
[09:56:24.047] Build status: STARTED
[09:56:24.047] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8951745659636323552:63:0) after 9m24s
[10:00:56.170] Received PubSub notification, asking Buildbucket for the build status
[10:00:56.191] Build:
{
"id": "8786315597502946737",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "angler-armv8-debug"
},
"number": 3344,
"createdBy": "project:art",
"createTime": "2023-03-18T04:25:01.029472509Z",
"startTime": "2023-03-18T06:51:09.214517Z",
"endTime": "2023-03-18T10:00:55.961058417Z",
"updateTime": "2023-03-18T10:00:55.961058417Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "e69d3de15ab2f0912926ff9a4f9b0b3b169ffde1",
"ref": "refs/heads/master"
}
}
}
[10:00:56.192] Invocation finished in 5h35m56.580747452s with status SUCCEEDED