Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.06.2021 07:27
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 16771184 kB
MemAvailable 31377580 kB
Buffers 0 kB
Cached 14860804 kB
SwapCached 1468 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 4.0.1
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-06-16 07:26:57
DTS_END                         2021-06-16 07:26:57.8590
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47452
TRN_ID                          226121
ATT_ID                          640
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: 8640.52 22790 1036862
customer order: creation 166.23 393 19947
customer order: refuse 14.67 414 1760
order to supplier: creation 44.50 1519 5340
order to supplier: removal 3.24 113275 389
invoice (draft): creation 44.45 3638 5334
invoice (draft): removal 46.91 964 5629
invoice accept: apply 40.92 19823 4911
invoice accept: cancel 42.76 987 5131
customer reserve: creation 3775.47 194 453056
customer reserve: removal 4181.40 70 501768
realization accept: apply 81.33 200 9760
realization accept: cancel 74.33 131 8919
payment from customer: creation 57.00 107 6840
payment from customer: removal 5.18 72 622
payment to supplier: creation 47.88 135 5746
payment to supplier: removal 3.35 113 402
service: total inventory turnovers 3.60 4208 432
service: total monetary turnovers 6.34 506 761
service: aggregate perf. data 0.93 15639 111
service: refresh index statistics 0.03 293415 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-06-2021 07:28:20 to 16-06-2021 07:28:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2139
TEST_TIME 2 2627
TEST_TIME 3 3133
TEST_TIME 4 3857
TEST_TIME 5 4094
TEST_TIME 6 4650
TEST_TIME 7 4384
TEST_TIME 8 4547
TEST_TIME 9 4805
TEST_TIME 10 5135
TEST_TIME 11 5278
TEST_TIME 12 5411
TEST_TIME 13 5856
TEST_TIME 14 5881
TEST_TIME 15 6102
TEST_TIME 16 6352
TEST_TIME 17 6775
TEST_TIME 18 7391
TEST_TIME 19 7494
TEST_TIME 20 7557
TEST_TIME 21 7873
TEST_TIME 22 8616
TEST_TIME 23 8678
TEST_TIME 24 8905
TEST_TIME 25 9049
TEST_TIME 26 9403
TEST_TIME 27 9491
TEST_TIME 28 9680
TEST_TIME 29 9744
TEST_TIME 30 10444
TEST_TIME 31 10268
TEST_TIME 32 9998
TEST_TIME 33 10173
TEST_TIME 34 10097
TEST_TIME 35 9905
TEST_TIME 36 9958
TEST_TIME 37 9833
TEST_TIME 38 9773
TEST_TIME 39 9736
TEST_TIME 40 9580
TEST_TIME 41 9460
TEST_TIME 42 9358
TEST_TIME 43 9152
TEST_TIME 44 9110
TEST_TIME 45 8993
TEST_TIME 46 8928
TEST_TIME 47 8912
TEST_TIME 48 8881
TEST_TIME 49 8809
TEST_TIME 50 8944
TEST_TIME 51 8823
TEST_TIME 52 8807
TEST_TIME 53 8943
TEST_TIME 54 8927
TEST_TIME 55 8887
TEST_TIME 56 8816
TEST_TIME 57 8920
TEST_TIME 58 8879
TEST_TIME 59 8844
TEST_TIME 60 8928
TEST_TIME 61 8971
TEST_TIME 62 8961
TEST_TIME 63 8946
TEST_TIME 64 9013
TEST_TIME 65 9014
TEST_TIME 66 9044
TEST_TIME 67 9009
TEST_TIME 68 9081
TEST_TIME 69 8957
TEST_TIME 70 8852
TEST_TIME 71 8801
TEST_TIME 72 8838
TEST_TIME 73 8749
TEST_TIME 74 8680
TEST_TIME 75 8700
TEST_TIME 76 8662
TEST_TIME 77 8660
TEST_TIME 78 8706
TEST_TIME 79 8730
TEST_TIME 80 8789
TEST_TIME 81 8743
TEST_TIME 82 8753
TEST_TIME 83 8819
TEST_TIME 84 8766
TEST_TIME 85 8797
TEST_TIME 86 8889
TEST_TIME 87 8874
TEST_TIME 88 8909
TEST_TIME 89 8854
TEST_TIME 90 8759
TEST_TIME 91 8715
TEST_TIME 92 8666
TEST_TIME 93 8637
TEST_TIME 94 8586
TEST_TIME 95 8603
TEST_TIME 96 8580
TEST_TIME 97 8609
TEST_TIME 98 8583
TEST_TIME 99 8560
TEST_TIME 100 8615
TEST_TIME 101 8742
TEST_TIME 102 8802
TEST_TIME 103 8817
TEST_TIME 104 8812
TEST_TIME 105 8818
TEST_TIME 106 8788
TEST_TIME 107 8838
TEST_TIME 108 8814
TEST_TIME 109 8760
TEST_TIME 110 8763
TEST_TIME 111 8707
TEST_TIME 112 8670
TEST_TIME 113 8667
TEST_TIME 114 8624
TEST_TIME 115 8607
TEST_TIME 116 8581
TEST_TIME 117 8557
TEST_TIME 118 8585
TEST_TIME 119 8556
TEST_TIME 120 8646
TEST_TIME 121 8574

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-06-2021 07:28:23 to 16-06-2021 07:28:27.

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 1453729 1453729 0 0.00 0 22852 7 0 0
doc_list_biud 1453729 1453729 0 0.00 0 51224 31 0 0
sp_add_invoice_to_stock 5041 4911 130 2.57 26 220427 19824 0 130
sp_cancel_adding_invoice 5131 5131 0 0.00 6 63117 988 0 0
sp_cancel_client_order 1760 1760 0 0.00 4 22779 414 0 0
sp_cancel_customer_reserve 501768 501768 0 0.00 1 18756 70 0 0
sp_cancel_pay_from_customer 622 622 0 0.00 3 1918 72 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 4951 114 0 0
sp_cancel_supplier_invoice 5634 5629 5 0.08 2 51255 965 0 5
sp_cancel_supplier_order 389 389 0 0.00 6 1206355 113275 0 0
sp_cancel_write_off 8919 8919 0 0.00 1 12357 131 0 0
sp_client_order 19947 19947 0 0.00 4 36584 393 0 0
sp_customer_reserve 453056 453056 0 0.00 0 37258 194 0 0
sp_fill_shopping_cart 30614 30614 0 0.00 1 28421 48 0 0
sp_fill_shopping_cart_clo_res 453052 453052 0 0.00 0 22077 17 0 0
sp_get_clo_for_invoice 4903 4903 0 0.00 0 31301 409 0 0
sp_kill_cost_storno 15074 15074 0 0.00 0 6837 65 0 0
sp_kill_qstorno_ret_qs2qd 1018473 1018473 0 0.00 0 51223 38 0 0
sp_kill_qty_storno 538259 538259 0 0.00 0 51223 83 0 0
sp_lock_selected_doc 20269 20269 0 0.00 0 7999 5 0 0
sp_make_cost_storno 27249 27249 0 0.00 0 18958 64 0 0
sp_multiply_rows_for_pdistr 27249 27249 0 0.00 0 8700 33 0 0
sp_multiply_rows_for_qdistr 19947 19947 0 0.00 0 24823 220 0 0
sp_pay_from_customer 6840 6840 0 0.00 4 15803 108 0 0
sp_pay_to_supplier 5746 5746 0 0.00 5 23427 136 0 0
sp_payment_common 12586 12586 0 0.00 1 13481 25 0 0
sp_qd_handle_on_cancel_clo 1760 1760 0 0.00 0 22769 387 0 0
sp_qd_handle_on_invoice_upd_sts 10034 10034 0 0.00 0 23644 426 0 0
sp_qd_handle_on_reserve_upd_sts 9760 9760 0 0.00 0 14524 54 0 0
sp_reserve_write_off 9760 9760 0 0.00 7 22651 200 0 0
sp_supplier_invoice 5334 5334 0 0.00 5 103001 3639 0 0
sp_supplier_order 5340 5340 0 0.00 4 89111 1520 0 0
srv_aggregate_perf_data 111 111 0 0.00 2573 163090 15639 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1007 172054 41013 0 0
srv_find_qd_qs_mism 989193 989193 0 0.00 0 16427 6 0 0
srv_make_invnt_saldo 432 432 0 0.00 40 140298 4208 0 0
srv_make_money_saldo 761 761 0 0.00 1 17434 506 0 0
srv_recalc_idx_stat 4 4 0 0.00 130049 433501 293415 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4911 4911 0 0.00 0 14589 183 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 1 5036 100 0 0
t$perf-norm:sp_cancel_client_order 1760 1760 0 0.00 0 989 9 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 4061 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 622 622 0 0.00 0 405 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 482 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 370 370 0 0.00 0 346 11 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 17219 1506 0 0
t$perf-norm:sp_cancel_write_off 969 969 0 0.00 0 3142 8 0 0
t$perf-norm:sp_client_order 19947 19947 0 0.00 0 6983 17 0 0
t$perf-norm:sp_customer_reserve 915 915 0 0.00 0 1145 5 0 0
t$perf-norm:sp_pay_from_customer 6840 6840 0 0.00 0 7742 7 0 0
t$perf-norm:sp_pay_to_supplier 5746 5746 0 0.00 0 3087 7 0 0
t$perf-norm:sp_reserve_write_off 9760 9760 0 0.00 0 11456 10 0 0
t$perf-norm:sp_supplier_invoice 5334 5334 0 0.00 0 7242 17 0 0
t$perf-norm:sp_supplier_order 5340 5340 0 0.00 0 6123 20 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 446 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 11311 301 0 0
t$perf-norm:srv_make_invnt_saldo 432 432 0 0.00 0 1226 15 0 0
t$perf-norm:srv_make_money_saldo 761 761 0 0.00 0 3128 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10066 9806 260 2.58 0 5303 18 0 260
v_all_customers 35572 35572 0 0.00 0 21 1 0 0
v_all_suppliers 29570 29570 0 0.00 0 17 1 0 0
v_all_wares 39890 39890 0 0.00 0 82 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 489 7 0 0
v_cancel_client_order 3520 3520 0 0.00 0 6806 6 0 0
v_cancel_customer_prepayment 1244 1244 0 0.00 0 1914 6 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 735 4 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 945 8 0 0
v_cancel_supplier_invoice 750 740 10 1.33 0 1500 19 0 10
v_cancel_supplier_order 778 778 0 0.00 0 5319 12 0 0
v_cancel_write_off 1938 1938 0 0.00 0 3359 18 0 0
v_max_id_clo_ord 5339 5339 0 0.00 0 985 2 0 0
v_max_id_clo_res 915 915 0 0.00 0 4161 15 0 0
v_max_id_ord_sup 5328 5328 0 0.00 0 4224 5 0 0
v_max_non_paid_invoice 1628 1628 0 0.00 0 135 2 0 0
v_max_non_paid_realizn 3866 3866 0 0.00 0 7762 4 0 0
v_min_id_clo_ord 5339 5339 0 0.00 0 3480 6 0 0
v_min_id_clo_res 915 915 0 0.00 0 1126 7 0 0
v_min_id_ord_sup 5328 5328 0 0.00 0 5057 6 0 0
v_min_non_paid_invoice 5746 5746 0 0.00 0 8809 21 0 0
v_min_non_paid_realizn 6840 6840 0 0.00 0 10950 16 0 0
v_our_firm 10266 10266 0 0.00 0 18 1 0 0
v_reserve_write_off 19520 19520 0 0.00 0 7028 7 0 0
x_lock_depdocs_on_canc_invoice 10390 10390 0 0.00 0 9893 117 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 9937 346 0 0
x_make_qstorno_1000_1200 5340 5340 0 0.00 2 89090 1432 0 0
x_make_qstorno_1200_2000 5334 5334 0 0.00 2 102462 3454 0 0
x_make_qstorno_2100_3300 453056 453056 0 0.00 0 37201 174 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-06-2021 07:28:28 to 16-06-2021 07:28:32.

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
04:57:00 4 8192000000 8796668864 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
04:58:01 21190 8192000000 9711088528 10003922944 786680176 8.761 100 88 176 0 815926016 7515072 21730768
04:59:46 18623 8192000000 9758009360 10066833408 840337008 9.303 100 86 172 0 871915344 10932912 20645424
05:01:10 1685 8192000000 9789555632 10103754752 865848880 9.559 100 86 172 1 901715824 15891632 19975312
05:02:19 15387 8192000000 9835688384 10166591488 905606800 9.954 100 74 148 0 949568496 27720080 16241616
05:03:38 11048 8192000000 9913420624 10254290944 957189440 10.462 100 83 166 0 1019575904 45317408 17069056
05:04:51 7286 8192000000 9960135024 10311266304 985286176 10.736 100 82 160 1 1067222624 65879280 16057168
05:06:01 4224 8192000000 9993595488 10353491968 1022498848 11.096 100 90 180 0 1105754192 65702832 17552512
05:07:07 3727 8192000000 10029087248 10392600576 1037186144 11.238 100 91 181 0 1132108960 77335824 17586992
05:08:14 2330 8192000000 10018577744 10390753280 1060816128 11.464 100 85 168 0 1139911136 61567872 17527136
05:09:17 1585 8192000000 10043014992 10414899200 1067422272 11.527 100 97 196 1 1151305920 63381152 20502496
05:10:22 13405 8192000000 10014010096 10396049408 1073036640 11.581 100 69 135 2 1129234480 41660992 14536848
05:11:36 8767 8192000000 9993104992 10393051136 1078824864 11.636 100 39 78 2 1131083872 43280912 8978096
05:12:46 5407 8192000000 9995351216 10397032448 1089557376 11.738 100 50 101 0 1117127696 16917584 10652736
05:13:52 10066 8192000000 10007625760 10414518272 1090789920 11.750 100 27 54 0 1119785008 23720384 5274704
05:15:03 5091 8192000000 10003618480 10402340864 1095235712 11.792 100 35 69 0 1127487888 24744928 7507248
05:16:08 9103 8192000000 10030468912 10416291840 1097234576 11.811 100 77 154 2 1126911776 13477696 16199504
05:17:19 4227 8192000000 10025667840 10420072448 1099037968 11.829 100 51 101 0 1134632192 23998544 11595680
05:18:23 5729 8192000000 9997873968 10395713536 1072918192 11.580 98 37 74 2 1095609968 13605248 9086528
05:19:29 10185 8192000000 9977252800 10373046272 1044842944 11.311 98 25 52 0 1075938368 25823808 5271616
05:20:45 1896 8192000000 9987833024 10370256896 1062836672 11.484 100 70 139 0 1092297440 13821200 15639568
05:21:47 8651 8192000000 9931360112 10321047552 971974464 10.606 94 51 98 2 1004875888 21616336 11285088
05:22:59 4944 8192000000 9960243296 10343391232 1029382512 11.162 100 44 89 3 1074054240 35258528 9413200
05:24:05 9047 8192000000 9957367328 10355236864 1041043376 11.275 100 24 48 2 1066577728 19936048 5598304
05:25:15 36678 8192000000 9977277792 10384908288 1041531504 11.279 99 36 71 1 1074517104 25192960 7792640
05:26:55 6335 8192000000 9993609584 10387009536 1067670240 11.530 100 32 61 2 1098536016 24025744 6840032
05:28:11 25719 8192000000 10027915824 10428604416 1090334160 11.746 100 44 88 2 1138579296 39059120 9186016
05:29:40 14188 8192000000 10082415984 10482573312 1107829344 11.912 100 44 87 1 1169267872 52558560 8879968
05:31:00 51421 8192000000 10115558640 10531524608 1127026096 12.093 100 41 81 1 1234679184 98920320 8732768
05:32:59 4661 8192000000 10117302208 10524844032 1134686512 12.166 100 63 123 1 1224389344 76575136 13127696
05:34:08 17420 8192000000 10163318960 10577530880 1149973920 12.309 100 46 91 0 1266846928 107466448 9406560
05:35:29 5475 8192000000 10212270960 10620162048 1157830592 12.383 100 67 133 1 1316046704 144039968 14176144
05:36:37 5872 8192000000 10260823616 10673258496 1167230752 12.471 100 59 116 1 1369653600 189607104 12815744
05:37:47 1005 8192000000 10296230624 10703319040 1178644992 12.578 100 86 171 3 1397928208 200890272 18392944
05:38:51 5442 8192000000 10335077024 10751864832 1183137296 12.619 100 66 131 1 1442404544 244545984 14721264
05:40:06 6875 8192000000 10312483248 10727403520 1186854592 12.654 100 74 147 0 1421427136 218475872 16096672
05:41:19 1529 8192000000 10368743664 10779410432 1194688688 12.727 100 81 164 3 1468541504 254022304 19830512
05:42:28 2011 8192000000 10404078416 10819813376 1198145488 12.759 100 83 167 2 1507140672 290715824 18279360
05:43:36 3221 8192000000 10388789856 10801811456 1184133600 12.629 99 88 176 2 1479664688 275428560 20102528
05:44:53 4063 8192000000 10352370112 10767589376 1189990784 12.683 100 78 155 0 1445574336 238619328 16964224
05:45:59 3303 8192000000 10356012096 10768470016 1167141600 12.470 100 84 166 2 1449426832 262508368 19776864
05:47:08 4497 8192000000 10406909536 10822983680 1173569040 12.530 100 75 150 2 1504941472 313613456 17758976
05:48:16 14682 8192000000 10301284256 10756894720 1154423712 12.351 100 33 66 0 1394409744 231626320 8359712
05:49:34 8579 8192000000 10271239728 10702823424 1143470672 12.248 100 31 62 0 1371941120 220423600 8046848
05:50:51 8352 8192000000 10221355552 10648686592 1086515376 11.710 97 35 70 0 1315880224 220702240 8662608
05:52:07 14633 8192000000 10204182848 10656325632 1126687216 12.090 99 30 60 1 1310471568 176744896 7039456
05:53:24 27657 8192000000 10223837312 10668249088 1127391664 12.097 100 22 44 2 1330370608 197802512 5176432
05:55:10 11721 8192000000 10207842016 10655731712 1121928832 12.045 100 35 69 0 1326570224 197327440 7313952
05:56:32 55082 8192000000 10081488960 10531254272 1102290768 11.859 99 15 30 0 1207649680 102365744 2993168
05:58:45 12294 8192000000 10118151264 10529439744 1109447936 11.927 100 58 109 3 1205202496 82991296 12763264
06:00:02 12664 8192000000 10083319712 10499588096 1126050304 12.084 100 62 123 2 1181621712 42753008 12818400
06:01:16 76136 8192000000 10083695488 10553155584 1110773936 11.940 98 14 29 0 1180469136 66877376 2817824
06:03:33 64161 8192000000 10057261888 10511196160 1129975744 12.121 100 21 42 0 1172977760 38337344 4664672
06:05:51 35679 8192000000 10031668000 10484551680 1100412544 11.842 98 18 36 2 1133445568 29487600 3545424
06:07:34 121638 8192000000 10059060944 10496983040 1123256912 12.058 100 24 48 1 1170499248 42183440 5058896
06:11:13 49378 8192000000 10077131280 10561601536 1125610752 12.080 99 13 26 0 1181373408 53580880 2181776
06:13:05 51967 8192000000 10128181040 10596995072 1130113056 12.122 99 23 46 0 1228123280 92700208 5310016
06:15:10 53824 8192000000 10153937984 10596933632 1141464576 12.229 100 22 44 0 1245111632 98671232 4975824
06:17:04 55763 8192000000 10120806816 10571866112 1131621056 12.137 99 15 30 1 1237752160 102596032 3535072
06:19:02 41400 8192000000 10120729696 10614812672 1141401104 12.229 100 15 30 0 1220260816 75122896 3736816
06:20:45 69536 8192000000 10102614544 10564128768 1151107280 12.320 100 19 38 2 1184712224 29577376 4027568
06:23:01 38256 8192000000 10077361568 10538659840 1143850288 12.252 100 26 52 0 1216758128 67821760 5086080
06:24:48 79524 8192000000 10141767968 10588327936 1126660080 12.090 97 20 40 0 1197385536 66779632 3945824
06:27:11 55503 8192000000 10076281808 10515771392 1126997776 12.093 97 21 41 4 1159407104 28308288 4101040
06:29:20 66071 8192000000 10060329136 10545983488 1093603216 11.777 95 15 30 0 1154077936 57410736 3063984
06:31:27 44597 8192000000 10083413008 10506133504 1103084272 11.867 97 19 38 1 1148952384 41867152 4000960
06:33:13 38506 8192000000 10106988656 10546159616 1103083424 11.867 97 10 20 0 1154216560 49172208 1960928
06:34:52 53737 8192000000 10070704576 10559057920 1131807472 12.138 100 5 10 0 1182529760 49825600 896688
06:36:51 10437 8192000000 10064049904 10500210688 1125813824 12.082 99 27 54 3 1155351952 24089568 5448560
06:38:19 61248 8192000000 10091749008 10593861632 1118142880 12.009 99 6 12 0 1190763120 71328480 1291760
06:40:21 95826 8192000000 10123272480 10574954496 1137271232 12.190 100 26 51 1 1217110064 74165840 5672992
06:43:15 51316 8192000000 10107974992 10558615552 1104140912 11.877 97 15 30 0 1193039312 85472544 3425856
06:45:08 54741 8192000000 10072438336 10505347072 1086708624 11.711 96 18 36 0 1140390784 49530640 4151520
06:47:18 26449 8192000000 10061357840 10480541696 1084627664 11.692 97 50 100 2 1119104448 23953936 10522848
06:48:48 65877 8192000000 10046786080 10514677760 1069983200 11.552 97 11 22 0 1149543648 77183600 2376848
06:50:58 66912 8192000000 10091154368 10554318848 1105707792 11.892 99 20 40 1 1197111520 87231792 4171936
06:53:10 62128 8192000000 10071164752 10531590144 1064514176 11.500 96 21 42 1 1112136000 43574896 4046928
06:55:51 107779 8192000000 10069639584 10516938752 1102123472 11.858 99 14 28 0 1156889536 51768016 2998048
06:58:49 61835 8192000000 10079758880 10569289728 1119874336 12.026 100 7 14 0 1187513888 66335536 1304016
07:00:51 48625 8192000000 10070839152 10541998080 1128439440 12.107 100 13 26 0 1206818240 75714496 2664304
07:02:43 41148 8192000000 10102234400 10573479936 1120276352 12.030 99 18 34 1 1196768144 73034992 3456800
07:04:33 67332 8192000000 10156241040 10598715392 1109017872 11.923 97 26 52 2 1215269792 100651632 5600288
07:06:41 78734 8192000000 10163914736 10763214848 1151247984 12.321 100 20 40 2 1332072032 176023664 4800384
07:09:44 36272 8192000000 10104123280 10555912192 1092175440 11.763 97 38 77 0 1156145216 56656768 7313008
07:11:26 28412 8192000000 10093082832 10537439232 1117632896 12.005 99 34 68 2 1177999600 53713552 6653152
07:12:56 26117 8192000000 10161471440 10608037888 1131627872 12.137 100 41 82 0 1232757728 93250672 7879184
07:14:23 172053 8192000000 10051262672 10523574272 1135861248 12.177 100 22 44 1 1211836048 71483968 4490832
07:18:17 81463 8192000000 10074448400 10577092608 1137570208 12.193 100 9 18 0 1175914720 36431376 1913136
07:20:39 31914 8192000000 10083964064 10538688512 1136887280 12.186 99 25 51 1 1164084096 21399456 5797360
07:22:12 47815 8192000000 10123234128 10567417856 1110897776 11.941 96 21 42 0 1199449664 83923600 4628288
07:24:05 43554 8192000000 10189344656 10629726208 1136161376 12.179 99 21 42 1 1249810208 108594048 5054784
07:26:27 55424 8192000000 9225211632 9623420928 334061280 3.918 30 2 3 0 374104928 39661264 382384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-06-2021 07:28:34 to 16-06-2021 07:28:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 130 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-06-2021 07:28:50 to 16-06-2021 07:28:52.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          226430
MON$OLDEST_ACTIVE               226431
MON$OLDEST_SNAPSHOT             226431
MON$NEXT_TRANSACTION            226431
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-30 07:30:08.6030 GMT
MON$PAGES                       2089120
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2517 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2517 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2517 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2517 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.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-oltp40 according to config parameter 'remove_isql_logs'

16.06.21 07:28:53. There are 3 logs in /var/tmp/logs-oltp40 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-06-16 07:28:56 No severe PSQL-related problems occured
16.06.21 07:28:57. End of report.