[02:20:31.543] New invocation is queued and will start shortly
[02:20:32.779] Starting the invocation (attempt 1)
[02:20:32.848] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6d9e35289de7b278da5724af7aa1675664c60aa6"
[02:20:32.848] Popped gitiles commit info from properties and tags
[02:20:32.848] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:20:32.849] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:20:32.849] Buildbucket request:
{
"requestId": "8979385565241788096",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid64"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6d9e35289de7b278da5724af7aa1675664c60aa6",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8979385565241788096"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTAndroid64"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[02:20:33.433] Scheduled build:
{
"id": "8813955503104805025",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid64"
},
"number": 13845,
"createdBy": "project:chromium",
"createTime": "2022-05-17T02:20:32.949672478Z",
"updateTime": "2022-05-17T02:20:32.949672478Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6d9e35289de7b278da5724af7aa1675664c60aa6",
"ref": "refs/heads/main"
}
}
}
[02:20:33.433] Task URL: https://cr-buildbucket.appspot.com/build/8813955503104805025
[02:20:33.433] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:2:0) after 5m29s
[02:21:57.582] Received PubSub notification, asking Buildbucket for the build status
[02:21:57.603] Build status: STARTED
[02:26:02.555] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:2:0)
[02:26:02.584] Build status: STARTED
[02:26:02.584] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:4:0) after 1m27s
[02:27:29.608] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:4:0)
[02:27:29.633] Build status: STARTED
[02:27:29.633] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:5:0) after 9m22s
[02:36:51.747] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:5:0)
[02:36:51.768] Build status: STARTED
[02:36:51.768] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:6:0) after 7m55s
[02:44:46.941] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:6:0)
[02:44:46.961] Build status: STARTED
[02:44:46.961] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:7:0) after 8m57s
[02:53:44.037] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:7:0)
[02:53:44.077] Build status: STARTED
[02:53:44.077] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:8:0) after 7m48s
[03:01:32.160] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:8:0)
[03:01:32.181] Build status: STARTED
[03:01:32.181] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:9:0) after 7m46s
[03:09:18.322] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:9:0)
[03:09:18.345] Build status: STARTED
[03:09:18.345] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:10:0) after 4m8s
[03:13:26.504] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:10:0)
[03:13:26.525] Build status: STARTED
[03:13:26.525] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:11:0) after 8m59s
[03:22:25.555] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:11:0)
[03:22:25.577] Build status: STARTED
[03:22:25.577] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:12:0) after 4m56s
[03:27:22.026] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:12:0)
[03:27:22.058] Build status: STARTED
[03:27:22.058] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:13:0) after 7m15s
[03:34:37.121] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:13:0)
[03:34:37.148] Build status: STARTED
[03:34:37.148] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:14:0) after 4m21s
[03:38:58.171] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:14:0)
[03:38:58.198] Build status: STARTED
[03:38:58.198] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:15:0) after 9m0s
[03:47:58.329] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:15:0)
[03:47:58.354] Build status: STARTED
[03:47:58.354] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:16:0) after 4m58s
[03:52:56.488] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:16:0)
[03:52:56.516] Build status: STARTED
[03:52:56.516] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:17:0) after 9m48s
[04:02:44.553] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:17:0)
[04:02:44.585] Build status: STARTED
[04:02:44.585] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:18:0) after 5m32s
[04:08:16.605] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:18:0)
[04:08:16.627] Build status: STARTED
[04:08:16.627] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:19:0) after 2m2s
[04:10:18.673] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:19:0)
[04:10:18.695] Build status: STARTED
[04:10:18.695] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:20:0) after 1m40s
[04:11:58.711] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:20:0)
[04:11:58.733] Build status: STARTED
[04:11:58.733] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:21:0) after 1m2s
[04:13:00.783] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:21:0)
[04:13:00.810] Build status: STARTED
[04:13:00.810] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:22:0) after 7m14s
[04:20:14.837] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:22:0)
[04:20:14.865] Build status: STARTED
[04:20:14.865] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:23:0) after 4m44s
[04:24:58.893] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:23:0)
[04:24:58.991] Build status: STARTED
[04:24:58.991] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:24:0) after 5m36s
[04:30:35.019] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:24:0)
[04:30:35.051] Build status: STARTED
[04:30:35.051] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:25:0) after 7m8s
[04:37:43.058] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:25:0)
[04:37:43.099] Build status: STARTED
[04:37:43.099] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:26:0) after 2m51s
[04:40:34.127] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:26:0)
[04:40:34.166] Build status: STARTED
[04:40:34.166] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:27:0) after 3m11s
[04:43:45.189] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:27:0)
[04:43:45.211] Build status: STARTED
[04:43:45.211] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:28:0) after 3m12s
[04:46:57.356] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:28:0)
[04:46:57.377] Build status: STARTED
[04:46:57.378] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:29:0) after 6m34s
[04:53:31.399] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:29:0)
[04:53:31.420] Build status: STARTED
[04:53:31.420] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:30:0) after 2m18s
[04:55:49.441] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:30:0)
[04:55:49.465] Build status: STARTED
[04:55:49.465] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:31:0) after 5m55s
[05:01:44.595] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:31:0)
[05:01:44.616] Build status: STARTED
[05:01:44.617] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:32:0) after 9m37s
[05:11:21.640] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:32:0)
[05:11:21.953] Build status: STARTED
[05:11:21.953] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:33:0) after 6m57s
[05:18:19.025] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:33:0)
[05:18:19.052] Build status: STARTED
[05:18:19.052] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:34:0) after 7m54s
[05:26:13.076] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:34:0)
[05:26:13.102] Build status: STARTED
[05:26:13.102] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:35:0) after 2m2s
[05:28:15.352] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:35:0)
[05:28:15.390] Build status: STARTED
[05:28:15.390] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:36:0) after 9m52s
[05:38:07.433] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:36:0)
[05:38:07.458] Build status: STARTED
[05:38:07.458] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:37:0) after 4m32s
[05:42:39.483] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:37:0)
[05:42:39.513] Build status: STARTED
[05:42:39.513] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:38:0) after 2m51s
[05:45:30.594] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:38:0)
[05:45:30.613] Build status: STARTED
[05:45:30.613] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:39:0) after 9m37s
[05:55:07.735] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:39:0)
[05:55:07.774] Build status: STARTED
[05:55:07.774] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:40:0) after 6m31s
[06:01:38.879] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:40:0)
[06:01:39.229] Build status: STARTED
[06:01:39.229] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:41:0) after 7m50s
[06:09:29.401] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:41:0)
[06:09:29.438] Build status: STARTED
[06:09:29.438] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:42:0) after 1m49s
[06:11:18.559] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:42:0)
[06:11:18.578] Build status: STARTED
[06:11:18.578] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:43:0) after 6m36s
[06:17:54.825] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:43:0)
[06:17:54.860] Build status: STARTED
[06:17:54.860] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:44:0) after 5m12s
[06:23:06.884] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:44:0)
[06:23:06.903] Build status: STARTED
[06:23:06.903] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:45:0) after 4m21s
[06:27:27.985] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:45:0)
[06:27:28.004] Build status: STARTED
[06:27:28.004] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:46:0) after 7m28s
[06:34:56.029] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:46:0)
[06:34:56.051] Build status: STARTED
[06:34:56.051] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:47:0) after 6m11s
[06:41:07.205] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:47:0)
[06:41:07.226] Build status: STARTED
[06:41:07.226] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:48:0) after 6m0s
[06:47:07.282] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:48:0)
[06:47:07.303] Build status: STARTED
[06:47:07.303] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:49:0) after 6m11s
[06:53:18.331] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:49:0)
[06:53:18.366] Build status: STARTED
[06:53:18.366] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:50:0) after 8m23s
[07:01:41.418] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:50:0)
[07:01:41.440] Build status: STARTED
[07:01:41.440] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:51:0) after 2m58s
[07:04:39.466] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:51:0)
[07:04:39.490] Build status: STARTED
[07:04:39.490] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:52:0) after 9m16s
[07:13:55.706] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:52:0)
[07:13:55.725] Build status: STARTED
[07:13:55.725] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:53:0) after 4m6s
[07:18:01.803] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:53:0)
[07:18:01.831] Build status: STARTED
[07:18:01.831] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:54:0) after 1m39s
[07:19:40.897] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:54:0)
[07:19:40.941] Build status: STARTED
[07:19:40.941] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:55:0) after 2m53s
[07:22:34.022] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:55:0)
[07:22:34.100] Build status: STARTED
[07:22:34.100] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:56:0) after 7m52s
[07:30:26.151] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:56:0)
[07:30:26.170] Build status: STARTED
[07:30:26.170] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:57:0) after 9m5s
[07:39:31.229] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:57:0)
[07:39:31.274] Build status: STARTED
[07:39:31.274] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:58:0) after 6m33s
[07:46:04.445] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:58:0)
[07:46:04.477] Build status: STARTED
[07:46:04.477] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:59:0) after 6m31s
[07:52:35.611] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:59:0)
[07:52:35.631] Build status: STARTED
[07:52:35.631] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:60:0) after 2m14s
[07:54:49.941] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:60:0)
[07:54:49.970] Build status: STARTED
[07:54:49.970] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:61:0) after 3m17s
[07:58:07.095] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:61:0)
[07:58:07.113] Build status: STARTED
[07:58:07.113] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:62:0) after 8m44s
[08:06:51.154] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:62:0)
[08:06:51.185] Build status: STARTED
[08:06:51.185] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:63:0) after 1m3s
[08:07:54.516] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:63:0)
[08:07:54.537] Build status: STARTED
[08:07:54.537] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:64:0) after 3m27s
[08:11:21.638] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:64:0)
[08:11:21.666] Build status: STARTED
[08:11:21.666] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:65:0) after 8m44s
[08:20:05.748] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:65:0)
[08:20:05.771] Build status: STARTED
[08:20:05.771] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:66:0) after 8m33s
[08:28:38.971] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:66:0)
[08:28:39.055] Build status: STARTED
[08:28:39.055] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:67:0) after 6m9s
[08:34:48.211] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:67:0)
[08:34:48.251] Build status: STARTED
[08:34:48.251] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid64:8979385565241788096:68:0) after 7m22s
[08:39:43.148] Received PubSub notification, asking Buildbucket for the build status
[08:39:43.207] Build:
{
"id": "8813955503104805025",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid64"
},
"number": 13845,
"createdBy": "project:chromium",
"createTime": "2022-05-17T02:20:32.949672478Z",
"startTime": "2022-05-17T02:21:53.491661Z",
"endTime": "2022-05-17T08:39:42.724359130Z",
"updateTime": "2022-05-17T08:39:42.724359130Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6d9e35289de7b278da5724af7aa1675664c60aa6",
"ref": "refs/heads/main"
}
}
}
[08:39:43.207] Invocation finished in 6h19m11.679649478s with status SUCCEEDED