[01:11:35.281] New invocation is queued and will start shortly
[01:11:36.559] Starting the invocation (attempt 1)
[01:11:36.604] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/32e1dbffa61b778623b441cd2dfd747c0bea9855"
[01:11:36.604] Popped gitiles commit info from properties and tags
[01:11:36.604] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:11:36.604] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:11:36.604] Buildbucket request:
{
"requestId": "8978574529735445296",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-arm64-proguard-rel"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "32e1dbffa61b778623b441cd2dfd747c0bea9855",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8978574529735445296"
},
{
"key": "scheduler_job_id",
"value": "chromium/android-arm64-proguard-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:11:37.369] Scheduled build:
{
"id": "8813144467603148913",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-arm64-proguard-rel"
},
"number": 6771,
"createdBy": "project:chromium",
"createTime": "2022-05-26T01:11:36.677787201Z",
"updateTime": "2022-05-26T01:11:36.677787201Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "32e1dbffa61b778623b441cd2dfd747c0bea9855",
"ref": "refs/heads/main"
}
}
}
[01:11:37.369] Task URL: https://cr-buildbucket.appspot.com/build/8813144467603148913
[01:11:37.369] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:2:0) after 1m50s
[01:13:27.603] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:2:0)
[01:13:27.621] Build status: SCHEDULED
[01:13:27.621] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:3:0) after 3m29s
[01:16:56.661] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:3:0)
[01:16:56.681] Build status: SCHEDULED
[01:16:56.681] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:4:0) after 5m2s
[01:19:58.115] Received PubSub notification, asking Buildbucket for the build status
[01:19:58.164] Build status: STARTED
[01:21:58.936] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:4:0)
[01:21:58.973] Build status: STARTED
[01:21:58.973] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:6:0) after 7m4s
[01:29:03.607] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:6:0)
[01:29:03.631] Build status: STARTED
[01:29:03.631] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:7:0) after 5m29s
[01:34:32.735] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:7:0)
[01:34:32.774] Build status: STARTED
[01:34:32.774] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:8:0) after 4m33s
[01:39:05.977] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:8:0)
[01:39:06.001] Build status: STARTED
[01:39:06.001] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:9:0) after 9m22s
[01:48:28.094] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:9:0)
[01:48:28.132] Build status: STARTED
[01:48:28.132] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:10:0) after 7m45s
[01:56:13.153] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:10:0)
[01:56:13.172] Build status: STARTED
[01:56:13.172] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:11:0) after 2m42s
[01:58:55.196] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:11:0)
[01:58:55.221] Build status: STARTED
[01:58:55.221] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:12:0) after 3m32s
[02:02:27.465] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:12:0)
[02:02:27.498] Build status: STARTED
[02:02:27.498] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:13:0) after 3m54s
[02:06:21.867] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:13:0)
[02:06:21.950] Build status: STARTED
[02:06:21.950] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:14:0) after 2m19s
[02:08:41.058] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:14:0)
[02:08:41.084] Build status: STARTED
[02:08:41.084] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:15:0) after 7m41s
[02:16:22.241] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:15:0)
[02:16:22.330] Build status: STARTED
[02:16:22.330] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:16:0) after 7m46s
[02:24:08.386] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:16:0)
[02:24:08.417] Build status: STARTED
[02:24:08.417] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:17:0) after 3m17s
[02:27:25.440] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:17:0)
[02:27:25.479] Build status: STARTED
[02:27:25.479] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:18:0) after 2m3s
[02:29:28.528] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:18:0)
[02:29:28.587] Build status: STARTED
[02:29:28.587] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:19:0) after 9m7s
[02:38:35.695] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:19:0)
[02:38:35.713] Build status: STARTED
[02:38:35.713] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:20:0) after 9m10s
[02:47:45.737] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:20:0)
[02:47:45.775] Build status: STARTED
[02:47:45.775] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:21:0) after 3m12s
[02:50:57.897] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:21:0)
[02:50:58.109] Build status: STARTED
[02:50:58.109] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:22:0) after 3m28s
[02:54:26.145] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:22:0)
[02:54:26.186] Build status: STARTED
[02:54:26.186] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:23:0) after 8m42s
[03:03:08.270] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:23:0)
[03:03:08.300] Build status: STARTED
[03:03:08.300] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:24:0) after 9m13s
[03:12:21.442] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:24:0)
[03:12:21.483] Build status: STARTED
[03:12:21.483] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:25:0) after 6m25s
[03:18:46.508] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:25:0)
[03:18:46.540] Build status: STARTED
[03:18:46.540] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:26:0) after 7m58s
[03:26:44.616] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:26:0)
[03:26:44.665] Build status: STARTED
[03:26:44.665] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:27:0) after 7m7s
[03:33:51.727] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:27:0)
[03:33:51.750] Build status: STARTED
[03:33:51.750] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:28:0) after 3m58s
[03:37:50.190] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:28:0)
[03:37:50.220] Build status: STARTED
[03:37:50.220] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:29:0) after 7m6s
[03:44:56.373] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:29:0)
[03:44:56.410] Build status: STARTED
[03:44:56.410] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:30:0) after 4m34s
[03:49:30.435] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:30:0)
[03:49:30.461] Build status: STARTED
[03:49:30.461] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:31:0) after 1m49s
[03:51:19.488] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:31:0)
[03:51:19.546] Build status: STARTED
[03:51:19.546] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:32:0) after 6m44s
[03:58:03.664] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:32:0)
[03:58:03.689] Build status: STARTED
[03:58:03.689] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:33:0) after 8m14s
[04:06:17.734] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:33:0)
[04:06:17.783] Build status: STARTED
[04:06:17.783] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:34:0) after 4m16s
[04:10:33.806] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:34:0)
[04:10:33.836] Build status: STARTED
[04:10:33.836] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:35:0) after 1m1s
[04:11:35.076] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:35:0)
[04:11:35.107] Build status: STARTED
[04:11:35.107] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:36:0) after 6m49s
[04:18:24.200] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:36:0)
[04:18:24.367] Build status: STARTED
[04:18:24.367] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:37:0) after 3m31s
[04:21:55.411] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:37:0)
[04:21:55.436] Build status: STARTED
[04:21:55.436] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:38:0) after 8m19s
[04:30:14.466] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:38:0)
[04:30:14.500] Build status: STARTED
[04:30:14.500] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:39:0) after 2m17s
[04:32:31.615] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:39:0)
[04:32:31.638] Build status: STARTED
[04:32:31.638] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:40:0) after 2m7s
[04:34:38.718] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:40:0)
[04:34:38.743] Build status: STARTED
[04:34:38.743] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:41:0) after 1m30s
[04:36:08.776] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:41:0)
[04:36:08.846] Build status: STARTED
[04:36:08.846] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:42:0) after 3m53s
[04:40:02.013] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:42:0)
[04:40:02.036] Build status: STARTED
[04:40:02.036] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:43:0) after 6m55s
[04:46:57.180] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:43:0)
[04:46:57.223] Build status: STARTED
[04:46:57.223] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:44:0) after 8m47s
[04:55:44.244] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:44:0)
[04:55:44.282] Build status: STARTED
[04:55:44.282] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:45:0) after 1m45s
[04:57:29.632] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:45:0)
[04:57:29.672] Build status: STARTED
[04:57:29.672] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:46:0) after 7m35s
[05:05:04.697] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:46:0)
[05:05:04.736] Build status: STARTED
[05:05:04.736] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:47:0) after 5m3s
[05:10:07.762] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:47:0)
[05:10:07.832] Build status: STARTED
[05:10:07.832] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:48:0) after 1m2s
[05:11:09.982] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:48:0)
[05:11:10.004] Build status: STARTED
[05:11:10.004] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:49:0) after 6m10s
[05:17:20.028] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:49:0)
[05:17:20.049] Build status: STARTED
[05:17:20.049] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:50:0) after 1m58s
[05:19:18.101] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:50:0)
[05:19:18.133] Build status: STARTED
[05:19:18.133] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:51:0) after 2m11s
[05:21:29.261] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:51:0)
[05:21:29.317] Build status: STARTED
[05:21:29.317] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:52:0) after 4m7s
[05:25:36.365] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:52:0)
[05:25:36.444] Build status: STARTED
[05:25:36.444] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:53:0) after 5m18s
[05:30:54.679] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:53:0)
[05:30:54.700] Build status: STARTED
[05:30:54.700] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:54:0) after 5m54s
[05:36:48.729] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:54:0)
[05:36:48.753] Build status: STARTED
[05:36:48.753] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:55:0) after 2m54s
[05:39:42.791] Handling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:55:0)
[05:39:42.822] Build status: STARTED
[05:39:42.822] Scheduling timer "check-buildbucket-build-status" (chromium/android-arm64-proguard-rel:8978574529735445296:56:0) after 9m28s
[05:39:46.249] Received PubSub notification, asking Buildbucket for the build status
[05:39:46.279] Build:
{
"id": "8813144467603148913",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-arm64-proguard-rel"
},
"number": 6771,
"createdBy": "project:chromium",
"createTime": "2022-05-26T01:11:36.677787201Z",
"startTime": "2022-05-26T01:19:57.284560Z",
"endTime": "2022-05-26T05:39:45.853737030Z",
"updateTime": "2022-05-26T05:39:45.853737030Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "32e1dbffa61b778623b441cd2dfd747c0bea9855",
"ref": "refs/heads/main"
}
}
}
[05:39:46.279] Invocation finished in 4h28m11.019456384s with status SUCCEEDED