[19:46:16.179] New invocation is queued and will start shortly
[19:46:17.306] Starting the invocation (attempt 1)
[19:46:17.332] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/89b07bbd3d0ca41c4271e9675a8d3e0c5f47d65f"
[19:46:17.332] Popped gitiles commit info from properties and tags
[19:46:17.332] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:46:17.332] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:46:17.333] Buildbucket request:
{
"requestId": "8896242354533378576",
"builder": {
"project": "chromium",
"bucket": "reclient",
"builder": "Linux Builder (canonical wd) (reclient compare)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "89b07bbd3d0ca41c4271e9675a8d3e0c5f47d65f",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8896242354533378576"
},
{
"key": "scheduler_job_id",
"value": "chromium/Linux Builder (canonical wd) (reclient compare)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:46:17.918] Scheduled build:
{
"id": "8730812292590417425",
"builder": {
"project": "chromium",
"bucket": "reclient",
"builder": "Linux Builder (canonical wd) (reclient compare)"
},
"number": 7218,
"createdBy": "project:chromium",
"createTime": "2024-11-19T19:46:17.400278148Z",
"updateTime": "2024-11-19T19:46:17.400278148Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "89b07bbd3d0ca41c4271e9675a8d3e0c5f47d65f",
"ref": "refs/heads/main"
}
}
}
[19:46:17.919] Task URL: https://cr-buildbucket.appspot.com/build/8730812292590417425
[19:46:17.919] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:2:0) after 7m37s
[19:46:18.901] Received PubSub notification, asking Buildbucket for the build status
[19:46:19.807] Build status: SCHEDULED
[19:46:22.841] Received PubSub notification, asking Buildbucket for the build status
[19:46:22.870] Build status: STARTED
[19:53:54.951] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:2:0)
[19:53:54.982] Build status: STARTED
[19:53:54.982] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:5:0) after 3m41s
[19:57:36.140] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:5:0)
[19:57:36.171] Build status: STARTED
[19:57:36.171] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:6:0) after 9m32s
[20:07:08.194] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:6:0)
[20:07:08.211] Build status: STARTED
[20:07:08.211] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:7:0) after 3m31s
[20:10:39.346] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:7:0)
[20:10:39.363] Build status: STARTED
[20:10:39.363] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:8:0) after 5m9s
[20:15:48.423] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:8:0)
[20:15:48.458] Build status: STARTED
[20:15:48.458] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:9:0) after 4m13s
[20:20:01.540] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:9:0)
[20:20:01.573] Build status: STARTED
[20:20:01.573] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:10:0) after 8m43s
[20:28:44.607] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:10:0)
[20:28:44.622] Build status: STARTED
[20:28:44.622] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:11:0) after 3m57s
[20:32:41.862] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:11:0)
[20:32:41.892] Build status: STARTED
[20:32:41.892] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:12:0) after 2m3s
[20:34:44.982] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:12:0)
[20:34:45.059] Build status: STARTED
[20:34:45.060] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:13:0) after 5m11s
[20:39:56.177] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:13:0)
[20:39:56.223] Build status: STARTED
[20:39:56.224] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:14:0) after 5m2s
[20:44:58.263] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:14:0)
[20:44:58.285] Build status: STARTED
[20:44:58.285] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:15:0) after 8m5s
[20:53:03.304] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:15:0)
[20:53:03.334] Build status: STARTED
[20:53:03.334] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:16:0) after 2m8s
[20:55:11.354] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:16:0)
[20:55:11.412] Build status: STARTED
[20:55:11.412] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:17:0) after 4m4s
[20:59:15.916] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:17:0)
[20:59:15.939] Build status: STARTED
[20:59:15.939] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:18:0) after 6m20s
[21:05:35.963] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:18:0)
[21:05:35.983] Build status: STARTED
[21:05:35.983] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:19:0) after 7m4s
[21:12:40.105] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:19:0)
[21:12:40.148] Build status: STARTED
[21:12:40.148] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:20:0) after 4m48s
[21:17:28.226] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:20:0)
[21:17:28.244] Build status: STARTED
[21:17:28.244] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:21:0) after 4m1s
[21:21:29.274] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:21:0)
[21:21:29.307] Build status: STARTED
[21:21:29.307] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:22:0) after 1m35s
[21:23:04.332] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:22:0)
[21:23:04.352] Build status: STARTED
[21:23:04.352] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:23:0) after 6m22s
[21:29:26.425] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:23:0)
[21:29:26.444] Build status: STARTED
[21:29:26.444] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:24:0) after 4m54s
[21:34:20.463] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:24:0)
[21:34:20.480] Build status: STARTED
[21:34:20.480] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:25:0) after 6m20s
[21:40:40.551] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:25:0)
[21:40:40.583] Build status: STARTED
[21:40:40.583] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:26:0) after 6m21s
[21:47:01.677] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:26:0)
[21:47:01.702] Build status: STARTED
[21:47:01.702] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:27:0) after 7m7s
[21:54:08.730] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:27:0)
[21:54:08.749] Build status: STARTED
[21:54:08.749] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:28:0) after 5m45s
[21:59:53.875] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:28:0)
[21:59:53.904] Build status: STARTED
[21:59:53.904] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:29:0) after 8m6s
[22:07:59.928] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:29:0)
[22:07:59.957] Build status: STARTED
[22:07:59.957] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:30:0) after 5m5s
[22:13:05.079] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:30:0)
[22:13:05.177] Build status: STARTED
[22:13:05.177] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:31:0) after 7m34s
[22:20:39.220] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:31:0)
[22:20:39.234] Build status: STARTED
[22:20:39.234] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:32:0) after 8m9s
[22:28:48.238] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:32:0)
[22:28:48.260] Build status: STARTED
[22:28:48.260] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:33:0) after 6m11s
[22:34:59.505] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:33:0)
[22:34:59.538] Build status: STARTED
[22:34:59.538] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:34:0) after 6m31s
[22:41:30.588] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:34:0)
[22:41:30.603] Build status: STARTED
[22:41:30.603] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:35:0) after 4m28s
[22:45:58.624] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:35:0)
[22:45:58.638] Build status: STARTED
[22:45:58.638] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:36:0) after 1m34s
[22:47:32.697] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:36:0)
[22:47:32.713] Build status: STARTED
[22:47:32.713] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:37:0) after 8m24s
[22:55:56.725] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:37:0)
[22:55:56.748] Build status: STARTED
[22:55:56.748] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:38:0) after 2m41s
[22:58:37.769] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:38:0)
[22:58:37.811] Build status: STARTED
[22:58:37.811] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:39:0) after 7m54s
[23:06:31.851] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:39:0)
[23:06:31.867] Build status: STARTED
[23:06:31.867] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:40:0) after 2m35s
[23:09:06.941] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:40:0)
[23:09:07.014] Build status: STARTED
[23:09:07.014] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:41:0) after 1m42s
[23:10:49.038] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:41:0)
[23:10:49.070] Build status: STARTED
[23:10:49.070] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:42:0) after 9m42s
[23:20:31.140] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:42:0)
[23:20:31.158] Build status: STARTED
[23:20:31.159] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:43:0) after 6m55s
[23:27:26.229] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:43:0)
[23:27:26.243] Build status: STARTED
[23:27:26.243] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:44:0) after 8m52s
[23:36:18.265] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:44:0)
[23:36:18.289] Build status: STARTED
[23:36:18.289] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (canonical wd) (reclient compare):8896242354533378576:45:0) after 6m22s
[23:37:57.345] Received PubSub notification, asking Buildbucket for the build status
[23:37:57.401] Build:
{
"id": "8730812292590417425",
"builder": {
"project": "chromium",
"bucket": "reclient",
"builder": "Linux Builder (canonical wd) (reclient compare)"
},
"number": 7218,
"createdBy": "project:chromium",
"createTime": "2024-11-19T19:46:17.400278148Z",
"startTime": "2024-11-19T19:46:22.568666726Z",
"endTime": "2024-11-19T23:37:57.011306205Z",
"updateTime": "2024-11-19T23:37:57.011306205Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "89b07bbd3d0ca41c4271e9675a8d3e0c5f47d65f",
"ref": "refs/heads/main"
}
}
}
[23:37:57.401] Invocation finished in 3h51m41.236415049s with status SUCCEEDED