Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.06.2021 07:28
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 16360296 kB
MemAvailable 31428864 kB
Buffers 0 kB
Cached 15315792 kB
SwapCached 2472 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-06-17 07:26:59
DTS_END                         2021-06-17 07:26:59.6750
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/34404
TRN_ID                          227478
ATT_ID                          593
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: 9500.86 21055 1140103
customer order: creation 166.25 400 19950
customer order: refuse 13.58 352 1630
order to supplier: creation 42.87 1384 5144
order to supplier: removal 3.43 121587 412
invoice (draft): creation 43.72 3735 5246
invoice (draft): removal 54.87 900 6584
invoice accept: apply 40.94 17098 4913
invoice accept: cancel 50.39 818 6047
customer reserve: creation 3831.93 163 459832
customer reserve: removal 4954.62 67 594554
realization accept: apply 78.27 182 9392
realization accept: cancel 96.91 114 11629
payment from customer: creation 57.12 107 6854
payment from customer: removal 5.36 85 643
payment to supplier: creation 47.29 113 5675
payment to supplier: removal 3.22 94 386
service: total inventory turnovers 3.33 3865 399
service: total monetary turnovers 5.84 639 701
service: aggregate perf. data 0.90 15101 108
service: refresh index statistics 0.03 254302 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 17-06-2021 07:29:09 to 17-06-2021 07:29:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 300
TEST_TIME 2 1372
TEST_TIME 3 2527
TEST_TIME 4 3269
TEST_TIME 5 3635
TEST_TIME 6 4011
TEST_TIME 7 4707
TEST_TIME 8 5474
TEST_TIME 9 6207
TEST_TIME 10 6268
TEST_TIME 11 7307
TEST_TIME 12 7385
TEST_TIME 13 7449
TEST_TIME 14 7360
TEST_TIME 15 7867
TEST_TIME 16 7965
TEST_TIME 17 8035
TEST_TIME 18 8446
TEST_TIME 19 8682
TEST_TIME 20 8900
TEST_TIME 21 9054
TEST_TIME 22 8931
TEST_TIME 23 8913
TEST_TIME 24 9147
TEST_TIME 25 9281
TEST_TIME 26 9462
TEST_TIME 27 9179
TEST_TIME 28 9531
TEST_TIME 29 10866
TEST_TIME 30 11606
TEST_TIME 31 11435
TEST_TIME 32 11207
TEST_TIME 33 11101
TEST_TIME 34 11406
TEST_TIME 35 11403
TEST_TIME 36 11292
TEST_TIME 37 11107
TEST_TIME 38 10885
TEST_TIME 39 10624
TEST_TIME 40 10614
TEST_TIME 41 10516
TEST_TIME 42 10450
TEST_TIME 43 10484
TEST_TIME 44 10440
TEST_TIME 45 10345
TEST_TIME 46 10225
TEST_TIME 47 10320
TEST_TIME 48 10209
TEST_TIME 49 10148
TEST_TIME 50 10090
TEST_TIME 51 10050
TEST_TIME 52 10087
TEST_TIME 53 10070
TEST_TIME 54 10147
TEST_TIME 55 10147
TEST_TIME 56 10119
TEST_TIME 57 10091
TEST_TIME 58 10262
TEST_TIME 59 10322
TEST_TIME 60 10354
TEST_TIME 61 10353
TEST_TIME 62 10242
TEST_TIME 63 10177
TEST_TIME 64 10158
TEST_TIME 65 10042
TEST_TIME 66 9945
TEST_TIME 67 9839
TEST_TIME 68 9861
TEST_TIME 69 9879
TEST_TIME 70 9852
TEST_TIME 71 9767
TEST_TIME 72 9806
TEST_TIME 73 9844
TEST_TIME 74 9887
TEST_TIME 75 9824
TEST_TIME 76 9891
TEST_TIME 77 9903
TEST_TIME 78 9951
TEST_TIME 79 9977
TEST_TIME 80 10028
TEST_TIME 81 9929
TEST_TIME 82 9825
TEST_TIME 83 9845
TEST_TIME 84 9756
TEST_TIME 85 9714
TEST_TIME 86 9670
TEST_TIME 87 9619
TEST_TIME 88 9642
TEST_TIME 89 9647
TEST_TIME 90 9608
TEST_TIME 91 9561
TEST_TIME 92 9595
TEST_TIME 93 9622
TEST_TIME 94 9774
TEST_TIME 95 9846
TEST_TIME 96 9857
TEST_TIME 97 9822
TEST_TIME 98 9870
TEST_TIME 99 9781
TEST_TIME 100 9741
TEST_TIME 101 9677
TEST_TIME 102 9612
TEST_TIME 103 9626
TEST_TIME 104 9585
TEST_TIME 105 9558
TEST_TIME 106 9551
TEST_TIME 107 9513
TEST_TIME 108 9533
TEST_TIME 109 9544
TEST_TIME 110 9528
TEST_TIME 111 9522
TEST_TIME 112 9668
TEST_TIME 113 9696
TEST_TIME 114 9689
TEST_TIME 115 9682
TEST_TIME 116 9712
TEST_TIME 117 9633
TEST_TIME 118 9576
TEST_TIME 119 9528
TEST_TIME 120 9501

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-06-2021 07:29:13 to 17-06-2021 07:29:17.

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 1565296 1565296 0 0.00 0 36656 6 0 0
doc_list_biud 1565262 1565262 0 0.00 0 51064 32 0 0
sp_add_invoice_to_stock 5057 4913 144 2.84 32 175396 17098 0 144
sp_cancel_adding_invoice 6047 6047 0 0.00 6 62064 818 0 0
sp_cancel_client_order 1630 1630 0 0.00 5 18211 352 0 0
sp_cancel_customer_reserve 594554 594554 0 0.00 1 51065 68 0 0
sp_cancel_pay_from_customer 643 643 0 0.00 3 6829 85 0 0
sp_cancel_pay_to_supplier 386 386 0 0.00 3 4821 95 0 0
sp_cancel_supplier_invoice 6599 6584 15 0.22 2 32065 901 0 15
sp_cancel_supplier_order 412 412 0 0.00 8 1073026 121587 0 0
sp_cancel_write_off 11629 11629 0 0.00 1 20763 114 0 0
sp_client_order 19950 19950 0 0.00 4 13668 400 0 0
sp_customer_reserve 459832 459832 0 0.00 0 48636 164 0 0
sp_fill_shopping_cart 30332 30332 0 0.00 1 14052 40 0 0
sp_fill_shopping_cart_clo_res 459832 459832 0 0.00 0 20626 16 0 0
sp_get_clo_for_invoice 4913 4913 0 0.00 0 23437 393 0 0
sp_kill_cost_storno 18705 18705 0 0.00 0 8097 54 0 0
sp_kill_qstorno_ret_qs2qd 1207733 1207733 0 0.00 0 51052 36 0 0
sp_kill_qty_storno 635138 635138 0 0.00 0 51064 78 0 0
sp_lock_selected_doc 19697 19697 0 0.00 0 13731 8 0 0
sp_make_cost_storno 26834 26834 0 0.00 0 16429 56 0 0
sp_multiply_rows_for_pdistr 26804 26804 0 0.00 0 8887 29 0 0
sp_multiply_rows_for_qdistr 19950 19950 0 0.00 0 13661 227 0 0
sp_pay_from_customer 6854 6854 0 0.00 4 14864 107 0 0
sp_pay_to_supplier 5675 5675 0 0.00 5 11467 113 0 0
sp_payment_common 12518 12518 0 0.00 1 11290 17 0 0
sp_qd_handle_on_cancel_clo 1630 1630 0 0.00 0 18206 312 0 0
sp_qd_handle_on_invoice_upd_sts 10954 10954 0 0.00 0 38645 381 0 0
sp_qd_handle_on_reserve_upd_sts 9375 9375 0 0.00 0 12612 52 0 0
sp_reserve_write_off 9392 9392 0 0.00 7 17564 183 0 0
sp_supplier_invoice 5246 5246 0 0.00 8 95507 3735 0 0
sp_supplier_order 5144 5144 0 0.00 5 24160 1385 0 0
srv_aggregate_perf_data 108 108 0 0.00 2480 122560 15102 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1110 173148 47197 0 0
srv_find_qd_qs_mism 1093428 1093428 0 0.00 0 36655 5 0 0
srv_make_invnt_saldo 399 399 0 0.00 45 92872 3866 0 0
srv_make_money_saldo 701 701 0 0.00 1 12382 640 0 0
srv_recalc_idx_stat 4 4 0 0.00 206793 303324 254303 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4913 4913 0 0.00 0 10496 170 0 0
t$perf-norm:sp_cancel_adding_invoice 388 388 0 0.00 0 3508 104 0 0
t$perf-norm:sp_cancel_client_order 1630 1630 0 0.00 0 2933 12 0 0
t$perf-norm:sp_cancel_customer_reserve 629 629 0 0.00 0 687 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 643 643 0 0.00 0 1185 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 386 386 0 0.00 0 285 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 996 16 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 17471 1596 0 0
t$perf-norm:sp_cancel_write_off 949 949 0 0.00 0 1609 8 0 0
t$perf-norm:sp_client_order 19950 19950 0 0.00 0 6359 18 0 0
t$perf-norm:sp_customer_reserve 939 939 0 0.00 0 3675 7 0 0
t$perf-norm:sp_pay_from_customer 6854 6854 0 0.00 0 5463 9 0 0
t$perf-norm:sp_pay_to_supplier 5675 5675 0 0.00 0 4316 10 0 0
t$perf-norm:sp_reserve_write_off 9392 9392 0 0.00 0 6889 10 0 0
t$perf-norm:sp_supplier_invoice 5246 5246 0 0.00 0 7160 17 0 0
t$perf-norm:sp_supplier_order 5144 5144 0 0.00 0 2953 16 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 297 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 196 5 0 0
t$perf-norm:srv_make_invnt_saldo 399 399 0 0.00 0 801 13 0 0
t$perf-norm:srv_make_money_saldo 701 701 0 0.00 0 2020 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10102 9814 288 2.85 0 4776 19 0 288
v_all_customers 36864 36864 0 0.00 0 36 1 0 0
v_all_suppliers 28930 28930 0 0.00 0 183 1 0 0
v_all_wares 39900 39900 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 776 776 0 0.00 0 2292 8 0 0
v_cancel_client_order 3260 3260 0 0.00 0 3403 7 0 0
v_cancel_customer_prepayment 1286 1286 0 0.00 0 3603 9 0 0
v_cancel_customer_reserve 1258 1258 0 0.00 0 757 5 0 0
v_cancel_payment_to_supplier 772 772 0 0.00 0 4815 16 0 0
v_cancel_supplier_invoice 786 756 30 3.81 0 3533 19 0 30
v_cancel_supplier_order 824 824 0 0.00 0 5357 17 0 0
v_cancel_write_off 1898 1898 0 0.00 0 2039 16 0 0
v_max_id_clo_ord 5141 5141 0 0.00 0 1876 2 0 0
v_max_id_clo_res 939 939 0 0.00 0 12393 30 0 0
v_max_id_ord_sup 5241 5241 0 0.00 0 7776 6 0 0
v_max_non_paid_invoice 1587 1587 0 0.00 0 135 2 0 0
v_max_non_paid_realizn 3403 3403 0 0.00 0 5690 7 0 0
v_min_id_clo_ord 5141 5141 0 0.00 0 2074 6 0 0
v_min_id_clo_res 939 939 0 0.00 0 9373 15 0 0
v_min_id_ord_sup 5241 5241 0 0.00 0 7084 8 0 0
v_min_non_paid_invoice 5670 5670 0 0.00 0 5561 9 0 0
v_min_non_paid_realizn 6845 6845 0 0.00 0 11285 10 0 0
v_our_firm 9922 9922 0 0.00 0 19 1 0 0
v_reserve_write_off 18750 18750 0 0.00 0 7647 7 0 0
x_lock_depdocs_on_canc_invoice 12253 12253 0 0.00 0 20775 105 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 14990 381 0 0
x_make_qstorno_1000_1200 5144 5144 0 0.00 1 24153 1305 0 0
x_make_qstorno_1200_2000 5246 5246 0 0.00 3 93079 3589 0 0
x_make_qstorno_2100_3300 459832 459832 0 0.00 0 48622 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-06-2021 07:29:18 to 17-06-2021 07:29:22.

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
04:57:03 6 8192000000 8796668864 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
04:58:04 37952 8192000000 9718808480 10014429184 792950784 8.825 100 88 176 0 822963696 8439200 21573712
04:59:54 31454 8192000000 9770381664 10080038912 839970928 9.299 100 86 172 0 873954272 13158176 20825168
05:01:33 18289 8192000000 9821930432 10147254272 888421328 9.783 100 80 160 0 934145520 27798016 17926176
05:03:01 11838 8192000000 9888678032 10228604928 937314912 10.267 100 83 166 0 999037008 44171824 17550272
05:04:18 6535 8192000000 9939566960 10290032640 978384416 10.668 100 80 157 0 1050949392 56464720 16100256
05:05:26 3049 8192000000 9969342304 10326253568 1005903472 10.936 100 84 168 1 1085246928 62337856 17005600
05:06:32 2751 8192000000 9988225216 10356031488 1032772880 11.195 100 85 170 1 1109204224 59127600 17303744
05:07:36 1259 8192000000 10014974688 10383933440 1049901376 11.360 100 91 183 2 1140674912 71826080 18947456
05:08:40 16522 8192000000 10011905248 10386354176 1060073840 11.457 100 58 116 0 1132677216 60658880 11944496
05:09:58 4176 8192000000 10026406592 10424918016 1067481216 11.528 100 57 114 2 1155058288 76178176 11398896
05:11:04 5565 8192000000 9999756224 10394099712 1076596464 11.615 100 38 75 1 1118437824 34058960 7782400
05:12:10 7445 8192000000 9987258240 10388406272 1080894112 11.656 100 42 83 3 1112707312 23082608 8730592
05:13:19 6780 8192000000 9998268672 10386722816 1086319536 11.708 100 47 94 0 1119013168 23184016 9509616
05:14:57 8912 8192000000 10016893056 10427469824 1090459200 11.747 100 32 65 1 1112499616 15245856 6794560
05:16:07 5620 8192000000 10000800240 10410549248 1090138320 11.744 100 35 71 3 1116156000 18241376 7776304
05:17:12 46715 8192000000 9993428704 10402951168 1073028368 11.581 98 28 56 0 1099163632 20597840 5537424
05:19:03 5426 8192000000 9958915344 10349023232 1015463136 11.028 96 31 62 0 1036858048 14579856 6815056
05:20:09 2319 8192000000 9945790112 10326532096 1019612672 11.068 98 44 84 1 1050012832 21049024 9351136
05:21:14 6722 8192000000 9914479504 10293297152 973474208 10.621 95 33 67 3 998006464 16642256 7890000
05:22:22 46755 8192000000 9939926080 10325671936 985814016 10.741 96 31 63 0 1009811792 16701968 7295808
05:24:10 7664 8192000000 9963281152 10367897600 1047240864 11.334 100 34 68 0 1084602944 29395104 7966976
05:25:19 8679 8192000000 9986610144 10388107264 1044274096 11.306 99 39 78 0 1078779120 26136784 8368240
05:26:33 10508 8192000000 9990778176 10398969856 1068880256 11.541 100 39 77 2 1096486144 18808704 8797184
05:27:45 50919 8192000000 10025065008 10425581568 1086415408 11.709 100 43 83 2 1131195120 36189344 8590368
05:29:42 17855 8192000000 10088367568 10493108224 1107873536 11.912 100 55 110 0 1202687520 83508464 11305520
05:31:03 2819 8192000000 10127119392 10526605312 1126644592 12.090 100 66 132 3 1230178944 88556400 14977952
05:32:20 12173 8192000000 10124143824 10536443904 1129826320 12.120 100 47 93 1 1237309648 97421088 10062240
05:33:37 6085 8192000000 10169334960 10586419200 1140269968 12.218 100 50 97 0 1278998928 128484896 10244064
05:34:47 2938 8192000000 10197093952 10602512384 1145839744 12.270 100 62 124 0 1297672256 138720176 13112336
05:35:54 8251 8192000000 10183775216 10601844736 1154295936 12.350 100 58 116 1 1295421792 127434656 13691200
05:37:07 4758 8192000000 10236564464 10641223680 1147191168 12.283 100 70 136 0 1331848144 169744912 14912064
05:38:16 9687 8192000000 10235130192 10644262912 1158292128 12.387 100 79 158 1 1337303440 162084784 16926528
05:39:33 3396 8192000000 10213738256 10634452992 1141694112 12.231 99 59 116 1 1319229744 164473584 13062048
05:40:42 3891 8192000000 10248837888 10658570240 1147800992 12.289 99 68 136 1 1342132416 178884064 15447360
05:41:50 3833 8192000000 10271152064 10675195904 1146106048 12.273 99 67 129 2 1370360464 210280272 13974144
05:42:57 3071 8192000000 10300359744 10704695296 1143152384 12.245 99 74 146 1 1394352752 234372864 16827504
05:44:04 2269 8192000000 10311526944 10741923840 1146769728 12.279 100 77 153 2 1420464384 255825728 17868928
05:45:11 6932 8192000000 10327053712 10748514304 1158992512 12.394 100 45 89 1 1441292144 271427104 10872528
05:46:25 2672 8192000000 10342309168 10775363584 1127749168 12.100 100 61 122 0 1432317008 289240064 15327776
05:47:32 8809 8192000000 10348861792 10775465984 1134139728 12.160 100 52 103 1 1444495600 297998320 12357552
05:48:57 2486 8192000000 10400136640 10800771072 1153930752 12.346 100 72 139 0 1487037920 316850640 16256528
05:50:03 1107 8192000000 10451984960 10856591360 1161537120 12.418 100 84 164 2 1540225168 358204208 20483840
05:51:09 4489 8192000000 10428111712 10844704768 1162030064 12.422 99 54 105 0 1523221680 347514640 13676976
05:52:15 26599 8192000000 10520189552 10978037760 1171210720 12.508 100 40 80 0 1608648976 427092992 10345264
05:53:43 17601 8192000000 10523199456 11010273280 1152871552 12.336 100 31 62 4 1651962704 489688608 9402544
05:55:06 29790 8192000000 10384338528 10985238528 1140251664 12.218 100 27 54 1 1612894832 464756768 7886400
05:56:45 85288 8192000000 10065844816 10493952000 1074273920 11.593 94 22 41 1 1105059184 26589088 4196176
05:59:21 28047 8192000000 10140372800 10563305472 1137278160 12.190 100 39 78 1 1225885840 80685296 7922384
06:00:52 124553 8192000000 10068547008 10521247744 1078486624 11.633 95 13 26 1 1154523616 73564784 2472208
06:03:57 133558 8192000000 10086264768 10524975104 1118055856 12.009 99 26 51 1 1168020944 44458880 5506208
06:07:16 34623 8192000000 10108309760 10552455168 1134587104 12.165 100 12 24 1 1195021888 57827920 2606864
06:08:52 101145 8192000000 10116596848 10639118336 1136279120 12.181 100 19 39 1 1256797056 116187968 4329968
06:11:33 47399 8192000000 10112349136 10587119616 1139871456 12.214 100 18 36 2 1223206928 79086048 4249424
06:13:21 45584 8192000000 10085449968 10627485696 1143516592 12.249 100 5 10 0 1225249392 80693632 1039168
06:15:07 49419 8192000000 10088130768 10556108800 1148332768 12.294 100 24 48 1 1185708464 32648512 4727184
06:16:56 49578 8192000000 10116104912 10571075584 1149350176 12.303 100 27 52 1 1216611024 61730192 5530656
06:18:48 41016 8192000000 10125571648 10593865728 1124868320 12.073 97 18 36 0 1201202688 72613152 3721216
06:20:29 42513 8192000000 10110406080 10601054208 1130438784 12.125 99 24 48 2 1199957040 63715616 5802640
06:22:13 27637 8192000000 10143156848 10600103936 1130028752 12.122 99 30 60 1 1217978192 81514880 6434560
06:23:41 34659 8192000000 10124601808 10615488512 1135348720 12.172 99 20 40 0 1240969760 100888752 4732288
06:25:27 115425 8192000000 10054329744 10505895936 1058947328 11.446 93 6 12 1 1094839648 34712992 1179328
06:28:26 109082 8192000000 10093379824 10562322432 1127509040 12.098 99 14 28 0 1216213760 85720000 2984720
06:31:23 35806 8192000000 10083804112 10580160512 1127225968 12.095 99 6 12 0 1179908384 51966592 715824
06:33:02 84972 8192000000 10151213776 10605535232 1129958992 12.121 99 11 22 0 1220477856 87919760 2599104
06:35:28 64787 8192000000 10083011072 10543001600 1123232096 12.058 98 19 38 0 1176776544 49853200 3691248
06:37:33 38548 8192000000 10159041904 10604994560 1150263216 12.312 100 43 84 2 1224744128 65339424 9141488
06:39:25 86120 8192000000 10093878960 10560004096 1122085568 12.047 97 28 57 1 1183836992 56057136 5694288
06:41:55 43834 8192000000 10128561696 10571108352 1108518256 11.918 97 50 100 1 1184869328 66317456 10033616
06:43:46 50725 8192000000 10105344288 10602893312 1129963056 12.121 99 12 24 0 1209823264 77657984 2202224
06:45:42 70346 8192000000 10066985248 10560389120 1091194848 11.754 96 17 34 0 1157927200 63516080 3216272
06:48:11 88184 8192000000 10085480624 10547478528 1098903152 11.827 97 15 30 1 1155243280 52668400 3671728
06:51:06 75561 8192000000 10076550576 10569539584 1106162976 11.896 98 22 46 1 1170771808 59183136 5425696
06:53:22 47194 8192000000 10111770784 10545577984 1106623152 11.900 97 20 40 1 1190003760 78909904 4470704
06:55:41 28222 8192000000 10115189088 10553323520 1120575968 12.032 99 22 44 0 1186872160 61486880 4809312
06:57:13 75498 8192000000 10165213696 10656882688 1095283472 11.793 95 23 46 0 1259792496 159373872 5135152
06:59:29 61020 8192000000 10104347968 10687004672 1116717616 11.996 99 14 27 1 1253762144 134254832 2789696
07:01:30 50844 8192000000 10070233376 10516455424 1113237856 11.963 98 35 70 0 1154243712 32946208 8059648
07:03:36 46402 8192000000 10113369760 10543288320 1127443040 12.097 99 33 66 2 1212865616 78896304 6526272
07:05:22 111914 8192000000 10077106224 10561454080 1128073056 12.103 100 16 32 0 1159662832 28371760 3218016
07:08:26 58766 8192000000 10080953600 10584580096 1139703072 12.213 100 7 14 0 1220976208 79775552 1497584
07:10:25 38114 8192000000 10061559792 10511028224 1110739264 11.939 97 19 38 0 1145434112 30564688 4130160
07:12:07 65826 8192000000 10095794032 10539716608 1144593440 12.259 100 14 28 2 1175130864 26648064 3889360
07:14:37 91704 8192000000 10139610304 10609754112 1131847520 12.139 99 24 48 0 1211441552 74655936 4938096
07:17:09 58512 8192000000 10185002064 10663485440 1121253264 12.039 98 29 58 1 1247993072 120319776 6420032
07:19:18 173148 8192000000 10081368848 10598338560 1069442944 11.547 94 10 20 0 1127862800 56731840 1688016
07:23:11 93848 8192000000 10065399168 10521473024 1121433568 12.041 99 22 44 0 1157741488 31597152 4710768
07:25:59 78676 8192000000 9796487888 10241007616 897894128 9.877 79 5 10 0 924448672 25404336 1150208

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-06-2021 07:29:23 to 17-06-2021 07:29:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 144 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-06-2021 07:29:39 to 17-06-2021 07:29:41.

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          228350
MON$OLDEST_ACTIVE               228351
MON$OLDEST_SNAPSHOT             228351
MON$NEXT_TRANSACTION            228351
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                       2141056
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             786
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2517 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2517 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2517 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2517 Firebird 4.0/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 ---
:106: 
:107: mscs-test-161.budzdorov.ru    Thu Jun 17 07:27:25 2021
:108:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:109: 
:110: 
:111: mscs-test-161.budzdorov.ru    Thu Jun 17 07:27:26 2021
:112:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:113: 
:114: 
:115: mscs-test-161.budzdorov.ru    Thu Jun 17 07:27:33 2021
:116:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:117: 
--- end of diff output ---

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

17.06.21 07:29:42. 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-06-17 07:29:45 No severe PSQL-related problems occured
17.06.21 07:29:46. End of report.