1

Ошибки Wialon Pro и периодические перезагрузки.

(12/03/2015 02:44:29 отредактировано solveMe)

Тема: Ошибки Wialon Pro и периодические перезагрузки.

В последнее время Wialon Pro начал иногда перезагружаться без каких-либо видимых на то причин.
Логи соответсвующие одному из случаев:
service.log:

2015/03/11 20:07:19:626: Wialon login - user: '*'; host: '77.34.*.*'; service: 'contora.ru';
2015/03/11 20:09:20:124: Wialon login - user: *'; host: '80.93.*.*'; service: 'contora.ru';
2015/03/11 20:20:02:868: Starting ADF service...
2015/03/11 20:20:02:875: adf_init_app('environment.txt')
2015/03/11 20:20:31:300: storage_messages_env::open_environment: opened 10 databases with 571025814 messages and 67413265 new messages, fragmentation level is 11%.
2015/03/11 20:20:31:308: messages_sync_server::enable('*:32001'): continuing messages synchronization with serial 1389737311
2015/03/11 20:20:31:357: props_sync_server::enable('*:32001'): continuing props synchronization with serial 2654988875
2015/03/11 20:20:32:292: storage_messages_cache::msgs_thread('547b503ef487b62eb022698da118ef0d'): started
2015/03/11 20:20:32:312: storage_messages_cache::msgs_thread('d7065831f0f63edf49b53a5f4b4e6b88'): started
2015/03/11 20:20:55:039: ADF service started(PID=25039).
2015/03/11 20:20:59:805: Wialon login - user: *'; host: *'; service: 'contora.ru';

Видно, что не было ни команды на завершение, ни команды на перезагрузку. Как будто бы процесс сам по себе умер (или его прибило что-то, ядро например, возможно ему kill -HUP прилетел) а потом заного запустился.


Trace.log

2015/03/11 08:05:20:209: storage_messages_env::bg_job: trickling db: 67% clean pages left, total pages: 1271
2015/03/11 08:20:20:006: retranslator_item_plugin::statistics of 'мастер'(wialon, 3 units): pending 0, sent 1591 messages, history 0 messages
2015/03/11 08:20:20:006: retranslator_item_plugin::statistics of 'локал'(wialon, 2 units): pending 0, sent 2095 messages, history 0 messages
2015/03/11 08:20:20:006: retranslator_item_plugin::statistics of 'Автоскан'(wialon, 8 units): pending 0, sent 0 messages, history 0 messages
2015/03/11 08:20:20:006: retranslator_item_plugin::statistics of '21345435132'(wialon, 1 units): pending 0, sent 411 messages, history 0 messages
2015/03/11 08:20:20:007: retranslator_item_plugin::statistics of 'Local'(wialon, 60 units): pending 0, sent 0 messages, history 0 messages
2015/03/11 19:31:20:206: storage_messages_env::bg_job: trickling db: 68% clean pages left, total pages: 1271
2015/03/11 20:20:02:868: Starting ADF service...
2015/03/11 20:20:02:868: core::load_library(adf_core)
2015/03/11 20:20:02:868: Loaded 'adf_core' version '3.29.0260'
2015/03/11 20:20:02:868: adf_load_environment('custom/config.txt')
2015/03/11 20:20:02:875: adf_init_app('environment.txt')
2015/03/11 20:20:02:875: adf_load_environment('environment.txt')
2015/03/11 20:20:02:888: Loading ADF plugin: 'fonts'...
2015/03/11 20:20:02:941: core::load_library(adf_image)
2015/03/11 20:20:03:038: Loaded 'adf_image' version '1.7.0042'
2015/03/11 20:20:03:038: Loading ADF plugin: 'email'...
2015/03/11 20:20:03:125: core::load_library(adf_email)
2015/03/11 20:20:03:144: Loaded 'adf_email' version '3.2.0034'
2015/03/11 20:20:03:144: Loading ADF plugin: 'chart'...
2015/03/11 20:20:03:173: core::load_library(adf_chart)
2015/03/11 20:20:03:198: Loaded 'adf_chart' version '1.1.0040'
2015/03/11 20:20:03:198: Loading ADF plugin: 'webbase'...
2015/03/11 20:20:03:220: Loading ADF plugin: 'avl_server'...
2015/03/11 20:20:03:229: core::load_library(adf_avl)
2015/03/11 20:20:03:964: Loaded 'adf_avl' version '7.5.0222'
2015/03/11 20:20:03:964: core::load_library(adf_serial)
2015/03/11 20:20:03:964: Loaded 'adf_serial' version '3.13.0121'
2015/03/11 20:20:03:964: core::load_library(adf_net)
2015/03/11 20:20:03:964: Loaded 'adf_net' version '7.8.0109'
2015/03/11 20:20:03:964: core::load_library(adf_zip)
2015/03/11 20:20:03:964: Loaded 'adf_zip' version '3.5.0018'
2015/03/11 20:20:03:966: core::load_library(adf_storage)
2015/03/11 20:20:03:966: Loaded 'adf_storage' version '8.7.0309'
2015/03/11 20:20:03:991: core::load_library(adf_http)
2015/03/11 20:20:03:991: Loaded 'adf_http' version '4.3.0183'
2015/03/11 20:20:04:739: storage_service::storage_service()
2015/03/11 20:20:04:740: core::load_library(adf_ed)
2015/03/11 20:20:04:740: Loaded 'adf_ed' version '5.1.0033'
2015/03/11 20:20:04:748: core::load_library(adf_http)
2015/03/11 20:20:04:813: Loading ADF plugin: 'avl_extensions'...
2015/03/11 20:20:04:838: core::load_library(adf_avl_extensions)
2015/03/11 20:20:04:908: Loaded 'adf_avl_extensions' version '1.14.0084'
2015/03/11 20:20:04:908: core::load_library(adf_gis)
2015/03/11 20:20:04:909: Loaded 'adf_gis' version '4.2.0060'
2015/03/11 20:20:04:995: Loading ADF plugin: 'cms'...
2015/03/11 20:20:05:059: core::load_library(adf_avl_msgs_renderer)
2015/03/11 20:20:05:085: Loaded 'adf_avl_msgs_renderer' version '2.7.0105'
2015/03/11 20:20:05:107: Loading ADF plugin: 'avl_unit_groups'...
2015/03/11 20:20:05:117: core::load_library(adf_avl_unit_groups)
2015/03/11 20:20:05:150: Loaded 'adf_avl_unit_groups' version '1.5.0015'
2015/03/11 20:20:05:152: Loading ADF plugin: 'avl_unit_icon'...
2015/03/11 20:20:05:175: core::load_library(adf_avl_unit_icon)
2015/03/11 20:20:05:177: Loaded 'adf_avl_unit_icon' version '1.0.0009'
2015/03/11 20:20:05:179: Loading ADF plugin: 'avl_zones_library'...
2015/03/11 20:20:05:179: core::load_library(adf_avl_zones_library)
2015/03/11 20:20:05:203: Loaded 'adf_avl_zones_library' version '2.2.0015'
2015/03/11 20:20:05:205: Loading ADF plugin: 'avl_poi'...
2015/03/11 20:20:05:226: core::load_library(adf_avl_poi)
2015/03/11 20:20:05:250: Loaded 'adf_avl_poi' version '1.1.0011'
2015/03/11 20:20:05:251: Loading ADF plugin: 'avl_jobs'...
2015/03/11 20:20:05:303: core::load_library(adf_avl_jobs)
2015/03/11 20:20:05:373: Loaded 'adf_avl_jobs' version '2.2.0034'
2015/03/11 20:20:05:375: Loading ADF plugin: 'avl_notifications'...
2015/03/11 20:20:05:397: core::load_library(adf_avl_notifications)
2015/03/11 20:20:05:404: Loaded 'adf_avl_notifications' version '3.10.0091'
2015/03/11 20:20:05:410: Loading ADF plugin: 'avl_drivers'...
2015/03/11 20:20:05:421: core::load_library(adf_avl_drivers)
2015/03/11 20:20:05:424: Loaded 'adf_avl_drivers' version '1.0.0009'
2015/03/11 20:20:05:425: Loading ADF plugin: 'avl_retranslator'...
2015/03/11 20:20:05:446: core::load_library(adf_avl_retranslator)
2015/03/11 20:20:05:479: Loaded 'adf_avl_retranslator' version '1.7.0121'
2015/03/11 20:20:05:501: Loading ADF plugin: 'avl_reports2'...
2015/03/11 20:20:05:517: core::load_library(adf_avl_reports_base)
2015/03/11 20:20:05:595: Loaded 'adf_avl_reports_base' version '3.2.0288'
2015/03/11 20:20:05:595: core::load_library(adf_avl_reports_calc)
2015/03/11 20:20:05:642: Loaded 'adf_avl_reports_calc' version '1.2.0106'
2015/03/11 20:20:05:642: core::load_library(adf_avl_reports_ext)
2015/03/11 20:20:05:756: Loaded 'adf_avl_reports_ext' version '1.0.0042'
2015/03/11 20:20:05:791: Loading ADF plugin: 'avl_maintenance'...
2015/03/11 20:20:05:804: core::load_library(adf_avl_maintenance)
2015/03/11 20:20:05:806: Loaded 'adf_avl_maintenance' version '1.0.008'
2015/03/11 20:20:05:807: Loading ADF plugin: 'avl_billing'...
2015/03/11 20:20:05:826: core::load_library(adf_avl_billing)
2015/03/11 20:20:05:860: Loaded 'adf_avl_billing' version '2.7.0060'
2015/03/11 20:20:05:864: Loading ADF plugin: 'storage_server'...
2015/03/11 20:20:05:886: core::load_library(adf_storage)
2015/03/11 20:20:05:886: storage_service::initialize(/wialon/storage)
2015/03/11 20:20:05:951: storage_service::open_environment: initializing properties environment (cache: size: 16 MB, chunks: 1)...
2015/03/11 20:20:05:951: storage_service::open_environment: preloading databases...
2015/03/11 20:20:06:627: storage_service::open_environment: opening database environment...
2015/03/11 20:20:07:362: storage_service::open_environment: opening databases...
2015/03/11 20:20:07:362: storage_messages_env::open_environment: opening database environment (cache: size: 64 MB, chunks: 1)...
2015/03/11 20:20:31:247: storage_messages_env::open_environment: opening databases...
2015/03/11 20:20:31:273: storage_messages_env::open_environment: registered v2 5 database: left: 0, right: 0, count: 65855982, new-count: 4970352
2015/03/11 20:20:31:285: storage_messages_env::open_environment: registered v2 9 database: left: 0, right: 0, count: 44678179, new-count: 5720881
2015/03/11 20:20:31:285: storage_messages_env::open_environment: registered v2 3 database: left: 0, right: 0, count: 35950670, new-count: 6086687
2015/03/11 20:20:31:286: storage_messages_env::open_environment: registered v2 1 database: left: 0, right: 0, count: 31669041, new-count: 3192512
2015/03/11 20:20:31:286: storage_messages_env::open_environment: registered v2 10 database: left: 0, right: 0, count: 9818482, new-count: 766987
2015/03/11 20:20:31:286: storage_messages_env::open_environment: registered v2 8 database: left: 0, right: 0, count: 49402811, new-count: 3441956
2015/03/11 20:20:31:299: storage_messages_env::open_environment: registered v2 7 database: left: 0, right: 0, count: 78595910, new-count: 306154
2015/03/11 20:20:31:299: storage_messages_env::open_environment: registered v2 4 database: left: 0, right: 0, count: 105615933, new-count: 21048912
2015/03/11 20:20:31:299: storage_messages_env::open_environment: registered v2 2 database: left: 0, right: 0, count: 60857236, new-count: 7710297
2015/03/11 20:20:31:300: storage_messages_env::open_environment: registered v2 6 database: left: 0, right: 0, count: 88581570, new-count: 14168527
2015/03/11 20:20:31:300: storage_messages_env::open_environment: opened 10 databases with 571025814 messages and 67413265 new messages, fragmentation level is 11%.
2015/03/11 20:20:31:308: messages_sync_server::enable('*:32001'): continuing messages synchronization with serial 1389737311
2015/03/11 20:20:31:357: props_sync_server::enable('*:32001'): continuing props synchronization with serial 2654988875
2015/03/11 20:20:31:357: storage_service::initialize: constructing service item...
2015/03/11 20:20:31:357: storage_service::initialize: next locid: 5158
2015/03/11 20:20:31:358: storage_service::initialize: service object constructed (guid='547b503ef487b62eb022698da118ef0d')
2015/03/11 20:20:31:359: net_session: started as client (sid='6', host='*' port='32001', encryption='none', compression='lzo')
2015/03/11 20:20:31:359: messages_sync_server::sync_thread('*:32001'): connection to synchronization server opened, checking for resync requirement...
2015/03/11 20:20:31:377: net_session: started as client (sid='7', host=*' port='32001', encryption='none', compression='lzo')
2015/03/11 20:20:31:377: props_sync_server::sync_thread('*:32001'): connection to synchronization server opened, checking for resync requirement...
2015/03/11 20:20:31:378: storage_service::initialize: constructing other items with 4 threads...
2015/03/11 20:20:31:668: messages_sync_server::sync_thread('*:32001'): connection registered, next state: 2
2015/03/11 20:20:31:668: props_sync_server::sync_thread('*:32001'): connection registered, next state: 2
2015/03/11 20:20:31:686: props_sync_server::flush_sync('*:32001'): removing cache file
2015/03/11 20:20:31:691: avl_job_pool_item::execute(18, 'Пробег'): '2 60'
2015/03/11 20:20:31:691: avl_job_pool_item::execute(21, 'слив'): '2 900'
2015/03/11 20:20:31:691: avl_job::execute_action(2, 'Пробег')
2015/03/11 20:20:31:691: avl_job::execute_action(1, 'слив')
2015/03/11 20:20:31:717: messages_sync_server::flush_sync('*:32001'): removing cache file
2015/03/11 20:20:32:051: job_fuel_level_control::do_action('слив', '333_777@mail.ru', ''): sending notifications
2015/03/11 20:20:32:225: avl_server::init_prop_manager('7b48a877153f9131fed57bb11b807aff')
2015/03/11 20:20:32:292: avl_job_pool_item::execute(34, 'Трафик'): '2 300'
2015/03/11 20:20:32:292: avl_job::execute_action(1, 'Трафик')
2015/03/11 20:20:32:292: avl_job_pool_item::execute(35, 'Трекер'): '2 300'
2015/03/11 20:20:32:292: avl_accounting_unit_bytes_counter::do_action('arsenev', 1)
2015/03/11 20:20:32:292: avl_job::execute_action(2, 'Трекер')
2015/03/11 20:20:32:292: avl_accounting_unit_bytes_counter::do_action('arsenev', 1)
2015/03/11 20:20:32:292: storage_messages_cache::msgs_thread('547b503ef487b62eb022698da118ef0d'): started
2015/03/11 20:20:32:312: storage_service::initialize: initialized 3121 objects in 1060 ms
2015/03/11 20:20:32:312: storage_messages_cache::msgs_thread('d7065831f0f63edf49b53a5f4b4e6b88'): started
2015/03/11 20:20:32:337: Loading ADF plugin: 'webgis'...
2015/03/11 20:20:32:350: Loading ADF plugin: 'webavl'...
2015/03/11 20:20:32:386: core::load_library(adf_avl_web)
2015/03/11 20:20:32:386: Loaded 'adf_avl_web' version '5.5.0104'
2015/03/11 20:20:32:410: Loading ADF plugin: 'avl_comm_server'...
2015/03/11 20:20:32:492: adf_avl_init_comm('local', '0:0', 1)
2015/03/11 20:20:32:492: adf_avl_scan_hw_dir('547b503ef487b62eb022698da118ef0d')
2015/03/11 20:20:32:611: script error in '/wialon/custom/hw/adm300/init.tcl': invalid command name "avl_hw_set_config"
2015/03/11 20:20:32:611: 'adm300'[41] line 1 error: invalid command name "avl_hw_set_config"
2015/03/11 20:20:32:699: script error in '/wialon/custom/hw/adm600/init.tcl': invalid command name "avl_hw_set_config"
2015/03/11 20:20:32:699: 'adm600'[45] line 1 error: invalid command name "avl_hw_set_config"
2015/03/11 20:20:32:771: script error in '/wialon/custom/hw/wialoneast/init.tcl': invalid command name "avl_hw_set_config"
2015/03/11 20:20:32:771: 'wialoneast'[48] line 1 error: invalid command name "avl_hw_set_config"
2015/03/11 20:20:32:806: script error in '/wialon/custom/hw/autolink/init.tcl': invalid command name "avl_hw_set_config"
2015/03/11 20:20:32:806: 'autolink'[49] line 1 error: invalid command name "avl_hw_set_config"
2015/03/11 20:20:32:928: script error in '/wialon/custom/hw/adm100/init.tcl': invalid command name "avl_hw_set_config"
2015/03/11 20:20:32:928: 'adm100'[55] line 1 error: invalid command name "avl_hw_set_config"
2015/03/11 20:20:33:057: retranslator_item_plugin::statistics of 'мастер'(wialon, 3 units): pending 0, sent 0 messages, history 0 messages
2015/03/11 20:20:33:057: retranslator_item_plugin::statistics of 'Автоскан'(wialon, 8 units): pending 0, sent 0 messages, history 0 messages
2015/03/11 20:20:33:057: retranslator_item_plugin::statistics of 'локал'(wialon, 2 units): pending 0, sent 0 messages, history 0 messages
2015/03/11 20:20:33:057: retranslator_item_plugin::statistics of 'Local'(wialon, 60 units): pending 0, sent 0 messages, history 0 messages
2015/03/11 20:20:34:376: Loading ADF plugin: 'avl_base_setup'...
2015/03/11 20:20:34:385: Loading ADF plugin: 'wialon_i18n'...
2015/03/11 20:20:34:841: Loading ADF plugin: 'gis_avd_driver'...
2015/03/11 20:20:34:861: core::load_library(gis_avd_driver)
2015/03/11 20:20:34:978: Loaded 'gis_avd_driver' version '3.7.0086'
2015/03/11 20:20:34:978: avd_driver::open_tiles_cache 1
2015/03/11 20:20:35:061: retranslator_item_plugin::statistics of '21345435132'(wialon, 1 units): pending 0, sent 0 messages, history 0 messages
2015/03/11 20:20:46:167: notify_action_sms::fire('kudo', 'kudo', 'Premio 543')
2015/03/11 20:20:46:168: notify_action_sms::fire('premio', 'premio', 'Premio 543')
2015/03/11 20:20:46:168: notify_action_message::fire('premio', 'premio', 'Premio 543')
2015/03/11 20:20:46:168: notify_action_sms::fire('premio', 'premio', 'Premio 543')
2015/03/11 20:20:46:168: notify_action_message::fire('premio', 'premio', 'Premio 543')
2015/03/11 20:20:47:124: notify_action_sms::fire('kudo', 'kudo', 'Ractis P466MB')
2015/03/11 20:20:47:125: notify_unit_event::fire('kudo', 'kudo', 'Ractis P466MB')
2015/03/11 20:20:47:899: Loaded layer '/wialon/plugins/gis_avd_driver/render_config/layers/layer_surface.xml' with priority 10
2015/03/11 20:20:47:899: Loaded layer '/wialon/plugins/gis_avd_driver/render_config/layers/layer_borders.xml' with priority 30
2015/03/11 20:20:47:900: Loaded layer '/wialon/plugins/gis_avd_driver/render_config/layers/layer_base.xml' with priority 1
2015/03/11 20:20:47:907: Loaded layer '/wialon/plugins/gis_avd_driver/render_config/layers/layer_poi.xml' with priority 70
2015/03/11 20:20:47:908: Loaded layer '/wialon/plugins/gis_avd_driver/render_config/layers/layer_water.xml' with priority 20
2015/03/11 20:20:47:912: Loaded layer '/wialon/plugins/gis_avd_driver/render_config/layers/layer_roads.xml' with priority 40
2015/03/11 20:20:47:913: Loaded layer '/wialon/plugins/gis_avd_driver/render_config/layers/layer_houses.xml' with priority 30
2015/03/11 20:20:47:914: Loaded layer '/wialon/plugins/gis_avd_driver/render_config/layers/layer_poi_cities.xml' with priority 60
2015/03/11 20:20:47:933: Loading ADF site: 'avl_admin'...
2015/03/11 20:20:48:022: http_service::create_server('avl_admin', '*', 8021)
2015/03/11 20:20:48:047: Loading ADF site: 'wialon_web'...
2015/03/11 20:20:53:027: http_service::create_server('wialon_web', '*', 8022)
2015/03/11 20:20:53:246: Loading ADF site: 'wialon_activex'...
2015/03/11 20:20:53:306: http_service::create_server('wialon_activex', '*', 8025)
2015/03/11 20:20:53:306: Loading ADF site: 'wialon_sdk'...
2015/03/11 20:20:53:428: http_service::create_server('wialon_sdk', '*', 8026)
2015/03/11 20:20:53:428: Loading ADF site: 'cms_manager'...
2015/03/11 20:20:53:988: http_service::create_server('cms_manager', '*', 8023)
.,...
2015/03/11 20:20:59:849: Wialon login - user: '*'; host: '*'; service: 'contora.ru';
2015/03/11 20:20:59:849: storage_user::store_user_history('*' => 'login', 112, 1426069258)
2015/03/11 20:40:20:201: storage_messages_env::bg_job: removing unused log files...


Помимо этого в последнее время логи с ошибками стали очень быстро расти. Ошибки генерируют скрипты для оборудования.
Единственное, что мне в голову приходит - изменились каие-нибудь внутренние библиотечные вызовы поэтому скрипты не могут нормально отработать. (Может быть как раз это влечет невысвобождение определенных ресурсов, что со временем приводит к отказу и перезагрузке).
error.log

2015/03/11 20:09:17:517: error exec_time 184404 microseconds per iteration
2015/03/11 20:09:45:919: 'egts-tcp_packet.tcl' error: TypeError in method 'i_storage_params_reader_get_param_binary', argument 1 of type 'adf::i_storage_params_reader *', debug_set_param_int: 14, calc_crc16_count: 0, tm = 2
2015/03/11 20:09:45:919: error unparsed: 2, ind1: 1, ind2: 1, ind3: 33, ack_count: 0
2015/03/11 20:09:45:920: 0 'egts-tcp_packet.tcl' error data(892): 0100010B006F03020001206803020088020210180058A5BE0900F6A08900942CC089008063000000000265000010180097A5BE0900F6A08900942CC08900806300000000026\
2015/03/11 20:09:45:920: error exec_time 1624646 microseconds per iteration
2015/03/11 20:09:49:304: script error in '/wialon/plugins/avl_base_setup/diskspace_checker.tcl': couldn't fork child process: not enough memory
2015/03/11 20:09:49:304: 'diskchecker'[522] line 1 error: couldn't fork child process: not enough memory
2015/03/11 20:09:51:777: 'egts-tcp_packet.tcl' error: TypeError in method 'i_storage_params_reader_get_param_binary', argument 1 of type 'adf::i_storage_params_reader *', debug_set_param_int: 14, calc_crc16_count: 0, tm = 0
2015/03/11 20:09:51:777: error unparsed: 2, ind1: 1, ind2: 1, ind3: 29, ack_count: 0
2015/03/11 20:09:51:777: 0 'egts-tcp_packet.tcl' error data(784): 0100010B00030302000138FC02020088020210180037E4BE0900D0BB8900122AC0890000C100000000004E000010180049E4BE0900D0BB8900122AC0890000C100000000004\
2015/03/11 20:09:51:777: error exec_time 10909 microseconds per iteration
2015/03/11 20:20:32:611: script error in '/wialon/custom/hw/adm300/init.tcl': invalid command name "avl_hw_set_config"
2015/03/11 20:20:32:611: 'adm300'[41] line 1 error: invalid command name "avl_hw_set_config"
2015/03/11 20:20:32:699: script error in '/wialon/custom/hw/adm600/init.tcl': invalid command name "avl_hw_set_config"
2015/03/11 20:20:32:699: 'adm600'[45] line 1 error: invalid command name "avl_hw_set_config"
2015/03/11 20:20:32:771: script error in '/wialon/custom/hw/wialoneast/init.tcl': invalid command name "avl_hw_set_config"
2015/03/11 20:20:32:771: 'wialoneast'[48] line 1 error: invalid command name "avl_hw_set_config"
2015/03/11 20:20:32:806: script error in '/wialon/custom/hw/autolink/init.tcl': invalid command name "avl_hw_set_config"
2015/03/11 20:20:32:806: 'autolink'[49] line 1 error: invalid command name "avl_hw_set_config"
2015/03/11 20:20:32:928: script error in '/wialon/custom/hw/adm100/init.tcl': invalid command name "avl_hw_set_config"
2015/03/11 20:20:32:928: 'adm100'[55] line 1 error: invalid command name "avl_hw_set_config"
2015/03/11 20:21:19:329: script error in '/wialon/custom/hw/autolink/init.tcl': invalid command name "avl_hw_set_config"
2015/03/11 20:21:19:329: 'autolink-init.tcl'[1379] line 1 error: invalid command name "avl_hw_set_config"
2015/03/11 20:21:32:978: script error in '/wialon/custom/hw/adm300/init.tcl': invalid command name "avl_hw_set_config"
2015/03/11 20:21:32:978: 'adm300-init.tcl'[2104] line 1 error: invalid command name "avl_hw_set_config"
2015/03/11 20:21:33:143: script error in '/wialon/custom/hw/adm100/init.tcl': invalid command name "avl_hw_set_config"
2015/03/11 20:21:33:143: 'adm100-init.tcl'[2119] line 1 error: invalid command name "avl_hw_set_config"
2015/03/11 20:21:36:133: script error in '/wialon/custom/hw/adm600/init.tcl': invalid command name "avl_hw_set_config"
2015/03/11 20:21:36:133: 'adm600-init.tcl'[2254] line 1 error: invalid command name "avl_hw_set_config"
2015/03/11 20:30:58:378: 'egts-tcp_packet.tcl' error: TypeError in method 'i_storage_params_reader_get_param_binary', argument 1 of type 'adf::i_storage_params_reader *', debug_set_param_int: 14, calc_crc16_count: 0, tm = 0
2015/03/11 20:30:58:378: error unparsed: 2, ind1: 1, ind2: 1, ind3: 28, ack_count: 0
2015/03/11 20:30:58:379: 0 'egts-tcp_packet.tcl' error data(757): 0100010B00E80202000153E1020200880202101800AFE7BE0900D0BB8900122AC0890000C100000000004E0000101800B9E7BE0900D0BB8900122AC0890000C100000000004\
2015/03/11 20:30:58:379: error exec_time 13882 microseconds per iteration
2015/03/11 20:31:02:212: 'egts-tcp_packet.tcl' error: TypeError in method 'i_storage_params_reader_get_param_binary', argument 1 of type 'adf::i_storage_params_reader *', debug_set_param_int: 14, calc_crc16_count: 0, tm = 0
2015/03/11 20:31:02:212: error unparsed: 2, ind1: 1, ind2: 1, ind3: 31, ack_count: 0
2015/03/11 20:31:02:212: 0 'egts-tcp_packet.tcl' error data(838): 0100010B0039030200011532030200880202101800B1F5BE0900D0BB8900122AC0890000C100000000004E0000101800CFF5BE0900D0BB8900122AC0890000C100000000054\
2015/03/11 20:31:02:213: error exec_time 12651 microseconds per iteration
2015/03/11 20:31:06:444: 'egts-tcp_packet.tcl' error: TypeError in method 'i_storage_params_reader_get_param_binary', argument 1 of type 'adf::i_storage_params_reader *', debug_set_param_int: 14, calc_crc16_count: 0, tm = 0
2015/03/11 20:31:06:444: error unparsed: 18, ind1: 1, ind2: 1, ind3: 21, ack_count: 0
2015/03/11 20:31:06:444: 0 'egts-tcp_packet.tcl' error data(584): 0100010B002B020200016524020200880202101800A0FCBE09005ABB8900362AC0890000000000000005540000101800B7FDBE09005ABB8900362AC08900000000000000055\
2015/03/11 20:31:06:444: error exec_time 10326 microseconds per iteration
2015/03/11 20:31:10:277: 'egts-tcp_packet.tcl' error: TypeError in method 'i_storage_params_reader_get_param_binary', argument 1 of type 'adf::i_storage_params_reader *', debug_set_param_int: 14, calc_crc16_count: 0, tm = 0
2015/03/11 20:31:10:277: error unparsed: 2, ind1: 1, ind2: 1, ind3: 31, ack_count: 0
2015/03/11 20:31:10:277: 0 'egts-tcp_packet.tcl' error data(838): 0100010B00390302000115320302008802021018005F12BF0900A4BB8900392AC089008039000000001E6B00001018006D12BF0900A6BB8900392AC099788030000000001C6\
2015/03/11 20:31:10:277: error exec_time 11539 microseconds per iteration
2015/03/11 20:31:14:115: 'egts-tcp_packet.tcl' error: TypeError in method 'i_storage_params_reader_get_param_binary', argument 1 of type 'adf::i_storage_params_reader *', debug_set_param_int: 14, calc_crc16_count: 0, tm = 0
2015/03/11 20:31:14:115: error unparsed: 2, ind1: 1, ind2: 1, ind3: 31, ack_count: 0
2015/03/11 20:31:14:115: 0 'egts-tcp_packet.tcl' error data(838): 0100010B0039030200011532030200880202101800C218BF090065BB8900492AC099640000000000001C470000101800C418BF090069BB8900492AC089000000000000001C4\
2015/03/11 20:31:14:115: error exec_time 15085 microseconds per iteration
2015/03/11 20:31:16:738: 'egts-tcp_packet.tcl' error: invalid command name "NULL", debug_set_param_int: 14, calc_crc16_count: 0, tm = 0
2015/03/11 20:31:16:738: error unparsed: 2, ind1: 1, ind2: 1, ind3: 1, ack_count: 0
2015/03/11 20:31:16:738: 'egts-tcp_packet.tcl' error data(28): 0100010B000F0002000185080002008802021505000000210005773D
2015/03/11 20:31:16:738: error exec_time 4346 microseconds per iteration
2015/03/11 20:31:19:347: 'egts-tcp_packet.tcl' error: TypeError in method 'i_storage_params_reader_get_param_binary', argument 1 of type 'adf::i_storage_params_reader *', debug_set_param_int: 14, calc_crc16_count: 0, tm = 0
2015/03/11 20:31:19:347: error unparsed: 2, ind1: 1, ind2: 1, ind3: 3, ack_count: 0
2015/03/11 20:31:19:348: 'egts-tcp_packet.tcl' error data(82): 0100010B004500020001ED3E000200880202101800BC75BF09008CBB8900142AC09923000000000000FF300000101800BD75BF090090BB8900182AC08900000000000000FF2E000015050000152B0004574E
2015/03/11 20:31:19:348: error exec_time 2698 microseconds per iteration
2015/03/11 20:31:22:984: 'egts-tcp_packet.tcl' error: TypeError in method 'i_storage_params_reader_get_param_binary', argument 1 of type 'adf::i_storage_params_reader *', debug_set_param_int: 14, calc_crc16_count: 0, tm = 0
2015/03/11 20:31:22:984: error unparsed: 2, ind1: 1, ind2: 1, ind3: 32, ack_count: 0
2015/03/11 20:31:22:984: 0 'egts-tcp_packet.tcl' error data(865): 0100010B005403020001DE4D0302008802021018005F81BF09005DA789008631C09993015100000000016200001018007D81BF09009EA78900BD32C08900005100000000015\
2015/03/11 20:31:22:984: error exec_time 14512 microseconds per iteration
2015/03/11 20:31:26:403: 'egts-tcp_packet.tcl' error: TypeError in method 'i_storage_params_reader_get_param_binary', argument 1 of type 'adf::i_storage_params_reader *', debug_set_param_int: 14, calc_crc16_count: 0, tm = 0
2015/03/11 20:31:26:403: error unparsed: 2, ind1: 1, ind2: 1, ind3: 29, ack_count: 0
2015/03/11 20:31:26:404: 0 'egts-tcp_packet.tcl' error data(784): 0100010B00030302000138FC020200880202101800E3F1BF090016A18900752CC08900006F00000000005E0000101800F6F2BF090016A18900752CC08900006F00000000005\
2015/03/11 20:31:26:404: error exec_time 13780 microseconds per iteration
2015/03/11 20:31:30:426: 'egts-tcp_packet.tcl' error: TypeError in method 'i_storage_params_reader_get_param_binary', argument 1 of type 'adf::i_storage_params_reader *', debug_set_param_int: 14, calc_crc16_count: 0, tm = 0
2015/03/11 20:31:30:426: error unparsed: 2, ind1: 1, ind2: 1, ind3: 32, ack_count: 0
2015/03/11 20:31:30:426: 0 'egts-tcp_packet.tcl' error data(865): 0100010B005403020001DE4D0302008802021018000AF7BF090016A18900752CC08900006F00000000005E000010180014F7BF090016A18900752CC08900006F00000000005\
2015/03/11 20:31:30:427: error exec_time 11408 microseconds per iteration
2015/03/11 20:31:34:862: 'egts-tcp_packet.tcl' error: TypeError in method 'i_storage_params_reader_get_param_binary', argument 1 of type 'adf::i_storage_params_reader *', debug_set_param_int: 14, calc_crc16_count: 0, tm = 0
2015/03/11 20:31:34:862: error unparsed: 2, ind1: 1, ind2: 1, ind3: 30, ack_count: 0
2015/03/11 20:31:34:862: 0 'egts-tcp_packet.tcl' error data(811): 0100010B001E03020001B6170302008802021018004E34C00900B1BC8900B029C09913016800000000024100001018006734C0090073BC8900722AC08900006800000000024\
2015/03/11 20:31:34:862: error exec_time 11540 microseconds per iteration
2015/03/11 20:31:38:403: 'egts-tcp_packet.tcl' error: TypeError in method 'i_storage_params_reader_get_param_binary', argument 1 of type 'adf::i_storage_params_reader *', debug_set_param_int: 14, calc_crc16_count: 0, tm = 0
2015/03/11 20:31:38:403: error unparsed: 2, ind1: 1, ind2: 1, ind3: 32, ack_count: 0
2015/03/11 20:31:38:403: 0 'egts-tcp_packet.tcl' error data(865): 0100010B005403020001DE4D0302008802021018007137C00900B0BB89007D2AC088000016000000001E5800001018007A37C00900B5BB89007F2AC089000016000000001E5\
2015/03/11 20:31:38:403: error exec_time 11683 microseconds per iteration
2015/03/11 20:31:41:011: 'egts-tcp_packet.tcl' error: TypeError in method 'i_storage_params_reader_get_param_binary', argument 1 of type 'adf::i_storage_params_reader *', debug_set_param_int: 14, calc_crc16_count: 0, tm = 1
2015/03/11 20:31:41:011: error unparsed: 2, ind1: 1, ind2: 1, ind3: 32, ack_count: 0
2015/03/11 20:31:41:011: 0 'egts-tcp_packet.tcl' error data(865): 0100010B005403020001DE4D030200880202101800B83BC0090018BC89007F2AC088000000000000001E750000101800BE3BC0090032BC89007C2AC089000000000000001E6\
2015/03/11 20:31:41:011: error exec_time 13035 microseconds per iteration
2015/03/11 20:31:44:236: 'egts-tcp_packet.tcl' error: TypeError in method 'i_storage_params_reader_get_param_binary', argument 1 of type 'adf::i_storage_params_reader *', debug_set_param_int: 14, calc_crc16_count: 0, tm = 0
2015/03/11 20:31:44:236: error unparsed: 2, ind1: 1, ind2: 1, ind3: 29, ack_count: 0
2015/03/11 20:31:44:236: 0 'egts-tcp_packet.tcl' error data(784): 0100010B00030302000138FC0202008802021018006340C0090081BB89000A2AC08900801E00000000004B00001018008340C0090081BB89000A2AC08900801E00000000004\
2015/03/11 20:31:44:236: error exec_time 10819 microseconds per iteration
2015/03/11 20:31:47:265: 'egts-tcp_packet.tcl' error: TypeError in method 'i_storage_params_reader_get_param_binary', argument 1 of type 'adf::i_storage_params_reader *', debug_set_param_int: 14, calc_crc16_count: 0, tm = 0

Еще меня заинтересовала ошибка "error fetching licence: avl_unit". Я так понимаю, что это происходит когда конфигурация на сервере лицезирования изменилась, а обновление дистрибутива еще не произошло. Правильно ли это?

2

Ошибки Wialon Pro и периодические перезагрузки.

Re: Ошибки Wialon Pro и периодические перезагрузки.

Если  в trace.log не данных между  2015/03/11 08:20 и 2015/03/11 19:31 , то представленным логам  получается, что сервис   11 часов не обрабатывал данных, либо вы слишком уж порезали  логи, чтобы  выкладывать их на  всеобщее обозрение.
Ошибки по оборудованию решаются путем обновления дистрибутиа до последней версии, а так же запросом на отдельные скрипты, лежащие в папке custom/hw, если по ним имеются ошибки.

2015/03/11 20:09:49:304: 'diskchecker'[522] line 1 error: couldn't fork child process: not enough memory

Ошибка сигнализирующая о нехватке памяти  для выполнения дочернего процесса  по проверке свободного места.

ошибка "error fetching licence: avl_unit"

Зачастую   бывает при попытке создании объектов, выходящих за пределы доступные по лицензии.

Евгений
WDC Administrator
Gurtam
3

Ошибки Wialon Pro и периодические перезагрузки.

Re: Ошибки Wialon Pro и периодические перезагрузки.

Если  в trace.log не данных между  2015/03/11 08:20 и 2015/03/11 19:31 , то представленным логам  получается, что сервис   11 часов не обрабатывал данных, либо вы слишком уж порезали  логи, чтобы  выкладывать их на  всеобщее обозрение

Это я просто grep'нул слишком грубо, а так в trace.log присутсвуют сообщения за указанный период, так что сервис исправно работал, до тех пор пока не перезапустился в районе 20:20.

Насчет памяти ее до сих пор было за глаза, но я на всякий случай сделал логирование использования памяти процессом виалона. Пока полет нормальный, буду следить. Больше всего настораживает то что между некоторыми, сообщениями типа "Starting ADF service..." прошло буквально час - два. ПОлучается, то виалон месяцаим работает нормально, то за 2 часа всю память сжирает. Хотя, я тут почитал форумы, это может быть какое-то убийственное задание на мульен объектов или действий или рассылка какая-нибудь положила процесс. Где копать, чтобы пролить свет на подобные вещи? В интерфейсе администратора или менеждера?

4

Ошибки Wialon Pro и периодические перезагрузки.

Re: Ошибки Wialon Pro и периодические перезагрузки.

solveMe пишет:

Где копать, чтобы пролить свет на подобные вещи? В интерфейсе администратора или менеждера?

trace.log нужно изучать,хотя бы за час до падения.
Пишете тикет в GHS, посмотрим.

Евгений
WDC Administrator
Gurtam