[09:52:22.470] New invocation is queued and will start shortly
[09:52:23.707] Starting the invocation (attempt 1)
[09:52:23.739] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ec7a03b40446049bab2d732eed70b3ccf96632be"
[09:52:23.739] Popped gitiles commit info from properties and tags
[09:52:23.739] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:52:23.739] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:52:23.739] Buildbucket request:
{
"requestId": "8904977027954217472",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-arm64-dbg"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ec7a03b40446049bab2d732eed70b3ccf96632be",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8904977027954217472"
},
{
"key": "scheduler_job_id",
"value": "chromium/win-arm64-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[09:52:24.373] Scheduled build:
{
"id": "8739546965903183665",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-arm64-dbg"
},
"number": 2487,
"createdBy": "project:chromium",
"createTime": "2024-08-15T09:52:23.799728072Z",
"updateTime": "2024-08-15T09:52:23.799728072Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ec7a03b40446049bab2d732eed70b3ccf96632be",
"ref": "refs/heads/main"
}
}
}
[09:52:24.373] Task URL: https://cr-buildbucket.appspot.com/build/8739546965903183665
[09:52:24.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:2:0) after 6m28s
[09:52:25.309] Received PubSub notification, asking Buildbucket for the build status
[09:52:25.332] Build status: SCHEDULED
[09:58:52.412] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:2:0)
[09:58:52.441] Build status: SCHEDULED
[09:58:52.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:4:0) after 3m46s
[10:02:38.508] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:4:0)
[10:02:38.525] Build status: SCHEDULED
[10:02:38.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:5:0) after 3m38s
[10:06:16.545] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:5:0)
[10:06:16.572] Build status: SCHEDULED
[10:06:16.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:6:0) after 8m3s
[10:14:19.734] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:6:0)
[10:14:19.760] Build status: SCHEDULED
[10:14:19.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:7:0) after 1m8s
[10:15:27.784] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:7:0)
[10:15:27.802] Build status: SCHEDULED
[10:15:27.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:8:0) after 9m33s
[10:25:00.836] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:8:0)
[10:25:00.853] Build status: SCHEDULED
[10:25:00.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:9:0) after 8m57s
[10:33:57.876] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:9:0)
[10:33:57.901] Build status: SCHEDULED
[10:33:57.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:10:0) after 6m34s
[10:40:32.003] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:10:0)
[10:40:32.021] Build status: SCHEDULED
[10:40:32.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:11:0) after 5m43s
[10:42:05.504] Received PubSub notification, asking Buildbucket for the build status
[10:42:05.544] Build status: STARTED
[10:46:15.041] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:11:0)
[10:46:15.065] Build status: STARTED
[10:46:15.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:13:0) after 7m27s
[10:53:42.136] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:13:0)
[10:53:42.156] Build status: STARTED
[10:53:42.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:14:0) after 5m55s
[10:59:37.176] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:14:0)
[10:59:37.201] Build status: STARTED
[10:59:37.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:15:0) after 1m5s
[11:00:42.228] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:15:0)
[11:00:42.289] Build status: STARTED
[11:00:42.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:16:0) after 6m14s
[11:06:56.322] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:16:0)
[11:06:56.347] Build status: STARTED
[11:06:56.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:17:0) after 3m4s
[11:10:00.430] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:17:0)
[11:10:00.452] Build status: STARTED
[11:10:00.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:18:0) after 7m10s
[11:17:10.775] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:18:0)
[11:17:10.802] Build status: STARTED
[11:17:10.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:19:0) after 9m4s
[11:26:14.826] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:19:0)
[11:26:14.847] Build status: STARTED
[11:26:14.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:20:0) after 3m19s
[11:29:34.178] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:20:0)
[11:29:34.202] Build status: STARTED
[11:29:34.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:21:0) after 5m53s
[11:35:27.370] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:21:0)
[11:35:27.387] Build status: STARTED
[11:35:27.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:22:0) after 3m4s
[11:38:31.549] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:22:0)
[11:38:31.571] Build status: STARTED
[11:38:31.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:23:0) after 7m53s
[11:46:24.597] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:23:0)
[11:46:24.616] Build status: STARTED
[11:46:24.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:24:0) after 6m53s
[11:53:17.643] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:24:0)
[11:53:17.659] Build status: STARTED
[11:53:17.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:25:0) after 8m32s
[12:01:49.831] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:25:0)
[12:01:49.853] Build status: STARTED
[12:01:49.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:26:0) after 1m12s
[12:03:01.870] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:26:0)
[12:03:01.888] Build status: STARTED
[12:03:01.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:27:0) after 6m38s
[12:09:39.910] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:27:0)
[12:09:39.933] Build status: STARTED
[12:09:39.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:28:0) after 9m37s
[12:19:17.052] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:28:0)
[12:19:17.070] Build status: STARTED
[12:19:17.070] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:29:0) after 7m55s
[12:27:12.344] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:29:0)
[12:27:12.362] Build status: STARTED
[12:27:12.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:30:0) after 3m42s
[12:30:54.462] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:30:0)
[12:30:54.487] Build status: STARTED
[12:30:54.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:31:0) after 8m38s
[12:39:32.548] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:31:0)
[12:39:32.564] Build status: STARTED
[12:39:32.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:32:0) after 4m11s
[12:43:43.685] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:32:0)
[12:43:43.706] Build status: STARTED
[12:43:43.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:33:0) after 4m29s
[12:48:12.730] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:33:0)
[12:48:12.746] Build status: STARTED
[12:48:12.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:34:0) after 5m38s
[12:53:50.945] Handling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:34:0)
[12:53:50.977] Build status: STARTED
[12:53:50.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-arm64-dbg:8904977027954217472:35:0) after 6m11s
[12:54:26.957] Received PubSub notification, asking Buildbucket for the build status
[12:54:27.016] Build:
{
"id": "8739546965903183665",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-arm64-dbg"
},
"number": 2487,
"createdBy": "project:chromium",
"createTime": "2024-08-15T09:52:23.799728072Z",
"startTime": "2024-08-15T10:41:01.353811731Z",
"endTime": "2024-08-15T12:54:26.628382623Z",
"updateTime": "2024-08-15T12:54:26.628382623Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ec7a03b40446049bab2d732eed70b3ccf96632be",
"ref": "refs/heads/main"
}
}
}
[12:54:27.016] Invocation finished in 3h2m4.555770613s with status SUCCEEDED