[04:00:10.269] New invocation is queued and will start shortly
[04:00:11.664] Starting the invocation (attempt 1)
[04:00:11.707] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cb5f19ffad6061d918c0f69c090607f68a518d20"
[04:00:11.707] Popped gitiles commit info from properties and tags
[04:00:11.707] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:00:11.707] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:00:11.707] Buildbucket request:
{
"requestId": "8933899618956489536",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-code-coverage"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "cb5f19ffad6061d918c0f69c090607f68a518d20",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8933899618956489536"
},
{
"key": "scheduler_job_id",
"value": "chromium/fuchsia-code-coverage"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[04:00:12.544] Scheduled build:
{
"id": "8768469556611851009",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-code-coverage"
},
"number": 2751,
"createdBy": "project:chromium",
"createTime": "2023-10-01T04:00:11.877577079Z",
"updateTime": "2023-10-01T04:00:11.877577079Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "cb5f19ffad6061d918c0f69c090607f68a518d20",
"ref": "refs/heads/main"
}
}
}
[04:00:12.544] Task URL: https://cr-buildbucket.appspot.com/build/8768469556611851009
[04:00:12.544] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:2:0) after 1m10s
[04:00:13.575] Received PubSub notification, asking Buildbucket for the build status
[04:00:13.647] Build status: SCHEDULED
[04:01:22.583] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:2:0)
[04:01:22.604] Build status: SCHEDULED
[04:01:22.604] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:4:0) after 5m23s
[04:06:45.598] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:4:0)
[04:06:45.632] Build status: SCHEDULED
[04:06:45.632] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:5:0) after 9m39s
[04:16:24.616] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:5:0)
[04:16:24.632] Build status: SCHEDULED
[04:16:24.632] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:6:0) after 4m18s
[04:20:42.608] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:6:0)
[04:20:42.625] Build status: SCHEDULED
[04:20:42.625] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:7:0) after 3m58s
[04:24:40.701] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:7:0)
[04:24:40.720] Build status: SCHEDULED
[04:24:40.720] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:8:0) after 3m20s
[04:28:01.397] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:8:0)
[04:28:01.413] Build status: SCHEDULED
[04:28:01.413] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:9:0) after 7m14s
[04:35:15.390] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:9:0)
[04:35:15.408] Build status: SCHEDULED
[04:35:15.408] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:10:0) after 7m21s
[04:42:36.428] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:10:0)
[04:42:36.451] Build status: SCHEDULED
[04:42:36.451] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:11:0) after 4m25s
[04:47:01.645] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:11:0)
[04:47:01.678] Build status: SCHEDULED
[04:47:01.678] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:12:0) after 3m37s
[04:50:38.856] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:12:0)
[04:50:38.884] Build status: SCHEDULED
[04:50:38.884] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:13:0) after 2m21s
[04:52:59.902] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:13:0)
[04:52:59.949] Build status: SCHEDULED
[04:52:59.949] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:14:0) after 5m22s
[04:58:22.175] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:14:0)
[04:58:22.195] Build status: SCHEDULED
[04:58:22.195] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:15:0) after 9m30s
[05:07:52.546] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:15:0)
[05:07:52.587] Build status: SCHEDULED
[05:07:52.587] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:16:0) after 7m41s
[05:15:33.676] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:16:0)
[05:15:33.765] Build status: SCHEDULED
[05:15:33.765] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:17:0) after 7m51s
[05:22:34.878] Received PubSub notification, asking Buildbucket for the build status
[05:22:34.904] Build status: STARTED
[05:23:25.211] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:17:0)
[05:23:25.233] Build status: STARTED
[05:23:25.233] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:19:0) after 1m39s
[05:25:04.479] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:19:0)
[05:25:04.514] Build status: STARTED
[05:25:04.514] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:20:0) after 4m37s
[05:29:42.326] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:20:0)
[05:29:42.343] Build status: STARTED
[05:29:42.344] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:21:0) after 8m40s
[05:38:23.195] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:21:0)
[05:38:23.212] Build status: STARTED
[05:38:23.212] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:22:0) after 8m23s
[05:46:46.485] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:22:0)
[05:46:46.562] Build status: STARTED
[05:46:46.562] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:23:0) after 6m30s
[05:53:17.233] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:23:0)
[05:53:17.259] Build status: STARTED
[05:53:17.259] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:24:0) after 3m45s
[05:57:03.173] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:24:0)
[05:57:03.197] Build status: STARTED
[05:57:03.197] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:25:0) after 3m36s
[06:00:39.602] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:25:0)
[06:00:39.645] Build status: STARTED
[06:00:39.645] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:26:0) after 4m39s
[06:05:18.673] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:26:0)
[06:05:18.693] Build status: STARTED
[06:05:18.693] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:27:0) after 7m15s
[06:12:33.811] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:27:0)
[06:12:33.831] Build status: STARTED
[06:12:33.831] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:28:0) after 2m5s
[06:14:38.838] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:28:0)
[06:14:38.859] Build status: STARTED
[06:14:38.859] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:29:0) after 8m20s
[06:22:58.895] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:29:0)
[06:22:58.919] Build status: STARTED
[06:22:58.919] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:30:0) after 9m38s
[06:32:36.985] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:30:0)
[06:32:37.004] Build status: STARTED
[06:32:37.004] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:31:0) after 4m22s
[06:36:59.165] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:31:0)
[06:36:59.188] Build status: STARTED
[06:36:59.188] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:32:0) after 2m35s
[06:39:34.209] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:32:0)
[06:39:34.236] Build status: STARTED
[06:39:34.236] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:33:0) after 7m17s
[06:46:51.256] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:33:0)
[06:46:51.280] Build status: STARTED
[06:46:51.280] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:34:0) after 8m50s
[06:55:41.395] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:34:0)
[06:55:41.469] Build status: STARTED
[06:55:41.470] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:35:0) after 6m59s
[07:02:40.508] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:35:0)
[07:02:40.527] Build status: STARTED
[07:02:40.527] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:36:0) after 2m23s
[07:05:04.343] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:36:0)
[07:05:04.365] Build status: STARTED
[07:05:04.365] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:37:0) after 1m3s
[07:06:07.381] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:37:0)
[07:06:07.509] Build status: STARTED
[07:06:07.509] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:38:0) after 3m45s
[07:09:52.561] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:38:0)
[07:09:52.584] Build status: STARTED
[07:09:52.584] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:39:0) after 1m42s
[07:11:34.604] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:39:0)
[07:11:34.628] Build status: STARTED
[07:11:34.628] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:40:0) after 4m33s
[07:16:07.649] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:40:0)
[07:16:07.685] Build status: STARTED
[07:16:07.685] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:41:0) after 6m14s
[07:22:22.034] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:41:0)
[07:22:22.055] Build status: STARTED
[07:22:22.055] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:42:0) after 6m28s
[07:28:50.252] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:42:0)
[07:28:50.271] Build status: STARTED
[07:28:50.271] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:43:0) after 2m44s
[07:31:34.398] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:43:0)
[07:31:34.425] Build status: STARTED
[07:31:34.425] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:44:0) after 1m53s
[07:33:27.444] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:44:0)
[07:33:27.464] Build status: STARTED
[07:33:27.464] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:45:0) after 9m59s
[07:43:26.685] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:45:0)
[07:43:26.702] Build status: STARTED
[07:43:26.702] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:46:0) after 9m1s
[07:52:27.802] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:46:0)
[07:52:27.819] Build status: STARTED
[07:52:27.819] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:47:0) after 3m49s
[07:56:16.931] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:47:0)
[07:56:16.951] Build status: STARTED
[07:56:16.951] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:48:0) after 2m51s
[07:59:07.980] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:48:0)
[07:59:08.001] Build status: STARTED
[07:59:08.001] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:49:0) after 2m6s
[08:01:14.094] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:49:0)
[08:01:14.114] Build status: STARTED
[08:01:14.114] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:50:0) after 3m18s
[08:04:32.139] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:50:0)
[08:04:32.156] Build status: STARTED
[08:04:32.156] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:51:0) after 7m12s
[08:11:44.384] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:51:0)
[08:11:44.443] Build status: STARTED
[08:11:44.443] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:52:0) after 5m54s
[08:17:38.425] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:52:0)
[08:17:38.449] Build status: STARTED
[08:17:38.449] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:53:0) after 8m11s
[08:25:49.526] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:53:0)
[08:25:49.547] Build status: STARTED
[08:25:49.547] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:54:0) after 8m36s
[08:34:25.547] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:54:0)
[08:34:25.564] Build status: STARTED
[08:34:25.564] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:55:0) after 9m49s
[08:44:14.573] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:55:0)
[08:44:14.597] Build status: STARTED
[08:44:14.597] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:56:0) after 4m44s
[08:48:58.609] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:56:0)
[08:48:58.666] Build status: STARTED
[08:48:58.666] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:57:0) after 9m19s
[08:58:17.687] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:57:0)
[08:58:17.707] Build status: STARTED
[08:58:17.707] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:58:0) after 2m9s
[09:00:26.727] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:58:0)
[09:00:26.748] Build status: STARTED
[09:00:26.748] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:59:0) after 5m21s
[09:05:47.805] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:59:0)
[09:05:47.828] Build status: STARTED
[09:05:47.828] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:60:0) after 2m44s
[09:08:31.915] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:60:0)
[09:08:31.933] Build status: STARTED
[09:08:31.933] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:61:0) after 4m20s
[09:12:52.007] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:61:0)
[09:12:52.029] Build status: STARTED
[09:12:52.029] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:62:0) after 9m32s
[09:22:24.234] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:62:0)
[09:22:24.289] Build status: STARTED
[09:22:24.289] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-code-coverage:8933899618956489536:63:0) after 8m10s
[09:29:18.894] Received PubSub notification, asking Buildbucket for the build status
[09:29:18.925] Build:
{
"id": "8768469556611851009",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-code-coverage"
},
"number": 2751,
"createdBy": "project:chromium",
"createTime": "2023-10-01T04:00:11.877577079Z",
"startTime": "2023-10-01T05:22:34.163558Z",
"endTime": "2023-10-01T09:29:18.494224399Z",
"updateTime": "2023-10-01T09:29:18.494224399Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "cb5f19ffad6061d918c0f69c090607f68a518d20",
"ref": "refs/heads/main"
}
}
}
[09:29:18.925] Invocation finished in 5h29m8.667687971s with status FAILED