[02:57:20.774] New invocation is queued and will start shortly
[02:57:21.895] Starting the invocation (attempt 1)
[02:57:21.930] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/87032c5d5687438abb8bd84f8e4d32b2338bcc4f"
[02:57:21.930] Popped gitiles commit info from properties and tags
[02:57:21.930] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:57:21.930] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:57:21.930] Buildbucket request:
{
"requestId": "8985815633316925408",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "87032c5d5687438abb8bd84f8e4d32b2338bcc4f",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8985815633316925408"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTAndroid"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[02:57:22.549] Scheduled build:
{
"id": "8820385571357530097",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid"
},
"number": 18926,
"createdBy": "project:chromium",
"createTime": "2022-03-07T02:57:22.004232208Z",
"updateTime": "2022-03-07T02:57:22.004232208Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "87032c5d5687438abb8bd84f8e4d32b2338bcc4f",
"ref": "refs/heads/main"
}
}
}
[02:57:22.549] Task URL: https://cr-buildbucket.appspot.com/build/8820385571357530097
[02:57:22.549] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:2:0) after 9m3s
[02:58:30.998] Received PubSub notification, asking Buildbucket for the build status
[02:58:31.018] Build status: STARTED
[03:06:25.579] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:2:0)
[03:06:25.606] Build status: STARTED
[03:06:25.606] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:4:0) after 8m45s
[03:15:10.722] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:4:0)
[03:15:10.766] Build status: STARTED
[03:15:10.766] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:5:0) after 6m30s
[03:21:40.784] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:5:0)
[03:21:40.817] Build status: STARTED
[03:21:40.817] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:6:0) after 4m48s
[03:26:28.887] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:6:0)
[03:26:28.913] Build status: STARTED
[03:26:28.913] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:7:0) after 7m20s
[03:33:49.064] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:7:0)
[03:33:49.099] Build status: STARTED
[03:33:49.099] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:8:0) after 6m7s
[03:39:56.115] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:8:0)
[03:39:56.141] Build status: STARTED
[03:39:56.141] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:9:0) after 4m25s
[03:44:21.319] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:9:0)
[03:44:21.338] Build status: STARTED
[03:44:21.338] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:10:0) after 9m2s
[03:53:23.581] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:10:0)
[03:53:23.601] Build status: STARTED
[03:53:23.601] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:11:0) after 6m6s
[03:59:29.620] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:11:0)
[03:59:29.639] Build status: STARTED
[03:59:29.639] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:12:0) after 5m27s
[04:04:56.618] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:12:0)
[04:04:56.650] Build status: STARTED
[04:04:56.650] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:13:0) after 5m44s
[04:10:40.672] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:13:0)
[04:10:40.709] Build status: STARTED
[04:10:40.709] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:14:0) after 9m5s
[04:19:45.733] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:14:0)
[04:19:46.983] Build status: STARTED
[04:19:46.983] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:15:0) after 9m57s
[04:29:44.004] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:15:0)
[04:29:44.037] Build status: STARTED
[04:29:44.037] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:16:0) after 5m6s
[04:34:50.159] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:16:0)
[04:34:50.204] Build status: STARTED
[04:34:50.205] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:17:0) after 7m0s
[04:41:50.270] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:17:0)
[04:41:50.292] Build status: STARTED
[04:41:50.292] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:18:0) after 7m21s
[04:49:11.314] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:18:0)
[04:49:11.342] Build status: STARTED
[04:49:11.342] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:19:0) after 9m1s
[04:58:12.461] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:19:0)
[04:58:12.483] Build status: STARTED
[04:58:12.483] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:20:0) after 9m21s
[05:07:33.491] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:20:0)
[05:07:33.519] Build status: STARTED
[05:07:33.519] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:21:0) after 4m55s
[05:12:28.612] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:21:0)
[05:12:28.632] Build status: STARTED
[05:12:28.632] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:22:0) after 4m36s
[05:17:04.652] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:22:0)
[05:17:04.685] Build status: STARTED
[05:17:04.685] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:23:0) after 3m23s
[05:20:28.001] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:23:0)
[05:20:28.068] Build status: STARTED
[05:20:28.068] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:24:0) after 9m6s
[05:29:34.094] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:24:0)
[05:29:34.114] Build status: STARTED
[05:29:34.114] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:25:0) after 9m21s
[05:38:55.151] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:25:0)
[05:38:55.186] Build status: STARTED
[05:38:55.186] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:26:0) after 6m39s
[05:45:34.203] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:26:0)
[05:45:34.272] Build status: STARTED
[05:45:34.272] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:27:0) after 7m37s
[05:53:11.366] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:27:0)
[05:53:11.386] Build status: STARTED
[05:53:11.386] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:28:0) after 2m14s
[05:55:25.637] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:28:0)
[05:55:25.661] Build status: STARTED
[05:55:25.661] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:29:0) after 2m34s
[05:57:59.703] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:29:0)
[05:57:59.724] Build status: STARTED
[05:57:59.724] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:30:0) after 7m55s
[06:05:54.948] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:30:0)
[06:05:54.979] Build status: STARTED
[06:05:54.979] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:31:0) after 8m35s
[06:14:29.997] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:31:0)
[06:14:30.031] Build status: STARTED
[06:14:30.031] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:32:0) after 4m16s
[06:18:46.126] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:32:0)
[06:18:46.183] Build status: STARTED
[06:18:46.183] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:33:0) after 2m1s
[06:20:47.204] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:33:0)
[06:20:47.239] Build status: STARTED
[06:20:47.239] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:34:0) after 9m29s
[06:30:16.333] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:34:0)
[06:30:16.353] Build status: STARTED
[06:30:16.353] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:35:0) after 4m24s
[06:34:40.484] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:35:0)
[06:34:40.522] Build status: STARTED
[06:34:40.522] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:36:0) after 6m31s
[06:41:11.716] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:36:0)
[06:41:11.737] Build status: STARTED
[06:41:11.738] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:37:0) after 9m18s
[06:50:29.851] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:37:0)
[06:50:29.880] Build status: STARTED
[06:50:29.880] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:38:0) after 4m28s
[06:54:57.927] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:38:0)
[06:54:57.960] Build status: STARTED
[06:54:57.960] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:39:0) after 2m0s
[06:56:58.039] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:39:0)
[06:56:58.083] Build status: STARTED
[06:56:58.083] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:40:0) after 9m43s
[07:06:41.110] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:40:0)
[07:06:41.138] Build status: STARTED
[07:06:41.138] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:41:0) after 8m40s
[07:15:21.394] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:41:0)
[07:15:21.451] Build status: STARTED
[07:15:21.451] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:42:0) after 1m26s
[07:16:47.506] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:42:0)
[07:16:47.534] Build status: STARTED
[07:16:47.534] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:43:0) after 2m46s
[07:19:33.554] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:43:0)
[07:19:33.576] Build status: STARTED
[07:19:33.576] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:44:0) after 1m21s
[07:20:54.606] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:44:0)
[07:20:54.628] Build status: STARTED
[07:20:54.628] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:45:0) after 2m25s
[07:23:19.676] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:45:0)
[07:23:19.709] Build status: STARTED
[07:23:19.709] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:46:0) after 5m51s
[07:29:10.842] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:46:0)
[07:29:10.876] Build status: STARTED
[07:29:10.876] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:47:0) after 5m14s
[07:34:25.104] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:47:0)
[07:34:25.140] Build status: STARTED
[07:34:25.140] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:48:0) after 6m34s
[07:40:59.158] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:48:0)
[07:40:59.200] Build status: STARTED
[07:40:59.200] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:49:0) after 1m16s
[07:42:15.321] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:49:0)
[07:42:15.339] Build status: STARTED
[07:42:15.339] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:8985815633316925408:50:0) after 7m46s
[07:47:04.336] Received PubSub notification, asking Buildbucket for the build status
[07:47:04.361] Build:
{
"id": "8820385571357530097",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid"
},
"number": 18926,
"createdBy": "project:chromium",
"createTime": "2022-03-07T02:57:22.004232208Z",
"startTime": "2022-03-07T02:58:29.340171Z",
"endTime": "2022-03-07T07:47:03.779750970Z",
"updateTime": "2022-03-07T07:47:03.779750970Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "87032c5d5687438abb8bd84f8e4d32b2338bcc4f",
"ref": "refs/heads/main"
}
}
}
[07:47:04.361] Invocation finished in 4h49m43.610447045s with status SUCCEEDED