Uploaded image for project: 'MariaDB Server'
  1. MariaDB Server
  2. MDEV-9305

MariaDB Server Crashes while querying OQGRAPH. This happens when using connection pooling from an node.js application.

    XMLWordPrintable

    Details

    • Type: Bug
    • Status: Confirmed (View Workflow)
    • Priority: Major
    • Resolution: Unresolved
    • Affects Version/s: 10.1.8, 10.1, 10.0
    • Fix Version/s: 10.1, 10.0
    • Labels:
      None
    • Environment:
      Distributor ID: Ubuntu
      Description: Ubuntu 15.10
      Release: 15.10
      Codename: wily
      Linux 4.2.0-17-generic
      running on the Azure Cloud

      Description

      Dec 18 14:09:27 spotlight mysqld[8755]: 2015-12-18 14:09:27 140541720311552 [Warning] Aborted connection 176 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 14:09:27 spotlight mysqld[8755]: 2015-12-18 14:09:27 140541766850304 [Warning] Aborted connection 175 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 14:09:27 spotlight mysqld[8755]: 2015-12-18 14:09:27 140541766551296 [Warning] Aborted connection 156 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 14:13:21 spotlight systemd[1]: Started Session 87 of user spotlightadmin.
      Dec 18 14:14:05 spotlight mysqld[8755]: 2015-12-18 14:14:05 140541720012544 [Warning] Aborted connection 177 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 14:14:05 spotlight mysqld[8755]: 2015-12-18 14:14:05 140541766551296 [Warning] Aborted connection 193 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 14:14:05 spotlight mysqld[8755]: 2015-12-18 14:14:05 140541719713536 [Warning] Aborted connection 194 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 14:17:01 spotlight CRON[9261]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
      Dec 18 14:24:19 spotlight mysqld[8755]: 2015-12-18 14:24:19 140541766850304 [Warning] Aborted connection 195 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 14:27:23 spotlight mysqld[8755]: 2015-12-18 14:27:23 140541719713536 [Warning] Aborted connection 205 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 14:57:31 spotlight systemd[1]: Started Session 89 of user spotlightadmin.
      Dec 18 14:59:11 spotlight systemd[1]: Started Session 90 of user spotlightadmin.
      Dec 18 15:00:22 spotlight mysqld[8755]: 2015-12-18 15:00:22 140541719713536 [Warning] Aborted connection 378 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 15:00:22 spotlight mysqld[8755]: 2015-12-18 15:00:22 140541720610560 [Warning] Aborted connection 379 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 15:09:13 spotlight systemd[1]: Started Session 91 of user spotlightadmin.
      Dec 18 15:10:39 spotlight mysqld[8755]: 2015-12-18 15:10:39 140541720012544 [Warning] Aborted connection 380 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 15:11:40 spotlight mysqld[8755]: 2015-12-18 15:11:40 140541720012544 [Warning] Aborted connection 426 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 15:11:40 spotlight mysqld[8755]: 2015-12-18 15:11:40 140541766850304 [Warning] Aborted connection 427 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 15:11:40 spotlight mysqld[8755]: 2015-12-18 15:11:40 140541719713536 [Warning] Aborted connection 401 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 15:17:01 spotlight CRON[9644]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
      Dec 18 15:21:41 spotlight mysqld[8755]: 2015-12-18 15:21:41 140541766551296 [Warning] Aborted connection 428 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 15:33:50 spotlight systemd[1]: Started Session 93 of user spotlightadmin.
      Dec 18 15:34:15 spotlight systemd[1]: Started Session 94 of user spotlightadmin.
      Dec 18 15:34:29 spotlight mysqld[8755]: 2015-12-18 15:34:29 140541720012544 [Warning] Aborted connection 520 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 15:34:29 spotlight mysqld[8755]: 2015-12-18 15:34:29 140541766551296 [Warning] Aborted connection 521 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 15:35:29 spotlight mysqld[8755]: 2015-12-18 15:35:29 140541720311552 [Warning] Aborted connection 528 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 15:35:29 spotlight mysqld[8755]: 2015-12-18 15:35:29 140541720012544 [Warning] Aborted connection 526 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 15:35:29 spotlight mysqld[8755]: 2015-12-18 15:35:29 140541766850304 [Warning] Aborted connection 522 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 15:35:29 spotlight mysqld[8755]: 2015-12-18 15:35:29 140541766551296 [Warning] Aborted connection 527 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 15:37:12 spotlight systemd[1]: Started Session 95 of user spotlightadmin.
      Dec 18 15:42:24 spotlight mysqld[8755]: 2015-12-18 15:42:24 140541766551296 [Warning] Aborted connection 557 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 15:42:24 spotlight mysqld[8755]: 2015-12-18 15:42:24 140541719713536 [Warning] Aborted connection 529 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 15:42:24 spotlight mysqld[8755]: 2015-12-18 15:42:24 140541720610560 [Warning] Aborted connection 558 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 15:50:39 spotlight mysqld[8755]: 2015-12-18 15:50:39 140541720610560 [Warning] Unsafe statement written to the binary log using statement format since BINLOG_FORMAT = STATEMENT. Statements writing to a table with an auto-increment column after selecting from another table are unsafe because the order in which rows are retrieved determines what (if any) rows will be written. This order cannot be predicted and may differ on master and the slave. Statement: update contacts as c  inner join subject_numbers n on n.telephonenumber=c.phonenumber inner join subject_matters sm on sm.uniqueid = n.subject_matter_uniqueid set c.subject_matter_user_id=sm.uniqueid and c.updateSequenceNumber=328 where c.user_id='10205587125490229' and c.updateSequenceNumber=327
      Dec 18 15:50:39 spotlight mysqld[8755]: 2015-12-18 15:50:39 140541720610560 [Warning] Unsafe statement written to the binary log using statement format since BINLOG_FORMAT = STATEMENT. Statements writing to a table with an auto-increment column after selecting from another table are unsafe because the order in which rows are retrieved determines what (if any) rows will be written. This order cannot be predicted and may differ on master and the slave. Statement: update contacts as c  inner join user u on c.phonenumber=u.mobileno set c.other_user_id=u.uniqueid and c.updateSequenceNumber=328 where c.user_id='10205587125490229'
      Dec 18 15:52:26 spotlight mysqld[8755]: 2015-12-18 15:52:26 140541720012544 [Warning] Aborted connection 559 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 16:00:39 spotlight mysqld[8755]: 2015-12-18 16:00:39 140541720610560 [Warning] Aborted connection 569 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 16:14:50 spotlight mysqld[8755]: 2015-12-18 16:14:50 140541720311552 [Warning] Aborted connection 644 to db: 'jalise_dev' user: 'root' host: 'localhost' (Unknown error)
      Dec 18 16:17:01 spotlight CRON[9941]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)

      Dec 18 16:19:20 spotlight mysqld[8755]: 151218 16:19:20 [ERROR] mysqld got signal 11 ;
      Dec 18 16:19:20 spotlight mysqld[8755]: This could be because you hit a bug. It is also possible that this binary
      Dec 18 16:19:20 spotlight mysqld[8755]: or one of the libraries it was linked against is corrupt, improperly built,
      Dec 18 16:19:20 spotlight mysqld[8755]: or misconfigured. This error can also be caused by malfunctioning hardware.
      Dec 18 16:19:20 spotlight mysqld[8755]: To report this bug, see http://kb.askmonty.org/en/reporting-bugs
      Dec 18 16:19:20 spotlight mysqld[8755]: We will try our best to scrape up some info that will hopefully help
      Dec 18 16:19:20 spotlight mysqld[8755]: diagnose the problem, but since we have already crashed,
      Dec 18 16:19:20 spotlight mysqld[8755]: something is definitely wrong and this may fail.
      Dec 18 16:19:20 spotlight mysqld[8755]: Server version: 10.1.8-MariaDB-1~vivid-log
      Dec 18 16:19:20 spotlight mysqld[8755]: key_buffer_size=134217728
      Dec 18 16:19:20 spotlight mysqld[8755]: read_buffer_size=2097152
      Dec 18 16:19:20 spotlight mysqld[8755]: max_used_connections=6
      Dec 18 16:19:20 spotlight mysqld[8755]: max_threads=102
      Dec 18 16:19:20 spotlight mysqld[8755]: thread_count=3
      Dec 18 16:19:20 spotlight mysqld[8755]: It is possible that mysqld could use up to
      Dec 18 16:19:20 spotlight mysqld[8755]: key_buffer_size + (read_buffer_size + sort_buffer_size)*max_threads = 759822 K  bytes of memory
      Dec 18 16:19:20 spotlight mysqld[8755]: Hope that's ok; if not, decrease some variables in the equation.
      Dec 18 16:19:20 spotlight mysqld[8755]: Thread pointer: 0x0x7fd233942008
      Dec 18 16:19:20 spotlight mysqld[8755]: Attempting backtrace. You can use the following information to find out
      Dec 18 16:19:20 spotlight mysqld[8755]: where mysqld died. If you see no messages after this, something went
      Dec 18 16:19:20 spotlight mysqld[8755]: terribly wrong...
      Dec 18 16:19:20 spotlight mysqld[8755]: stack_bottom = 0x7fd26b478db8 thread_stack 0x48000
      Dec 18 16:19:20 spotlight mysqld[8755]: /usr/sbin/mysqld(my_print_stacktrace+0x2e)[0x5651419fae2e]
      Dec 18 16:19:20 spotlight mysqld[8755]: /usr/sbin/mysqld(handle_fatal_signal+0x34d)[0x56514153ea3d]
      Dec 18 16:19:20 spotlight mysqld[8755]: /lib/x86_64-linux-gnu/libpthread.so.0(+0x10d10)[0x7fd26caefd10]
      Dec 18 16:19:20 spotlight mysqld[8755]: /usr/sbin/mysqld(+0x71f91d)[0x5651416c791d]
      Dec 18 16:19:20 spotlight mysqld[8755]: /usr/sbin/mysqld(_Z11open_tablesP3THDRK14DDL_options_stPP10TABLE_LISTPjjP19Prelocking_strategy+0xfb0)[0x56514137b140]
      Dec 18 16:19:20 spotlight mysqld[8755]: /usr/sbin/mysqld(_Z20open_and_lock_tablesP3THDRK14DDL_options_stP10TABLE_LISTbjP19Prelocking_strategy+0x33)[0x56514137b563]
      Dec 18 16:19:20 spotlight mysqld[8755]: /usr/sbin/mysqld(+0x40a86d)[0x5651413b286d]
      Dec 18 16:19:20 spotlight mysqld[8755]: /usr/sbin/mysqld(_Z21mysql_execute_commandP3THD+0x5d41)[0x5651413be611]
      Dec 18 16:19:20 spotlight mysqld[8755]: /usr/sbin/mysqld(_Z11mysql_parseP3THDPcjP12Parser_state+0x20e)[0x5651413c193e]
      Dec 18 16:19:20 spotlight mysqld[8755]: /usr/sbin/mysqld(_Z16dispatch_command19enum_server_commandP3THDPcj+0x1c89)[0x5651413c43f9]
      Dec 18 16:19:20 spotlight mysqld[8755]: /usr/sbin/mysqld(_Z10do_commandP3THD+0x16e)[0x5651413c4b3e]
      Dec 18 16:19:20 spotlight mysqld[8755]: /usr/sbin/mysqld(_Z24do_handle_one_connectionP3THD+0x182)[0x56514148c3e2]
      Dec 18 16:19:20 spotlight mysqld[8755]: /usr/sbin/mysqld(handle_one_connection+0x40)[0x56514148c5a0]
      Dec 18 16:19:20 spotlight mysqld[8755]: /lib/x86_64-linux-gnu/libpthread.so.0(+0x76aa)[0x7fd26cae66aa]
      Dec 18 16:19:20 spotlight mysqld[8755]: /lib/x86_64-linux-gnu/libc.so.6(clone+0x6d)[0x7fd26c191eed]
      Dec 18 16:19:20 spotlight mysqld[8755]: Trying to get some variables.
      Dec 18 16:19:20 spotlight mysqld[8755]: Some pointers may be invalid and cause the dump to abort.
      Dec 18 16:19:20 spotlight mysqld[8755]: Query (0x7fd23a6f9020): select  uu.*, fn_distance_to_current_user(1,uu.id) distance_to_current_user, fn_path_to_current_user(1,uu.id) path_to_current_user, fn_known_by_how_many_users(uu.id) know_to_how_many_others,fn_knows_how_many_users(uu.id) knows_how_many_users  from  (select distinct u.* from user u   where u.systemaccount=1 and u.updateSequenceNumber > 0 union  select distinct u.* from user u   inner join contacts c on u.mobileNo=c.phonenumber  where c.user_id='10205587125490229' and u.updateSequenceNumber > 0 union   select distinct  u.* from user u   inner join reviews r on r.created_by_user_id=u.uniqueID  inner join subject_numbers s on r.subject_matter_uniqueid= s.subject_matter_uniqueid  inner join contacts c on s.telephonenumber = c.phonenumber  where c.user_id='10205587125490229' and u.updateSequenceNumber > 0 union   select distinct u.* from user u   inner join likes l on l.created_by_user_id=u.uniqueID  inner join reviews r on l.review_uniqueid=r.uniqueID  inner join subject_numbers s on r.subject_matter_uniqueid= s.subject_matter_uniqueid  inner join contacts c on s.telephonenumber = c.phonenumber  where c.user_id='10205587125490229'  and u.updateSequenceNumber > 0 union   select distinct u.* from user u   inner join comments cn on cn.created_by_user_id=u.uniqueID  inner join reviews r on cn.review_uniqueid=r.uniqueID  inner join subject_numbers s on r.subject_matter_uniqueid= s.subject_matter_uniqueid  inner join contacts c on s.telephonenumber=c.phonenumber   where c.user_id='10205587125490229' and u.updateSequenceNumber > 0 union   select distinct u.* from user u   inner join call_logs c on u.mobileNo=c.number   where c.user_id='10205587125490229'  and u.updateSequenceNumber > 0 union   select distinct  u.* from user u   inner join reviews r on r.created_by_user_id=u.uniqueID  inner join subject_numbers s on r.subject_matter_uniqueid= s.subject_matter_uniqueid  inner join call_logs c on s.telephonenumber = c.number  where c.user_id='10205587125490229'  and u.updateSequenceNumber > 0 union  sele
      Dec 18 16:19:20 spotlight mysqld[8755]: ct distinct u.* from user u   inner join likes l on l.created_by_user_id=u.uniqueID  inner join reviews r on l.review_uniqueid=r.uniqueID  inner join subject_numbers s on r.subject_matter_uniqueid= s.subject_matter_uniqueid  inner join call_logs c on s.telephonenumber = c.number  where c.user_id='10205587125490229'  and u.updateSequenceNumber > 0 union   select distinct u.* from user u   inner join comments cn on cn.created_by_user_id=u.uniqueID  inner join reviews r on cn.review_uniqueid=r.uniqueID  inner join subject_numbers s on r.subject_matter_uniqueid= s.subject_matter_uniqueid  inner join call_logs c on s.telephonenumber=c.number   where c.user_id='10205587125490229'  and u.updateSequenceNumber > 0 ) uu
      Dec 18 16:19:20 spotlight mysqld[8755]: Connection ID (thread ID): 701
      Dec 18 16:19:20 spotlight mysqld[8755]: Status: NOT_KILLED
      Dec 18 16:19:20 spotlight mysqld[8755]: Optimizer switch: index_merge=on,index_merge_union=on,index_merge_sort_union=on,index_merge_intersection=on,index_merge_sort_intersection=off,engine_condition_pushdown=off,index_condition_pushdown=on,derived_merge=on,derived_with_keys=on,firstmatch=on,loosescan=on,materialization=on,in_to_exists=on,semijoin=on,partial_match_rowid_merge=on,partial_match_table_scan=on,subquery_cache=on,mrr=off,mrr_cost_based=off,mrr_sort_keys=off,outer_join_with_cache=on,semijoin_with_cache=on,join_cache_incremental=on,join_cache_hashed=on,join_cache_bka=on,optimize_join_buffer_size=off,table_elimination=on,extended_keys=on,exists_to_in=on
      Dec 18 16:19:20 spotlight mysqld[8755]: The manual page at http://dev.mysql.com/doc/mysql/en/crashing.html contains
      Dec 18 16:19:20 spotlight mysqld[8755]: information that should help you find out what is causing the crash.
      Dec 18 16:19:20 spotlight systemd[1]: mariadb.service: Main process exited, code=exited, status=1/FAILURE
      Dec 18 16:19:20 spotlight systemd[1]: mariadb.service: Unit entered failed state.
      Dec 18 16:19:20 spotlight systemd[1]: mariadb.service: Failed with result 'exit-code'.

        Attachments

          Activity

            People

            Assignee:
            andymc73 Andrew McDonnell
            Reporter:
            masukuma Ben Chege Ngumi
            Votes:
            1 Vote for this issue
            Watchers:
            4 Start watching this issue

              Dates

              Created:
              Updated: