[22:27:37.140] New invocation is queued and will start shortly
[22:27:38.260] Starting the invocation (attempt 1)
[22:27:38.285] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8398c25e9688d25580b5f5494c82ef30ea7a96a3"
[22:27:38.285] Popped gitiles commit info from properties and tags
[22:27:38.285] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:27:38.285] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:27:38.285] Buildbucket request:
{
"requestId": "8940896507741237824",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Android arm64 Builder (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8398c25e9688d25580b5f5494c82ef30ea7a96a3",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8940896507741237824"
},
{
"key": "scheduler_job_id",
"value": "chromium/Android arm64 Builder (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:27:38.893] Scheduled build:
{
"id": "8775466445828605009",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Android arm64 Builder (dbg)"
},
"number": 51515,
"createdBy": "project:chromium",
"createTime": "2023-07-15T22:27:38.336812391Z",
"updateTime": "2023-07-15T22:27:38.336812391Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8398c25e9688d25580b5f5494c82ef30ea7a96a3",
"ref": "refs/heads/main"
}
}
}
[22:27:38.893] Task URL: https://cr-buildbucket.appspot.com/build/8775466445828605009
[22:27:38.893] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:2:0) after 4m56s
[22:32:34.908] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:2:0)
[22:32:34.929] Build status: SCHEDULED
[22:32:34.929] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:3:0) after 7m11s
[22:39:45.949] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:3:0)
[22:39:45.975] Build status: SCHEDULED
[22:39:45.975] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:4:0) after 5m34s
[22:41:27.514] Received PubSub notification, asking Buildbucket for the build status
[22:41:27.530] Build status: STARTED
[22:45:19.988] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:4:0)
[22:45:20.003] Build status: STARTED
[22:45:20.003] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:6:0) after 5m6s
[22:50:26.184] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:6:0)
[22:50:26.203] Build status: STARTED
[22:50:26.203] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:7:0) after 5m8s
[22:55:34.218] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:7:0)
[22:55:34.234] Build status: STARTED
[22:55:34.234] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:8:0) after 8m16s
[23:03:50.212] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:8:0)
[23:03:50.235] Build status: STARTED
[23:03:50.235] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:9:0) after 6m22s
[23:10:12.250] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:9:0)
[23:10:12.288] Build status: STARTED
[23:10:12.288] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:10:0) after 7m46s
[23:17:58.312] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:10:0)
[23:17:58.333] Build status: STARTED
[23:17:58.333] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:11:0) after 6m22s
[23:24:20.348] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:11:0)
[23:24:20.365] Build status: STARTED
[23:24:20.365] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:12:0) after 6m29s
[23:30:49.349] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:12:0)
[23:30:49.372] Build status: STARTED
[23:30:49.372] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:13:0) after 1m58s
[23:32:47.390] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:13:0)
[23:32:47.407] Build status: STARTED
[23:32:47.407] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:14:0) after 7m45s
[23:40:32.429] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:14:0)
[23:40:32.452] Build status: STARTED
[23:40:32.452] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:15:0) after 4m24s
[23:44:56.487] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:15:0)
[23:44:56.506] Build status: STARTED
[23:44:56.506] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:16:0) after 9m44s
[23:54:40.490] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:16:0)
[23:54:40.512] Build status: STARTED
[23:54:40.512] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:17:0) after 3m33s
[23:58:13.528] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:17:0)
[23:58:13.554] Build status: STARTED
[23:58:13.554] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:18:0) after 5m2s
[00:03:16.729] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:18:0)
[00:03:16.753] Build status: STARTED
[00:03:16.753] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:19:0) after 8m16s
[00:11:32.857] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:19:0)
[00:11:32.880] Build status: STARTED
[00:11:32.880] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:20:0) after 2m14s
[00:13:46.899] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:20:0)
[00:13:46.921] Build status: STARTED
[00:13:46.921] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:21:0) after 8m31s
[00:22:18.750] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:21:0)
[00:22:18.773] Build status: STARTED
[00:22:18.773] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:22:0) after 9m1s
[00:31:20.111] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:22:0)
[00:31:20.127] Build status: STARTED
[00:31:20.127] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:23:0) after 7m13s
[00:38:33.160] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:23:0)
[00:38:33.177] Build status: STARTED
[00:38:33.177] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:24:0) after 4m8s
[00:42:42.066] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:24:0)
[00:42:42.088] Build status: STARTED
[00:42:42.088] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:25:0) after 6m17s
[00:48:59.439] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:25:0)
[00:48:59.458] Build status: STARTED
[00:48:59.458] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:26:0) after 3m19s
[00:52:18.786] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:26:0)
[00:52:18.808] Build status: STARTED
[00:52:18.808] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:27:0) after 1m36s
[00:53:55.429] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:27:0)
[00:53:55.459] Build status: STARTED
[00:53:55.459] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:28:0) after 6m59s
[01:00:54.483] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:28:0)
[01:00:54.501] Build status: STARTED
[01:00:54.501] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:29:0) after 2m27s
[01:03:21.593] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:29:0)
[01:03:21.610] Build status: STARTED
[01:03:21.610] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:30:0) after 3m10s
[01:06:31.687] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:30:0)
[01:06:31.703] Build status: STARTED
[01:06:31.703] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:31:0) after 5m20s
[01:11:51.790] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:31:0)
[01:11:51.814] Build status: STARTED
[01:11:51.814] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:32:0) after 1m26s
[01:13:17.914] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:32:0)
[01:13:18.087] Build status: STARTED
[01:13:18.087] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:33:0) after 2m34s
[01:15:52.103] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:33:0)
[01:15:52.123] Build status: STARTED
[01:15:52.123] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:34:0) after 1m48s
[01:17:41.789] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:34:0)
[01:17:41.879] Build status: STARTED
[01:17:41.879] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:35:0) after 9m2s
[01:26:43.895] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:35:0)
[01:26:43.925] Build status: STARTED
[01:26:43.925] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:36:0) after 3m0s
[01:29:44.112] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:36:0)
[01:29:44.129] Build status: STARTED
[01:29:44.129] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8940896507741237824:37:0) after 3m38s
[01:30:12.695] Received PubSub notification, asking Buildbucket for the build status
[01:30:12.724] Build:
{
"id": "8775466445828605009",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Android arm64 Builder (dbg)"
},
"number": 51515,
"createdBy": "project:chromium",
"createTime": "2023-07-15T22:27:38.336812391Z",
"startTime": "2023-07-15T22:41:26.780886Z",
"endTime": "2023-07-16T01:30:12.366461181Z",
"updateTime": "2023-07-16T01:30:12.366461181Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8398c25e9688d25580b5f5494c82ef30ea7a96a3",
"ref": "refs/heads/main"
}
}
}
[01:30:12.724] Invocation finished in 3h2m35.595570442s with status SUCCEEDED