[04:04:19.505] New invocation is queued and will start shortly
[04:04:19.505] Triggered by user:infra-codesearch@chops-service-accounts.iam.gserviceaccount.com
[04:04:20.578] Starting the invocation (attempt 1)
[04:04:20.622] Ignoring gitiles_ref tag without the buildset tag
[04:04:20.622] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:04:20.622] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:04:20.622] Buildbucket request:
{
"requestId": "9009185436695280064",
"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": "adb644e0f0e6aa420202618bc5cda82bd0b2ddeb",
"codesearch_mirror_revision_timestamp": 1624334445,
"root_solution_revision": "6c67ccf3e65c7592fdeccc686a8a89145db0aea7",
"root_solution_revision_timestamp": 1624334197
},
"tags": [
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:13141",
"user_agent:recipe"
]
},
"id": "6dc9585d-0317-4f34-8bc2-0e102348b9ab",
"title": "codesearch-gen-chromium-initiator/13141"
}
]
},
"codesearch_mirror_revision": "adb644e0f0e6aa420202618bc5cda82bd0b2ddeb",
"codesearch_mirror_revision_timestamp": 1624334445,
"root_solution_revision": "6c67ccf3e65c7592fdeccc686a8a89145db0aea7",
"root_solution_revision_timestamp": 1624334197
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9009185436695280064"
},
{
"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": "13141"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[04:04:21.186] Scheduled build:
{
"id": "8843755374725735520",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"number": 7859,
"createdBy": "project:infra",
"createTime": "2021-06-22T04:04:20.752101Z",
"updateTime": "2021-06-22T04:04:21.110520Z",
"status": "SCHEDULED",
"input": {
}
}
[04:04:21.186] Task URL: https://cr-buildbucket.appspot.com/build/8843755374725735520
[04:04:21.186] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:2:0) after 5m51s
[04:05:04.077] Received PubSub notification, asking Buildbucket for the build status
[04:05:04.150] Build status: STARTED
[04:10:12.229] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:2:0)
[04:10:13.116] Build status: STARTED
[04:10:13.116] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:4:0) after 3m31s
[04:13:44.278] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:4:0)
[04:13:44.316] Build status: STARTED
[04:13:44.316] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:5:0) after 8m13s
[04:21:57.309] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:5:0)
[04:21:57.501] Build status: STARTED
[04:21:57.501] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:6:0) after 6m13s
[04:28:10.605] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:6:0)
[04:28:10.652] Build status: STARTED
[04:28:10.652] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:7:0) after 6m9s
[04:34:19.675] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:7:0)
[04:34:19.751] Build status: STARTED
[04:34:19.751] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:8:0) after 9m58s
[04:44:17.767] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:8:0)
[04:44:17.802] Build status: STARTED
[04:44:17.802] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:9:0) after 1m18s
[04:45:35.930] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:9:0)
[04:45:35.975] Build status: STARTED
[04:45:35.975] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:10:0) after 9m18s
[04:54:54.090] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:10:0)
[04:54:54.153] Build status: STARTED
[04:54:54.153] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:11:0) after 2m42s
[04:57:44.358] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:11:0)
[04:57:44.405] Build status: STARTED
[04:57:44.405] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:12:0) after 4m15s
[05:01:59.406] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:12:0)
[05:01:59.479] Build status: STARTED
[05:01:59.479] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:13:0) after 1m19s
[05:03:18.544] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:13:0)
[05:03:18.600] Build status: STARTED
[05:03:18.600] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:14:0) after 7m1s
[05:10:19.822] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:14:0)
[05:10:19.863] Build status: STARTED
[05:10:19.863] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:15:0) after 5m58s
[05:16:17.882] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:15:0)
[05:16:17.930] Build status: STARTED
[05:16:17.930] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:16:0) after 4m48s
[05:21:05.956] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:16:0)
[05:21:06.031] Build status: STARTED
[05:21:06.031] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:17:0) after 2m30s
[05:23:36.209] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:17:0)
[05:23:36.273] Build status: STARTED
[05:23:36.274] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:18:0) after 1m44s
[05:25:20.325] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:18:0)
[05:25:20.385] Build status: STARTED
[05:25:20.385] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:19:0) after 2m37s
[05:27:57.358] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:19:0)
[05:27:57.393] Build status: STARTED
[05:27:57.393] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:20:0) after 4m48s
[05:32:45.489] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:20:0)
[05:32:45.561] Build status: STARTED
[05:32:45.561] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:21:0) after 8m54s
[05:41:39.579] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:21:0)
[05:41:39.635] Build status: STARTED
[05:41:39.635] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:22:0) after 5m24s
[05:47:03.947] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:22:0)
[05:47:04.113] Build status: STARTED
[05:47:04.113] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:23:0) after 4m30s
[05:51:34.137] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:23:0)
[05:51:34.196] Build status: STARTED
[05:51:34.196] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:24:0) after 8m3s
[05:59:37.219] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:24:0)
[05:59:37.271] Build status: STARTED
[05:59:37.271] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:25:0) after 4m22s
[06:03:59.289] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:25:0)
[06:03:59.599] Build status: STARTED
[06:03:59.599] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:26:0) after 2m47s
[06:06:46.708] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:26:0)
[06:06:46.740] Build status: STARTED
[06:06:46.740] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:27:0) after 5m13s
[06:11:59.902] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:27:0)
[06:11:59.976] Build status: STARTED
[06:11:59.976] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:28:0) after 5m36s
[06:17:36.252] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:28:0)
[06:17:36.301] Build status: STARTED
[06:17:36.301] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:29:0) after 2m5s
[06:19:41.320] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:29:0)
[06:19:41.396] Build status: STARTED
[06:19:41.396] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:30:0) after 8m38s
[06:28:19.420] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:30:0)
[06:28:19.471] Build status: STARTED
[06:28:19.471] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:31:0) after 9m1s
[06:37:20.498] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:31:0)
[06:37:20.534] Build status: STARTED
[06:37:20.535] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:32:0) after 8m58s
[06:46:18.640] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:32:0)
[06:46:18.688] Build status: STARTED
[06:46:18.688] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:33:0) after 9m4s
[06:55:22.861] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:33:0)
[06:55:22.917] Build status: STARTED
[06:55:22.917] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:34:0) after 7m23s
[07:02:45.999] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:34:0)
[07:02:46.150] Build status: STARTED
[07:02:46.150] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:35:0) after 9m51s
[07:12:37.211] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:35:0)
[07:12:37.256] Build status: STARTED
[07:12:37.256] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:36:0) after 4m36s
[07:17:13.495] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:36:0)
[07:17:13.541] Build status: STARTED
[07:17:13.541] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:37:0) after 3m25s
[07:20:38.638] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:37:0)
[07:20:38.685] Build status: STARTED
[07:20:38.685] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:38:0) after 4m6s
[07:24:44.792] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:38:0)
[07:24:44.857] Build status: STARTED
[07:24:44.857] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:39:0) after 4m24s
[07:29:08.979] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:39:0)
[07:29:09.025] Build status: STARTED
[07:29:09.025] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:40:0) after 8m4s
[07:37:13.046] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:40:0)
[07:37:13.103] Build status: STARTED
[07:37:13.103] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9009185436695280064:41:0) after 8m15s
[07:44:57.434] Received PubSub notification, asking Buildbucket for the build status
[07:44:57.626] Build:
{
"id": "8843755374725735520",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"number": 7859,
"createdBy": "project:infra",
"createTime": "2021-06-22T04:04:20.752101Z",
"startTime": "2021-06-22T04:05:03.519838Z",
"endTime": "2021-06-22T07:44:57.119131281Z",
"updateTime": "2021-06-22T07:44:57.119646457Z",
"status": "SUCCESS",
"input": {
}
}
[07:44:57.626] Invocation finished in 3h40m38.136516436s with status SUCCEEDED