[12:03:00.162] New invocation is queued and will start shortly
[12:03:00.162] Triggered by user:infra-codesearch@chops-service-accounts.iam.gserviceaccount.com
[12:03:01.346] Starting the invocation (attempt 1)
[12:03:01.384] Ignoring gitiles_ref tag without the buildset tag
[12:03:01.384] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:03:01.385] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:03:01.385] Buildbucket request:
{
"requestId": "9001001593928301456",
"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": "3176b81a49dc3811a2b55eb70df6499400d53470",
"codesearch_mirror_revision_timestamp": 1632139052,
"root_solution_revision": "b380e0f3d48352c2cee92646c8be723e464737c0",
"root_solution_revision_timestamp": 1632138958
},
"tags": [
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:13683",
"user_agent:recipe"
]
},
"id": "798bf06c-d49b-41f6-a7e2-25a011db3f37",
"title": "codesearch-gen-chromium-initiator/13683"
}
]
},
"codesearch_mirror_revision": "3176b81a49dc3811a2b55eb70df6499400d53470",
"codesearch_mirror_revision_timestamp": 1632139052,
"root_solution_revision": "b380e0f3d48352c2cee92646c8be723e464737c0",
"root_solution_revision_timestamp": 1632138958
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9001001593928301456"
},
{
"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": "13683"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[12:03:01.842] Scheduled build:
{
"id": "8835571531891087121",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"number": 8401,
"createdBy": "project:infra",
"createTime": "2021-09-20T12:03:01.473061113Z",
"updateTime": "2021-09-20T12:03:01.661600584Z",
"status": "SCHEDULED",
"input": {
},
"canary": true
}
[12:03:01.842] Task URL: https://cr-buildbucket.appspot.com/build/8835571531891087121
[12:03:01.843] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:2:0) after 1m50s
[12:03:18.818] Received PubSub notification, asking Buildbucket for the build status
[12:03:18.868] Build status: STARTED
[12:04:51.958] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:2:0)
[12:04:51.990] Build status: STARTED
[12:04:51.990] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:4:0) after 1m41s
[12:06:33.009] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:4:0)
[12:06:33.066] Build status: STARTED
[12:06:33.066] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:5:0) after 6m13s
[12:12:46.183] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:5:0)
[12:12:46.251] Build status: STARTED
[12:12:46.251] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:6:0) after 6m41s
[12:19:27.318] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:6:0)
[12:19:27.358] Build status: STARTED
[12:19:27.358] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:7:0) after 7m1s
[12:26:28.442] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:7:0)
[12:26:28.525] Build status: STARTED
[12:26:28.525] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:8:0) after 9m58s
[12:36:26.740] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:8:0)
[12:36:26.769] Build status: STARTED
[12:36:26.769] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:9:0) after 2m52s
[12:39:18.867] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:9:0)
[12:39:19.151] Build status: STARTED
[12:39:19.151] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:10:0) after 2m56s
[12:42:15.171] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:10:0)
[12:42:15.206] Build status: STARTED
[12:42:15.206] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:11:0) after 9m55s
[12:52:10.299] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:11:0)
[12:52:10.356] Build status: STARTED
[12:52:10.356] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:12:0) after 4m50s
[12:57:00.445] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:12:0)
[12:57:00.484] Build status: STARTED
[12:57:00.484] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:13:0) after 6m51s
[13:03:51.507] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:13:0)
[13:03:51.552] Build status: STARTED
[13:03:51.552] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:14:0) after 2m20s
[13:06:11.589] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:14:0)
[13:06:11.620] Build status: STARTED
[13:06:11.620] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:15:0) after 9m59s
[13:16:10.643] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:15:0)
[13:16:10.678] Build status: STARTED
[13:16:10.678] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:16:0) after 4m25s
[13:20:35.709] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:16:0)
[13:20:35.744] Build status: STARTED
[13:20:35.744] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:17:0) after 7m55s
[13:28:30.754] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:17:0)
[13:28:30.785] Build status: STARTED
[13:28:30.785] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:18:0) after 7m53s
[13:36:23.913] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:18:0)
[13:36:23.957] Build status: STARTED
[13:36:23.957] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:19:0) after 2m28s
[13:38:52.013] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:19:0)
[13:38:52.057] Build status: STARTED
[13:38:52.057] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:20:0) after 4m28s
[13:43:20.109] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:20:0)
[13:43:20.136] Build status: STARTED
[13:43:20.136] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:21:0) after 9m51s
[13:53:11.253] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:21:0)
[13:53:11.290] Build status: STARTED
[13:53:11.290] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:22:0) after 6m20s
[13:59:31.413] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:22:0)
[13:59:31.469] Build status: STARTED
[13:59:31.469] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:23:0) after 2m2s
[14:01:33.489] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:23:0)
[14:01:33.517] Build status: STARTED
[14:01:33.517] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:24:0) after 8m49s
[14:10:22.538] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:24:0)
[14:10:22.572] Build status: STARTED
[14:10:22.572] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:25:0) after 3m44s
[14:14:06.730] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:25:0)
[14:14:06.777] Build status: STARTED
[14:14:06.777] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:26:0) after 4m54s
[14:19:00.921] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:26:0)
[14:19:00.952] Build status: STARTED
[14:19:00.952] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:27:0) after 5m29s
[14:24:29.980] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:27:0)
[14:24:30.016] Build status: STARTED
[14:24:30.016] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:28:0) after 2m29s
[14:26:59.035] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:28:0)
[14:26:59.082] Build status: STARTED
[14:26:59.082] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:29:0) after 6m20s
[14:33:19.176] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:29:0)
[14:33:19.207] Build status: STARTED
[14:33:19.207] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:30:0) after 4m48s
[14:38:07.473] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:30:0)
[14:38:07.518] Build status: STARTED
[14:38:07.518] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:31:0) after 4m51s
[14:42:58.521] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:31:0)
[14:42:58.553] Build status: STARTED
[14:42:58.553] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:32:0) after 6m23s
[14:49:21.580] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:32:0)
[14:49:21.712] Build status: STARTED
[14:49:21.712] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:33:0) after 3m16s
[14:52:37.730] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:33:0)
[14:52:37.764] Build status: STARTED
[14:52:37.764] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:34:0) after 8m22s
[15:00:59.888] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:34:0)
[15:00:59.982] Build status: STARTED
[15:00:59.982] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9001001593928301456:35:0) after 2m47s
[15:01:09.176] Received PubSub notification, asking Buildbucket for the build status
[15:01:09.214] Build:
{
"id": "8835571531891087121",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"number": 8401,
"createdBy": "project:infra",
"createTime": "2021-09-20T12:03:01.473061113Z",
"startTime": "2021-09-20T12:03:17.913255Z",
"endTime": "2021-09-20T15:01:08.359108791Z",
"updateTime": "2021-09-20T15:01:08.359770925Z",
"status": "SUCCESS",
"input": {
},
"canary": true
}
[15:01:09.214] Invocation finished in 2h58m9.067984827s with status SUCCEEDED