Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.09.2021 16: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 16208120 kB
MemAvailable 30885656 kB
Buffers 0 kB
Cached 15465492 kB
SwapCached 336 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-03 16:37:49
DTS_END                         2021-09-03 16:37:49.6860
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/49088
TRN_ID                          248570
ATT_ID                          669
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: 9612.12 18867 1153454
customer order: creation 186.32 345 22358
customer order: refuse 15.69 313 1883
order to supplier: creation 49.42 1217 5930
order to supplier: removal 3.84 86632 461
invoice (draft): creation 48.01 3075 5761
invoice (draft): removal 54.62 698 6554
invoice accept: apply 45.24 17282 5429
invoice accept: cancel 48.76 706 5851
customer reserve: creation 4053.21 178 486385
customer reserve: removal 4799.12 54 575894
realization accept: apply 90.34 172 10841
realization accept: cancel 79.72 121 9566
payment from customer: creation 62.99 110 7559
payment from customer: removal 6.34 87 761
payment to supplier: creation 51.96 112 6235
payment to supplier: removal 3.63 85 435
service: total inventory turnovers 5.08 917 609
service: total monetary turnovers 7.07 22 848
service: aggregate perf. data 0.75 25637 90
service: refresh index statistics 0.03 239570 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-09-2021 16:38:49 to 03-09-2021 16:38:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1952
TEST_TIME 2 3137
TEST_TIME 3 4139
TEST_TIME 4 4306
TEST_TIME 5 4952
TEST_TIME 6 4928
TEST_TIME 7 4998
TEST_TIME 8 5238
TEST_TIME 9 5482
TEST_TIME 10 5604
TEST_TIME 11 5974
TEST_TIME 12 6125
TEST_TIME 13 6592
TEST_TIME 14 6775
TEST_TIME 15 7428
TEST_TIME 16 7708
TEST_TIME 17 8085
TEST_TIME 18 8222
TEST_TIME 19 8504
TEST_TIME 20 8937
TEST_TIME 21 9367
TEST_TIME 22 9443
TEST_TIME 23 9644
TEST_TIME 24 10167
TEST_TIME 25 10935
TEST_TIME 26 10963
TEST_TIME 27 10941
TEST_TIME 28 10784
TEST_TIME 29 11035
TEST_TIME 30 10871
TEST_TIME 31 10551
TEST_TIME 32 10456
TEST_TIME 33 10350
TEST_TIME 34 10242
TEST_TIME 35 10253
TEST_TIME 36 10150
TEST_TIME 37 10326
TEST_TIME 38 10264
TEST_TIME 39 10148
TEST_TIME 40 10027
TEST_TIME 41 9894
TEST_TIME 42 9903
TEST_TIME 43 10152
TEST_TIME 44 10093
TEST_TIME 45 10110
TEST_TIME 46 10038
TEST_TIME 47 10128
TEST_TIME 48 10209
TEST_TIME 49 10119
TEST_TIME 50 10253
TEST_TIME 51 10155
TEST_TIME 52 10090
TEST_TIME 53 10380
TEST_TIME 54 10329
TEST_TIME 55 10392
TEST_TIME 56 10222
TEST_TIME 57 10270
TEST_TIME 58 10145
TEST_TIME 59 10062
TEST_TIME 60 10118
TEST_TIME 61 10061
TEST_TIME 62 10026
TEST_TIME 63 9928
TEST_TIME 64 9819
TEST_TIME 65 9832
TEST_TIME 66 9798
TEST_TIME 67 9931
TEST_TIME 68 9896
TEST_TIME 69 9912
TEST_TIME 70 9926
TEST_TIME 71 9914
TEST_TIME 72 9873
TEST_TIME 73 9813
TEST_TIME 74 9914
TEST_TIME 75 9946
TEST_TIME 76 9955
TEST_TIME 77 9957
TEST_TIME 78 9954
TEST_TIME 79 9896
TEST_TIME 80 9814
TEST_TIME 81 9856
TEST_TIME 82 9803
TEST_TIME 83 9799
TEST_TIME 84 9751
TEST_TIME 85 9719
TEST_TIME 86 9673
TEST_TIME 87 9592
TEST_TIME 88 9539
TEST_TIME 89 9654
TEST_TIME 90 9643
TEST_TIME 91 9664
TEST_TIME 92 9667
TEST_TIME 93 9698
TEST_TIME 94 9715
TEST_TIME 95 9701
TEST_TIME 96 9724
TEST_TIME 97 9747
TEST_TIME 98 9743
TEST_TIME 99 9783
TEST_TIME 100 9770
TEST_TIME 101 9767
TEST_TIME 102 9795
TEST_TIME 103 9810
TEST_TIME 104 9731
TEST_TIME 105 9771
TEST_TIME 106 9705
TEST_TIME 107 9684
TEST_TIME 108 9663
TEST_TIME 109 9624
TEST_TIME 110 9600
TEST_TIME 111 9624
TEST_TIME 112 9599
TEST_TIME 113 9580
TEST_TIME 114 9556
TEST_TIME 115 9549
TEST_TIME 116 9589
TEST_TIME 117 9593
TEST_TIME 118 9596
TEST_TIME 119 9575
TEST_TIME 120 9612

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-09-2021 16:38:52 to 03-09-2021 16:38:55.

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 1605842 1605842 0 0.00 0 17214 6 0 0
doc_list_biud 1605842 1605842 0 0.00 0 98326 25 0 0
sp_add_invoice_to_stock 5545 5429 116 2.09 53 167828 17283 0 116
sp_cancel_adding_invoice 5851 5851 0 0.00 5 63796 706 0 0
sp_cancel_client_order 1883 1883 0 0.00 5 10081 314 0 0
sp_cancel_customer_reserve 575894 575894 0 0.00 1 63430 55 0 0
sp_cancel_pay_from_customer 761 761 0 0.00 3 4682 88 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 3 8677 85 0 0
sp_cancel_supplier_invoice 6560 6554 6 0.09 1 10441 698 0 6
sp_cancel_supplier_order 461 461 0 0.00 7 881200 86632 0 0
sp_cancel_write_off 9566 9566 0 0.00 1 98329 121 0 0
sp_client_order 22358 22358 0 0.00 3 16909 345 0 0
sp_customer_reserve 486385 486385 0 0.00 0 107946 179 0 0
sp_fill_shopping_cart 34037 34037 0 0.00 1 14798 36 0 0
sp_fill_shopping_cart_clo_res 486383 486383 0 0.00 0 19745 13 0 0
sp_get_clo_for_invoice 5427 5427 0 0.00 0 44371 297 0 0
sp_kill_cost_storno 16613 16613 0 0.00 0 13904 55 0 0
sp_kill_qstorno_ret_qs2qd 1168369 1168369 0 0.00 0 98325 30 0 0
sp_kill_qty_storno 616477 616477 0 0.00 0 98326 64 0 0
sp_lock_selected_doc 22417 22417 0 0.00 0 5889 5 0 0
sp_make_cost_storno 30062 30062 0 0.00 0 10600 47 0 0
sp_multiply_rows_for_pdistr 30062 30062 0 0.00 0 6732 26 0 0
sp_multiply_rows_for_qdistr 22358 22358 0 0.00 0 12611 155 0 0
sp_pay_from_customer 7559 7559 0 0.00 4 15589 110 0 0
sp_pay_to_supplier 6235 6235 0 0.00 5 13401 113 0 0
sp_payment_common 13794 13794 0 0.00 1 15581 26 0 0
sp_qd_handle_on_cancel_clo 1883 1883 0 0.00 0 10071 293 0 0
sp_qd_handle_on_invoice_upd_sts 11278 11278 0 0.00 0 17398 282 0 0
sp_qd_handle_on_reserve_upd_sts 10841 10841 0 0.00 0 23416 67 0 0
sp_reserve_write_off 10841 10841 0 0.00 7 23462 173 0 0
sp_supplier_invoice 5761 5761 0 0.00 6 87100 3076 0 0
sp_supplier_order 5930 5930 0 0.00 5 25876 1218 0 0
srv_aggregate_perf_data 90 90 0 0.00 3342 623540 25638 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2153 161894 45817 0 0
srv_find_qd_qs_mism 1103671 1103671 0 0.00 0 17214 6 0 0
srv_make_invnt_saldo 609 609 0 0.00 38 22257 918 0 0
srv_make_money_saldo 848 848 0 0.00 1 809 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 118120 352736 239571 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5429 5429 0 0.00 0 10480 133 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 1 5568 71 0 0
t$perf-norm:sp_cancel_client_order 1883 1883 0 0.00 0 2457 10 0 0
t$perf-norm:sp_cancel_customer_reserve 755 755 0 0.00 0 4708 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 761 761 0 0.00 0 668 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 3342 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 445 445 0 0.00 0 1515 11 0 0
t$perf-norm:sp_cancel_supplier_order 461 461 0 0.00 0 32754 1621 0 0
t$perf-norm:sp_cancel_write_off 970 970 0 0.00 0 2895 10 0 0
t$perf-norm:sp_client_order 22358 22358 0 0.00 0 25656 16 0 0
t$perf-norm:sp_customer_reserve 1088 1088 0 0.00 0 5204 11 0 0
t$perf-norm:sp_pay_from_customer 7559 7559 0 0.00 0 10437 11 0 0
t$perf-norm:sp_pay_to_supplier 6235 6235 0 0.00 0 7960 14 0 0
t$perf-norm:sp_reserve_write_off 10841 10841 0 0.00 0 5653 10 0 0
t$perf-norm:sp_supplier_invoice 5761 5761 0 0.00 0 2322 10 0 0
t$perf-norm:sp_supplier_order 5930 5930 0 0.00 0 5741 16 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 932 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 309 7 0 0
t$perf-norm:srv_make_invnt_saldo 609 609 0 0.00 0 2913 16 0 0
t$perf-norm:srv_make_money_saldo 848 848 0 0.00 0 4035 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 4 0 0
v_add_invoice_to_stock 11086 10854 232 2.09 0 4513 19 0 232
v_all_customers 40528 40528 0 0.00 0 25 1 0 0
v_all_suppliers 32726 32726 0 0.00 0 26 1 0 0
v_all_wares 44716 44716 0 0.00 0 38 1 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 4874 13 0 0
v_cancel_client_order 3766 3766 0 0.00 0 2361 4 0 0
v_cancel_customer_prepayment 1522 1522 0 0.00 0 4561 20 0 0
v_cancel_customer_reserve 1510 1510 0 0.00 0 2147 6 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 1660 6 0 0
v_cancel_supplier_invoice 902 890 12 1.33 0 1996 19 0 12
v_cancel_supplier_order 922 922 0 0.00 0 1370 8 0 0
v_cancel_write_off 1940 1940 0 0.00 0 4084 14 0 0
v_max_id_clo_ord 5930 5930 0 0.00 0 11458 3 0 0
v_max_id_clo_res 1088 1088 0 0.00 0 1506 7 0 0
v_max_id_ord_sup 5749 5749 0 0.00 0 1403 2 0 0
v_max_non_paid_invoice 1550 1550 0 0.00 0 737 3 0 0
v_max_non_paid_realizn 4026 4026 0 0.00 0 6168 6 0 0
v_min_id_clo_ord 5930 5930 0 0.00 0 1662 5 0 0
v_min_id_clo_res 1088 1088 0 0.00 0 1687 5 0 0
v_min_id_ord_sup 5749 5749 0 0.00 0 5204 6 0 0
v_min_non_paid_invoice 6235 6235 0 0.00 0 13378 15 0 0
v_min_non_paid_realizn 7559 7559 0 0.00 0 9721 17 0 0
v_our_firm 11254 11254 0 0.00 0 14 1 0 0
v_reserve_write_off 21682 21682 0 0.00 0 4467 5 0 0
x_lock_depdocs_on_canc_invoice 11960 11960 0 0.00 0 14654 107 0 0
x_lock_depdocs_on_canc_sup_ord 461 461 0 0.00 0 3407 264 0 0
x_make_qstorno_1000_1200 5930 5930 0 0.00 2 25865 1145 0 0
x_make_qstorno_1200_2000 5761 5761 0 0.00 3 87088 2939 0 0
x_make_qstorno_2100_3300 486385 486385 0 0.00 0 107945 163 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 03-09-2021 16:38:56 to 03-09-2021 16:39:01.

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
14:07:53 29 8192000000 8826229344 8844451840 25191168 0.306 6 5 6 0 25551056 98896 260992
14:08:54 1597 8192000000 10366200672 10684399616 1427565664 14.840 100 86 172 0 1459791088 5669152 26556272
14:10:02 15350 8192000000 10432020832 10757513216 1486741616 15.360 100 95 190 0 1526167360 8717984 30707760
14:11:24 24087 8192000000 10480725168 10818756608 1527510848 15.715 100 86 172 2 1564470736 10136416 26823472
14:12:56 15724 8192000000 10557416656 10908618752 1601421792 16.352 100 86 171 0 1643040976 16122288 25496896
14:14:17 11220 8192000000 10611625792 10977955840 1673030016 16.959 100 77 154 0 1709033264 12291392 23711856
14:15:32 8467 8192000000 10667739808 11048443904 1733642032 17.466 100 73 146 2 1767441184 12687424 21111728
14:16:42 3180 8192000000 10726824512 11109027840 1788999376 17.924 100 87 171 1 1824798704 12599584 23199744
14:17:47 2267 8192000000 10772898880 11155599360 1822875360 18.201 100 83 164 1 1858721152 13202928 22642864
14:18:50 1530 8192000000 10808783264 11194970112 1861589520 18.516 100 95 188 1 1901863440 13446512 26827408
14:19:53 10006 8192000000 10769891936 11191844864 1861527552 18.516 100 29 58 0 1876199360 5600656 9071152
14:21:03 6275 8192000000 10791329808 11216023552 1884613888 18.702 100 21 43 1 1896153408 5438496 6101024
14:22:11 9101 8192000000 10795658896 11232763904 1895440128 18.790 100 21 42 0 1907555824 6174160 5941536
14:23:21 62418 8192000000 10834495856 11259117568 1911256320 18.917 100 35 69 2 1927046784 4974272 10816192
14:25:27 8050 8192000000 10834504784 11265105920 1917551360 18.967 100 36 70 3 1933083872 5805152 9727360
14:26:38 4561 8192000000 10828214544 11257643008 1908277088 18.893 100 34 68 1 1924766384 6127136 10362160
14:27:49 72849 8192000000 10750718736 11182567424 1760880736 17.692 93 24 48 2 1773198880 5098544 7219600
14:30:03 10582 8192000000 10738494528 11164696576 1816406976 18.148 99 34 69 1 1833307072 7642752 9257344
14:31:14 4644 8192000000 10734735552 11159113728 1821280160 18.188 100 30 59 3 1836617024 6828832 8508032
14:32:22 13314 8192000000 10743326128 11150688256 1799711008 18.012 99 23 46 0 1811538672 5469424 6358240
14:33:37 29138 8192000000 10727519920 11157962752 1790391344 17.935 98 16 32 2 1800697360 4986992 5319024
14:35:10 6496 8192000000 10771427232 11195588608 1848481056 18.410 100 24 47 4 1861084832 6441632 6162144
14:36:21 6349 8192000000 10785896592 11212439552 1867506016 18.564 100 18 36 2 1878092064 5391920 5194128
14:37:30 71039 8192000000 10826277392 11254185984 1899880176 18.825 100 30 60 0 1913097392 4776736 8440480
14:39:49 7129 8192000000 10875862512 11295834112 1939077664 19.139 100 69 138 2 1971912080 13676240 19158176
14:41:02 13400 8192000000 10885204544 11312947200 1948749808 19.217 100 43 86 0 1976965392 15553632 12661952
14:42:18 19619 8192000000 10907013936 11335872512 1962761536 19.328 100 53 106 0 1995666800 17241440 15663824
14:43:54 4612 8192000000 10928741856 11352772608 1980050368 19.465 100 54 109 1 2014576288 18731920 15794000
14:45:07 4773 8192000000 10931732256 11357585408 1978259376 19.451 100 65 128 2 2019173408 22766416 18147616
14:46:21 8676 8192000000 10954303104 11379367936 2002052192 19.639 100 68 134 0 2045786496 23943968 19790336
14:47:34 8169 8192000000 10941240496 11371495424 1961162352 19.315 98 54 108 0 2002286944 25478832 15645760
14:48:53 16774 8192000000 10930922208 11360477184 1953963328 19.258 99 48 96 1 1999578064 30634960 14979776
14:50:16 2291 8192000000 10950257328 11374747648 1982091200 19.481 100 62 123 0 2034677488 32497552 20088736
14:51:24 2363 8192000000 10979183344 11394072576 1998965408 19.615 100 84 167 0 2067282336 42085568 26231360
14:52:32 2152 8192000000 10982767200 11397337088 1994811056 19.582 100 94 186 3 2067951392 44564960 28575376
14:53:40 2844 8192000000 10955138800 11369414656 1965459664 19.349 100 85 170 1 2039203824 47357488 26386672
14:54:46 4849 8192000000 10943778000 11375714304 1970136464 19.387 100 65 130 1 2031653360 41393040 20123856
14:55:59 20667 8192000000 10909435856 11350384640 1918696208 18.976 98 32 63 2 1971698688 41244608 11757872
14:57:26 8433 8192000000 10911621616 11356753920 1953395696 19.254 100 52 104 0 2006855392 36628608 16831088
14:58:42 20433 8192000000 10929438640 11386593280 1978208528 19.451 100 30 60 2 2024938416 37894368 8835520
15:00:04 17013 8192000000 10915802352 11373043712 1962776368 19.328 100 25 50 1 2006325936 35126256 8423312
15:01:26 45920 8192000000 10877599648 11345432576 1902743712 18.848 98 20 41 0 1955677760 46324576 6609472
15:03:21 62012 8192000000 10875356336 11336908800 1918029424 18.971 98 13 26 1 1937606112 15319872 4256816
15:05:30 27545 8192000000 10888501488 11342213120 1927579744 19.048 98 17 34 2 1947909536 15444080 4885712
15:07:03 104643 8192000000 10880063232 11316641792 1910121984 18.908 98 18 36 1 1930401984 15435072 4844928
15:09:55 148154 8192000000 10905826720 11365707776 1951947952 19.242 99 29 57 1 1978558048 18267600 8342496
15:13:26 22414 8192000000 10917873856 11376316416 1979741792 19.463 100 38 77 1 2009413632 18646096 11025744
15:14:55 115026 8192000000 10919049904 11395403776 1996666784 19.596 100 16 32 1 2011078864 9789504 4622576
15:17:51 21416 8192000000 10915193312 11387465728 1984315648 19.499 99 15 31 0 1999177408 10325696 4536064
15:19:13 79395 8192000000 10914044416 11409928192 1971927648 19.401 98 16 32 2 1994518416 17811872 4778896
15:21:33 56953 8192000000 10924491648 11390779392 1927314480 19.045 96 41 82 1 1950957296 11497792 12145024
15:23:37 27164 8192000000 10968765648 11414794240 2016161808 19.750 100 29 58 1 2036680848 12077584 8441456
15:25:09 76404 8192000000 10931034960 11399151616 1955944480 19.274 97 11 22 0 1977517904 18227296 3346128
15:27:30 57011 8192000000 10915035488 11376005120 1953104464 19.251 98 16 32 2 1967357728 8782432 5470832
15:29:28 36766 8192000000 10917190064 11393282048 1989484512 19.540 100 18 36 0 2010356112 15579872 5291728
15:31:09 63715 8192000000 10929675936 11394224128 1922929440 19.010 96 18 36 0 1949725552 21530096 5266016
15:33:17 161894 8192000000 10893891968 11354578944 1898767504 18.816 96 18 36 1 1924192432 20476512 4948416
15:37:01 45800 8192000000 10842293680 11306696704 1895458784 18.790 97 24 48 1 1919035232 17155744 6420704
15:38:50 60056 8192000000 10868440384 11340906496 1918894576 18.978 98 5 10 0 1928466896 7774304 1798016
15:40:53 38250 8192000000 10862685184 11339304960 1939985440 19.147 99 6 12 0 1951064960 9646848 1432672
15:42:32 58877 8192000000 10871967040 11346944000 1885617232 18.710 96 19 39 0 1906507808 15498560 5392016
15:45:05 30174 8192000000 10876711568 11339554816 1928642656 19.056 99 11 22 2 1939774912 8245344 2886912
15:46:52 59560 8192000000 10887589712 11351150592 1932262944 19.085 99 23 46 0 1948769168 9831312 6674912
15:48:56 49221 8192000000 10904097600 11362979840 1936923472 19.122 99 31 62 1 1955495520 10429104 8142944
15:50:48 26325 8192000000 10894232544 11355938816 1926802992 19.041 98 42 84 2 1953910160 15101680 12005488
15:52:18 99547 8192000000 10870405984 11339091968 1846846208 18.396 94 9 18 1 1858954864 9604768 2503888
15:54:58 54495 8192000000 10883220512 11331985408 1879600960 18.662 97 22 44 0 1898991376 13617376 5773040
15:56:53 67111 8192000000 10880327600 11342303232 1934039216 19.099 99 42 83 0 1956639584 9577952 13022416
15:59:00 54231 8192000000 10890087072 11373801472 1956609280 19.279 100 17 34 1 1984357760 22768320 4980160
16:01:08 45486 8192000000 10851339184 11328548864 1918208592 18.972 99 10 20 0 1928862384 7962960 2690832
16:02:53 50939 8192000000 10886540208 11352752128 1921783952 19.001 98 13 26 0 1936351888 10677664 3890272
16:04:59 69647 8192000000 10894613424 11381481472 1966825280 19.360 100 18 34 1 1992267888 20688992 4753616
16:07:09 34723 8192000000 10891831488 11369115648 1956565424 19.279 99 22 42 2 1969376576 7119552 5691600
16:08:53 53610 8192000000 10905265296 11382722560 1927804224 19.049 97 16 32 1 1941991072 9790240 4396608
16:10:50 40560 8192000000 10922015552 11397906432 1984260752 19.498 100 16 32 0 2001037568 12173264 4603552
16:12:32 40397 8192000000 10941231552 11392294912 1983768096 19.495 99 43 84 1 2006415904 9318896 13328912
16:14:20 77248 8192000000 10924318768 11403517952 1936746208 19.121 97 11 22 0 1949955792 10230784 2978800
16:17:22 67651 8192000000 10874089280 11342499840 1864049520 18.536 95 18 36 3 1881897936 12700736 5147680
16:19:35 18608 8192000000 10878230048 11337478144 1895654128 18.791 97 42 84 1 1921916784 14646736 11615920
16:20:55 107423 8192000000 10859978352 11328446464 1872938976 18.608 96 22 43 1 1897316448 17856720 6520752
16:24:05 42712 8192000000 10863950048 11331833856 1925949248 19.034 99 11 22 2 1940426368 11121184 3355936
16:25:51 27932 8192000000 10862083680 11341426688 1923394048 19.014 99 14 28 0 1938181600 10412048 4375504
16:27:19 51027 8192000000 10858165696 11333992448 1904348864 18.861 98 28 56 3 1925267872 11726320 9192688
16:29:47 56691 8192000000 10849345632 11320942592 1860225232 18.505 95 18 37 2 1878465680 13273296 4967152
16:31:55 59569 8192000000 10886530240 11353772032 1918238896 18.973 98 20 40 0 1937284592 13146400 5899296
16:33:58 53064 8192000000 10875813040 11348250624 1914682016 18.944 98 21 42 0 1928751248 8437248 5631984
16:35:58 49588 8192000000 10875068368 11329269760 1903886160 18.858 97 34 68 1 1921390544 7987328 9517056

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-09-2021 16:39:02 to 03-09-2021 16:39:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

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

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          248569
MON$OLDEST_ACTIVE               251030
MON$OLDEST_SNAPSHOT             251030
MON$NEXT_TRANSACTION            251030
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                       2020992
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.33491 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33491 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33491 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33491 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 ---
:82: 
:83: mscs-test-161.budzdorov.ru    Fri Sep  3 16:37:53 2021
:84:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:85: 
:86: 
:87: mscs-test-161.budzdorov.ru    Fri Sep  3 16:37:55 2021
:88:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:89: 
:90: 
:91: mscs-test-161.budzdorov.ru    Fri Sep  3 16:37:56 2021
:92:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:93: 
--- end of diff output ---

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

03.09.21 16:39:21. 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-03 16:39:23 No severe PSQL-related problems occured
03.09.21 16:39:25. End of report.