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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15856944 kB
MemAvailable 30819844 kB
Buffers 0 kB
Cached 15846676 kB
SwapCached 668 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-09-21 22:37:50
DTS_END                         2021-09-21 22:37:50.9300
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53044
TRN_ID                          248610
ATT_ID                          722
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: 9676.24 19024 1161149
customer order: creation 181.25 355 21750
customer order: refuse 15.23 350 1827
order to supplier: creation 48.73 1274 5848
order to supplier: removal 3.61 117835 433
invoice (draft): creation 47.79 3442 5735
invoice (draft): removal 57.10 853 6852
invoice accept: apply 42.98 18064 5157
invoice accept: cancel 51.48 838 6178
customer reserve: creation 3941.78 180 473014
customer reserve: removal 4971.62 67 596594
realization accept: apply 88.18 183 10581
realization accept: cancel 91.95 114 11034
payment from customer: creation 61.74 125 7409
payment from customer: removal 5.83 77 699
payment to supplier: creation 50.62 157 6074
payment to supplier: removal 3.67 88 440
service: total inventory turnovers 5.20 1084 624
service: total monetary turnovers 6.63 35 795
service: aggregate perf. data 0.84 20054 101
service: refresh index statistics 0.03 215310 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 21-09-2021 22:39:26 to 21-09-2021 22:39:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 724
TEST_TIME 2 2479
TEST_TIME 3 3185
TEST_TIME 4 3471
TEST_TIME 5 3545
TEST_TIME 6 3423
TEST_TIME 7 4076
TEST_TIME 8 4601
TEST_TIME 9 4691
TEST_TIME 10 4685
TEST_TIME 11 4772
TEST_TIME 12 5013
TEST_TIME 13 5262
TEST_TIME 14 5408
TEST_TIME 15 5556
TEST_TIME 16 6135
TEST_TIME 17 6254
TEST_TIME 18 6655
TEST_TIME 19 6966
TEST_TIME 20 7437
TEST_TIME 21 7867
TEST_TIME 22 7989
TEST_TIME 23 7871
TEST_TIME 24 8138
TEST_TIME 25 8060
TEST_TIME 26 8142
TEST_TIME 27 8199
TEST_TIME 28 8388
TEST_TIME 29 8889
TEST_TIME 30 9571
TEST_TIME 31 10443
TEST_TIME 32 11331
TEST_TIME 33 11183
TEST_TIME 34 11127
TEST_TIME 35 10912
TEST_TIME 36 10836
TEST_TIME 37 10878
TEST_TIME 38 10765
TEST_TIME 39 10575
TEST_TIME 40 10383
TEST_TIME 41 10425
TEST_TIME 42 10379
TEST_TIME 43 10369
TEST_TIME 44 10216
TEST_TIME 45 10140
TEST_TIME 46 10060
TEST_TIME 47 10134
TEST_TIME 48 10155
TEST_TIME 49 10171
TEST_TIME 50 10133
TEST_TIME 51 10129
TEST_TIME 52 10052
TEST_TIME 53 9988
TEST_TIME 54 9886
TEST_TIME 55 9782
TEST_TIME 56 9698
TEST_TIME 57 9741
TEST_TIME 58 9776
TEST_TIME 59 9742
TEST_TIME 60 9789
TEST_TIME 61 9764
TEST_TIME 62 9732
TEST_TIME 63 9781
TEST_TIME 64 9836
TEST_TIME 65 9854
TEST_TIME 66 9839
TEST_TIME 67 9930
TEST_TIME 68 9880
TEST_TIME 69 9887
TEST_TIME 70 9929
TEST_TIME 71 9868
TEST_TIME 72 10003
TEST_TIME 73 9912
TEST_TIME 74 9858
TEST_TIME 75 9825
TEST_TIME 76 9764
TEST_TIME 77 9801
TEST_TIME 78 9762
TEST_TIME 79 9719
TEST_TIME 80 9664
TEST_TIME 81 9609
TEST_TIME 82 9531
TEST_TIME 83 9605
TEST_TIME 84 9695
TEST_TIME 85 9663
TEST_TIME 86 9646
TEST_TIME 87 9654
TEST_TIME 88 9723
TEST_TIME 89 9730
TEST_TIME 90 9695
TEST_TIME 91 9773
TEST_TIME 92 9870
TEST_TIME 93 9882
TEST_TIME 94 9916
TEST_TIME 95 9921
TEST_TIME 96 9952
TEST_TIME 97 9910
TEST_TIME 98 9823
TEST_TIME 99 9801
TEST_TIME 100 9754
TEST_TIME 101 9746
TEST_TIME 102 9733
TEST_TIME 103 9718
TEST_TIME 104 9697
TEST_TIME 105 9631
TEST_TIME 106 9607
TEST_TIME 107 9634
TEST_TIME 108 9635
TEST_TIME 109 9598
TEST_TIME 110 9629
TEST_TIME 111 9599
TEST_TIME 112 9593
TEST_TIME 113 9638
TEST_TIME 114 9649
TEST_TIME 115 9663
TEST_TIME 116 9717
TEST_TIME 117 9700
TEST_TIME 118 9701
TEST_TIME 119 9698
TEST_TIME 120 9683

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-09-2021 22:39:30 to 21-09-2021 22:39:33.

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 1600199 1600199 0 0.00 0 46860 7 0 0
doc_list_biud 1600199 1600199 0 0.00 0 41156 31 0 0
sp_add_invoice_to_stock 5320 5157 163 3.06 30 231362 18064 0 163
sp_cancel_adding_invoice 6178 6178 0 0.00 5 74386 838 0 0
sp_cancel_client_order 1827 1827 0 0.00 4 20794 351 0 0
sp_cancel_customer_reserve 596594 596594 0 0.00 1 41158 68 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 3 4837 78 0 0
sp_cancel_pay_to_supplier 440 440 0 0.00 3 5016 88 0 0
sp_cancel_supplier_invoice 6871 6852 19 0.27 2 18110 854 0 19
sp_cancel_supplier_order 433 433 0 0.00 5 1190319 117836 0 0
sp_cancel_write_off 11034 11034 0 0.00 1 10507 115 0 0
sp_client_order 21750 21750 0 0.00 3 21215 355 0 0
sp_customer_reserve 473014 473014 0 0.00 0 71452 180 0 0
sp_fill_shopping_cart 33332 33332 0 0.00 1 11461 39 0 0
sp_fill_shopping_cart_clo_res 473013 473013 0 0.00 0 13372 13 0 0
sp_get_clo_for_invoice 5154 5154 0 0.00 0 22090 275 0 0
sp_kill_cost_storno 18351 18351 0 0.00 0 6503 60 0 0
sp_kill_qstorno_ret_qs2qd 1211507 1211507 0 0.00 0 41148 36 0 0
sp_kill_qty_storno 638653 638653 0 0.00 0 41155 78 0 0
sp_lock_selected_doc 21814 21814 0 0.00 0 10112 6 0 0
sp_make_cost_storno 29218 29218 0 0.00 0 46605 65 0 0
sp_multiply_rows_for_pdistr 29218 29218 0 0.00 0 16461 30 0 0
sp_multiply_rows_for_qdistr 21750 21750 0 0.00 0 12934 163 0 0
sp_pay_from_customer 7409 7409 0 0.00 4 15868 126 0 0
sp_pay_to_supplier 6074 6074 0 0.00 4 46977 157 0 0
sp_payment_common 13483 13483 0 0.00 2 16519 45 0 0
sp_qd_handle_on_cancel_clo 1827 1827 0 0.00 0 12746 315 0 0
sp_qd_handle_on_invoice_upd_sts 11332 11332 0 0.00 0 29225 354 0 0
sp_qd_handle_on_reserve_upd_sts 10581 10581 0 0.00 0 13697 58 0 0
sp_reserve_write_off 10581 10581 0 0.00 6 16511 184 0 0
sp_supplier_invoice 5735 5735 0 0.00 5 69686 3443 0 0
sp_supplier_order 5848 5848 0 0.00 5 29253 1275 0 0
srv_aggregate_perf_data 101 101 0 0.00 2545 346573 20055 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1257 148606 44618 0 0
srv_find_qd_qs_mism 1112116 1112116 0 0.00 0 11912 5 0 0
srv_make_invnt_saldo 624 624 0 0.00 45 135274 1085 0 0
srv_make_money_saldo 795 795 0 0.00 1 6986 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 136104 293849 215310 0 0
t$perf-abend:sp_add_invoice_to_stock 163 163 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-norm:sp_add_invoice_to_stock 5157 5157 0 0.00 0 20451 178 0 0
t$perf-norm:sp_cancel_adding_invoice 447 447 0 0.00 0 15469 145 0 0
t$perf-norm:sp_cancel_client_order 1827 1827 0 0.00 0 1633 10 0 0
t$perf-norm:sp_cancel_customer_reserve 743 743 0 0.00 0 3094 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 352 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 440 440 0 0.00 0 965 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 439 439 0 0.00 0 564 13 0 0
t$perf-norm:sp_cancel_supplier_order 433 433 0 0.00 0 26676 1818 0 0
t$perf-norm:sp_cancel_write_off 1051 1051 0 0.00 0 7908 14 0 0
t$perf-norm:sp_client_order 21750 21750 0 0.00 0 7776 16 0 0
t$perf-norm:sp_customer_reserve 1038 1038 0 0.00 0 212 2 0 0
t$perf-norm:sp_pay_from_customer 7409 7409 0 0.00 0 6015 10 0 0
t$perf-norm:sp_pay_to_supplier 6074 6074 0 0.00 0 2860 9 0 0
t$perf-norm:sp_reserve_write_off 10581 10581 0 0.00 0 4235 9 0 0
t$perf-norm:sp_supplier_invoice 5735 5735 0 0.00 0 7105 20 0 0
t$perf-norm:sp_supplier_order 5848 5848 0 0.00 0 10756 22 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 337 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 269 6 0 0
t$perf-norm:srv_make_invnt_saldo 624 624 0 0.00 0 979 26 0 0
t$perf-norm:srv_make_money_saldo 795 795 0 0.00 0 6780 32 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 4 0 0
v_add_invoice_to_stock 10634 10308 326 3.06 0 4502 19 0 326
v_all_customers 39524 39524 0 0.00 0 69 1 0 0
v_all_suppliers 32172 32172 0 0.00 0 16 1 0 0
v_all_wares 43500 43500 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 894 894 0 0.00 0 130 6 0 0
v_cancel_client_order 3654 3654 0 0.00 0 3207 4 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 1098 5 0 0
v_cancel_customer_reserve 1486 1486 0 0.00 0 2049 6 0 0
v_cancel_payment_to_supplier 880 880 0 0.00 0 1707 6 0 0
v_cancel_supplier_invoice 916 878 38 4.14 0 1708 17 0 38
v_cancel_supplier_order 866 866 0 0.00 0 439 5 0 0
v_cancel_write_off 2102 2102 0 0.00 0 4518 14 0 0
v_max_id_clo_ord 5848 5848 0 0.00 0 451 1 0 0
v_max_id_clo_res 1038 1038 0 0.00 0 2258 19 0 0
v_max_id_ord_sup 5734 5734 0 0.00 0 7021 6 0 0
v_max_non_paid_invoice 1570 1570 0 0.00 0 1456 3 0 0
v_max_non_paid_realizn 3948 3948 0 0.00 0 4284 5 0 0
v_min_id_clo_ord 5848 5848 0 0.00 0 11448 13 0 0
v_min_id_clo_res 1038 1038 0 0.00 0 3896 9 0 0
v_min_id_ord_sup 5734 5734 0 0.00 0 8030 10 0 0
v_min_non_paid_invoice 6073 6073 0 0.00 0 16512 39 0 0
v_min_non_paid_realizn 7409 7409 0 0.00 0 15565 29 0 0
v_our_firm 10898 10898 0 0.00 0 49 1 0 0
v_reserve_write_off 21162 21162 0 0.00 0 3603 6 0 0
x_lock_depdocs_on_canc_invoice 12591 12591 0 0.00 0 36474 127 0 0
x_lock_depdocs_on_canc_sup_ord 433 433 0 0.00 0 6032 366 0 0
x_make_qstorno_1000_1200 5848 5848 0 0.00 1 25708 1188 0 0
x_make_qstorno_1200_2000 5735 5735 0 0.00 2 69490 3304 0 0
x_make_qstorno_2100_3300 473014 473014 0 0.00 0 71444 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-09-2021 22:39:34 to 21-09-2021 22:39:38.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:07:54 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:55 27079 8192000000 10378330032 10698305536 1430677824 14.867 100 85 170 0 1464731024 6593328 27459872
20:10:28 17489 8192000000 10458725232 10790965248 1510749184 15.570 100 95 189 0 1549011344 8864208 29397952
20:11:52 19882 8192000000 10488900608 10832445440 1549384448 15.905 100 86 172 0 1586697072 10124624 27188000
20:13:24 14072 8192000000 10568011504 10922504192 1623305760 16.538 100 80 160 0 1658942832 11916832 23720240
20:14:42 9208 8192000000 10639531744 11006992384 1700790480 17.192 100 87 174 0 1738513120 11626240 26096400
20:15:54 4555 8192000000 10706656256 11086491648 1769573568 17.763 100 79 156 2 1803622272 12071904 21976800
20:17:03 9952 8192000000 10749890144 11136610304 1815523168 18.141 100 81 160 2 1851996160 14771616 21701376
20:18:14 1974 8192000000 10797762976 11187896320 1858655088 18.492 100 87 174 0 1897275584 13092896 25527600
20:19:18 2463 8192000000 10795190480 11195867136 1865681248 18.549 100 51 102 1 1889842656 9578688 14582720
20:20:22 47112 8192000000 10816707776 11221929984 1880585280 18.670 100 53 108 0 1903080848 5700544 16795024
20:22:09 3320 8192000000 10808422256 11227095040 1888459856 18.733 100 37 71 5 1906801008 7417792 10923360
20:23:13 6915 8192000000 10808123664 11239403520 1894087984 18.779 100 20 40 1 1905582912 5935968 5558960
20:24:21 8014 8192000000 10822218784 11253911552 1903136224 18.852 100 22 44 2 1919224096 10093376 5994496
20:25:32 29367 8192000000 10855268736 11256848384 1912768960 18.929 100 76 155 2 1939908752 4608832 22530960
20:27:02 4890 8192000000 10809534656 11244285952 1900183712 18.828 100 37 74 1 1917481872 6031744 11266416
20:28:08 9680 8192000000 10767640656 11194970112 1823983280 18.210 98 21 42 1 1836141680 6304944 5853456
20:29:18 68786 8192000000 10721837664 11143954432 1692347152 17.121 92 31 63 2 1706847104 5093808 9406144
20:31:29 8617 8192000000 10696174080 11106791424 1732336176 17.455 96 21 42 0 1749253008 10490768 6426064
20:32:38 7965 8192000000 10740778240 11167924224 1830124032 18.260 100 29 58 1 1848137952 9803664 8210256
20:33:47 8587 8192000000 10769288880 11189149696 1844820320 18.380 100 16 31 2 1854083712 5250976 4012416
20:34:57 30372 8192000000 10757146608 11185709056 1838176368 18.326 99 25 50 0 1850368608 5776336 6415904
20:36:33 5858 8192000000 10789184064 11221004288 1874612128 18.622 100 21 42 0 1887324528 6119632 6592768
20:37:39 10000 8192000000 10818763936 11246813184 1905142672 18.868 100 26 52 3 1917667264 5088480 7436112
20:38:58 19208 8192000000 10854926368 11277012992 1926151712 19.036 100 44 87 1 1945355040 7378432 11824896
20:40:22 22248 8192000000 10870864752 11299184640 1939337248 19.141 100 31 63 0 1959545472 10656096 9552128
20:41:47 44357 8192000000 10881925232 11312128000 1932732144 19.089 99 26 52 0 1953010112 12569584 7708384
20:43:38 23338 8192000000 10910894288 11341025280 1968819872 19.376 100 56 112 0 2004175776 19544352 15811552
20:45:07 21692 8192000000 10915828592 11345743872 1969694816 19.383 100 48 96 0 2004931568 20957392 14279360
20:46:34 2817 8192000000 10925238480 11351371776 1963027552 19.330 99 60 117 1 2002119408 21867712 17224144
20:47:42 4563 8192000000 10948868944 11376988160 1995172304 19.585 100 62 124 0 2037106656 24142000 17792352
20:48:51 7028 8192000000 10951264384 11377319936 1973075632 19.410 98 62 121 0 2019472992 28626240 17771120
20:50:03 4939 8192000000 10976544192 11400290304 2006802960 19.676 100 70 140 1 2062993584 35160848 21029776
20:51:14 2244 8192000000 10982346336 11398631424 1999345488 19.618 100 87 174 0 2065859952 40501120 26013344
20:52:19 4454 8192000000 10948801920 11366617088 1960460016 19.310 98 79 158 3 2029452864 43979280 25013568
20:53:28 5907 8192000000 10983249680 11411124224 2002837424 19.645 100 58 114 1 2068014768 46941008 18236336
20:54:50 3445 8192000000 10994487072 11406766080 1992348912 19.562 100 89 177 3 2074041360 53812320 27880128
20:56:01 2942 8192000000 10983948848 11394158592 1978691424 19.454 99 89 177 3 2061446464 54855808 27899232
20:57:08 1255 8192000000 10968882640 11384225792 1966869344 19.361 100 75 150 1 2048710128 58371136 23469648
20:58:18 2304 8192000000 11015953776 11430825984 2007312160 19.680 100 89 178 2 2093156624 58188400 27656064
20:59:29 1536 8192000000 10998149248 11410644992 1995219040 19.585 100 90 175 2 2078606352 56012736 27374576
21:00:34 4243 8192000000 11006272736 11432583168 2010297888 19.704 100 59 118 0 2087927504 57721712 19907904
21:01:39 18100 8192000000 10976786800 11415437312 1973212464 19.411 99 45 89 0 2054221648 66006800 15002384
21:03:03 9840 8192000000 11020266992 11462217728 2019315616 19.775 100 50 100 1 2107488080 70882656 17289808
21:04:39 4610 8192000000 11022966512 11468374016 2006047408 19.670 100 46 92 1 2108734928 86265120 16422400
21:05:45 13802 8192000000 10989310272 11442507776 1980260352 19.467 100 37 74 2 2073845728 80175072 13410304
21:07:56 28315 8192000000 10941471600 11393339392 1935699744 19.112 99 23 46 1 2008882704 64505520 8677440
21:09:26 148606 8192000000 10876782240 11341770752 1843525104 18.369 94 7 14 0 1865331152 20297120 1508928
21:12:55 72647 8192000000 10913498176 11373891584 1952335696 19.245 99 38 72 1 1976736448 14447264 9953488
21:15:16 134497 8192000000 10921214080 11357970432 1923656864 19.016 97 28 55 0 1940148144 8843280 7648000
21:18:34 33002 8192000000 10933752160 11374202880 1975880880 19.432 100 38 76 2 1999172720 12553456 10738384
21:20:08 42189 8192000000 10921329168 11373899776 1924377056 19.022 97 28 56 0 1942748064 10346048 8024960
21:21:56 52965 8192000000 10915600880 11361619968 1946275600 19.197 98 44 88 1 1968239920 9288880 12675440
21:23:50 44690 8192000000 10904545984 11383488512 1979304640 19.459 100 17 34 0 1998391488 13345712 5741136
21:25:38 52695 8192000000 10931480096 11402235904 1991839280 19.558 100 15 29 0 2013442032 17167920 4434832
21:27:31 45858 8192000000 10916907472 11397120000 1996450976 19.595 100 8 16 0 2009382256 10440112 2491168
21:29:18 48323 8192000000 10915705888 11387084800 1986267120 19.514 100 14 28 0 1999046160 8343664 4435376
21:31:08 93606 8192000000 10901488496 11390337024 1972195168 19.403 99 17 34 0 1987017360 9772896 5049296
21:33:58 47456 8192000000 10903260672 11385409536 1971992864 19.401 99 14 29 0 1986282768 11269072 3020832
21:35:46 65939 8192000000 10903295840 11383308288 1933328880 19.093 97 8 14 1 1946225680 11043856 1852944
21:37:52 39420 8192000000 10893475392 11357618176 1921345920 18.998 97 16 32 0 1936039632 10752304 3941408
21:39:37 23327 8192000000 10871060704 11326468096 1912422736 18.926 98 37 74 2 1932680896 10400448 9857712
21:41:04 46516 8192000000 10870543168 11343622144 1927747904 19.049 99 18 36 1 1944847296 12369552 4729840
21:42:52 42423 8192000000 10867813056 11323781120 1885744384 18.711 97 21 42 0 1906373232 14478128 6150720
21:44:37 52864 8192000000 10891126624 11345690624 1941236416 19.157 99 31 62 1 1957035360 7912464 7886480
21:46:45 35389 8192000000 10893780368 11358048256 1938637424 19.136 99 27 54 0 1959994560 13875504 7481632
21:48:26 71211 8192000000 10877024992 11349745664 1884643984 18.703 96 19 38 1 1910314112 20042784 5627344
21:50:41 108337 8192000000 10853701488 11326676992 1838078880 18.325 94 30 60 0 1857417888 10209136 9129872
21:53:34 33775 8192000000 10854537104 11305328640 1912515888 18.927 100 39 78 1 1936157152 12578224 11063040
21:55:12 54439 8192000000 10863054576 11334045696 1907212640 18.884 98 19 39 2 1923822832 11450816 5159376
21:57:16 90553 8192000000 10873009728 11352727552 1914692240 18.944 98 13 26 0 1933943104 14850816 4400048
22:00:20 50027 8192000000 10894180752 11357016064 1922407888 19.006 98 18 36 1 1945307728 17881168 5018672
22:02:10 30916 8192000000 10885286288 11357184000 1955089648 19.267 100 30 59 0 1974675792 11056800 8529344
22:03:42 46444 8192000000 10879298096 11359416320 1936044592 19.115 99 18 36 1 1954664320 13392176 5227552
22:05:34 57390 8192000000 10902403840 11378380800 1947401360 19.206 99 26 52 0 1965363984 10564448 7398176
22:07:32 46194 8192000000 10916137328 11395883008 1967617968 19.367 99 37 75 1 1993045552 15794512 9633072
22:09:34 129705 8192000000 10904374288 11388530688 1950150784 19.228 98 11 22 2 1970664032 17516928 2996320
22:12:44 104969 8192000000 10914685152 11394695168 1942801040 19.169 98 4 8 0 1960268320 16323568 1143712
22:15:32 82768 8192000000 10921163728 11393699840 1953685824 19.256 98 34 68 0 1977790544 14603440 9501280
22:18:19 31939 8192000000 10899053712 11385188352 1967023920 19.362 99 18 36 0 1984166832 12049136 5093776
22:19:58 66613 8192000000 10908288368 11390681088 1985286832 19.507 100 14 28 0 1997742288 8703808 3751648
22:22:11 97939 8192000000 10890582304 11372085248 1919517872 18.983 96 23 45 0 1934955760 9037296 6400592
22:25:39 60615 8192000000 10925786320 11391426560 1964631552 19.343 99 26 51 3 1979338864 7944416 6762896
22:27:43 52891 8192000000 10877166528 11352895488 1862895872 18.527 94 14 28 2 1878214944 10961296 4357776
22:29:36 94669 8192000000 10845539504 11322052608 1817189504 18.155 93 20 41 0 1844257712 21179456 5888752
22:32:18 20981 8192000000 10897965872 11361042432 1950819312 19.233 100 28 56 1 1967172304 8723248 7629744
22:33:45 101882 8192000000 10896751360 11364339712 1852761568 18.445 94 17 34 2 1870286896 13079536 4445792
22:36:27 45604 8192000000 10904931824 11360256000 1886610000 18.718 96 38 71 1 1905137744 8861584 9666160

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-09-2021 22:39:40 to 21-09-2021 22:39:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 163 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 21-09-2021 22:39:56 to 21-09-2021 22:39:58.

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          247034
MON$OLDEST_ACTIVE               248800
MON$OLDEST_SNAPSHOT             248800
MON$NEXT_TRANSACTION            248800
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                       2053728
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.33501 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33501 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33501 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33501 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 ---
:28: 
:29: mscs-test-161.budzdorov.ru    Tue Sep 21 22:37:55 2021
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Tue Sep 21 22:38:00 2021
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Tue Sep 21 22:38:01 2021
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:39: 
--- end of diff output ---

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

21.09.21 22:39:59. 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-09-21 22:40:01 No severe PSQL-related problems occured
21.09.21 22:40:02. End of report.