Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.12.2021 22:38
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 16493240 kB
MemAvailable 31420572 kB
Buffers 0 kB
Cached 15147348 kB
SwapCached 0 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.9
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 2021-12-11 22:37:56
DTS_END                         2021-12-11 22:37:56.1500
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33664
TRN_ID                          251203
ATT_ID                          676
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: 9557.72 22267 1146926
customer order: creation 183.07 351 21968
customer order: refuse 15.82 308 1898
order to supplier: creation 49.98 1269 5997
order to supplier: removal 3.40 89079 408
invoice (draft): creation 49.08 3222 5889
invoice (draft): removal 51.50 732 6180
invoice accept: apply 45.73 17239 5487
invoice accept: cancel 47.21 830 5665
customer reserve: creation 4173.51 173 500821
customer reserve: removal 4626.48 51 555177
realization accept: apply 90.38 167 10845
realization accept: cancel 85.02 107 10202
payment from customer: creation 63.10 103 7572
payment from customer: removal 6.16 81 739
payment to supplier: creation 50.40 149 6048
payment to supplier: removal 3.97 73 476
service: total inventory turnovers 5.22 976 626
service: total monetary turnovers 6.82 28 818
service: aggregate perf. data 0.88 19967 106
service: refresh index statistics 0.03 310443 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-12-2021 22:38:42 to 11-12-2021 22:38:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1661
TEST_TIME 2 2313
TEST_TIME 3 3592
TEST_TIME 4 3634
TEST_TIME 5 3616
TEST_TIME 6 4141
TEST_TIME 7 4343
TEST_TIME 8 4772
TEST_TIME 9 4948
TEST_TIME 10 5380
TEST_TIME 11 5772
TEST_TIME 12 6109
TEST_TIME 13 6221
TEST_TIME 14 6256
TEST_TIME 15 7075
TEST_TIME 16 7188
TEST_TIME 17 7711
TEST_TIME 18 7857
TEST_TIME 19 8346
TEST_TIME 20 8394
TEST_TIME 21 8520
TEST_TIME 22 8823
TEST_TIME 23 9151
TEST_TIME 24 9434
TEST_TIME 25 9855
TEST_TIME 26 10739
TEST_TIME 27 10401
TEST_TIME 28 10215
TEST_TIME 29 10226
TEST_TIME 30 10329
TEST_TIME 31 10393
TEST_TIME 32 10196
TEST_TIME 33 10042
TEST_TIME 34 10023
TEST_TIME 35 9964
TEST_TIME 36 10150
TEST_TIME 37 10077
TEST_TIME 38 9933
TEST_TIME 39 10158
TEST_TIME 40 10190
TEST_TIME 41 10115
TEST_TIME 42 10042
TEST_TIME 43 10062
TEST_TIME 44 10149
TEST_TIME 45 10241
TEST_TIME 46 10125
TEST_TIME 47 9975
TEST_TIME 48 9957
TEST_TIME 49 9955
TEST_TIME 50 9936
TEST_TIME 51 9774
TEST_TIME 52 9750
TEST_TIME 53 9731
TEST_TIME 54 9706
TEST_TIME 55 9856
TEST_TIME 56 9830
TEST_TIME 57 9705
TEST_TIME 58 9674
TEST_TIME 59 9534
TEST_TIME 60 9618
TEST_TIME 61 9535
TEST_TIME 62 9520
TEST_TIME 63 9516
TEST_TIME 64 9533
TEST_TIME 65 9529
TEST_TIME 66 9535
TEST_TIME 67 9516
TEST_TIME 68 9525
TEST_TIME 69 9619
TEST_TIME 70 9691
TEST_TIME 71 9684
TEST_TIME 72 9695
TEST_TIME 73 9733
TEST_TIME 74 9792
TEST_TIME 75 9751
TEST_TIME 76 9723
TEST_TIME 77 9769
TEST_TIME 78 9668
TEST_TIME 79 9637
TEST_TIME 80 9562
TEST_TIME 81 9608
TEST_TIME 82 9549
TEST_TIME 83 9522
TEST_TIME 84 9487
TEST_TIME 85 9435
TEST_TIME 86 9403
TEST_TIME 87 9435
TEST_TIME 88 9436
TEST_TIME 89 9469
TEST_TIME 90 9463
TEST_TIME 91 9452
TEST_TIME 92 9478
TEST_TIME 93 9488
TEST_TIME 94 9520
TEST_TIME 95 9594
TEST_TIME 96 9632
TEST_TIME 97 9681
TEST_TIME 98 9686
TEST_TIME 99 9752
TEST_TIME 100 9782
TEST_TIME 101 9761
TEST_TIME 102 9684
TEST_TIME 103 9639
TEST_TIME 104 9638
TEST_TIME 105 9607
TEST_TIME 106 9611
TEST_TIME 107 9578
TEST_TIME 108 9558
TEST_TIME 109 9573
TEST_TIME 110 9516
TEST_TIME 111 9528
TEST_TIME 112 9509
TEST_TIME 113 9534
TEST_TIME 114 9518
TEST_TIME 115 9523
TEST_TIME 116 9514
TEST_TIME 117 9519
TEST_TIME 118 9541
TEST_TIME 119 9546
TEST_TIME 120 9562

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-12-2021 22:38:45 to 11-12-2021 22:38:49.

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 1608969 1608969 0 0.00 0 21147 6 0 0
doc_list_biud 1608969 1608969 0 0.00 0 68990 23 0 0
sp_add_invoice_to_stock 5574 5487 87 1.56 18 190169 17239 0 87
sp_cancel_adding_invoice 5665 5665 0 0.00 4 84190 830 0 0
sp_cancel_client_order 1898 1898 0 0.00 4 11005 308 0 0
sp_cancel_customer_reserve 555177 555177 0 0.00 1 68991 52 0 0
sp_cancel_pay_from_customer 739 739 0 0.00 3 7512 81 0 0
sp_cancel_pay_to_supplier 476 476 0 0.00 2 3728 73 0 0
sp_cancel_supplier_invoice 6191 6180 11 0.17 2 18344 732 0 11
sp_cancel_supplier_order 408 408 0 0.00 6 953645 89080 0 0
sp_cancel_write_off 10202 10202 0 0.00 1 11802 108 0 0
sp_client_order 21968 21968 0 0.00 3 31963 351 0 0
sp_customer_reserve 500821 500821 0 0.00 0 61157 173 0 0
sp_fill_shopping_cart 33842 33842 0 0.00 1 22372 40 0 0
sp_fill_shopping_cart_clo_res 500814 500814 0 0.00 0 13349 13 0 0
sp_get_clo_for_invoice 5480 5480 0 0.00 0 35614 300 0 0
sp_kill_cost_storno 17082 17082 0 0.00 0 9480 54 0 0
sp_kill_qstorno_ret_qs2qd 1127144 1127144 0 0.00 0 68986 28 0 0
sp_kill_qty_storno 595855 595855 0 0.00 0 68989 61 0 0
sp_lock_selected_doc 22458 22458 0 0.00 0 8137 3 0 0
sp_make_cost_storno 29945 29945 0 0.00 0 14245 58 0 0
sp_multiply_rows_for_pdistr 29945 29945 0 0.00 0 8649 26 0 0
sp_multiply_rows_for_qdistr 21968 21968 0 0.00 0 15348 142 0 0
sp_pay_from_customer 7572 7572 0 0.00 4 29299 103 0 0
sp_pay_to_supplier 6048 6048 0 0.00 5 24671 149 0 0
sp_payment_common 13620 13620 0 0.00 1 29230 36 0 0
sp_qd_handle_on_cancel_clo 1898 1898 0 0.00 0 10997 275 0 0
sp_qd_handle_on_invoice_upd_sts 11145 11145 0 0.00 0 17573 320 0 0
sp_qd_handle_on_reserve_upd_sts 10845 10845 0 0.00 0 11418 51 0 0
sp_reserve_write_off 10845 10845 0 0.00 6 21160 168 0 0
sp_supplier_invoice 5889 5889 0 0.00 7 116978 3222 0 0
sp_supplier_order 5997 5997 0 0.00 5 41263 1270 0 0
srv_aggregate_perf_data 106 106 0 0.00 2374 472806 19968 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1855 158481 49505 0 0
srv_find_qd_qs_mism 1095004 1095004 0 0.00 0 17395 5 0 0
srv_make_invnt_saldo 626 626 0 0.00 32 31605 976 0 0
srv_make_money_saldo 818 818 0 0.00 1 1592 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 259816 374722 310443 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5487 5487 0 0.00 0 21088 143 0 0
t$perf-norm:sp_cancel_adding_invoice 463 463 0 0.00 0 4093 75 0 0
t$perf-norm:sp_cancel_client_order 1898 1898 0 0.00 0 1942 10 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 2427 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 739 739 0 0.00 0 1354 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 476 476 0 0.00 0 917 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 2794 23 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 34299 1589 0 0
t$perf-norm:sp_cancel_write_off 1059 1059 0 0.00 0 3620 7 0 0
t$perf-norm:sp_client_order 21968 21968 0 0.00 0 4648 15 0 0
t$perf-norm:sp_customer_reserve 987 987 0 0.00 0 299 3 0 0
t$perf-norm:sp_pay_from_customer 7572 7572 0 0.00 0 8873 11 0 0
t$perf-norm:sp_pay_to_supplier 6048 6048 0 0.00 0 3798 8 0 0
t$perf-norm:sp_reserve_write_off 10845 10845 0 0.00 0 8211 11 0 0
t$perf-norm:sp_supplier_invoice 5889 5889 0 0.00 0 4086 14 0 0
t$perf-norm:sp_supplier_order 5997 5997 0 0.00 0 7281 20 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 177 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 22 1 0 0
t$perf-norm:srv_make_invnt_saldo 626 626 0 0.00 0 1192 20 0 0
t$perf-norm:srv_make_money_saldo 818 818 0 0.00 0 2125 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 10 4 0 0
v_add_invoice_to_stock 11132 10958 174 1.56 0 3002 15 0 174
v_all_customers 40022 40022 0 0.00 0 20 1 0 0
v_all_suppliers 32152 32152 0 0.00 0 100 1 0 0
v_all_wares 43934 43934 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 926 926 0 0.00 0 5882 12 0 0
v_cancel_client_order 3796 3796 0 0.00 0 5940 10 0 0
v_cancel_customer_prepayment 1478 1478 0 0.00 0 1842 7 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 0 134 2 0 0
v_cancel_payment_to_supplier 952 952 0 0.00 0 3719 17 0 0
v_cancel_supplier_invoice 858 836 22 2.56 0 2841 22 0 22
v_cancel_supplier_order 816 816 0 0.00 0 5654 11 0 0
v_cancel_write_off 2118 2118 0 0.00 0 2774 17 0 0
v_max_id_clo_ord 5994 5994 0 0.00 0 4006 2 0 0
v_max_id_clo_res 987 987 0 0.00 0 1959 11 0 0
v_max_id_ord_sup 5881 5881 0 0.00 0 4764 3 0 0
v_max_non_paid_invoice 1847 1847 0 0.00 0 2132 3 0 0
v_max_non_paid_realizn 4028 4028 0 0.00 0 1400 4 0 0
v_min_id_clo_ord 5994 5994 0 0.00 0 15291 19 0 0
v_min_id_clo_res 987 987 0 0.00 0 609 3 0 0
v_min_id_ord_sup 5881 5881 0 0.00 0 6232 7 0 0
v_min_non_paid_invoice 6048 6048 0 0.00 0 24603 34 0 0
v_min_non_paid_realizn 7572 7572 0 0.00 0 13303 20 0 0
v_our_firm 11000 11000 0 0.00 0 68 1 0 0
v_reserve_write_off 21686 21686 0 0.00 0 5968 6 0 0
x_lock_depdocs_on_canc_invoice 11427 11427 0 0.00 0 16221 104 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 8786 303 0 0
x_make_qstorno_1000_1200 5997 5997 0 0.00 1 41258 1178 0 0
x_make_qstorno_1200_2000 5889 5889 0 0.00 3 116952 3091 0 0
x_make_qstorno_2100_3300 500821 500821 0 0.00 0 61061 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-12-2021 22:38:50 to 11-12-2021 22:38:54.

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:07:59 52 8192000000 8817771312 8835928064 14151568 0.172 5 2 2 0 14262112 67760 42784
20:09:00 21626 8192000000 9907466864 10216767488 993334496 10.814 100 81 162 0 1019844016 5854704 20654816
20:10:28 32521 8192000000 9954826784 10280448000 1028221136 11.151 100 82 164 0 1057754592 8467888 21065568
20:12:03 19350 8192000000 9994188128 10329743360 1074095936 11.591 100 88 176 0 1105584816 9899296 21589584
20:13:24 12088 8192000000 10033656016 10384601088 1122598704 12.052 100 76 152 0 1150851808 10732656 17520448
20:14:41 6887 8192000000 10088108880 10449010688 1178829808 12.579 100 77 155 1 1207801936 11485632 17486496
20:15:50 6007 8192000000 10131068736 10502520832 1225190576 13.010 100 79 158 2 1255336528 12793200 17352752
20:16:57 3181 8192000000 10163946640 10540253184 1255501984 13.289 100 90 177 4 1290004304 15034400 19467920
20:18:02 1884 8192000000 10200249040 10582712320 1281222816 13.524 100 89 178 2 1314760464 12883104 20654544
20:19:05 2522 8192000000 10210657744 10601349120 1299981280 13.695 100 86 172 0 1332087808 12529904 19576624
20:20:09 2057 8192000000 10208129360 10609160192 1304604960 13.737 100 68 136 2 1330527744 10447296 15475488
20:21:13 8127 8192000000 10194589008 10609782784 1309405024 13.781 100 17 33 2 1318955488 5898928 3651536
20:22:21 10838 8192000000 10202087712 10619232256 1316705248 13.847 100 23 46 2 1328018208 6101760 5211200
20:23:33 11110 8192000000 10210301648 10630512640 1325569664 13.927 100 27 54 0 1338198640 6677600 5951376
20:24:45 34923 8192000000 10207403072 10636410880 1331675696 13.982 100 25 50 1 1342701840 5695744 5330400
20:26:25 9348 8192000000 10215065040 10639421440 1326519088 13.936 100 25 49 1 1338816384 5966768 6330528
20:27:36 10403 8192000000 10192713520 10600374272 1249370624 13.232 95 12 24 1 1257341216 4684320 3286272
20:28:48 19953 8192000000 10176017632 10578706432 1220964736 12.971 94 23 46 0 1231325088 5065392 5294960
20:30:09 7429 8192000000 10160165104 10566291456 1228174960 13.037 96 11 22 1 1235443376 5050400 2218016
20:31:21 5365 8192000000 10138641952 10541240320 1247077616 13.211 100 23 46 1 1262316496 9931376 5307504
20:32:27 7377 8192000000 10138702176 10540576768 1240855936 13.154 99 29 58 2 1253478544 5674608 6948000
20:33:34 7165 8192000000 10161047088 10572005376 1263752784 13.364 99 22 41 2 1274128576 5657648 4718144
20:34:44 47010 8192000000 10178604224 10587070464 1268606176 13.409 99 22 44 1 1278876928 5561248 4709504
20:36:31 8456 8192000000 10191724544 10605379584 1301040704 13.705 100 23 46 0 1312030064 5767728 5221632
20:37:44 5596 8192000000 10200922624 10612932608 1306017824 13.750 100 27 53 0 1317282704 4814608 6450272
20:38:59 16169 8192000000 10237099552 10641743872 1325237952 13.924 100 39 78 1 1343304544 9369584 8697008
20:40:22 21905 8192000000 10260644688 10676670464 1350446256 14.151 100 40 80 1 1374189728 13954448 9789024
20:41:48 57683 8192000000 10270927408 10693251072 1352212160 14.167 99 37 73 1 1375452560 14506304 8734096
20:43:50 13915 8192000000 10292894352 10706505728 1369699920 14.324 100 61 121 2 1404275792 20363200 14212672
20:45:07 5528 8192000000 10316589408 10730422272 1386274272 14.473 100 68 134 0 1424856128 23095424 15486432
20:46:20 5836 8192000000 10312296208 10727362560 1378086880 14.399 99 64 125 3 1413318048 19938592 15292576
20:47:32 1929 8192000000 10340730112 10756440064 1401227376 14.606 100 78 156 4 1447261520 26801504 19232640
20:48:40 5473 8192000000 10349978000 10770829312 1413473184 14.715 100 67 134 0 1461756608 32021904 16261520
20:49:51 5611 8192000000 10327216064 10743320576 1385126784 14.462 100 68 136 1 1434621056 32475552 17018720
20:51:02 11347 8192000000 10331656720 10746187776 1369714080 14.324 99 60 121 0 1425894064 41080592 15099392
20:52:18 6853 8192000000 10331774640 10743296000 1380909408 14.425 100 67 133 0 1439266992 42015680 16341904
20:53:28 6617 8192000000 10339688272 10744320000 1369584352 14.323 98 82 164 2 1431510864 41411888 20514624
20:54:41 2354 8192000000 10328361696 10736619520 1365676480 14.288 99 93 184 1 1425287744 34888592 24722672
20:55:54 1853 8192000000 10322517104 10735583232 1370706880 14.333 100 65 130 1 1427731920 40991152 16033888
20:57:02 8129 8192000000 10308896560 10735902720 1374456928 14.367 100 45 90 1 1422247920 35813504 11977488
20:58:13 12480 8192000000 10324388960 10741714944 1375852432 14.379 100 62 121 5 1430606704 37718336 17035936
20:59:31 18273 8192000000 10331909520 10766897152 1394056272 14.542 100 34 68 1 1438679408 35335344 9287792
21:00:52 27570 8192000000 10299148336 10738102272 1373193424 14.356 100 23 46 2 1413755712 34108640 6453648
21:02:24 26955 8192000000 10300680224 10737422336 1340805808 14.065 98 22 44 2 1381359056 34566352 5986896
21:03:54 51132 8192000000 10274214144 10703302656 1324242480 13.915 97 24 48 1 1347061968 16329568 6489920
21:05:48 32063 8192000000 10277317456 10708824064 1350730688 14.154 99 30 60 0 1376277712 18790560 6756464
21:07:21 50451 8192000000 10256596720 10710360064 1313721440 13.820 97 15 30 1 1332597376 15468912 3407024
21:09:20 69226 8192000000 10276339680 10716811264 1355742688 14.199 99 27 55 2 1372806768 9614848 7449232
21:11:30 85319 8192000000 10287527184 10731978752 1355415008 14.196 99 16 32 1 1376328160 17784704 3128448
21:14:21 56758 8192000000 10306888048 10749259776 1368300752 14.312 99 27 54 0 1387295344 12941040 6053552
21:16:18 103372 8192000000 10290369616 10739400704 1353500016 14.179 98 5 10 0 1375451440 20892192 1059232
21:19:03 78939 8192000000 10306719584 10739163136 1374109760 14.364 99 17 33 1 1387275232 9445280 3720192
21:21:34 111766 8192000000 10302474112 10763509760 1373013136 14.354 99 7 14 1 1394847104 20297264 1536704
21:24:26 158480 8192000000 10286379504 10741055488 1345708288 14.109 97 23 44 2 1363016544 11923552 5384704
21:28:06 72726 8192000000 10275064608 10722455552 1351724000 14.163 98 24 48 2 1367683280 9338096 6621184
21:30:20 40103 8192000000 10299959200 10758823936 1390578048 14.511 100 26 52 1 1413492256 16053248 6860960
21:32:11 25863 8192000000 10277597200 10749644800 1368413152 14.313 99 19 39 0 1393228160 20332976 4482032
21:34:45 88792 8192000000 10278908880 10740563968 1377441840 14.394 100 13 26 0 1388687264 8387248 2858176
21:37:55 48221 8192000000 10278645936 10739257344 1358834592 14.227 98 18 36 0 1372072400 9518752 3719056
21:39:44 42207 8192000000 10249095216 10702266368 1323755968 13.911 96 9 18 0 1332329856 6855600 1718288
21:41:27 68957 8192000000 10249375648 10704232448 1281395472 13.526 94 16 32 1 1292611744 7973440 3242832
21:43:43 66291 8192000000 10284010528 10735243264 1340041696 14.058 97 18 36 1 1361264976 16789552 4433728
21:46:02 68274 8192000000 10277191376 10743291904 1341497312 14.071 97 16 32 0 1361455936 16264672 3693952
21:48:19 31227 8192000000 10293804528 10740453376 1358793200 14.227 98 38 76 0 1382048208 14854720 8400288
21:51:31 77587 8192000000 10285255664 10731671552 1318026912 13.859 95 13 26 1 1329020512 8251808 2741792
21:53:53 37266 8192000000 10310522752 10761621504 1390225616 14.508 100 43 86 0 1413526688 13760224 9540848
21:55:34 95225 8192000000 10277166192 10732969984 1326211200 13.933 96 30 60 1 1339919728 7039824 6668704
21:58:10 41958 8192000000 10252582928 10711601152 1340296976 14.060 98 24 48 1 1356202464 10467424 5438064
21:59:56 78485 8192000000 10277106960 10739154944 1348128192 14.131 98 10 20 1 1361568176 11382704 2057280
22:02:37 86907 8192000000 10276424592 10744590336 1370006288 14.327 99 35 70 1 1392304656 13114352 9184016
22:05:08 44593 8192000000 10267378752 10728284160 1359896896 14.236 98 18 36 0 1372755168 8765168 4093104
22:06:59 98841 8192000000 10305658192 10756251648 1367115088 14.301 98 24 48 0 1385835472 14194640 4525744
22:09:39 85688 8192000000 10298490320 10761056256 1370084864 14.328 98 13 26 1 1384563104 11710480 2767760
22:12:05 77086 8192000000 10319834944 10785144832 1406574384 14.653 100 19 39 0 1430589072 19500624 4514064
22:14:22 77861 8192000000 10314011904 10786631680 1375276464 14.374 98 14 28 0 1401228672 22543952 3408256
22:16:45 31476 8192000000 10307546208 10766499840 1393770496 14.539 100 31 62 1 1412140016 11735232 6634288
22:18:17 74079 8192000000 10285499200 10732306432 1324211536 13.915 95 27 54 0 1338924672 8730496 5982640
22:20:40 57169 8192000000 10279307744 10728148992 1347227792 14.123 98 32 64 0 1363488912 8822592 7438528
22:22:41 5219 8192000000 10298946000 10741329920 1380776208 14.423 100 57 114 2 1405870576 12445904 12648464
22:23:46 78764 8192000000 10272036592 10737500160 1348524784 14.134 98 7 14 0 1362894416 12854720 1514912
22:26:05 53540 8192000000 10260752576 10728271872 1351374896 14.160 99 9 18 0 1370053168 16160352 2517920
22:28:04 62011 8192000000 10240249376 10694356992 1280776096 13.520 94 13 26 0 1293141792 9082704 3282992
22:30:06 54472 8192000000 10234848448 10687864832 1290255200 13.607 96 15 30 0 1303631888 10271872 3104816
22:32:02 48186 8192000000 10246130240 10694967296 1318995024 13.868 98 21 43 0 1333232960 9562416 4675520
22:33:51 37940 8192000000 10259752048 10709426176 1333942144 14.003 98 30 58 2 1351017040 9826752 7248144
22:35:46 108732 8192000000 10282989248 10729648128 1364061184 14.274 100 18 36 0 1383791520 15793664 3936672

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 11-12-2021 22:38:56 to 11-12-2021 22:39:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 87 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-12-2021 22:39:11 to 11-12-2021 22:39:13.

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          250708
MON$OLDEST_ACTIVE               251777
MON$OLDEST_SNAPSHOT             251777
MON$NEXT_TRANSACTION            251777
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                       2045984
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33543 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33543 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33543 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33543 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 ---
:36: 
:37: mscs-test-161.budzdorov.ru    Sat Dec 11 22:38:01 2021
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Sat Dec 11 22:38:06 2021
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:43: 
--- end of diff output ---

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

11.12.21 22:39:14. 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
2021-12-11 22:39:17 No severe PSQL-related problems occured
11.12.21 22:39:18. End of report.