Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.09.2021 07: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 15641560 kB
MemAvailable 30834864 kB
Buffers 0 kB
Cached 16078080 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 4.0.1
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 2021-09-23 07:37:06
DTS_END                         2021-09-23 07:37:06.3080
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51976
TRN_ID                          238947
ATT_ID                          660
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: 9387.82 21342 1126538
customer order: creation 172.76 380 20731
customer order: refuse 15.50 334 1860
order to supplier: creation 46.60 1345 5592
order to supplier: removal 3.47 116869 416
invoice (draft): creation 46.03 3407 5523
invoice (draft): removal 54.42 935 6530
invoice accept: apply 41.10 17848 4932
invoice accept: cancel 49.91 870 5989
customer reserve: creation 3818.61 175 458233
customer reserve: removal 4840.73 66 580888
realization accept: apply 83.16 197 9979
realization accept: cancel 88.62 120 10634
payment from customer: creation 59.41 126 7129
payment from customer: removal 6.37 85 764
payment to supplier: creation 46.89 144 5627
payment to supplier: removal 3.52 88 422
service: total inventory turnovers 3.48 4031 418
service: total monetary turnovers 6.34 497 761
service: aggregate perf. data 0.88 17655 106
service: refresh index statistics 0.03 261664 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 23-09-2021 07:39:12 to 23-09-2021 07:39:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5219
TEST_TIME 2 4057
TEST_TIME 3 5399
TEST_TIME 4 5405
TEST_TIME 5 5427
TEST_TIME 6 5262
TEST_TIME 7 5332
TEST_TIME 8 5382
TEST_TIME 9 5486
TEST_TIME 10 5919
TEST_TIME 11 5665
TEST_TIME 12 5718
TEST_TIME 13 6007
TEST_TIME 14 6889
TEST_TIME 15 7154
TEST_TIME 16 7293
TEST_TIME 17 8024
TEST_TIME 18 8198
TEST_TIME 19 8168
TEST_TIME 20 8534
TEST_TIME 21 8592
TEST_TIME 22 8921
TEST_TIME 23 8885
TEST_TIME 24 8804
TEST_TIME 25 9116
TEST_TIME 26 9037
TEST_TIME 27 9838
TEST_TIME 28 10721
TEST_TIME 29 10843
TEST_TIME 30 11009
TEST_TIME 31 10958
TEST_TIME 32 10822
TEST_TIME 33 10789
TEST_TIME 34 10868
TEST_TIME 35 10709
TEST_TIME 36 10645
TEST_TIME 37 10502
TEST_TIME 38 10480
TEST_TIME 39 10435
TEST_TIME 40 10527
TEST_TIME 41 10602
TEST_TIME 42 10515
TEST_TIME 43 10422
TEST_TIME 44 10199
TEST_TIME 45 10192
TEST_TIME 46 10147
TEST_TIME 47 9993
TEST_TIME 48 9961
TEST_TIME 49 9946
TEST_TIME 50 9880
TEST_TIME 51 10008
TEST_TIME 52 9978
TEST_TIME 53 10002
TEST_TIME 54 9983
TEST_TIME 55 9885
TEST_TIME 56 9824
TEST_TIME 57 9788
TEST_TIME 58 9727
TEST_TIME 59 9802
TEST_TIME 60 9842
TEST_TIME 61 9855
TEST_TIME 62 9925
TEST_TIME 63 9974
TEST_TIME 64 10015
TEST_TIME 65 10020
TEST_TIME 66 9944
TEST_TIME 67 10032
TEST_TIME 68 9959
TEST_TIME 69 10087
TEST_TIME 70 10107
TEST_TIME 71 10132
TEST_TIME 72 10031
TEST_TIME 73 9933
TEST_TIME 74 9871
TEST_TIME 75 9831
TEST_TIME 76 9840
TEST_TIME 77 9797
TEST_TIME 78 9746
TEST_TIME 79 9727
TEST_TIME 80 9648
TEST_TIME 81 9593
TEST_TIME 82 9577
TEST_TIME 83 9572
TEST_TIME 84 9619
TEST_TIME 85 9673
TEST_TIME 86 9682
TEST_TIME 87 9670
TEST_TIME 88 9747
TEST_TIME 89 9714
TEST_TIME 90 9743
TEST_TIME 91 9771
TEST_TIME 92 9793
TEST_TIME 93 9780
TEST_TIME 94 9873
TEST_TIME 95 9814
TEST_TIME 96 9737
TEST_TIME 97 9680
TEST_TIME 98 9708
TEST_TIME 99 9679
TEST_TIME 100 9625
TEST_TIME 101 9607
TEST_TIME 102 9537
TEST_TIME 103 9493
TEST_TIME 104 9474
TEST_TIME 105 9421
TEST_TIME 106 9449
TEST_TIME 107 9481
TEST_TIME 108 9434
TEST_TIME 109 9464
TEST_TIME 110 9454
TEST_TIME 111 9439
TEST_TIME 112 9479
TEST_TIME 113 9489
TEST_TIME 114 9523
TEST_TIME 115 9520
TEST_TIME 116 9532
TEST_TIME 117 9542
TEST_TIME 118 9485
TEST_TIME 119 9460
TEST_TIME 120 9392

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-09-2021 07:39:16 to 23-09-2021 07:39:20.

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 1552815 1552815 0 0.00 0 27259 7 0 0
doc_list_biud 1552812 1552812 0 0.00 0 33568 31 0 0
sp_add_invoice_to_stock 5012 4932 80 1.59 44 194437 17849 0 80
sp_cancel_adding_invoice 5989 5989 0 0.00 4 86351 871 0 0
sp_cancel_client_order 1860 1860 0 0.00 5 15799 334 0 0
sp_cancel_customer_reserve 580888 580888 0 0.00 1 20753 66 0 0
sp_cancel_pay_from_customer 764 764 0 0.00 3 4278 85 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 3 8416 89 0 0
sp_cancel_supplier_invoice 6537 6530 7 0.10 1 31268 935 0 7
sp_cancel_supplier_order 416 416 0 0.00 9 1114779 116870 0 0
sp_cancel_write_off 10634 10634 0 0.00 1 16107 121 0 0
sp_client_order 20731 20731 0 0.00 4 34347 381 0 0
sp_customer_reserve 458233 458233 0 0.00 0 40665 175 0 0
sp_fill_shopping_cart 31843 31843 0 0.00 1 22008 40 0 0
sp_fill_shopping_cart_clo_res 458229 458229 0 0.00 0 15831 16 0 0
sp_get_clo_for_invoice 4927 4927 0 0.00 0 30558 311 0 0
sp_kill_cost_storno 17809 17809 0 0.00 0 16004 63 0 0
sp_kill_qstorno_ret_qs2qd 1179356 1179356 0 0.00 0 31255 36 0 0
sp_kill_qty_storno 621223 621223 0 0.00 0 33568 77 0 0
sp_lock_selected_doc 20883 20883 0 0.00 0 12434 6 0 0
sp_make_cost_storno 27662 27662 0 0.00 0 19972 59 0 0
sp_multiply_rows_for_pdistr 27662 27662 0 0.00 0 16449 37 0 0
sp_multiply_rows_for_qdistr 20731 20731 0 0.00 0 33549 186 0 0
sp_pay_from_customer 7129 7129 0 0.00 4 16463 127 0 0
sp_pay_to_supplier 5627 5627 0 0.00 5 20634 144 0 0
sp_payment_common 12756 12756 0 0.00 1 16448 26 0 0
sp_qd_handle_on_cancel_clo 1860 1860 0 0.00 0 15786 299 0 0
sp_qd_handle_on_invoice_upd_sts 10916 10916 0 0.00 0 33567 384 0 0
sp_qd_handle_on_reserve_upd_sts 9979 9979 0 0.00 0 15427 53 0 0
sp_reserve_write_off 9979 9979 0 0.00 7 20229 197 0 0
sp_supplier_invoice 5523 5523 0 0.00 8 93325 3408 0 0
sp_supplier_order 5592 5592 0 0.00 6 36749 1345 0 0
srv_aggregate_perf_data 106 106 0 0.00 2715 241993 17656 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1426 133516 40654 0 0
srv_find_qd_qs_mism 1080050 1080050 0 0.00 0 27053 5 0 0
srv_make_invnt_saldo 418 418 0 0.00 60 107506 4031 0 0
srv_make_money_saldo 761 761 0 0.00 1 15143 497 0 0
srv_recalc_idx_stat 4 4 0 0.00 215334 312139 261665 0 0
t$perf-abend:sp_add_invoice_to_stock 80 80 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4932 4932 0 0.00 0 19378 144 0 0
t$perf-norm:sp_cancel_adding_invoice 447 447 0 0.00 0 2179 77 0 0
t$perf-norm:sp_cancel_client_order 1860 1860 0 0.00 0 6469 15 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 1517 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 764 764 0 0.00 0 3612 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 179 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 347 8 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 27703 1565 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 1329 6 0 0
t$perf-norm:sp_client_order 20731 20731 0 0.00 0 8164 16 0 0
t$perf-norm:sp_customer_reserve 1040 1040 0 0.00 0 1184 6 0 0
t$perf-norm:sp_pay_from_customer 7129 7129 0 0.00 0 4823 7 0 0
t$perf-norm:sp_pay_to_supplier 5627 5627 0 0.00 0 3433 8 0 0
t$perf-norm:sp_reserve_write_off 9979 9979 0 0.00 0 7236 9 0 0
t$perf-norm:sp_supplier_invoice 5523 5523 0 0.00 0 5048 13 0 0
t$perf-norm:sp_supplier_order 5592 5592 0 0.00 0 4307 11 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 73 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 497 17 0 0
t$perf-norm:srv_make_invnt_saldo 418 418 0 0.00 0 1006 11 0 0
t$perf-norm:srv_make_money_saldo 761 761 0 0.00 0 3295 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 15 5 0 0
v_add_invoice_to_stock 10014 9854 160 1.59 0 11971 18 0 160
v_all_customers 37902 37902 0 0.00 0 22 1 0 0
v_all_suppliers 30572 30572 0 0.00 0 33 1 0 0
v_all_wares 41460 41460 0 0.00 0 28 1 0 0
v_cancel_adding_invoice 894 894 0 0.00 0 1617 9 0 0
v_cancel_client_order 3720 3720 0 0.00 0 7233 10 0 0
v_cancel_customer_prepayment 1528 1528 0 0.00 0 2358 9 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 0 4344 9 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 3495 14 0 0
v_cancel_supplier_invoice 834 820 14 1.67 0 2052 19 0 14
v_cancel_supplier_order 832 832 0 0.00 0 3485 11 0 0
v_cancel_write_off 1978 1978 0 0.00 0 1702 15 0 0
v_max_id_clo_ord 5592 5592 0 0.00 0 408 1 0 0
v_max_id_clo_res 1040 1040 0 0.00 0 13246 26 0 0
v_max_id_ord_sup 5521 5521 0 0.00 0 4777 4 0 0
v_max_non_paid_invoice 1454 1454 0 0.00 0 325 2 0 0
v_max_non_paid_realizn 3704 3704 0 0.00 0 1140 3 0 0
v_min_id_clo_ord 5592 5592 0 0.00 0 21847 9 0 0
v_min_id_clo_res 1040 1040 0 0.00 0 7227 25 0 0
v_min_id_ord_sup 5521 5521 0 0.00 0 8483 9 0 0
v_min_non_paid_invoice 5627 5627 0 0.00 0 10732 19 0 0
v_min_non_paid_realizn 7129 7129 0 0.00 0 16442 19 0 0
v_our_firm 10408 10408 0 0.00 0 59 1 0 0
v_reserve_write_off 19958 19958 0 0.00 0 8766 10 0 0
x_lock_depdocs_on_canc_invoice 12109 12109 0 0.00 0 20876 97 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 2864 290 0 0
x_make_qstorno_1000_1200 5592 5592 0 0.00 2 36692 1271 0 0
x_make_qstorno_1200_2000 5523 5523 0 0.00 4 93316 3258 0 0
x_make_qstorno_2100_3300 458233 458233 0 0.00 0 40658 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-09-2021 07:39:21 to 23-09-2021 07:39:25.

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:10 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:11 26627 8192000000 9729879440 10029715456 809108512 8.988 100 88 176 0 837886096 7440656 21336928
05:09:43 21826 8192000000 9778195488 10087510016 853192848 9.432 100 90 181 0 887012432 12557136 21262448
05:11:12 12378 8192000000 9811489520 10132045824 883928816 9.739 100 90 180 0 926976864 21776624 21271424
05:12:30 12298 8192000000 9864093120 10198589440 915719888 10.054 100 81 162 0 979780224 46126576 17933760
05:13:48 6749 8192000000 9917228864 10265202688 970158896 10.588 100 71 141 0 1036252560 51398688 14694976
05:14:57 3449 8192000000 9970072768 10328031232 1004669200 10.924 100 77 154 1 1080578448 59688944 16220304
05:16:03 2824 8192000000 10002611824 10366922752 1038361376 11.249 100 88 174 1 1120151296 64464352 17325568
05:17:07 2294 8192000000 10018342176 10392629248 1058787376 11.445 100 90 179 2 1134511984 56824336 18900272
05:18:10 18362 8192000000 10055562528 10432331776 1071913072 11.570 100 72 145 1 1160628368 73456080 15259216
05:19:29 53512 8192000000 9995121120 10387980288 1080170352 11.649 100 55 109 0 1142644704 50326064 12148288
05:21:23 7858 8192000000 10002239904 10401632256 1093705664 11.778 100 42 85 1 1130733024 27617408 9409952
05:22:33 3876 8192000000 10018318048 10417397760 1095043488 11.791 100 45 89 3 1133949152 29493936 9411728
05:23:38 4869 8192000000 10016891952 10424713216 1100449824 11.842 100 29 58 3 1130979648 24505232 6024592
05:24:46 9020 8192000000 10027853888 10417397760 1103297040 11.869 100 64 128 1 1130729504 12690672 14741792
05:25:55 8403 8192000000 10031813584 10432876544 1107731584 11.911 100 39 77 0 1143158624 27359008 8068032
05:27:04 7642 8192000000 10011442928 10416508928 1081474784 11.662 98 36 72 1 1111034704 21708752 7851168
05:28:16 13510 8192000000 9955645568 10352103424 1010954432 10.985 96 38 73 2 1036979984 17795216 8230336
05:29:31 22038 8192000000 9957054592 10348118016 950695856 10.398 90 16 32 0 974742704 20264208 3782640
05:30:59 7500 8192000000 9924951472 10314567680 991118736 10.792 98 62 122 3 1031138944 25761008 14259200
05:32:07 9069 8192000000 9957873168 10358910976 1037902368 11.244 100 20 40 1 1072693424 30185968 4605088
05:33:17 55913 8192000000 9957936688 10361204736 1048541952 11.347 100 22 41 0 1077516528 24766736 4207840
05:35:13 8508 8192000000 9987905888 10381959168 1062366800 11.479 100 49 96 1 1102644560 29676688 10601072
05:36:22 9009 8192000000 9987332000 10385817600 1072156784 11.573 100 40 80 2 1101841056 20049632 9634640
05:37:36 2850 8192000000 10023515200 10412826624 1092982640 11.771 100 55 108 0 1128952320 24146336 11823344
05:38:44 70512 8192000000 10063109056 10464776192 1105967472 11.894 100 40 80 0 1170322240 56111920 8242848
05:40:58 12631 8192000000 10083456624 10490662912 1124608368 12.071 100 50 100 1 1190896368 54941440 11346560
05:42:14 5355 8192000000 10137429776 10538369024 1134150768 12.160 100 59 118 1 1250435648 103505888 12778992
05:43:21 7913 8192000000 10142782512 10553368576 1142494656 12.239 100 46 92 0 1254719264 102601872 9622736
05:44:38 15624 8192000000 10193918048 10605989888 1153219680 12.340 100 55 110 2 1304649104 138844512 12584912
05:45:58 21368 8192000000 10234317888 10652286976 1167276448 12.471 100 47 94 1 1328504352 150120928 11106976
05:47:26 6244 8192000000 10238158992 10677059584 1175523616 12.548 100 49 98 3 1349984496 162652656 11808224
05:48:39 5640 8192000000 10268366496 10682302464 1181520816 12.604 100 65 130 1 1377072640 180768320 14783504
05:49:49 3412 8192000000 10334532512 10736934912 1155188112 12.358 99 86 173 0 1435400192 259890448 20321632
05:50:56 1769 8192000000 10375605312 10784313344 1161914992 12.421 100 77 153 1 1476866064 297213696 17737376
05:52:04 6705 8192000000 10331173968 10747228160 1166333584 12.463 100 55 110 2 1441742896 262554992 12854320
05:53:14 5165 8192000000 10361103584 10782613504 1170907392 12.505 100 68 136 1 1465979712 278766240 16306080
05:54:24 1425 8192000000 10373454272 10771480576 1149855440 12.308 100 82 163 1 1465719824 297428912 18435472
05:55:28 3779 8192000000 10315392112 10728325120 1151544224 12.324 100 54 102 1 1418383232 253605632 13233376
05:56:36 4379 8192000000 10368518512 10784399360 1162188624 12.424 100 54 108 2 1468918800 293941488 12788688
05:57:47 15843 8192000000 10351628480 10772336640 1125523680 12.079 98 40 79 1 1432719888 297103632 10092576
05:59:08 11673 8192000000 10369375152 10805067776 1157017952 12.375 100 36 72 0 1477543280 311581600 8943728
06:00:23 11990 8192000000 10409141680 10870419456 1144363696 12.257 100 27 54 1 1499986320 348284544 7338080
06:01:47 9068 8192000000 10441964032 10870259712 1144676704 12.260 100 42 85 2 1542550480 387213856 10659920
06:03:00 10626 8192000000 10443349632 10898444288 1133528528 12.155 99 33 66 2 1547696240 405290816 8876896
06:04:24 15242 8192000000 10412188528 10885160960 1125787520 12.082 100 31 62 2 1526177888 392018912 8371456
06:05:54 33990 8192000000 10153304992 10710355968 1112476400 11.956 100 19 38 1 1320942704 203833328 4632976
06:07:51 47694 8192000000 10065462688 10525847552 1089195376 11.735 97 26 52 0 1135221968 39976784 6049808
06:09:42 42683 8192000000 10076423520 10491031552 1088688864 11.730 97 29 56 1 1130940848 36335120 5916864
06:11:27 74269 8192000000 10057195200 10483802112 1119428016 12.022 99 8 16 0 1186411888 65130672 1853200
06:13:46 32374 8192000000 10086915792 10515701760 1142183120 12.236 100 42 81 2 1173269136 22271296 8814720
06:15:28 82481 8192000000 10117398496 10578538496 1121917360 12.045 98 15 30 2 1194260000 68782960 3559680
06:18:17 88979 8192000000 10077138512 10500759552 1115149760 11.981 97 15 30 2 1158033520 40499344 2384416
06:20:46 93045 8192000000 10132795600 10570334208 1150067856 12.310 100 31 60 2 1218201328 61012400 7121072
06:23:20 36851 8192000000 10138728400 10590650368 1163703664 12.438 100 36 70 2 1217091104 46112896 7274544
06:24:58 22914 8192000000 10109276880 10573099008 1142108960 12.235 99 11 22 0 1213194608 68382592 2703056
06:26:27 17990 8192000000 10126384928 10563588096 1159656528 12.400 100 29 58 2 1223578272 58168320 5753424
06:27:48 47639 8192000000 10210229312 10659336192 1157983248 12.384 99 46 91 2 1276853648 108603056 10267344
06:29:36 29181 8192000000 10135922640 10615840768 1166864160 12.468 100 7 14 1 1250182528 81766032 1552336
06:31:06 27184 8192000000 10132074448 10622697472 1162679984 12.428 100 10 20 0 1237843680 72877184 2286512
06:32:34 53413 8192000000 10124426032 10570440704 1154685168 12.353 99 20 40 0 1211528464 52716368 4126928
06:34:35 39234 8192000000 10145349760 10658496512 1157856496 12.383 100 12 24 0 1225986368 65440496 2689376
06:36:34 65424 8192000000 10092761840 10582511616 1126710816 12.090 97 18 34 1 1185091152 54676224 3704112
06:38:39 67181 8192000000 10138649168 10585952256 1120846480 12.035 97 14 28 1 1216003440 92367952 2789008
06:41:05 49431 8192000000 10090846784 10569908224 1086566368 11.710 95 10 20 0 1135116928 46655280 1895280
06:43:07 27737 8192000000 10103591360 10524401664 1115753392 11.987 98 40 80 1 1162872304 38761472 8357440
06:44:36 34686 8192000000 10063819568 10473615360 1095052592 11.791 97 38 75 1 1136359584 34499504 6807488
06:46:14 62849 8192000000 10110859376 10609815552 1084650080 11.692 98 15 30 0 1141461584 53745840 3065664
06:48:19 90745 8192000000 10017646848 10469945344 1049864960 11.359 95 21 42 0 1076462320 22200208 4397152
06:51:15 51402 8192000000 10065839920 10502520832 1088463664 11.728 98 40 80 0 1136768272 40524336 7780272
06:54:02 39790 8192000000 10040521664 10474897408 1081666016 11.663 98 15 30 2 1111715552 27185056 2864480
06:55:42 57728 8192000000 10074891280 10544676864 1109413696 11.927 99 25 50 1 1170265600 55562320 5289584
06:57:41 79888 8192000000 10114396576 10588487680 1127169152 12.095 100 18 35 2 1220926336 89437040 4320144
07:00:03 52778 8192000000 10126615968 10614038528 1119246848 12.020 99 15 31 0 1233678256 110991152 3440256
07:02:04 56217 8192000000 10075763232 10528161792 1134125072 12.160 100 17 34 0 1168706592 30719616 3861904
07:04:01 37662 8192000000 10123072784 10572775424 1145413584 12.266 100 26 52 1 1198752480 47945904 5392992
07:05:45 61315 8192000000 10073346608 10522836992 1088093136 11.725 96 22 44 1 1155001952 62526880 4381936
07:07:48 67751 8192000000 10074022336 10523398144 1134232064 12.161 99 14 28 0 1180917584 43966048 2719472
07:09:56 59254 8192000000 10133974688 10567507968 1118326368 12.011 97 24 48 0 1189968672 67177296 4465008
07:12:00 91223 8192000000 10076529744 10511282176 1140665392 12.222 99 24 49 0 1169219376 23054992 5498992
07:14:33 45832 8192000000 10117717008 10566426624 1147734976 12.288 99 33 66 1 1208628032 54194224 6698832
07:16:21 67212 8192000000 10078471152 10579685376 1136307328 12.181 99 6 11 0 1177125552 39997344 820880
07:18:28 78203 8192000000 10096222016 10554470400 1155634560 12.362 100 14 28 0 1177736688 19089408 3012720
07:21:00 107766 8192000000 10097462992 10580045824 1142685648 12.241 99 8 14 0 1201139216 56804576 1648992
07:23:54 54650 8192000000 10104312640 10567528448 1137248160 12.190 98 15 30 0 1184986576 44923648 2814768
07:25:49 33465 8192000000 10118767280 10579726336 1140742752 12.223 99 40 80 0 1217169552 68538800 7888000
07:27:30 32006 8192000000 10105999760 10556592128 1113233552 11.963 97 28 56 0 1167363712 48183056 5947104
07:29:07 33342 8192000000 10119031520 10583465984 1134129744 12.160 99 30 60 0 1212795088 72654048 6011296
07:30:43 19844 8192000000 10093166320 10527739904 1112092032 11.952 98 42 84 1 1180967072 59976240 8898800
07:32:06 45671 8192000000 10084082304 10540937216 1091542928 11.757 97 34 67 0 1152161744 53013488 7605328
07:34:17 133516 8192000000 10053350656 10527653888 1067250528 11.526 95 24 48 0 1130697408 58065168 5381712

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-09-2021 07:39:27 to 23-09-2021 07:39:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 80 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-09-2021 07:39:43 to 23-09-2021 07:39:45.

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          238430
MON$OLDEST_ACTIVE               239138
MON$OLDEST_SNAPSHOT             239138
MON$NEXT_TRANSACTION            239138
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                       2081696
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             810
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2613 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2613 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2613 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2613 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
:74: 
:75: mscs-test-161.budzdorov.ru    Thu Sep 23 07:37:10 2021
:76:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:77: 
--- end of diff output ---

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

23.09.21 07:39:46. 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
2021-09-23 07:39:48 No severe PSQL-related problems occured
23.09.21 07:39:49. End of report.