[22:03:48.215] New invocation is queued and will start shortly
[22:03:48.215] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[22:03:49.268] Starting the invocation (attempt 1)
[22:03:49.298] Ignoring gitiles_ref tag without the buildset tag
[22:03:49.298] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:03:49.298] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:03:49.298] Buildbucket request:
{
"requestId": "8955846505530241984",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8955846505530241984",
"job": "infra/Build From Tarball",
"triggers": [
{
"buildbucket": {
"properties": {
"version": "112.0.5571.0"
},
"tags": [
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
},
"id": "e1279f37-c0b6-4914-9431-8e754b0ba98b",
"title": "publish_tarball/0"
}
]
},
"version": "112.0.5571.0"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8955846505530241984"
},
{
"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"
}
}
[22:03:49.653] Scheduled build:
{
"id": "8790416443622950465",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"createdBy": "project:infra",
"createTime": "2023-01-31T22:03:49.409924861Z",
"updateTime": "2023-01-31T22:03:49.409924861Z",
"status": "SCHEDULED",
"input": {
}
}
[22:03:49.653] Task URL: https://cr-buildbucket.appspot.com/build/8790416443622950465
[22:03:49.653] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:2:0) after 2m6s
[22:05:55.670] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:2:0)
[22:05:55.702] Build status: SCHEDULED
[22:05:55.702] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:3:0) after 2m4s
[22:07:59.801] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:3:0)
[22:07:59.855] Build status: SCHEDULED
[22:07:59.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:4:0) after 3m28s
[22:11:28.259] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:4:0)
[22:11:28.318] Build status: SCHEDULED
[22:11:28.318] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:5:0) after 7m4s
[22:18:32.330] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:5:0)
[22:18:32.431] Build status: SCHEDULED
[22:18:32.431] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:6:0) after 1m57s
[22:20:29.471] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:6:0)
[22:20:29.589] Build status: SCHEDULED
[22:20:29.589] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:7:0) after 1m47s
[22:22:16.658] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:7:0)
[22:22:16.740] Build status: SCHEDULED
[22:22:16.740] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:8:0) after 4m48s
[22:27:04.771] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:8:0)
[22:27:04.791] Build status: SCHEDULED
[22:27:04.791] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:9:0) after 4m7s
[22:31:11.807] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:9:0)
[22:31:11.849] Build status: SCHEDULED
[22:31:11.849] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:10:0) after 8m24s
[22:39:35.877] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:10:0)
[22:39:35.922] Build status: SCHEDULED
[22:39:35.922] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:11:0) after 8m38s
[22:48:13.943] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:11:0)
[22:48:14.110] Build status: SCHEDULED
[22:48:14.110] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:12:0) after 5m49s
[22:54:03.227] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:12:0)
[22:54:03.264] Build status: SCHEDULED
[22:54:03.264] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:13:0) after 5m8s
[22:59:11.282] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:13:0)
[22:59:11.307] Build status: SCHEDULED
[22:59:11.307] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:14:0) after 8m54s
[23:08:05.351] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:14:0)
[23:08:05.377] Build status: SCHEDULED
[23:08:05.377] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:15:0) after 6m26s
[23:14:31.395] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:15:0)
[23:14:31.430] Build status: SCHEDULED
[23:14:31.430] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:16:0) after 9m8s
[23:23:39.450] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:16:0)
[23:23:39.501] Build status: SCHEDULED
[23:23:39.501] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:17:0) after 4m5s
[23:27:44.505] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:17:0)
[23:27:44.531] Build status: SCHEDULED
[23:27:44.531] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:18:0) after 1m54s
[23:29:38.652] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:18:0)
[23:29:38.682] Build status: SCHEDULED
[23:29:38.682] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:19:0) after 7m57s
[23:37:35.719] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:19:0)
[23:37:35.743] Build status: SCHEDULED
[23:37:35.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:20:0) after 7m2s
[23:44:37.770] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:20:0)
[23:44:37.811] Build status: SCHEDULED
[23:44:37.811] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:21:0) after 5m5s
[23:49:42.927] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:21:0)
[23:49:42.961] Build status: SCHEDULED
[23:49:42.961] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:22:0) after 5m25s
[23:53:06.980] Received PubSub notification, asking Buildbucket for the build status
[23:53:07.008] Build status: STARTED
[23:55:07.988] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:22:0)
[23:55:08.021] Build status: STARTED
[23:55:08.021] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:24:0) after 8m59s
[00:04:07.145] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:24:0)
[00:04:07.290] Build status: STARTED
[00:04:07.290] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:25:0) after 4m4s
[00:08:11.736] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:25:0)
[00:08:11.759] Build status: STARTED
[00:08:11.759] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:26:0) after 6m12s
[00:14:23.818] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:26:0)
[00:14:23.839] Build status: STARTED
[00:14:23.839] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:27:0) after 4m3s
[00:18:27.408] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:27:0)
[00:18:27.429] Build status: STARTED
[00:18:27.429] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:28:0) after 4m57s
[00:23:24.473] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:28:0)
[00:23:24.499] Build status: STARTED
[00:23:24.499] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:29:0) after 8m46s
[00:32:10.523] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:29:0)
[00:32:10.578] Build status: STARTED
[00:32:10.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:30:0) after 3m45s
[00:35:55.568] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:30:0)
[00:35:55.600] Build status: STARTED
[00:35:55.600] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:31:0) after 1m9s
[00:37:04.625] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:31:0)
[00:37:04.644] Build status: STARTED
[00:37:04.644] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:32:0) after 5m45s
[00:42:49.716] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:32:0)
[00:42:49.746] Build status: STARTED
[00:42:49.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:33:0) after 5m17s
[00:48:06.764] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:33:0)
[00:48:06.790] Build status: STARTED
[00:48:06.791] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:34:0) after 3m7s
[00:51:13.867] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:34:0)
[00:51:13.887] Build status: STARTED
[00:51:13.887] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:35:0) after 1m49s
[00:53:02.908] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:35:0)
[00:53:02.931] Build status: STARTED
[00:53:02.932] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:36:0) after 1m18s
[00:54:20.955] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:36:0)
[00:54:21.010] Build status: STARTED
[00:54:21.010] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:37:0) after 6m43s
[01:01:04.396] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:37:0)
[01:01:04.427] Build status: STARTED
[01:01:04.427] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:38:0) after 5m11s
[01:06:15.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:38:0)
[01:06:15.537] Build status: STARTED
[01:06:15.537] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:39:0) after 8m45s
[01:15:00.561] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:39:0)
[01:15:00.592] Build status: STARTED
[01:15:00.592] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:40:0) after 6m32s
[01:21:32.583] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:40:0)
[01:21:32.609] Build status: STARTED
[01:21:32.609] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:41:0) after 5m6s
[01:26:38.825] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:41:0)
[01:26:38.860] Build status: STARTED
[01:26:38.860] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:42:0) after 7m44s
[01:34:22.882] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:42:0)
[01:34:22.913] Build status: STARTED
[01:34:22.913] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:43:0) after 4m5s
[01:38:27.939] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:43:0)
[01:38:27.963] Build status: STARTED
[01:38:27.963] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:44:0) after 9m22s
[01:47:50.256] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:44:0)
[01:47:50.287] Build status: STARTED
[01:47:50.287] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:45:0) after 5m41s
[01:53:31.306] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:45:0)
[01:53:31.329] Build status: STARTED
[01:53:31.329] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:46:0) after 1m48s
[01:55:19.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:46:0)
[01:55:19.609] Build status: STARTED
[01:55:19.610] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:47:0) after 1m38s
[01:56:57.630] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:47:0)
[01:56:57.663] Build status: STARTED
[01:56:57.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:48:0) after 6m48s
[02:03:45.677] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:48:0)
[02:03:45.710] Build status: STARTED
[02:03:45.710] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:49:0) after 8m35s
[02:12:20.919] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:49:0)
[02:12:20.955] Build status: STARTED
[02:12:20.955] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:50:0) after 5m53s
[02:18:14.024] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:50:0)
[02:18:14.044] Build status: STARTED
[02:18:14.044] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:51:0) after 2m43s
[02:20:57.208] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:51:0)
[02:20:57.235] Build status: STARTED
[02:20:57.235] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:52:0) after 1m47s
[02:22:44.245] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:52:0)
[02:22:44.269] Build status: STARTED
[02:22:44.269] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:53:0) after 5m57s
[02:28:41.391] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:53:0)
[02:28:41.434] Build status: STARTED
[02:28:41.434] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:54:0) after 8m40s
[02:37:21.492] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:54:0)
[02:37:21.690] Build status: STARTED
[02:37:21.690] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:55:0) after 2m4s
[02:39:25.797] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:55:0)
[02:39:25.825] Build status: STARTED
[02:39:25.825] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:56:0) after 1m53s
[02:41:18.872] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:56:0)
[02:41:18.899] Build status: STARTED
[02:41:18.899] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:57:0) after 3m43s
[02:45:02.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:57:0)
[02:45:02.078] Build status: STARTED
[02:45:02.078] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:58:0) after 6m16s
[02:51:18.367] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:58:0)
[02:51:18.394] Build status: STARTED
[02:51:18.394] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:59:0) after 4m42s
[02:56:00.422] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:59:0)
[02:56:00.443] Build status: STARTED
[02:56:00.443] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:60:0) after 1m22s
[02:57:22.632] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:60:0)
[02:57:22.685] Build status: STARTED
[02:57:22.685] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:61:0) after 2m24s
[02:59:46.811] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:61:0)
[02:59:46.845] Build status: STARTED
[02:59:46.845] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:62:0) after 6m5s
[03:05:52.080] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:62:0)
[03:05:52.179] Build status: STARTED
[03:05:52.179] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:63:0) after 3m10s
[03:09:02.254] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:63:0)
[03:09:02.497] Build status: STARTED
[03:09:02.497] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:64:0) after 4m54s
[03:13:56.749] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:64:0)
[03:13:56.777] Build status: STARTED
[03:13:56.777] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:65:0) after 2m15s
[03:16:11.898] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:65:0)
[03:16:11.937] Build status: STARTED
[03:16:11.937] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955846505530241984:66:0) after 9m15s
[03:22:12.111] Received PubSub notification, asking Buildbucket for the build status
[03:22:12.135] Build:
{
"id": "8790416443622950465",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"createdBy": "project:infra",
"createTime": "2023-01-31T22:03:49.409924861Z",
"startTime": "2023-01-31T23:53:06.410170Z",
"endTime": "2023-02-01T03:22:11.763381588Z",
"updateTime": "2023-02-01T03:22:11.763381588Z",
"status": "FAILURE",
"input": {
}
}
[03:22:12.135] Invocation finished in 5h18m23.928033107s with status FAILED