[22:22:21.397] New invocation is queued and will start shortly
[22:22:21.397] Triggered by user:infra-codesearch@chops-service-accounts.iam.gserviceaccount.com
[22:22:22.520] Starting the invocation (attempt 1)
[22:22:22.557] Ignoring gitiles_ref tag without the buildset tag
[22:22:22.557] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:22:22.557] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:22:22.557] Buildbucket request:
{
"requestId": "9015367545206833616",
"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": "efb0f6367a11f87129195032753f332b925ecf5f",
"codesearch_mirror_revision_timestamp": 1618430009,
"root_solution_revision": "ee30f3894050c29baf012e30cef02bbf09cad002",
"root_solution_revision_timestamp": 1618429901
},
"tags": [
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:12731",
"user_agent:recipe"
]
},
"id": "f7540348-93b0-427c-aebb-2352fcf64d01",
"title": "codesearch-gen-chromium-initiator/12731"
}
]
},
"codesearch_mirror_revision": "efb0f6367a11f87129195032753f332b925ecf5f",
"codesearch_mirror_revision_timestamp": 1618430009,
"root_solution_revision": "ee30f3894050c29baf012e30cef02bbf09cad002",
"root_solution_revision_timestamp": 1618429901
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9015367545206833616"
},
{
"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": "12731"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:22:23.136] Scheduled build:
{
"id": "8849937483211855968",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"number": 7451,
"createdBy": "project:infra",
"createTime": "2021-04-14T22:22:22.667431Z",
"updateTime": "2021-04-14T22:22:23.060409Z",
"status": "SCHEDULED",
"input": {
},
"canary": true
}
[22:22:23.136] Task URL: https://cr-buildbucket.appspot.com/build/8849937483211855968
[22:22:23.136] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:2:0) after 7m25s
[22:25:42.844] Received PubSub notification, asking Buildbucket for the build status
[22:25:42.873] Build status: STARTED
[22:29:48.316] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:2:0)
[22:29:48.346] Build status: STARTED
[22:29:48.346] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:4:0) after 6m59s
[22:36:47.465] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:4:0)
[22:36:47.493] Build status: STARTED
[22:36:47.493] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:5:0) after 6m53s
[22:43:40.746] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:5:0)
[22:43:40.772] Build status: STARTED
[22:43:40.772] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:6:0) after 3m33s
[22:47:13.781] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:6:0)
[22:47:13.810] Build status: STARTED
[22:47:13.810] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:7:0) after 2m18s
[22:49:31.853] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:7:0)
[22:49:31.878] Build status: STARTED
[22:49:31.878] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:8:0) after 9m40s
[22:59:11.899] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:8:0)
[22:59:11.932] Build status: STARTED
[22:59:11.932] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:9:0) after 8m49s
[23:08:01.128] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:9:0)
[23:08:01.159] Build status: STARTED
[23:08:01.159] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:10:0) after 7m31s
[23:15:32.364] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:10:0)
[23:15:32.395] Build status: STARTED
[23:15:32.395] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:11:0) after 8m4s
[23:23:36.547] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:11:0)
[23:23:36.582] Build status: STARTED
[23:23:36.582] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:12:0) after 6m51s
[23:30:27.890] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:12:0)
[23:30:27.922] Build status: STARTED
[23:30:27.922] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:13:0) after 1m12s
[23:31:39.938] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:13:0)
[23:31:39.964] Build status: STARTED
[23:31:39.964] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:14:0) after 9m41s
[23:41:21.081] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:14:0)
[23:41:21.168] Build status: STARTED
[23:41:21.168] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:15:0) after 5m35s
[23:46:56.247] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:15:0)
[23:46:56.277] Build status: STARTED
[23:46:56.277] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:16:0) after 5m10s
[23:52:06.297] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:16:0)
[23:52:06.336] Build status: STARTED
[23:52:06.336] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:17:0) after 9m2s
[00:01:08.435] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:17:0)
[00:01:08.469] Build status: STARTED
[00:01:08.469] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:18:0) after 1m52s
[00:03:00.576] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:18:0)
[00:03:00.708] Build status: STARTED
[00:03:00.708] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:19:0) after 4m51s
[00:07:51.941] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:19:0)
[00:07:51.967] Build status: STARTED
[00:07:51.967] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:20:0) after 7m58s
[00:15:49.993] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:20:0)
[00:15:50.023] Build status: STARTED
[00:15:50.023] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:21:0) after 9m10s
[00:25:00.041] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:21:0)
[00:25:00.073] Build status: STARTED
[00:25:00.073] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:22:0) after 4m13s
[00:29:13.093] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:22:0)
[00:29:13.117] Build status: STARTED
[00:29:13.117] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:23:0) after 7m55s
[00:37:08.139] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:23:0)
[00:37:08.169] Build status: STARTED
[00:37:08.169] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:24:0) after 5m43s
[00:42:51.196] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:24:0)
[00:42:51.227] Build status: STARTED
[00:42:51.227] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:25:0) after 8m22s
[00:51:13.265] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:25:0)
[00:51:13.293] Build status: STARTED
[00:51:13.293] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:26:0) after 3m56s
[00:55:09.312] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:26:0)
[00:55:09.358] Build status: STARTED
[00:55:09.358] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:27:0) after 1m21s
[00:56:30.384] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:27:0)
[00:56:30.409] Build status: STARTED
[00:56:30.409] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:28:0) after 7m38s
[01:04:08.799] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:28:0)
[01:04:08.829] Build status: STARTED
[01:04:08.829] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:29:0) after 1m5s
[01:05:13.857] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:29:0)
[01:05:13.892] Build status: STARTED
[01:05:13.892] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:30:0) after 3m3s
[01:08:16.964] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:30:0)
[01:08:16.995] Build status: STARTED
[01:08:16.995] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:31:0) after 4m54s
[01:13:10.971] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:31:0)
[01:13:11.007] Build status: STARTED
[01:13:11.007] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:32:0) after 3m26s
[01:16:37.131] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:32:0)
[01:16:37.162] Build status: STARTED
[01:16:37.162] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:33:0) after 5m35s
[01:22:12.443] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:33:0)
[01:22:12.474] Build status: STARTED
[01:22:12.474] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:34:0) after 1m37s
[01:23:49.600] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:34:0)
[01:23:49.627] Build status: STARTED
[01:23:49.627] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:35:0) after 8m47s
[01:32:36.650] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:35:0)
[01:32:36.679] Build status: STARTED
[01:32:36.679] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:36:0) after 1m47s
[01:34:23.917] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:36:0)
[01:34:23.948] Build status: STARTED
[01:34:23.948] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:37:0) after 8m7s
[01:42:31.083] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:37:0)
[01:42:31.116] Build status: STARTED
[01:42:31.116] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:38:0) after 7m49s
[01:50:20.275] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:38:0)
[01:50:20.300] Build status: STARTED
[01:50:20.300] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:39:0) after 3m34s
[01:53:54.313] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:39:0)
[01:53:54.340] Build status: STARTED
[01:53:54.340] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:40:0) after 7m8s
[02:01:02.363] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:40:0)
[02:01:02.394] Build status: STARTED
[02:01:02.394] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:41:0) after 5m23s
[02:06:25.642] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:41:0)
[02:06:25.676] Build status: STARTED
[02:06:25.676] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:42:0) after 7m22s
[02:13:47.698] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:42:0)
[02:13:47.748] Build status: STARTED
[02:13:47.748] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-win:9015367545206833616:43:0) after 7m42s
[02:19:06.116] Received PubSub notification, asking Buildbucket for the build status
[02:19:06.157] Build:
{
"id": "8849937483211855968",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-win"
},
"number": 7451,
"createdBy": "project:infra",
"createTime": "2021-04-14T22:22:22.667431Z",
"startTime": "2021-04-14T22:25:42.141899Z",
"endTime": "2021-04-15T02:18:55.586509395Z",
"updateTime": "2021-04-15T02:18:55.586857690Z",
"status": "SUCCESS",
"input": {
},
"canary": true
}
[02:19:06.157] Invocation finished in 3h56m44.775873528s with status SUCCEEDED