Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.11.2021 19:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16678512 kB
MemAvailable 31627824 kB
Buffers 0 kB
Cached 15019736 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.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-11-17 19:37:54
DTS_END                         2021-11-17 19:37:54.6160
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/51478
TRN_ID                          243915
ATT_ID                          725
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: 9448.58 23450 1133829
customer order: creation 172.91 359 20749
customer order: refuse 15.13 347 1816
order to supplier: creation 46.88 1184 5626
order to supplier: removal 3.64 91974 437
invoice (draft): creation 46.36 3182 5563
invoice (draft): removal 53.87 715 6464
invoice accept: apply 42.34 17163 5081
invoice accept: cancel 49.14 684 5897
customer reserve: creation 3887.46 172 466495
customer reserve: removal 4826.13 54 579135
realization accept: apply 84.30 172 10116
realization accept: cancel 90.25 94 10830
payment from customer: creation 59.33 103 7120
payment from customer: removal 5.83 53 700
payment to supplier: creation 49.39 107 5927
payment to supplier: removal 3.58 77 429
service: total inventory turnovers 4.63 1096 555
service: total monetary turnovers 6.59 21 791
service: aggregate perf. data 0.78 23977 94
service: refresh index statistics 0.03 327459 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-11-2021 19:39:23 to 17-11-2021 19:39:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 720
TEST_TIME 2 1788
TEST_TIME 3 2423
TEST_TIME 4 3099
TEST_TIME 5 3455
TEST_TIME 6 3606
TEST_TIME 7 4565
TEST_TIME 8 4610
TEST_TIME 9 4976
TEST_TIME 10 5171
TEST_TIME 11 5635
TEST_TIME 12 5910
TEST_TIME 13 6939
TEST_TIME 14 6948
TEST_TIME 15 7001
TEST_TIME 16 7140
TEST_TIME 17 7595
TEST_TIME 18 7862
TEST_TIME 19 8160
TEST_TIME 20 8370
TEST_TIME 21 8616
TEST_TIME 22 8880
TEST_TIME 23 8937
TEST_TIME 24 9343
TEST_TIME 25 9437
TEST_TIME 26 9711
TEST_TIME 27 10529
TEST_TIME 28 10687
TEST_TIME 29 10547
TEST_TIME 30 10355
TEST_TIME 31 10213
TEST_TIME 32 10379
TEST_TIME 33 10410
TEST_TIME 34 10373
TEST_TIME 35 10485
TEST_TIME 36 10367
TEST_TIME 37 10389
TEST_TIME 38 10245
TEST_TIME 39 10069
TEST_TIME 40 10010
TEST_TIME 41 9916
TEST_TIME 42 9888
TEST_TIME 43 9833
TEST_TIME 44 9801
TEST_TIME 45 9921
TEST_TIME 46 9995
TEST_TIME 47 9915
TEST_TIME 48 9807
TEST_TIME 49 9725
TEST_TIME 50 9665
TEST_TIME 51 9794
TEST_TIME 52 10005
TEST_TIME 53 9921
TEST_TIME 54 9958
TEST_TIME 55 9900
TEST_TIME 56 9951
TEST_TIME 57 10099
TEST_TIME 58 10045
TEST_TIME 59 10028
TEST_TIME 60 10075
TEST_TIME 61 10058
TEST_TIME 62 10023
TEST_TIME 63 10072
TEST_TIME 64 9979
TEST_TIME 65 9935
TEST_TIME 66 9833
TEST_TIME 67 9770
TEST_TIME 68 9870
TEST_TIME 69 9777
TEST_TIME 70 9717
TEST_TIME 71 9688
TEST_TIME 72 9593
TEST_TIME 73 9614
TEST_TIME 74 9549
TEST_TIME 75 9506
TEST_TIME 76 9625
TEST_TIME 77 9596
TEST_TIME 78 9618
TEST_TIME 79 9680
TEST_TIME 80 9626
TEST_TIME 81 9683
TEST_TIME 82 9664
TEST_TIME 83 9670
TEST_TIME 84 9688
TEST_TIME 85 9742
TEST_TIME 86 9753
TEST_TIME 87 9710
TEST_TIME 88 9852
TEST_TIME 89 9883
TEST_TIME 90 9797
TEST_TIME 91 9731
TEST_TIME 92 9691
TEST_TIME 93 9664
TEST_TIME 94 9633
TEST_TIME 95 9622
TEST_TIME 96 9613
TEST_TIME 97 9578
TEST_TIME 98 9577
TEST_TIME 99 9602
TEST_TIME 100 9577
TEST_TIME 101 9574
TEST_TIME 102 9618
TEST_TIME 103 9671
TEST_TIME 104 9638
TEST_TIME 105 9623
TEST_TIME 106 9602
TEST_TIME 107 9706
TEST_TIME 108 9684
TEST_TIME 109 9689
TEST_TIME 110 9679
TEST_TIME 111 9603
TEST_TIME 112 9590
TEST_TIME 113 9580
TEST_TIME 114 9538
TEST_TIME 115 9484
TEST_TIME 116 9478
TEST_TIME 117 9512
TEST_TIME 118 9483
TEST_TIME 119 9461
TEST_TIME 120 9449

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-11-2021 19:39:27 to 17-11-2021 19:39:30.

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 1566272 1566272 0 0.00 0 16092 6 0 0
doc_list_biud 1566267 1566267 0 0.00 0 20586 25 0 0
sp_add_invoice_to_stock 5230 5081 149 2.84 16 161829 17164 0 149
sp_cancel_adding_invoice 5897 5897 0 0.00 6 43422 685 0 0
sp_cancel_client_order 1816 1816 0 0.00 4 16017 347 0 0
sp_cancel_customer_reserve 579135 579135 0 0.00 1 20587 54 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 3 1489 53 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 3 4674 77 0 0
sp_cancel_supplier_invoice 6475 6464 11 0.16 2 13095 715 0 11
sp_cancel_supplier_order 437 437 0 0.00 10 898764 91975 0 0
sp_cancel_write_off 10830 10830 0 0.00 1 9671 95 0 0
sp_client_order 20749 20749 0 0.00 3 16517 359 0 0
sp_customer_reserve 466495 466495 0 0.00 0 106891 172 0 0
sp_fill_shopping_cart 31928 31928 0 0.00 1 17548 36 0 0
sp_fill_shopping_cart_clo_res 466493 466493 0 0.00 0 24001 13 0 0
sp_get_clo_for_invoice 5077 5077 0 0.00 0 17713 274 0 0
sp_kill_cost_storno 17856 17856 0 0.00 0 11556 49 0 0
sp_kill_qstorno_ret_qs2qd 1176001 1176001 0 0.00 0 20579 29 0 0
sp_kill_qty_storno 619771 619771 0 0.00 0 20586 64 0 0
sp_lock_selected_doc 21111 21111 0 0.00 0 9876 5 0 0
sp_make_cost_storno 28240 28240 0 0.00 0 14997 45 0 0
sp_multiply_rows_for_pdistr 28236 28236 0 0.00 0 6089 26 0 0
sp_multiply_rows_for_qdistr 20749 20749 0 0.00 0 14472 166 0 0
sp_pay_from_customer 7120 7120 0 0.00 4 12887 104 0 0
sp_pay_to_supplier 5927 5927 0 0.00 5 7598 107 0 0
sp_payment_common 13044 13044 0 0.00 1 9789 24 0 0
sp_qd_handle_on_cancel_clo 1816 1816 0 0.00 0 16011 319 0 0
sp_qd_handle_on_invoice_upd_sts 10973 10973 0 0.00 0 13745 304 0 0
sp_qd_handle_on_reserve_upd_sts 10116 10116 0 0.00 0 15673 64 0 0
sp_reserve_write_off 10116 10116 0 0.00 6 16068 172 0 0
sp_supplier_invoice 5563 5563 0 0.00 7 76020 3183 0 0
sp_supplier_order 5626 5626 0 0.00 6 34974 1184 0 0
srv_aggregate_perf_data 94 94 0 0.00 2098 352007 23977 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2385 167113 48203 0 0
srv_find_qd_qs_mism 1085937 1085937 0 0.00 0 16091 5 0 0
srv_make_invnt_saldo 555 555 0 0.00 40 29144 1096 0 0
srv_make_money_saldo 791 791 0 0.00 0 725 21 0 0
srv_recalc_idx_stat 4 4 0 0.00 210850 482013 327460 0 0
t$perf-abend:sp_add_invoice_to_stock 149 149 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5081 5081 0 0.00 0 9682 150 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 7663 114 0 0
t$perf-norm:sp_cancel_client_order 1816 1816 0 0.00 0 4919 13 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 3949 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 4527 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 574 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 1378 19 0 0
t$perf-norm:sp_cancel_supplier_order 437 437 0 0.00 0 25410 1555 0 0
t$perf-norm:sp_cancel_write_off 1015 1015 0 0.00 0 2309 8 0 0
t$perf-norm:sp_client_order 20749 20749 0 0.00 0 7278 16 0 0
t$perf-norm:sp_customer_reserve 964 964 0 0.00 0 514 3 0 0
t$perf-norm:sp_pay_from_customer 7120 7120 0 0.00 0 9847 12 0 0
t$perf-norm:sp_pay_to_supplier 5927 5927 0 0.00 0 5314 12 0 0
t$perf-norm:sp_reserve_write_off 10116 10116 0 0.00 0 9668 14 0 0
t$perf-norm:sp_supplier_invoice 5563 5563 0 0.00 0 8864 17 0 0
t$perf-norm:sp_supplier_order 5626 5626 0 0.00 0 5824 14 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 181 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 242 5 0 0
t$perf-norm:srv_make_invnt_saldo 555 555 0 0.00 0 1582 22 0 0
t$perf-norm:srv_make_money_saldo 791 791 0 0.00 0 1543 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10450 10152 298 2.85 0 5920 21 0 298
v_all_customers 37762 37762 0 0.00 0 15 1 0 0
v_all_suppliers 30766 30766 0 0.00 0 21 1 0 0
v_all_wares 41498 41498 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 7097 16 0 0
v_cancel_client_order 3632 3632 0 0.00 0 4996 6 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 1416 5 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 2515 8 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 4663 12 0 0
v_cancel_supplier_invoice 818 796 22 2.68 0 2681 17 0 22
v_cancel_supplier_order 874 874 0 0.00 0 5420 16 0 0
v_cancel_write_off 2030 2030 0 0.00 0 4252 17 0 0
v_max_id_clo_ord 5623 5623 0 0.00 0 472 1 0 0
v_max_id_clo_res 964 964 0 0.00 0 1988 19 0 0
v_max_id_ord_sup 5556 5556 0 0.00 0 5375 4 0 0
v_max_non_paid_invoice 1721 1721 0 0.00 0 361 3 0 0
v_max_non_paid_realizn 3796 3796 0 0.00 0 1719 5 0 0
v_min_id_clo_ord 5623 5623 0 0.00 0 3627 5 0 0
v_min_id_clo_res 964 964 0 0.00 0 6302 15 0 0
v_min_id_ord_sup 5556 5556 0 0.00 0 4630 7 0 0
v_min_non_paid_invoice 5925 5925 0 0.00 0 7468 13 0 0
v_min_non_paid_realizn 7119 7119 0 0.00 0 9783 20 0 0
v_our_firm 10382 10382 0 0.00 0 12 1 0 0
v_reserve_write_off 20232 20232 0 0.00 0 4949 7 0 0
x_lock_depdocs_on_canc_invoice 11963 11963 0 0.00 0 10785 118 0 0
x_lock_depdocs_on_canc_sup_ord 437 437 0 0.00 0 7726 315 0 0
x_make_qstorno_1000_1200 5626 5626 0 0.00 2 34965 1107 0 0
x_make_qstorno_1200_2000 5563 5563 0 0.00 2 76009 3054 0 0
x_make_qstorno_2100_3300 466495 466495 0 0.00 0 106887 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-11-2021 19:39:31 to 17-11-2021 19:39:35.

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:58 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:08:59 18071 8192000000 9907437584 10217373696 985850032 10.741 100 86 172 1 1014731856 5471200 23410624
17:10:20 27819 8192000000 9954608016 10277724160 1035315680 11.220 100 93 186 1 1067686976 7863824 24507472
17:11:52 11610 8192000000 9994059680 10325204992 1071339664 11.565 100 88 176 0 1102239168 8794960 22104544
17:13:07 11599 8192000000 10024215808 10375245824 1114520928 11.975 100 74 148 2 1141279984 9564784 17194272
17:14:22 10458 8192000000 10090258384 10448293888 1170187968 12.499 100 74 148 1 1199067824 12239968 16639888
17:15:34 5942 8192000000 10135098672 10502279168 1221750176 12.978 100 81 162 0 1252347104 12446112 18150816
17:16:42 2991 8192000000 10161189904 10535620608 1246488320 13.206 100 87 174 0 1281058112 14536896 20032896
17:17:49 1106 8192000000 10196239232 10576359424 1280065472 13.514 100 86 173 1 1313821536 15026480 18729584
17:18:51 39267 8192000000 10200715456 10602659840 1298630208 13.683 100 48 96 2 1319178112 9099408 11448496
17:20:35 6314 8192000000 10213840176 10618556416 1314684352 13.829 100 42 85 2 1332191440 7723056 9784032
17:21:42 10146 8192000000 10196970656 10617524224 1316022304 13.841 100 14 28 1 1325127616 6050592 3054720
17:22:53 59273 8192000000 10215927280 10641276928 1322338288 13.898 100 36 72 0 1339855344 9463296 8053760
17:24:54 3954 8192000000 10223858592 10645127168 1333142352 13.996 100 25 50 0 1344126896 5569440 5415104
17:25:58 8241 8192000000 10217710912 10634928128 1307280928 13.761 98 20 40 1 1317480480 5709456 4490096
17:27:08 13333 8192000000 10200302304 10622484480 1295563936 13.655 98 19 37 1 1305619552 5438576 4617040
17:28:23 33690 8192000000 10190040752 10603311104 1269402208 13.416 97 38 76 1 1285217248 6627968 9187072
17:29:57 7313 8192000000 10156787904 10558885888 1242410976 13.168 97 30 60 0 1255655184 5847232 7396976
17:31:05 7395 8192000000 10128988256 10522652672 1196596272 12.745 96 18 35 2 1205597472 5191376 3809824
17:32:14 8613 8192000000 10154631152 10555101184 1249723824 13.236 99 33 67 0 1263269344 5644240 7901280
17:33:25 41294 8192000000 10154939088 10570354688 1259547408 13.326 99 20 38 0 1269435872 5053104 4835360
17:35:09 9374 8192000000 10193953840 10605596672 1299140480 13.687 100 17 35 1 1308960528 5432656 4387392
17:36:21 7436 8192000000 10200868320 10616840192 1308441072 13.772 100 31 62 0 1322114864 5959472 7714320
17:37:30 15032 8192000000 10220924208 10640269312 1335162112 14.014 100 17 33 2 1343110256 4560976 3387168
17:38:48 56298 8192000000 10247888960 10668711936 1348304176 14.132 100 37 74 1 1363644304 6431280 8908848
17:40:48 30889 8192000000 10287162656 10706042880 1371315952 14.339 100 45 88 1 1395037440 13082880 10638608
17:42:31 12373 8192000000 10302538784 10719670272 1384746272 14.459 100 49 98 2 1409996112 13654016 11595824
17:43:51 26157 8192000000 10308541440 10735730688 1395784800 14.557 100 47 94 4 1423646416 15858912 12002704
17:45:21 8826 8192000000 10316911104 10740768768 1398624592 14.583 100 54 108 1 1429852944 18464832 12763520
17:46:32 9982 8192000000 10318658224 10737807360 1386592144 14.475 99 57 114 1 1420412816 20224768 13595904
17:47:45 3493 8192000000 10348220976 10764156928 1410035536 14.684 100 70 138 3 1451222016 24215456 16971024
17:48:56 5386 8192000000 10351963136 10777763840 1417555488 14.751 100 61 121 1 1459855472 27216736 15083248
17:50:09 3956 8192000000 10350710384 10769866752 1407785072 14.664 100 71 142 1 1457209728 32095072 17329584
17:51:19 2384 8192000000 10344626032 10756149248 1379385792 14.411 98 83 164 0 1430654784 31654592 19614400
17:52:27 2801 8192000000 10344214128 10754256896 1391243600 14.517 100 90 179 0 1449235808 35475264 22516944
17:53:33 5740 8192000000 10336764640 10742833152 1380336800 14.420 100 71 140 1 1436926624 39304336 17285488
17:54:44 13541 8192000000 10310862336 10731634688 1357264192 14.213 99 45 90 1 1414567744 45210880 12092672
17:56:04 9310 8192000000 10345547440 10762543104 1387362704 14.482 100 62 124 1 1452253776 48500064 16391008
17:57:36 10505 8192000000 10321110384 10749710336 1360952496 14.246 99 35 70 1 1418336064 47485424 9898144
17:58:52 18205 8192000000 10300893072 10728009728 1357475648 14.215 99 31 63 5 1409722960 43697360 8549952
18:00:13 20948 8192000000 10303469872 10733154304 1348019536 14.130 99 27 54 1 1399573568 44069568 7484464
18:01:40 30175 8192000000 10289111280 10723921920 1337466176 14.035 99 24 48 0 1389578320 45502000 6610144
18:03:22 10437 8192000000 10292532928 10722738176 1353828800 14.182 100 32 65 0 1406309632 43878672 8602160
18:04:44 49757 8192000000 10262629216 10725974016 1351750560 14.163 100 9 18 1 1379912304 26133968 2027776
18:06:38 56956 8192000000 10296408112 10723581952 1348087392 14.130 98 37 73 3 1363977360 7314784 8575184
18:08:37 34767 8192000000 10285073264 10724294656 1359955776 14.237 99 31 62 0 1381208912 14304944 6948192
18:10:13 99050 8192000000 10270864160 10711502848 1315269520 13.834 95 15 30 0 1331314272 12928608 3116144
18:12:53 32926 8192000000 10304372848 10737422336 1381377600 14.429 100 41 82 2 1402481616 11567552 9536464
18:14:28 91337 8192000000 10283969632 10734186496 1370858048 14.335 99 20 40 0 1387895504 12512128 4525328
18:17:14 22408 8192000000 10325968320 10762334208 1380751584 14.423 99 51 100 4 1407504864 14181472 12571808
18:18:45 84892 8192000000 10328243840 10767982592 1396604096 14.565 100 23 46 1 1415686368 14282640 4799632
18:21:11 32033 8192000000 10329347408 10771578880 1404858176 14.638 100 35 70 1 1427276256 14139264 8278816
18:22:43 40939 8192000000 10309724784 10775896064 1400143984 14.596 100 23 46 2 1422791408 17599904 5047520
18:24:27 43731 8192000000 10310901040 10770890752 1407985872 14.666 100 15 30 0 1424452912 12623616 3843424
18:26:12 80638 8192000000 10316384064 10780229632 1407436416 14.661 100 15 30 0 1430929712 18890848 4602448
18:28:39 43870 8192000000 10299870208 10759409664 1371704144 14.342 97 15 29 1 1388732928 13505600 3523184
18:30:26 46597 8192000000 10313911984 10779455488 1405194112 14.641 100 24 48 1 1420742176 9827936 5720128
18:32:23 29845 8192000000 10328275440 10778771456 1397230000 14.570 99 50 99 1 1426354384 17277696 11846688
18:33:54 87263 8192000000 10309250048 10765103104 1341683440 14.073 95 12 24 0 1366102720 21476064 2943216
18:36:21 66223 8192000000 10313611488 10755059712 1353400784 14.178 97 24 48 0 1372339232 13764384 5174064
18:38:38 33537 8192000000 10249856816 10684731392 1279410864 13.508 94 30 60 0 1295583424 9896816 6275744
18:40:12 53751 8192000000 10268092560 10725560320 1362656432 14.261 100 19 38 1 1378730912 12213760 3860720
18:42:17 87702 8192000000 10244277200 10690977792 1293128288 13.633 94 14 28 0 1305335296 8371264 3835744
18:45:00 57069 8192000000 10237903280 10695073792 1311370912 13.799 97 5 10 0 1325793712 13257568 1165232
18:47:03 48384 8192000000 10264488832 10714587136 1356556288 14.206 100 16 31 1 1371494128 11533168 3404672
18:48:51 84819 8192000000 10261372096 10716233728 1312015344 13.804 96 18 36 0 1334081168 17900864 4164960
18:51:26 101551 8192000000 10257284752 10724823040 1351139872 14.158 99 24 48 1 1379293776 21826816 6327088
18:54:16 67871 8192000000 10260419488 10718875648 1340614208 14.063 99 24 48 0 1357694848 11480560 5600080
18:56:37 85019 8192000000 10274114320 10727813120 1352757312 14.172 99 9 18 0 1372420560 17906160 1757088
18:59:11 35069 8192000000 10280738752 10728230912 1359237488 14.231 99 22 44 1 1372406384 8731760 4437136
19:00:49 41682 8192000000 10292521072 10740973568 1361053648 14.247 99 25 50 3 1379440896 12959264 5427984
19:02:36 70043 8192000000 10268186032 10722574336 1346745584 14.118 99 18 36 2 1363247152 12667472 3834096
19:04:47 37775 8192000000 10271869760 10731065344 1353032608 14.175 99 32 64 0 1377778416 17388464 7357344
19:07:00 161330 8192000000 10287217968 10739593216 1356568896 14.207 98 22 44 0 1378580144 16908448 5102800
19:10:57 52054 8192000000 10287731232 10757406720 1390299360 14.509 100 11 22 0 1403340608 10826880 2214368
19:12:49 47792 8192000000 10288076784 10757795840 1394764128 14.548 100 11 22 0 1406436592 8993360 2679104
19:14:37 47759 8192000000 10295953712 10753884160 1390824464 14.513 99 22 44 1 1405960976 10593488 4543024
19:16:31 39610 8192000000 10283283504 10736181248 1345945824 14.111 97 21 42 0 1358408576 7502592 4960160
19:18:10 43203 8192000000 10283624304 10739867648 1370403744 14.331 99 27 54 0 1390142160 13713104 6025312
19:19:55 157727 8192000000 10288900304 10746482688 1346288288 14.114 97 9 18 0 1361685664 13532704 1864672
19:23:33 57818 8192000000 10291403824 10745032704 1376903968 14.389 100 26 52 1 1401627440 18925264 5798208
19:26:01 167113 8192000000 10269334400 10708901888 1242716144 13.171 90 25 50 1 1256729776 8353488 5660144
19:30:33 39050 8192000000 10255782112 10712133632 1345361008 14.106 99 13 26 0 1359262512 10592832 3308672
19:32:14 66689 8192000000 10252671360 10705162240 1334328608 14.006 98 13 26 0 1348340272 10924688 3086976
19:34:46 39986 8192000000 10249622752 10710913024 1342265840 14.078 99 15 31 0 1354816976 9434640 3116496
19:36:36 39515 8192000000 10265529104 10716327936 1349953792 14.147 99 19 37 0 1365539664 11351456 4234416

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-11-2021 19:39:37 to 17-11-2021 19:39:52.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-11-2021 19:39:53 to 17-11-2021 19:39:55.

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          241684
MON$OLDEST_ACTIVE               244576
MON$OLDEST_SNAPSHOT             244576
MON$NEXT_TRANSACTION            244576
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                       2050400
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.33535 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33535 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33535 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33535 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Wed Nov 17 19:37:56 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Wed Nov 17 19:38:01 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Wed Nov 17 19:38:05 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:26: 
--- end of diff output ---

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

17.11.21 19:39:56. 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-11-17 19:39:58 No severe PSQL-related problems occured
17.11.21 19:40:00. End of report.