[05:12:04.706] New invocation is queued and will start shortly
[05:12:04.706] Triggered by user:infra-codesearch@chops-service-accounts.iam.gserviceaccount.com
[05:12:06.062] Starting the invocation (attempt 1)
[05:12:06.131] Ignoring gitiles_ref tag without the buildset tag
[05:12:06.131] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:12:06.131] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:12:06.132] Buildbucket request:
{
"requestId": "8976022684319105296",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-chromiumos"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8976022684319105296",
"job": "infra/codesearch-gen-chromium-chromiumos",
"triggers": [
{
"buildbucket": {
"properties": {
"codesearch_mirror_revision": "09aedb7f8443df4dcdf203db02b6a3e1f276133e",
"codesearch_mirror_revision_timestamp": 1655957163,
"root_solution_revision": "506abd3232169f84fa6535ea3e6e5bd6b87a52a9",
"root_solution_revision_timestamp": 1655957028
},
"tags": [
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:15337",
"user_agent:recipe"
]
},
"id": "33346c21-41b2-47ad-8b7b-e1262e5cd2a3",
"title": "codesearch-gen-chromium-initiator/15337"
}
]
},
"codesearch_mirror_revision": "09aedb7f8443df4dcdf203db02b6a3e1f276133e",
"codesearch_mirror_revision_timestamp": 1655957163,
"root_solution_revision": "506abd3232169f84fa6535ea3e6e5bd6b87a52a9",
"root_solution_revision_timestamp": 1655957028
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8976022684319105296"
},
{
"key": "scheduler_job_id",
"value": "infra/codesearch-gen-chromium-chromiumos"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "codesearch-gen-chromium-initiator"
},
{
"key": "parent_buildnumber",
"value": "15337"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[05:12:06.768] Scheduled build:
{
"id": "8810592622044184849",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-chromiumos"
},
"number": 12715,
"createdBy": "project:infra",
"createTime": "2022-06-23T05:12:06.246867841Z",
"updateTime": "2022-06-23T05:12:06.246867841Z",
"status": "SCHEDULED",
"input": {
}
}
[05:12:06.768] Task URL: https://cr-buildbucket.appspot.com/build/8810592622044184849
[05:12:06.768] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:2:0) after 7m57s
[05:12:42.729] Received PubSub notification, asking Buildbucket for the build status
[05:12:42.760] Build status: STARTED
[05:20:04.113] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:2:0)
[05:20:04.212] Build status: STARTED
[05:20:04.212] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:4:0) after 5m9s
[05:25:13.192] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:4:0)
[05:25:13.227] Build status: STARTED
[05:25:13.227] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:5:0) after 7m54s
[05:33:07.244] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:5:0)
[05:33:07.277] Build status: STARTED
[05:33:07.277] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:6:0) after 1m28s
[05:34:35.723] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:6:0)
[05:34:35.755] Build status: STARTED
[05:34:35.755] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:7:0) after 6m22s
[05:40:58.038] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:7:0)
[05:40:58.071] Build status: STARTED
[05:40:58.071] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:8:0) after 4m58s
[05:45:56.143] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:8:0)
[05:45:56.177] Build status: STARTED
[05:45:56.177] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:9:0) after 6m37s
[05:52:33.319] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:9:0)
[05:52:33.346] Build status: STARTED
[05:52:33.346] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:10:0) after 1m29s
[05:54:02.361] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:10:0)
[05:54:02.502] Build status: STARTED
[05:54:02.502] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:11:0) after 2m16s
[05:56:18.518] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:11:0)
[05:56:18.549] Build status: STARTED
[05:56:18.549] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:12:0) after 6m52s
[06:03:10.566] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:12:0)
[06:03:10.643] Build status: STARTED
[06:03:10.643] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:13:0) after 4m10s
[06:07:20.844] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:13:0)
[06:07:20.906] Build status: STARTED
[06:07:20.906] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:14:0) after 4m33s
[06:11:53.929] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:14:0)
[06:11:53.961] Build status: STARTED
[06:11:53.961] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:15:0) after 1m48s
[06:13:42.111] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:15:0)
[06:13:42.147] Build status: STARTED
[06:13:42.147] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:16:0) after 8m56s
[06:22:38.201] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:16:0)
[06:22:38.234] Build status: STARTED
[06:22:38.234] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:17:0) after 6m0s
[06:28:38.254] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:17:0)
[06:28:38.292] Build status: STARTED
[06:28:38.292] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:18:0) after 9m27s
[06:38:05.430] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:18:0)
[06:38:05.465] Build status: STARTED
[06:38:05.465] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:19:0) after 5m40s
[06:43:45.520] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:19:0)
[06:43:45.555] Build status: STARTED
[06:43:45.555] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:20:0) after 3m24s
[06:47:09.902] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:20:0)
[06:47:09.943] Build status: STARTED
[06:47:09.943] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:21:0) after 2m43s
[06:49:52.963] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:21:0)
[06:49:52.999] Build status: STARTED
[06:49:52.999] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:22:0) after 3m10s
[06:53:03.025] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:22:0)
[06:53:03.101] Build status: STARTED
[06:53:03.101] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:23:0) after 1m59s
[06:55:02.366] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:23:0)
[06:55:02.401] Build status: STARTED
[06:55:02.401] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:24:0) after 8m0s
[07:03:02.520] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:24:0)
[07:03:02.553] Build status: STARTED
[07:03:02.554] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:25:0) after 5m51s
[07:08:53.677] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:25:0)
[07:08:53.714] Build status: STARTED
[07:08:53.714] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:26:0) after 7m0s
[07:15:53.750] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:26:0)
[07:15:53.789] Build status: STARTED
[07:15:53.789] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:27:0) after 3m15s
[07:19:08.823] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:27:0)
[07:19:08.865] Build status: STARTED
[07:19:08.865] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:28:0) after 3m7s
[07:22:15.887] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:28:0)
[07:22:15.943] Build status: STARTED
[07:22:15.943] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:29:0) after 5m1s
[07:27:16.966] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:29:0)
[07:27:17.004] Build status: STARTED
[07:27:17.004] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:30:0) after 4m16s
[07:31:33.015] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:30:0)
[07:31:33.046] Build status: STARTED
[07:31:33.046] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:31:0) after 1m13s
[07:32:46.067] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:31:0)
[07:32:46.128] Build status: STARTED
[07:32:46.128] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:32:0) after 5m12s
[07:37:58.396] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:32:0)
[07:37:58.439] Build status: STARTED
[07:37:58.440] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:33:0) after 7m29s
[07:45:27.461] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:33:0)
[07:45:27.495] Build status: STARTED
[07:45:27.495] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:34:0) after 7m19s
[07:52:46.665] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:34:0)
[07:52:57.532] Build status: STARTED
[07:52:57.532] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:35:0) after 8m37s
[08:01:34.552] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:35:0)
[08:01:34.594] Build status: STARTED
[08:01:34.594] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:36:0) after 2m31s
[08:04:05.616] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:36:0)
[08:04:05.651] Build status: STARTED
[08:04:05.651] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:37:0) after 6m32s
[08:10:37.803] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:37:0)
[08:10:37.836] Build status: STARTED
[08:10:37.836] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:38:0) after 7m7s
[08:17:44.977] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:38:0)
[08:17:45.013] Build status: STARTED
[08:17:45.013] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:39:0) after 4m32s
[08:22:17.082] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:39:0)
[08:22:17.116] Build status: STARTED
[08:22:17.116] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:40:0) after 7m59s
[08:30:16.762] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:40:0)
[08:30:16.816] Build status: STARTED
[08:30:16.816] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:41:0) after 7m13s
[08:37:29.903] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:41:0)
[08:37:29.958] Build status: STARTED
[08:37:29.958] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:42:0) after 9m47s
[08:47:17.056] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:42:0)
[08:47:17.099] Build status: STARTED
[08:47:17.099] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:43:0) after 9m40s
[08:56:57.219] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:43:0)
[08:56:57.262] Build status: STARTED
[08:56:57.262] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:44:0) after 1m59s
[08:58:56.424] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:44:0)
[08:58:56.517] Build status: STARTED
[08:58:56.517] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:45:0) after 7m9s
[09:06:05.623] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:45:0)
[09:06:05.671] Build status: STARTED
[09:06:05.671] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:46:0) after 5m19s
[09:11:24.690] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:46:0)
[09:11:24.727] Build status: STARTED
[09:11:24.727] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:47:0) after 3m18s
[09:14:42.941] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:47:0)
[09:14:42.973] Build status: STARTED
[09:14:42.973] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:48:0) after 4m26s
[09:19:09.035] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:48:0)
[09:19:09.073] Build status: STARTED
[09:19:09.073] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:49:0) after 8m56s
[09:28:05.095] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:49:0)
[09:28:05.133] Build status: STARTED
[09:28:05.133] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:50:0) after 8m28s
[09:36:33.158] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:50:0)
[09:36:33.195] Build status: STARTED
[09:36:33.195] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:51:0) after 1m25s
[09:37:58.218] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:51:0)
[09:37:58.250] Build status: STARTED
[09:37:58.250] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:52:0) after 4m43s
[09:42:41.270] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:52:0)
[09:42:41.307] Build status: STARTED
[09:42:41.307] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:53:0) after 9m24s
[09:52:05.379] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:53:0)
[09:52:05.410] Build status: STARTED
[09:52:05.410] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:54:0) after 4m29s
[09:56:34.485] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:54:0)
[09:56:34.527] Build status: STARTED
[09:56:34.527] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:55:0) after 2m55s
[09:59:29.547] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:55:0)
[09:59:29.635] Build status: STARTED
[09:59:29.635] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:56:0) after 4m43s
[10:04:12.667] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:56:0)
[10:04:12.706] Build status: STARTED
[10:04:12.706] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:57:0) after 3m37s
[10:07:49.897] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:57:0)
[10:07:49.947] Build status: STARTED
[10:07:49.947] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-chromiumos:8976022684319105296:58:0) after 5m39s
[10:10:50.324] Received PubSub notification, asking Buildbucket for the build status
[10:10:50.358] Build:
{
"id": "8810592622044184849",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-chromiumos"
},
"number": 12715,
"createdBy": "project:infra",
"createTime": "2022-06-23T05:12:06.246867841Z",
"startTime": "2022-06-23T05:12:41.288610Z",
"endTime": "2022-06-23T10:10:49.694135727Z",
"updateTime": "2022-06-23T10:10:49.694135727Z",
"status": "SUCCESS",
"input": {
}
}
[10:10:50.358] Invocation finished in 4h58m45.666673791s with status SUCCEEDED