[22:04:10.854] New invocation is queued and will start shortly
[22:04:10.854] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[22:04:11.972] Starting the invocation (attempt 1)
[22:04:11.996] Ignoring gitiles_ref tag without the buildset tag
[22:04:11.996] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:04:11.996] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:04:11.996] Buildbucket request:
{
"requestId": "8940173206601847040",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8940173206601847040",
"job": "infra/Build From Tarball",
"triggers": [
{
"buildbucket": {
"properties": {
"version": "117.0.5904.0"
},
"tags": [
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
},
"id": "8c6cb47f-8618-491f-bb76-7c139a0369ef",
"title": "publish_tarball/0"
}
]
},
"version": "117.0.5904.0"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8940173206601847040"
},
{
"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:04:12.522] Scheduled build:
{
"id": "8774743144667158753",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"createdBy": "project:infra",
"createTime": "2023-07-23T22:04:12.077778716Z",
"updateTime": "2023-07-23T22:04:12.077778716Z",
"status": "SCHEDULED",
"input": {
}
}
[22:04:12.522] Task URL: https://cr-buildbucket.appspot.com/build/8774743144667158753
[22:04:12.522] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:2:0) after 1m18s
[22:04:13.545] Received PubSub notification, asking Buildbucket for the build status
[22:04:13.571] Build status: SCHEDULED
[22:05:26.609] Received PubSub notification, asking Buildbucket for the build status
[22:05:26.642] Build status: STARTED
[22:05:30.536] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:2:0)
[22:05:30.560] Build status: STARTED
[22:05:30.560] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:5:0) after 4m13s
[22:09:43.594] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:5:0)
[22:09:43.614] Build status: STARTED
[22:09:43.614] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:6:0) after 2m27s
[22:12:10.635] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:6:0)
[22:12:10.693] Build status: STARTED
[22:12:10.693] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:7:0) after 5m48s
[22:17:58.724] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:7:0)
[22:17:58.758] Build status: STARTED
[22:17:58.758] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:8:0) after 4m7s
[22:22:06.150] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:8:0)
[22:22:06.219] Build status: STARTED
[22:22:06.219] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:9:0) after 6m35s
[22:28:41.233] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:9:0)
[22:28:41.254] Build status: STARTED
[22:28:41.254] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:10:0) after 8m26s
[22:37:07.290] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:10:0)
[22:37:07.329] Build status: STARTED
[22:37:07.329] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:11:0) after 4m1s
[22:41:08.371] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:11:0)
[22:41:08.391] Build status: STARTED
[22:41:08.391] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:12:0) after 1m20s
[22:42:28.404] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:12:0)
[22:42:28.436] Build status: STARTED
[22:42:28.436] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:13:0) after 4m58s
[22:47:26.579] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:13:0)
[22:47:26.596] Build status: STARTED
[22:47:26.596] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:14:0) after 5m40s
[22:53:06.617] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:14:0)
[22:53:06.663] Build status: STARTED
[22:53:06.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:15:0) after 2m24s
[22:55:30.678] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:15:0)
[22:55:30.711] Build status: STARTED
[22:55:30.711] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:16:0) after 2m17s
[22:57:47.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:16:0)
[22:57:47.752] Build status: STARTED
[22:57:47.752] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:17:0) after 7m10s
[23:04:57.998] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:17:0)
[23:04:58.020] Build status: STARTED
[23:04:58.020] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:18:0) after 1m36s
[23:06:34.035] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:18:0)
[23:06:34.053] Build status: STARTED
[23:06:34.053] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:19:0) after 7m59s
[23:14:33.440] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:19:0)
[23:14:33.461] Build status: STARTED
[23:14:33.461] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:20:0) after 3m55s
[23:18:28.695] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:20:0)
[23:18:28.712] Build status: STARTED
[23:18:28.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:21:0) after 5m46s
[23:24:14.714] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:21:0)
[23:24:14.743] Build status: STARTED
[23:24:14.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:22:0) after 3m49s
[23:28:03.770] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:22:0)
[23:28:03.789] Build status: STARTED
[23:28:03.789] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:23:0) after 7m36s
[23:35:39.917] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:23:0)
[23:35:39.936] Build status: STARTED
[23:35:39.936] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:24:0) after 9m24s
[23:45:03.956] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:24:0)
[23:45:03.982] Build status: STARTED
[23:45:03.982] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:25:0) after 1m24s
[23:46:27.994] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:25:0)
[23:46:28.018] Build status: STARTED
[23:46:28.018] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:26:0) after 9m49s
[23:56:17.067] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:26:0)
[23:56:17.101] Build status: STARTED
[23:56:17.101] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:27:0) after 5m30s
[00:01:47.151] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:27:0)
[00:01:47.273] Build status: STARTED
[00:01:47.273] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:28:0) after 1m3s
[00:02:50.290] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:28:0)
[00:02:50.309] Build status: STARTED
[00:02:50.309] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:29:0) after 4m31s
[00:07:21.330] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:29:0)
[00:07:21.360] Build status: STARTED
[00:07:21.360] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:30:0) after 4m0s
[00:11:21.373] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:30:0)
[00:11:21.390] Build status: STARTED
[00:11:21.390] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:31:0) after 6m26s
[00:17:47.411] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:31:0)
[00:17:47.437] Build status: STARTED
[00:17:47.437] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:32:0) after 1m10s
[00:18:57.450] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:32:0)
[00:18:57.468] Build status: STARTED
[00:18:57.468] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:33:0) after 1m54s
[00:20:51.493] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:33:0)
[00:20:51.517] Build status: STARTED
[00:20:51.517] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:34:0) after 1m18s
[00:22:09.597] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:34:0)
[00:22:09.631] Build status: STARTED
[00:22:09.631] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:35:0) after 2m56s
[00:25:05.653] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:35:0)
[00:25:05.720] Build status: STARTED
[00:25:05.720] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:36:0) after 7m6s
[00:32:11.921] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:36:0)
[00:32:11.994] Build status: STARTED
[00:32:11.994] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:37:0) after 8m40s
[00:40:52.008] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:37:0)
[00:40:52.061] Build status: STARTED
[00:40:52.061] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:38:0) after 6m7s
[00:46:59.082] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:38:0)
[00:46:59.101] Build status: STARTED
[00:46:59.101] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:39:0) after 6m34s
[00:53:33.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:39:0)
[00:53:33.164] Build status: STARTED
[00:53:33.164] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:40:0) after 3m57s
[00:57:30.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:40:0)
[00:57:30.235] Build status: STARTED
[00:57:30.235] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:41:0) after 4m15s
[01:01:45.249] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:41:0)
[01:01:45.265] Build status: STARTED
[01:01:45.265] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:42:0) after 2m11s
[01:03:56.250] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:42:0)
[01:03:56.279] Build status: STARTED
[01:03:56.279] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:43:0) after 3m53s
[01:07:49.325] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:43:0)
[01:07:49.343] Build status: STARTED
[01:07:49.343] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:44:0) after 7m31s
[01:15:20.471] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:44:0)
[01:15:20.490] Build status: STARTED
[01:15:20.490] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:45:0) after 9m3s
[01:24:23.552] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:45:0)
[01:24:23.578] Build status: STARTED
[01:24:23.578] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:46:0) after 7m42s
[01:32:05.615] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:46:0)
[01:32:05.680] Build status: STARTED
[01:32:05.680] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:47:0) after 6m21s
[01:38:26.691] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:47:0)
[01:38:26.714] Build status: STARTED
[01:38:26.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:48:0) after 6m59s
[01:45:25.729] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:48:0)
[01:45:25.789] Build status: STARTED
[01:45:25.789] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:49:0) after 7m26s
[01:52:51.804] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:49:0)
[01:52:51.865] Build status: STARTED
[01:52:51.865] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:50:0) after 3m6s
[01:55:57.843] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:50:0)
[01:55:57.862] Build status: STARTED
[01:55:57.863] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:51:0) after 4m45s
[02:00:43.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:51:0)
[02:00:43.026] Build status: STARTED
[02:00:43.026] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:52:0) after 9m56s
[02:10:39.044] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:52:0)
[02:10:39.076] Build status: STARTED
[02:10:39.076] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:53:0) after 3m51s
[02:14:30.299] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:53:0)
[02:14:30.352] Build status: STARTED
[02:14:30.352] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:54:0) after 9m40s
[02:24:10.699] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:54:0)
[02:24:10.748] Build status: STARTED
[02:24:10.748] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:55:0) after 8m50s
[02:33:00.968] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:55:0)
[02:33:00.999] Build status: STARTED
[02:33:00.999] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:56:0) after 3m29s
[02:36:30.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:56:0)
[02:36:30.055] Build status: STARTED
[02:36:30.055] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:57:0) after 7m33s
[02:44:03.111] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:57:0)
[02:44:03.151] Build status: STARTED
[02:44:03.151] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:58:0) after 1m54s
[02:45:57.179] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:58:0)
[02:45:57.199] Build status: STARTED
[02:45:57.199] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:59:0) after 6m11s
[02:52:08.308] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:59:0)
[02:52:08.337] Build status: STARTED
[02:52:08.337] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:60:0) after 4m22s
[02:56:30.359] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:60:0)
[02:56:30.379] Build status: STARTED
[02:56:30.379] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:61:0) after 6m45s
[03:03:15.472] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:61:0)
[03:03:15.514] Build status: STARTED
[03:03:15.514] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:62:0) after 8m3s
[03:11:18.691] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:62:0)
[03:11:18.711] Build status: STARTED
[03:11:18.711] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:63:0) after 2m53s
[03:14:11.834] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:63:0)
[03:14:11.875] Build status: STARTED
[03:14:11.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:64:0) after 5m40s
[03:19:51.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:64:0)
[03:19:51.984] Build status: STARTED
[03:19:51.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:65:0) after 2m3s
[03:21:55.003] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:65:0)
[03:21:55.024] Build status: STARTED
[03:21:55.024] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:66:0) after 2m42s
[03:24:37.203] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:66:0)
[03:24:37.237] Build status: STARTED
[03:24:37.237] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8940173206601847040:67:0) after 7m31s
[03:27:39.714] Received PubSub notification, asking Buildbucket for the build status
[03:27:39.734] Build:
{
"id": "8774743144667158753",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"createdBy": "project:infra",
"createTime": "2023-07-23T22:04:12.077778716Z",
"startTime": "2023-07-23T22:05:25.950485Z",
"endTime": "2023-07-24T03:27:39.404868378Z",
"updateTime": "2023-07-24T03:27:39.404868378Z",
"status": "SUCCESS",
"input": {
}
}
[03:27:39.734] Invocation finished in 5h23m28.885941345s with status SUCCEEDED