[22:24:42.833] New invocation is queued and will start shortly
[22:24:43.968] Starting the invocation (attempt 1)
[22:24:44.050] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f80acab915edd714b411613767aae6db84940f6b"
[22:24:44.050] Popped gitiles commit info from properties and tags
[22:24:44.050] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:24:44.050] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:24:44.050] Buildbucket request:
{
"requestId": "8967260407740457856",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Android arm64 Builder (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f80acab915edd714b411613767aae6db84940f6b",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8967260407740457856"
},
{
"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:24:44.536] Scheduled build:
{
"id": "8801830345706071889",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Android arm64 Builder (dbg)"
},
"number": 47286,
"createdBy": "project:chromium",
"createTime": "2022-09-27T22:24:44.142062360Z",
"updateTime": "2022-09-27T22:24:44.142062360Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f80acab915edd714b411613767aae6db84940f6b",
"ref": "refs/heads/main"
}
}
}
[22:24:44.536] Task URL: https://cr-buildbucket.appspot.com/build/8801830345706071889
[22:24:44.536] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:2:0) after 3m54s
[22:24:46.379] Received PubSub notification, asking Buildbucket for the build status
[22:24:46.407] Build status: STARTED
[22:28:38.744] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:2:0)
[22:28:38.775] Build status: STARTED
[22:28:38.776] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:4:0) after 4m53s
[22:33:31.965] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:4:0)
[22:33:31.992] Build status: STARTED
[22:33:31.993] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:5:0) after 3m58s
[22:37:30.108] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:5:0)
[22:37:30.136] Build status: STARTED
[22:37:30.136] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:6:0) after 4m47s
[22:42:17.238] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:6:0)
[22:42:17.288] Build status: STARTED
[22:42:17.288] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:7:0) after 7m37s
[22:49:54.544] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:7:0)
[22:49:54.573] Build status: STARTED
[22:49:54.573] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:8:0) after 9m54s
[22:59:48.598] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:8:0)
[22:59:48.623] Build status: STARTED
[22:59:48.623] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:9:0) after 3m51s
[23:03:39.726] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:9:0)
[23:03:39.750] Build status: STARTED
[23:03:39.750] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:10:0) after 9m4s
[23:12:43.883] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:10:0)
[23:12:43.913] Build status: STARTED
[23:12:43.913] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:11:0) after 5m17s
[23:18:01.490] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:11:0)
[23:18:01.515] Build status: STARTED
[23:18:01.515] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:12:0) after 3m6s
[23:21:07.667] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:12:0)
[23:21:07.701] Build status: STARTED
[23:21:07.701] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:13:0) after 2m24s
[23:23:31.716] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:13:0)
[23:23:31.775] Build status: STARTED
[23:23:31.775] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:14:0) after 1m12s
[23:24:44.147] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:14:0)
[23:24:44.186] Build status: STARTED
[23:24:44.186] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:15:0) after 4m25s
[23:29:09.553] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:15:0)
[23:29:09.577] Build status: STARTED
[23:29:09.578] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:16:0) after 7m44s
[23:36:53.794] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:16:0)
[23:36:53.823] Build status: STARTED
[23:36:53.824] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:17:0) after 4m14s
[23:41:07.917] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:17:0)
[23:41:08.112] Build status: STARTED
[23:41:08.112] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:18:0) after 8m4s
[23:49:12.253] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:18:0)
[23:49:12.323] Build status: STARTED
[23:49:12.324] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:19:0) after 7m54s
[23:57:06.355] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:19:0)
[23:57:06.381] Build status: STARTED
[23:57:06.381] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:20:0) after 6m8s
[00:03:14.612] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:20:0)
[00:03:14.664] Build status: STARTED
[00:03:14.664] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:21:0) after 6m2s
[00:09:16.789] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:21:0)
[00:09:16.812] Build status: STARTED
[00:09:16.812] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:22:0) after 7m5s
[00:16:21.838] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:22:0)
[00:16:21.865] Build status: STARTED
[00:16:21.865] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:23:0) after 4m29s
[00:20:50.908] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:23:0)
[00:20:50.933] Build status: STARTED
[00:20:50.933] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:24:0) after 9m29s
[00:30:20.021] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:24:0)
[00:30:20.211] Build status: STARTED
[00:30:20.211] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:25:0) after 8m21s
[00:38:41.236] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:25:0)
[00:38:41.258] Build status: STARTED
[00:38:41.258] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:26:0) after 4m3s
[00:42:44.294] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:26:0)
[00:42:44.321] Build status: STARTED
[00:42:44.321] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:27:0) after 7m14s
[00:49:58.429] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:27:0)
[00:49:58.455] Build status: STARTED
[00:49:58.455] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:28:0) after 5m45s
[00:55:43.812] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:28:0)
[00:55:43.841] Build status: STARTED
[00:55:43.841] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:29:0) after 7m27s
[01:03:11.000] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:29:0)
[01:03:11.026] Build status: STARTED
[01:03:11.026] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:30:0) after 8m55s
[01:12:06.023] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:30:0)
[01:12:06.046] Build status: STARTED
[01:12:06.046] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:31:0) after 7m33s
[01:19:39.107] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:31:0)
[01:19:39.133] Build status: STARTED
[01:19:39.133] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:32:0) after 2m11s
[01:21:50.241] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:32:0)
[01:21:50.263] Build status: STARTED
[01:21:50.263] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:33:0) after 6m10s
[01:28:00.359] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:33:0)
[01:28:00.394] Build status: STARTED
[01:28:00.395] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:34:0) after 2m26s
[01:30:26.529] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:34:0)
[01:30:26.553] Build status: STARTED
[01:30:26.553] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:35:0) after 1m31s
[01:31:57.823] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:35:0)
[01:31:57.851] Build status: STARTED
[01:31:57.851] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:36:0) after 8m6s
[01:40:04.493] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:36:0)
[01:40:04.522] Build status: STARTED
[01:40:04.522] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:37:0) after 5m51s
[01:45:55.656] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:37:0)
[01:45:55.696] Build status: STARTED
[01:45:55.696] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:38:0) after 2m11s
[01:48:06.879] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:38:0)
[01:48:06.908] Build status: STARTED
[01:48:06.908] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:39:0) after 5m51s
[01:53:57.932] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:39:0)
[01:53:57.956] Build status: STARTED
[01:53:57.956] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:40:0) after 6m7s
[02:00:05.002] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:40:0)
[02:00:05.028] Build status: STARTED
[02:00:05.028] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:41:0) after 3m12s
[02:03:17.246] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:41:0)
[02:03:17.283] Build status: STARTED
[02:03:17.283] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:42:0) after 7m41s
[02:10:58.410] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:42:0)
[02:10:58.439] Build status: STARTED
[02:10:58.439] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:43:0) after 9m9s
[02:20:07.564] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:43:0)
[02:20:07.603] Build status: STARTED
[02:20:07.603] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:44:0) after 5m43s
[02:25:50.627] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:44:0)
[02:25:50.720] Build status: STARTED
[02:25:50.720] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:45:0) after 8m49s
[02:34:39.801] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:45:0)
[02:34:39.826] Build status: STARTED
[02:34:39.826] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:46:0) after 5m58s
[02:40:38.362] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:46:0)
[02:40:38.387] Build status: STARTED
[02:40:38.387] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:47:0) after 8m33s
[02:49:11.412] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:47:0)
[02:49:11.442] Build status: STARTED
[02:49:11.443] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:48:0) after 7m24s
[02:56:35.641] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:48:0)
[02:56:35.676] Build status: STARTED
[02:56:35.676] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:49:0) after 5m34s
[03:02:09.883] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:49:0)
[03:02:09.913] Build status: STARTED
[03:02:09.913] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:50:0) after 2m13s
[03:04:23.020] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:50:0)
[03:04:23.045] Build status: STARTED
[03:04:23.045] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:51:0) after 6m49s
[03:11:12.089] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:51:0)
[03:11:12.118] Build status: STARTED
[03:11:12.118] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:52:0) after 1m20s
[03:12:32.119] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:52:0)
[03:12:32.142] Build status: STARTED
[03:12:32.142] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:53:0) after 7m43s
[03:20:15.172] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:53:0)
[03:20:15.196] Build status: STARTED
[03:20:15.196] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:54:0) after 3m30s
[03:23:45.190] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:54:0)
[03:23:45.213] Build status: STARTED
[03:23:45.213] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8967260407740457856:55:0) after 4m55s
[03:24:09.221] Received PubSub notification, asking Buildbucket for the build status
[03:24:09.244] Build:
{
"id": "8801830345706071889",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Android arm64 Builder (dbg)"
},
"number": 47286,
"createdBy": "project:chromium",
"createTime": "2022-09-27T22:24:44.142062360Z",
"startTime": "2022-09-27T22:24:45.590806Z",
"endTime": "2022-09-28T03:24:08.837506579Z",
"updateTime": "2022-09-28T03:24:08.837506579Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f80acab915edd714b411613767aae6db84940f6b",
"ref": "refs/heads/main"
}
}
}
[03:24:09.244] Invocation finished in 4h59m26.424809159s with status SUCCEEDED