16 ответов в этой теме

[Thorvardr]

Здравствуйте.

Пока тут проблемы были с необновлением станций, не дергал, сейчас вроде как по той задаче решение есть, потому пишу про свою оказию.

Не могу серверную часть обновить до версии "03-07-2015 05:00:00".

Текущая версия залипла на "10-05-2015 05:00:00".

Причем понижение до резервной копии "06-05-2015 05:00:00" и обновление ее на "10-05-2015 05:00:00" происходит нормально, но до "03-07-2015 05:00:00" нет.

В логе ключевые места, видимо, такие:

Spoiler

20150716 16:00:21 [DBG] [ Script ] Upgrading database

20150716 16:00:22 [DBG] [ Script ] Upgrading database process exit code is 20

20150716 16:00:22 [DBG] [ Script ] Added following revert forms: 

progn, { { stop_server, [=[DrWebES]=] }

, { update_db, [=[C:/Program Files/DrWeb Server/bin/drwcsd.exe]=], [=[C:/Program Files/DrWeb Server/etc\clean.sql]=] }

, { init_db, [=[C:/Program Files/DrWeb Server/bin/drwcsd.exe]=], [=[C:/Program Files/DrWeb Server/etc\agent.key]=] }

, { import_db, [=[C:/Program Files/DrWeb Server/bin/drwcsd.exe]=], [=[C:/Program Files/DrWeb Server/var\update_backup_20150506000000_20150703000000\dbexport.gz]=] }

 }

 

20150716 16:00:22 [ERR] [ Script ] Failed to run `upgrade_db([=[C:/Program Files/DrWeb Server/bin/drwcsd.exe]=])', details: unable to upgrade database, process exit code is 20

20150716 16:00:22 [ERR] [ Script ] Failed on `upgrade'

20150716 16:00:22 [DBG] [ Script ] Upgrade process exit code (upgrade -> master) is 1

20150716 16:00:22 [WRN] [ Script ] Reverting changes (master -> revert)...

Ну ладно, думаю... Раз пишет про проблему с базой, по инструкции починю ее, не в первый раз.

Нормально делается клон, подменяю файл, все запускается, web-интерфейс доступен, но... все равно не обновляется.

На всякий случай полный лог в приложении.

Подумал, что может как то релиз "03-07-2015 05:00:00" криво скачался, зашел в "Содержимое репозитория", удалил вообще там группу Сервер DrWeb - 20150703000000, перезакачался релиз, попробовал приподнять версию и проблема не разрешилась.

Прошу содействия в разборе проблемы, если это возможно.

Прикрепленные файлы:

•  dwupdater.rar   314,79К   1 Скачано раз

[Kirill Polubelov]

По предоставленному логу, вы успешно откатились с 201507030 к 201505060, чтобы это ни значило )

Вернулись, потому что произошла ошибка апгрейда БД, но что за ошибка, конкретно, это в др. логе должно быть отображено.

[Thorvardr]

По предоставленному логу, вы успешно откатились с 201507030 к 201505060, чтобы это ни значило )

Вернулись, потому что произошла ошибка апгрейда БД, но что за ошибка, конкретно, это в др. логе должно быть отображено.

Спасибо за вектор. Завтра буду разбираться, я ограничился логом dwupdater.log, думал, что в данной проблеме смысл смотреть есть только его.

[Thorvardr]

Исследования показали вот что.

1. updater_ops.log - затирается и нужная часть лога улетает в никуда даже с учетом того, что идет сохраненка в updater_ops.X.log.gz, потому пришлось ловить на паузах нужное место.

2. Нужное место в логе, похоже, такое:

Spoiler

20150717.135847.21 ERR [06100 1914] main   [DB] Failed, Statement compile error: table oauth_clients already exists, 00.000, CREATE TABLE oauth_clients(\nid CHARACTER VARYING(255) NOT NULL, secret CHARACTER VARYING(255) NOT NULL, title CHARACTER VARYING(255) NOT NULL, description TEXT DEFAULT '', icon_url TEXT DEFAULT '', homepage TEXT DEFAULT '', created NUMERIC(17) NOT NULL, token_ttl INTEGER DEFAULT 3600 NOT NULL, code_ttl INTEGER DEFAULT 600 NOT NULL, user_id CHARACTER VARYING(255) DEFAULT '', client_type INTEGER DEFAULT 0 NOT NULL, client_status INTEGER DEFAULT 0 NOT NULL,\nPRIMARY KEY(id));

20150717.135847.21 ERR [06100 1914] main   [DB] Unsuccessful SQL "upgrade DB" transaction, rolling back

20150717.135847.21 db3 [06100 1914] main   [SqLite3/0] Statement "ROLLBACK"

20150717.135847.23 db3 [06100 1914] main   [DB] Database has been freed but nobody wants it now

20150717.135847.23 db3 [06100 1914] main   [DB] Successful ROLLBACK transaction, 44 statements, 00.000 wait, 00.013 execute, 00.019 rollback

20150717.135847.23 db3 [06100 1914] main   [DB] Thread has freed connection "upgrade DB"

20150717.135847.23 FTL [06100 1914] main   Database upgrade error.

See the log file for details.

В переводе на человеческий язык: в базе таблица oauth_clients уже есть, попытка добавить ее еще разок неудачна, откат.

Смотрю что там за таблица. Вот что в ней:

 

Не знаю откуда она взялась в базе, но наличествует. То ли от предыдущих попыток обновлений, то ли еще почему.

Прошу рекомендации по методу исправления.

Варианты:

1. Дропнуть ее нафиг, пусть создастся по новой. Минусы - я не знаю есть ли связи с таблицей и как в этом sqlite поддерживается целостность данных, возможно поиметь ссылки на записи с битыми ключами.

2. Откатиться на архив, когда таблицы в базе не было (не факт что он у меня вообще есть такой) и обновиться с него.

3. Ваши предложения.

[Kirill Polubelov]

Хм, а вы точно сейчас _уже_ не на rel-1000-201507030?

[Thorvardr]

Ага, точно.

[Thorvardr]

Жаль, что разработчики там "IF NOT EXIST" не дописали, проблема бы не возникла. Судя по документации такое поддерживается.

А скрипты обновления вручную не подкрутить же?

[Afalin]

Очень странно. Эти таблицы появились в 221 версии схемы, которая накатывается впервые в 0703 из релизных сборок. То есть в одной и той же транзакции должны были создаться эти таблицы и поменяться версия схемы с 220 на 221.

[Thorvardr]

Очень странно. Эти таблицы появились в 221 версии схемы, которая накатывается впервые в 0703 из релизных сборок. То есть в одной и той же транзакции должны были создаться эти таблицы и поменяться версия схемы с 220 на 221.

Ну... Как то и у меня они появились, не знаю как но почему то без фиксирования того что версия сервера стала новой. Напрямую в базе я там ничего не трогал, плагин для Firefox для того чтобы посмотреть что это за проблемная таблица поставил всего пару часов назад.

[Afalin]

Покажите вывод

select * from group_cfg where id='20e27d73-d21d-b211-a788-85419c46f0e6' and name='DaemonCommunication/Address'

и

select * from iface_hidden_groups where gid='ed9651cd-f7e8-4874-8119-123616cd993c'

[Thorvardr]

Результаты запросов - пустая выборка.

Сообщение было изменено Thorvardr: 17 Июль 2015 - 14:33

[Afalin]

Так, если есть ещё таблицы oauth_codes и oauth_tokens, то выполните следующее:

INSERT INTO iface_hidden_groups SELECT DISTINCT id,'ed9651cd-f7e8-4874-8119-123616cd993c',1 FROM admins;

DELETE FROM dbstruct;
INSERT INTO dbstruct VALUES(221);

И будем считать, что обновление доделано руками. Должно взлететь.

Почему оно вот так вот половинчато прошло – непонятно.

З.Ы. Но на всякий случай бэкап БД иметь стоит.

[Thorvardr]

Не взлетело.

20150717.170204.84 FTL [10000 1ddc] wrk:5  [DbMgr] Incompatible database version 221, expected 220

20150717.170204.84 FTL [10000 1ddc] wrk:5  [DbMgr] Database verification failed, 00:00:00.000

20150717.170204.84 FTL [10000 1ddc] wrk:5  [Server] Database is inconsistent, terminating

20150717.170204.84 wrn [10000 1ddc] wrk:5  [DB] Destroying instance "unnamed" in transaction state, rolling back

 

Прежнюю пока назад вернул.

[Afalin]

Стойте, тогда это не 0703.

[Thorvardr]

Ну так я сразу и говорил, что застрял на "10-05-2015 05:00:00" и не могу продвинуться дальше. От 0703 в базе почему то наличествует одна из таблиц, которую я просто бы грохнул и попробовал обновиться, ток хотел проконсультироваться по связям с другими таблицами базы. А то что это не 0703, это точно, т.к. в логах обновления есть куча сведений о копировании всевозможных файлов в рабочую папку серверной части и потом откат их из за неуспеха реструктуризации базы данных.

[Afalin]

За Вами не успеть. То 0206, то в логах 0703 =)

Грохать таблицу можно (или три таблицы, oauth_*, если другие там тоже есть).

[Thorvardr]

Убрал три таблицы, все прошло в лучшем виде. Версия стала от 03 июля, в логах все красиво.

В понедельник приступлю к анализу того какие там станции не обновляются.