[05:01:09.451] New invocation is queued and will start shortly
[05:01:10.588] Starting the invocation (attempt 1)
[05:01:10.631] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2318c6cf40448ae010e6ecced06a667f55cddeee"
[05:01:10.631] Popped gitiles commit info from properties and tags
[05:01:10.631] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:01:10.631] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:01:10.631] Buildbucket request:
{
"requestId": "8966691883868033488",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Android arm64 Builder (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2318c6cf40448ae010e6ecced06a667f55cddeee",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8966691883868033488"
},
{
"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"
}
}
[05:01:11.073] Scheduled build:
{
"id": "8801261821878042353",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Android arm64 Builder (dbg)"
},
"number": 47324,
"createdBy": "project:chromium",
"createTime": "2022-10-04T05:01:10.716611389Z",
"updateTime": "2022-10-04T05:01:10.716611389Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2318c6cf40448ae010e6ecced06a667f55cddeee",
"ref": "refs/heads/main"
}
}
}
[05:01:11.073] Task URL: https://cr-buildbucket.appspot.com/build/8801261821878042353
[05:01:11.073] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:2:0) after 4m21s
[05:01:13.515] Received PubSub notification, asking Buildbucket for the build status
[05:01:13.548] Build status: STARTED
[05:05:32.103] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:2:0)
[05:05:32.138] Build status: STARTED
[05:05:32.138] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:4:0) after 1m37s
[05:07:09.194] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:4:0)
[05:07:09.230] Build status: STARTED
[05:07:09.230] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:5:0) after 6m42s
[05:13:51.452] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:5:0)
[05:13:51.488] Build status: STARTED
[05:13:51.488] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:6:0) after 7m17s
[05:21:08.543] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:6:0)
[05:21:08.565] Build status: STARTED
[05:21:08.565] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:7:0) after 4m15s
[05:25:23.636] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:7:0)
[05:25:23.658] Build status: STARTED
[05:25:23.658] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:8:0) after 8m2s
[05:33:25.717] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:8:0)
[05:33:25.739] Build status: STARTED
[05:33:25.739] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:9:0) after 1m56s
[05:35:21.889] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:9:0)
[05:35:21.916] Build status: STARTED
[05:35:21.916] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:10:0) after 6m47s
[05:42:08.940] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:10:0)
[05:42:08.964] Build status: STARTED
[05:42:08.964] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:11:0) after 8m52s
[05:51:00.986] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:11:0)
[05:51:01.086] Build status: STARTED
[05:51:01.087] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:12:0) after 2m26s
[05:53:27.123] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:12:0)
[05:53:27.156] Build status: STARTED
[05:53:27.156] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:13:0) after 2m34s
[05:56:01.345] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:13:0)
[05:56:01.372] Build status: STARTED
[05:56:01.372] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:14:0) after 1m16s
[05:57:17.489] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:14:0)
[05:57:17.523] Build status: STARTED
[05:57:17.523] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:15:0) after 8m28s
[06:05:45.564] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:15:0)
[06:05:45.588] Build status: STARTED
[06:05:45.588] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:16:0) after 5m46s
[06:11:31.777] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:16:0)
[06:11:31.800] Build status: STARTED
[06:11:31.800] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:17:0) after 1m5s
[06:12:36.922] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:17:0)
[06:12:36.944] Build status: STARTED
[06:12:36.944] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:18:0) after 2m11s
[06:14:48.036] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:18:0)
[06:14:48.057] Build status: STARTED
[06:14:48.057] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:19:0) after 7m54s
[06:22:42.145] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:19:0)
[06:22:42.168] Build status: STARTED
[06:22:42.168] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:20:0) after 5m29s
[06:28:11.196] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:20:0)
[06:28:11.219] Build status: STARTED
[06:28:11.219] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:21:0) after 8m11s
[06:36:22.349] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:21:0)
[06:36:22.372] Build status: STARTED
[06:36:22.372] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:22:0) after 8m22s
[06:44:44.402] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:22:0)
[06:44:44.445] Build status: STARTED
[06:44:44.445] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:23:0) after 6m3s
[06:50:47.477] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:23:0)
[06:50:47.500] Build status: STARTED
[06:50:47.500] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:24:0) after 3m7s
[06:53:54.545] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:24:0)
[06:53:54.573] Build status: STARTED
[06:53:54.573] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:25:0) after 5m56s
[06:59:50.731] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:25:0)
[06:59:50.755] Build status: STARTED
[06:59:50.755] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:26:0) after 3m28s
[07:03:18.785] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:26:0)
[07:03:18.819] Build status: STARTED
[07:03:18.819] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:27:0) after 5m42s
[07:09:00.845] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:27:0)
[07:09:00.869] Build status: STARTED
[07:09:00.869] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:28:0) after 4m40s
[07:13:41.185] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:28:0)
[07:13:41.213] Build status: STARTED
[07:13:41.213] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:29:0) after 7m39s
[07:21:20.256] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:29:0)
[07:21:20.294] Build status: STARTED
[07:21:20.295] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:30:0) after 1m49s
[07:23:09.471] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:30:0)
[07:23:09.500] Build status: STARTED
[07:23:09.500] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:31:0) after 6m19s
[07:29:28.760] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:31:0)
[07:29:28.781] Build status: STARTED
[07:29:28.781] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:32:0) after 6m49s
[07:36:18.145] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:32:0)
[07:36:18.174] Build status: STARTED
[07:36:18.174] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:33:0) after 5m30s
[07:41:50.087] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:33:0)
[07:41:50.110] Build status: STARTED
[07:41:50.110] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:34:0) after 5m46s
[07:47:36.143] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:34:0)
[07:47:36.172] Build status: STARTED
[07:47:36.172] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:35:0) after 6m46s
[07:54:22.317] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:35:0)
[07:54:22.422] Build status: STARTED
[07:54:22.422] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:36:0) after 6m37s
[08:00:59.526] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:36:0)
[08:00:59.557] Build status: STARTED
[08:00:59.557] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:37:0) after 9m1s
[08:10:00.725] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:37:0)
[08:10:00.751] Build status: STARTED
[08:10:00.751] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:38:0) after 8m48s
[08:18:48.735] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:38:0)
[08:18:48.845] Build status: STARTED
[08:18:48.845] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:39:0) after 3m13s
[08:22:01.845] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:39:0)
[08:22:01.889] Build status: STARTED
[08:22:01.889] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:40:0) after 9m9s
[08:31:10.976] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:40:0)
[08:31:11.041] Build status: STARTED
[08:31:11.041] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:41:0) after 7m8s
[08:38:19.105] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:41:0)
[08:38:19.136] Build status: STARTED
[08:38:19.136] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:42:0) after 3m52s
[08:42:11.175] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:42:0)
[08:42:11.201] Build status: STARTED
[08:42:11.201] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:43:0) after 6m22s
[08:48:33.508] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:43:0)
[08:48:33.546] Build status: STARTED
[08:48:33.546] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:44:0) after 5m44s
[08:54:18.307] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:44:0)
[08:54:18.599] Build status: STARTED
[08:54:18.599] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:45:0) after 7m48s
[09:02:06.774] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:45:0)
[09:02:06.816] Build status: STARTED
[09:02:06.816] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:46:0) after 7m50s
[09:09:57.067] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:46:0)
[09:09:57.089] Build status: STARTED
[09:09:57.089] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8966691883868033488:47:0) after 6m26s
[09:09:58.625] Received PubSub notification, asking Buildbucket for the build status
[09:09:58.665] Build:
{
"id": "8801261821878042353",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Android arm64 Builder (dbg)"
},
"number": 47324,
"createdBy": "project:chromium",
"createTime": "2022-10-04T05:01:10.716611389Z",
"startTime": "2022-10-04T05:01:12.380273Z",
"endTime": "2022-10-04T09:09:58.410982550Z",
"updateTime": "2022-10-04T09:09:58.410982550Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2318c6cf40448ae010e6ecced06a667f55cddeee",
"ref": "refs/heads/main"
}
}
}
[09:09:58.665] Invocation finished in 4h8m49.230281222s with status SUCCEEDED