[12:02:33.433] New invocation is queued and will start shortly
[12:02:33.433] Triggered by user:infra-codesearch@chops-service-accounts.iam.gserviceaccount.com
[12:02:35.786] Starting the invocation (attempt 1)
[12:02:35.822] Ignoring gitiles_ref tag without the buildset tag
[12:02:35.822] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:02:35.822] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:02:35.823] Buildbucket request:
{
"requestId": "8998827294762620624",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"triggers": [
{
"buildbucket": {
"properties": {
"codesearch_mirror_revision": "4e1a2c9cbe37836804be62110a7d30c6db4ce108",
"codesearch_mirror_revision_timestamp": 1634211737,
"root_solution_revision": "0181faab19c13c2501a2a4b62e1def9d920c2d85",
"root_solution_revision_timestamp": 1634211615
},
"tags": [
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:13827",
"user_agent:recipe"
]
},
"id": "f0a04672-1606-4de3-b297-5a9d53698f92",
"title": "codesearch-gen-chromium-initiator/13827"
}
]
},
"codesearch_mirror_revision": "4e1a2c9cbe37836804be62110a7d30c6db4ce108",
"codesearch_mirror_revision_timestamp": 1634211737,
"root_solution_revision": "0181faab19c13c2501a2a4b62e1def9d920c2d85",
"root_solution_revision_timestamp": 1634211615
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8998827294762620624"
},
{
"key": "scheduler_job_id",
"value": "infra/codesearch-gen-chromium-win"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "codesearch-gen-chromium-initiator"
},
{
"key": "parent_buildnumber",
"value": "13827"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[12:02:37.312] Scheduled build:
{
"id": "8833397231522936561",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"number": 8545,
"createdBy": "project:infra",
"createTime": "2021-10-14T12:02:35.890149944Z",
"updateTime": "2021-10-14T12:02:36.098413227Z",
"status": "SCHEDULED",
"input": {
}
}
[12:02:37.312] Task URL: https://cr-buildbucket.appspot.com/build/8833397231522936561
[12:02:37.312] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:2:0) after 2m9s
[12:03:08.404] Received PubSub notification, asking Buildbucket for the build status
[12:03:08.433] Build status: STARTED
[12:04:46.494] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:2:0)
[12:04:46.522] Build status: STARTED
[12:04:46.522] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:4:0) after 1m19s
[12:06:05.524] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:4:0)
[12:06:05.548] Build status: STARTED
[12:06:05.548] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:5:0) after 4m55s
[12:11:00.567] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:5:0)
[12:11:00.633] Build status: STARTED
[12:11:00.633] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:6:0) after 4m21s
[12:15:21.651] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:6:0)
[12:15:21.683] Build status: STARTED
[12:15:21.683] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:7:0) after 4m32s
[12:19:53.724] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:7:0)
[12:19:53.754] Build status: STARTED
[12:19:53.754] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:8:0) after 3m48s
[12:23:41.772] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:8:0)
[12:23:41.805] Build status: STARTED
[12:23:41.805] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:9:0) after 9m45s
[12:33:26.835] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:9:0)
[12:33:26.866] Build status: STARTED
[12:33:26.866] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:10:0) after 7m5s
[12:40:31.883] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:10:0)
[12:40:31.910] Build status: STARTED
[12:40:31.910] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:11:0) after 8m32s
[12:49:04.082] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:11:0)
[12:49:04.110] Build status: STARTED
[12:49:04.111] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:12:0) after 9m44s
[12:58:48.133] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:12:0)
[12:58:48.163] Build status: STARTED
[12:58:48.163] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:13:0) after 4m4s
[13:02:52.206] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:13:0)
[13:02:52.245] Build status: STARTED
[13:02:52.245] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:14:0) after 2m43s
[13:05:35.264] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:14:0)
[13:05:35.293] Build status: STARTED
[13:05:35.293] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:15:0) after 3m47s
[13:09:22.337] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:15:0)
[13:09:22.369] Build status: STARTED
[13:09:22.369] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:16:0) after 1m38s
[13:11:00.451] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:16:0)
[13:11:00.488] Build status: STARTED
[13:11:00.488] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:17:0) after 2m18s
[13:13:18.604] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:17:0)
[13:13:18.688] Build status: STARTED
[13:13:18.688] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:18:0) after 1m25s
[13:14:43.806] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:18:0)
[13:14:43.832] Build status: STARTED
[13:14:43.832] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:19:0) after 5m24s
[13:20:07.890] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:19:0)
[13:20:07.990] Build status: STARTED
[13:20:07.990] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:20:0) after 6m17s
[13:26:25.104] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:20:0)
[13:26:25.141] Build status: STARTED
[13:26:25.141] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:21:0) after 2m12s
[13:28:37.218] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:21:0)
[13:28:37.247] Build status: STARTED
[13:28:37.248] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:22:0) after 1m3s
[13:29:40.265] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:22:0)
[13:29:40.301] Build status: STARTED
[13:29:40.301] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:23:0) after 5m4s
[13:34:44.467] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:23:0)
[13:34:44.495] Build status: STARTED
[13:34:44.495] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:24:0) after 3m24s
[13:38:08.661] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:24:0)
[13:38:08.688] Build status: STARTED
[13:38:08.688] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:25:0) after 4m28s
[13:42:36.757] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:25:0)
[13:42:36.788] Build status: STARTED
[13:42:36.788] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:26:0) after 7m36s
[13:50:12.831] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:26:0)
[13:50:12.887] Build status: STARTED
[13:50:12.887] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:27:0) after 5m17s
[13:55:30.085] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:27:0)
[13:55:30.125] Build status: STARTED
[13:55:30.125] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:28:0) after 1m56s
[13:57:26.141] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:28:0)
[13:57:26.171] Build status: STARTED
[13:57:26.171] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:29:0) after 4m16s
[14:01:42.327] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:29:0)
[14:01:42.357] Build status: STARTED
[14:01:42.357] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:30:0) after 1m22s
[14:03:04.381] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:30:0)
[14:03:04.417] Build status: STARTED
[14:03:04.417] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:31:0) after 6m17s
[14:09:21.506] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:31:0)
[14:09:21.593] Build status: STARTED
[14:09:21.593] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:32:0) after 5m38s
[14:14:59.611] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:32:0)
[14:14:59.637] Build status: STARTED
[14:14:59.637] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:33:0) after 2m31s
[14:17:30.654] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:33:0)
[14:17:30.695] Build status: STARTED
[14:17:30.695] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:34:0) after 5m10s
[14:22:40.894] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:34:0)
[14:22:41.031] Build status: STARTED
[14:22:41.031] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:35:0) after 9m3s
[14:31:44.126] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:35:0)
[14:31:44.155] Build status: STARTED
[14:31:44.155] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:36:0) after 8m56s
[14:40:40.174] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:36:0)
[14:40:40.206] Build status: STARTED
[14:40:40.206] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:37:0) after 8m0s
[14:48:40.278] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:37:0)
[14:48:40.307] Build status: STARTED
[14:48:40.307] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:38:0) after 9m4s
[14:57:44.404] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:38:0)
[14:57:44.431] Build status: STARTED
[14:57:44.431] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:39:0) after 7m1s
[15:04:45.449] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:39:0)
[15:04:45.492] Build status: STARTED
[15:04:45.492] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:40:0) after 2m44s
[15:07:29.512] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:40:0)
[15:07:29.545] Build status: STARTED
[15:07:29.545] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:41:0) after 2m40s
[15:10:10.077] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:41:0)
[15:10:10.118] Build status: STARTED
[15:10:10.118] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:42:0) after 7m13s
[15:17:23.162] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:42:0)
[15:17:23.196] Build status: STARTED
[15:17:23.196] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:43:0) after 1m48s
[15:19:11.302] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:43:0)
[15:19:11.354] Build status: STARTED
[15:19:11.354] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:44:0) after 7m43s
[15:26:54.415] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:44:0)
[15:26:54.465] Build status: STARTED
[15:26:54.465] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:45:0) after 5m20s
[15:32:14.581] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:45:0)
[15:32:14.610] Build status: STARTED
[15:32:14.610] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:46:0) after 8m7s
[15:40:21.604] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:46:0)
[15:40:21.637] Build status: STARTED
[15:40:21.637] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:47:0) after 2m50s
[15:43:11.690] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:47:0)
[15:43:11.726] Build status: STARTED
[15:43:11.726] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8998827294762620624:48:0) after 8m42s
[15:46:12.614] Received PubSub notification, asking Buildbucket for the build status
[15:46:12.643] Build:
{
"id": "8833397231522936561",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"number": 8545,
"createdBy": "project:infra",
"createTime": "2021-10-14T12:02:35.890149944Z",
"startTime": "2021-10-14T12:03:06.518598Z",
"endTime": "2021-10-14T15:46:12.033448367Z",
"updateTime": "2021-10-14T15:46:12.033902630Z",
"status": "SUCCESS",
"input": {
}
}
[15:46:12.644] Invocation finished in 3h43m39.226082472s with status SUCCEEDED