[04:00:06.554] New invocation is queued and will start shortly
[04:00:08.115] Starting the invocation (attempt 1)
[04:00:08.157] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6eb45da5dab877c19552f8af6edb6fd2f1a2f90a"
[04:00:08.158] Popped gitiles commit info from properties and tags
[04:00:08.158] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:00:08.158] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:00:08.158] Buildbucket request:
{
"requestId": "8915417841707952336",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-code-coverage"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6eb45da5dab877c19552f8af6edb6fd2f1a2f90a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8915417841707952336"
},
{
"key": "scheduler_job_id",
"value": "chromium/android-code-coverage"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[04:00:08.900] Scheduled build:
{
"id": "8749987779268960017",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-code-coverage"
},
"number": 7256,
"createdBy": "project:chromium",
"createTime": "2024-04-22T04:00:08.250160749Z",
"updateTime": "2024-04-22T04:00:08.250160749Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6eb45da5dab877c19552f8af6edb6fd2f1a2f90a",
"ref": "refs/heads/main"
}
}
}
[04:00:08.900] Task URL: https://cr-buildbucket.appspot.com/build/8749987779268960017
[04:00:08.900] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:2:0) after 4m14s
[04:00:09.853] Received PubSub notification, asking Buildbucket for the build status
[04:00:09.896] Build status: SCHEDULED
[04:00:31.142] Received PubSub notification, asking Buildbucket for the build status
[04:00:31.165] Build status: STARTED
[04:04:22.985] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:2:0)
[04:04:23.030] Build status: STARTED
[04:04:23.031] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:5:0) after 2m40s
[04:07:03.201] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:5:0)
[04:07:03.218] Build status: STARTED
[04:07:03.219] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:6:0) after 6m14s
[04:13:17.336] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:6:0)
[04:13:17.494] Build status: STARTED
[04:13:17.494] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:7:0) after 4m37s
[04:17:54.550] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:7:0)
[04:17:54.585] Build status: STARTED
[04:17:54.585] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:8:0) after 6m58s
[04:24:52.743] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:8:0)
[04:24:52.781] Build status: STARTED
[04:24:52.781] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:9:0) after 8m31s
[04:33:23.799] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:9:0)
[04:33:23.833] Build status: STARTED
[04:33:23.833] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:10:0) after 2m40s
[04:36:03.857] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:10:0)
[04:36:03.895] Build status: STARTED
[04:36:03.895] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:11:0) after 2m38s
[04:38:41.920] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:11:0)
[04:38:41.940] Build status: STARTED
[04:38:41.940] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:12:0) after 5m29s
[04:44:10.992] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:12:0)
[04:44:11.018] Build status: STARTED
[04:44:11.018] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:13:0) after 5m33s
[04:49:44.127] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:13:0)
[04:49:44.143] Build status: STARTED
[04:49:44.143] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:14:0) after 5m33s
[04:55:17.446] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:14:0)
[04:55:17.494] Build status: STARTED
[04:55:17.494] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:15:0) after 7m35s
[05:02:52.872] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:15:0)
[05:02:52.929] Build status: STARTED
[05:02:52.929] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:16:0) after 3m21s
[05:06:13.993] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:16:0)
[05:06:14.008] Build status: STARTED
[05:06:14.008] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:17:0) after 5m2s
[05:11:16.122] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:17:0)
[05:11:16.145] Build status: STARTED
[05:11:16.145] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:18:0) after 4m37s
[05:15:53.216] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:18:0)
[05:15:53.308] Build status: STARTED
[05:15:53.308] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:19:0) after 9m58s
[05:25:51.403] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:19:0)
[05:25:51.570] Build status: STARTED
[05:25:51.570] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:20:0) after 9m6s
[05:34:57.597] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:20:0)
[05:34:57.617] Build status: STARTED
[05:34:57.617] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:21:0) after 4m16s
[05:39:13.655] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:21:0)
[05:39:13.678] Build status: STARTED
[05:39:13.678] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:22:0) after 5m46s
[05:44:59.731] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:22:0)
[05:44:59.939] Build status: STARTED
[05:44:59.939] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:23:0) after 5m0s
[05:49:59.982] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:23:0)
[05:50:00.000] Build status: STARTED
[05:50:00.000] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:24:0) after 7m19s
[05:57:19.186] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:24:0)
[05:57:19.206] Build status: STARTED
[05:57:19.206] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:25:0) after 5m14s
[06:02:33.225] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:25:0)
[06:02:33.284] Build status: STARTED
[06:02:33.284] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:26:0) after 8m25s
[06:10:58.725] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:26:0)
[06:10:58.749] Build status: STARTED
[06:10:58.749] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:27:0) after 9m20s
[06:20:18.858] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:27:0)
[06:20:18.916] Build status: STARTED
[06:20:18.916] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:28:0) after 9m41s
[06:29:59.976] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:28:0)
[06:29:59.995] Build status: STARTED
[06:29:59.995] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:29:0) after 2m26s
[06:32:26.029] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:29:0)
[06:32:26.048] Build status: STARTED
[06:32:26.048] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:30:0) after 3m6s
[06:35:32.083] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:30:0)
[06:35:32.149] Build status: STARTED
[06:35:32.149] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:31:0) after 8m22s
[06:43:54.177] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:31:0)
[06:43:54.197] Build status: STARTED
[06:43:54.197] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:32:0) after 3m52s
[06:47:46.181] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:32:0)
[06:47:46.212] Build status: STARTED
[06:47:46.212] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:33:0) after 3m19s
[06:51:05.371] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:33:0)
[06:51:05.388] Build status: STARTED
[06:51:05.388] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:34:0) after 9m26s
[07:00:31.608] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:34:0)
[07:00:31.694] Build status: STARTED
[07:00:31.694] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:35:0) after 2m55s
[07:03:26.836] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:35:0)
[07:03:26.885] Build status: STARTED
[07:03:26.885] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage:8915417841707952336:36:0) after 5m6s
[07:06:08.547] Received PubSub notification, asking Buildbucket for the build status
[07:06:08.565] Build:
{
"id": "8749987779268960017",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-code-coverage"
},
"number": 7256,
"createdBy": "project:chromium",
"createTime": "2024-04-22T04:00:08.250160749Z",
"startTime": "2024-04-22T04:00:30.858873377Z",
"endTime": "2024-04-22T07:06:08.126249118Z",
"updateTime": "2024-04-22T07:06:08.126249118Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6eb45da5dab877c19552f8af6edb6fd2f1a2f90a",
"ref": "refs/heads/main"
}
}
}
[07:06:08.565] Invocation finished in 3h6m2.024429203s with status FAILED