[09:01:52.388] New invocation is queued and will start shortly
[09:01:52.388] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[09:01:53.522] Starting the invocation (attempt 1)
[09:01:53.549] Ignoring gitiles_ref tag without the buildset tag
[09:01:53.549] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:01:53.549] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:01:53.549] Buildbucket request:
{
"requestId": "8952634209549752480",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8952634209549752480",
"job": "infra/Build From Tarball",
"triggers": [
{
"buildbucket": {
"properties": {
"version": "112.0.5604.0"
},
"tags": [
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
},
"id": "893784d9-a2c6-4cd5-be9b-7c9338b760fd",
"title": "publish_tarball/0"
}
]
},
"version": "112.0.5604.0"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8952634209549752480"
},
{
"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"
}
}
[09:01:53.843] Scheduled build:
{
"id": "8787204147589993457",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"createdBy": "project:infra",
"createTime": "2023-03-08T09:01:53.632005979Z",
"updateTime": "2023-03-08T09:01:53.632005979Z",
"status": "SCHEDULED",
"input": {
}
}
[09:01:53.843] Task URL: https://cr-buildbucket.appspot.com/build/8787204147589993457
[09:01:53.843] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:2:0) after 2m41s
[09:01:55.634] Received PubSub notification, asking Buildbucket for the build status
[09:01:55.663] Build status: STARTED
[09:04:34.926] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:2:0)
[09:04:34.945] Build status: STARTED
[09:04:34.945] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:4:0) after 8m8s
[09:12:43.075] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:4:0)
[09:12:43.114] Build status: STARTED
[09:12:43.114] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:5:0) after 4m25s
[09:17:08.129] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:5:0)
[09:17:08.179] Build status: STARTED
[09:17:08.179] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:6:0) after 1m56s
[09:19:04.321] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:6:0)
[09:19:04.341] Build status: STARTED
[09:19:04.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:7:0) after 4m56s
[09:24:00.452] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:7:0)
[09:24:00.490] Build status: STARTED
[09:24:00.490] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:8:0) after 5m54s
[09:29:54.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:8:0)
[09:29:54.557] Build status: STARTED
[09:29:54.557] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:9:0) after 8m44s
[09:38:38.591] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:9:0)
[09:38:38.611] Build status: STARTED
[09:38:38.611] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:10:0) after 9m11s
[09:47:49.626] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:10:0)
[09:47:49.661] Build status: STARTED
[09:47:49.661] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:11:0) after 7m33s
[09:55:22.746] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:11:0)
[09:55:22.786] Build status: STARTED
[09:55:22.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:12:0) after 3m58s
[09:59:20.812] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:12:0)
[09:59:20.840] Build status: STARTED
[09:59:20.840] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:13:0) after 5m14s
[10:04:34.979] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:13:0)
[10:04:35.000] Build status: STARTED
[10:04:35.000] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:14:0) after 6m39s
[10:11:14.008] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:14:0)
[10:11:14.031] Build status: STARTED
[10:11:14.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:15:0) after 8m3s
[10:19:17.134] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:15:0)
[10:19:17.157] Build status: STARTED
[10:19:17.157] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:16:0) after 6m7s
[10:25:24.176] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:16:0)
[10:25:24.203] Build status: STARTED
[10:25:24.203] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:17:0) after 4m49s
[10:30:13.365] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:17:0)
[10:30:13.398] Build status: STARTED
[10:30:13.398] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:18:0) after 6m32s
[10:36:45.415] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:18:0)
[10:36:45.454] Build status: STARTED
[10:36:45.454] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:19:0) after 5m12s
[10:41:57.590] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:19:0)
[10:41:57.622] Build status: STARTED
[10:41:57.622] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:20:0) after 5m18s
[10:47:15.638] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:20:0)
[10:47:15.662] Build status: STARTED
[10:47:15.662] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:21:0) after 1m26s
[10:48:41.681] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:21:0)
[10:48:41.703] Build status: STARTED
[10:48:41.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:22:0) after 9m10s
[10:57:51.765] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:22:0)
[10:57:51.786] Build status: STARTED
[10:57:51.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:23:0) after 1m11s
[10:59:02.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:23:0)
[10:59:02.854] Build status: STARTED
[10:59:02.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:24:0) after 7m40s
[11:06:43.358] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:24:0)
[11:06:43.378] Build status: STARTED
[11:06:43.378] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:25:0) after 3m11s
[11:09:54.401] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:25:0)
[11:09:54.424] Build status: STARTED
[11:09:54.424] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:26:0) after 7m55s
[11:17:49.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:26:0)
[11:17:49.510] Build status: STARTED
[11:17:49.510] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:27:0) after 7m23s
[11:25:12.580] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:27:0)
[11:25:12.618] Build status: STARTED
[11:25:12.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:28:0) after 4m32s
[11:29:44.664] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:28:0)
[11:29:44.692] Build status: STARTED
[11:29:44.692] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:29:0) after 1m0s
[11:30:44.849] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:29:0)
[11:30:44.870] Build status: STARTED
[11:30:44.871] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:30:0) after 4m48s
[11:35:32.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:30:0)
[11:35:32.907] Build status: STARTED
[11:35:32.907] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:31:0) after 9m12s
[11:44:45.284] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:31:0)
[11:44:45.310] Build status: STARTED
[11:44:45.310] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:32:0) after 4m16s
[11:49:01.431] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:32:0)
[11:49:01.468] Build status: STARTED
[11:49:01.468] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:33:0) after 7m37s
[11:56:38.634] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:33:0)
[11:56:38.687] Build status: STARTED
[11:56:38.687] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:34:0) after 9m2s
[12:05:40.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:34:0)
[12:05:40.781] Build status: STARTED
[12:05:40.781] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:35:0) after 6m44s
[12:12:24.811] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:35:0)
[12:12:24.882] Build status: STARTED
[12:12:24.882] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:36:0) after 4m43s
[12:17:07.906] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:36:0)
[12:17:07.931] Build status: STARTED
[12:17:07.931] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:37:0) after 9m20s
[12:26:27.962] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:37:0)
[12:26:27.982] Build status: STARTED
[12:26:27.982] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:38:0) after 1m25s
[12:27:52.999] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:38:0)
[12:27:53.133] Build status: STARTED
[12:27:53.133] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:39:0) after 5m33s
[12:33:26.152] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:39:0)
[12:33:26.176] Build status: STARTED
[12:33:26.176] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:40:0) after 5m46s
[12:39:12.206] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:40:0)
[12:39:12.276] Build status: STARTED
[12:39:12.276] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:41:0) after 4m14s
[12:43:26.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:41:0)
[12:43:26.535] Build status: STARTED
[12:43:26.535] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:42:0) after 2m42s
[12:46:08.712] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:42:0)
[12:46:08.736] Build status: STARTED
[12:46:08.736] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8952634209549752480:43:0) after 4m6s
[12:49:52.644] Received PubSub notification, asking Buildbucket for the build status
[12:49:52.711] Build:
{
"id": "8787204147589993457",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"createdBy": "project:infra",
"createTime": "2023-03-08T09:01:53.632005979Z",
"startTime": "2023-03-08T09:01:54.676272Z",
"endTime": "2023-03-08T12:49:52.140452500Z",
"updateTime": "2023-03-08T12:49:52.140452500Z",
"status": "SUCCESS",
"input": {
}
}
[12:49:52.711] Invocation finished in 3h48m0.331214576s with status SUCCEEDED