sberdevices/salute-issues

[sberbox] Проблема с new_session

subnetsRU opened this issue · 8 comments

Описание проблемы: Пользователь запускает апп. Какое-то время взаимодействует с ним и вдруг, без переоткрытия аппа, от платформы приходит "new_session":true
Проблема воспроизводится у других пользователей? Да.
Дополнительная информация:
Примеры:
Человек запускает апп и начинает игру.
Вот он жмет кнопку внутри канваса:

[07.05.2021 12:40:48.5207]
"messageId":1620380444658086000,
"sessionId":"355d6e39-9dc8-3413-8aab-201270a7d538",
"messageName":"SERVER_ACTION"

При этом "new_session", как и положено false.Ведь апп уже запущен и это не первое сообщение.

Затем пауза в 39 секунд и человек снова жмёт кнопку ВНУТРИ канваса:

[07.05.2021 12:41:27.5469]
"messageId":1620380486003833000,
"sessionId":"a814057b-def9-3381-8b02-c1778420eeb3",
"messageName":"SERVER_ACTION"
"new_session":true

Видим что new_session вдруг стал true.

Такая же ситуация, но между сообщениями всего 2 сек.

[09.05.2021 04:58:55.4791] 
"messageId":1620525434515752000,
"sessionId":"d61792f8-b943-3fa4-a583-9b2b89f8ea66",
"messageName":"SERVER_ACTION",
"new_session":false

И вдруг:

[09.05.2021 04:58:57.5138]
"messageId":1620525434796291000,
"sessionId":"d61792f8-b943-3fa4-a583-9b2b89f8ea66",
"messageName":"SERVER_ACTION"
"new_session":true

Ещё:

[08.05.2021 13:46:29.1802]
"messageId":1620470784144935000,"sessionId":"e28367d6-9aa6-387b-9b26-4f5cf8ae00f4","messageName":"MESSAGE_TO_SKILL"
"new_session":false

Ну и следом:

[08.05.2021 13:46:53.5320]
"messageId":1620470812950610000,"sessionId":"fa5868e5-e96a-34c3-afe1-7631ff792f07","messageName":"SERVER_ACTION"
"new_session":true

Ещё:

[08.05.2021 17:24:48.5381]
"messageId":1620483880144673000,"sessionId":"6bd8f323-59ed-3457-af5c-04fcfa5b1438","messageName":"SERVER_ACTION"
"new_session":true

Во всех случаях это поверхность: "surface":"SBERBOX","surfaceVersion":"1.70.53"

P.S. Пытался воспроизвести проблему на своём боксе, но не получается. Либо проблема плавающая, либо к проблеме приводит некий порядок действий, но каких не понятно.

Не знаю связано это как-то с этой проблемой, но у "deviceId": "SBB01Y12AB037478" вообще что-то явно идёт не так.

То от девайса приходит три RUN_APP подряд в рамках одной сессии:

"message_id": 1620483907253489000,
"sessionId":"6bd8f323-59ed-3457-af5c-04fcfa5b1438"
"time": "2021-05-08 17:25:23",
"source": "RUN_APP",

"message_id": 1620483909032500000,
"sessionId":"6bd8f323-59ed-3457-af5c-04fcfa5b1438"
"time": "2021-05-08 17:25:35",
"source": "RUN_APP",

"message_id": 1620483912375387000,
"sessionId":"6bd8f323-59ed-3457-af5c-04fcfa5b1438"
"time": "2021-05-08 17:25:38",
"source": "RUN_APP",

То выход:

"message_id": 1620484049603317000,
"sessionId":"78918c50-d669-3112-a374-aea842d5629b"
"time": "2021-05-08 17:27:46",
"source": "SERVER_ACTION",
"request": "exit",

"message_id": 1620484055240870000,
"sessionId":"78918c50-d669-3112-a374-aea842d5629b"
"time": "2021-05-08 17:27:47",
"source": "SERVER_ACTION",
"request": "exit",

"message_id": 1620484057418599000,
"sessionId":"78918c50-d669-3112-a374-aea842d5629b"
"time": "2021-05-08 17:27:54",
"source": "SERVER_ACTION",
"request": "exit",

"message_id": 1620484058067105000,
"sessionId":"78918c50-d669-3112-a374-aea842d5629b"
"time": "2021-05-08 17:27:55",
"source": "SERVER_ACTION",
"request": "exit",

"message_id": 1620484058369057000,
"sessionId":"78918c50-d669-3112-a374-aea842d5629b"
"time": "2021-05-08 17:27:57",
"source": "SERVER_ACTION",
"request": "exit",

"message_id": 1620484058628607000,
"sessionId":"78918c50-d669-3112-a374-aea842d5629b"
"time": "2021-05-08 17:28:03",
"source": "SERVER_ACTION",
"request": "exit",

"message_id": 1620484058802921000,
"sessionId":"78918c50-d669-3112-a374-aea842d5629b"
"time": "2021-05-08 17:28:09",
"source": "SERVER_ACTION",
"request": "exit",

"message_id": 1620484059125275000,
"sessionId":"78918c50-d669-3112-a374-aea842d5629b"
"time": "2021-05-08 17:28:16",
"source": "SERVER_ACTION",
"request": "exit",

Во всех случаях был отправлен и "finished":true и вызван assistant.close();

Такая же ситуация, но в меньшем кол-ве сообщений иногда наблюдается и других пользователей, но не у всех. Таких меньшинство, но они есть. Как бы понять, а что происходит ?

@subnetsRU проверьте, пожалуйста. Должно уже полечиться )))

Хорошо, понаблюдаю.

Похоже, что всё ещё актуально. "surfaceVersion": "1.71.43-star"

[22.07.2021 16:25:25.0801] [sid: a24e6b739cffabfa7c549f343ab682a3-20210722] Request from 45.147.162.221
"messageId":1626960318113082000,
"sessionId":"aeac4920-a47f-34e9-ba9f-9a3002c7cea4",
"messageName":"MESSAGE_TO_SKILL"
"new_session":true

... тут взаимодействие с апом.... и вот один из ответов перед последующим сообщением

[22.07.2021 16:29:44.2153] [sid: a24e6b739cffabfa7c549f343ab682a3-20210722] Request from 45.147.162.221
"messageId":1626960580952153000,
"sessionId":"88c0078f-d961-35cb-953c-37191da3ca25",
"messageName":"SERVER_ACTION"
"new_session":false

И через 23 сек:

[22.07.2021 16:30:07.5820] [sid: a24e6b739cffabfa7c549f343ab682a3-20210722] Request from 45.147.162.221
"messageId":1626960606847555000,
"sessionId":"d0745b5a-e16c-3335-8e4d-c23dab805b7b",
"messageName":"SERVER_ACTION"
"new_session":true

Снова видим true.

И с выходом у него тоже было не всё гладко:

[22.07.2021 16:35:29.0708] [sid: a24e6b739cffabfa7c549f343ab682a3-20210722] Request from 45.89.226.38
"messageId":1626960926163313000,
"sessionId":"16da138c-a625-3a42-b31b-6eb70fc5a6dd",
"messageName":"SERVER_ACTION"
"server_action":{"action_id":"exit"}
[22.07.2021 16:35:38.4768] [sid: a24e6b739cffabfa7c549f343ab682a3-20210722] Request from 45.147.162.221
"messageId":1626960929567822000,
"sessionId":"16da138c-a625-3a42-b31b-6eb70fc5a6dd",
"messageName":"SERVER_ACTION"
"server_action":{"action_id":"exit"}

Сообщения идут подряд. Т.е. получается человек вышел два раза на отрезке в 9 сек.

И таких опять больше чем один.

Человек с "surfaceVersion": "1.72.27" тоже сумел выйти два раза:

[22.07.2021 00:24:46.8375] [sid: 3d5c231ca1170d0240bc7609c508ca02-20210722] Request from 45.89.226.38
"messageId":1626902680783752000,
"sessionId":"50673a3d-5128-31d8-8138-93d3060fb948",
"messageName":"SERVER_ACTION",
"new_session":false
"server_action":{"action_id":"exit"}
[22.07.2021 00:24:47.6018] [sid: 3d5c231ca1170d0240bc7609c508ca02-20210722] Request from 45.89.226.38
"messageId":1626902683180794000,
"sessionId":"50673a3d-5128-31d8-8138-93d3060fb948",
"messageName":"SERVER_ACTION",
"new_session":true
"server_action":{"action_id":"exit"}

Кстати, обратите внимание на new_session и в этих сообщениях. Его поведение полностью соответствует тому, что он вышел.

А потом ещё и нажать на элемент на экране канваса:

[22.07.2021 00:24:56.9553] [sid: 3d5c231ca1170d0240bc7609c508ca02-20210722] Request from 45.89.226.38
"messageId":1626902683665197000,
"sessionId":"50673a3d-5128-31d8-8138-93d3060fb948",
"messageName":"SERVER_ACTION"
"new_session":true
"server_action":{"action_id":"menu_open"}

В описанном кейсе между new_session: false и new_session: false был голосовой запрос “который час“, т.е. был вызов навыка времени

Интересная новость. Т.е. при запросе времени мой смартап не закрывается, затем идёт ответ о времени и возврат управления в мой смартап. Я верно понял?

Раз мой смартап не закрывается на это время, то логично, что тогда и new_session должна быть false, а никак не true.

Тогда прошу этот тикет превратить в фичареквест.

Есть новости по тикету?