Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.10.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 15736068 kB
MemAvailable 30674288 kB
Buffers 0 kB
Cached 15936540 kB
SwapCached 3840 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-10-09 19:37:50
DTS_END                         2021-10-09 19:37:50.7870
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/48170
TRN_ID                          244401
ATT_ID                          719
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: 9647.99 19350 1157759
customer order: creation 177.08 332 21249
customer order: refuse 15.95 347 1914
order to supplier: creation 46.98 1256 5637
order to supplier: removal 3.30 115859 396
invoice (draft): creation 47.18 3116 5661
invoice (draft): removal 54.60 902 6552
invoice accept: apply 43.54 17167 5225
invoice accept: cancel 49.92 793 5990
customer reserve: creation 4047.22 168 485666
customer reserve: removal 4849.98 60 581998
realization accept: apply 85.77 171 10292
realization accept: cancel 93.36 106 11203
payment from customer: creation 61.08 129 7329
payment from customer: removal 5.72 77 686
payment to supplier: creation 50.33 127 6040
payment to supplier: removal 3.58 69 430
service: total inventory turnovers 4.88 1139 586
service: total monetary turnovers 6.73 62 808
service: aggregate perf. data 0.78 27173 93
service: refresh index statistics 0.03 217937 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-10-2021 19:38:46 to 09-10-2021 19:38:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6501
TEST_TIME 2 5437
TEST_TIME 3 5092
TEST_TIME 4 5525
TEST_TIME 5 6442
TEST_TIME 6 6256
TEST_TIME 7 6247
TEST_TIME 8 6115
TEST_TIME 9 6375
TEST_TIME 10 6300
TEST_TIME 11 6201
TEST_TIME 12 6105
TEST_TIME 13 6133
TEST_TIME 14 6551
TEST_TIME 15 6503
TEST_TIME 16 6557
TEST_TIME 17 6614
TEST_TIME 18 6570
TEST_TIME 19 6731
TEST_TIME 20 7101
TEST_TIME 21 7562
TEST_TIME 22 8012
TEST_TIME 23 8179
TEST_TIME 24 8123
TEST_TIME 25 8148
TEST_TIME 26 8097
TEST_TIME 27 8552
TEST_TIME 28 8381
TEST_TIME 29 8508
TEST_TIME 30 9346
TEST_TIME 31 10631
TEST_TIME 32 10852
TEST_TIME 33 10841
TEST_TIME 34 10874
TEST_TIME 35 10866
TEST_TIME 36 10827
TEST_TIME 37 10709
TEST_TIME 38 10745
TEST_TIME 39 10568
TEST_TIME 40 10318
TEST_TIME 41 10257
TEST_TIME 42 10278
TEST_TIME 43 10112
TEST_TIME 44 10109
TEST_TIME 45 10097
TEST_TIME 46 10081
TEST_TIME 47 10036
TEST_TIME 48 10079
TEST_TIME 49 9963
TEST_TIME 50 10002
TEST_TIME 51 9880
TEST_TIME 52 9793
TEST_TIME 53 9790
TEST_TIME 54 9810
TEST_TIME 55 9745
TEST_TIME 56 9639
TEST_TIME 57 9674
TEST_TIME 58 9645
TEST_TIME 59 9665
TEST_TIME 60 9658
TEST_TIME 61 9702
TEST_TIME 62 9701
TEST_TIME 63 9834
TEST_TIME 64 9848
TEST_TIME 65 9805
TEST_TIME 66 9840
TEST_TIME 67 9846
TEST_TIME 68 9908
TEST_TIME 69 9903
TEST_TIME 70 9952
TEST_TIME 71 9965
TEST_TIME 72 10041
TEST_TIME 73 9948
TEST_TIME 74 9921
TEST_TIME 75 9830
TEST_TIME 76 9796
TEST_TIME 77 9796
TEST_TIME 78 9749
TEST_TIME 79 9722
TEST_TIME 80 9784
TEST_TIME 81 9724
TEST_TIME 82 9692
TEST_TIME 83 9645
TEST_TIME 84 9566
TEST_TIME 85 9649
TEST_TIME 86 9610
TEST_TIME 87 9579
TEST_TIME 88 9555
TEST_TIME 89 9575
TEST_TIME 90 9624
TEST_TIME 91 9625
TEST_TIME 92 9692
TEST_TIME 93 9660
TEST_TIME 94 9668
TEST_TIME 95 9717
TEST_TIME 96 9700
TEST_TIME 97 9807
TEST_TIME 98 9759
TEST_TIME 99 9846
TEST_TIME 100 9841
TEST_TIME 101 9825
TEST_TIME 102 9927
TEST_TIME 103 9853
TEST_TIME 104 9810
TEST_TIME 105 9767
TEST_TIME 106 9777
TEST_TIME 107 9776
TEST_TIME 108 9754
TEST_TIME 109 9743
TEST_TIME 110 9706
TEST_TIME 111 9676
TEST_TIME 112 9644
TEST_TIME 113 9613
TEST_TIME 114 9649
TEST_TIME 115 9606
TEST_TIME 116 9617
TEST_TIME 117 9664
TEST_TIME 118 9651
TEST_TIME 119 9674
TEST_TIME 120 9652

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-10-2021 19:38:49 to 09-10-2021 19:38:53.

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 1606397 1606397 0 0.00 0 15570 6 0 0
doc_list_biud 1606397 1606397 0 0.00 0 31433 28 0 0
sp_add_invoice_to_stock 5322 5225 97 1.82 33 195083 17167 0 97
sp_cancel_adding_invoice 5990 5990 0 0.00 4 98490 794 0 0
sp_cancel_client_order 1914 1914 0 0.00 4 10989 348 0 0
sp_cancel_customer_reserve 581998 581998 0 0.00 1 31438 61 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 2 5315 78 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 2 7155 69 0 0
sp_cancel_supplier_invoice 6571 6552 19 0.28 1 14874 903 0 19
sp_cancel_supplier_order 396 396 0 0.00 6 1315286 115859 0 0
sp_cancel_write_off 11203 11203 0 0.00 1 9081 106 0 0
sp_client_order 21249 21249 0 0.00 3 31142 333 0 0
sp_customer_reserve 485666 485666 0 0.00 0 57512 169 0 0
sp_fill_shopping_cart 32538 32538 0 0.00 1 8552 34 0 0
sp_fill_shopping_cart_clo_res 485660 485660 0 0.00 0 17226 11 0 0
sp_get_clo_for_invoice 5219 5219 0 0.00 0 31675 255 0 0
sp_kill_cost_storno 18309 18309 0 0.00 0 12628 57 0 0
sp_kill_qstorno_ret_qs2qd 1182147 1182147 0 0.00 0 31406 33 0 0
sp_kill_qty_storno 623564 623564 0 0.00 0 31430 72 0 0
sp_lock_selected_doc 21504 21504 0 0.00 0 9697 5 0 0
sp_make_cost_storno 28880 28880 0 0.00 0 12773 53 0 0
sp_multiply_rows_for_pdistr 28880 28880 0 0.00 0 7158 27 0 0
sp_multiply_rows_for_qdistr 21249 21249 0 0.00 0 10154 138 0 0
sp_pay_from_customer 7329 7329 0 0.00 4 12942 130 0 0
sp_pay_to_supplier 6040 6040 0 0.00 5 11922 127 0 0
sp_payment_common 13369 13369 0 0.00 2 11911 29 0 0
sp_qd_handle_on_cancel_clo 1914 1914 0 0.00 0 10981 315 0 0
sp_qd_handle_on_invoice_upd_sts 11209 11209 0 0.00 0 18541 356 0 0
sp_qd_handle_on_reserve_upd_sts 10292 10292 0 0.00 0 12256 55 0 0
sp_reserve_write_off 10292 10292 0 0.00 6 12373 172 0 0
sp_supplier_invoice 5661 5661 0 0.00 6 59648 3117 0 0
sp_supplier_order 5637 5637 0 0.00 6 48808 1256 0 0
srv_aggregate_perf_data 94 93 1 1.06 3268 469574 27174 0 1
srv_fill_mon_cache_memory 67 67 0 0.00 3465 121538 41025 0 0
srv_find_qd_qs_mism 1107745 1107745 0 0.00 0 15569 5 0 0
srv_make_invnt_saldo 586 586 0 0.00 37 124270 1139 0 0
srv_make_money_saldo 808 808 0 0.00 1 15723 63 0 0
srv_recalc_idx_stat 4 4 0 0.00 84492 299827 217938 0 0
t$perf-abend:sp_add_invoice_to_stock 97 97 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 19 19 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5226 5226 0 0.00 0 22813 218 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 0 12935 168 0 0
t$perf-norm:sp_cancel_client_order 1914 1914 0 0.00 0 3939 14 0 0
t$perf-norm:sp_cancel_customer_reserve 673 673 0 0.00 0 1022 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 4768 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 1223 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 3231 27 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 33101 1954 0 0
t$perf-norm:sp_cancel_write_off 1083 1083 0 0.00 0 3800 13 0 0
t$perf-norm:sp_client_order 21249 21249 0 0.00 0 10781 21 0 0
t$perf-norm:sp_customer_reserve 1044 1044 0 0.00 0 3393 13 0 0
t$perf-norm:sp_pay_from_customer 7329 7329 0 0.00 0 8664 15 0 0
t$perf-norm:sp_pay_to_supplier 6040 6040 0 0.00 0 7275 15 0 0
t$perf-norm:sp_reserve_write_off 10292 10292 0 0.00 0 5800 10 0 0
t$perf-norm:sp_supplier_invoice 5661 5661 0 0.00 0 7104 19 0 0
t$perf-norm:sp_supplier_order 5637 5637 0 0.00 0 9755 21 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 691 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 6623 148 0 0
t$perf-norm:srv_make_invnt_saldo 586 586 0 0.00 0 2469 25 0 0
t$perf-norm:srv_make_money_saldo 808 808 0 0.00 0 2353 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10632 10438 194 1.82 0 7447 17 0 194
v_all_customers 39014 39014 0 0.00 0 111 1 0 0
v_all_suppliers 31470 31470 0 0.00 0 109 1 0 0
v_all_wares 42498 42498 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 2478 10 0 0
v_cancel_client_order 3828 3828 0 0.00 0 4739 7 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 1053 4 0 0
v_cancel_customer_reserve 1346 1346 0 0.00 0 3024 6 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 204 3 0 0
v_cancel_supplier_invoice 798 760 38 4.76 0 2639 24 0 38
v_cancel_supplier_order 792 792 0 0.00 0 3211 16 0 0
v_cancel_write_off 2166 2166 0 0.00 0 3083 15 0 0
v_max_id_clo_ord 5637 5637 0 0.00 0 926 1 0 0
v_max_id_clo_res 1044 1044 0 0.00 0 1236 8 0 0
v_max_id_ord_sup 5652 5652 0 0.00 0 3842 3 0 0
v_max_non_paid_invoice 1594 1594 0 0.00 0 4344 5 0 0
v_max_non_paid_realizn 3788 3788 0 0.00 0 3591 7 0 0
v_min_id_clo_ord 5637 5637 0 0.00 0 3138 7 0 0
v_min_id_clo_res 1044 1044 0 0.00 0 7401 17 0 0
v_min_id_ord_sup 5652 5652 0 0.00 0 8542 9 0 0
v_min_non_paid_invoice 6040 6040 0 0.00 0 11908 15 0 0
v_min_non_paid_realizn 7329 7329 0 0.00 0 6272 25 0 0
v_our_firm 10566 10566 0 0.00 0 18 1 0 0
v_reserve_write_off 20584 20584 0 0.00 0 11591 6 0 0
x_lock_depdocs_on_canc_invoice 12162 12162 0 0.00 0 15743 114 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 12811 356 0 0
x_make_qstorno_1000_1200 5637 5637 0 0.00 2 48798 1182 0 0
x_make_qstorno_1200_2000 5661 5661 0 0.00 3 59638 2996 0 0
x_make_qstorno_2100_3300 485666 485666 0 0.00 0 57509 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-10-2021 19:38:54 to 09-10-2021 19:38:58.

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:54 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:08:56 22619 8192000000 9907015488 10221473792 996549280 10.845 100 85 170 0 1024956976 6264352 22143344
17:10:24 26455 8192000000 9950871952 10277560320 1034058464 11.208 100 84 168 1 1063439840 7986704 21394672
17:11:54 12940 8192000000 9980543264 10312310784 1063368448 11.489 100 79 158 1 1092390064 8832336 20189280
17:13:10 15287 8192000000 10033509264 10382643200 1118852208 12.016 100 76 152 0 1147536720 10286016 18398496
17:14:29 10153 8192000000 10096574176 10456842240 1178026992 12.572 100 78 156 0 1208403472 12435648 17940832
17:15:41 3770 8192000000 10124384976 10495537152 1215899456 12.924 100 74 148 1 1244515248 12739504 15876288
17:16:47 3695 8192000000 10163217600 10540941312 1255963664 13.293 100 84 168 0 1288632352 14686464 17982224
17:17:51 1881 8192000000 10198235568 10579890176 1280364752 13.516 100 91 178 2 1315584592 15076592 20143248
17:18:55 1793 8192000000 10211773664 10597761024 1300230432 13.697 100 87 173 0 1332783232 13344896 19207904
17:20:00 4327 8192000000 10225250384 10616164352 1310714096 13.793 100 96 191 0 1345898448 14049424 21134928
17:21:05 2376 8192000000 10223061056 10622754816 1316938192 13.849 100 62 123 0 1341366400 9418048 15010160
17:22:09 3380 8192000000 10216749424 10629869568 1322177056 13.896 100 26 52 0 1338712336 10517264 6018016
17:23:14 8848 8192000000 10223137312 10641096704 1331918928 13.984 100 22 44 0 1343018112 6471824 4627360
17:24:24 50727 8192000000 10231339472 10654187520 1337473792 14.035 100 25 50 0 1348028688 5374112 5180784
17:26:20 6163 8192000000 10242110560 10660438016 1340030816 14.058 100 31 60 2 1353305568 6464976 6809776
17:27:28 4269 8192000000 10218379904 10637877248 1328034288 13.949 100 33 66 1 1341921744 6095072 7792384
17:28:34 6657 8192000000 10188162992 10591899648 1263251936 13.360 97 20 38 3 1277574496 9980592 4341968
17:29:42 43032 8192000000 10154542992 10555932672 1172674800 12.522 91 37 74 0 1187474672 6235936 8563936
17:31:29 7029 8192000000 10142200256 10543292416 1240440176 13.150 98 24 48 1 1252285008 6117888 5726944
17:32:37 10841 8192000000 10142466352 10548948992 1238345456 13.131 98 25 50 2 1250312416 6374784 5592176
17:33:48 59788 8192000000 10175633536 10577182720 1264592576 13.372 99 39 78 2 1279693696 5690160 9410960
17:35:49 5172 8192000000 10187015360 10596429824 1292433936 13.626 100 42 83 4 1307516512 5383344 9699232
17:36:54 13829 8192000000 10198443184 10616705024 1305312688 13.744 100 21 42 0 1315108080 4960944 4834448
17:38:10 16884 8192000000 10246313296 10652672000 1336398592 14.025 100 49 95 1 1354151104 5920480 11832032
17:39:29 20821 8192000000 10252835632 10672242688 1351119232 14.158 100 60 120 1 1373837776 8943824 13774720
17:40:54 5770 8192000000 10277474928 10690916352 1364177440 14.275 100 62 124 0 1388144560 9453056 14514064
17:42:03 27656 8192000000 10288853904 10713149440 1371994576 14.345 100 43 86 0 1402258368 20693328 9570464
17:43:34 8597 8192000000 10286239312 10710167552 1377807472 14.397 100 46 92 2 1402958960 14529504 10621984
17:44:48 18694 8192000000 10307364352 10728087552 1389560688 14.502 100 55 109 2 1419561472 17368336 12632448
17:46:09 15086 8192000000 10304255056 10727174144 1380122208 14.418 99 51 102 0 1413395072 21201040 12071824
17:47:32 10349 8192000000 10314308224 10738716672 1383678640 14.449 99 56 112 0 1420218368 23230560 13309168
17:48:59 13787 8192000000 10320877936 10744201216 1386204208 14.472 99 55 109 1 1426565600 26976256 13385136
17:50:19 8625 8192000000 10343920080 10758434816 1387294048 14.482 99 68 136 0 1443225600 38735232 17196320
17:51:32 6154 8192000000 10339713824 10761363456 1398464752 14.581 99 56 110 1 1447814256 36064272 13285232
17:52:45 4246 8192000000 10361844704 10780143616 1410553536 14.689 100 72 144 0 1468646064 40468256 17624272
17:53:54 3464 8192000000 10363569840 10774585344 1403825472 14.629 100 77 154 2 1469570976 46903280 18842224
17:55:05 5524 8192000000 10365635936 10776948736 1392682528 14.530 99 80 161 1 1464578576 51016032 20880016
17:56:17 6376 8192000000 10370368256 10776010752 1390063312 14.506 100 83 166 2 1472589088 60756608 21769168
17:57:29 7101 8192000000 10369050272 10779631616 1382120176 14.436 99 78 155 2 1463298816 59818384 21360256
17:58:43 3595 8192000000 10377420304 10785857536 1394396240 14.545 100 82 164 1 1476049776 60370624 21282912
17:59:50 9563 8192000000 10363281904 10790584320 1404578416 14.636 100 46 92 0 1473691408 57065248 12047744
18:01:03 10474 8192000000 10354568768 10788220928 1395462288 14.555 99 31 62 2 1455429136 51096064 8870784
18:02:26 20167 8192000000 10376763376 10804936704 1408804896 14.673 100 32 64 1 1479286192 61093328 9387968
18:03:55 22684 8192000000 10358031488 10791677952 1391872560 14.523 100 33 66 0 1470301632 68657840 9771232
18:05:30 30405 8192000000 10378199376 10816581632 1393375728 14.536 100 28 56 0 1482189456 79935744 8877984
18:07:12 57437 8192000000 10364085984 10831605760 1390653360 14.512 100 30 60 2 1502498016 102853712 8990944
18:09:12 67193 8192000000 10287850304 10741239808 1335157488 14.014 96 7 14 0 1352127248 15857184 1112576
18:11:20 56574 8192000000 10286207584 10727342080 1324510656 13.918 96 5 10 0 1336140752 10667520 962576
18:13:19 92606 8192000000 10292472064 10741985280 1378373024 14.402 100 20 40 1 1394731856 12789728 3569104
18:15:53 79150 8192000000 10305772528 10765729792 1381046368 14.426 99 16 32 2 1397686544 12957344 3682832
18:18:44 38240 8192000000 10302954256 10765119488 1393996864 14.542 100 24 48 0 1413068080 13560368 5510848
18:20:25 72977 8192000000 10322533840 10774720512 1401278320 14.606 100 26 52 0 1418818496 11854368 5685808
18:22:40 54867 8192000000 10305979312 10759254016 1385921872 14.469 99 19 38 1 1398417008 7794736 4700400
18:24:36 27948 8192000000 10308508880 10771165184 1400737104 14.602 100 35 71 1 1423364320 14736656 7890560
18:26:06 25816 8192000000 10315405392 10777890816 1406533824 14.653 100 13 26 0 1416823104 7474672 2814608
18:27:33 38558 8192000000 10300786048 10773762048 1392112800 14.525 99 10 20 0 1405019584 10596384 2310400
18:29:37 58352 8192000000 10302159024 10779344896 1392127200 14.525 99 17 34 0 1409194608 12982048 4085360
18:31:56 40621 8192000000 10279995840 10755014656 1361653632 14.252 98 11 22 0 1374448848 10539872 2255344
18:33:38 60875 8192000000 10281371760 10752364544 1373314864 14.357 99 12 25 1 1389244224 12642160 3287200
18:35:40 51383 8192000000 10277978224 10736025600 1342560240 14.080 97 14 26 1 1355677152 10273232 2843680
18:37:33 25077 8192000000 10287025248 10739810304 1364319344 14.276 99 27 52 1 1378481632 8543328 5618960
18:38:58 49286 8192000000 10298773872 10756521984 1349884960 14.146 97 17 34 0 1364879248 11423088 3571200
18:40:48 45512 8192000000 10285653888 10750238720 1355803024 14.200 98 11 21 2 1372724256 14702176 2219056
18:42:39 35251 8192000000 10284922528 10736295936 1343235520 14.087 98 41 82 0 1364236464 12254896 8746048
18:44:17 53234 8192000000 10273363616 10719461376 1339669008 14.054 98 16 32 1 1357050896 13711296 3670592
18:46:16 45745 8192000000 10286232752 10727878656 1328643440 13.955 97 22 44 0 1346452384 12841648 4967296
18:48:03 89292 8192000000 10266255968 10730360832 1327005056 13.940 98 16 32 0 1348318976 17669792 3644128
18:50:39 121537 8192000000 10228550656 10676916224 1280752432 13.520 95 18 35 0 1293316768 8976736 3587600
18:53:47 46108 8192000000 10264903808 10722816000 1339531344 14.053 98 24 47 0 1359864656 14990144 5343168
18:55:40 32015 8192000000 10273537136 10720333824 1348552160 14.134 99 11 22 2 1363603552 12742560 2308832
18:57:12 54142 8192000000 10264608096 10732855296 1365754896 14.289 100 14 28 1 1386709504 17226512 3728096
18:59:09 52576 8192000000 10276488304 10735239168 1371818896 14.343 100 18 36 0 1389415360 13388624 4207840
19:01:48 19956 8192000000 10278595376 10737512448 1365742368 14.289 100 30 58 1 1394389280 20976608 7670304
19:03:08 53556 8192000000 10258610912 10716438528 1329636496 13.964 97 15 30 0 1342802272 9826896 3338880
19:05:15 71584 8192000000 10254386496 10713407488 1308870144 13.776 96 9 18 0 1330774032 19627392 2276496
19:07:27 59076 8192000000 10292422080 10740359168 1360379280 14.241 99 20 40 0 1379562480 15435056 3748144
19:09:26 63249 8192000000 10289388064 10738159616 1344093920 14.094 97 17 34 1 1357720688 9746080 3880688
19:11:30 15332 8192000000 10320759744 10761203712 1391676064 14.521 100 53 106 3 1416092032 12849008 11566960
19:12:46 57995 8192000000 10322068832 10769063936 1386648928 14.476 99 29 58 0 1410974816 17868576 6457312
19:14:47 71448 8192000000 10319868096 10773360640 1399383232 14.590 100 14 27 1 1420434544 18483552 2567760
19:17:30 54276 8192000000 10310282288 10758627328 1368537872 14.314 98 20 39 1 1386257984 13366192 4353920
19:19:28 100539 8192000000 10286050208 10747273216 1368723968 14.316 99 11 22 0 1386571248 15430496 2416784
19:22:19 56261 8192000000 10264460768 10717274112 1320503360 13.881 97 22 44 1 1344661680 19152736 5005584
19:24:16 28279 8192000000 10257107680 10712510464 1330636464 13.973 97 33 66 0 1347137456 9169088 7331904
19:25:50 36045 8192000000 10267685040 10711576576 1340701408 14.064 98 16 32 0 1354616416 9465104 4449904
19:27:26 62769 8192000000 10267767952 10723004416 1350234752 14.150 98 20 40 1 1367456272 12472512 4749008
19:29:29 85430 8192000000 10252585648 10709237760 1314147664 13.824 96 16 32 2 1333543616 15765008 3630944
19:32:04 52746 8192000000 10263193792 10713706496 1335248960 14.015 98 23 44 2 1348989072 8736688 5003424
19:34:17 48949 8192000000 10234151824 10679824384 1267651104 13.400 93 14 28 0 1278574800 7889312 3034384
19:36:06 62744 8192000000 10242153312 10689839104 1278365664 13.498 95 23 46 0 1295438544 12424832 4648048

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-10-2021 19:39:00 to 09-10-2021 19:39:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 97 v_add_invoice_to_stock 335544517
except 19 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-10-2021 19:39:16 to 09-10-2021 19:39:18.

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          242683
MON$OLDEST_ACTIVE               244952
MON$OLDEST_SNAPSHOT             244952
MON$NEXT_TRANSACTION            244952
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                       2049504
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.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 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 ---
:35: 
:36: mscs-test-161.budzdorov.ru    Sat Oct  9 19:37:53 2021
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:38: 
:39: 
:40: mscs-test-161.budzdorov.ru    Sat Oct  9 19:37:57 2021
:41:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:42: 
:43: 
:44: mscs-test-161.budzdorov.ru    Sat Oct  9 19:37:57 2021
:45:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:46: 
--- end of diff output ---

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

09.10.21 19:39:19. 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-10-09 19:39:21 No severe PSQL-related problems occured
09.10.21 19:39:22. End of report.