[6.0b1] zmlogger dies / "must define at least one Data Source"

[jens]

Hi there,

using 6.0.0_BETA1_1397.RHEL5 on CentOS 5.3, zmlogger always dies after a few seconds. I can find the following in ~/log/zmlogswatch.out:

*** logswatch version 3.2.3 (pid:25143) started at Mon May 11 07:54:33 BST 2009

 you must define at least one Data Source at /opt/zimbra/libexec/zmlogger line 172, <> line 22.

Any ideas?

[jens]

. *bump* .

[pfnguyen]

Can you provide the contents of /var/log/zimbra.log or /var/log/zimbra-stats.log for around the time that this occurs?

[jens]

Don't have a /var/log/zimbra-stats.log, but below are excerpts from zmlogswatch.out and zimbra.log while issuing a zmloggerctl start. It seems that starting zmlogger does create or update files in the rrds directory but logger.sqlitedb stays untouched.

This was a fresh Zimbra install. Is it possible to re-install the relevant packages without loosing other configuration or data?

*** logswatch version 3.2.3 (pid:30611) started at Mon May 25 13:40:47 BST 2009

 you must define at least one Data Source at /opt/zimbra/libexec/zmlogger line 172, <> line 20.

May 25 13:40:30 mail zimbramon[2692]: 2692:info: zmstat proc.csv: timestamp, system, user, sys, idle, iowait, mailbox, mailbox-total-cpu, mailbox-utime, mailbox-stime, mailbox-totalMB, mailbox-rssMB, mailbox-sharedMB, mailbox-process-count, mysql, mysql-total-cpu, mysql-utime, mysql-stime, mysql-totalMB, mysql-rssMB, mysql-sharedMB, mysql-process-count, convertd, convertd-total-cpu, convertd-utime, convertd-stime, convertd-totalMB, convertd-rssMB, convertd-sharedMB, convertd-process-count, ldap, ldap-total-cpu, ldap-utime, ldap-stime, ldap-totalMB, ldap-rssMB, ldap-sharedMB, ldap-process-count, postfix, postfix-total-cpu, postfix-utime, postfix-stime, postfix-totalMB, postfix-rssMB, postfix-sharedMB, postfix-process-count, amavis, amavis-total-cpu, amavis-utime, amavis-stime, amavis-totalMB, amavis-rssMB, amavis-sharedMB, amavis-process-count, clam, clam-total-cpu, clam-utime, clam-stime, clam-totalMB, clam-rssMB, clam-sharedMB, clam-p:::3B097738-4929-11DE-8CC6-6C6FA4792AB3::: 
May 25 13:40:30 mail zimbramon[2692]: :::3B097738-4929-11DE-8CC6-6C6FA4792AB3:::rocess-count:: 05/25/2009 13:40:30, system, 4.9, 1.1, 94.0, 0.0, mailbox, 0.0, 0.0, 0.0, 639.4, 421.4, 47.2, 1, mysql, 0.0, 0.0, 0.0, 123.1, 41.1, 4.3, 1, convertd, 0.0, 0.0, 0.0, 0.0, 0.0, 0.0, 0, ldap, 0.1, 0.1, 0.0, 84.5, 28.6, 3.7, 1, postfix, 0.0, 0.0, 0.0, 8.6, 2.9, 2.3, 12, amavis, 0.0, 0.0, 0.0, 67.3, 57.9, 3.6, 3, clam, 0.0, 0.0, 0.0, 94.0, 75.6, 1.0, 2 
May 25 13:40:30 mail zimbramon[2706]: 2706:info: zmstat mysql.csv: timestamp, Aborted_clients, Aborted_connects, Binlog_cache_disk_use, Binlog_cache_use, Bytes_received, Bytes_sent, Com_admin_commands, Com_alter_db, Com_alter_table, Com_analyze, Com_backup_table, Com_begin, Com_call_procedure, Com_change_db, Com_change_master, Com_check, Com_checksum, Com_commit, Com_create_db, Com_create_function, Com_create_index, Com_create_table, Com_create_user, Com_dealloc_sql, Com_delete, Com_delete_multi, Com_do, Com_drop_db, Com_drop_function, Com_drop_index, Com_drop_table, Com_drop_user, Com_execute_sql, Com_flush, Com_grant, Com_ha_close, Com_ha_open, Com_ha_read, Com_help, Com_insert, Com_insert_select, Com_kill, Com_load, Com_load_master_data, Com_load_master_table, Com_lock_tables, Com_optimize, Com_preload_keys, Com_prepare_sql, Com_purge, Com_purge_before_date, Com_rename_table, Com_repair, Com_replace, Com_replace_select, Com_reset, C:::3B2FEEF4-4929-11DE-A807-7A6FA4792AB3::: 
May 25 13:40:30 mail zimbramon[2706]: 2706:info: :::3B2FEEF4-4929-11DE-A807-7A6FA4792AB3:::om_restore_table, Com_revoke, Com_revoke_all, Com_rollback, Com_savepoint, Com_select, Com_set_option, Com_show_binlog_events, Com_show_binlogs, Com_show_charsets, Com_show_collations, Com_show_column_types, Com_show_create_db, Com_show_create_table, Com_show_databases, Com_show_errors, Com_show_fields, Com_show_grants, Com_show_innodb_status, Com_show_keys, Com_show_logs, Com_show_master_status, Com_show_ndb_status, Com_show_new_master, Com_show_open_tables, Com_show_privileges, Com_show_processlist, Com_show_slave_hosts, Com_show_slave_status, Com_show_status, Com_show_storage_engines, Com_show_tables, Com_show_triggers, Com_show_variables, Com_show_warnings, Com_slave_start, Com_slave_stop, Com_stmt_close, Com_stmt_execute, Com_stmt_fetch, Com_stmt_prepare, Com_stmt_reset, Com_stmt_send_long_data, Com_truncate, Com_unlock_tables, Com_update, Com_update_multi, Com_xa_commit, Com_xa_end:::3B2FFD2C-4929-11DE-A807-7A6F
May 25 13:40:30 mail zimbramon[2706]: 2706:info: :::3B2FFD2C-4929-11DE-A807-7A6FA4792AB3:::, Com_xa_prepare, Com_xa_recover, Com_xa_rollback, Com_xa_start, Compression, Connections, Created_tmp_disk_tables, Created_tmp_files, Created_tmp_tables, Delayed_errors, Delayed_insert_threads, Delayed_writes, Flush_commands, Handler_commit, Handler_delete, Handler_discover, Handler_prepare, Handler_read_first, Handler_read_key, Handler_read_next, Handler_read_prev, Handler_read_rnd, Handler_read_rnd_next, Handler_rollback, Handler_savepoint, Handler_savepoint_rollback, Handler_update, Handler_write, Innodb_buffer_pool_pages_data, Innodb_buffer_pool_pages_dirty, Innodb_buffer_pool_pages_flushed, Innodb_buffer_pool_pages_free, Innodb_buffer_pool_pages_misc, Innodb_buffer_pool_pages_total, Innodb_buffer_pool_read_ahead_rnd, Innodb_buffer_pool_read_ahead_seq, Innodb_buffer_pool_read_requests, Innodb_buffer_pool_reads, Innodb_buffer_pool_wait_free, Innodb_buffer_pool_write_requests, Innodb_:::3B300178-4929-11DE-A807-7A6F
May 25 13:40:30 mail zimbramon[2706]: 2706:info: :::3B300178-4929-11DE-A807-7A6FA4792AB3:::data_fsyncs, Innodb_data_pending_fsyncs, Innodb_data_pending_reads, Innodb_data_pending_writes, Innodb_data_read, Innodb_data_reads, Innodb_data_writes, Innodb_data_written, Innodb_dblwr_pages_written, Innodb_dblwr_writes, Innodb_log_waits, Innodb_log_write_requests, Innodb_log_writes, Innodb_os_log_fsyncs, Innodb_os_log_pending_fsyncs, Innodb_os_log_pending_writes, Innodb_os_log_written, Innodb_page_size, Innodb_pages_created, Innodb_pages_read, Innodb_pages_written, Innodb_row_lock_current_waits, Innodb_row_lock_time, Innodb_row_lock_time_avg, Innodb_row_lock_time_max, Innodb_row_lock_waits, Innodb_rows_deleted, Innodb_rows_inserted, Innodb_rows_read, Innodb_rows_updated, Key_blocks_not_flushed, Key_blocks_unused, Key_blocks_used, Key_read_requests, Key_reads, Key_write_requests, Key_writes, Last_query_cost, Max_used_connections, Not_flushed_delayed_rows, Open_files, Open_streams, Open:::3B300556-4929-11DE-A807-7A6F
May 25 13:40:30 mail zimbramon[2706]: 2706:info: :::3B300556-4929-11DE-A807-7A6FA4792AB3:::_tables, Opened_tables, Prepared_stmt_count, Qcache_free_blocks, Qcache_free_memory, Qcache_hits, Qcache_inserts, Qcache_lowmem_prunes, Qcache_not_cached, Qcache_queries_in_cache, Qcache_total_blocks, Queries, Questions, Rpl_status, Select_full_join, Select_full_range_join, Select_range, Select_range_check, Select_scan, Slave_open_temp_tables, Slave_retried_transactions, Slave_running, Slow_launch_threads, Slow_queries, Sort_merge_passes, Sort_range, Sort_rows, Sort_scan, Ssl_accept_renegotiates, Ssl_accepts, Ssl_callback_cache_hits, Ssl_cipher, Ssl_cipher_list, Ssl_client_connects, Ssl_connect_renegotiates, Ssl_ctx_verify_depth, Ssl_ctx_verify_mode, Ssl_default_timeout, Ssl_finished_accepts, Ssl_finished_connects, Ssl_session_cache_hits, Ssl_session_cache_misses, Ssl_session_cache_mode, Ssl_session_cache_overflows, Ssl_session_cache_size, Ssl_session_cache_timeouts, Ssl_sessions_reused,:::3B30090C-4929-11DE-A807-7A6F
May 25 13:40:30 mail zimbramon[2706]: 2706:info: :::3B30090C-4929-11DE-A807-7A6FA4792AB3::: Ssl_used_session_cache_entries, Ssl_verify_depth, Ssl_verify_mode, Ssl_version, Table_locks_immediate, Table_locks_waited, Tc_log_max_pages_used, Tc_log_page_size, Tc_log_page_waits, Threads_cached, Threads_connected, Threads_created, Threads_running, Uptime, Uptime_since_flush_status:: 05/25/2009 13:40:30,1,0,0,0,12681984,115172357,3,0,0,255,0,0,0,28,0,272,0,13495,0,0,0,0,0,0,11,0,0,0,0,0,0,0,0,0,0,0,0,0,0,267,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,17904,0,21244,53538,0,0,0,3,0,0,0,1,0,0,0,3801,0,0,0,0,0,0,0,0,0,0,10230,0,28,0,3,0,0,0,17077,29380,0,17462,0,0,0,0,15205,2,0,0,0,0,0,0,OFF,10235,2,5,7649,0,0,0,1,28953,0,0,0,15,141101,2417622,7739,12500,1902459,511,0,0,0,1895041,1273,0,17251,1,6,1280,197,45,3802459,8261,0,95517,23819,0,0,0,279302144,9803,31294,580410368,17251,4178,0,28328,7116,11049,0,0,13115904,16384,72,16914,17251,0,0,0,0,0,42,216,3488593,17914,0,7245,0,0,0,0,0,0.000000,4,:::3B300CC2-4929-11DE-A807-7A6F
May 25 13:40:30 mail zimbramon[2706]: :::3B300CC2-4929-11DE-A807-7A6FA4792AB3:::0,75,0,120,761,25,0,0,0,0,0,0,0,0,178430,143891,NULL,0,0,943,0,7645,0,0,OFF,0,11,0,86,1646,2,0,0,0,,,0,0,0,0,0,0,0,0,0,NONE,0,0,0,0,0,0,0,,52950,0,0,0,0,1,3,4,1,228140,178430 
May 25 13:40:31 mail zimbramon[2696]: 2696:info: zmstat vm.csv: timestamp, r, b, swpd, free, buff, cache, si, so, bi, bo, in, cs, us, sy, id, wa, st, MemTotal, MemFree, Buffers, Cached, SwapCached, Active, Inactive, SwapTotal, SwapFree, Dirty, Writeback, AnonPages, Mapped, Slab, SReclaimable, SUnreclaim, PageTables, NFS_Unstable, Bounce, CommitLimit, Committed_AS, VmallocTotal, VmallocUsed, VmallocChunk, HugePages_Total, HugePages_Free, HugePages_Rsvd, HugePages_Surp, Hugepagesize, loadavg:: 05/25/2009 13:40:31, 0, 0, 0, 2924456, 0, 0, 0, 0, 0, 19, 0, 6343, 5, 1, 94, 0, 0, 4045944, 2924456, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 2048, 0.08 
May 25 13:40:41 mail zimbramon[2704]: 2704:info: zmstat fd.csv: timestamp, fd_count:: 05/25/2009 13:40:41, 640 
May 25 13:40:43 mail zimbramon[2694]: 2694:info: zmstat cpu.csv: timestamp, cpu:user, cpu:nice, cpu:sys, cpu:idle, cpu:iowait, cpu:irq, cpu:softirq, cpu0:user, cpu0:nice, cpu0:sys, cpu0:idle, cpu0:iowait, cpu0:irq, cpu0:softirq, cpu1:user, cpu1:nice, cpu1:sys, cpu1:idle, cpu1:iowait, cpu1:irq, cpu1:softirq, cpu2:user, cpu2:nice, cpu2:sys, cpu2:idle, cpu2:iowait, cpu2:irq, cpu2:softirq, cpu3:user, cpu3:nice, cpu3:sys, cpu3:idle, cpu3:iowait, cpu3:irq, cpu3:softirq:: 05/25/2009 13:40:43, 0.6, 0.0, 0.4, 99.0, 0.0, 0.0, 0.0, 0.3, 0.0, 0.1, 99.6, 0.0, 0.0, 0.0, 57.1, 0.0, 42.9, 0.0, 0.0, 0.0, 0.0, 57.1, 0.0, 42.9, 0.0, 0.0, 0.0, 0.0, 0.4, 0.0, 0.2, 99.4, 0.0, 0.0, 0.0 
May 25 13:40:44 mail zimbramon[2712]: 2712:info: zmstat mtaqueue.csv: timestamp, KBytes, requests:: 05/25/2009 13:40:44, 0, 0 
May 25 13:40:58 mail zimbramon[2700]: 2700:info: zmstat df.csv: timestamp, path, disk, disk_use, disk_space, disk_pct_used:: 05/25/2009 13:40:58, /, simfs, 16154208, 67108864, 25 
May 25 13:40:58 mail zimbramon[2700]: 2700:info: zmstat df.csv: timestamp, path, disk, disk_use, disk_space, disk_pct_used:: 05/25/2009 13:40:58, /dev, none, 4, 2022972, 1 
May 25 13:41:00 mail zimbramon[2692]: 2692:info: zmstat proc.csv: timestamp, system, user, sys, idle, iowait, mailbox, mailbox-total-cpu, mailbox-utime, mailbox-stime, mailbox-totalMB, mailbox-rssMB, mailbox-sharedMB, mailbox-process-count, mysql, mysql-total-cpu, mysql-utime, mysql-stime, mysql-totalMB, mysql-rssMB, mysql-sharedMB, mysql-process-count, convertd, convertd-total-cpu, convertd-utime, convertd-stime, convertd-totalMB, convertd-rssMB, convertd-sharedMB, convertd-process-count, ldap, ldap-total-cpu, ldap-utime, ldap-stime, ldap-totalMB, ldap-rssMB, ldap-sharedMB, ldap-process-count, postfix, postfix-total-cpu, postfix-utime, postfix-stime, postfix-totalMB, postfix-rssMB, postfix-sharedMB, postfix-process-count, amavis, amavis-total-cpu, amavis-utime, amavis-stime, amavis-totalMB, amavis-rssMB, amavis-sharedMB, amavis-process-count, clam, clam-total-cpu, clam-utime, clam-stime, clam-totalMB, clam-rssMB, clam-sharedMB, clam-p:::4CED4182-4929-11DE-8CC6-6C6FA4792AB3::: 
May 25 13:41:00 mail zimbramon[2692]: :::4CED4182-4929-11DE-8CC6-6C6FA4792AB3:::rocess-count:: 05/25/2009 13:41:00, system, 1.8, 0.8, 97.4, 0.0, mailbox, 0.1, 0.0, 0.1, 639.9, 421.9, 47.2, 1, mysql, 0.0, 0.0, 0.0, 123.1, 41.1, 4.3, 1, convertd, 0.0, 0.0, 0.0, 0.0, 0.0, 0.0, 0, ldap, 0.1, 0.1, 0.0, 84.5, 28.6, 3.7, 1, postfix, 0.0, 0.0, 0.0, 8.6, 2.9, 2.4, 9, amavis, 0.0, 0.0, 0.0, 67.3, 57.9, 3.6, 3, clam, 0.0, 0.0, 0.0, 94.0, 75.6, 1.0, 2 
May 25 13:41:00 mail zimbramon[2706]: 2706:info: zmstat mysql.csv: timestamp, Aborted_clients, Aborted_connects, Binlog_cache_disk_use, Binlog_cache_use, Bytes_received, Bytes_sent, Com_admin_commands, Com_alter_db, Com_alter_table, Com_analyze, Com_backup_table, Com_begin, Com_call_procedure, Com_change_db, Com_change_master, Com_check, Com_checksum, Com_commit, Com_create_db, Com_create_function, Com_create_index, Com_create_table, Com_create_user, Com_dealloc_sql, Com_delete, Com_delete_multi, Com_do, Com_drop_db, Com_drop_function, Com_drop_index, Com_drop_table, Com_drop_user, Com_execute_sql, Com_flush, Com_grant, Com_ha_close, Com_ha_open, Com_ha_read, Com_help, Com_insert, Com_insert_select, Com_kill, Com_load, Com_load_master_data, Com_load_master_table, Com_lock_tables, Com_optimize, Com_preload_keys, Com_prepare_sql, Com_purge, Com_purge_before_date, Com_rename_table, Com_repair, Com_replace, Com_replace_select, Com_reset, C:::4D154AE2-4929-11DE-A807-7A6FA4792AB3::: 
May 25 13:41:00 mail zimbramon[2706]: 2706:info: :::4D154AE2-4929-11DE-A807-7A6FA4792AB3:::om_restore_table, Com_revoke, Com_revoke_all, Com_rollback, Com_savepoint, Com_select, Com_set_option, Com_show_binlog_events, Com_show_binlogs, Com_show_charsets, Com_show_collations, Com_show_column_types, Com_show_create_db, Com_show_create_table, Com_show_databases, Com_show_errors, Com_show_fields, Com_show_grants, Com_show_innodb_status, Com_show_keys, Com_show_logs, Com_show_master_status, Com_show_ndb_status, Com_show_new_master, Com_show_open_tables, Com_show_privileges, Com_show_processlist, Com_show_slave_hosts, Com_show_slave_status, Com_show_status, Com_show_storage_engines, Com_show_tables, Com_show_triggers, Com_show_variables, Com_show_warnings, Com_slave_start, Com_slave_stop, Com_stmt_close, Com_stmt_execute, Com_stmt_fetch, Com_stmt_prepare, Com_stmt_reset, Com_stmt_send_long_data, Com_truncate, Com_unlock_tables, Com_update, Com_update_multi, Com_xa_commit, Com_xa_end:::4D1558C0-4929-11DE-A807-7A6F
May 25 13:41:00 mail zimbramon[2706]: 2706:info: :::4D1558C0-4929-11DE-A807-7A6FA4792AB3:::, Com_xa_prepare, Com_xa_recover, Com_xa_rollback, Com_xa_start, Compression, Connections, Created_tmp_disk_tables, Created_tmp_files, Created_tmp_tables, Delayed_errors, Delayed_insert_threads, Delayed_writes, Flush_commands, Handler_commit, Handler_delete, Handler_discover, Handler_prepare, Handler_read_first, Handler_read_key, Handler_read_next, Handler_read_prev, Handler_read_rnd, Handler_read_rnd_next, Handler_rollback, Handler_savepoint, Handler_savepoint_rollback, Handler_update, Handler_write, Innodb_buffer_pool_pages_data, Innodb_buffer_pool_pages_dirty, Innodb_buffer_pool_pages_flushed, Innodb_buffer_pool_pages_free, Innodb_buffer_pool_pages_misc, Innodb_buffer_pool_pages_total, Innodb_buffer_pool_read_ahead_rnd, Innodb_buffer_pool_read_ahead_seq, Innodb_buffer_pool_read_requests, Innodb_buffer_pool_reads, Innodb_buffer_pool_wait_free, Innodb_buffer_pool_write_requests, Innodb_:::4D155D20-4929-11DE-A807-7A6F
May 25 13:41:00 mail zimbramon[2706]: 2706:info: :::4D155D20-4929-11DE-A807-7A6FA4792AB3:::data_fsyncs, Innodb_data_pending_fsyncs, Innodb_data_pending_reads, Innodb_data_pending_writes, Innodb_data_read, Innodb_data_reads, Innodb_data_writes, Innodb_data_written, Innodb_dblwr_pages_written, Innodb_dblwr_writes, Innodb_log_waits, Innodb_log_write_requests, Innodb_log_writes, Innodb_os_log_fsyncs, Innodb_os_log_pending_fsyncs, Innodb_os_log_pending_writes, Innodb_os_log_written, Innodb_page_size, Innodb_pages_created, Innodb_pages_read, Innodb_pages_written, Innodb_row_lock_current_waits, Innodb_row_lock_time, Innodb_row_lock_time_avg, Innodb_row_lock_time_max, Innodb_row_lock_waits, Innodb_rows_deleted, Innodb_rows_inserted, Innodb_rows_read, Innodb_rows_updated, Key_blocks_not_flushed, Key_blocks_unused, Key_blocks_used, Key_read_requests, Key_reads, Key_write_requests, Key_writes, Last_query_cost, Max_used_connections, Not_flushed_delayed_rows, Open_files, Open_streams, Open:::4D156194-4929-11DE-A807-7A6F
May 25 13:41:00 mail zimbramon[2706]: 2706:info: :::4D156194-4929-11DE-A807-7A6FA4792AB3:::_tables, Opened_tables, Prepared_stmt_count, Qcache_free_blocks, Qcache_free_memory, Qcache_hits, Qcache_inserts, Qcache_lowmem_prunes, Qcache_not_cached, Qcache_queries_in_cache, Qcache_total_blocks, Queries, Questions, Rpl_status, Select_full_join, Select_full_range_join, Select_range, Select_range_check, Select_scan, Slave_open_temp_tables, Slave_retried_transactions, Slave_running, Slow_launch_threads, Slow_queries, Sort_merge_passes, Sort_range, Sort_rows, Sort_scan, Ssl_accept_renegotiates, Ssl_accepts, Ssl_callback_cache_hits, Ssl_cipher, Ssl_cipher_list, Ssl_client_connects, Ssl_connect_renegotiates, Ssl_ctx_verify_depth, Ssl_ctx_verify_mode, Ssl_default_timeout, Ssl_finished_accepts, Ssl_finished_connects, Ssl_session_cache_hits, Ssl_session_cache_misses, Ssl_session_cache_mode, Ssl_session_cache_overflows, Ssl_session_cache_size, Ssl_session_cache_timeouts, Ssl_sessions_reused,:::4D1565EA-4929-11DE-A807-7A6F
May 25 13:41:00 mail zimbramon[2706]: 2706:info: :::4D1565EA-4929-11DE-A807-7A6FA4792AB3::: Ssl_used_session_cache_entries, Ssl_verify_depth, Ssl_verify_mode, Ssl_version, Table_locks_immediate, Table_locks_waited, Tc_log_max_pages_used, Tc_log_page_size, Tc_log_page_waits, Threads_cached, Threads_connected, Threads_created, Threads_running, Uptime, Uptime_since_flush_status:: 05/25/2009 13:41:00,1,0,0,0,12682120,115178913,3,0,0,255,0,0,0,28,0,272,0,13495,0,0,0,0,0,0,11,0,0,0,0,0,0,0,0,0,0,0,0,0,0,267,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,17904,0,21245,53538,0,0,0,3,0,0,0,1,0,0,0,3801,0,0,0,0,0,0,0,0,0,0,10231,0,28,0,3,0,0,0,17077,29380,0,17462,0,0,0,0,15205,2,0,0,0,0,0,0,OFF,10236,2,5,7650,0,0,0,1,28953,0,0,0,15,141101,2417622,7739,12500,1902709,511,0,0,0,1895290,1273,0,17251,1,6,1280,197,45,3802459,8261,0,95517,23819,0,0,0,279302144,9803,31294,580410368,17251,4178,0,28328,7116,11049,0,0,13115904,16384,72,16914,17251,0,0,0,0,0,42,216,3488593,17914,0,7245,0,0,0,0,0,0.000000,4,:::4D1569F0-4929-11DE-A807-7A6F
May 25 13:41:00 mail zimbramon[2706]: :::4D1569F0-4929-11DE-A807-7A6FA4792AB3:::0,75,0,120,761,25,0,0,0,0,0,0,0,0,178433,143894,NULL,0,0,943,0,7646,0,0,OFF,0,11,0,86,1646,2,0,0,0,,,0,0,0,0,0,0,0,0,0,NONE,0,0,0,0,0,0,0,,52950,0,0,0,0,1,3,4,1,228170,178433 
May 25 13:41:00 mail zimbramon[2702]: 2702:info: zmstat io.csv: timestamp:: 05/25/2009 13:40:30, tps, kB_read/s, kB_wrtn/s, kB_read, kB_wrtn 
May 25 13:41:00 mail zimbramon[2698]: 2698:info: zmstat io-x.csv: timestamp:: 05/25/2009 13:40:30, rrqm/s, wrqm/s, r/s, w/s, rkB/s, wkB/s, avgrq-sz, avgqu-sz, await, svctm, %util 
May 25 13:41:01 mail zimbramon[2696]: 2696:info: zmstat vm.csv: timestamp, r, b, swpd, free, buff, cache, si, so, bi, bo, in, cs, us, sy, id, wa, st, MemTotal, MemFree, Buffers, Cached, SwapCached, Active, Inactive, SwapTotal, SwapFree, Dirty, Writeback, AnonPages, Mapped, Slab, SReclaimable, SUnreclaim, PageTables, NFS_Unstable, Bounce, CommitLimit, Committed_AS, VmallocTotal, VmallocUsed, VmallocChunk, HugePages_Total, HugePages_Free, HugePages_Rsvd, HugePages_Surp, Hugepagesize, loadavg:: 05/25/2009 13:41:01, 0, 0, 0, 2918008, 0, 0, 0, 0, 11, 15, 0, 6028, 2, 1, 97, 0, 0, 4045944, 2918008, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 2048, 0.05 
May 25 13:41:11 mail zimbramon[2704]: 2704:info: zmstat fd.csv: timestamp, fd_count:: 05/25/2009 13:41:11, 640

[pfnguyen]

I see what the problem is, the long line eliding is failing because duplicate uuid values are being used.

I'll check to see if this happens with current code or not...

Edit: oops, I appear to have read too quickly, the UUIDs are not duplicate (just the trailing section), that would not be the cause of the problem. This does not occur on our local server (nor in test environments either).

I'll try taking your output and running it and seeing what happens.

[pfnguyen]

OK, the lines are too long (>1022 characters)

Bug 38284 – Zimbra::Mon::Logger::LogStat lines too long

[jens]

Patched ~/zimbramon/lib/Zimbra/Mon/Logger.pm locally (doing a "s/900/800/"), restarted zimbra, rotated the logs, can confirm that lines are shorter now, but alas... the logger dies after a couple of seconds with the same error as in my original post.

[pfnguyen]

Hmm, sorry, I seem to have lost track of this thread; are you still having this problem?

[jens]

it didn't sort out itself if that's what you mean i ignored the logger for the time being and will update you when we switch to 6.0 final