Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.08.2021 19: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 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 16082872 kB
MemAvailable 31017828 kB
Buffers 0 kB
Cached 15550744 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-05 19:37:45
DTS_END                         2021-08-05 19:37:45.4140
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/44926
TRN_ID                          249957
ATT_ID                          718
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: 9787.40 20060 1174488
customer order: creation 183.89 331 22067
customer order: refuse 15.58 332 1869
order to supplier: creation 49.71 1305 5965
order to supplier: removal 3.72 109665 446
invoice (draft): creation 49.18 3301 5902
invoice (draft): removal 56.36 886 6763
invoice accept: apply 43.78 17926 5253
invoice accept: cancel 50.23 873 6028
customer reserve: creation 4095.97 175 491516
customer reserve: removal 4932.27 64 591873
realization accept: apply 89.59 172 10751
realization accept: cancel 79.31 129 9517
payment from customer: creation 63.04 110 7565
payment from customer: removal 6.48 73 778
payment to supplier: creation 51.80 123 6216
payment to supplier: removal 3.56 82 427
service: total inventory turnovers 4.83 1213 580
service: total monetary turnovers 7.13 30 856
service: aggregate perf. data 0.93 21005 112
service: refresh index statistics 0.03 243400 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-08-2021 19:38:44 to 05-08-2021 19:38:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2430
TEST_TIME 2 4251
TEST_TIME 3 4061
TEST_TIME 4 4794
TEST_TIME 5 4699
TEST_TIME 6 4957
TEST_TIME 7 5174
TEST_TIME 8 5383
TEST_TIME 9 5511
TEST_TIME 10 5798
TEST_TIME 11 5974
TEST_TIME 12 6387
TEST_TIME 13 6409
TEST_TIME 14 6714
TEST_TIME 15 6584
TEST_TIME 16 6841
TEST_TIME 17 7329
TEST_TIME 18 7709
TEST_TIME 19 7820
TEST_TIME 20 7870
TEST_TIME 21 7909
TEST_TIME 22 8218
TEST_TIME 23 8430
TEST_TIME 24 8785
TEST_TIME 25 8851
TEST_TIME 26 9420
TEST_TIME 27 9947
TEST_TIME 28 10599
TEST_TIME 29 10907
TEST_TIME 30 11262
TEST_TIME 31 11115
TEST_TIME 32 11000
TEST_TIME 33 11077
TEST_TIME 34 11035
TEST_TIME 35 10967
TEST_TIME 36 10770
TEST_TIME 37 10760
TEST_TIME 38 10742
TEST_TIME 39 10716
TEST_TIME 40 10608
TEST_TIME 41 10651
TEST_TIME 42 10552
TEST_TIME 43 10375
TEST_TIME 44 10320
TEST_TIME 45 10320
TEST_TIME 46 10239
TEST_TIME 47 10118
TEST_TIME 48 10187
TEST_TIME 49 10170
TEST_TIME 50 10045
TEST_TIME 51 10207
TEST_TIME 52 10317
TEST_TIME 53 10252
TEST_TIME 54 10251
TEST_TIME 55 10204
TEST_TIME 56 10096
TEST_TIME 57 10088
TEST_TIME 58 9935
TEST_TIME 59 9842
TEST_TIME 60 10054
TEST_TIME 61 9977
TEST_TIME 62 10015
TEST_TIME 63 10010
TEST_TIME 64 10091
TEST_TIME 65 10057
TEST_TIME 66 10026
TEST_TIME 67 10080
TEST_TIME 68 10048
TEST_TIME 69 10055
TEST_TIME 70 10028
TEST_TIME 71 10094
TEST_TIME 72 10091
TEST_TIME 73 10212
TEST_TIME 74 10207
TEST_TIME 75 10208
TEST_TIME 76 10159
TEST_TIME 77 10125
TEST_TIME 78 10071
TEST_TIME 79 10048
TEST_TIME 80 9981
TEST_TIME 81 9931
TEST_TIME 82 9953
TEST_TIME 83 9898
TEST_TIME 84 9846
TEST_TIME 85 9796
TEST_TIME 86 9871
TEST_TIME 87 9785
TEST_TIME 88 9785
TEST_TIME 89 9807
TEST_TIME 90 9805
TEST_TIME 91 9783
TEST_TIME 92 9840
TEST_TIME 93 9812
TEST_TIME 94 9809
TEST_TIME 95 9835
TEST_TIME 96 9832
TEST_TIME 97 9868
TEST_TIME 98 9922
TEST_TIME 99 9913
TEST_TIME 100 9895
TEST_TIME 101 9958
TEST_TIME 102 10007
TEST_TIME 103 10009
TEST_TIME 104 9996
TEST_TIME 105 9932
TEST_TIME 106 9946
TEST_TIME 107 9885
TEST_TIME 108 9872
TEST_TIME 109 9836
TEST_TIME 110 9854
TEST_TIME 111 9822
TEST_TIME 112 9785
TEST_TIME 113 9788
TEST_TIME 114 9771
TEST_TIME 115 9756
TEST_TIME 116 9812
TEST_TIME 117 9775
TEST_TIME 118 9812
TEST_TIME 119 9796
TEST_TIME 120 9789

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-08-2021 19:38:48 to 05-08-2021 19:38:51.

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 1629515 1629515 0 0.00 0 34269 6 0 0
doc_list_biud 1629514 1629514 0 0.00 0 51964 30 0 0
sp_add_invoice_to_stock 5384 5253 131 2.43 35 182759 17926 0 131
sp_cancel_adding_invoice 6028 6028 0 0.00 4 79547 873 0 0
sp_cancel_client_order 1869 1869 0 0.00 4 11648 332 0 0
sp_cancel_customer_reserve 591873 591873 0 0.00 1 30780 65 0 0
sp_cancel_pay_from_customer 778 778 0 0.00 2 4300 73 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 7961 83 0 0
sp_cancel_supplier_invoice 6777 6763 14 0.20 2 43066 886 0 14
sp_cancel_supplier_order 446 446 0 0.00 9 1076590 109666 0 0
sp_cancel_write_off 9517 9517 0 0.00 1 18018 129 0 0
sp_client_order 22067 22067 0 0.00 3 16698 331 0 0
sp_customer_reserve 491516 491516 0 0.00 0 86628 176 0 0
sp_fill_shopping_cart 33933 33933 0 0.00 1 21065 35 0 0
sp_fill_shopping_cart_clo_res 491513 491513 0 0.00 0 19793 13 0 0
sp_get_clo_for_invoice 5248 5248 0 0.00 0 29118 273 0 0
sp_kill_cost_storno 16750 16750 0 0.00 0 7956 64 0 0
sp_kill_qstorno_ret_qs2qd 1200472 1200472 0 0.00 0 43025 35 0 0
sp_kill_qty_storno 632491 632491 0 0.00 0 51963 75 0 0
sp_lock_selected_doc 22200 22200 0 0.00 0 12401 5 0 0
sp_make_cost_storno 29776 29776 0 0.00 0 34184 53 0 0
sp_multiply_rows_for_pdistr 29776 29776 0 0.00 0 8793 29 0 0
sp_multiply_rows_for_qdistr 22067 22067 0 0.00 0 9961 142 0 0
sp_pay_from_customer 7565 7565 0 0.00 4 15994 110 0 0
sp_pay_to_supplier 6216 6216 0 0.00 4 34278 123 0 0
sp_payment_common 13781 13781 0 0.00 2 25689 30 0 0
sp_qd_handle_on_cancel_clo 1869 1869 0 0.00 0 11343 304 0 0
sp_qd_handle_on_invoice_upd_sts 11272 11272 0 0.00 0 51963 342 0 0
sp_qd_handle_on_reserve_upd_sts 10751 10751 0 0.00 0 10048 59 0 0
sp_reserve_write_off 10751 10751 0 0.00 6 17547 173 0 0
sp_supplier_invoice 5902 5902 0 0.00 5 87135 3301 0 0
sp_supplier_order 5965 5965 0 0.00 5 28842 1306 0 0
srv_aggregate_perf_data 112 112 0 0.00 2662 257241 21005 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1514 132801 43520 0 0
srv_find_qd_qs_mism 1123511 1123511 0 0.00 0 11956 5 0 0
srv_make_invnt_saldo 580 580 0 0.00 63 108174 1214 0 0
srv_make_money_saldo 856 856 0 0.00 1 2904 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 178298 351025 243400 0 0
t$perf-abend:sp_add_invoice_to_stock 131 131 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5253 5253 0 0.00 0 19444 180 0 0
t$perf-norm:sp_cancel_adding_invoice 441 441 0 0.00 0 2702 83 0 0
t$perf-norm:sp_cancel_client_order 1869 1869 0 0.00 0 3721 14 0 0
t$perf-norm:sp_cancel_customer_reserve 726 726 0 0.00 0 3543 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 778 778 0 0.00 0 1676 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 1263 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 446 446 0 0.00 0 3054 26 0 0
t$perf-norm:sp_cancel_supplier_order 446 446 0 0.00 0 27540 1738 0 0
t$perf-norm:sp_cancel_write_off 1073 1073 0 0.00 0 2807 9 0 0
t$perf-norm:sp_client_order 22067 22067 0 0.00 0 10647 18 0 0
t$perf-norm:sp_customer_reserve 1111 1111 0 0.00 0 4137 10 0 0
t$perf-norm:sp_pay_from_customer 7565 7565 0 0.00 0 6450 8 0 0
t$perf-norm:sp_pay_to_supplier 6216 6216 0 0.00 0 7288 10 0 0
t$perf-norm:sp_reserve_write_off 10752 10752 0 0.00 0 17832 11 0 0
t$perf-norm:sp_supplier_invoice 5902 5902 0 0.00 0 14195 20 0 0
t$perf-norm:sp_supplier_order 5965 5965 0 0.00 0 9543 18 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 204 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 2106 34 0 0
t$perf-norm:srv_make_invnt_saldo 580 580 0 0.00 0 5838 31 0 0
t$perf-norm:srv_make_money_saldo 856 856 0 0.00 0 5394 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10748 10486 262 2.43 0 4725 17 0 262
v_all_customers 39810 39810 0 0.00 0 16 1 0 0
v_all_suppliers 32986 32986 0 0.00 0 84 1 0 0
v_all_wares 44134 44134 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 882 882 0 0.00 0 1877 9 0 0
v_cancel_client_order 3738 3738 0 0.00 0 4165 8 0 0
v_cancel_customer_prepayment 1556 1556 0 0.00 0 4287 9 0 0
v_cancel_customer_reserve 1452 1452 0 0.00 0 1373 6 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 211 3 0 0
v_cancel_supplier_invoice 920 892 28 3.04 0 1389 12 0 28
v_cancel_supplier_order 892 892 0 0.00 0 515 5 0 0
v_cancel_write_off 2146 2146 0 0.00 0 4477 16 0 0
v_max_id_clo_ord 5964 5964 0 0.00 0 975 1 0 0
v_max_id_clo_res 1111 1111 0 0.00 0 1644 9 0 0
v_max_id_ord_sup 5901 5901 0 0.00 0 3774 4 0 0
v_max_non_paid_invoice 1588 1588 0 0.00 0 2098 4 0 0
v_max_non_paid_realizn 4184 4184 0 0.00 0 4983 4 0 0
v_min_id_clo_ord 5964 5964 0 0.00 0 586 4 0 0
v_min_id_clo_res 1111 1111 0 0.00 0 6368 13 0 0
v_min_id_ord_sup 5901 5901 0 0.00 0 5945 6 0 0
v_min_non_paid_invoice 6216 6216 0 0.00 0 25684 22 0 0
v_min_non_paid_realizn 7565 7565 0 0.00 0 15535 20 0 0
v_our_firm 11086 11086 0 0.00 0 20 1 0 0
v_reserve_write_off 21502 21502 0 0.00 0 4598 6 0 0
x_lock_depdocs_on_canc_invoice 12345 12345 0 0.00 0 21032 129 0 0
x_lock_depdocs_on_canc_sup_ord 446 446 0 0.00 0 19979 483 0 0
x_make_qstorno_1000_1200 5965 5965 0 0.00 2 28818 1223 0 0
x_make_qstorno_1200_2000 5902 5902 0 0.00 3 87125 3185 0 0
x_make_qstorno_2100_3300 491516 491516 0 0.00 0 86627 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-08-2021 19:38:52 to 05-08-2021 19:38:56.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:07:49 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:49 23571 8192000000 10367018528 10683158528 1415151872 14.730 100 91 182 0 1450921536 5902816 29866848
17:10:22 22273 8192000000 10427109040 10759462912 1477165536 15.277 100 86 172 0 1513646608 8004864 28476208
17:11:49 18443 8192000000 10467985472 10808610816 1522298480 15.670 100 82 164 1 1557405808 9131904 25975424
17:13:11 13048 8192000000 10551485728 10906992640 1612726624 16.448 100 73 146 0 1643816752 9836688 21253440
17:14:26 8127 8192000000 10616856320 10986180608 1684577920 17.056 100 71 142 1 1716299056 11580640 20140496
17:15:36 5617 8192000000 10698816128 11071160320 1750338000 17.604 100 88 176 1 1788448480 14006960 24103520
17:16:45 2708 8192000000 10744817440 11125350400 1805149184 18.056 100 90 180 0 1844660720 14072720 25438816
17:17:49 2355 8192000000 10780322576 11171074048 1843526368 18.370 100 84 166 1 1880292384 12671296 24094720
17:18:53 1311 8192000000 10819957040 11209043968 1877663552 18.646 100 89 175 0 1915629152 12111824 25853776
17:19:55 2690 8192000000 10806900768 11220410368 1880416464 18.668 100 50 100 0 1905661184 11313152 13931568
17:20:58 6053 8192000000 10811366608 11229564928 1893788384 18.776 100 47 95 2 1917089696 10127328 13173984
17:22:07 5570 8192000000 10810530784 11238449152 1895808032 18.793 100 23 46 1 1908717680 6699008 6210640
17:23:20 24351 8192000000 10813502864 11248197632 1905246800 18.868 100 32 64 0 1919718960 5259648 9212512
17:24:46 6083 8192000000 10832032048 11261276160 1915245216 18.949 100 32 64 2 1931103808 6077904 9780688
17:25:54 2900 8192000000 10848301744 11275751424 1924853200 19.026 100 44 85 3 1948886336 11316784 12716352
17:26:57 28674 8192000000 10792571936 11227860992 1858689696 18.493 98 28 56 2 1872601472 5794880 8116896
17:28:29 7479 8192000000 10782722944 11206922240 1815073424 18.137 96 30 60 1 1829852208 6445280 8333504
17:29:42 5542 8192000000 10752482096 11173056512 1808794864 18.086 98 30 59 2 1823679440 6077728 8806848
17:30:51 9013 8192000000 10726515296 11147706368 1790402240 17.935 98 16 32 0 1800782400 5552752 4827408
17:32:03 64040 8192000000 10711000992 11135766528 1732396208 17.455 95 18 36 0 1742273280 4711520 5165552
17:34:08 8754 8192000000 10767953776 11192934400 1846562800 18.394 100 20 39 1 1857670192 5135808 5971584
17:35:18 12207 8192000000 10797813504 11220205568 1875361200 18.628 100 36 72 1 1891600128 5624352 10614576
17:36:31 62185 8192000000 10809709424 11234762752 1891574848 18.758 100 29 56 1 1905297664 5380480 8342336
17:38:42 13960 8192000000 10844926800 11270225920 1921573376 18.999 100 36 70 2 1940250144 8031376 10645392
17:40:02 13060 8192000000 10896681824 11317772288 1957004736 19.282 100 54 108 0 1985472480 12769008 15698736
17:41:21 40710 8192000000 10898173360 11342262272 1967582400 19.366 100 27 54 1 1989287712 13435984 8269328
17:43:05 14992 8192000000 10925745328 11358924800 1984833712 19.503 100 45 90 2 2016211456 17936512 13441232
17:44:23 9199 8192000000 10933990000 11361751040 1972675200 19.407 99 66 130 1 2014780048 22504992 19599856
17:45:37 3729 8192000000 10947834416 11370516480 1991547200 19.556 100 66 131 2 2032340912 21809296 18984416
17:46:46 16384 8192000000 10949263344 11381710848 1973622960 19.414 99 55 110 1 2024337488 33683536 17030992
17:48:14 5623 8192000000 10957956480 11395317760 1995828368 19.590 100 56 111 1 2050937696 37742288 17367040
17:49:27 4332 8192000000 10960641872 11377504256 1985049328 19.505 100 76 152 0 2043120448 34122048 23949072
17:50:40 1620 8192000000 11006700320 11422900224 2016973120 19.756 100 81 161 3 2082816400 40745056 25098224
17:51:47 8325 8192000000 10993542880 11418513408 2011270896 19.712 100 74 147 2 2079515888 45065408 23179584
17:53:08 2556 8192000000 11008161248 11433738240 1995360016 19.586 99 68 135 2 2069773824 52714624 21699184
17:54:18 3001 8192000000 11003816080 11425562624 2014941456 19.740 100 89 178 0 2096116944 53980144 27195344
17:55:30 4359 8192000000 10963461712 11384717312 1970435824 19.389 100 62 124 0 2040813440 50159344 20218272
17:56:46 2752 8192000000 10993019856 11410018304 1991708496 19.557 99 86 171 3 2068206704 49907392 26590816
17:57:52 1837 8192000000 11007099408 11424325632 2007536752 19.682 100 87 172 1 2087057392 51745936 27774704
17:58:58 1511 8192000000 11015582144 11427614720 2008400992 19.689 100 85 168 0 2092861200 56837072 27623136
18:00:04 1732 8192000000 11002799184 11413602304 1991449312 19.555 100 80 154 1 2071679360 55397232 24832816
18:01:08 11745 8192000000 10913343888 11364188160 1906654848 18.880 97 40 79 2 1971742240 51336832 13750560
18:02:24 16015 8192000000 10943577840 11382857728 1951211072 19.236 100 38 76 1 2025688112 60546624 13930416
18:04:05 30380 8192000000 10925972048 11378520064 1936030848 19.115 99 23 46 0 1998309984 53587824 8691312
18:06:21 61330 8192000000 10872781248 11342286848 1931776992 19.081 100 11 22 0 1974350800 38623712 3950096
18:08:23 32708 8192000000 10882931168 11322073088 1922161008 19.004 98 21 40 2 1935422048 7953296 5307744
18:09:56 84304 8192000000 10884206704 11355533312 1915493392 18.951 98 7 14 0 1933641136 16525120 1622624
18:12:20 80523 8192000000 10911931760 11350577152 1914898128 18.946 97 20 41 0 1928621152 8296000 5427024
18:14:46 24927 8192000000 10935026672 11366764544 1974922960 19.424 100 48 92 1 2000838304 12082832 13832512
18:16:17 93621 8192000000 10920429168 11386331136 1956687888 19.280 99 10 20 1 1978868352 19369744 2810720
18:19:00 132801 8192000000 10900669232 11359301632 1905886128 18.874 96 31 62 0 1922243760 7487296 8870336
18:22:18 39206 8192000000 10935805888 11395579904 1986727616 19.518 100 34 64 1 2008924880 13086032 9111232
18:24:04 32138 8192000000 10936390000 11402653696 2000614752 19.628 100 22 44 0 2023427840 16975264 5837824
18:25:39 22445 8192000000 10945790784 11408523264 2009436864 19.697 100 25 48 1 2029893296 13190560 7265872
18:27:03 21632 8192000000 10933038064 11392708608 1979740400 19.463 99 30 58 2 2005123216 17047408 8335408
18:28:27 28906 8192000000 10934622224 11417636864 1995266400 19.585 100 21 42 2 2018768960 17097072 6405488
18:30:22 66481 8192000000 10927266800 11398828032 1978668480 19.454 99 11 22 1 1990806064 8820256 3317328
18:32:32 41253 8192000000 10908279232 11384152064 1979741712 19.463 100 18 36 0 1995820912 11119456 4959744
18:34:22 83633 8192000000 10902543296 11386359808 1971925184 19.401 100 21 42 1 1996061888 17939760 6196944
18:37:14 57658 8192000000 10903563216 11379073024 1931270272 19.077 97 18 36 0 1953447008 16846512 5330224
18:39:12 76921 8192000000 10910021808 11404292096 1991282800 19.554 100 8 16 0 2009656080 16196656 2176624
18:41:32 64519 8192000000 10866771136 11338297344 1868338688 18.571 95 6 12 2 1880932016 11249872 1343456
18:43:37 65972 8192000000 10884542640 11350061056 1896685456 18.800 97 27 54 2 1915974976 11670688 7618832
18:45:50 11498 8192000000 10898034256 11334221824 1906857552 18.881 98 50 98 0 1931036752 9981200 14198000
18:47:06 62532 8192000000 10871043856 11320913920 1842658768 18.362 95 26 52 0 1862027840 12014256 7354816
18:49:19 60150 8192000000 10862433712 11334557696 1892465600 18.766 98 17 34 0 1911513744 14361680 4686464
18:51:23 73291 8192000000 10884589936 11347390464 1924906480 19.026 99 17 32 0 1944042496 14663648 4472368
18:53:54 109031 8192000000 10891255328 11357474816 1934188544 19.100 99 36 71 1 1961321856 17018640 10114672
18:56:44 78489 8192000000 10893389232 11361800192 1924216784 19.021 98 29 58 0 1951270272 18103904 8949584
18:59:02 29624 8192000000 10866509024 11349516288 1931238976 19.077 99 10 20 3 1943065024 9146560 2679488
19:00:35 37922 8192000000 10884153072 11347894272 1934791376 19.105 99 32 64 0 1952360400 7868928 9700096
19:02:21 58713 8192000000 10883843632 11361492992 1926532848 19.039 98 16 32 0 1947160320 15797568 4829904
19:04:23 121415 8192000000 10900530960 11398250496 1966043616 19.354 100 19 38 0 1986421152 14700272 5677264
19:07:25 36227 8192000000 10906404192 11381641216 1966961280 19.361 99 17 34 0 1981586176 10386688 4238208
19:09:02 79216 8192000000 10904308848 11391660032 1940770336 19.153 97 18 36 2 1958016816 12370192 4876288
19:11:22 27328 8192000000 10926729440 11397394432 1978675184 19.454 99 28 56 0 1994380080 7990928 7713968
19:13:00 24269 8192000000 10941528400 11404750848 1998706544 19.613 100 57 114 2 2024269008 8929184 16633280
19:14:33 126580 8192000000 10901704480 11384598528 1910275152 18.909 96 21 42 1 1929037248 12739312 6022784
19:17:40 76981 8192000000 10915827712 11397300224 1937654064 19.128 97 24 48 1 1963736624 19375360 6707200
19:19:58 62843 8192000000 10898431728 11361595392 1867125856 18.561 94 17 34 1 1880754080 9360448 4267776
19:22:01 99032 8192000000 10887020816 11380236288 1922130320 19.004 97 21 42 0 1937327424 9490080 5707024
19:25:41 62819 8192000000 10887490960 11379986432 1965949504 19.353 100 7 14 0 1986249328 18359072 1940752
19:27:44 75663 8192000000 10867881568 11339907072 1871421408 18.596 96 21 42 1 1886323216 8022528 6879280
19:30:00 52270 8192000000 10822436816 11298942976 1862167552 18.521 96 14 28 0 1876294464 10209984 3916928
19:32:18 59347 8192000000 10852637760 11341488128 1870070624 18.585 96 19 38 0 1887694608 12260768 5363216
19:34:27 31704 8192000000 10869115648 11334311936 1925333328 19.030 99 55 110 1 1950109744 8738688 16037728
19:35:59 24581 8192000000 10887401344 11350032384 1952816304 19.249 100 50 100 2 1976489056 9949168 13723584
19:37:32 28407 8192000000 9403471088 9706180608 549777296 6.289 29 4 8 0 562512480 11952720 782464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-08-2021 19:38:58 to 05-08-2021 19:39:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 131 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-08-2021 19:39:14 to 05-08-2021 19:39:16.

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          249806
MON$OLDEST_ACTIVE               250765
MON$OLDEST_SNAPSHOT             250765
MON$NEXT_TRANSACTION            250765
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                       2055648
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.33487 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33487 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33487 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 ---
:203: 
:204: mscs-test-161.budzdorov.ru    Thu Aug  5 19:38:03 2021
:205:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:206: 
:207: 
:208: mscs-test-161.budzdorov.ru    Thu Aug  5 19:38:06 2021
:209:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:210: 
--- end of diff output ---

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

05.08.21 19:39:17. 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-05 19:39:19 No severe PSQL-related problems occured
05.08.21 19:39:21. End of report.