[22:23:56.200] New invocation is queued and will start shortly
[22:23:58.543] Starting the invocation (attempt 1)
[22:23:58.577] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6660a0a73a0d24d7a6372a100c01ecb2bac39ec4"
[22:23:58.577] Popped gitiles commit info from properties and tags
[22:23:58.577] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:23:58.577] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:23:58.578] Buildbucket request:
{
"requestId": "8957657178195865616",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Android arm64 Builder (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6660a0a73a0d24d7a6372a100c01ecb2bac39ec4",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8957657178195865616"
},
{
"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:23:59.074] Scheduled build:
{
"id": "8792227114975286417",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Android arm64 Builder (dbg)"
},
"number": 48581,
"createdBy": "project:chromium",
"createTime": "2023-01-11T22:23:58.645858954Z",
"updateTime": "2023-01-11T22:23:58.645858954Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6660a0a73a0d24d7a6372a100c01ecb2bac39ec4",
"ref": "refs/heads/main"
}
}
}
[22:23:59.074] Task URL: https://cr-buildbucket.appspot.com/build/8792227114975286417
[22:23:59.074] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:2:0) after 5m29s
[22:28:06.796] Received PubSub notification, asking Buildbucket for the build status
[22:28:06.817] Build status: STARTED
[22:29:28.162] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:2:0)
[22:29:28.196] Build status: STARTED
[22:29:28.196] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:4:0) after 7m34s
[22:37:02.420] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:4:0)
[22:37:02.443] Build status: STARTED
[22:37:02.443] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:5:0) after 7m6s
[22:44:08.585] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:5:0)
[22:44:08.610] Build status: STARTED
[22:44:08.610] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:6:0) after 1m48s
[22:45:56.630] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:6:0)
[22:45:56.675] Build status: STARTED
[22:45:56.675] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:7:0) after 7m45s
[22:53:41.863] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:7:0)
[22:53:41.896] Build status: STARTED
[22:53:41.896] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:8:0) after 2m16s
[22:55:57.999] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:8:0)
[22:55:58.050] Build status: STARTED
[22:55:58.050] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:9:0) after 9m34s
[23:05:32.163] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:9:0)
[23:05:32.196] Build status: STARTED
[23:05:32.196] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:10:0) after 7m35s
[23:13:07.327] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:10:0)
[23:13:07.349] Build status: STARTED
[23:13:07.350] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:11:0) after 8m15s
[23:21:22.374] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:11:0)
[23:21:22.399] Build status: STARTED
[23:21:22.399] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:12:0) after 6m52s
[23:28:14.515] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:12:0)
[23:28:14.537] Build status: STARTED
[23:28:14.537] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:13:0) after 5m44s
[23:33:58.857] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:13:0)
[23:33:58.881] Build status: STARTED
[23:33:58.881] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:14:0) after 8m19s
[23:42:18.003] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:14:0)
[23:42:18.027] Build status: STARTED
[23:42:18.027] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:15:0) after 3m21s
[23:45:39.188] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:15:0)
[23:45:39.216] Build status: STARTED
[23:45:39.216] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:16:0) after 7m2s
[23:52:41.325] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:16:0)
[23:52:41.351] Build status: STARTED
[23:52:41.351] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:17:0) after 6m31s
[23:59:12.616] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:17:0)
[23:59:12.663] Build status: STARTED
[23:59:12.663] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:18:0) after 2m48s
[00:02:00.689] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:18:0)
[00:02:00.716] Build status: STARTED
[00:02:00.716] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:19:0) after 6m17s
[00:08:17.740] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:19:0)
[00:08:17.767] Build status: STARTED
[00:08:17.767] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:20:0) after 3m55s
[00:12:12.901] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:20:0)
[00:12:12.928] Build status: STARTED
[00:12:12.928] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:21:0) after 9m5s
[00:21:17.922] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:21:0)
[00:21:17.948] Build status: STARTED
[00:21:17.948] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:22:0) after 4m52s
[00:26:10.168] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:22:0)
[00:26:10.203] Build status: STARTED
[00:26:10.203] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:23:0) after 3m48s
[00:29:58.275] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:23:0)
[00:29:58.304] Build status: STARTED
[00:29:58.304] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:24:0) after 9m55s
[00:39:53.543] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:24:0)
[00:39:53.591] Build status: STARTED
[00:39:53.591] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:25:0) after 1m50s
[00:41:43.577] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:25:0)
[00:41:43.603] Build status: STARTED
[00:41:43.603] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:26:0) after 2m12s
[00:43:56.043] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:26:0)
[00:43:56.119] Build status: STARTED
[00:43:56.119] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:27:0) after 1m0s
[00:44:56.145] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:27:0)
[00:44:56.166] Build status: STARTED
[00:44:56.166] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8957657178195865616:28:0) after 3m53s
[00:46:48.580] Received PubSub notification, asking Buildbucket for the build status
[00:46:48.607] Build:
{
"id": "8792227114975286417",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Android arm64 Builder (dbg)"
},
"number": 48581,
"createdBy": "project:chromium",
"createTime": "2023-01-11T22:23:58.645858954Z",
"startTime": "2023-01-11T22:28:05.775536Z",
"endTime": "2023-01-12T00:46:48.360348170Z",
"updateTime": "2023-01-12T00:46:48.360348170Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6660a0a73a0d24d7a6372a100c01ecb2bac39ec4",
"ref": "refs/heads/main"
}
}
}
[00:46:48.607] Invocation finished in 2h22m52.417556665s with status SUCCEEDED