Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.11.2020 07:37
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16874304 kB
MemAvailable 31485148 kB
Buffers 12 kB
Cached 14775232 kB
SwapCached 2748 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 4.0.0
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 2020-11-21 07:37:08
DTS_END                         2020-11-21 07:37:08.5520
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(68:17)
IP                              ::1/46894
TRN_ID                          218343
ATT_ID                          643
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: 8665.81 24559 1039897
customer order: creation 153.32 455 18399
customer order: refuse 13.08 415 1569
order to supplier: creation 40.46 1546 4855
order to supplier: removal 3.30 135168 396
invoice (draft): creation 40.80 4025 4896
invoice (draft): removal 51.58 1064 6190
invoice accept: apply 36.96 18835 4435
invoice accept: cancel 46.80 970 5616
customer reserve: creation 3416.55 182 409986
customer reserve: removal 4592.18 75 551062
realization accept: apply 73.72 194 8847
realization accept: cancel 82.88 136 9946
payment from customer: creation 52.73 116 6328
payment from customer: removal 5.04 87 605
payment to supplier: creation 43.78 139 5254
payment to supplier: removal 3.01 98 361
service: total inventory turnovers 3.54 4030 425
service: total monetary turnovers 5.21 675 625
service: aggregate perf. data 0.81 15336 97
service: refresh index statistics 0.04 307638 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 21-11-2020 07:37:45 to 21-11-2020 07:37:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6118
TEST_TIME 2 6585
TEST_TIME 3 5641
TEST_TIME 4 5046
TEST_TIME 5 5007
TEST_TIME 6 5023
TEST_TIME 7 5471
TEST_TIME 8 5480
TEST_TIME 9 5507
TEST_TIME 10 5659
TEST_TIME 11 6115
TEST_TIME 12 6083
TEST_TIME 13 6087
TEST_TIME 14 6463
TEST_TIME 15 6626
TEST_TIME 16 6500
TEST_TIME 17 6794
TEST_TIME 18 7290
TEST_TIME 19 7362
TEST_TIME 20 7464
TEST_TIME 21 7890
TEST_TIME 22 7872
TEST_TIME 23 8223
TEST_TIME 24 8109
TEST_TIME 25 7956
TEST_TIME 26 8243
TEST_TIME 27 9161
TEST_TIME 28 9249
TEST_TIME 29 9947
TEST_TIME 30 10757
TEST_TIME 31 10414
TEST_TIME 32 10197
TEST_TIME 33 10189
TEST_TIME 34 10184
TEST_TIME 35 10017
TEST_TIME 36 9909
TEST_TIME 37 9870
TEST_TIME 38 9726
TEST_TIME 39 9616
TEST_TIME 40 9485
TEST_TIME 41 9532
TEST_TIME 42 9307
TEST_TIME 43 9363
TEST_TIME 44 9327
TEST_TIME 45 9304
TEST_TIME 46 9325
TEST_TIME 47 9213
TEST_TIME 48 9098
TEST_TIME 49 9080
TEST_TIME 50 9117
TEST_TIME 51 9049
TEST_TIME 52 8986
TEST_TIME 53 9008
TEST_TIME 54 8954
TEST_TIME 55 8945
TEST_TIME 56 8839
TEST_TIME 57 8849
TEST_TIME 58 8885
TEST_TIME 59 9196
TEST_TIME 60 9118
TEST_TIME 61 9171
TEST_TIME 62 9123
TEST_TIME 63 9126
TEST_TIME 64 9141
TEST_TIME 65 9181
TEST_TIME 66 9054
TEST_TIME 67 8981
TEST_TIME 68 8903
TEST_TIME 69 8828
TEST_TIME 70 8787
TEST_TIME 71 8812
TEST_TIME 72 8790
TEST_TIME 73 8756
TEST_TIME 74 8702
TEST_TIME 75 8741
TEST_TIME 76 8740
TEST_TIME 77 8730
TEST_TIME 78 8802
TEST_TIME 79 8821
TEST_TIME 80 8840
TEST_TIME 81 8817
TEST_TIME 82 8979
TEST_TIME 83 8925
TEST_TIME 84 8864
TEST_TIME 85 8789
TEST_TIME 86 8815
TEST_TIME 87 8796
TEST_TIME 88 8719
TEST_TIME 89 8675
TEST_TIME 90 8641
TEST_TIME 91 8671
TEST_TIME 92 8680
TEST_TIME 93 8632
TEST_TIME 94 8650
TEST_TIME 95 8654
TEST_TIME 96 8687
TEST_TIME 97 8727
TEST_TIME 98 8693
TEST_TIME 99 8714
TEST_TIME 100 8757
TEST_TIME 101 8746
TEST_TIME 102 8759
TEST_TIME 103 8846
TEST_TIME 104 8810
TEST_TIME 105 8729
TEST_TIME 106 8697
TEST_TIME 107 8647
TEST_TIME 108 8613
TEST_TIME 109 8574
TEST_TIME 110 8579
TEST_TIME 111 8550
TEST_TIME 112 8589
TEST_TIME 113 8565
TEST_TIME 114 8584
TEST_TIME 115 8610
TEST_TIME 116 8640
TEST_TIME 117 8622
TEST_TIME 118 8682
TEST_TIME 119 8737
TEST_TIME 120 8668

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-11-2020 07:37:49 to 21-11-2020 07:37:52.

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 1420028 1420028 0 0.00 0 25021 7 0 0
doc_list_biud 1420025 1420025 0 0.00 0 61399 37 0 0
sp_add_invoice_to_stock 4621 4435 186 4.02 33 187990 18835 0 186
sp_cancel_adding_invoice 5616 5616 0 0.00 5 72629 970 0 0
sp_cancel_client_order 1569 1569 0 0.00 5 23465 416 0 0
sp_cancel_customer_reserve 551062 551062 0 0.00 1 27557 76 0 0
sp_cancel_pay_from_customer 605 605 0 0.00 3 3069 88 0 0
sp_cancel_pay_to_supplier 361 361 0 0.00 3 4750 99 0 0
sp_cancel_supplier_invoice 6198 6190 8 0.12 2 61421 1065 0 8
sp_cancel_supplier_order 396 396 0 0.00 10 1094428 135168 0 0
sp_cancel_write_off 9946 9946 0 0.00 1 24684 137 0 0
sp_client_order 18399 18399 0 0.00 4 55105 455 0 0
sp_customer_reserve 409986 409986 0 0.00 0 56493 182 0 0
sp_fill_shopping_cart 28130 28130 0 0.00 1 25325 48 0 0
sp_fill_shopping_cart_clo_res 409982 409982 0 0.00 0 27909 19 0 0
sp_get_clo_for_invoice 4430 4430 0 0.00 0 40982 494 0 0
sp_kill_cost_storno 16528 16528 0 0.00 0 8698 72 0 0
sp_kill_qstorno_ret_qs2qd 1118656 1118656 0 0.00 0 61398 41 0 0
sp_kill_qty_storno 588054 588054 0 0.00 0 61399 89 0 0
sp_lock_selected_doc 18382 18382 0 0.00 0 7512 4 0 0
sp_make_cost_storno 24859 24859 0 0.00 0 25001 63 0 0
sp_multiply_rows_for_pdistr 24856 24856 0 0.00 0 7054 32 0 0
sp_multiply_rows_for_qdistr 18399 18399 0 0.00 0 55095 260 0 0
sp_pay_from_customer 6328 6328 0 0.00 4 11829 117 0 0
sp_pay_to_supplier 5254 5254 0 0.00 5 18853 139 0 0
sp_payment_common 11581 11581 0 0.00 1 9540 24 0 0
sp_qd_handle_on_cancel_clo 1569 1569 0 0.00 0 23457 382 0 0
sp_qd_handle_on_invoice_upd_sts 10046 10046 0 0.00 0 27768 448 0 0
sp_qd_handle_on_reserve_upd_sts 8845 8845 0 0.00 0 23334 63 0 0
sp_reserve_write_off 8847 8847 0 0.00 7 25071 194 0 0
sp_supplier_invoice 4896 4896 0 0.00 8 118078 4025 0 0
sp_supplier_order 4855 4855 0 0.00 6 58560 1547 0 0
srv_aggregate_perf_data 97 97 0 0.00 2891 129743 15336 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 2321 171987 48463 0 0
srv_find_qd_qs_mism 997776 997776 0 0.00 0 11306 5 0 0
srv_make_invnt_saldo 425 425 0 0.00 60 116110 4030 0 0
srv_make_money_saldo 625 625 0 0.00 2 15282 676 0 0
srv_recalc_idx_stat 5 5 0 0.00 126910 511230 307639 0 0
t$perf-abend:sp_add_invoice_to_stock 186 186 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4435 4435 0 0.00 0 9565 183 0 0
t$perf-norm:sp_cancel_adding_invoice 349 349 0 0.00 0 9874 143 0 0
t$perf-norm:sp_cancel_client_order 1569 1569 0 0.00 0 1101 9 0 0
t$perf-norm:sp_cancel_customer_reserve 599 599 0 0.00 0 2748 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 605 605 0 0.00 0 641 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 361 361 0 0.00 0 629 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 354 354 0 0.00 0 1337 15 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 24470 1637 0 0
t$perf-norm:sp_cancel_write_off 879 879 0 0.00 0 2672 13 0 0
t$perf-norm:sp_client_order 18399 18399 0 0.00 0 12482 19 0 0
t$perf-norm:sp_customer_reserve 877 877 0 0.00 0 1554 6 0 0
t$perf-norm:sp_pay_from_customer 6328 6328 0 0.00 0 8912 10 0 0
t$perf-norm:sp_pay_to_supplier 5254 5254 0 0.00 0 23565 14 0 0
t$perf-norm:sp_reserve_write_off 8847 8847 0 0.00 0 4321 8 0 0
t$perf-norm:sp_supplier_invoice 4896 4896 0 0.00 0 6126 19 0 0
t$perf-norm:sp_supplier_order 4855 4855 0 0.00 0 6235 18 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 3262 41 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 738 15 0 0
t$perf-norm:srv_make_invnt_saldo 425 425 0 0.00 0 3249 19 0 0
t$perf-norm:srv_make_money_saldo 625 625 0 0.00 0 2680 11 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 163 34 0 0
v_add_invoice_to_stock 9224 8852 372 4.03 0 13257 19 0 372
v_all_customers 33786 33786 0 0.00 0 28 1 0 0
v_all_suppliers 27184 27184 0 0.00 0 25 1 0 0
v_all_wares 36790 36790 0 0.00 0 75 1 0 0
v_cancel_adding_invoice 698 698 0 0.00 0 3655 11 0 0
v_cancel_client_order 3138 3138 0 0.00 0 5473 8 0 0
v_cancel_customer_prepayment 1210 1210 0 0.00 0 921 6 0 0
v_cancel_customer_reserve 1198 1198 0 0.00 0 539 5 0 0
v_cancel_payment_to_supplier 722 722 0 0.00 0 610 6 0 0
v_cancel_supplier_invoice 724 708 16 2.20 0 541 14 0 16
v_cancel_supplier_order 792 792 0 0.00 0 1532 5 0 0
v_cancel_write_off 1758 1758 0 0.00 0 1520 14 0 0
v_max_id_clo_ord 4854 4854 0 0.00 0 259 1 0 0
v_max_id_clo_res 877 877 0 0.00 0 2581 14 0 0
v_max_id_ord_sup 4881 4881 0 0.00 0 4783 7 0 0
v_max_non_paid_invoice 1396 1396 0 0.00 0 3106 4 0 0
v_max_non_paid_realizn 3242 3242 0 0.00 0 5711 6 0 0
v_min_id_clo_ord 4854 4854 0 0.00 0 3157 9 0 0
v_min_id_clo_res 877 877 0 0.00 0 1788 6 0 0
v_min_id_ord_sup 4881 4881 0 0.00 0 16343 12 0 0
v_min_non_paid_invoice 5254 5254 0 0.00 0 3971 12 0 0
v_min_non_paid_realizn 6327 6327 0 0.00 0 3759 13 0 0
v_our_firm 9174 9174 0 0.00 0 29 1 0 0
v_reserve_write_off 17688 17688 0 0.00 0 4680 6 0 0
x_lock_depdocs_on_canc_invoice 11452 11452 0 0.00 0 15854 130 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 14615 460 0 0
x_make_qstorno_1000_1200 4855 4855 0 0.00 2 58527 1460 0 0
x_make_qstorno_1200_2000 4896 4896 0 0.00 5 111305 3838 0 0
x_make_qstorno_2100_3300 409986 409986 0 0.00 0 56442 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-11-2020 07:37:53 to 21-11-2020 07:37:57.

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
05:07:12 2 8192000000 8796641312 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
05:08:13 22245 8192000000 9718250320 10012295168 799512576 8.891 100 85 169 0 827555696 7738528 20304592
05:09:41 25949 8192000000 9756931744 10062622720 830942160 9.209 100 84 168 0 862376192 11548480 19885552
05:11:18 15266 8192000000 9800872000 10120065024 870813008 9.608 100 89 178 1 908908128 17689680 20405440
05:12:46 14985 8192000000 9872202816 10203860992 919531408 10.091 100 82 164 1 982634688 45134672 17968608
05:14:03 8645 8192000000 9922271904 10266095616 949053504 10.382 100 77 154 2 1037291936 72221296 16017136
05:15:14 4586 8192000000 9957213216 10308345856 988179584 10.764 100 85 170 1 1069231600 63514576 17537440
05:16:20 3620 8192000000 9991168976 10352566272 1018833376 11.061 100 83 166 1 1109180048 73181840 17164832
05:17:27 4281 8192000000 10006135936 10373070848 1040809952 11.272 100 75 149 0 1125747984 70591568 14346464
05:18:32 2030 8192000000 10037684160 10408665088 1057431040 11.432 100 90 180 0 1149310800 73499456 18380304
05:19:36 12527 8192000000 9998507440 10377220096 1067003072 11.523 100 69 137 2 1114958960 34153968 13801920
05:20:52 6268 8192000000 9999159024 10392719360 1074684320 11.597 100 40 79 2 1109382640 26002480 8695840
05:22:02 3259 8192000000 10008191408 10400878592 1086745952 11.712 100 56 112 2 1118619568 19624816 12248800
05:23:07 8222 8192000000 10001374192 10402484224 1089983952 11.743 100 25 47 2 1108787008 13442400 5360656
05:24:20 44246 8192000000 10016436896 10411421696 1096168560 11.801 100 46 91 6 1111914320 6697712 9048048
05:26:04 6954 8192000000 10040392336 10447933440 1101577488 11.853 100 34 68 1 1143559344 34788784 7193072
05:27:13 4807 8192000000 10015071920 10411274240 1099653824 11.834 100 50 98 6 1119493248 9428448 10410976
05:28:22 8134 8192000000 9991918976 10386223104 1063524768 11.490 98 41 77 3 1093746640 21876640 8345232
05:29:48 15594 8192000000 9945001280 10336010240 997666656 10.856 94 32 62 0 1025477200 20932176 6878368
05:31:07 5114 8192000000 9952033168 10332405760 1013769488 11.012 99 53 106 3 1055103888 29954640 11379760
05:32:17 7953 8192000000 9940165392 10328621056 997164480 10.851 97 39 78 0 1026388112 20252816 8970816
05:33:28 54422 8192000000 9936485824 10335940608 1012570336 11.000 98 19 38 0 1040111664 23335536 4205792
05:35:28 8583 8192000000 9969440432 10367721472 1049015456 11.351 100 44 88 0 1075801632 16372256 10413920
05:36:38 11420 8192000000 9965882576 10365366272 1058221888 11.439 100 30 60 1 1077536656 12068480 7246288
05:37:56 17228 8192000000 10018909520 10405531648 1089303168 11.736 100 49 98 0 1124882880 25143440 10436272
05:39:20 67313 8192000000 10066368720 10471051264 1108379952 11.917 100 50 99 3 1158572192 39553968 10638272
05:41:34 11467 8192000000 10096405264 10499780608 1121164384 12.038 100 51 102 2 1204821712 73243968 10413360
05:42:55 9506 8192000000 10117118128 10518495232 1127205312 12.095 100 54 106 2 1206408928 67796672 11406944
05:44:08 18862 8192000000 10141635152 10556366848 1142533104 12.239 100 47 94 1 1255841920 103507344 9801472
05:45:34 6180 8192000000 10171649584 10572804096 1154034672 12.347 100 65 128 2 1279278976 111209952 14034352
05:46:45 11807 8192000000 10209522656 10615504896 1157321424 12.378 100 65 125 4 1302121024 131690272 13109328
05:48:01 9024 8192000000 10267230784 10690736128 1173808256 12.532 100 66 131 0 1367323552 179061632 14453664
05:49:15 7860 8192000000 10268640304 10684669952 1176745168 12.560 99 58 112 1 1365088208 175136096 13206944
05:50:27 8809 8192000000 10324008592 10736136192 1187400096 12.659 100 64 126 1 1409938512 207686912 14851504
05:51:46 5019 8192000000 10280329712 10721120256 1191293968 12.695 99 65 129 0 1377070272 170563312 15212992
05:52:55 4647 8192000000 10295785152 10702327808 1166885600 12.468 98 65 130 2 1395692896 212609248 16198048
05:54:06 3649 8192000000 10383630432 10797600768 1187441968 12.660 100 75 149 3 1483234496 278380704 17411824
05:55:19 4393 8192000000 10368408144 10788839424 1183902704 12.627 100 65 130 0 1479120320 280419120 14798496
05:56:27 2320 8192000000 10388754512 10792140800 1182390480 12.612 100 77 152 1 1484387200 284283888 17712832
05:57:36 2647 8192000000 10427096560 10845564928 1178860384 12.580 100 80 160 1 1526171456 327972480 19338592
05:58:43 13717 8192000000 10409271456 10853015552 1155701056 12.363 98 37 71 0 1495443920 330273664 9469200
05:59:58 8851 8192000000 10461669680 10884034560 1167697760 12.475 100 49 98 0 1557136960 376674176 12765024
06:01:13 11772 8192000000 10440244496 10879102976 1142027280 12.235 98 38 74 1 1525524320 374262784 9234256
06:02:29 11039 8192000000 10484050480 10916462592 1148740928 12.298 100 45 87 0 1589824496 429407872 11675696
06:03:44 21366 8192000000 10447788352 10888708096 1122061632 12.046 99 41 82 0 1590303072 457532448 10708992
06:05:06 16951 8192000000 10378728816 10834505728 1112351728 11.955 99 23 46 2 1473861328 354999872 6509728
06:06:56 52165 8192000000 10044607648 10482470912 1085335760 11.698 98 18 37 1 1166229376 76475584 4418032
06:09:45 11659 8192000000 10056556208 10477641728 1108434384 11.918 100 56 112 1 1155052096 33931376 12686336
06:10:59 59070 8192000000 10059855728 10546356224 1114341120 11.973 99 19 38 0 1191586832 73150928 4094784
06:13:01 63140 8192000000 10080055584 10556444672 1115485264 11.984 99 13 26 0 1184536720 66678304 2373152
06:15:08 111188 8192000000 10084537808 10532028416 1074188320 11.592 94 25 50 2 1135638944 56182848 5267776
06:17:59 82618 8192000000 10127882544 10619121664 1123137104 12.057 100 25 50 2 1192097984 63280272 5680608
06:23:26 77606 8192000000 10075285776 10525204480 1102833824 11.865 98 20 41 0 1174938672 67724240 4380608
06:25:45 60636 8192000000 10112214064 10586533888 1128948464 12.111 100 21 42 2 1228222240 94985968 4287808
06:27:54 86115 8192000000 10049236432 10521198592 1128202992 12.104 100 5 10 0 1163641328 34435344 1002992
06:30:21 45882 8192000000 10062300944 10528440320 1136293936 12.181 100 14 28 0 1180623824 41270688 3059200
06:32:07 78476 8192000000 10139825008 10605752320 1156657680 12.372 100 23 46 2 1237687840 76031088 4999072
06:34:28 27583 8192000000 10216667504 10666766336 1165886224 12.458 100 18 36 0 1303448368 133185984 4376160
06:35:56 100455 8192000000 10069443264 10523312128 1140057232 12.216 99 9 18 0 1215748528 73829456 1861840
06:38:38 70167 8192000000 10070463136 10508218368 1112116144 11.952 96 29 58 0 1154819552 36986528 5716880
06:40:58 64030 8192000000 10088365696 10551324672 1101025248 11.847 96 8 16 0 1173947216 71646336 1275632
06:43:03 77395 8192000000 10042089376 10490929152 1113279712 11.963 98 16 32 1 1146649360 30313824 3055824
06:45:49 90969 8192000000 10107864912 10569375744 1146499040 12.277 100 14 28 0 1214463840 65081216 2883584
06:48:24 45937 8192000000 10068105504 10560663552 1118477120 12.013 98 13 26 0 1164692176 43230896 2984160
06:50:17 62138 8192000000 10103563024 10548482048 1135486320 12.173 99 20 38 2 1191342384 51824560 4031504
06:52:20 38088 8192000000 10111690864 10562121728 1129033040 12.112 98 17 34 1 1220952336 88056800 3862496
06:54:00 89546 8192000000 10154820064 10647298048 1131995696 12.140 99 17 34 0 1224902400 89138352 3768352
06:56:32 36786 8192000000 10078945520 10564308992 1097437024 11.813 97 26 52 0 1164962912 62265824 5260064
06:58:12 68229 8192000000 10098817824 10615754752 1101201232 11.849 98 17 34 0 1204984800 100435072 3348496
07:00:21 57568 8192000000 10109482224 10564333568 1113826768 11.969 99 26 52 0 1191615136 71747808 6040560
07:03:42 52838 8192000000 10072580416 10611638272 1122297184 12.049 100 9 18 0 1193356112 68915328 2143600
07:05:36 25347 8192000000 10051199440 10524782592 1119728112 12.024 99 4 8 0 1155124592 35028224 368256
07:07:12 66008 8192000000 10088825664 10544754688 1124857840 12.073 99 18 36 1 1192279232 63726528 3694864
07:09:38 73260 8192000000 10046361824 10464808960 1040278016 11.267 93 19 38 0 1084555664 40466672 3810976
07:11:53 114726 8192000000 10096818880 10565758976 1087426720 11.718 97 12 23 2 1150980288 61264768 2288800
07:15:05 21802 8192000000 10073867024 10494054400 1102371024 11.860 98 27 53 0 1126579712 18637376 5571312
07:16:36 86593 8192000000 10061996704 10507587584 1069494752 11.547 95 26 52 1 1125808944 50994320 5319872
07:19:04 62695 8192000000 10108968576 10560974848 1104718320 11.882 98 11 22 1 1181656800 74760656 2177824
07:21:07 171987 8192000000 10095420112 10544222208 1127933568 12.102 100 19 37 2 1184487328 52568288 3985472
07:24:59 90048 8192000000 10093375392 10564005888 1135780848 12.176 100 13 26 0 1184110032 45315664 3013520
07:27:34 95084 8192000000 10136377296 10604326912 1126022128 12.084 98 20 40 2 1193264096 62814752 4427216
07:30:23 75897 8192000000 10133662208 10599591936 1123603552 12.061 98 22 43 0 1209364560 81194848 4566160
07:33:10 42949 8192000000 10119923200 10589642752 1151876816 12.327 100 21 42 2 1237978368 81322368 4779184
07:34:53 96447 8192000000 10141121600 10609967104 1133120912 12.151 98 18 35 0 1216988976 80394672 3473392

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-11-2020 07:37:59 to 21-11-2020 07:38:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 186 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-11-2020 07:38:15 to 21-11-2020 07:38:17.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          217046
MON$OLDEST_ACTIVE               218677
MON$OLDEST_SNAPSHOT             218677
MON$NEXT_TRANSACTION            218677
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-30 07:30:08.6030 GMT
MON$PAGES                       2098176
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             764
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2272 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2272 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2272 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2272 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Nov 21 07:37:14 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

21.11.20 07:38:18. There are 3 logs in /var/tmp/logs-oltp40 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
2020-11-21 07:38:21 No severe PSQL-related problems occured
21.11.20 07:38:22. End of report.