Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.08.2021 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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16500796 kB
MemAvailable 31025244 kB
Buffers 0 kB
Cached 15211860 kB
SwapCached 2580 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.8
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-08-15 22:37:50
DTS_END                         2021-08-15 22:37:50.0780
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/35210
TRN_ID                          234121
ATT_ID                          604
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: 8802.13 21435 1056255
customer order: creation 163.76 370 19651
customer order: refuse 13.75 372 1650
order to supplier: creation 43.45 1279 5214
order to supplier: removal 3.23 127503 388
invoice (draft): creation 42.98 3692 5158
invoice (draft): removal 50.22 938 6026
invoice accept: apply 39.59 20795 4751
invoice accept: cancel 45.67 923 5480
customer reserve: creation 3679.78 205 441574
customer reserve: removal 4445.01 73 533401
realization accept: apply 77.88 202 9345
realization accept: cancel 74.53 128 8943
payment from customer: creation 55.55 146 6666
payment from customer: removal 5.16 96 619
payment to supplier: creation 46.28 172 5553
payment to supplier: removal 3.32 173 398
service: total inventory turnovers 4.61 1407 553
service: total monetary turnovers 6.41 28 769
service: aggregate perf. data 0.93 14045 112
service: refresh index statistics 0.03 256161 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 15-08-2021 22:40:14 to 15-08-2021 22:40:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2213
TEST_TIME 2 1985
TEST_TIME 3 2885
TEST_TIME 4 4072
TEST_TIME 5 3851
TEST_TIME 6 4050
TEST_TIME 7 4156
TEST_TIME 8 4262
TEST_TIME 9 4317
TEST_TIME 10 4439
TEST_TIME 11 4917
TEST_TIME 12 4894
TEST_TIME 13 5037
TEST_TIME 14 5576
TEST_TIME 15 6062
TEST_TIME 16 6304
TEST_TIME 17 6721
TEST_TIME 18 6848
TEST_TIME 19 7310
TEST_TIME 20 7870
TEST_TIME 21 7859
TEST_TIME 22 7685
TEST_TIME 23 7775
TEST_TIME 24 8123
TEST_TIME 25 8251
TEST_TIME 26 8503
TEST_TIME 27 8520
TEST_TIME 28 9313
TEST_TIME 29 10076
TEST_TIME 30 10378
TEST_TIME 31 10167
TEST_TIME 32 10026
TEST_TIME 33 9794
TEST_TIME 34 9753
TEST_TIME 35 9769
TEST_TIME 36 9678
TEST_TIME 37 9587
TEST_TIME 38 9536
TEST_TIME 39 9448
TEST_TIME 40 9440
TEST_TIME 41 9346
TEST_TIME 42 9303
TEST_TIME 43 9224
TEST_TIME 44 9230
TEST_TIME 45 9219
TEST_TIME 46 9119
TEST_TIME 47 9044
TEST_TIME 48 8955
TEST_TIME 49 8837
TEST_TIME 50 8807
TEST_TIME 51 8849
TEST_TIME 52 8971
TEST_TIME 53 8880
TEST_TIME 54 8755
TEST_TIME 55 8812
TEST_TIME 56 8827
TEST_TIME 57 8867
TEST_TIME 58 8830
TEST_TIME 59 8790
TEST_TIME 60 8819
TEST_TIME 61 8900
TEST_TIME 62 8897
TEST_TIME 63 8869
TEST_TIME 64 8883
TEST_TIME 65 8915
TEST_TIME 66 8915
TEST_TIME 67 9039
TEST_TIME 68 8975
TEST_TIME 69 8977
TEST_TIME 70 8956
TEST_TIME 71 8896
TEST_TIME 72 8785
TEST_TIME 73 8810
TEST_TIME 74 8774
TEST_TIME 75 8768
TEST_TIME 76 8760
TEST_TIME 77 8745
TEST_TIME 78 8714
TEST_TIME 79 8648
TEST_TIME 80 8733
TEST_TIME 81 8696
TEST_TIME 82 8677
TEST_TIME 83 8672
TEST_TIME 84 8715
TEST_TIME 85 8737
TEST_TIME 86 8796
TEST_TIME 87 8874
TEST_TIME 88 8870
TEST_TIME 89 8851
TEST_TIME 90 8916
TEST_TIME 91 8899
TEST_TIME 92 8925
TEST_TIME 93 8959
TEST_TIME 94 8923
TEST_TIME 95 8866
TEST_TIME 96 8823
TEST_TIME 97 8834
TEST_TIME 98 8851
TEST_TIME 99 8786
TEST_TIME 100 8773
TEST_TIME 101 8731
TEST_TIME 102 8742
TEST_TIME 103 8783
TEST_TIME 104 8814
TEST_TIME 105 8813
TEST_TIME 106 8760
TEST_TIME 107 8883
TEST_TIME 108 8880
TEST_TIME 109 8869
TEST_TIME 110 8877
TEST_TIME 111 8865
TEST_TIME 112 8929
TEST_TIME 113 8950
TEST_TIME 114 8918
TEST_TIME 115 8992
TEST_TIME 116 8925
TEST_TIME 117 8869
TEST_TIME 118 8851
TEST_TIME 119 8847
TEST_TIME 120 8802

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-08-2021 22:40:18 to 15-08-2021 22:40:21.

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 1466104 1466104 0 0.00 0 23503 7 0 0
doc_list_biud 1466103 1466103 0 0.00 0 39168 33 0 0
sp_add_invoice_to_stock 4845 4751 94 1.94 24 263701 20796 0 94
sp_cancel_adding_invoice 5480 5480 0 0.00 5 70424 924 0 0
sp_cancel_client_order 1650 1650 0 0.00 4 22440 373 0 0
sp_cancel_customer_reserve 533401 533401 0 0.00 1 39170 73 0 0
sp_cancel_pay_from_customer 619 619 0 0.00 3 5157 96 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 9807 174 0 0
sp_cancel_supplier_invoice 6030 6026 4 0.06 1 30936 939 0 4
sp_cancel_supplier_order 388 388 0 0.00 8 924305 127504 0 0
sp_cancel_write_off 8943 8943 0 0.00 1 9742 128 0 0
sp_client_order 19651 19651 0 0.00 4 23483 371 0 0
sp_customer_reserve 441574 441574 0 0.00 0 60709 205 0 0
sp_fill_shopping_cart 30021 30021 0 0.00 1 15094 42 0 0
sp_fill_shopping_cart_clo_res 441573 441573 0 0.00 0 12369 16 0 0
sp_get_clo_for_invoice 4750 4750 0 0.00 0 19494 340 0 0
sp_kill_cost_storno 15440 15440 0 0.00 0 23447 70 0 0
sp_kill_qstorno_ret_qs2qd 1082159 1082159 0 0.00 0 39166 39 0 0
sp_kill_qty_storno 569983 569983 0 0.00 0 39168 84 0 0
sp_lock_selected_doc 19514 19514 0 0.00 0 7426 6 0 0
sp_make_cost_storno 26314 26314 0 0.00 0 20869 65 0 0
sp_multiply_rows_for_pdistr 26314 26314 0 0.00 0 19511 32 0 0
sp_multiply_rows_for_qdistr 19651 19651 0 0.00 0 17584 170 0 0
sp_pay_from_customer 6666 6666 0 0.00 4 19361 146 0 0
sp_pay_to_supplier 5553 5553 0 0.00 5 20909 173 0 0
sp_payment_common 12219 12219 0 0.00 1 20385 56 0 0
sp_qd_handle_on_cancel_clo 1650 1650 0 0.00 0 18531 331 0 0
sp_qd_handle_on_invoice_upd_sts 10230 10230 0 0.00 0 24325 361 0 0
sp_qd_handle_on_reserve_upd_sts 9345 9345 0 0.00 0 18473 63 0 0
sp_reserve_write_off 9345 9345 0 0.00 6 20854 202 0 0
sp_supplier_invoice 5158 5158 0 0.00 7 91076 3692 0 0
sp_supplier_order 5214 5214 0 0.00 5 35783 1279 0 0
srv_aggregate_perf_data 112 112 0 0.00 2381 150274 14045 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 1914 141526 52709 0 0
srv_find_qd_qs_mism 1011427 1011427 0 0.00 0 23501 6 0 0
srv_make_invnt_saldo 553 553 0 0.00 51 137430 1407 0 0
srv_make_money_saldo 769 769 0 0.00 1 463 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 106042 457776 256162 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4751 4751 0 0.00 0 21477 191 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 1932 69 0 0
t$perf-norm:sp_cancel_client_order 1650 1650 0 0.00 0 7690 27 0 0
t$perf-norm:sp_cancel_customer_reserve 660 660 0 0.00 0 1896 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 619 619 0 0.00 0 4012 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 508 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 3388 27 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 33253 1933 0 0
t$perf-norm:sp_cancel_write_off 920 920 0 0.00 0 1080 5 0 0
t$perf-norm:sp_client_order 19651 19651 0 0.00 0 12052 21 0 0
t$perf-norm:sp_customer_reserve 919 919 0 0.00 0 1230 7 0 0
t$perf-norm:sp_pay_from_customer 6666 6666 0 0.00 0 7264 15 0 0
t$perf-norm:sp_pay_to_supplier 5553 5553 0 0.00 0 6811 16 0 0
t$perf-norm:sp_reserve_write_off 9345 9345 0 0.00 0 13275 15 0 0
t$perf-norm:sp_supplier_invoice 5158 5158 0 0.00 0 4339 19 0 0
t$perf-norm:sp_supplier_order 5214 5214 0 0.00 0 6056 19 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 624 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 28 2 0 0
t$perf-norm:srv_make_invnt_saldo 553 553 0 0.00 0 2360 34 0 0
t$perf-norm:srv_make_money_saldo 769 769 0 0.00 0 1088 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9688 9500 188 1.94 0 9041 21 0 188
v_all_customers 35238 35238 0 0.00 0 37 1 0 0
v_all_suppliers 28762 28762 0 0.00 0 21 1 0 0
v_all_wares 39302 39302 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 1271 8 0 0
v_cancel_client_order 3300 3300 0 0.00 0 6079 12 0 0
v_cancel_customer_prepayment 1238 1238 0 0.00 0 1308 5 0 0
v_cancel_customer_reserve 1320 1320 0 0.00 0 1365 4 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 8006 28 0 0
v_cancel_supplier_invoice 776 768 8 1.03 0 3721 23 0 8
v_cancel_supplier_order 776 776 0 0.00 0 1044 4 0 0
v_cancel_write_off 1840 1840 0 0.00 0 6982 21 0 0
v_max_id_clo_ord 5214 5214 0 0.00 0 343 1 0 0
v_max_id_clo_res 919 919 0 0.00 0 1563 14 0 0
v_max_id_ord_sup 5156 5156 0 0.00 0 3763 6 0 0
v_max_non_paid_invoice 1542 1542 0 0.00 0 1278 4 0 0
v_max_non_paid_realizn 3691 3691 0 0.00 0 256 2 0 0
v_min_id_clo_ord 5214 5214 0 0.00 0 5770 9 0 0
v_min_id_clo_res 919 919 0 0.00 0 1487 6 0 0
v_min_id_ord_sup 5156 5156 0 0.00 0 2934 4 0 0
v_min_non_paid_invoice 5553 5553 0 0.00 0 19183 39 0 0
v_min_non_paid_realizn 6666 6666 0 0.00 0 19142 48 0 0
v_our_firm 10014 10014 0 0.00 0 42 1 0 0
v_reserve_write_off 18690 18690 0 0.00 0 10016 8 0 0
x_lock_depdocs_on_canc_invoice 11122 11122 0 0.00 0 16977 144 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 3783 386 0 0
x_make_qstorno_1000_1200 5214 5214 0 0.00 1 35593 1191 0 0
x_make_qstorno_1200_2000 5158 5158 0 0.00 3 91013 3536 0 0
x_make_qstorno_2100_3300 441574 441574 0 0.00 0 60704 186 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-08-2021 22:40:22 to 15-08-2021 22:40:26.

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:53 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:55 31130 8192000000 10377753424 10698534912 1424655104 14.814 100 85 170 1 1457757024 5501088 27600832
20:10:29 12042 8192000000 10446442528 10777038848 1503614272 15.508 100 91 182 0 1540299392 7658832 29026288
20:11:47 18390 8192000000 10483765440 10826620928 1533929312 15.771 100 82 165 1 1569000128 9103648 25967168
20:13:17 13640 8192000000 10550750224 10911088640 1608239968 16.410 100 75 150 0 1640779984 10012256 22527760
20:14:44 8200 8192000000 10656192928 11030536192 1716161056 17.320 100 71 142 1 1748127056 11752768 20213232
20:15:54 5187 8192000000 10718636208 11097350144 1777531536 17.829 100 90 180 3 1816050560 13534576 24984448
20:17:01 3643 8192000000 10767361248 11151056896 1824868496 18.217 100 90 180 3 1864075408 13251712 25955200
20:18:09 2940 8192000000 10799181536 11183353856 1854482400 18.459 100 87 174 1 1893402512 14413520 24506592
20:19:14 1691 8192000000 10825921952 11219193856 1882158736 18.683 100 91 179 2 1920485904 11865088 26462080
20:20:17 15015 8192000000 10813431216 11220754432 1882921760 18.689 100 51 101 2 1907525392 10351808 14251824
20:21:38 7638 8192000000 10807641680 11230457856 1892069184 18.762 100 34 68 2 1908875664 6795184 10011296
20:22:47 3867 8192000000 10802876496 11233583104 1898284112 18.812 100 32 65 0 1914956592 7692480 8980000
20:23:55 11433 8192000000 10817171968 11245154304 1907199136 18.884 100 33 65 4 1923579232 7165120 9214976
20:25:11 23429 8192000000 10841436656 11248406528 1889084256 18.738 99 54 111 0 1910645584 5500224 16061104
20:26:36 5043 8192000000 10824884240 11253366784 1906005904 18.875 100 33 64 2 1921887680 7090480 8791296
20:27:46 6960 8192000000 10786899664 11209433088 1838113728 18.325 97 17 34 0 1847968784 5000064 4854992
20:28:53 9229 8192000000 10742573392 11162624000 1803824464 18.045 98 15 28 1 1812758080 4664528 4269088
20:30:03 25482 8192000000 10769301600 11163697152 1828594560 18.248 100 52 103 1 1849839008 5652416 15592032
20:31:29 5895 8192000000 10690348512 11095367680 1739440944 17.514 98 39 76 4 1756419408 6409440 10569024
20:32:38 7327 8192000000 10718896064 11127476224 1769956560 17.767 98 26 52 0 1783737584 5902384 7878640
20:33:47 56096 8192000000 10744768800 11174674432 1838369824 18.328 100 37 74 2 1855061248 5689728 11001696
20:35:44 7141 8192000000 10774523536 11193282560 1853735696 18.452 100 41 80 2 1871617808 5907840 11974272
20:36:53 8226 8192000000 10775384624 11207729152 1864410880 18.539 100 24 48 1 1881962160 10703184 6848096
20:38:08 34346 8192000000 10836587984 11259838464 1912139152 18.924 100 47 92 0 1932577872 6491760 13946960
20:39:46 75360 8192000000 10861412096 11296407552 1931630656 19.080 100 47 94 0 1959011776 13080160 14300960
20:42:10 55912 8192000000 10886684048 11324198912 1954581040 19.263 100 31 62 1 1977161440 13617984 8962416
20:44:18 39471 8192000000 10932980176 11364282368 1988425376 19.531 100 31 62 1 2013245104 15482192 9337536
20:46:03 17758 8192000000 10942029072 11371458560 1998894608 19.614 100 63 122 1 2034104912 17926016 17284288
20:47:27 14998 8192000000 10947127648 11383914496 2003377616 19.649 100 54 108 1 2040663696 20711664 16574416
20:48:45 10117 8192000000 10946607888 11384930304 2001272368 19.633 100 48 96 1 2038797840 22469920 15055552
20:50:01 2522 8192000000 10941912992 11363356672 1975730080 19.431 99 68 136 0 2023517904 25965792 21822032
20:51:09 9590 8192000000 10953561312 11373535232 1975055472 19.426 99 73 146 1 2028401040 29999792 23345776
20:52:22 3716 8192000000 10965608480 11390382080 1993457968 19.571 100 73 146 3 2050270464 33611616 23200880
20:53:29 2748 8192000000 10953614048 11364528128 1954774784 19.264 99 88 177 2 2017865008 36828384 26261840
20:54:41 3213 8192000000 10973704080 11402334208 1993391136 19.571 100 73 145 1 2061369280 44569280 23408864
20:55:51 8592 8192000000 11000889792 11420184576 2004196672 19.656 100 81 162 1 2079418560 49327824 25894064
20:57:09 4197 8192000000 10982409744 11396763648 1995430528 19.587 100 81 160 1 2065807472 45091648 25285296
20:58:20 6409 8192000000 10996200352 11427500032 2015666464 19.746 100 62 122 0 2073005120 38204896 19133760
20:59:31 1911 8192000000 11005348272 11426881536 2014550896 19.737 100 83 165 1 2081840224 41807456 25481872
21:00:40 2089 8192000000 11021088672 11438895104 2024634016 19.817 100 86 172 0 2103271424 51565712 27071696
21:01:46 9385 8192000000 11001378128 11430551552 2013939536 19.733 100 54 108 0 2077585712 45993216 17652960
21:02:56 16434 8192000000 11004352960 11460812800 2023076416 19.804 100 42 84 2 2093748400 56096192 14575792
21:04:17 17938 8192000000 10985229552 11437649920 1992450976 19.563 100 33 66 2 2068051536 62697840 12902720
21:06:34 71332 8192000000 10895070960 11354198016 1932977168 19.091 99 24 48 1 1993467392 51694752 8795472
21:08:46 70671 8192000000 10895493536 11333201920 1944918160 19.186 99 37 74 1 1970958752 14484224 11556368
21:10:59 81628 8192000000 10882606944 11347529728 1920066752 18.987 98 16 33 1 1937213536 12881376 4265408
21:13:21 50227 8192000000 10867103888 11314790400 1900638400 18.831 97 15 30 0 1915643696 11277120 3728176
21:15:16 112738 8192000000 10905712736 11368439808 1969711408 19.383 100 20 39 0 1985144832 10316016 5117408
21:20:13 101023 8192000000 10883852960 11352731648 1923309120 19.013 97 34 69 2 1950322768 16966048 10047600
21:23:02 53216 8192000000 10895299248 11356557312 1952696560 19.248 99 16 32 2 1966023280 8347184 4979536
21:24:59 74835 8192000000 10904568672 11361951744 1957199328 19.284 99 27 54 1 1979959200 14752176 8007696
21:27:14 80621 8192000000 10902292912 11371266048 1961527360 19.318 99 36 72 2 1990945024 18756864 10660800
21:29:35 65378 8192000000 10888335664 11366952960 1972476608 19.405 100 10 21 0 1983375824 8047568 2851648
21:32:09 61658 8192000000 10892570144 11371663360 1975396560 19.428 100 16 33 3 1989250752 8800288 5053904
21:34:12 92805 8192000000 10898491600 11381215232 1961068784 19.315 99 11 21 1 1983624096 19619840 2935472
21:36:46 93224 8192000000 10878441328 11362734080 1890923536 18.753 96 7 14 1 1907295568 14814640 1557392
21:39:19 75009 8192000000 10869784816 11334008832 1883407952 18.693 96 12 24 0 1894082176 7272208 3402016
21:41:34 47839 8192000000 10869682928 11325325312 1875290224 18.627 96 28 56 1 1891041280 8576704 7174352
21:43:23 47326 8192000000 10909156960 11374043136 1964418352 19.341 99 23 46 1 1981567520 10441408 6707760
21:45:11 41483 8192000000 10878915872 11329908736 1899453024 18.822 97 27 52 3 1917210688 9473760 8283904
21:46:55 64150 8192000000 10897042224 11351400448 1911164640 18.916 97 25 50 1 1928609952 10550688 6894624
21:49:06 102429 8192000000 10862204672 11326746624 1891623856 18.759 97 26 52 1 1911721488 12781568 7316064
21:51:49 6657 8192000000 10879793072 11348664320 1956685088 19.280 100 27 55 1 1976115056 11321632 8108336
21:52:56 53626 8192000000 10865354640 11344220160 1945542400 19.191 99 10 20 1 1960950000 12311776 3095824
21:54:53 84311 8192000000 10874551392 11338375168 1850821632 18.429 94 8 16 1 1864402528 11148784 2432112
21:57:18 66410 8192000000 10862789328 11318059008 1848185600 18.407 95 13 25 0 1861825088 9693440 3946048
21:59:40 43108 8192000000 10878407760 11314958336 1904117008 18.859 98 29 57 1 1925110960 12274752 8719200
22:01:30 97534 8192000000 10873839680 11351269376 1895251392 18.788 97 16 32 0 1916275936 16237040 4787504
22:04:51 79289 8192000000 10876586160 11337854976 1914663952 18.944 98 16 32 0 1929827568 10934432 4229184
22:07:11 66061 8192000000 10896037728 11370061824 1942333904 19.165 98 28 54 4 1962067728 12076528 7657296
22:09:17 86369 8192000000 10889482080 11360886784 1870462736 18.588 95 14 28 0 1890040896 15501536 4076624
22:11:45 89333 8192000000 10904828656 11351756800 1923447072 19.014 98 36 70 0 1942157136 8694624 10015440
22:14:23 83600 8192000000 10878582336 11362492416 1927863920 19.050 98 11 22 1 1942156592 11170976 3121696
22:16:48 141502 8192000000 10916490832 11386802176 1961304384 19.316 99 16 31 3 1982974304 16866560 4803360
22:20:10 83593 8192000000 10884731232 11364032512 1936230912 19.117 98 6 12 0 1953565280 15460336 1874032
22:22:35 77070 8192000000 10935478352 11405197312 1970534320 19.390 99 15 31 1 1994744480 19673552 4536608
22:25:10 34644 8192000000 10921655600 11396329472 1976882432 19.440 99 24 48 1 1991671968 8442656 6346880
22:26:54 55288 8192000000 10913444544 11372290048 1899828592 18.825 95 28 56 0 1917814416 10358320 7627504
22:28:52 53998 8192000000 10915941920 11407695872 1985036576 19.505 100 45 89 3 2016465584 18877088 12551920
22:30:50 85204 8192000000 10910421824 11383857152 1909323088 18.901 96 13 26 1 1929823904 16928208 3572608
22:35:02 37909 8192000000 10911615712 11379367936 1966798624 19.360 99 37 72 1 1988361088 11401408 10161056
22:36:58 59996 8192000000 9674753280 10147713024 695466000 7.825 35 5 10 0 700380640 3449840 1464800

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-08-2021 22:40:28 to 15-08-2021 22:40:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-08-2021 22:40:44 to 15-08-2021 22:40:46.

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          234378
MON$OLDEST_ACTIVE               234379
MON$OLDEST_SNAPSHOT             234379
MON$NEXT_TRANSACTION            234379
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                       2013824
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33488 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33488 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33488 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33488 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 ---
--- end of diff output ---

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

15.08.21 22:40:47. 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-08-15 22:40:49 No severe PSQL-related problems occured
15.08.21 22:40:50. End of report.