sql/backends/monet5/UDF/udf standard output

# MonetDB 5 server v11.54.0
# This is an unreleased version
# Serving database 'mTests_sql_backends_monet5_UDF_udf', using 8 threads
# Compiled for x86_64-pc-linux-gnu/64bit with 128bit integers
# Found 31.303 GiB available main-memory of which we use 25.512 GiB
# Virtual memory usage limited to 8.000 GiB
# Copyright (c) 2024, 2025 MonetDB Foundation, all rights reserved
# Visit https://www.monetdb.org/ for further information
# MonetDB/GIS module loaded
# MonetDB/SQL module loaded
# Listening for connection requests on mapi:monetdb://lab06.da.cwi.nl:34873/
# Listening for UNIX domain connection requests on mapi:monetdb:///tmp/mtest-1018254/.s.monetdb.34873
#remapped: batcalc.+
#remapped: batcalc.==
#remapped: batcalc.+
#remapped: batcalc.not
#remapped: batcalc.>
#remapped: batcalc.and
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: select s.name, t.name, case when t.type in (select table_type_id from sys.table_types where table_type_name like '%VIEW%') then 'VIEW' else 'TABLE' end from sys.tables t, sys.schemas s where not t.system and t.schema_id = s.id
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: select s.name, f.name, ft.function_type_keyword from functions f, schemas s, function_types ft where not f.system and f.schema_id = s.id and f.type = ft.function_type_id
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: select s.name, q.name from sys.sequences q, schemas s where q.schema_id = s.id
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: select name from sys.users where name not in ('monetdb', '.snapshot')
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: select name from sys.schemas where not system
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: select name from sys.users where name not in ('monetdb', '.snapshot')
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: select sqlname from sys.types where systemname is null order by id
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: call sys.setsessiontimeout(60)
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: select * from sys.types where sqlname = 'hugeint'
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: set optimizer = 'sequential_pipe'
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: create table udf_reverse ( x string )
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: insert into udf_reverse values ('MonetDB')
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: insert into udf_reverse values ('Database Architecture')
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: insert into udf_reverse values ('Information Systems')
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: insert into udf_reverse values ('Centrum Wiskunde & Informatica')
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: select * from udf_reverse
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: create procedure profiler.starttrace() external name profiler."starttrace"
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: create procedure profiler.stoptrace() external name profiler.stoptrace
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: call profiler."starttrace"()
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: select reverse('MonetDB')
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: call profiler.stoptrace()
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: select count(*) from sys.tracelog() where stmt like '% batudf.reverse%'
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: select count(*) from sys.tracelog() where stmt like '% udf.reverse%'
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: select reverse('MonetDB')
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: call profiler."starttrace"()
#remapped: batudf.reverse
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: select reverse(x) from udf_reverse
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: call profiler.stoptrace()
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: select count(*) from sys.tracelog() where stmt like '% batudf.reverse%'
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: select count(*) from sys.tracelog() where stmt like '% udf.reverse%'
#remapped: batudf.reverse
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: select reverse(x) from udf_reverse
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: drop procedure profiler.starttrace()
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: drop procedure profiler.stoptrace()
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: drop table udf_reverse
#2025-04-05 00:37:41: client0019: SQLrun: INFO: Executing: set optimizer = 'default_pipe'
#remapped: batcalc.+
#remapped: batcalc.==
#remapped: batcalc.+
#remapped: batcalc.not
#remapped: batcalc.>
#remapped: batcalc.and
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select s.name, t.name, case when t.type in (select table_type_id from sys.table_types where table_type_name like '%VIEW%') then 'VIEW' else 'TABLE' end from sys.tables t, sys.schemas s where not t.system and t.schema_id = s.id
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select s.name, f.name, ft.function_type_keyword from functions f, schemas s, function_types ft where not f.system and f.schema_id = s.id and f.type = ft.function_type_id
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select s.name, q.name from sys.sequences q, schemas s where q.schema_id = s.id
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select name from sys.users where name not in ('monetdb', '.snapshot')
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select name from sys.schemas where not system
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select name from sys.users where name not in ('monetdb', '.snapshot')
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select sqlname from sys.types where systemname is null order by id
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: call sys.setsessiontimeout(60)
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select * from sys.types where sqlname = 'hugeint'
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: set optimizer = 'sequential_pipe'
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: create table udf_fuse ( a tinyint , b tinyint , c smallint , d smallint , e integer , f integer )
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: insert into udf_fuse values (1,2,1000,2000,1000000,2000000)
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: insert into udf_fuse values (3,4,3000,4000,3000000,4000000)
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: insert into udf_fuse values (5,6,5000,6000,5000000,6000000)
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: insert into udf_fuse values (7,8,7000,8000,7000000,8000000)
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select * from udf_fuse
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: create procedure profiler.starttrace() external name profiler."starttrace"
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: create procedure profiler.stoptrace() external name profiler.stoptrace
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: call profiler."starttrace"()
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select fuse(1,2)
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: call profiler.stoptrace()
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select count(*) from sys.tracelog() where stmt like '% batudf.fuse%'
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select count(*) from sys.tracelog() where stmt like '% udf.fuse%'
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select fuse(1,2)
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: call profiler."starttrace"()
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select fuse(1000,2000)
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: call profiler.stoptrace()
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select count(*) from sys.tracelog() where stmt like '% batudf.fuse%'
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select count(*) from sys.tracelog() where stmt like '% udf.fuse%'
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select fuse(1000,2000)
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: call profiler."starttrace"()
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select fuse(1000000,2000000)
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: call profiler.stoptrace()
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select count(*) from sys.tracelog() where stmt like '% batudf.fuse%'
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select count(*) from sys.tracelog() where stmt like '% udf.fuse%'
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select fuse(1000000,2000000)
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: call profiler."starttrace"()
#remapped: batudf.fuse
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select fuse(a,b) from udf_fuse
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: call profiler.stoptrace()
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select count(*) from sys.tracelog() where stmt like '% batudf.fuse%'
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select count(*) from sys.tracelog() where stmt like '% udf.fuse%'
#remapped: batudf.fuse
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select fuse(a,b) from udf_fuse
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: call profiler."starttrace"()
#remapped: batudf.fuse
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select fuse(c,d) from udf_fuse
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: call profiler.stoptrace()
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select count(*) from sys.tracelog() where stmt like '% batudf.fuse%'
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select count(*) from sys.tracelog() where stmt like '% udf.fuse%'
#remapped: batudf.fuse
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select fuse(c,d) from udf_fuse
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: call profiler."starttrace"()
#remapped: batudf.fuse
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select fuse(e,f) from udf_fuse
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: call profiler.stoptrace()
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select count(*) from sys.tracelog() where stmt like '% batudf.fuse%'
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select count(*) from sys.tracelog() where stmt like '% udf.fuse%'
#remapped: batudf.fuse
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: select fuse(e,f) from udf_fuse
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: drop procedure profiler.starttrace()
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: drop procedure profiler.stoptrace()
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: drop table udf_fuse
#2025-04-05 00:37:41: client0020: SQLrun: INFO: Executing: set optimizer = 'default_pipe'
#SIGUSR1 info start
#Virtual memory allocated: 11593544, of which 8709960 with malloc
#gdk_vm_maxsize: 8589934592, gdk_mem_maxsize: 27393153454
#gdk_mmap_minsize_persistent 262144, gdk_mmap_minsize_transient 1712072090
#Virtual size: 2313670656, anonymous RSS: 119562240, shared RSS: 43384832 (together: 162947072)
#BATs:
#fix, dirty, persistent, loaded, hot: 1 bats, 0 virtual, 0 malloc
#fix, dirty, transient, loaded, hot: 206 bats, 26416 virtual, 26416 malloc
#fix, clean, persistent, loaded, hot: 5 bats, 7168 virtual, 7168 malloc
#no fix, dirty, persistent, loaded, hot: 117 bats, 318464 virtual, 318464 malloc
#no fix, dirty, transient, loaded, hot: 49 bats, 0 virtual, 0 malloc
#no fix, clean, persistent, loaded, hot: 135 bats, 4239903 virtual, 1356319 malloc
#640 bats total, 513 in use, 65 free bats in common shared list
#Threads:
#client0020, tid 20, Thread 0x7f5694f4a6c0, LWP 1018968, 0 free bats, waiting for nothing, working on exiting
#heartbeat, tid 16, Thread 0x7f569514b6c0, LWP 1018962, 0 free bats, waiting for nothing, working on sleeping
#listenThread, tid 15, Thread 0x7f568c2c16c0, LWP 1018961, 0 free bats, waiting for nothing, working on nothing
#logmanager, tid 14, Thread 0x7f566db746c0, LWP 1018959, 0 free bats, waiting for nothing, working on sleeping
#DFLOWworker0011, tid 11, Thread 0x7f569534c6c0, LWP 1018956, 16 free bats, waiting for semaphore todo, working on waiting for work
#DFLOWworker0010, tid 10, Thread 0x7f569554d6c0, LWP 1018955, 16 free bats, waiting for semaphore todo, working on waiting for work
#DFLOWworker0009, tid 9, Thread 0x7f569574e6c0, LWP 1018954, 2 free bats, waiting for semaphore todo, working on waiting for work
#DFLOWworker0008, tid 8, Thread 0x7f569594f6c0, LWP 1018953, 2 free bats, waiting for semaphore DFLOWsema0008, working on idle, waiting for new client
#DFLOWworker0007, tid 7, Thread 0x7f5695b506c0, LWP 1018952, 2 free bats, waiting for semaphore todo, working on waiting for work
#DFLOWworker0006, tid 6, Thread 0x7f5695d516c0, LWP 1018951, 4 free bats, waiting for semaphore todo, working on waiting for work
#DFLOWworker0005, tid 5, Thread 0x7f568ffff6c0, LWP 1018950, 8 free bats, waiting for semaphore todo, working on waiting for work
#DFLOWworker0004, tid 4, Thread 0x7f5695f526c0, LWP 1018949, 3 free bats, waiting for semaphore DFLOWsema0004, working on idle, waiting for new client
#DFLOWworker0003, tid 3, Thread 0x7f56961536c0, LWP 1018948, 9 free bats, waiting for semaphore todo, working on waiting for work
#BBPmanager, tid 2, Thread 0x7f56dfbff6c0, LWP 1018947, 0 free bats, waiting for nothing, working on sleeping
#main-thread, tid 1, Thread 0x7f56e1926740, LWP 1018946, 0 free bats, waiting for nothing, working on nothing
#Clients:
#0 active clients, 0 finishing clients, 0 blocked clients; max: 64
#WAL:
#SQL store oldest pending 199
#logger sql:
#current log file 7, last handled log file 6
#current transaction id 19, saved transaction id 10
#number of flushers: 0
#number of catalog entries 252, of which 0 deleted
#pending range 7: drops 28, last_ts 197, flushed_ts 197, refcount 1, file size 2173 (current)
#SIGUSR1 info end
# mserver5 exiting