Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.02.2023 19: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 15197304 kB
MemAvailable 30402236 kB
Buffers 0 kB
Cached 16488684 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 19:38:38
DTS_END                         2023-02-11 19:38:38.8250
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/51810
TRN_ID                          236466
ATT_ID                          653
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: 9455.58 18472 1134669
customer order: creation 169.87 339 20384
customer order: refuse 14.93 337 1791
order to supplier: creation 45.78 1137 5493
order to supplier: removal 3.43 108211 411
invoice (draft): creation 46.35 3183 5562
invoice (draft): removal 53.84 742 6461
invoice accept: apply 42.06 17184 5047
invoice accept: cancel 48.38 862 5806
customer reserve: creation 3947.01 167 473641
customer reserve: removal 4782.51 62 573901
realization accept: apply 83.58 183 10030
realization accept: cancel 88.68 127 10641
payment from customer: creation 60.36 121 7243
payment from customer: removal 5.51 90 661
payment to supplier: creation 48.53 148 5824
payment to supplier: removal 3.31 71 397
service: total inventory turnovers 4.36 1317 523
service: total monetary turnovers 6.28 51 753
service: aggregate perf. data 0.80 20022 96
service: refresh index statistics 0.03 215085 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-02-2023 19:39:55 to 11-02-2023 19:39:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5599
TEST_TIME 2 4170
TEST_TIME 3 3650
TEST_TIME 4 4330
TEST_TIME 5 4320
TEST_TIME 6 4967
TEST_TIME 7 4734
TEST_TIME 8 5106
TEST_TIME 9 5491
TEST_TIME 10 5415
TEST_TIME 11 5363
TEST_TIME 12 5484
TEST_TIME 13 5706
TEST_TIME 14 5825
TEST_TIME 15 5975
TEST_TIME 16 5966
TEST_TIME 17 6308
TEST_TIME 18 6422
TEST_TIME 19 6887
TEST_TIME 20 6984
TEST_TIME 21 7224
TEST_TIME 22 7996
TEST_TIME 23 8054
TEST_TIME 24 8148
TEST_TIME 25 8265
TEST_TIME 26 8444
TEST_TIME 27 8622
TEST_TIME 28 9518
TEST_TIME 29 10911
TEST_TIME 30 10716
TEST_TIME 31 10934
TEST_TIME 32 10754
TEST_TIME 33 10717
TEST_TIME 34 10551
TEST_TIME 35 10447
TEST_TIME 36 10411
TEST_TIME 37 10250
TEST_TIME 38 10264
TEST_TIME 39 10249
TEST_TIME 40 10117
TEST_TIME 41 10118
TEST_TIME 42 10161
TEST_TIME 43 10070
TEST_TIME 44 10075
TEST_TIME 45 10119
TEST_TIME 46 10147
TEST_TIME 47 10241
TEST_TIME 48 10070
TEST_TIME 49 9908
TEST_TIME 50 9785
TEST_TIME 51 9783
TEST_TIME 52 9735
TEST_TIME 53 9679
TEST_TIME 54 9800
TEST_TIME 55 9735
TEST_TIME 56 9810
TEST_TIME 57 9697
TEST_TIME 58 9752
TEST_TIME 59 9662
TEST_TIME 60 9604
TEST_TIME 61 9630
TEST_TIME 62 9543
TEST_TIME 63 9515
TEST_TIME 64 9506
TEST_TIME 65 9558
TEST_TIME 66 9560
TEST_TIME 67 9734
TEST_TIME 68 9687
TEST_TIME 69 9764
TEST_TIME 70 9758
TEST_TIME 71 9726
TEST_TIME 72 9784
TEST_TIME 73 9738
TEST_TIME 74 9816
TEST_TIME 75 9869
TEST_TIME 76 9911
TEST_TIME 77 9930
TEST_TIME 78 9834
TEST_TIME 79 9764
TEST_TIME 80 9761
TEST_TIME 81 9706
TEST_TIME 82 9709
TEST_TIME 83 9662
TEST_TIME 84 9653
TEST_TIME 85 9624
TEST_TIME 86 9577
TEST_TIME 87 9517
TEST_TIME 88 9482
TEST_TIME 89 9515
TEST_TIME 90 9556
TEST_TIME 91 9529
TEST_TIME 92 9512
TEST_TIME 93 9564
TEST_TIME 94 9608
TEST_TIME 95 9568
TEST_TIME 96 9600
TEST_TIME 97 9634
TEST_TIME 98 9652
TEST_TIME 99 9675
TEST_TIME 100 9702
TEST_TIME 101 9738
TEST_TIME 102 9734
TEST_TIME 103 9715
TEST_TIME 104 9647
TEST_TIME 105 9616
TEST_TIME 106 9607
TEST_TIME 107 9595
TEST_TIME 108 9589
TEST_TIME 109 9534
TEST_TIME 110 9524
TEST_TIME 111 9504
TEST_TIME 112 9456
TEST_TIME 113 9447
TEST_TIME 114 9418
TEST_TIME 115 9385
TEST_TIME 116 9398
TEST_TIME 117 9454
TEST_TIME 118 9458
TEST_TIME 119 9422
TEST_TIME 120 9500

Performance per minute, chart


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

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 1573034 1573034 0 0.00 0 32282 6 0 0
doc_list_biud 1573034 1573034 0 0.00 0 30006 28 0 0
sp_add_invoice_to_stock 5156 5047 109 2.11 46 195796 17184 0 109
sp_cancel_adding_invoice 5806 5806 0 0.00 4 62350 862 0 0
sp_cancel_client_order 1791 1791 0 0.00 4 14613 338 0 0
sp_cancel_customer_reserve 573901 573901 0 0.00 0 27874 62 0 0
sp_cancel_pay_from_customer 661 661 0 0.00 3 7971 91 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 2 3714 71 0 0
sp_cancel_supplier_invoice 6471 6461 10 0.15 1 27383 742 0 10
sp_cancel_supplier_order 411 411 0 0.00 7 1135958 108211 0 0
sp_cancel_write_off 10641 10641 0 0.00 1 29090 127 0 0
sp_client_order 20384 20384 0 0.00 3 28232 339 0 0
sp_customer_reserve 473641 473641 0 0.00 0 73370 167 0 0
sp_fill_shopping_cart 31428 31428 0 0.00 1 15926 35 0 0
sp_fill_shopping_cart_clo_res 473631 473631 0 0.00 0 20327 14 0 0
sp_get_clo_for_invoice 5037 5037 0 0.00 0 20870 292 0 0
sp_kill_cost_storno 17505 17505 0 0.00 0 15413 61 0 0
sp_kill_qstorno_ret_qs2qd 1165315 1165315 0 0.00 0 29083 33 0 0
sp_kill_qty_storno 614078 614078 0 0.00 0 30004 71 0 0
sp_lock_selected_doc 20894 20894 0 0.00 0 8212 5 0 0
sp_make_cost_storno 28134 28134 0 0.00 0 32273 61 0 0
sp_multiply_rows_for_pdistr 28134 28134 0 0.00 0 16617 29 0 0
sp_multiply_rows_for_qdistr 20384 20384 0 0.00 0 13238 148 0 0
sp_pay_from_customer 7243 7243 0 0.00 4 16626 121 0 0
sp_pay_to_supplier 5824 5824 0 0.00 4 14187 149 0 0
sp_payment_common 13067 13067 0 0.00 1 14515 44 0 0
sp_qd_handle_on_cancel_clo 1791 1791 0 0.00 0 14602 315 0 0
sp_qd_handle_on_invoice_upd_sts 10843 10843 0 0.00 0 30004 323 0 0
sp_qd_handle_on_reserve_upd_sts 10030 10030 0 0.00 0 17375 57 0 0
sp_reserve_write_off 10030 10030 0 0.00 6 17389 183 0 0
sp_supplier_invoice 5562 5562 0 0.00 7 89571 3183 0 0
sp_supplier_order 5493 5493 0 0.00 4 76350 1138 0 0
srv_aggregate_perf_data 96 96 0 0.00 3108 174322 20022 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1275 153866 48671 0 0
srv_find_qd_qs_mism 1086503 1086503 0 0.00 0 26612 5 0 0
srv_make_invnt_saldo 523 523 0 0.00 40 122651 1318 0 0
srv_make_money_saldo 753 753 0 0.00 0 23287 52 0 0
srv_recalc_idx_stat 4 4 0 0.00 92140 349830 215085 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5047 5047 0 0.00 0 7747 148 0 0
t$perf-norm:sp_cancel_adding_invoice 432 432 0 0.00 0 5329 90 0 0
t$perf-norm:sp_cancel_client_order 1791 1791 0 0.00 0 5268 12 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 854 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 661 661 0 0.00 0 392 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 2168 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 516 10 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 24556 1416 0 0
t$perf-norm:sp_cancel_write_off 1039 1039 0 0.00 0 1800 8 0 0
t$perf-norm:sp_client_order 20384 20384 0 0.00 0 8271 16 0 0
t$perf-norm:sp_customer_reserve 1016 1016 0 0.00 0 4875 15 0 0
t$perf-norm:sp_pay_from_customer 7243 7243 0 0.00 0 8088 12 0 0
t$perf-norm:sp_pay_to_supplier 5824 5824 0 0.00 0 20483 15 0 0
t$perf-norm:sp_reserve_write_off 10030 10030 0 0.00 0 7228 9 0 0
t$perf-norm:sp_supplier_invoice 5562 5562 0 0.00 0 6691 22 0 0
t$perf-norm:sp_supplier_order 5493 5493 0 0.00 0 4831 15 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 184 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 486 11 0 0
t$perf-norm:srv_make_invnt_saldo 523 523 0 0.00 0 1091 23 0 0
t$perf-norm:srv_make_money_saldo 753 753 0 0.00 0 2766 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10292 10074 218 2.11 0 16648 18 0 218
v_all_customers 37798 37798 0 0.00 0 189 1 0 0
v_all_suppliers 30902 30902 0 0.00 0 190 1 0 0
v_all_wares 40768 40768 0 0.00 0 31 1 0 0
v_cancel_adding_invoice 864 864 0 0.00 0 2435 14 0 0
v_cancel_client_order 3582 3582 0 0.00 0 2040 6 0 0
v_cancel_customer_prepayment 1322 1322 0 0.00 0 5404 8 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 1993 7 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 682 4 0 0
v_cancel_supplier_invoice 796 776 20 2.51 0 3492 19 0 20
v_cancel_supplier_order 822 822 0 0.00 0 2780 9 0 0
v_cancel_write_off 2078 2078 0 0.00 0 3589 15 0 0
v_max_id_clo_ord 5493 5493 0 0.00 0 386 1 0 0
v_max_id_clo_res 1016 1016 0 0.00 0 3535 13 0 0
v_max_id_ord_sup 5551 5551 0 0.00 0 2347 3 0 0
v_max_non_paid_invoice 1417 1417 0 0.00 0 3462 7 0 0
v_max_non_paid_realizn 3541 3541 0 0.00 0 1804 4 0 0
v_min_id_clo_ord 5493 5493 0 0.00 0 4945 6 0 0
v_min_id_clo_res 1016 1016 0 0.00 0 12038 24 0 0
v_min_id_ord_sup 5551 5551 0 0.00 0 8072 10 0 0
v_min_non_paid_invoice 5824 5824 0 0.00 0 14146 38 0 0
v_min_non_paid_realizn 7243 7243 0 0.00 0 14503 35 0 0
v_our_firm 10374 10374 0 0.00 0 19 1 0 0
v_reserve_write_off 20060 20060 0 0.00 0 4659 7 0 0
x_lock_depdocs_on_canc_invoice 11879 11879 0 0.00 0 12824 114 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 26827 388 0 0
x_make_qstorno_1000_1200 5493 5493 0 0.00 1 76331 1067 0 0
x_make_qstorno_1200_2000 5562 5562 0 0.00 4 88966 3057 0 0
x_make_qstorno_2100_3300 473641 473641 0 0.00 0 73369 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-02-2023 19:40:03 to 11-02-2023 19:40:07.

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
17:08:42 9 8192000000 8811273184 8826626048 15702448 0.191 4 4 6 0 15949952 97680 149824
17:09:43 38819 8192000000 9915353888 10227388416 989424512 10.776 100 86 172 0 1018058592 6290592 22343488
17:11:24 3178 8192000000 9940879312 10259292160 1023136032 11.102 100 93 186 0 1055750496 8796480 23817984
17:12:31 15806 8192000000 9965022752 10291896320 1045122464 11.314 100 89 178 0 1077833632 10069216 22641952
17:14:01 10440 8192000000 10026746704 10373570560 1113797792 11.968 100 86 172 0 1146810768 11507600 21505376
17:15:16 8617 8192000000 10074175248 10431438848 1158851888 12.393 100 78 154 1 1188086800 11547424 17687488
17:16:31 3599 8192000000 10122037424 10491719680 1210756672 12.876 100 84 166 0 1242483216 12893200 18833344
17:17:37 2142 8192000000 10154478640 10528169984 1246697840 13.208 100 79 156 0 1276932304 12710336 17524128
17:18:41 6237 8192000000 10179963936 10562547712 1270037280 13.422 100 83 161 0 1299898064 12273040 17587744
17:19:49 1027 8192000000 10213087744 10598084608 1295394080 13.653 100 82 164 1 1327361664 12967040 19000544
17:20:51 3931 8192000000 10219140288 10608365568 1307598208 13.764 100 78 154 0 1335760480 11483008 16679264
17:21:59 7610 8192000000 10190569680 10611990528 1313032064 13.814 100 33 66 2 1327891568 6825120 8034384
17:23:12 12764 8192000000 10198671360 10616475648 1318976704 13.867 100 15 30 0 1328328816 5798016 3554096
17:24:26 18522 8192000000 10212476720 10630942720 1321756592 13.893 100 12 24 0 1329931712 5408304 2766816
17:25:45 69146 8192000000 10224035296 10645356544 1331047280 13.977 100 32 64 1 1343516592 5007296 7462016
17:27:55 8181 8192000000 10218224416 10641727488 1330588848 13.972 100 23 46 0 1341398464 5949952 4859664
17:29:04 6088 8192000000 10210941360 10627743744 1298517760 13.682 98 26 52 1 1310443440 5676608 6249072
17:30:13 15562 8192000000 10137176144 10541490176 1165544512 12.455 91 20 39 0 1174635792 4513056 4578224
17:31:30 25666 8192000000 10129420208 10528538624 1141369664 12.228 90 16 31 1 1149973840 5141616 3462560
17:32:56 5821 8192000000 10156642464 10552209408 1239506864 13.142 98 35 71 2 1253512528 5105168 8900496
17:34:04 10253 8192000000 10143684064 10543194112 1216473344 12.929 96 14 28 0 1224895200 5096576 3325280
17:35:16 72457 8192000000 10176293888 10582646784 1283135680 13.542 100 35 69 0 1297238352 5227792 8874880
17:37:29 4040 8192000000 10190856272 10604490752 1300216544 13.697 100 27 54 0 1312966592 6534672 6215376
17:38:34 8243 8192000000 10212164320 10628128768 1320771760 13.884 100 24 48 1 1331122736 4697856 5653120
17:39:48 2243 8192000000 10243134048 10654531584 1340846464 14.065 100 46 92 0 1359252640 7986656 10419520
17:40:56 95216 8192000000 10260909008 10682134528 1353927072 14.183 100 42 84 0 1375630352 11998112 9705168
17:43:42 23125 8192000000 10276190880 10699902976 1365601408 14.288 100 43 82 2 1389176128 13771872 9802848
17:45:11 15416 8192000000 10298205376 10720444416 1380218048 14.418 100 41 82 1 1409583024 19550800 9814176
17:46:48 6024 8192000000 10314030592 10736058368 1388941344 14.496 100 59 114 0 1426003744 22851664 14210736
17:47:57 10425 8192000000 10326261792 10749992960 1399368960 14.589 100 51 102 1 1433343248 22153472 11820816
17:49:13 11967 8192000000 10333448944 10762182656 1405061232 14.640 100 45 90 0 1440439280 24969648 10408400
17:50:29 12416 8192000000 10361528400 10780483584 1419253328 14.766 100 75 149 2 1467079648 30994320 16832000
17:51:45 5349 8192000000 10378597056 10799652864 1433063952 14.888 100 74 146 1 1485269664 35057424 17148288
17:52:59 7005 8192000000 10363362144 10787762176 1417241312 14.748 100 56 112 0 1473272448 41147984 14883152
17:54:14 9091 8192000000 10385742816 10800451584 1422231008 14.792 100 69 137 1 1489466112 49697392 17537712
17:55:30 11614 8192000000 10386672896 10809954304 1413132640 14.712 99 54 108 0 1485225584 57161552 14931392
17:56:51 2279 8192000000 10387929680 10798915584 1413030704 14.711 100 86 167 0 1494824304 60139520 21654080
17:57:59 4487 8192000000 10408294192 10822320128 1426935664 14.834 100 80 160 0 1509368208 61679984 20752560
17:59:10 1275 8192000000 10404718448 10810806272 1411314000 14.696 100 87 172 0 1503523184 69828080 22381104
18:00:16 6869 8192000000 10376805664 10794995712 1397366592 14.572 100 66 132 1 1487933744 72903856 17663296
18:01:34 4390 8192000000 10373132112 10790055936 1399397120 14.590 100 54 107 0 1473226320 59346256 14482944
18:02:41 28453 8192000000 10372254960 10803490816 1392446656 14.528 99 34 68 1 1468574736 66380784 9747296
18:04:11 23701 8192000000 10361380432 10808172544 1385988240 14.470 100 30 61 0 1471605920 76806096 8811584
18:05:38 20256 8192000000 10359678256 10808778752 1372586080 14.350 100 23 46 0 1459188944 79291840 7311024
18:07:52 65688 8192000000 10287834464 10718736384 1287761344 13.584 94 11 22 0 1312982016 22574208 2646464
18:10:07 45727 8192000000 10256575312 10697396224 1336575904 14.027 99 25 50 1 1352192576 10433488 5183184
18:11:56 76604 8192000000 10263315472 10712760320 1332852096 13.993 98 19 38 0 1352618816 14932000 4834720
18:14:27 66047 8192000000 10262708448 10700554240 1325603232 13.927 98 20 38 0 1338385568 7996528 4785808
18:16:54 80717 8192000000 10276639680 10719641600 1347873824 14.128 99 11 22 1 1360551664 10430656 2247184
18:19:16 79485 8192000000 10278970256 10729472000 1340398064 14.061 98 9 16 1 1356756096 14219696 2138336
18:21:36 52608 8192000000 10293876464 10743103488 1385902448 14.469 100 22 44 1 1399596496 8519328 5174720
18:23:33 153844 8192000000 10317481040 10776375296 1365374528 14.286 98 6 13 0 1389065024 22279136 1411360
18:27:07 69486 8192000000 10297475936 10747113472 1380075216 14.417 99 32 64 0 1396416320 9081440 7259664
18:29:22 65143 8192000000 10321033744 10765803520 1390369984 14.509 99 54 108 1 1417870864 15233184 12267696
18:31:27 63876 8192000000 10314455280 10785710080 1402435056 14.617 100 29 51 2 1422718400 14279760 6003584
18:33:40 73568 8192000000 10305837008 10780995584 1396483888 14.564 99 14 28 1 1413661088 13874512 3302688
18:36:17 31589 8192000000 10301716416 10770980864 1402665744 14.619 100 11 20 0 1414763392 9580832 2516816
18:37:51 65713 8192000000 10309057456 10768879616 1386317776 14.473 98 23 46 0 1401604112 9658288 5628048
18:40:03 75603 8192000000 10291252848 10760663040 1389497232 14.501 99 14 28 0 1399720816 7317888 2905696
18:42:18 46560 8192000000 10305246512 10772758528 1400584240 14.600 100 10 20 0 1413149984 10581920 1983824
18:44:14 71102 8192000000 10309259728 10777092096 1343841680 14.092 95 15 30 1 1361713728 14357552 3514496
18:46:26 27668 8192000000 10311658608 10749169664 1386372736 14.473 100 50 99 0 1406521328 9206320 10942272
18:47:58 85381 8192000000 10277772720 10721783808 1271644528 13.437 92 21 40 0 1287517168 11020592 4852048
18:50:23 35855 8192000000 10269526256 10719821824 1338830976 14.047 98 15 30 0 1351314256 9299696 3183584
18:52:04 25863 8192000000 10288734768 10728996864 1349549072 14.143 99 37 74 0 1374888640 17399200 7940368
18:53:37 88747 8192000000 10263322736 10716016640 1295610512 13.655 95 10 20 1 1316612752 19106016 1896224
18:56:07 86037 8192000000 10251199024 10697732096 1334008848 14.003 98 21 42 0 1345650400 6999664 4641888
18:58:39 64258 8192000000 10258749808 10705059840 1325750688 13.929 98 24 48 0 1341477456 10192016 5534752
19:00:44 80801 8192000000 10231966784 10700001280 1309354672 13.780 97 7 14 0 1323094976 12357216 1383088
19:03:08 75921 8192000000 10230848416 10683793408 1312149904 13.806 97 11 23 0 1321345360 6779216 2416240
19:05:24 64147 8192000000 10253110736 10709901312 1330902112 13.975 98 15 30 0 1346251728 12224768 3124848
19:07:40 90637 8192000000 10261422736 10718576640 1346585568 14.117 99 15 30 1 1363378592 13673968 3119056
19:10:12 49763 8192000000 10277544256 10725695488 1307819696 13.766 95 16 33 0 1322144304 11094704 3229904
19:12:04 56492 8192000000 10264827696 10724597760 1352952608 14.174 99 22 44 0 1369711632 11825744 4933280
19:14:02 43276 8192000000 10287012768 10740826112 1363327376 14.267 99 25 50 0 1378025424 8981856 5716192
19:15:48 59569 8192000000 10305325600 10766807040 1391090432 14.516 100 18 36 0 1406272640 11167392 4014816
19:17:48 59203 8192000000 10315905600 10777608192 1394777456 14.548 100 21 42 0 1419258720 19912896 4568368
19:19:48 64579 8192000000 10295267120 10750283776 1356252240 14.204 97 12 24 0 1371063984 12294880 2516864
19:21:53 97426 8192000000 10297466944 10746265600 1384031232 14.453 99 52 103 0 1410961280 15316752 11613296
19:24:31 37889 8192000000 10282690864 10734305280 1355442320 14.196 98 38 75 0 1373632912 9354160 8836432
19:26:12 80170 8192000000 10265655920 10731319296 1343886752 14.092 97 6 10 1 1353254592 8250640 1117200
19:28:32 40707 8192000000 10274353072 10737696768 1368272656 14.312 99 19 36 0 1379532576 6631024 4628896
19:30:19 98842 8192000000 10262444400 10729193472 1340727216 14.064 97 17 34 0 1351979024 7737824 3513984
19:32:58 54706 8192000000 10274202128 10729234432 1335452224 14.016 97 11 22 0 1349558784 11556416 2550144
19:34:55 40400 8192000000 10251800272 10696232960 1306131408 13.751 96 18 36 1 1323688784 12814192 4743184
19:36:41 64649 8192000000 10274517936 10712072192 1305491664 13.745 96 25 50 0 1330707840 19443312 5772864

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-02-2023 19:40:09 to 11-02-2023 19:40:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-02-2023 19:40:25 to 11-02-2023 19:40:27.

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          234341
MON$OLDEST_ACTIVE               236584
MON$OLDEST_SNAPSHOT             236584
MON$NEXT_TRANSACTION            236584
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                       2038112
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 ---
:171: 
:172: mscs-test-161.budzdorov.ru    Sat Feb 11 19:38:54 2023
:173:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:174: 
--- end of diff output ---

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

11.02.23 19:40:28. 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 19:40:31 No severe PSQL-related problems occured
11.02.23 19:40:32. End of report.