[19:55:12.559] New invocation is queued and will start shortly
[19:55:13.677] Starting the invocation (attempt 1)
[19:55:13.718] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/82fe4ed8a79e5d70504ef7b24e4a1dfe6ecb3bab"
[19:55:13.718] Popped gitiles commit info from properties and tags
[19:55:13.718] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:55:13.718] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:55:13.718] Buildbucket request:
{
"requestId": "8983758461641335456",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-code-coverage-native"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "82fe4ed8a79e5d70504ef7b24e4a1dfe6ecb3bab",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8983758461641335456"
},
{
"key": "scheduler_job_id",
"value": "chromium/android-code-coverage-native"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:55:14.260] Scheduled build:
{
"id": "8818328399679068289",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-code-coverage-native"
},
"number": 11300,
"createdBy": "project:chromium",
"createTime": "2022-03-29T19:55:13.798975065Z",
"updateTime": "2022-03-29T19:55:13.798975065Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "82fe4ed8a79e5d70504ef7b24e4a1dfe6ecb3bab",
"ref": "refs/heads/main"
}
}
}
[19:55:14.260] Task URL: https://cr-buildbucket.appspot.com/build/8818328399679068289
[19:55:14.260] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:2:0) after 9m12s
[20:04:26.311] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:2:0)
[20:04:26.336] Build status: SCHEDULED
[20:04:26.336] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:3:0) after 3m59s
[20:08:25.852] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:3:0)
[20:08:25.884] Build status: SCHEDULED
[20:08:25.884] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:4:0) after 1m46s
[20:10:12.081] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:4:0)
[20:10:12.116] Build status: SCHEDULED
[20:10:12.116] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:5:0) after 2m57s
[20:13:09.139] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:5:0)
[20:13:09.174] Build status: SCHEDULED
[20:13:09.174] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:6:0) after 9m14s
[20:22:23.162] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:6:0)
[20:22:23.183] Build status: SCHEDULED
[20:22:23.183] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:7:0) after 8m56s
[20:31:19.530] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:7:0)
[20:31:19.570] Build status: SCHEDULED
[20:31:19.570] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:8:0) after 2m9s
[20:33:28.592] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:8:0)
[20:33:28.617] Build status: SCHEDULED
[20:33:28.617] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:9:0) after 5m11s
[20:38:39.638] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:9:0)
[20:38:39.658] Build status: SCHEDULED
[20:38:39.658] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:10:0) after 4m39s
[20:43:18.771] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:10:0)
[20:43:18.793] Build status: SCHEDULED
[20:43:18.793] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:11:0) after 9m55s
[20:46:25.913] Received PubSub notification, asking Buildbucket for the build status
[20:46:25.931] Build status: STARTED
[20:53:13.820] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:11:0)
[20:53:13.841] Build status: STARTED
[20:53:13.841] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:13:0) after 1m4s
[20:54:18.205] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:13:0)
[20:54:18.224] Build status: STARTED
[20:54:18.224] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:14:0) after 2m18s
[20:56:36.710] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:14:0)
[20:56:36.731] Build status: STARTED
[20:56:36.731] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:15:0) after 1m50s
[20:58:27.043] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:15:0)
[20:58:27.084] Build status: STARTED
[20:58:27.084] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:16:0) after 7m51s
[21:06:18.155] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:16:0)
[21:06:18.177] Build status: STARTED
[21:06:18.177] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:17:0) after 1m49s
[21:08:07.293] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:17:0)
[21:08:07.325] Build status: STARTED
[21:08:07.325] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:18:0) after 4m25s
[21:12:32.411] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:18:0)
[21:12:32.445] Build status: STARTED
[21:12:32.445] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:19:0) after 4m18s
[21:16:50.468] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:19:0)
[21:16:50.536] Build status: STARTED
[21:16:50.536] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:20:0) after 4m32s
[21:21:22.796] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:20:0)
[21:21:22.840] Build status: STARTED
[21:21:22.840] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:21:0) after 1m30s
[21:22:53.035] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:21:0)
[21:22:53.064] Build status: STARTED
[21:22:53.064] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:22:0) after 7m55s
[21:30:48.156] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:22:0)
[21:30:48.190] Build status: STARTED
[21:30:48.190] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:23:0) after 2m57s
[21:33:45.522] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:23:0)
[21:33:45.541] Build status: STARTED
[21:33:45.541] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:24:0) after 9m4s
[21:42:49.645] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:24:0)
[21:42:49.666] Build status: STARTED
[21:42:49.666] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:25:0) after 4m43s
[21:47:32.748] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:25:0)
[21:47:32.791] Build status: STARTED
[21:47:32.791] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:26:0) after 3m44s
[21:51:16.823] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:26:0)
[21:51:16.859] Build status: STARTED
[21:51:16.859] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:27:0) after 2m36s
[21:53:53.108] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:27:0)
[21:53:53.131] Build status: STARTED
[21:53:53.131] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:28:0) after 7m19s
[22:01:12.293] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:28:0)
[22:01:12.314] Build status: STARTED
[22:01:12.314] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:29:0) after 6m31s
[22:07:43.418] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:29:0)
[22:07:43.454] Build status: STARTED
[22:07:43.454] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:30:0) after 9m11s
[22:16:54.479] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:30:0)
[22:16:54.510] Build status: STARTED
[22:16:54.510] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:31:0) after 9m49s
[22:26:43.799] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:31:0)
[22:26:43.849] Build status: STARTED
[22:26:43.849] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:32:0) after 5m26s
[22:32:09.903] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:32:0)
[22:32:09.967] Build status: STARTED
[22:32:09.967] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:33:0) after 6m21s
[22:38:31.002] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:33:0)
[22:38:31.054] Build status: STARTED
[22:38:31.054] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:34:0) after 6m17s
[22:44:48.100] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:34:0)
[22:44:48.121] Build status: STARTED
[22:44:48.121] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:35:0) after 3m54s
[22:48:42.161] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:35:0)
[22:48:42.186] Build status: STARTED
[22:48:42.186] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:36:0) after 6m54s
[22:55:36.313] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:36:0)
[22:55:36.335] Build status: STARTED
[22:55:36.335] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:37:0) after 4m27s
[23:00:03.362] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:37:0)
[23:00:03.410] Build status: STARTED
[23:00:03.410] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:38:0) after 9m36s
[23:09:39.399] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:38:0)
[23:09:39.419] Build status: STARTED
[23:09:39.419] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:39:0) after 4m49s
[23:14:28.410] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:39:0)
[23:14:28.430] Build status: STARTED
[23:14:28.430] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:40:0) after 4m29s
[23:18:57.454] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:40:0)
[23:18:57.491] Build status: STARTED
[23:18:57.491] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:41:0) after 6m59s
[23:25:56.538] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:41:0)
[23:25:56.561] Build status: STARTED
[23:25:56.561] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:42:0) after 3m36s
[23:29:32.585] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:42:0)
[23:29:32.608] Build status: STARTED
[23:29:32.608] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:43:0) after 2m2s
[23:31:34.896] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:43:0)
[23:31:34.916] Build status: STARTED
[23:31:34.916] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:44:0) after 9m34s
[23:41:08.936] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:44:0)
[23:41:08.960] Build status: STARTED
[23:41:08.960] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:45:0) after 7m38s
[23:48:46.986] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:45:0)
[23:48:47.006] Build status: STARTED
[23:48:47.006] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:46:0) after 4m23s
[23:53:10.036] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:46:0)
[23:53:10.075] Build status: STARTED
[23:53:10.075] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:47:0) after 8m52s
[00:02:02.177] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:47:0)
[00:02:02.204] Build status: STARTED
[00:02:02.204] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:48:0) after 4m12s
[00:06:14.250] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:48:0)
[00:06:14.278] Build status: STARTED
[00:06:14.278] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:49:0) after 4m59s
[00:11:13.394] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:49:0)
[00:11:13.414] Build status: STARTED
[00:11:13.414] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:50:0) after 9m51s
[00:21:04.446] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:50:0)
[00:21:04.469] Build status: STARTED
[00:21:04.469] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:51:0) after 3m3s
[00:24:08.133] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:51:0)
[00:24:08.155] Build status: STARTED
[00:24:08.155] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:52:0) after 8m54s
[00:33:02.499] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:52:0)
[00:33:02.526] Build status: STARTED
[00:33:02.526] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:53:0) after 8m12s
[00:41:14.579] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:53:0)
[00:41:14.603] Build status: STARTED
[00:41:14.603] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:54:0) after 8m44s
[00:49:58.626] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:54:0)
[00:49:58.648] Build status: STARTED
[00:49:58.648] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:55:0) after 4m23s
[00:54:21.672] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:55:0)
[00:54:21.735] Build status: STARTED
[00:54:21.735] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:56:0) after 4m50s
[00:59:11.936] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:56:0)
[00:59:11.958] Build status: STARTED
[00:59:11.958] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:57:0) after 9m10s
[01:08:22.196] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:57:0)
[01:08:22.218] Build status: STARTED
[01:08:22.218] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:58:0) after 7m9s
[01:15:31.422] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:58:0)
[01:15:31.447] Build status: STARTED
[01:15:31.447] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:59:0) after 6m41s
[01:22:12.473] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:59:0)
[01:22:12.504] Build status: STARTED
[01:22:12.504] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:60:0) after 3m45s
[01:25:57.617] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:60:0)
[01:25:57.641] Build status: STARTED
[01:25:57.641] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:61:0) after 6m52s
[01:32:49.630] Handling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:61:0)
[01:32:49.783] Build status: STARTED
[01:32:49.783] Scheduling timer "check-buildbucket-build-status" (chromium/android-code-coverage-native:8983758461641335456:62:0) after 3m14s
[01:35:08.798] Received PubSub notification, asking Buildbucket for the build status
[01:35:08.817] Build:
{
"id": "8818328399679068289",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-code-coverage-native"
},
"number": 11300,
"createdBy": "project:chromium",
"createTime": "2022-03-29T19:55:13.798975065Z",
"startTime": "2022-03-29T20:46:25.312323Z",
"endTime": "2022-03-30T01:35:07.891573176Z",
"updateTime": "2022-03-30T01:35:07.891573176Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "82fe4ed8a79e5d70504ef7b24e4a1dfe6ecb3bab",
"ref": "refs/heads/main"
}
}
}
[01:35:08.817] Invocation finished in 5h39m56.273550502s with status FAILED