[21:39:44.152] New invocation is queued and will start shortly
[21:39:45.224] Starting the invocation (attempt 1)
[21:39:45.256] Popped buildset tag "buildset:commit/gitiles/android.googlesource.com/platform/art/+/4ba1b021732bebda8289cbc60f3e0a2444ce6bbb"
[21:39:45.256] Popped gitiles commit info from properties and tags
[21:39:45.256] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:39:45.256] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:39:45.256] Buildbucket request:
{
"requestId": "8935373105331987632",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "angler-armv8-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8935373105331987632",
"job": "art/angler-armv8-debug",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/art",
"revision": "64d3bd47a5c86fbc0d3f425e157ff372a52a6366"
},
"id": "https://android.googlesource.com/platform/art/+/refs/heads/master@64d3bd47a5c86fbc0d3f425e157ff372a52a6366",
"title": "64d3bd47a5c86fbc0d3f425e157ff372a52a6366",
"url": "https://android.googlesource.com/platform/art/+/64d3bd47a5c86fbc0d3f425e157ff372a52a6366"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/art",
"revision": "4ba1b021732bebda8289cbc60f3e0a2444ce6bbb"
},
"id": "https://android.googlesource.com/platform/art/+/refs/heads/master@4ba1b021732bebda8289cbc60f3e0a2444ce6bbb",
"title": "4ba1b021732bebda8289cbc60f3e0a2444ce6bbb",
"url": "https://android.googlesource.com/platform/art/+/4ba1b021732bebda8289cbc60f3e0a2444ce6bbb"
}
]
}
},
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "4ba1b021732bebda8289cbc60f3e0a2444ce6bbb",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8935373105331987632"
},
{
"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"
}
}
[21:39:45.815] Scheduled build:
{
"id": "8769943043462461553",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "angler-armv8-debug"
},
"number": 3856,
"createdBy": "project:art",
"createTime": "2023-09-14T21:39:45.312380769Z",
"updateTime": "2023-09-14T21:39:45.312380769Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "4ba1b021732bebda8289cbc60f3e0a2444ce6bbb",
"ref": "refs/heads/master"
}
}
}
[21:39:45.815] Task URL: https://cr-buildbucket.appspot.com/build/8769943043462461553
[21:39:45.815] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:2:0) after 5m31s
[21:39:46.754] Received PubSub notification, asking Buildbucket for the build status
[21:39:46.774] Build status: SCHEDULED
[21:39:51.631] Received PubSub notification, asking Buildbucket for the build status
[21:39:51.661] Build status: STARTED
[21:45:17.280] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:2:0)
[21:45:17.309] Build status: STARTED
[21:45:17.310] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:5:0) after 4m11s
[21:49:28.892] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:5:0)
[21:49:28.911] Build status: STARTED
[21:49:28.911] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:6:0) after 8m13s
[21:57:42.399] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:6:0)
[21:57:42.493] Build status: STARTED
[21:57:42.493] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:7:0) after 9m47s
[22:07:29.552] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:7:0)
[22:07:29.570] Build status: STARTED
[22:07:29.570] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:8:0) after 2m33s
[22:10:02.647] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:8:0)
[22:10:02.677] Build status: STARTED
[22:10:02.677] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:9:0) after 9m20s
[22:19:23.109] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:9:0)
[22:19:23.144] Build status: STARTED
[22:19:23.144] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:10:0) after 2m19s
[22:21:42.199] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:10:0)
[22:21:42.218] Build status: STARTED
[22:21:42.218] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:11:0) after 3m2s
[22:24:44.237] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:11:0)
[22:24:44.271] Build status: STARTED
[22:24:44.271] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:12:0) after 7m43s
[22:32:27.393] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:12:0)
[22:32:27.415] Build status: STARTED
[22:32:27.416] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:13:0) after 6m0s
[22:38:27.939] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:13:0)
[22:38:28.030] Build status: STARTED
[22:38:28.030] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:14:0) after 3m34s
[22:42:02.047] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:14:0)
[22:42:02.116] Build status: STARTED
[22:42:02.116] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:15:0) after 9m14s
[22:51:16.209] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:15:0)
[22:51:16.249] Build status: STARTED
[22:51:16.249] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:16:0) after 6m48s
[22:58:04.360] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:16:0)
[22:58:04.380] Build status: STARTED
[22:58:04.380] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:17:0) after 9m8s
[23:07:12.403] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:17:0)
[23:07:12.453] Build status: STARTED
[23:07:12.454] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:18:0) after 4m50s
[23:12:02.541] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:18:0)
[23:12:02.603] Build status: STARTED
[23:12:02.603] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:19:0) after 6m14s
[23:18:16.626] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:19:0)
[23:18:16.663] Build status: STARTED
[23:18:16.663] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:20:0) after 4m43s
[23:23:00.702] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:20:0)
[23:23:00.725] Build status: STARTED
[23:23:00.726] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:21:0) after 8m23s
[23:31:23.842] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:21:0)
[23:31:23.863] Build status: STARTED
[23:31:23.863] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:22:0) after 4m38s
[23:36:01.894] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:22:0)
[23:36:01.943] Build status: STARTED
[23:36:01.943] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:23:0) after 5m40s
[23:41:41.990] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:23:0)
[23:41:42.011] Build status: STARTED
[23:41:42.011] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:24:0) after 1m36s
[23:43:18.228] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:24:0)
[23:43:18.250] Build status: STARTED
[23:43:18.250] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:25:0) after 1m13s
[23:44:31.275] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:25:0)
[23:44:31.293] Build status: STARTED
[23:44:31.294] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:26:0) after 3m17s
[23:47:48.689] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:26:0)
[23:47:48.716] Build status: STARTED
[23:47:48.716] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:27:0) after 3m42s
[23:51:31.307] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:27:0)
[23:51:31.336] Build status: STARTED
[23:51:31.336] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:28:0) after 2m16s
[23:53:47.558] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:28:0)
[23:53:47.588] Build status: STARTED
[23:53:47.588] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:29:0) after 9m42s
[00:03:29.947] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:29:0)
[00:03:30.008] Build status: STARTED
[00:03:30.008] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:30:0) after 8m20s
[00:11:50.219] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:30:0)
[00:11:50.267] Build status: STARTED
[00:11:50.267] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:31:0) after 7m41s
[00:19:31.239] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:31:0)
[00:19:31.257] Build status: STARTED
[00:19:31.258] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:32:0) after 2m27s
[00:21:58.278] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:32:0)
[00:21:58.300] Build status: STARTED
[00:21:58.300] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:33:0) after 4m55s
[00:26:53.589] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:33:0)
[00:26:53.656] Build status: STARTED
[00:26:53.656] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:34:0) after 7m36s
[00:34:29.736] Handling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:34:0)
[00:34:29.765] Build status: STARTED
[00:34:29.765] Scheduling timer "check-buildbucket-build-status" (art/angler-armv8-debug:8935373105331987632:35:0) after 8m47s
[00:38:53.808] Received PubSub notification, asking Buildbucket for the build status
[00:38:53.834] Build:
{
"id": "8769943043462461553",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "angler-armv8-debug"
},
"number": 3856,
"createdBy": "project:art",
"createTime": "2023-09-14T21:39:45.312380769Z",
"startTime": "2023-09-14T21:39:50.876121Z",
"endTime": "2023-09-15T00:38:53.598084952Z",
"updateTime": "2023-09-15T00:38:53.598084952Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "4ba1b021732bebda8289cbc60f3e0a2444ce6bbb",
"ref": "refs/heads/master"
}
}
}
[00:38:53.834] Invocation finished in 2h59m9.688340723s with status SUCCEEDED