[04:01:40.816] New invocation is queued and will start shortly
[04:01:40.816] Triggered by user:infra-codesearch@chops-service-accounts.iam.gserviceaccount.com
[04:01:41.856] Starting the invocation (attempt 1)
[04:01:41.890] Ignoring gitiles_ref tag without the buildset tag
[04:01:41.890] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:01:41.890] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:01:41.890] Buildbucket request:
{
"requestId": "8987804719021370960",
"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": "b77d6164dfa716a14a1d2655524d81a275d61433",
"codesearch_mirror_revision_timestamp": 1644721141,
"root_solution_revision": "8808831c3f92ea3c6235de6346cf069716a9c7cb",
"root_solution_revision_timestamp": 1644720943
},
"tags": [
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:14557",
"user_agent:recipe"
]
},
"id": "8f618557-b28f-46a0-86f3-3e4eb3406e3c",
"title": "codesearch-gen-chromium-initiator/14557"
}
]
},
"codesearch_mirror_revision": "b77d6164dfa716a14a1d2655524d81a275d61433",
"codesearch_mirror_revision_timestamp": 1644721141,
"root_solution_revision": "8808831c3f92ea3c6235de6346cf069716a9c7cb",
"root_solution_revision_timestamp": 1644720943
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8987804719021370960"
},
{
"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": "14557"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[04:01:42.562] Scheduled build:
{
"id": "8822374657116429025",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"number": 9276,
"createdBy": "project:infra",
"createTime": "2022-02-13T04:01:42.002165320Z",
"updateTime": "2022-02-13T04:01:42.002165320Z",
"status": "SCHEDULED",
"input": {
}
}
[04:01:42.562] Task URL: https://cr-buildbucket.appspot.com/build/8822374657116429025
[04:01:42.562] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:2:0) after 6m59s
[04:01:47.484] Received PubSub notification, asking Buildbucket for the build status
[04:01:47.513] Build status: STARTED
[04:08:41.579] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:2:0)
[04:08:41.613] Build status: STARTED
[04:08:41.614] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:4:0) after 4m29s
[04:13:10.643] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:4:0)
[04:13:10.674] Build status: STARTED
[04:13:10.674] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:5:0) after 9m47s
[04:22:57.753] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:5:0)
[04:22:57.784] Build status: STARTED
[04:22:57.784] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:6:0) after 3m11s
[04:26:08.932] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:6:0)
[04:26:08.960] Build status: STARTED
[04:26:08.960] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:7:0) after 3m6s
[04:29:15.749] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:7:0)
[04:29:15.780] Build status: STARTED
[04:29:15.781] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:8:0) after 4m47s
[04:34:03.035] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:8:0)
[04:34:03.066] Build status: STARTED
[04:34:03.066] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:9:0) after 9m31s
[04:43:34.083] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:9:0)
[04:43:34.114] Build status: STARTED
[04:43:34.114] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:10:0) after 9m47s
[04:53:21.131] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:10:0)
[04:53:21.185] Build status: STARTED
[04:53:21.185] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:11:0) after 7m2s
[05:00:23.208] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:11:0)
[05:00:23.247] Build status: STARTED
[05:00:23.247] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:12:0) after 7m11s
[05:07:34.361] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:12:0)
[05:07:34.398] Build status: STARTED
[05:07:34.398] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:13:0) after 2m49s
[05:10:23.458] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:13:0)
[05:10:23.491] Build status: STARTED
[05:10:23.491] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:14:0) after 1m2s
[05:11:25.508] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:14:0)
[05:11:25.536] Build status: STARTED
[05:11:25.536] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:15:0) after 3m46s
[05:15:11.558] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:15:0)
[05:15:11.595] Build status: STARTED
[05:15:11.595] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:16:0) after 3m14s
[05:18:26.034] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:16:0)
[05:18:26.069] Build status: STARTED
[05:18:26.069] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:17:0) after 8m23s
[05:26:49.431] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:17:0)
[05:26:49.460] Build status: STARTED
[05:26:49.460] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:18:0) after 3m19s
[05:30:08.583] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:18:0)
[05:30:08.649] Build status: STARTED
[05:30:08.649] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:19:0) after 6m12s
[05:36:20.669] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:19:0)
[05:36:20.704] Build status: STARTED
[05:36:20.704] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:20:0) after 6m3s
[05:42:23.681] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:20:0)
[05:42:23.717] Build status: STARTED
[05:42:23.717] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:21:0) after 1m47s
[05:44:10.832] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:21:0)
[05:44:10.860] Build status: STARTED
[05:44:10.860] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:22:0) after 3m56s
[05:48:06.879] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:22:0)
[05:48:06.908] Build status: STARTED
[05:48:06.908] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:23:0) after 4m46s
[05:52:52.927] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:23:0)
[05:52:52.956] Build status: STARTED
[05:52:52.956] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:24:0) after 5m49s
[05:58:41.974] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:24:0)
[05:58:42.071] Build status: STARTED
[05:58:42.071] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:25:0) after 8m24s
[06:07:06.142] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:25:0)
[06:07:06.173] Build status: STARTED
[06:07:06.173] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:26:0) after 8m9s
[06:15:15.216] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:26:0)
[06:15:15.248] Build status: STARTED
[06:15:15.248] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:8987804719021370960:27:0) after 4m8s
[06:18:35.770] Received PubSub notification, asking Buildbucket for the build status
[06:18:35.803] Build:
{
"id": "8822374657116429025",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"number": 9276,
"createdBy": "project:infra",
"createTime": "2022-02-13T04:01:42.002165320Z",
"startTime": "2022-02-13T04:01:46.525671Z",
"endTime": "2022-02-13T06:18:35.451939599Z",
"updateTime": "2022-02-13T06:18:35.451939599Z",
"status": "SUCCESS",
"input": {
}
}
[06:18:35.803] Invocation finished in 2h16m55.000771343s with status SUCCEEDED