[04:09:14.490] New invocation is queued and will start shortly
[04:09:14.490] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[04:09:15.572] Starting the invocation (attempt 1)
[04:09:15.599] Ignoring gitiles_ref tag without the buildset tag
[04:09:15.599] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:09:15.599] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:09:15.599] Buildbucket request:
{
"requestId": "8926470097060459984",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8926470097060459984",
"job": "infra/Build From Tarball",
"triggers": [
{
"buildbucket": {
"properties": {
"version": "120.0.6099.179"
},
"tags": [
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
},
"id": "f04b3ac5-b951-4aca-acd0-e8da4dec3c56",
"title": "publish_tarball/0"
}
]
},
"version": "120.0.6099.179"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8926470097060459984"
},
{
"key": "scheduler_job_id",
"value": "infra/Build From Tarball"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "publish_tarball"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[04:09:16.140] Scheduled build:
{
"id": "8761040035174043761",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"createdBy": "project:infra",
"createTime": "2023-12-22T04:09:15.655360050Z",
"updateTime": "2023-12-22T04:09:15.655360050Z",
"status": "SCHEDULED",
"input": {
}
}
[04:09:16.140] Task URL: https://cr-buildbucket.appspot.com/build/8761040035174043761
[04:09:16.140] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:2:0) after 5m25s
[04:09:17.048] Received PubSub notification, asking Buildbucket for the build status
[04:09:17.075] Build status: SCHEDULED
[04:14:41.158] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:2:0)
[04:14:41.199] Build status: SCHEDULED
[04:14:41.199] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:4:0) after 7m16s
[04:21:57.236] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:4:0)
[04:21:57.278] Build status: SCHEDULED
[04:21:57.278] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:5:0) after 9m5s
[04:31:02.417] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:5:0)
[04:31:02.449] Build status: SCHEDULED
[04:31:02.449] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:6:0) after 9m36s
[04:40:38.625] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:6:0)
[04:40:38.654] Build status: SCHEDULED
[04:40:38.654] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:7:0) after 7m57s
[04:48:35.669] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:7:0)
[04:48:35.686] Build status: SCHEDULED
[04:48:35.686] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:8:0) after 8m30s
[04:57:05.802] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:8:0)
[04:57:06.040] Build status: SCHEDULED
[04:57:06.040] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:9:0) after 9m30s
[05:06:36.057] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:9:0)
[05:06:36.094] Build status: SCHEDULED
[05:06:36.095] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:10:0) after 2m12s
[05:08:48.144] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:10:0)
[05:08:48.191] Build status: SCHEDULED
[05:08:48.191] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:11:0) after 8m32s
[05:17:20.321] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:11:0)
[05:17:20.346] Build status: SCHEDULED
[05:17:20.346] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:12:0) after 2m51s
[05:20:11.448] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:12:0)
[05:20:11.472] Build status: SCHEDULED
[05:20:11.472] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:13:0) after 5m16s
[05:25:27.497] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:13:0)
[05:25:27.517] Build status: SCHEDULED
[05:25:27.517] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:14:0) after 1m25s
[05:26:52.603] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:14:0)
[05:26:52.636] Build status: SCHEDULED
[05:26:52.637] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:15:0) after 1m44s
[05:28:36.650] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:15:0)
[05:28:36.712] Build status: SCHEDULED
[05:28:36.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:16:0) after 2m5s
[05:30:41.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:16:0)
[05:30:41.749] Build status: SCHEDULED
[05:30:41.749] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:17:0) after 5m18s
[05:35:59.761] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:17:0)
[05:35:59.789] Build status: SCHEDULED
[05:35:59.789] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:18:0) after 1m13s
[05:37:12.870] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:18:0)
[05:37:12.890] Build status: SCHEDULED
[05:37:12.890] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:19:0) after 1m40s
[05:38:52.920] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:19:0)
[05:38:52.967] Build status: SCHEDULED
[05:38:52.967] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:20:0) after 6m54s
[05:45:46.988] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:20:0)
[05:45:47.005] Build status: SCHEDULED
[05:45:47.005] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:21:0) after 5m14s
[05:51:01.027] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:21:0)
[05:51:01.061] Build status: SCHEDULED
[05:51:01.061] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:22:0) after 8m36s
[05:59:37.050] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:22:0)
[05:59:37.116] Build status: SCHEDULED
[05:59:37.116] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:23:0) after 6m33s
[06:06:10.343] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:23:0)
[06:06:10.362] Build status: SCHEDULED
[06:06:10.362] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:24:0) after 2m9s
[06:08:19.387] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:24:0)
[06:08:19.413] Build status: SCHEDULED
[06:08:19.413] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:25:0) after 5m52s
[06:14:11.467] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:25:0)
[06:14:11.502] Build status: SCHEDULED
[06:14:11.502] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:26:0) after 3m48s
[06:17:59.612] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:26:0)
[06:17:59.823] Build status: SCHEDULED
[06:17:59.823] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:27:0) after 4m7s
[06:22:06.820] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:27:0)
[06:22:06.849] Build status: SCHEDULED
[06:22:06.849] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:28:0) after 8m35s
[06:30:42.063] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:28:0)
[06:30:42.091] Build status: SCHEDULED
[06:30:42.091] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:29:0) after 9m2s
[06:39:44.308] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:29:0)
[06:39:44.325] Build status: SCHEDULED
[06:39:44.325] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:30:0) after 1m1s
[06:40:45.571] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:30:0)
[06:40:45.613] Build status: SCHEDULED
[06:40:45.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:31:0) after 9m32s
[06:50:17.680] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:31:0)
[06:50:17.716] Build status: SCHEDULED
[06:50:17.716] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:32:0) after 7m40s
[06:57:57.814] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:32:0)
[06:57:57.893] Build status: SCHEDULED
[06:57:57.893] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:33:0) after 5m55s
[07:03:53.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:33:0)
[07:03:53.171] Build status: SCHEDULED
[07:03:53.171] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:34:0) after 7m30s
[07:11:23.190] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:34:0)
[07:11:23.227] Build status: SCHEDULED
[07:11:23.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:35:0) after 6m49s
[07:18:12.355] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:35:0)
[07:18:12.389] Build status: SCHEDULED
[07:18:12.389] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:36:0) after 8m5s
[07:26:17.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:36:0)
[07:26:17.470] Build status: SCHEDULED
[07:26:17.470] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:37:0) after 8m45s
[07:35:02.536] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:37:0)
[07:35:02.555] Build status: SCHEDULED
[07:35:02.555] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:38:0) after 2m58s
[07:38:00.576] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:38:0)
[07:38:00.598] Build status: SCHEDULED
[07:38:00.598] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:39:0) after 6m20s
[07:44:20.656] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:39:0)
[07:44:20.695] Build status: SCHEDULED
[07:44:20.695] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:40:0) after 7m5s
[07:47:03.325] Received PubSub notification, asking Buildbucket for the build status
[07:47:03.349] Build status: STARTED
[07:51:25.824] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:40:0)
[07:51:25.849] Build status: STARTED
[07:51:25.849] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:42:0) after 2m34s
[07:53:59.869] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:42:0)
[07:53:59.903] Build status: STARTED
[07:53:59.903] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:43:0) after 1m25s
[07:55:24.921] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:43:0)
[07:55:25.125] Build status: STARTED
[07:55:25.126] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:44:0) after 4m15s
[07:59:40.241] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:44:0)
[07:59:40.282] Build status: STARTED
[07:59:40.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:45:0) after 7m13s
[08:06:53.633] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:45:0)
[08:06:53.674] Build status: STARTED
[08:06:53.674] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:46:0) after 6m52s
[08:13:45.692] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:46:0)
[08:13:45.848] Build status: STARTED
[08:13:45.848] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:47:0) after 3m35s
[08:17:20.869] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:47:0)
[08:17:20.891] Build status: STARTED
[08:17:20.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:48:0) after 7m33s
[08:24:53.908] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:48:0)
[08:24:53.935] Build status: STARTED
[08:24:53.935] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:49:0) after 7m3s
[08:31:57.094] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:49:0)
[08:31:57.112] Build status: STARTED
[08:31:57.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:50:0) after 3m51s
[08:35:48.128] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:50:0)
[08:35:48.149] Build status: STARTED
[08:35:48.149] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:51:0) after 3m21s
[08:39:09.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:51:0)
[08:39:09.303] Build status: STARTED
[08:39:09.303] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:52:0) after 2m40s
[08:41:49.329] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:52:0)
[08:41:49.403] Build status: STARTED
[08:41:49.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:53:0) after 7m5s
[08:48:54.536] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:53:0)
[08:48:54.614] Build status: STARTED
[08:48:54.615] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:54:0) after 3m52s
[08:52:46.640] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:54:0)
[08:52:46.671] Build status: STARTED
[08:52:46.671] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:55:0) after 3m41s
[08:56:27.708] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:55:0)
[08:56:27.741] Build status: STARTED
[08:56:27.741] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8926470097060459984:56:0) after 9m34s
[09:01:52.175] Received PubSub notification, asking Buildbucket for the build status
[09:01:52.303] Build:
{
"id": "8761040035174043761",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"createdBy": "project:infra",
"createTime": "2023-12-22T04:09:15.655360050Z",
"startTime": "2023-12-22T07:47:01.057718Z",
"endTime": "2023-12-22T09:01:51.551914Z",
"updateTime": "2023-12-22T09:01:51.551914Z",
"status": "FAILURE",
"input": {
}
}
[09:01:52.303] Invocation finished in 4h52m37.830215817s with status FAILED