View Issue Details

IDProjectCategoryView StatusLast Update
0006929Talermechant backendpublic2021-08-24 16:22
Reporterttn Assigned ToChristian Grothoff  
PrioritynormalSeverityminorReproducibilityhave not tried
Status closedResolutionfixed 
PlatformLinuxOSDebianOS VersionBullseye/Sid
Product Versiongit (master) 
Summary0006929: "make check" fails on test_merchantdb_postgres
DescriptionHEAD is at f6dd87633dbba6d42e2b5e2a3949205205a4ee31.
Logs attached.
TagsNo tags attached.
Attached Files
.ttn.merchant-make-check.log (4,497 bytes)   
-*- mode: compilation; default-directory: "~/build/GNU/T/merchant/" -*-
Compilation started at Mon Jul 19 02:09:18

make check PGHOST=$HOME/pg TMPDIR=$HOME/tmp PATH=$HOME/t/bin:$PATH
Makefile:926: warning: overriding recipe for target 'doxygen-doc/taler-merchant.tag'
Makefile:921: warning: ignoring old recipe for target 'doxygen-doc/taler-merchant.tag'
Makefile:931: warning: overriding recipe for target 'doxygen-doc/taler-merchant.tag'
Makefile:926: warning: ignoring old recipe for target 'doxygen-doc/taler-merchant.tag'
Makefile:936: warning: overriding recipe for target 'doxygen-doc/taler-merchant.tag'
Makefile:931: warning: ignoring old recipe for target 'doxygen-doc/taler-merchant.tag'
Making check in .
make[1]: Entering directory '/home/ttn/build/GNU/T/merchant'
Makefile:926: warning: overriding recipe for target 'doxygen-doc/taler-merchant.tag'
Makefile:921: warning: ignoring old recipe for target 'doxygen-doc/taler-merchant.tag'
Makefile:931: warning: overriding recipe for target 'doxygen-doc/taler-merchant.tag'
Makefile:926: warning: ignoring old recipe for target 'doxygen-doc/taler-merchant.tag'
Makefile:936: warning: overriding recipe for target 'doxygen-doc/taler-merchant.tag'
Makefile:931: warning: ignoring old recipe for target 'doxygen-doc/taler-merchant.tag'
make[1]: Leaving directory '/home/ttn/build/GNU/T/merchant'
Making check in src
make[1]: Entering directory '/home/ttn/build/GNU/T/merchant/src'
Making check in include
make[2]: Entering directory '/home/ttn/build/GNU/T/merchant/src/include'
make[2]: Nothing to be done for 'check'.
make[2]: Leaving directory '/home/ttn/build/GNU/T/merchant/src/include'
Making check in mustach
make[2]: Entering directory '/home/ttn/build/GNU/T/merchant/src/mustach'
make  test_mustach_jansson run-original-tests.sh
make[3]: Entering directory '/home/ttn/build/GNU/T/merchant/src/mustach'
  CC       test_mustach_jansson.o
  CCLD     test_mustach_jansson
make[3]: Nothing to be done for 'run-original-tests.sh'.
make[3]: Leaving directory '/home/ttn/build/GNU/T/merchant/src/mustach'
make  check-TESTS
make[3]: Entering directory '/home/ttn/build/GNU/T/merchant/src/mustach'
make[4]: Entering directory '/home/ttn/build/GNU/T/merchant/src/mustach'
SKIP: run-original-tests.sh
PASS: test_mustach_jansson
============================================================================
Testsuite summary for taler-merchant 0.8.0
============================================================================
# TOTAL: 2
# PASS:  1
# SKIP:  1
# XFAIL: 0
# FAIL:  0
# XPASS: 0
# ERROR: 0
============================================================================
make[4]: Leaving directory '/home/ttn/build/GNU/T/merchant/src/mustach'
make[3]: Leaving directory '/home/ttn/build/GNU/T/merchant/src/mustach'
make[2]: Leaving directory '/home/ttn/build/GNU/T/merchant/src/mustach'
Making check in backenddb
make[2]: Entering directory '/home/ttn/build/GNU/T/merchant/src/backenddb'
make  test-merchantdb-postgres
make[3]: Entering directory '/home/ttn/build/GNU/T/merchant/src/backenddb'
  CC       test_merchantdb.o
  CCLD     test-merchantdb-postgres
make[3]: Leaving directory '/home/ttn/build/GNU/T/merchant/src/backenddb'
make  check-TESTS
make[3]: Entering directory '/home/ttn/build/GNU/T/merchant/src/backenddb'
make[4]: Entering directory '/home/ttn/build/GNU/T/merchant/src/backenddb'
FAIL: test-merchantdb-postgres
============================================================================
Testsuite summary for taler-merchant 0.8.0
============================================================================
# TOTAL: 1
# PASS:  0
# SKIP:  0
# XFAIL: 0
# FAIL:  1
# XPASS: 0
# ERROR: 0
============================================================================
See src/backenddb/test-suite.log
Please report to taler-bug@gnunet.org
============================================================================
make[4]: *** [Makefile:956: test-suite.log] Error 1
make[4]: Leaving directory '/home/ttn/build/GNU/T/merchant/src/backenddb'
make[3]: *** [Makefile:1064: check-TESTS] Error 2
make[3]: Leaving directory '/home/ttn/build/GNU/T/merchant/src/backenddb'
make[2]: *** [Makefile:1138: check-am] Error 2
make[2]: Leaving directory '/home/ttn/build/GNU/T/merchant/src/backenddb'
make[1]: *** [Makefile:411: check-recursive] Error 1
make[1]: Leaving directory '/home/ttn/build/GNU/T/merchant/src'
make: *** [Makefile:472: check-recursive] Error 1

Compilation exited abnormally with code 2 at Mon Jul 19 02:09:26
.ttn.merchant-make-check.log (4,497 bytes)   
test-suite.log (3,971 bytes)   
========================================================
   taler-merchant 0.8.0: src/backenddb/test-suite.log
========================================================

# TOTAL: 1
# PASS:  0
# SKIP:  0
# XFAIL: 0
# FAIL:  1
# XPASS: 0
# ERROR: 0

.. contents:: :depth: 2

FAIL: test-merchantdb-postgres
==============================

Jul 19 02:09:22-716614 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-2040393 INFO Loading SQL resources from `/home/ttn/t/share/taler//sql/merchant/drop'
Jul 19 02:09:22-717464 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-2040393 INFO Applying SQL file `/home/ttn/t/share/taler//sql/merchant/drop0001.sql' on database postgres:///talercheck
Jul 19 02:09:22-790744 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-2040393 WARNING Could not run PSQL on file /home/ttn/t/share/taler//sql/merchant/drop0001.sql: 3
Jul 19 02:09:22-790804 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-2040393 WARNING Failed to load SQL statements from `/home/ttn/t/share/taler//sql/merchant/drop*'
Jul 19 02:09:22-794182 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-2040393 INFO Loading SQL resources from `/home/ttn/t/share/taler//sql/merchant/merchant-'
Jul 19 02:09:22-794775 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-2040393 INFO Applying SQL file `/home/ttn/t/share/taler//sql/merchant/merchant-0001.sql' on database postgres:///talercheck
Jul 19 02:09:26-006310 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-2040393 INFO Applying SQL file `/home/ttn/t/share/taler//sql/merchant/merchant-0002.sql' on database postgres:///talercheck
Jul 19 02:09:26-530016 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-2040393 INFO Storing wire fee for 7SE23DRB starting at Mon Jul 19 02:09:26 2021 of EUR:0.49
Jul 19 02:09:26-539244 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-2040393 INFO Storing wire fee for 7SE23DRB starting at Mon Jul 19 02:09:26 2021 of EUR:0.49
Jul 19 02:09:26-539652 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-2040393 INFO Storing wire fee for 7SE23DRB starting at Mon Jul 19 02:09:26 2021 of EUR:0.49
Jul 19 02:09:26-632975 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-2040393 INFO Starting merchant DB transaction insert transfer details (READ COMMITTED)
Jul 19 02:09:26-636476 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-2040393 INFO Committing merchant DB transaction insert transfer details
Jul 19 02:09:26-647310 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-2040393 WARNING Lookup transfer returned 1
Jul 19 02:09:26-647341 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-2040393 ERROR Lookup transfer failed: mismatched data
Jul 19 02:09:26-647360 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-2040393 ERROR Assertion failed at test_merchantdb.c:4302.
Jul 19 02:09:26-647375 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-2040393 ERROR Assertion failed at test_merchantdb.c:6653.
Jul 19 02:09:26-650965 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-2040393 INFO Loading SQL resources from `/home/ttn/t/share/taler//sql/merchant/drop'
Jul 19 02:09:26-651454 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-2040393 INFO Applying SQL file `/home/ttn/t/share/taler//sql/merchant/drop0001.sql' on database postgres:///talercheck
Jul 19 02:09:26-779684 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-2040393 INFO Applying SQL file `/home/ttn/t/share/taler//sql/merchant/drop0002.sql' on database postgres:///talercheck
FAIL test-merchantdb-postgres (exit status: 1)

test-suite.log (3,971 bytes)   

Activities

Christian Grothoff

2021-07-19 14:34

manager   ~0017998

I cannot reproduce this. Which version is this? Are both exchange and merchant on Git master?

ttn

2021-07-20 15:35

developer   ~0018001

Git (merchant) HEAD is now 0c16f9e8b34fef082d33ffff6fcdc9fcc6b27ed7.
GIt HEAD (exchange) is 195a6b6b3d706927617dbd08aec67caf3eb56c07 (as before).
These are latest, as far as i can tell.

I re-ran the build (bootstrap, configure, make, make install, make check) and was
able to reproduce the failure. I notice in the log (attached) that there are several
"ERROR" messages and a couple assertion failures. Could they be all related?
test-suite-2.log (4,452 bytes)   
========================================================
   taler-merchant 0.8.0: src/backenddb/test-suite.log
========================================================

# TOTAL: 1
# PASS:  0
# SKIP:  0
# XFAIL: 0
# FAIL:  1
# XPASS: 0
# ERROR: 0

.. contents:: :depth: 2

FAIL: test-merchantdb-postgres
==============================

Jul 20 15:24:42-945591 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-3201828 INFO Failed to prepare statement to check patch level. Likely versioning schema does not exist yet, loading patch level 0000!
Jul 20 15:24:42-945694 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-3201828 INFO Applying SQL file `/home/ttn/t/share/taler//sql/merchant/drop0000.sql' on database postgres:///talercheck
Jul 20 15:24:43-012341 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-3201828 WARNING Could not run PSQL on file /home/ttn/t/share/taler//sql/merchant/drop0000.sql: 1
Jul 20 15:24:43-012409 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-3201828 ERROR Failed to run SQL logic to setup database versioning logic
Jul 20 15:24:43-015469 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-3201828 INFO Failed to prepare statement to check patch level. Likely versioning schema does not exist yet, loading patch level 0000!
Jul 20 15:24:43-015498 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-3201828 INFO Applying SQL file `/home/ttn/t/share/taler//sql/merchant/merchant-0000.sql' on database postgres:///talercheck
Jul 20 15:24:43-260855 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-3201828 INFO Loading SQL resources from `/home/ttn/t/share/taler//sql/merchant/merchant-'
Jul 20 15:24:43-261930 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-3201828 INFO Applying SQL file `/home/ttn/t/share/taler//sql/merchant/merchant-0001.sql' on database postgres:///talercheck
Jul 20 15:24:46-695463 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-3201828 INFO Applying SQL file `/home/ttn/t/share/taler//sql/merchant/merchant-0002.sql' on database postgres:///talercheck
Jul 20 15:24:47-373535 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-3201828 INFO Storing wire fee for T0JAPZ17 starting at Tue Jul 20 15:24:47 2021 of EUR:0.49
Jul 20 15:24:47-383901 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-3201828 INFO Storing wire fee for T0JAPZ17 starting at Tue Jul 20 15:24:47 2021 of EUR:0.49
Jul 20 15:24:47-384444 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-3201828 INFO Storing wire fee for T0JAPZ17 starting at Tue Jul 20 15:24:47 2021 of EUR:0.49
Jul 20 15:24:47-469501 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-3201828 INFO Starting merchant DB transaction insert transfer details (READ COMMITTED)
Jul 20 15:24:47-472354 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-3201828 INFO Committing merchant DB transaction insert transfer details
Jul 20 15:24:47-477688 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-3201828 WARNING Lookup transfer returned 1
Jul 20 15:24:47-477713 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-3201828 ERROR Lookup transfer failed: mismatched data
Jul 20 15:24:47-477729 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-3201828 ERROR Assertion failed at test_merchantdb.c:4302.
Jul 20 15:24:47-477740 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-3201828 ERROR Assertion failed at test_merchantdb.c:6653.
Jul 20 15:24:47-480741 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-3201828 INFO Loading SQL resources from `/home/ttn/t/share/taler//sql/merchant/drop'
Jul 20 15:24:47-481339 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-3201828 INFO Applying SQL file `/home/ttn/t/share/taler//sql/merchant/drop0001.sql' on database postgres:///talercheck
Jul 20 15:24:47-608469 /home/ttn/build/GNU/T/merchant/src/backenddb/.libs/test-merchantdb-postgres-3201828 INFO Applying SQL file `/home/ttn/t/share/taler//sql/merchant/drop0002.sql' on database postgres:///talercheck
FAIL test-merchantdb-postgres (exit status: 1)

test-suite-2.log (4,452 bytes)   

ttn

2021-07-20 15:40

developer   ~0018002

I forgot to mention that for the merchant "make check", i also did
"DROP DATABASE talercheck" and "CREATE DATABASE talercheck",
both successfully.

ttn

2021-07-21 20:43

developer   ~0018003

FWIW, still fails in same place w/ Git HEAD at 39695b7f95bfe33bd2a9f63f2527d83427196c9a.

Christian Grothoff

2021-07-29 23:07

manager   ~0018028

It is a bit strange. During the hackathon last week, we asked all devs present to try to reproduce this, and it worked for all of them :-(.

ttn

2021-07-31 02:28

developer   ~0018034

This is apparently fixed in latest Git master (fb41ef844529f6cde9b42e12eebe36747cf02654).
Marking as resolved.

ttn

2021-07-31 02:29

developer   ~0018035

(I think there was some related changes in GNUNET and Exchange, as well.)

Issue History

Date Modified Username Field Change
2021-07-19 02:18 ttn New Issue
2021-07-19 02:18 ttn Status new => assigned
2021-07-19 02:18 ttn Assigned To => Christian Grothoff
2021-07-19 02:18 ttn File Added: .ttn.merchant-make-check.log
2021-07-19 02:18 ttn File Added: test-suite.log
2021-07-19 14:34 Christian Grothoff Status assigned => feedback
2021-07-19 14:34 Christian Grothoff Note Added: 0017998
2021-07-20 15:35 ttn Note Added: 0018001
2021-07-20 15:35 ttn File Added: test-suite-2.log
2021-07-20 15:35 ttn Status feedback => assigned
2021-07-20 15:40 ttn Note Added: 0018002
2021-07-21 20:43 ttn Note Added: 0018003
2021-07-29 23:07 Christian Grothoff Note Added: 0018028
2021-07-29 23:07 Christian Grothoff Status assigned => feedback
2021-07-31 02:28 ttn Note Added: 0018034
2021-07-31 02:28 ttn Status feedback => assigned
2021-07-31 02:29 ttn Status assigned => resolved
2021-07-31 02:29 ttn Resolution open => fixed
2021-07-31 02:29 ttn Note Added: 0018035
2021-08-24 16:22 Christian Grothoff Status resolved => closed