Received: from malur.postgresql.org ([217.196.149.56]) by arkaria.postgresql.org with esmtps (TLS1.3:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.92) (envelope-from ) id 1jewy2-0007UC-MI for pljava-dev@arkaria.postgresql.org; Sat, 30 May 2020 08:35:47 +0000 Received: from localhost ([127.0.0.1] helo=malur.postgresql.org) by malur.postgresql.org with esmtp (Exim 4.92) (envelope-from ) id 1jewy1-00023O-79 for pljava-dev@arkaria.postgresql.org; Sat, 30 May 2020 08:35:45 +0000 Received: from makus.postgresql.org ([2001:4800:3e1:1::229]) by malur.postgresql.org with esmtps (TLS1.3:ECDHE_RSA_AES_256_GCM_SHA384:256) (Exim 4.92) (envelope-from ) id 1jewy0-00023H-Mq for pljava-dev@lists.postgresql.org; Sat, 30 May 2020 08:35:45 +0000 Received: from mail-ed1-x541.google.com ([2a00:1450:4864:20::541]) by makus.postgresql.org with esmtps (TLS1.3:ECDHE_RSA_AES_128_GCM_SHA256:128) (Exim 4.92) (envelope-from ) id 1jewxy-0001ad-As for pljava-dev@lists.postgresql.org; Sat, 30 May 2020 08:35:43 +0000 Received: by mail-ed1-x541.google.com with SMTP id p18so3517771eds.7 for ; Sat, 30 May 2020 01:35:42 -0700 (PDT) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=gmail.com; s=20161025; h=mime-version:references:in-reply-to:from:date:message-id:subject:to :cc; bh=wfX8ZsPDS+L94Ag8FCtTlAvg9jfUGn7j8T6CMwTE4sY=; b=hlWJ7JKR22P6W/zzOpJsVU9F4ySPArPv4v3rpJnzXUAy5T7cqq9LfLVkjgYeZJxtcj x+2pYyqDSAe4YrKUdg7Ju3CQNT/woctqhuCrzTFEIMO+odHOChMUr+w5QlxVScZEHhST lSJSzjbGfD6Px8x2Jis4fmtPwmQYqB6azyzQnDvSaM1fv+EMaNiPZuJuh71Rkjd1uNVG 29GH420PCc3ymSD1asXbfBC+QtPeRJ3N9Eu6OmMNinJXJXkcC5oh1+asGXfjyfQmtMd6 rQSof5t1TqCIFCjSLZY7/n9pGDcUjcReRNdX1u1hrMeBkN6KfQOWRR8YJ44H3HP9K2SQ TCxA== X-Google-DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=1e100.net; s=20161025; h=x-gm-message-state:mime-version:references:in-reply-to:from:date :message-id:subject:to:cc; bh=wfX8ZsPDS+L94Ag8FCtTlAvg9jfUGn7j8T6CMwTE4sY=; b=XcrWaepm5QkiVJjzmppGnhLiiKL5jwQiPdfso0CAE8H+rthC1MwRfqJ7r7l18KDznx jlkppwN8U0ypqMfIUwBgQAJAwvf0GI4XAO+R9IEpUm2YX8GlEPoK0i3wmc0LuLJNtj35 1t42qBnrE/64kTScpIxzrj4zw+Y3dMjf/zd9XXm7ysDwsNWPN7qImLReWH35BWiIsS/N kD4MlPK8eDM++EgSUQmifoNT8AYeZxx59HgNxDnDzeznNJbh/POnNjZu9uMkSE1GGcBz XTXN9fn2p1UJ6Nb6zlWXpO4CpCG+e3xrCFn42KlZ1XOyptdfzjZ+FvL4e3bhP9i7cwKJ T6PQ== X-Gm-Message-State: AOAM531H9VFPb2NE6YjnHgFAPaxTiX/6+5T0cbTumhitSW5o46RP4Gj6 Qo2toW01X5+5r9buf6BD70qWmwi8C4CKvODkWhQREXLnZSE= X-Google-Smtp-Source: ABdhPJxWm83Cgin5eBUavLYFg6oHFqIv7TviYOabgZip/FqTu3w3l3QtL7lcozYnjtT5xw7mMoCQ0+WjjFePrjROUvk= X-Received: by 2002:a50:d785:: with SMTP id w5mr11849789edi.212.1590827739319; Sat, 30 May 2020 01:35:39 -0700 (PDT) MIME-Version: 1.0 References: <5EC17E17.7070002@anastigmatix.net> <5ECED046.3000308@anastigmatix.net> <5ED0FCB0.405@anastigmatix.net> <5ED10C6D.2020000@anastigmatix.net> <5ED10F36.7060304@anastigmatix.net> <5ED15825.9060504@anastigmatix.net> In-Reply-To: <5ED15825.9060504@anastigmatix.net> From: Kartik Ohri Date: Sat, 30 May 2020 14:05:27 +0530 Message-ID: Subject: Re: Starting build-system work (Windows/Mac users please speak up) To: Chapman Flack Cc: pljava-dev@lists.postgresql.org Content-Type: multipart/alternative; boundary="0000000000004d10e705a6d97207" List-Id: List-Help: List-Subscribe: List-Post: List-Owner: List-Archive: Precedence: bulk --0000000000004d10e705a6d97207 Content-Type: text/plain; charset="UTF-8" I am now redirecting the output of the scripts to a file and trying to filter using grep. I discovered that passing the -Xcheck:jni flag not crashes travis but also the PostgreSQL database. Below is the postgres log output. Can you shed some light on this? 020-05-30 08:02:10.952 UTC [10741] LOG: starting PostgreSQL 12.3 (Ubuntu 12.3-1.pgdg18.04+1) on x86_64-pc-linux-gnu, compiled by gcc (Ubuntu 7.5.0-3ubuntu1~18.04) 7.5.0, 64-bit 11432020-05-30 08:02:10.952 UTC [10741] LOG: listening on IPv4 address "127.0.0.1", port 5432 11442020-05-30 08:02:10.952 UTC [10741] LOG: listening on IPv4 address "127.0.1.1", port 5432 11452020-05-30 08:02:10.954 UTC [10741] LOG: listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432" 11462020-05-30 08:02:10.972 UTC [10753] LOG: database system was shut down at 2020-05-30 08:02:09 UTC 11472020-05-30 08:02:10.978 UTC [10741] LOG: database system is ready to accept connections 11482020-05-30 08:04:14.658 UTC [10741] LOG: received SIGHUP, reloading configuration files 11492020-05-30 08:04:14.659 UTC [10741] LOG: parameter "pljava.libjvm_location" changed to "/home/travis/openjdk14/lib/server/libjvm.so" 11502020-05-30 08:04:14.713 UTC [10741] LOG: received SIGHUP, reloading configuration files 11512020-05-30 08:04:14.714 UTC [10741] LOG: parameter "pljava.vmoptions" changed to "-enableassertions:org.postgresql.pljava... -Xcheck:jni" 11522020-05-30 08:04:54.113 UTC [10741] LOG: server process (PID 12377) was terminated by signal 6: Aborted 11532020-05-30 08:04:54.113 UTC [10741] DETAIL: Failed process was running: CREATE EXTENSION pljava; 11542020-05-30 08:04:54.113 UTC [10741] LOG: terminating any other active server processes 11552020-05-30 08:04:54.114 UTC [10758] WARNING: terminating connection because of crash of another server process 11562020-05-30 08:04:54.114 UTC [10758] DETAIL: The postmaster has commanded this server process to roll back the current transaction and exit, because another server process exited abnormally and possibly corrupted shared memory. 11292020-05-30 08:04:54.114 UTC [10758] HINT: In a moment you should be able to reconnect to the database and repeat your command. 11302020-05-30 08:04:54.121 UTC [10741] LOG: all server processes terminated; reinitializing 11312020-05-30 08:04:54.170 UTC [12415] LOG: database system was interrupted; last known up at 2020-05-30 08:02:10 UTC 11322020-05-30 08:04:54.225 UTC [12415] LOG: database system was not properly shut down; automatic recovery in progress 11332020-05-30 08:04:54.228 UTC [12415] LOG: redo starts at 0/164E5E8 11342020-05-30 08:04:54.231 UTC [12415] LOG: invalid record length at 0/169CF30: wanted 24, got 0 11352020-05-30 08:04:54.231 UTC [12415] LOG: redo done at 0/169CEE8 11362020-05-30 08:04:54.235 UTC [12429] postgres@postgres FATAL: the database system is in recovery mode 11372020-05-30 08:04:54.259 UTC [10741] LOG: database system is ready to accept connections 11382020-05-30 08:04:54.290 UTC [12448] postgres@postgres ERROR: schema "sqlj" does not exist at character 8 11392020-05-30 08:04:54.290 UTC [12448] postgres@postgres STATEMENT: SELECT sqlj.set_classpath('public', 'saxon'); 11402020-05-30 08:04:54.336 UTC [12462] postgres@postgres ERROR: schema "sqlj" does not exist at character 8 11412020-05-30 08:04:54.336 UTC [12462] postgres@postgres STATEMENT: SELECT sqlj.install_jar('file:/home/travis/build/amCap1712/pljava/pljava-examples/target/pljava-examples-1.6.0-SNAPSHOT.jar','examples',true); 1142 11572020-05-30 08:04:54.384 UTC [12477] postgres@postgres STATEMENT: SELECT sqlj.get_classpath('javatest'); 11582020-05-30 08:04:54.430 UTC [12490] postgres@postgres ERROR: schema "sqlj" does not exist at character 8 11592020-05-30 08:04:54.430 UTC [12490] postgres@postgres STATEMENT: SELECT sqlj.set_classpath('javatest', 'examples'); 11602020-05-30 08:04:54.475 UTC [12503] postgres@postgres ERROR: schema "javatest" does not exist at character 8 11612020-05-30 08:04:54.475 UTC [12503] postgres@postgres STATEMENT: SELECT javatest.java_addone(3); 11622020-05-30 08:07:19.280 UTC [10741] LOG: received SIGHUP, reloading configuration files 11632020-05-30 08:07:29.750 UTC [10741] LOG: received SIGHUP, reloading configuration files 11642020-05-30 08:07:48.355 UTC [10741] LOG: server process (PID 12610) was terminated by signal 6: Aborted 11652020-05-30 08:07:48.355 UTC [10741] DETAIL: Failed process was running: CREATE EXTENSION pljava; 11662020-05-30 08:07:48.355 UTC [10741] LOG: terminating any other active server processes 11672020-05-30 08:07:48.357 UTC [12435] WARNING: terminating connection because of crash of another server process 11682020-05-30 08:07:48.357 UTC [12435] DETAIL: The postmaster has commanded this server process to roll back the current transaction and exit, because another server process exited abnormally and possibly corrupted shared memory. 11692020-05-30 08:07:48.357 UTC [12435] HINT: In a moment you should be able to reconnect to the database and repeat your command. 11702020-05-30 08:07:48.359 UTC [10741] LOG: all server processes terminated; reinitializing 11712020-05-30 08:07:48.371 UTC [12629] LOG: database system was interrupted; last known up at 2020-05-30 08:04:54 UTC 11722020-05-30 08:07:48.412 UTC [12629] LOG: database system was not properly shut down; automatic recovery in progress 11732020-05-30 08:07:48.415 UTC [12629] LOG: redo starts at 0/169CFA8 11742020-05-30 08:07:48.417 UTC [12629] LOG: redo done at 0/16EBFA8 11752020-05-30 08:07:48.436 UTC [10741] LOG: database system is ready to accept connections 11762020-05-30 08:11:29.901 UTC [10741] LOG: received SIGHUP, reloading configuration files 11772020-05-30 08:11:29.955 UTC [10741] LOG: received SIGHUP, reloading configuration files 11782020-05-30 08:11:29.956 UTC [10741] LOG: parameter "pljava.vmoptions" changed to "-enableassertions:org.postgresql.pljava..." --0000000000004d10e705a6d97207 Content-Type: text/html; charset="UTF-8" Content-Transfer-Encoding: quoted-printable
I am now redirecting the output of the scripts to a file a= nd trying to filter using grep. I discovered that passing the -Xcheck:jni f= lag not crashes travis but also the PostgreSQL database. Below is the postg= res log output. Can you shed some light on this?
020-05-30 08:02:1=
0.952 UTC [10741] LOG:  starting PostgreSQL 12.3 (Ubuntu 12.3-1.pgdg18.04+1=
) on x86_64-pc-linux-gnu, compiled by gcc (Ubuntu 7.5.0-3ubuntu1~18.04) 7.5=
.0, 64-bit
11432020-05-30 08:02:10.952 UTC [10741] LOG:  l=
istening on IPv4 address "127.0.0.1", port 5432
11442020-05-30 08:02:10.952 UTC [10741] LOG:  listening on IPv4 address &qu=
ot;127.0.1.1", port 5432
11452020-05-30 08:02:10.954 =
UTC [10741] LOG:  listening on Unix socket "/var/run/postgresql/.s.PGS=
QL.5432"
11462020-05-30 08:02:10.972 UTC [10753] LOG=
:  database system was shut down at 2020-05-30 08:02:09 UTC
11472020-05-30 08:02:10.978 UTC [10741] LOG:  database system is ready to=
 accept connections
11482020-05-30 08:04:14.658 UTC [10741= ] LOG: received SIGHUP, reloading configuration files
11492020-05-30 08:04:14.659 UTC [10741] LOG: parameter "pljava.libjvm_lo= cation" changed to "/home/travis/openjdk14/lib/server/libjvm.so&q= uot;
11502020-05-30 08:04:14.713 UTC [10741] LOG: receiv= ed SIGHUP, reloading configuration files
115= 12020-05-30 08= :04:14.714 UTC [10741] LOG: parameter "pljava.vmoptions" changed= to "-enableassertions:org.postgresql.pljava... -Xcheck:jni"
11522020-05-30 08:04:54.113 UTC [10741] LOG: server process = (PID 12377) was terminated by signal 6: Aborted
11532020-= 05-30 08:04:54.113 UTC [10741] DETAIL: Failed process was running: CREATE = EXTENSION pljava;
11542020-05-30 08:04:54.113 UTC [10741]= LOG: terminating any other active server processes
1155= 2020-05-30 08:04:54.114 UTC [10758] WARNING: terminating connection becaus= e of crash of another server process
11562020-05-30 08:04= :54.114 UTC [10758] DETAIL: The postmaster has commanded this server proce= ss to roll back the current transaction and exit, because another server process exite= d abnormally and possibly corrupted shared memory.
11292= 020-05-30 08:04:54.114 UTC [10758] HINT: In a moment you should be able to= reconnect to the database and repeat your command.
11302= 020-05-30 08:04:54.121 UTC [10741] LOG: all server processes terminated; r= einitializing
11312020-05-30 08:04:54.170 UTC [12415] LOG= : database system was interrupted; last known up at 2020-05-30 08:02:10 UT= C
11322020-05-30 08:04:54.225 UTC [12415] LOG: database s= ystem was not properly shut down; automatic recovery in progress
11332020-05-30 08:04:54.228 UTC [12415] LOG: redo starts at 0/164E5= E8
11342020-05-30 08:04:54.231 UTC [12415] LOG: invalid r= ecord length at 0/169CF30: wanted 24, got 0
= 11352020-05-30= 08:04:54.231 UTC [12415] LOG: redo done at 0/169CEE8
11362020-05-30 08:04:54.235 UTC [12429] postgres@postgres FATAL: the database= system is in recovery mode
11372020-05-30 08:04:54.259 UT= C [10741] LOG: database system is ready to accept connections
=
11382020-05-30 08:04:54.290 UTC [12448] postgres@postgres ERROR: sch= ema "sqlj" does not exist at character 8
1139= 2020-05-30 08:04:54.290 UTC [12448] postgres@postgres STATEMENT: SELECT sq= lj.set_classpath('public', 'saxon');
1140= 2020-05-30 08:04:54.336 UTC [12462] postgres@postgres ERROR: schema "= sqlj" does not exist at character 8
114= 12020-05-30 0= 8:04:54.336 UTC [12462] postgres@postgres STATEMENT: SELECT sqlj.install_j= ar('file:/home/travis/build/amCap1712/pljava/pljava-examples/target/plj= ava-examples-1.6.0-SNAPSHOT.jar','examples',true);
=
1142<= /span>
11572020-05-30 08:04:54.384 UTC [12477] postgres@postgres = STATEMENT: SELECT sqlj.get_classpath('javatest');
11582020-05-30 08:04:54.430 UTC [12490] postgres@postgres ERROR: schema &= quot;sqlj" does not exist at character 8
2020-05-= 30 08:04:54.430 UTC [12490] postgres@postgres STATEMENT: SELECT sqlj.set_c= lasspath('javatest', 'examples');
11602020= -05-30 08:04:54.475 UTC [12503] postgres@postgres ERROR: schema "java= test" does not exist at character 8
116= 12020-05-30 08= :04:54.475 UTC [12503] postgres@postgres STATEMENT: SELECT javatest.java_a= ddone(3);
11622020-05-30 08:07:19.280 UTC [10741] LOG: re= ceived SIGHUP, reloading configuration files
11632020-05-3= 0 08:07:29.750 UTC [10741] LOG: received SIGHUP, reloading configuration f= iles
11642020-05-30 08:07:48.355 UTC [10741] LOG: server= process (PID 12610) was terminated by signal 6: Aborted
11652020-05-30 08:07:48.355 UTC [10741] DETAIL: Failed process was running:= CREATE EXTENSION pljava;
11662020-05-30 08:07:48.355 U= TC [10741] LOG: terminating any other active server processes
=
11672020-05-30 08:07:48.357 UTC [12435] WARNING: terminating connect= ion because of crash of another server process
11682020-0= 5-30 08:07:48.357 UTC [12435] DETAIL: The postmaster has commanded this se= rver process to roll back the current transaction and exit, because another= server process exited abnormally and possibly corrupted shared memory.
11692020-05-30 08:07:48.357 UTC [12435] HINT: In a moment y= ou should be able to reconnect to the database and repeat your command.
11702020-05-30 08:07:48.359 UTC [10741] LOG: all server pro= cesses terminated; reinitializing
11712020-05-30 08:07:48= .371 UTC [12629] LOG: database system was interrupted; last known up at 2020-05-30 08= :04:54 UTC
11722020-05-30 08:07:48.412 UTC [12629] LOG: d= atabase system was not properly shut down; automatic recovery in progress
11732020-05-30 08:07:48.415 UTC [12629] LOG: redo starts a= t 0/169CFA8
11742020-05-30 08:07:48.417 UTC [12629] LOG: = redo done at 0/16EBFA8
= 11752020-05-30 08:07:48.436 UTC [10= 741] LOG: database system is ready to accept connections
11762020-05-30 08:11:29.901 UTC [10741] LOG: received SIGHUP, reloading co= nfiguration files
11772020-05-30 08:11:29.955 UTC [10741] = LOG: received SIGHUP, reloading configuration files
11782020-05-30 08:11:29.956 UTC [10741] LOG: par= ameter "pljava.vmoptions" changed to "-enableassertions:org.= postgresql.pljava..."
--0000000000004d10e705a6d97207--