Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.02.2023 22:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15155428 kB
MemAvailable 30439372 kB
Buffers 0 kB
Cached 16566868 kB
SwapCached 3356 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-02-11 22:38:38
DTS_END                         2023-02-11 22:38:38.8670
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53702
TRN_ID                          246387
ATT_ID                          638
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9381.52 20675 1125782
customer order: creation 178.62 332 21434
customer order: refuse 15.65 348 1878
order to supplier: creation 47.12 1224 5654
order to supplier: removal 3.49 83971 419
invoice (draft): creation 48.47 3296 5816
invoice (draft): removal 52.21 734 6265
invoice accept: apply 43.81 16494 5257
invoice accept: cancel 47.02 718 5642
customer reserve: creation 4052.55 163 486306
customer reserve: removal 4591.38 49 550966
realization accept: apply 86.35 174 10362
realization accept: cancel 81.38 99 9766
payment from customer: creation 61.22 92 7346
payment from customer: removal 5.96 74 715
payment to supplier: creation 50.52 103 6062
payment to supplier: removal 3.58 59 429
service: total inventory turnovers 4.59 1353 551
service: total monetary turnovers 6.69 31 803
service: aggregate perf. data 0.89 18370 107
service: refresh index statistics 0.03 285819 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 11-02-2023 22:40:15 to 11-02-2023 22:40:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4296
TEST_TIME 2 4018
TEST_TIME 3 4524
TEST_TIME 4 4651
TEST_TIME 5 4561
TEST_TIME 6 5239
TEST_TIME 7 5775
TEST_TIME 8 6394
TEST_TIME 9 6319
TEST_TIME 10 6635
TEST_TIME 11 7221
TEST_TIME 12 7317
TEST_TIME 13 7123
TEST_TIME 14 7218
TEST_TIME 15 7516
TEST_TIME 16 8085
TEST_TIME 17 8187
TEST_TIME 18 8681
TEST_TIME 19 8755
TEST_TIME 20 8744
TEST_TIME 21 8944
TEST_TIME 22 9053
TEST_TIME 23 8947
TEST_TIME 24 8873
TEST_TIME 25 8924
TEST_TIME 26 9241
TEST_TIME 27 9451
TEST_TIME 28 9535
TEST_TIME 29 9881
TEST_TIME 30 9910
TEST_TIME 31 10141
TEST_TIME 32 9942
TEST_TIME 33 9844
TEST_TIME 34 9847
TEST_TIME 35 9996
TEST_TIME 36 9960
TEST_TIME 37 10010
TEST_TIME 38 9890
TEST_TIME 39 9967
TEST_TIME 40 9885
TEST_TIME 41 9921
TEST_TIME 42 10104
TEST_TIME 43 10064
TEST_TIME 44 10092
TEST_TIME 45 10103
TEST_TIME 46 10069
TEST_TIME 47 9875
TEST_TIME 48 9847
TEST_TIME 49 9847
TEST_TIME 50 9813
TEST_TIME 51 9721
TEST_TIME 52 9659
TEST_TIME 53 9683
TEST_TIME 54 9784
TEST_TIME 55 9732
TEST_TIME 56 9634
TEST_TIME 57 9614
TEST_TIME 58 9647
TEST_TIME 59 9592
TEST_TIME 60 9574
TEST_TIME 61 9608
TEST_TIME 62 9537
TEST_TIME 63 9525
TEST_TIME 64 9577
TEST_TIME 65 9579
TEST_TIME 66 9559
TEST_TIME 67 9585
TEST_TIME 68 9546
TEST_TIME 69 9573
TEST_TIME 70 9637
TEST_TIME 71 9619
TEST_TIME 72 9628
TEST_TIME 73 9592
TEST_TIME 74 9691
TEST_TIME 75 9646
TEST_TIME 76 9633
TEST_TIME 77 9839
TEST_TIME 78 9898
TEST_TIME 79 9867
TEST_TIME 80 9761
TEST_TIME 81 9688
TEST_TIME 82 9642
TEST_TIME 83 9639
TEST_TIME 84 9639
TEST_TIME 85 9570
TEST_TIME 86 9559
TEST_TIME 87 9555
TEST_TIME 88 9510
TEST_TIME 89 9437
TEST_TIME 90 9421
TEST_TIME 91 9459
TEST_TIME 92 9460
TEST_TIME 93 9486
TEST_TIME 94 9504
TEST_TIME 95 9477
TEST_TIME 96 9524
TEST_TIME 97 9516
TEST_TIME 98 9530
TEST_TIME 99 9527
TEST_TIME 100 9537
TEST_TIME 101 9610
TEST_TIME 102 9605
TEST_TIME 103 9595
TEST_TIME 104 9598
TEST_TIME 105 9665
TEST_TIME 106 9579
TEST_TIME 107 9582
TEST_TIME 108 9560
TEST_TIME 109 9560
TEST_TIME 110 9556
TEST_TIME 111 9527
TEST_TIME 112 9492
TEST_TIME 113 9448
TEST_TIME 114 9430
TEST_TIME 115 9398
TEST_TIME 116 9404
TEST_TIME 117 9424
TEST_TIME 118 9378
TEST_TIME 119 9361
TEST_TIME 120 9383

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-02-2023 22:40:19 to 11-02-2023 22:40:22.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1576408 1576408 0 0.00 0 22768 6 0 0
doc_list_biud 1576400 1576400 0 0.00 0 31153 23 0 0
sp_add_invoice_to_stock 5352 5257 95 1.77 19 179406 16494 0 95
sp_cancel_adding_invoice 5642 5642 0 0.00 4 54919 719 0 0
sp_cancel_client_order 1878 1878 0 0.00 5 12966 349 0 0
sp_cancel_customer_reserve 550966 550966 0 0.00 0 20027 50 0 0
sp_cancel_pay_from_customer 715 715 0 0.00 3 3127 75 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 2 4650 59 0 0
sp_cancel_supplier_invoice 6271 6265 6 0.09 1 18777 734 0 6
sp_cancel_supplier_order 419 419 0 0.00 7 863523 83972 0 0
sp_cancel_write_off 9766 9766 0 0.00 1 25122 99 0 0
sp_client_order 21434 21434 0 0.00 3 27349 333 0 0
sp_customer_reserve 486306 486306 0 0.00 0 109304 164 0 0
sp_fill_shopping_cart 32873 32873 0 0.00 1 11002 40 0 0
sp_fill_shopping_cart_clo_res 486296 486296 0 0.00 0 15237 12 0 0
sp_get_clo_for_invoice 5247 5247 0 0.00 0 24154 270 0 0
sp_kill_cost_storno 16552 16552 0 0.00 0 4827 47 0 0
sp_kill_qstorno_ret_qs2qd 1118382 1118382 0 0.00 0 25110 27 0 0
sp_kill_qty_storno 590545 590545 0 0.00 0 31152 60 0 0
sp_lock_selected_doc 21615 21615 0 0.00 0 8831 3 0 0
sp_make_cost_storno 29017 29017 0 0.00 0 10979 44 0 0
sp_multiply_rows_for_pdistr 29017 29017 0 0.00 0 6829 26 0 0
sp_multiply_rows_for_qdistr 21431 21431 0 0.00 0 19713 137 0 0
sp_pay_from_customer 7346 7346 0 0.00 4 10456 93 0 0
sp_pay_to_supplier 6062 6062 0 0.00 4 9891 104 0 0
sp_payment_common 13408 13408 0 0.00 1 7947 21 0 0
sp_qd_handle_on_cancel_clo 1878 1878 0 0.00 0 12890 322 0 0
sp_qd_handle_on_invoice_upd_sts 10889 10889 0 0.00 0 31151 308 0 0
sp_qd_handle_on_reserve_upd_sts 10362 10362 0 0.00 0 25131 74 0 0
sp_reserve_write_off 10362 10362 0 0.00 6 25191 174 0 0
sp_supplier_invoice 5816 5816 0 0.00 6 91056 3296 0 0
sp_supplier_order 5654 5654 0 0.00 6 26869 1224 0 0
srv_aggregate_perf_data 107 107 0 0.00 3113 147558 18371 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1498 127209 45188 0 0
srv_find_qd_qs_mism 1076221 1076221 0 0.00 0 22767 5 0 0
srv_make_invnt_saldo 551 551 0 0.00 24 51188 1353 0 0
srv_make_money_saldo 803 803 0 0.00 1 2481 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 133092 356917 285819 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5257 5257 0 0.00 0 14812 182 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 0 3149 87 0 0
t$perf-norm:sp_cancel_client_order 1878 1878 0 0.00 0 4580 14 0 0
t$perf-norm:sp_cancel_customer_reserve 725 725 0 0.00 0 3158 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 715 715 0 0.00 0 1152 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 1475 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 439 439 0 0.00 0 5343 31 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 37275 1770 0 0
t$perf-norm:sp_cancel_write_off 975 975 0 0.00 0 1919 6 0 0
t$perf-norm:sp_client_order 21434 21434 0 0.00 0 6347 18 0 0
t$perf-norm:sp_customer_reserve 1090 1090 0 0.00 0 3000 7 0 0
t$perf-norm:sp_pay_from_customer 7346 7346 0 0.00 0 10884 16 0 0
t$perf-norm:sp_pay_to_supplier 6062 6062 0 0.00 0 6671 14 0 0
t$perf-norm:sp_reserve_write_off 10362 10362 0 0.00 0 9341 15 0 0
t$perf-norm:sp_supplier_invoice 5816 5816 0 0.00 0 3477 15 0 0
t$perf-norm:sp_supplier_order 5654 5654 0 0.00 0 6349 14 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 2147 39 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 99 2 0 0
t$perf-norm:srv_make_invnt_saldo 551 551 0 0.00 0 5895 37 0 0
t$perf-norm:srv_make_money_saldo 803 803 0 0.00 0 907 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 12 4 0 0
v_add_invoice_to_stock 10682 10492 190 1.77 0 8371 19 0 190
v_all_customers 38660 38660 0 0.00 0 22 1 0 0
v_all_suppliers 31234 31234 0 0.00 0 31 1 0 0
v_all_wares 42844 42844 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 1606 8 0 0
v_cancel_client_order 3756 3756 0 0.00 0 6625 6 0 0
v_cancel_customer_prepayment 1430 1430 0 0.00 0 3115 8 0 0
v_cancel_customer_reserve 1450 1450 0 0.00 0 2206 6 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 378 4 0 0
v_cancel_supplier_invoice 890 878 12 1.34 0 6711 27 0 12
v_cancel_supplier_order 838 838 0 0.00 0 608 4 0 0
v_cancel_write_off 1950 1950 0 0.00 0 4651 21 0 0
v_max_id_clo_ord 5650 5650 0 0.00 0 722 2 0 0
v_max_id_clo_res 1090 1090 0 0.00 0 1674 13 0 0
v_max_id_ord_sup 5801 5801 0 0.00 0 3742 5 0 0
v_max_non_paid_invoice 1896 1896 0 0.00 0 508 3 0 0
v_max_non_paid_realizn 3981 3981 0 0.00 0 5139 5 0 0
v_min_id_clo_ord 5650 5650 0 0.00 0 3164 6 0 0
v_min_id_clo_res 1090 1090 0 0.00 0 4279 12 0 0
v_min_id_ord_sup 5801 5801 0 0.00 0 6500 7 0 0
v_min_non_paid_invoice 6062 6062 0 0.00 0 7945 16 0 0
v_min_non_paid_realizn 7346 7346 0 0.00 0 7818 14 0 0
v_our_firm 10914 10914 0 0.00 0 200 1 0 0
v_reserve_write_off 20724 20724 0 0.00 0 8103 7 0 0
x_lock_depdocs_on_canc_invoice 11468 11468 0 0.00 0 22443 115 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 3701 288 0 0
x_make_qstorno_1000_1200 5654 5654 0 0.00 1 26853 1151 0 0
x_make_qstorno_1200_2000 5816 5816 0 0.00 2 91049 3142 0 0
x_make_qstorno_2100_3300 486306 486306 0 0.00 0 109257 149 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 11-02-2023 22:40:23 to 11-02-2023 22:40:28.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:42 15 8192000000 8815928416 8832991232 19514688 0.237 5 5 6 0 19784256 98112 171456
20:09:44 24017 8192000000 9910155168 10222206976 988473920 10.767 100 82 164 0 1016043552 6775424 20794208
20:11:11 21038 8192000000 9946300160 10268581888 1031426928 11.182 100 92 184 0 1063386464 8949952 23009584
20:12:39 11840 8192000000 9974325392 10302926848 1056125056 11.419 100 84 168 0 1087012752 9858464 21029232
20:14:02 10576 8192000000 10043079200 10390859776 1125215888 12.076 100 87 172 0 1155809776 10499744 20094144
20:15:20 5895 8192000000 10093035600 10452504576 1177325728 12.565 100 79 158 2 1208356000 13035696 17994576
20:16:30 4498 8192000000 10124681312 10497798144 1217616208 12.940 100 70 136 1 1243594272 11326928 14651136
20:17:38 2252 8192000000 10165232624 10542211072 1249469520 13.233 100 90 175 0 1282780848 13712560 19598768
20:18:43 1641 8192000000 10190780480 10573549568 1277443504 13.490 100 76 150 0 1306037984 12117504 16476976
20:19:45 23501 8192000000 10197814864 10596368384 1295782000 13.657 100 51 100 0 1320501104 14272848 10446256
20:21:10 4506 8192000000 10200512528 10607968256 1307873952 13.767 100 43 87 0 1325904224 8837392 9192880
20:22:17 10114 8192000000 10199976496 10619047936 1321273648 13.888 100 18 36 0 1330755920 5381680 4100592
20:23:28 16120 8192000000 10212986272 10630815744 1325529808 13.927 100 26 52 0 1336926160 5014880 6381472
20:25:03 6634 8192000000 10217564768 10641457152 1329694272 13.964 100 18 34 0 1338981488 5630160 3657056
20:26:11 4378 8192000000 10222648368 10643214336 1332895552 13.993 100 31 62 1 1345145952 5454224 6796176
20:27:19 11603 8192000000 10227473648 10648276992 1335343008 14.015 100 18 36 0 1345242080 5746496 4152576
20:28:32 65034 8192000000 10208311104 10621100032 1259243344 13.323 94 19 37 1 1267876480 4602272 4030864
20:30:38 5775 8192000000 10174003584 10587803648 1268450160 13.407 98 34 68 1 1283072016 6694576 7927280
20:31:47 8697 8192000000 10137349440 10532003840 1220096208 12.963 98 26 52 0 1232015696 5720960 6198528
20:32:58 10327 8192000000 10148716896 10548690944 1254188656 13.277 100 28 55 2 1267029872 5409360 7431856
20:34:09 27237 8192000000 10145352240 10553851904 1254299456 13.278 99 41 82 2 1270807872 5802816 10705600
20:35:37 4946 8192000000 10188252816 10596581376 1291951488 13.622 100 24 48 1 1304247840 6058464 6237888
20:36:43 6133 8192000000 10194377136 10607046656 1300042080 13.696 100 35 70 1 1314709088 6334864 8332144
20:37:52 9862 8192000000 10196901312 10603225088 1293239072 13.634 99 14 26 2 1300500688 4578240 2683376
20:39:09 16643 8192000000 10220544976 10636017664 1322896288 13.903 100 36 72 0 1336793696 5809376 8088032
20:40:31 13272 8192000000 10252305328 10664681472 1345396464 14.106 100 49 98 0 1366286384 10064272 10825648
20:41:52 16690 8192000000 10270088432 10687459328 1363288944 14.267 100 48 96 0 1383505856 9198608 11018304
20:43:12 16223 8192000000 10282403584 10698100736 1361480400 14.251 99 47 93 1 1384656688 11878528 11297760
20:45:10 14069 8192000000 10293946928 10719629312 1382472816 14.439 100 44 88 0 1408364448 15501328 10390304
20:46:28 9497 8192000000 10312006160 10731290624 1383292752 14.446 99 59 117 0 1414291888 16254544 14744592
20:47:40 10837 8192000000 10300604048 10719809536 1377526400 14.394 100 48 95 1 1405134864 15593600 12014864
20:49:00 3322 8192000000 10321490416 10747842560 1398040592 14.578 100 49 98 0 1431417280 20957760 12418928
20:50:11 7092 8192000000 10305801648 10732646400 1388080816 14.489 100 41 80 0 1413796448 15956272 9759360
20:51:30 8873 8192000000 10312443744 10732748800 1376541216 14.386 99 52 104 0 1409082992 20098928 12442848
20:52:45 3433 8192000000 10315807728 10720079872 1373629200 14.360 100 81 159 2 1416135376 23286672 19219504
20:53:51 1496 8192000000 10297393296 10698645504 1352613024 14.171 99 83 166 3 1399025456 26520720 19891712
20:55:01 6053 8192000000 10292480544 10707070976 1361948320 14.255 100 53 106 0 1398950176 23820880 13180976
20:56:13 3255 8192000000 10294978096 10704326656 1355688544 14.199 100 67 134 1 1394880240 23017232 16174464
20:57:23 5594 8192000000 10303216512 10717700096 1367650672 14.306 100 70 134 1 1405599792 21716752 16232368
20:58:33 5652 8192000000 10299190064 10717437952 1365586848 14.287 100 54 105 1 1406883792 28311040 12985904
20:59:42 11662 8192000000 10297774208 10717671424 1336664864 14.027 97 36 72 0 1372888288 27130448 9092976
21:00:56 7178 8192000000 10313978640 10733387776 1378714800 14.405 100 36 72 0 1413479936 25681136 9084000
21:02:06 15793 8192000000 10322936128 10751610880 1384311856 14.455 99 34 66 0 1422642320 29526112 8804352
21:03:42 15066 8192000000 10334099088 10761265152 1379742880 14.414 99 42 84 2 1435157568 43960320 11454368
21:05:04 19131 8192000000 10300874416 10735583232 1372058128 14.345 100 24 46 0 1412948544 34640208 6250208
21:06:25 13951 8192000000 10307446864 10741846016 1361229840 14.248 99 18 35 1 1402881056 37293520 4357696
21:08:04 29134 8192000000 10279500848 10724589568 1353406672 14.178 99 9 18 0 1378843104 23175968 2260464
21:09:34 53157 8192000000 10300047904 10729250816 1369656416 14.324 99 15 27 0 1388503664 15924624 2922624
21:11:30 15865 8192000000 10312176416 10732986368 1384196016 14.454 100 40 78 0 1402814256 9419152 9199088
21:12:49 61152 8192000000 10293495984 10730246144 1367008928 14.300 98 17 34 1 1383894784 12885024 4000832
21:15:01 84683 8192000000 10316242688 10745815040 1393934992 14.541 100 28 52 0 1409592832 9236560 6421280
21:17:29 107145 8192000000 10307636768 10755452928 1354671504 14.189 97 7 14 0 1369802176 13974752 1155920
21:20:39 87106 8192000000 10313285280 10754887680 1365974304 14.291 98 10 20 0 1383984688 15579216 2431168
21:23:09 50332 8192000000 10312864320 10768199680 1386705696 14.476 99 12 25 0 1402523168 13078240 2739232
21:25:01 116680 8192000000 10314329776 10763264000 1389941760 14.505 99 26 49 1 1404790944 9330016 5519168
21:28:04 100233 8192000000 10315286000 10762178560 1384487008 14.457 99 23 46 0 1402592144 12917456 5187680
21:30:46 50023 8192000000 10302021664 10754228224 1371020576 14.336 98 36 72 2 1394149232 14668480 8460176
21:32:40 34660 8192000000 10308300080 10769186816 1401637552 14.610 100 9 18 0 1416602224 12773152 2191520
21:34:23 62695 8192000000 10297931280 10753556480 1366042000 14.292 98 14 27 0 1386054416 16773568 3238848
21:36:26 91529 8192000000 10287305872 10738941952 1345282880 14.105 97 25 51 0 1360157776 9282832 5592064
21:39:00 58919 8192000000 10276381504 10741985280 1343983264 14.093 97 7 14 0 1356828512 11509536 1335712
21:41:01 63808 8192000000 10297445168 10761302016 1383656976 14.449 100 14 27 0 1399795408 13224320 2914112
21:43:06 84794 8192000000 10259396688 10705563648 1213571120 12.902 88 8 16 0 1223481424 8448208 1462096
21:45:31 21057 8192000000 10254249264 10698391552 1311396400 13.799 97 24 46 1 1325550688 8673904 5480384
21:46:53 58670 8192000000 10256054976 10705395712 1308946128 13.777 97 14 28 0 1325246432 13206192 3094112
21:48:59 83318 8192000000 10266376496 10710691840 1294837584 13.648 96 16 32 0 1324108208 26008864 3261760
21:51:22 75029 8192000000 10271167664 10735390720 1356673424 14.207 100 22 45 0 1377093840 15057296 5363120
21:53:39 105735 8192000000 10302921488 10753646592 1352505072 14.170 98 20 40 0 1385140688 28005296 4630320
21:56:25 127208 8192000000 10281424496 10739683328 1360133520 14.239 99 10 20 0 1377304944 14726144 2445280
22:00:00 15886 8192000000 10284853008 10726174720 1353172544 14.176 99 42 80 0 1371386112 9456240 8757328
22:01:30 46648 8192000000 10273677408 10728476672 1376135152 14.382 100 23 46 0 1389861536 8471328 5255056
22:04:29 47999 8192000000 10271658320 10731814912 1349250592 14.141 98 16 30 0 1361208752 8414416 3543744
22:06:18 101231 8192000000 10284156544 10741260288 1377951088 14.398 100 12 25 0 1393705264 12662080 3092096
22:09:39 21004 8192000000 10289064384 10745544704 1392677600 14.530 100 23 44 0 1403116352 5318736 5120016
22:11:05 59234 8192000000 10294684672 10755112960 1378330048 14.402 99 24 48 0 1390980432 7520640 5129744
22:13:06 51528 8192000000 10309854976 10766135296 1363581488 14.270 97 21 42 0 1383497056 15005008 4910560
22:14:58 41705 8192000000 10315233056 10761547776 1397103184 14.569 100 33 66 0 1413642208 9122640 7416384
22:16:44 38270 8192000000 10320731680 10779291648 1395991024 14.559 99 24 48 0 1412727328 11330752 5405552
22:18:30 77621 8192000000 10317477984 10791952384 1382398256 14.438 98 9 18 0 1401684368 17072192 2213920
22:20:56 105860 8192000000 10304672512 10756718592 1270858864 13.429 90 17 34 0 1284156672 9878240 3419568
22:24:10 114477 8192000000 10286714112 10747863040 1353499312 14.179 98 23 44 0 1371564960 13015216 5050432
22:27:06 48814 8192000000 10296448160 10763902976 1380275792 14.419 100 20 40 0 1406843184 21836960 4730432
22:28:55 53546 8192000000 10256234320 10721189888 1344503856 14.098 98 8 16 0 1357538800 11306016 1728928
22:31:42 70071 8192000000 10253158608 10711621632 1349987040 14.147 100 9 18 0 1362601920 10466336 2148544
22:34:03 21754 8192000000 10268449360 10734796800 1357941280 14.219 99 15 30 0 1372340800 10926096 3473424
22:35:27 53403 8192000000 10254983808 10716639232 1333906912 14.002 98 10 20 1 1346627280 10182704 2537664
22:37:21 30005 8192000000 10249551808 10694320128 1311715824 13.802 97 31 58 1 1333551280 14719456 7116000

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-02-2023 22:40:29 to 11-02-2023 22:40:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 95 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-02-2023 22:40:46 to 11-02-2023 22:40:48.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          243434
MON$OLDEST_ACTIVE               246633
MON$OLDEST_SNAPSHOT             246633
MON$NEXT_TRANSACTION            246633
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2052256
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33662 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33662 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33662 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33662 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:180: 
:181: mscs-test-161.budzdorov.ru    Sat Feb 11 22:38:45 2023
:182:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:183: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp30 according to config parameter 'remove_isql_logs'

11.02.23 22:40:49. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-02-11 22:40:51 No severe PSQL-related problems occured
11.02.23 22:40:53. End of report.