[03:30:54.315] New invocation is queued and will start shortly
[03:30:55.478] Starting the invocation (attempt 1)
[03:30:55.521] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d3edcf4b13d7b0e256439ef4beafc07d5fd325d6"
[03:30:55.521] Popped gitiles commit info from properties and tags
[03:30:55.521] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:30:55.521] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:30:55.521] Buildbucket request:
{
"requestId": "8988803221848293216",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Android arm64 Builder (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d3edcf4b13d7b0e256439ef4beafc07d5fd325d6",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8988803221848293216"
},
{
"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"
}
}
[03:30:56.374] Scheduled build:
{
"id": "8823373159839358705",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Android arm64 Builder (dbg)"
},
"number": 46125,
"createdBy": "project:chromium",
"createTime": "2022-02-02T03:30:55.600566311Z",
"updateTime": "2022-02-02T03:30:55.600566311Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d3edcf4b13d7b0e256439ef4beafc07d5fd325d6",
"ref": "refs/heads/main"
}
}
}
[03:30:56.375] Task URL: https://cr-buildbucket.appspot.com/build/8823373159839358705
[03:30:56.375] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:2:0) after 4m32s
[03:31:07.148] Received PubSub notification, asking Buildbucket for the build status
[03:31:07.171] Build status: STARTED
[03:35:28.693] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:2:0)
[03:35:28.728] Build status: STARTED
[03:35:28.728] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:4:0) after 3m34s
[03:39:03.117] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:4:0)
[03:39:03.136] Build status: STARTED
[03:39:03.136] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:5:0) after 3m39s
[03:42:42.215] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:5:0)
[03:42:42.238] Build status: STARTED
[03:42:42.238] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:6:0) after 3m51s
[03:46:33.260] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:6:0)
[03:46:33.289] Build status: STARTED
[03:46:33.289] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:7:0) after 6m46s
[03:53:19.434] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:7:0)
[03:53:19.462] Build status: STARTED
[03:53:19.462] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:8:0) after 6m22s
[03:59:41.596] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:8:0)
[03:59:41.664] Build status: STARTED
[03:59:41.665] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:9:0) after 9m17s
[04:08:58.698] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:9:0)
[04:08:58.717] Build status: STARTED
[04:08:58.717] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:10:0) after 8m28s
[04:17:26.783] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:10:0)
[04:17:26.802] Build status: STARTED
[04:17:26.802] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:11:0) after 8m17s
[04:25:43.826] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:11:0)
[04:25:43.850] Build status: STARTED
[04:25:43.850] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:12:0) after 7m37s
[04:33:20.889] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:12:0)
[04:33:20.913] Build status: STARTED
[04:33:20.913] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:13:0) after 1m51s
[04:35:12.079] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:13:0)
[04:35:12.103] Build status: STARTED
[04:35:12.103] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:14:0) after 5m56s
[04:41:08.129] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:14:0)
[04:41:08.153] Build status: STARTED
[04:41:08.153] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:15:0) after 5m6s
[04:46:14.185] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:15:0)
[04:46:14.219] Build status: STARTED
[04:46:14.219] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:16:0) after 6m44s
[04:52:58.239] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:16:0)
[04:52:58.259] Build status: STARTED
[04:52:58.259] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:17:0) after 5m19s
[04:58:17.279] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:17:0)
[04:58:17.300] Build status: STARTED
[04:58:17.300] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:18:0) after 2m54s
[05:01:11.382] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:18:0)
[05:01:11.402] Build status: STARTED
[05:01:11.402] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:19:0) after 7m51s
[05:09:02.467] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:19:0)
[05:09:02.487] Build status: STARTED
[05:09:02.487] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:20:0) after 6m33s
[05:15:35.613] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:20:0)
[05:15:35.634] Build status: STARTED
[05:15:35.634] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:21:0) after 9m6s
[05:24:41.750] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:21:0)
[05:24:41.770] Build status: STARTED
[05:24:41.770] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:22:0) after 1m13s
[05:25:54.804] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:22:0)
[05:25:54.839] Build status: STARTED
[05:25:54.839] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:23:0) after 7m43s
[05:33:38.004] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:23:0)
[05:33:38.025] Build status: STARTED
[05:33:38.025] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:24:0) after 9m53s
[05:43:31.148] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:24:0)
[05:43:31.185] Build status: STARTED
[05:43:31.185] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:25:0) after 8m53s
[05:52:24.242] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:25:0)
[05:52:24.265] Build status: STARTED
[05:52:24.265] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:26:0) after 3m51s
[05:56:15.404] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:26:0)
[05:56:15.425] Build status: STARTED
[05:56:15.425] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:27:0) after 5m21s
[06:01:36.485] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:27:0)
[06:01:36.507] Build status: STARTED
[06:01:36.508] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:28:0) after 1m9s
[06:02:45.840] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:28:0)
[06:02:45.865] Build status: STARTED
[06:02:45.865] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:29:0) after 8m28s
[06:11:13.924] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:29:0)
[06:11:13.947] Build status: STARTED
[06:11:13.947] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:30:0) after 2m22s
[06:13:35.998] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:30:0)
[06:13:36.020] Build status: STARTED
[06:13:36.020] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:31:0) after 6m57s
[06:20:33.046] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:31:0)
[06:20:33.072] Build status: STARTED
[06:20:33.072] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:32:0) after 3m17s
[06:23:50.148] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:32:0)
[06:23:50.169] Build status: STARTED
[06:23:50.169] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:33:0) after 9m21s
[06:33:11.190] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:33:0)
[06:33:11.240] Build status: STARTED
[06:33:11.240] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:34:0) after 8m41s
[06:41:52.599] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:34:0)
[06:41:52.635] Build status: STARTED
[06:41:52.635] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:35:0) after 4m56s
[06:46:48.962] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:35:0)
[06:46:49.000] Build status: STARTED
[06:46:49.000] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:36:0) after 9m59s
[06:56:48.276] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:36:0)
[06:56:48.312] Build status: STARTED
[06:56:48.312] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:37:0) after 2m53s
[06:59:41.386] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:37:0)
[06:59:41.409] Build status: STARTED
[06:59:41.409] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:38:0) after 8m0s
[07:07:41.631] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:38:0)
[07:07:41.877] Build status: STARTED
[07:07:41.877] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:39:0) after 6m57s
[07:14:38.945] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:39:0)
[07:14:38.972] Build status: STARTED
[07:14:38.972] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:40:0) after 6m3s
[07:20:41.996] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:40:0)
[07:20:42.017] Build status: STARTED
[07:20:42.017] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:41:0) after 9m45s
[07:30:27.153] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:41:0)
[07:30:27.174] Build status: STARTED
[07:30:27.174] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:42:0) after 8m10s
[07:38:37.209] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:42:0)
[07:38:37.246] Build status: STARTED
[07:38:37.246] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:43:0) after 4m14s
[07:42:51.270] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:43:0)
[07:42:51.305] Build status: STARTED
[07:42:51.305] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:44:0) after 2m55s
[07:45:46.343] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:44:0)
[07:45:46.382] Build status: STARTED
[07:45:46.382] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:45:0) after 1m16s
[07:47:02.483] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:45:0)
[07:47:02.577] Build status: STARTED
[07:47:02.577] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:46:0) after 1m37s
[07:48:39.826] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:46:0)
[07:48:39.893] Build status: STARTED
[07:48:39.893] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:47:0) after 5m48s
[07:54:27.939] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:47:0)
[07:54:27.962] Build status: STARTED
[07:54:27.962] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:48:0) after 6m10s
[08:00:38.023] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:48:0)
[08:00:38.047] Build status: STARTED
[08:00:38.047] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:49:0) after 7m40s
[08:08:18.072] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:49:0)
[08:08:18.101] Build status: STARTED
[08:08:18.101] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:50:0) after 5m48s
[08:14:06.114] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:50:0)
[08:14:06.133] Build status: STARTED
[08:14:06.133] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:51:0) after 5m39s
[08:19:45.173] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:51:0)
[08:19:45.195] Build status: STARTED
[08:19:45.195] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:52:0) after 7m9s
[08:26:54.221] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:52:0)
[08:26:54.260] Build status: STARTED
[08:26:54.260] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:53:0) after 7m52s
[08:34:46.366] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:53:0)
[08:34:46.392] Build status: STARTED
[08:34:46.392] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:54:0) after 2m3s
[08:36:49.517] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:54:0)
[08:36:49.549] Build status: STARTED
[08:36:49.549] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:55:0) after 5m59s
[08:42:48.633] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:55:0)
[08:42:48.656] Build status: STARTED
[08:42:48.656] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:56:0) after 8m41s
[08:51:29.685] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:56:0)
[08:51:29.722] Build status: STARTED
[08:51:29.722] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:57:0) after 9m35s
[09:01:04.865] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:57:0)
[09:01:04.897] Build status: STARTED
[09:01:04.897] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:58:0) after 6m19s
[09:07:23.928] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:58:0)
[09:07:23.956] Build status: STARTED
[09:07:23.956] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:59:0) after 2m8s
[09:09:32.025] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:59:0)
[09:09:32.048] Build status: STARTED
[09:09:32.048] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:60:0) after 7m12s
[09:16:44.138] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:60:0)
[09:16:44.270] Build status: STARTED
[09:16:44.270] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:61:0) after 4m20s
[09:21:04.307] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:61:0)
[09:21:04.329] Build status: STARTED
[09:21:04.329] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:62:0) after 1m45s
[09:22:49.645] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:62:0)
[09:22:49.691] Build status: STARTED
[09:22:49.691] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:63:0) after 5m34s
[09:28:23.716] Handling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:63:0)
[09:28:23.737] Build status: STARTED
[09:28:23.737] Scheduling timer "check-buildbucket-build-status" (chromium/Android arm64 Builder (dbg):8988803221848293216:64:0) after 6m4s
[09:29:10.839] Received PubSub notification, asking Buildbucket for the build status
[09:29:10.857] Build:
{
"id": "8823373159839358705",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Android arm64 Builder (dbg)"
},
"number": 46125,
"createdBy": "project:chromium",
"createTime": "2022-02-02T03:30:55.600566311Z",
"startTime": "2022-02-02T03:31:06.483590Z",
"endTime": "2022-02-02T09:29:09.298704844Z",
"updateTime": "2022-02-02T09:29:09.298704844Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d3edcf4b13d7b0e256439ef4beafc07d5fd325d6",
"ref": "refs/heads/main"
}
}
}
[09:29:10.857] Invocation finished in 5h58m16.556227094s with status SUCCEEDED