[03:44:42.680] New invocation is queued and will start shortly
[03:44:44.103] Starting the invocation (attempt 1)
[03:44:44.197] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/01528a1136e083d1151052ee0bf809b31d0cfc70"
[03:44:44.197] Popped gitiles commit info from properties and tags
[03:44:44.197] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:44:44.198] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:44:44.198] Buildbucket request:
{
"requestId": "8980648626270839584",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "01528a1136e083d1151052ee0bf809b31d0cfc70",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8980648626270839584"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTAndroid (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[03:44:45.254] Scheduled build:
{
"id": "8815218563879798289",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid (dbg)"
},
"number": 15970,
"createdBy": "project:chromium",
"createTime": "2022-05-03T03:44:44.328492217Z",
"updateTime": "2022-05-03T03:44:44.328492217Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "01528a1136e083d1151052ee0bf809b31d0cfc70",
"ref": "refs/heads/main"
}
}
}
[03:44:45.254] Task URL: https://cr-buildbucket.appspot.com/build/8815218563879798289
[03:44:45.254] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:2:0) after 5m45s
[03:45:23.871] Received PubSub notification, asking Buildbucket for the build status
[03:45:23.908] Build status: STARTED
[03:50:30.484] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:2:0)
[03:50:30.505] Build status: STARTED
[03:50:30.505] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:4:0) after 1m55s
[03:52:25.531] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:4:0)
[03:52:25.627] Build status: STARTED
[03:52:25.627] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:5:0) after 9m17s
[04:01:42.660] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:5:0)
[04:01:42.725] Build status: STARTED
[04:01:42.725] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:6:0) after 2m14s
[04:03:56.823] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:6:0)
[04:03:56.857] Build status: STARTED
[04:03:56.857] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:7:0) after 7m37s
[04:11:33.929] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:7:0)
[04:11:33.953] Build status: STARTED
[04:11:33.953] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:8:0) after 7m11s
[04:18:45.438] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:8:0)
[04:18:45.469] Build status: STARTED
[04:18:45.469] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:9:0) after 8m51s
[04:27:36.488] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:9:0)
[04:27:36.560] Build status: STARTED
[04:27:36.560] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:10:0) after 1m55s
[04:29:31.756] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:10:0)
[04:29:31.773] Build status: STARTED
[04:29:31.773] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:11:0) after 2m45s
[04:32:16.837] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:11:0)
[04:32:16.872] Build status: STARTED
[04:32:16.872] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:12:0) after 9m36s
[04:41:52.968] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:12:0)
[04:41:52.990] Build status: STARTED
[04:41:52.990] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:13:0) after 2m32s
[04:44:25.010] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:13:0)
[04:44:25.044] Build status: STARTED
[04:44:25.044] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:14:0) after 6m24s
[04:50:49.135] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:14:0)
[04:50:49.231] Build status: STARTED
[04:50:49.231] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:15:0) after 9m44s
[05:00:33.214] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:15:0)
[05:00:33.252] Build status: STARTED
[05:00:33.252] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:16:0) after 8m34s
[05:09:07.277] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:16:0)
[05:09:07.342] Build status: STARTED
[05:09:07.342] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:17:0) after 5m6s
[05:14:13.566] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:17:0)
[05:14:13.600] Build status: STARTED
[05:14:13.600] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:18:0) after 7m46s
[05:21:59.833] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:18:0)
[05:21:59.855] Build status: STARTED
[05:21:59.855] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:19:0) after 8m46s
[05:30:45.957] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:19:0)
[05:30:46.011] Build status: STARTED
[05:30:46.011] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:20:0) after 8m7s
[05:38:53.032] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:20:0)
[05:38:53.059] Build status: STARTED
[05:38:53.059] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:21:0) after 2m30s
[05:41:23.086] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:21:0)
[05:41:23.106] Build status: STARTED
[05:41:23.106] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:22:0) after 4m17s
[05:45:40.169] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:22:0)
[05:45:40.190] Build status: STARTED
[05:45:40.190] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:23:0) after 3m1s
[05:48:41.275] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:23:0)
[05:48:41.295] Build status: STARTED
[05:48:41.295] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:24:0) after 2m42s
[05:51:23.369] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:24:0)
[05:51:23.388] Build status: STARTED
[05:51:23.388] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:25:0) after 5m42s
[05:57:05.408] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:25:0)
[05:57:05.428] Build status: STARTED
[05:57:05.428] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:26:0) after 3m2s
[06:00:07.462] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:26:0)
[06:00:07.495] Build status: STARTED
[06:00:07.495] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:27:0) after 2m58s
[06:03:05.518] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:27:0)
[06:03:05.538] Build status: STARTED
[06:03:05.538] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:28:0) after 4m12s
[06:07:17.560] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:28:0)
[06:07:17.590] Build status: STARTED
[06:07:17.590] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:29:0) after 4m47s
[06:12:04.594] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:29:0)
[06:12:04.617] Build status: STARTED
[06:12:04.617] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:30:0) after 5m8s
[06:17:12.676] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:30:0)
[06:17:12.719] Build status: STARTED
[06:17:12.719] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:31:0) after 8m47s
[06:25:59.737] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:31:0)
[06:25:59.770] Build status: STARTED
[06:25:59.770] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:32:0) after 8m41s
[06:34:40.793] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:32:0)
[06:34:40.818] Build status: STARTED
[06:34:40.818] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:33:0) after 8m34s
[06:43:14.796] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:33:0)
[06:43:14.832] Build status: STARTED
[06:43:14.832] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:34:0) after 4m4s
[06:47:18.855] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:34:0)
[06:47:18.893] Build status: STARTED
[06:47:18.893] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:35:0) after 9m37s
[06:56:56.046] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:35:0)
[06:56:56.074] Build status: STARTED
[06:56:56.074] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:36:0) after 4m44s
[07:01:40.211] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:36:0)
[07:01:40.236] Build status: STARTED
[07:01:40.236] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:37:0) after 1m50s
[07:03:30.356] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:37:0)
[07:03:30.388] Build status: STARTED
[07:03:30.388] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:38:0) after 2m16s
[07:05:46.424] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:38:0)
[07:05:46.448] Build status: STARTED
[07:05:46.448] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:39:0) after 7m27s
[07:13:13.486] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:39:0)
[07:13:13.610] Build status: STARTED
[07:13:13.610] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:40:0) after 5m28s
[07:18:41.594] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:40:0)
[07:18:41.629] Build status: STARTED
[07:18:41.629] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:41:0) after 7m39s
[07:26:20.678] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:41:0)
[07:26:20.728] Build status: STARTED
[07:26:20.728] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:42:0) after 1m0s
[07:27:20.748] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:42:0)
[07:27:20.791] Build status: STARTED
[07:27:20.791] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:43:0) after 7m46s
[07:35:06.900] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:43:0)
[07:35:06.924] Build status: STARTED
[07:35:06.924] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:44:0) after 8m9s
[07:43:16.002] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:44:0)
[07:43:16.024] Build status: STARTED
[07:43:16.024] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:45:0) after 4m43s
[07:47:59.045] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:45:0)
[07:47:59.069] Build status: STARTED
[07:47:59.069] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:46:0) after 3m38s
[07:51:37.092] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:46:0)
[07:51:37.115] Build status: STARTED
[07:51:37.115] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:47:0) after 2m50s
[07:54:27.140] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:47:0)
[07:54:27.182] Build status: STARTED
[07:54:27.182] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:48:0) after 9m31s
[08:03:58.360] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:48:0)
[08:03:58.385] Build status: STARTED
[08:03:58.385] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:49:0) after 5m56s
[08:09:54.456] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:49:0)
[08:09:54.481] Build status: STARTED
[08:09:54.481] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:50:0) after 2m36s
[08:12:30.503] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:50:0)
[08:12:30.533] Build status: STARTED
[08:12:30.533] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:51:0) after 1m39s
[08:14:09.741] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:51:0)
[08:14:09.766] Build status: STARTED
[08:14:09.766] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:52:0) after 5m31s
[08:19:40.894] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:52:0)
[08:19:40.956] Build status: STARTED
[08:19:40.956] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:53:0) after 6m18s
[08:25:59.081] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:53:0)
[08:25:59.128] Build status: STARTED
[08:25:59.128] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:54:0) after 8m8s
[08:34:07.357] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:54:0)
[08:34:07.383] Build status: STARTED
[08:34:07.383] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:55:0) after 8m41s
[08:42:48.405] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:55:0)
[08:42:48.442] Build status: STARTED
[08:42:48.442] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:56:0) after 4m13s
[08:47:01.504] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:56:0)
[08:47:01.528] Build status: STARTED
[08:47:01.528] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:57:0) after 9m49s
[08:56:50.588] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:57:0)
[08:56:50.625] Build status: STARTED
[08:56:50.625] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:58:0) after 5m7s
[09:01:57.664] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:58:0)
[09:01:57.694] Build status: STARTED
[09:01:57.694] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:59:0) after 6m0s
[09:07:57.707] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:59:0)
[09:07:57.738] Build status: STARTED
[09:07:57.738] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:60:0) after 1m17s
[09:09:15.097] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:60:0)
[09:09:15.128] Build status: STARTED
[09:09:15.129] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:61:0) after 3m27s
[09:12:42.160] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:61:0)
[09:12:42.185] Build status: STARTED
[09:12:42.185] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:62:0) after 6m34s
[09:19:16.209] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:62:0)
[09:19:16.240] Build status: STARTED
[09:19:16.240] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8980648626270839584:63:0) after 8m17s
[09:20:37.767] Received PubSub notification, asking Buildbucket for the build status
[09:20:37.802] Build:
{
"id": "8815218563879798289",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid (dbg)"
},
"number": 15970,
"createdBy": "project:chromium",
"createTime": "2022-05-03T03:44:44.328492217Z",
"startTime": "2022-05-03T03:45:23.250204Z",
"endTime": "2022-05-03T09:20:37.201824099Z",
"updateTime": "2022-05-03T09:20:37.201824099Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "01528a1136e083d1151052ee0bf809b31d0cfc70",
"ref": "refs/heads/main"
}
}
}
[09:20:37.802] Invocation finished in 5h35m55.138487753s with status SUCCEEDED