Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.07.2022 13: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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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.33
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 16111036 kB
MemAvailable 31046104 kB
Buffers 0 kB
Cached 15565576 kB
SwapCached 2492 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.2
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 2022-07-16 13:37:19
DTS_END                         2022-07-16 13:37:19.0700
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43310
TRN_ID                          215386
ATT_ID                          641
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: 8670.26 20665 1040431
customer order: creation 162.93 459 19551
customer order: refuse 13.63 403 1636
order to supplier: creation 41.41 1399 4969
order to supplier: removal 3.36 163048 403
invoice (draft): creation 42.63 3150 5115
invoice (draft): removal 47.27 991 5672
invoice accept: apply 39.29 27339 4715
invoice accept: cancel 43.25 1248 5190
customer reserve: creation 3712.13 258 445456
customer reserve: removal 4285.93 102 514312
realization accept: apply 78.93 270 9471
realization accept: cancel 78.14 167 9377
payment from customer: creation 54.73 184 6567
payment from customer: removal 5.70 160 684
payment to supplier: creation 45.96 205 5515
payment to supplier: removal 3.17 123 380
service: total inventory turnovers 3.75 3976 450
service: total monetary turnovers 7.06 380 847
service: aggregate perf. data 0.97 13482 116
service: refresh index statistics 0.04 195957 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-07-2022 13:38:10 to 16-07-2022 13:38:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7912
TEST_TIME 2 6710
TEST_TIME 3 7564
TEST_TIME 4 7514
TEST_TIME 5 7069
TEST_TIME 6 6616
TEST_TIME 7 6645
TEST_TIME 8 6743
TEST_TIME 9 6696
TEST_TIME 10 6609
TEST_TIME 11 6967
TEST_TIME 12 6943
TEST_TIME 13 6804
TEST_TIME 14 7028
TEST_TIME 15 7106
TEST_TIME 16 7363
TEST_TIME 17 7384
TEST_TIME 18 7607
TEST_TIME 19 8109
TEST_TIME 20 8232
TEST_TIME 21 8383
TEST_TIME 22 9041
TEST_TIME 23 8816
TEST_TIME 24 8653
TEST_TIME 25 8823
TEST_TIME 26 8726
TEST_TIME 27 9245
TEST_TIME 28 9801
TEST_TIME 29 9936
TEST_TIME 30 10734
TEST_TIME 31 11131
TEST_TIME 32 10913
TEST_TIME 33 10596
TEST_TIME 34 10412
TEST_TIME 35 10305
TEST_TIME 36 10325
TEST_TIME 37 10162
TEST_TIME 38 9957
TEST_TIME 39 10022
TEST_TIME 40 9824
TEST_TIME 41 9829
TEST_TIME 42 9793
TEST_TIME 43 9757
TEST_TIME 44 9701
TEST_TIME 45 9599
TEST_TIME 46 9402
TEST_TIME 47 9572
TEST_TIME 48 9545
TEST_TIME 49 9483
TEST_TIME 50 9327
TEST_TIME 51 9213
TEST_TIME 52 9171
TEST_TIME 53 9073
TEST_TIME 54 9012
TEST_TIME 55 9046
TEST_TIME 56 9059
TEST_TIME 57 9036
TEST_TIME 58 8988
TEST_TIME 59 8950
TEST_TIME 60 8954
TEST_TIME 61 9133
TEST_TIME 62 9131
TEST_TIME 63 9211
TEST_TIME 64 9208
TEST_TIME 65 9171
TEST_TIME 66 9263
TEST_TIME 67 9211
TEST_TIME 68 9189
TEST_TIME 69 9081
TEST_TIME 70 9110
TEST_TIME 71 9048
TEST_TIME 72 8972
TEST_TIME 73 9013
TEST_TIME 74 8962
TEST_TIME 75 8958
TEST_TIME 76 8928
TEST_TIME 77 8944
TEST_TIME 78 8892
TEST_TIME 79 8888
TEST_TIME 80 8863
TEST_TIME 81 8828
TEST_TIME 82 8797
TEST_TIME 83 8812
TEST_TIME 84 8824
TEST_TIME 85 8816
TEST_TIME 86 8968
TEST_TIME 87 9073
TEST_TIME 88 9086
TEST_TIME 89 9024
TEST_TIME 90 8987
TEST_TIME 91 8942
TEST_TIME 92 8906
TEST_TIME 93 8897
TEST_TIME 94 8841
TEST_TIME 95 8820
TEST_TIME 96 8848
TEST_TIME 97 8814
TEST_TIME 98 8875
TEST_TIME 99 8844
TEST_TIME 100 8833
TEST_TIME 101 8824
TEST_TIME 102 8853
TEST_TIME 103 8825
TEST_TIME 104 8860
TEST_TIME 105 8922
TEST_TIME 106 8908
TEST_TIME 107 8898
TEST_TIME 108 8914
TEST_TIME 109 8842
TEST_TIME 110 8854
TEST_TIME 111 8816
TEST_TIME 112 8788
TEST_TIME 113 8778
TEST_TIME 114 8768
TEST_TIME 115 8744
TEST_TIME 116 8742
TEST_TIME 117 8710
TEST_TIME 118 8712
TEST_TIME 119 8721
TEST_TIME 120 8694

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-07-2022 13:38:14 to 16-07-2022 13:38: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 1450563 1450563 0 0.00 0 25992 13 0 0
doc_list_biud 1450553 1450553 0 0.00 0 51190 42 0 0
sp_add_invoice_to_stock 4837 4715 122 2.52 63 257585 27339 0 122
sp_cancel_adding_invoice 5190 5190 0 0.00 6 105293 1249 0 0
sp_cancel_client_order 1636 1636 0 0.00 6 12743 403 0 0
sp_cancel_customer_reserve 514312 514312 0 0.00 1 30947 103 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 4 13760 161 0 0
sp_cancel_pay_to_supplier 380 380 0 0.00 3 6855 124 0 0
sp_cancel_supplier_invoice 5677 5672 5 0.08 3 51194 992 0 5
sp_cancel_supplier_order 403 403 0 0.00 10 1309068 163048 0 0
sp_cancel_write_off 9377 9377 0 0.00 2 9490 168 0 0
sp_client_order 19551 19551 0 0.00 5 18785 460 0 0
sp_customer_reserve 445456 445456 0 0.00 0 60827 258 0 0
sp_fill_shopping_cart 29626 29626 0 0.00 1 16983 80 0 0
sp_fill_shopping_cart_clo_res 445434 445434 0 0.00 0 14108 21 0 0
sp_get_clo_for_invoice 4688 4688 0 0.00 0 35703 383 0 0
sp_kill_cost_storno 15631 15631 0 0.00 0 23939 75 0 0
sp_kill_qstorno_ret_qs2qd 1044076 1044076 0 0.00 0 51189 46 0 0
sp_kill_qty_storno 550748 550748 0 0.00 0 51190 103 0 0
sp_lock_selected_doc 19650 19650 0 0.00 0 8658 7 0 0
sp_make_cost_storno 26241 26241 0 0.00 0 18068 85 0 0
sp_multiply_rows_for_pdistr 26241 26241 0 0.00 0 7649 36 0 0
sp_multiply_rows_for_qdistr 19551 19551 0 0.00 0 14772 192 0 0
sp_pay_from_customer 6567 6567 0 0.00 5 12776 185 0 0
sp_pay_to_supplier 5515 5515 0 0.00 6 15269 205 0 0
sp_payment_common 12082 12082 0 0.00 2 12275 48 0 0
sp_qd_handle_on_cancel_clo 1636 1636 0 0.00 0 12612 326 0 0
sp_qd_handle_on_invoice_upd_sts 9878 9878 0 0.00 0 41129 490 0 0
sp_qd_handle_on_reserve_upd_sts 9470 9470 0 0.00 0 12904 62 0 0
sp_reserve_write_off 9471 9471 0 0.00 8 18621 270 0 0
sp_supplier_invoice 5115 5115 0 0.00 10 95957 3150 0 0
sp_supplier_order 4969 4969 0 0.00 7 67580 1400 0 0
srv_aggregate_perf_data 116 116 0 0.00 2457 103142 13482 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 826 107620 34548 0 0
srv_find_qd_qs_mism 994089 994089 0 0.00 0 25992 10 0 0
srv_make_invnt_saldo 450 450 0 0.00 36 83849 3977 0 0
srv_make_money_saldo 847 847 0 0.00 1 9262 381 0 0
srv_recalc_idx_stat 5 5 0 0.00 62295 322737 195958 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4722 4722 0 0.00 1 9013 826 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 1 7190 485 0 0
t$perf-norm:sp_cancel_client_order 1636 1636 0 0.00 0 5887 27 0 0
t$perf-norm:sp_cancel_customer_reserve 639 639 0 0.00 0 4810 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 301 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 380 380 0 0.00 0 1630 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 362 362 0 0.00 0 727 26 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 67219 5870 0 0
t$perf-norm:sp_cancel_write_off 1002 1002 0 0.00 0 11495 29 0 0
t$perf-norm:sp_client_order 19551 19551 0 0.00 0 11359 33 0 0
t$perf-norm:sp_customer_reserve 966 966 0 0.00 0 1628 17 0 0
t$perf-norm:sp_pay_from_customer 6567 6567 0 0.00 0 11341 20 0 0
t$perf-norm:sp_pay_to_supplier 5515 5515 0 0.00 0 20585 21 0 0
t$perf-norm:sp_reserve_write_off 9472 9472 0 0.00 0 11423 24 0 0
t$perf-norm:sp_supplier_invoice 5115 5115 0 0.00 0 11707 28 0 0
t$perf-norm:sp_supplier_order 4969 4969 0 0.00 0 10736 32 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 324 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 59 4 0 0
t$perf-norm:srv_make_invnt_saldo 450 450 0 0.00 0 3032 19 0 0
t$perf-norm:srv_make_money_saldo 847 847 0 0.00 0 3503 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 6 189 85 0 0
v_add_invoice_to_stock 9616 9372 244 2.53 0 11138 29 0 244
v_all_customers 35710 35710 0 0.00 0 67 3 0 0
v_all_suppliers 28296 28296 0 0.00 0 73 4 0 0
v_all_wares 39096 39096 0 0.00 0 133 3 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 803 15 0 0
v_cancel_client_order 3272 3272 0 0.00 0 4524 16 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 13719 36 0 0
v_cancel_customer_reserve 1278 1278 0 0.00 0 3100 17 0 0
v_cancel_payment_to_supplier 760 760 0 0.00 0 1825 15 0 0
v_cancel_supplier_invoice 734 724 10 1.36 0 1116 24 0 10
v_cancel_supplier_order 806 806 0 0.00 0 3498 14 0 0
v_cancel_write_off 2004 2004 0 0.00 0 4812 25 0 0
v_max_id_clo_ord 4968 4968 0 0.00 0 222 4 0 0
v_max_id_clo_res 966 966 0 0.00 0 1156 15 0 0
v_max_id_ord_sup 5107 5107 0 0.00 0 4079 6 0 0
v_max_non_paid_invoice 1441 1441 0 0.00 0 4457 10 0 0
v_max_non_paid_realizn 3311 3311 0 0.00 0 423 6 0 0
v_min_id_clo_ord 4968 4968 0 0.00 0 6113 12 0 0
v_min_id_clo_res 966 966 0 0.00 0 4512 22 0 0
v_min_id_ord_sup 5107 5107 0 0.00 0 5543 11 0 0
v_min_non_paid_invoice 5514 5514 0 0.00 0 12262 21 0 0
v_min_non_paid_realizn 6566 6566 0 0.00 0 11971 25 0 0
v_our_firm 9894 9894 0 0.00 0 68 4 0 0
v_reserve_write_off 18938 18938 0 0.00 0 18471 14 0 0
x_lock_depdocs_on_canc_invoice 10500 10500 0 0.00 0 14008 136 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 4532 315 0 0
x_make_qstorno_1000_1200 4968 4968 0 0.00 2 64904 1252 0 0
x_make_qstorno_1200_2000 5115 5115 0 0.00 3 92254 2909 0 0
x_make_qstorno_2100_3300 445456 445456 0 0.00 0 57894 226 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-07-2022 13:38:18 to 16-07-2022 13:38: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
11:07:23 9 8192000000 8803899632 8817270784 8077728 0.098 3 2 3 0 8273392 126880 68784
11:08:23 31080 8192000000 9740698592 10042617856 816573264 9.064 100 90 175 1 845281136 7598944 21108928
11:10:01 18035 8192000000 9778985392 10091741184 852973344 9.430 100 89 178 0 886948048 13017136 20957568
11:11:24 12562 8192000000 9819305872 10142937088 884710384 9.747 100 83 163 0 921606976 18598000 18298592
11:12:41 7514 8192000000 9881566064 10217517056 921841392 10.114 100 79 158 0 982555328 43706208 17007728
11:13:52 4889 8192000000 9916256480 10260144128 957130000 10.461 100 77 149 0 1026993024 54609360 15253664
11:15:00 1951 8192000000 9970332976 10322960384 995560896 10.835 100 90 179 1 1085198336 71299664 18337776
11:16:04 1390 8192000000 9994355328 10358091776 1023208944 11.103 100 93 180 0 1109582336 68610672 17762720
11:17:09 920 8192000000 10045631680 10417491968 1041503824 11.279 100 96 186 0 1166043136 106072688 18466624
11:18:11 1540 8192000000 10032701312 10406359040 1058881920 11.446 100 90 176 0 1150392816 73384448 18126448
11:19:14 17481 8192000000 10037334176 10417000448 1070684560 11.559 100 81 154 0 1170088352 83904480 15499312
11:20:32 1556 8192000000 10041617840 10426363904 1072859680 11.579 100 84 167 0 1168147792 77620608 17667504
11:21:35 1236 8192000000 10050715904 10443341824 1079350048 11.641 100 86 168 3 1174294976 76979552 17965376
11:22:37 952 8192000000 10058388768 10449453056 1084537760 11.691 100 89 167 1 1178522848 77147232 16837856
11:23:40 1214 8192000000 10078473392 10470232064 1093878176 11.780 100 99 190 1 1202123440 89344016 18901248
11:24:42 1063 8192000000 10058282784 10449403904 1096696128 11.806 100 97 183 0 1179691024 64803040 18191856
11:25:44 1168 8192000000 10065141760 10455846912 1100854064 11.846 100 96 188 2 1191138816 70360272 19924480
11:26:46 1095 8192000000 10057680640 10450067456 1098622032 11.825 100 95 184 0 1182415552 65428864 18364656
11:27:50 1079 8192000000 10075427360 10464378880 1090778720 11.750 99 93 182 1 1194716000 85249856 18687424
11:28:51 1086 8192000000 10028271472 10414133248 1081266080 11.660 99 92 175 0 1148400000 49805232 17328688
11:29:54 1291 8192000000 10028314864 10413121536 1062527200 11.481 99 78 142 2 1146690352 70811248 13351904
11:30:56 2462 8192000000 10019346240 10412654592 1073115600 11.582 100 71 137 1 1136827488 49889968 13821920
11:32:01 918 8192000000 10028398768 10414972928 1073731712 11.588 100 81 152 2 1149799232 60934352 15133168
11:33:03 1049 8192000000 10038180752 10423123968 1063947024 11.494 100 85 166 0 1161085184 79900272 17237888
11:34:05 897 8192000000 10007295024 10386366464 1054884176 11.407 100 94 178 2 1122689856 49320448 18485232
11:35:09 6159 8192000000 9960058144 10339397632 1018732464 11.060 98 63 108 2 1073427056 44213392 10481200
11:36:16 2076 8192000000 10005363792 10382536704 1053559168 11.395 100 93 170 3 1128467232 57474944 17433120
11:37:19 4941 8192000000 9993001328 10374029312 1051117408 11.371 99 65 121 2 1114808944 51393520 12298016
11:38:31 3308 8192000000 10022094800 10399911936 1076486560 11.614 100 84 153 3 1136754240 45924496 14343184
11:39:41 12143 8192000000 10071300864 10459607040 1098532272 11.824 100 67 129 1 1189278688 77866928 12879488
11:41:00 1800 8192000000 10096538256 10486566912 1117994320 12.008 100 73 139 1 1214849536 82590208 14265008
11:42:05 2425 8192000000 10103667664 10504695808 1123075856 12.056 100 62 122 0 1222486528 86792560 12618112
11:43:09 11811 8192000000 10135762864 10532319232 1118489296 12.013 99 68 129 1 1245754448 113875616 13389536
11:44:28 6383 8192000000 10191173936 10593538048 1140246768 12.218 100 75 144 1 1316135680 160301872 15587040
11:45:37 3662 8192000000 10224564896 10627567616 1161110224 12.414 100 78 152 2 1338324528 158775760 18438544
11:46:45 4687 8192000000 10237973056 10641514496 1156464736 12.370 100 73 136 0 1353809248 182621632 14722880
11:47:53 5259 8192000000 10265582848 10693701632 1172739520 12.522 100 81 160 1 1380741328 188849152 19152656
11:49:09 6369 8192000000 10291743488 10692943872 1170052464 12.497 100 85 168 0 1403675648 214207088 19416096
11:50:19 954 8192000000 10313802032 10724327424 1171858944 12.514 100 96 192 0 1425768192 231117728 22791520
11:51:24 990 8192000000 10372639040 10781921280 1187002800 12.655 100 99 196 0 1486404992 276741152 22661040
11:52:27 816 8192000000 10415856704 10824507392 1185273520 12.639 100 97 188 2 1525989472 317462336 23253616
11:53:30 830 8192000000 10432490160 10858422272 1191253488 12.695 100 95 186 1 1538583280 324754032 22575760
11:54:34 4106 8192000000 10458681312 10879709184 1185505760 12.642 100 78 154 1 1568006480 363637008 18863712
11:55:42 1100 8192000000 10421669504 10885922816 1186021760 12.646 100 91 174 0 1523716832 316825664 20869408
11:56:45 919 8192000000 10440725968 10872754176 1193373616 12.715 100 94 179 1 1546393920 332275552 20744752
11:57:48 1231 8192000000 10456604272 10868826112 1176503552 12.558 100 88 171 0 1562537120 365450608 20582960
11:58:54 3371 8192000000 10441846272 10912575488 1172030368 12.516 100 68 136 0 1548809888 360448144 16331376
12:00:01 17835 8192000000 10412646800 10854912000 1182279248 12.611 100 36 72 0 1509170064 317873440 9017376
12:01:22 10817 8192000000 10481339504 10912448512 1185576272 12.642 100 46 90 1 1576350000 379821568 10952160
12:02:34 15190 8192000000 10492499872 10947575808 1184672176 12.634 100 32 64 0 1592224592 399150912 8401504
12:04:01 26918 8192000000 10484505600 10988736512 1171661376 12.512 100 44 88 0 1648217184 464767872 11787936
12:05:29 11832 8192000000 10360951696 10901630976 1156571200 12.371 100 35 68 0 1459127424 293667440 8888784
12:06:45 26621 8192000000 10257304752 10751365120 1159763104 12.401 100 28 56 0 1378026528 211655504 6607920
12:08:19 59453 8192000000 10080931712 10548305920 1122348048 12.049 98 14 26 1 1180904496 56316960 2239488
12:10:22 43477 8192000000 10095794704 10537840640 1149013232 12.300 100 54 102 5 1192912288 34254928 9644128
12:12:06 11854 8192000000 10180020800 10616135680 1167877840 12.477 100 59 110 0 1268265424 89216000 11171584
12:13:22 81007 8192000000 10156487808 10624626688 1152004368 12.328 99 23 46 0 1262836016 105889152 4942496
12:16:10 78242 8192000000 10184088368 10677010432 1167731344 12.476 100 17 34 0 1273585040 101971776 3881920
12:19:04 48311 8192000000 10181484576 10634944512 1136317200 12.181 98 22 44 0 1264365520 122833424 5214896
12:20:53 63701 8192000000 10137794032 10581381120 1137869248 12.195 98 39 75 2 1233898928 86515504 9514176
12:23:01 54874 8192000000 10152145072 10610200576 1116301344 11.992 98 27 52 1 1236576848 114050752 6224752
12:26:07 61507 8192000000 10070568992 10533425152 1113578816 11.966 97 14 27 0 1142548352 25841536 3128000
12:28:15 75013 8192000000 10088807264 10545614848 1102646816 11.863 96 31 62 0 1159153584 50015712 6491056
12:30:30 71095 8192000000 10105063296 10585505792 1123775232 12.063 98 10 20 0 1196349952 70333664 2241056
12:33:01 41876 8192000000 10058366608 10529894400 1103663392 11.872 97 31 62 0 1138825376 28625488 6536496
12:34:48 17923 8192000000 10095891440 10539618304 1145904384 12.271 100 35 68 0 1188029376 35023920 7101072
12:36:06 23162 8192000000 10156911184 10602741760 1162458256 12.426 100 45 90 0 1253071776 80808656 9804864
12:37:32 68262 8192000000 10105611504 10655641600 1146876160 12.280 100 19 38 0 1284439072 133191488 4371424
12:39:45 22834 8192000000 10113502960 10565890048 1129643776 12.118 99 45 90 0 1191445664 53373840 8428048
12:41:11 42335 8192000000 10100194960 10565226496 1137451824 12.192 99 25 49 0 1189202512 46898160 4852528
12:42:54 17928 8192000000 10144454768 10605211648 1140613328 12.221 99 54 108 1 1232333312 80731488 10988496
12:44:16 29882 8192000000 10133079136 10573967360 1148198560 12.293 100 48 95 0 1218514208 60878912 9436736
12:45:49 87817 8192000000 10122196720 10546991104 1133951936 12.159 100 58 110 3 1227955632 82023920 11979776
12:48:21 56747 8192000000 10126667824 10578640896 1146486160 12.277 99 22 44 0 1228070080 77069936 4513984
12:50:18 67891 8192000000 10092987344 10611593216 1133951440 12.159 99 5 8 0 1198615184 63807216 856528
12:52:40 48722 8192000000 10068108480 10519126016 1109176480 11.925 97 18 36 0 1145931840 33175184 3580176
12:54:51 19108 8192000000 10092119888 10525499392 1140803136 12.223 99 43 77 1 1185608000 36307936 8496928
12:56:12 32560 8192000000 10117124272 10570723328 1120204352 12.029 99 27 54 0 1203366320 77459968 5702000
12:57:55 35763 8192000000 10167634448 10607857664 1130280176 12.124 98 35 70 0 1252295648 113681632 8333840
12:59:40 17828 8192000000 10212423744 10651205632 1146029792 12.272 99 39 78 0 1310747680 155314384 9403504
13:01:03 50763 8192000000 10218994336 10682363904 1124783088 12.072 97 35 68 2 1313880224 181203984 7893152
13:03:03 64409 8192000000 10176320192 10697809920 1129079024 12.113 99 16 32 0 1315303824 182193184 4031616
13:05:21 35056 8192000000 10062692880 10498883584 1103435568 11.870 97 22 44 0 1138196592 30025776 4735248
13:06:57 54370 8192000000 10062015520 10511691776 1128807584 12.110 100 29 58 0 1159901712 25216720 5877408
13:08:53 19400 8192000000 10084943728 10534993920 1135800928 12.176 100 32 62 1 1188847968 46969712 6077328
13:10:14 68105 8192000000 10091334400 10554822656 1132396816 12.144 99 15 30 0 1185943040 50188656 3357568
13:12:22 44162 8192000000 10095347168 10578583552 1135887104 12.177 99 12 23 0 1190173856 52005968 2280784
13:14:23 69965 8192000000 10113725840 10557353984 1111676112 11.948 97 23 46 0 1180493232 63594176 5222944
13:16:37 13647 8192000000 10098949824 10533097472 1147665744 12.288 100 53 100 1 1186604336 28950896 9987696
13:17:54 29627 8192000000 10099234592 10561220608 1151112112 12.320 100 22 44 0 1223100544 66763600 5224832
13:19:39 70885 8192000000 10156355520 10608861184 1157215600 12.377 100 22 44 0 1251024464 88883120 4925744
13:21:54 70445 8192000000 10072204496 10508197888 1095783344 11.798 95 14 28 0 1154214368 55587216 2843808
13:24:45 15101 8192000000 10117208096 10538098688 1101509488 11.852 98 50 100 0 1192158592 80832544 9816560
13:26:03 103693 8192000000 10070470672 10543558656 1095058608 11.791 98 32 64 0 1165780240 64352416 6369216
13:28:49 107620 8192000000 10078714256 10571964416 1115474240 11.984 99 6 12 0 1174017520 57287424 1255856
13:31:37 48227 8192000000 10049094384 10496450560 1100850640 11.846 98 17 32 0 1129664544 26089504 2724400
13:33:25 70867 8192000000 10072024464 10524782592 1106664224 11.901 98 22 44 0 1137321344 26538464 4118656
13:35:40 50734 8192000000 10071643040 10532429824 1128208208 12.104 100 25 50 0 1211257520 77307792 5741520

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 16-07-2022 13:38:24 to 16-07-2022 13:38:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-07-2022 13:38:41 to 16-07-2022 13:38:43.

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          214838
MON$OLDEST_ACTIVE               215574
MON$OLDEST_SNAPSHOT             215574
MON$NEXT_TRANSACTION            215574
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                       2031968
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2799 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2799 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2799 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2799 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 ---
:28: 
:29: mscs-test-161.budzdorov.ru    Sat Jul 16 13:37:20 2022
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Sat Jul 16 13:37:23 2022
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
--- end of diff output ---

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

16.07.22 13:38:44. 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
2022-07-16 13:38:46 No severe PSQL-related problems occured
16.07.22 13:38:48. End of report.