Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.11.2021 13: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 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 16697956 kB
MemAvailable 31600140 kB
Buffers 0 kB
Cached 15024740 kB
SwapCached 0 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-11-25 13:37:07
DTS_END                         2021-11-25 13:37:07.0180
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46178
TRN_ID                          223397
ATT_ID                          597
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: 8688.36 23352 1042603
customer order: creation 157.40 465 18888
customer order: refuse 12.88 427 1545
order to supplier: creation 42.12 1541 5054
order to supplier: removal 3.26 128827 391
invoice (draft): creation 42.50 4018 5100
invoice (draft): removal 50.17 1034 6020
invoice accept: apply 38.29 18476 4595
invoice accept: cancel 44.98 945 5397
customer reserve: creation 3610.83 176 433300
customer reserve: removal 4414.88 73 529785
realization accept: apply 75.69 222 9083
realization accept: cancel 80.41 125 9649
payment from customer: creation 54.03 128 6483
payment from customer: removal 5.01 97 601
payment to supplier: creation 43.47 151 5216
payment to supplier: removal 3.07 109 368
service: total inventory turnovers 3.18 4971 381
service: total monetary turnovers 5.42 730 650
service: aggregate perf. data 0.78 16936 93
service: refresh index statistics 0.03 287579 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-11-2021 13:38:42 to 25-11-2021 13:38:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8422
TEST_TIME 2 5798
TEST_TIME 3 5987
TEST_TIME 4 5819
TEST_TIME 5 5335
TEST_TIME 6 5126
TEST_TIME 7 5038
TEST_TIME 8 4720
TEST_TIME 9 4658
TEST_TIME 10 4702
TEST_TIME 11 4704
TEST_TIME 12 4757
TEST_TIME 13 5013
TEST_TIME 14 5347
TEST_TIME 15 5784
TEST_TIME 16 6020
TEST_TIME 17 6020
TEST_TIME 18 6370
TEST_TIME 19 6526
TEST_TIME 20 6569
TEST_TIME 21 6799
TEST_TIME 22 7593
TEST_TIME 23 7743
TEST_TIME 24 7958
TEST_TIME 25 8058
TEST_TIME 26 8417
TEST_TIME 27 8346
TEST_TIME 28 8536
TEST_TIME 29 8783
TEST_TIME 30 9400
TEST_TIME 31 10138
TEST_TIME 32 10266
TEST_TIME 33 10048
TEST_TIME 34 10407
TEST_TIME 35 10300
TEST_TIME 36 10172
TEST_TIME 37 9981
TEST_TIME 38 9868
TEST_TIME 39 9856
TEST_TIME 40 9708
TEST_TIME 41 9561
TEST_TIME 42 9478
TEST_TIME 43 9320
TEST_TIME 44 9291
TEST_TIME 45 9239
TEST_TIME 46 9234
TEST_TIME 47 9292
TEST_TIME 48 9198
TEST_TIME 49 9113
TEST_TIME 50 9019
TEST_TIME 51 8911
TEST_TIME 52 8861
TEST_TIME 53 8838
TEST_TIME 54 8797
TEST_TIME 55 8766
TEST_TIME 56 8770
TEST_TIME 57 8803
TEST_TIME 58 8808
TEST_TIME 59 8822
TEST_TIME 60 8834
TEST_TIME 61 8817
TEST_TIME 62 8805
TEST_TIME 63 8774
TEST_TIME 64 8975
TEST_TIME 65 9031
TEST_TIME 66 9094
TEST_TIME 67 9047
TEST_TIME 68 9098
TEST_TIME 69 9116
TEST_TIME 70 9198
TEST_TIME 71 9198
TEST_TIME 72 9184
TEST_TIME 73 9069
TEST_TIME 74 9033
TEST_TIME 75 8955
TEST_TIME 76 9038
TEST_TIME 77 9017
TEST_TIME 78 8992
TEST_TIME 79 8956
TEST_TIME 80 8880
TEST_TIME 81 8822
TEST_TIME 82 8800
TEST_TIME 83 8782
TEST_TIME 84 8743
TEST_TIME 85 8784
TEST_TIME 86 8763
TEST_TIME 87 8859
TEST_TIME 88 8910
TEST_TIME 89 8866
TEST_TIME 90 8897
TEST_TIME 91 8939
TEST_TIME 92 8969
TEST_TIME 93 9012
TEST_TIME 94 9028
TEST_TIME 95 8967
TEST_TIME 96 8912
TEST_TIME 97 8894
TEST_TIME 98 8834
TEST_TIME 99 8813
TEST_TIME 100 8797
TEST_TIME 101 8781
TEST_TIME 102 8759
TEST_TIME 103 8738
TEST_TIME 104 8717
TEST_TIME 105 8744
TEST_TIME 106 8796
TEST_TIME 107 8796
TEST_TIME 108 8759
TEST_TIME 109 8795
TEST_TIME 110 8913
TEST_TIME 111 8899
TEST_TIME 112 8945
TEST_TIME 113 8983
TEST_TIME 114 8923
TEST_TIME 115 8888
TEST_TIME 116 8868
TEST_TIME 117 8805
TEST_TIME 118 8778
TEST_TIME 119 8742
TEST_TIME 120 8691

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-11-2021 13:38:45 to 25-11-2021 13:38:48.

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 1444955 1444955 0 0.00 0 32303 7 0 0
doc_list_biud 1444954 1444954 0 0.00 0 46419 34 0 0
sp_add_invoice_to_stock 4720 4595 125 2.64 33 166841 18476 0 125
sp_cancel_adding_invoice 5397 5397 0 0.00 3 54257 946 0 0
sp_cancel_client_order 1545 1545 0 0.00 4 34208 427 0 0
sp_cancel_customer_reserve 529785 529785 0 0.00 1 39316 74 0 0
sp_cancel_pay_from_customer 601 601 0 0.00 3 3359 97 0 0
sp_cancel_pay_to_supplier 368 368 0 0.00 3 9236 110 0 0
sp_cancel_supplier_invoice 6024 6020 4 0.06 2 41278 1034 0 4
sp_cancel_supplier_order 391 391 0 0.00 18 1090117 128828 0 0
sp_cancel_write_off 9649 9649 0 0.00 1 6758 125 0 0
sp_client_order 18888 18888 0 0.00 4 33089 465 0 0
sp_customer_reserve 433300 433300 0 0.00 0 82386 176 0 0
sp_fill_shopping_cart 29037 29037 0 0.00 1 17088 50 0 0
sp_fill_shopping_cart_clo_res 433296 433296 0 0.00 0 21445 18 0 0
sp_get_clo_for_invoice 4591 4591 0 0.00 0 44901 498 0 0
sp_kill_cost_storno 16015 16015 0 0.00 0 7643 74 0 0
sp_kill_qstorno_ret_qs2qd 1075630 1075630 0 0.00 0 41221 40 0 0
sp_kill_qty_storno 566461 566461 0 0.00 0 46419 87 0 0
sp_lock_selected_doc 18783 18783 0 0.00 0 7924 5 0 0
sp_make_cost_storno 25373 25373 0 0.00 0 32300 72 0 0
sp_multiply_rows_for_pdistr 25373 25373 0 0.00 0 10414 39 0 0
sp_multiply_rows_for_qdistr 18888 18888 0 0.00 0 32762 217 0 0
sp_pay_from_customer 6483 6483 0 0.00 4 14689 128 0 0
sp_pay_to_supplier 5216 5216 0 0.00 5 15506 151 0 0
sp_payment_common 11699 11699 0 0.00 1 9708 25 0 0
sp_qd_handle_on_cancel_clo 1545 1545 0 0.00 0 34188 396 0 0
sp_qd_handle_on_invoice_upd_sts 9988 9988 0 0.00 0 46418 423 0 0
sp_qd_handle_on_reserve_upd_sts 9083 9083 0 0.00 0 40543 67 0 0
sp_reserve_write_off 9083 9083 0 0.00 7 40572 223 0 0
sp_supplier_invoice 5100 5100 0 0.00 9 68251 4018 0 0
sp_supplier_order 5054 5054 0 0.00 5 44701 1542 0 0
srv_aggregate_perf_data 93 93 0 0.00 2868 143036 16936 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1793 156336 48594 0 0
srv_find_qd_qs_mism 999377 999377 0 0.00 0 12748 5 0 0
srv_make_invnt_saldo 381 381 0 0.00 30 115180 4972 0 0
srv_make_money_saldo 650 650 0 0.00 1 27610 730 0 0
srv_recalc_idx_stat 4 4 0 0.00 156885 424845 287580 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4595 4595 0 0.00 0 17056 201 0 0
t$perf-norm:sp_cancel_adding_invoice 352 352 0 0.00 1 4814 91 0 0
t$perf-norm:sp_cancel_client_order 1545 1545 0 0.00 0 2415 14 0 0
t$perf-norm:sp_cancel_customer_reserve 621 621 0 0.00 0 1652 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 601 601 0 0.00 0 335 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 368 368 0 0.00 0 6585 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 343 343 0 0.00 0 12541 53 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 19769 1723 0 0
t$perf-norm:sp_cancel_write_off 888 888 0 0.00 0 3754 15 0 0
t$perf-norm:sp_client_order 18888 18888 0 0.00 0 12953 27 0 0
t$perf-norm:sp_customer_reserve 902 902 0 0.00 0 4042 11 0 0
t$perf-norm:sp_pay_from_customer 6483 6483 0 0.00 0 13663 19 0 0
t$perf-norm:sp_pay_to_supplier 5216 5216 0 0.00 0 12983 22 0 0
t$perf-norm:sp_reserve_write_off 9083 9083 0 0.00 0 11211 15 0 0
t$perf-norm:sp_supplier_invoice 5100 5100 0 0.00 0 7347 24 0 0
t$perf-norm:sp_supplier_order 5054 5054 0 0.00 0 5076 18 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 156 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 129 4 0 0
t$perf-norm:srv_make_invnt_saldo 381 381 0 0.00 0 1651 16 0 0
t$perf-norm:srv_make_money_saldo 650 650 0 0.00 0 9261 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 9432 9182 250 2.65 0 8277 19 0 250
v_all_customers 34708 34708 0 0.00 0 15 1 0 0
v_all_suppliers 27952 27952 0 0.00 0 29 1 0 0
v_all_wares 37776 37776 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 704 704 0 0.00 0 2925 11 0 0
v_cancel_client_order 3090 3090 0 0.00 0 1743 6 0 0
v_cancel_customer_prepayment 1202 1202 0 0.00 0 3211 11 0 0
v_cancel_customer_reserve 1242 1242 0 0.00 0 2875 5 0 0
v_cancel_payment_to_supplier 736 736 0 0.00 0 1036 6 0 0
v_cancel_supplier_invoice 694 686 8 1.15 0 1767 16 0 8
v_cancel_supplier_order 782 782 0 0.00 0 3019 8 0 0
v_cancel_write_off 1776 1776 0 0.00 0 2052 14 0 0
v_max_id_clo_ord 5053 5053 0 0.00 0 415 1 0 0
v_max_id_clo_res 902 902 0 0.00 0 2598 11 0 0
v_max_id_ord_sup 5096 5096 0 0.00 0 6288 5 0 0
v_max_non_paid_invoice 1389 1389 0 0.00 0 1686 3 0 0
v_max_non_paid_realizn 3350 3350 0 0.00 0 2183 4 0 0
v_min_id_clo_ord 5053 5053 0 0.00 0 2524 6 0 0
v_min_id_clo_res 902 902 0 0.00 0 9171 27 0 0
v_min_id_ord_sup 5096 5096 0 0.00 0 5127 9 0 0
v_min_non_paid_invoice 5216 5216 0 0.00 0 9704 18 0 0
v_min_non_paid_realizn 6483 6483 0 0.00 0 6757 19 0 0
v_our_firm 9334 9334 0 0.00 0 17 1 0 0
v_reserve_write_off 18166 18166 0 0.00 0 5784 6 0 0
x_lock_depdocs_on_canc_invoice 11074 11074 0 0.00 0 33648 129 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 5621 397 0 0
x_make_qstorno_1000_1200 5054 5054 0 0.00 2 44690 1456 0 0
x_make_qstorno_1200_2000 5100 5100 0 0.00 4 68244 3821 0 0
x_make_qstorno_2100_3300 433300 433300 0 0.00 0 82365 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-11-2021 13:38:49 to 25-11-2021 13:38:53.

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:10 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:11 36153 8192000000 9710938320 10006564864 788452896 8.779 100 84 168 0 817993264 7668208 21872160
11:09:56 21250 8192000000 9756489904 10059681792 834282144 9.242 100 85 170 0 867942960 13173120 20487696
11:11:20 20230 8192000000 9803850992 10120282112 862084320 9.521 100 83 166 0 910302640 28732400 19485920
11:12:48 16564 8192000000 9858773792 10188218368 906210464 9.960 100 73 146 0 963273072 40784832 16277776
11:14:07 9033 8192000000 9888347552 10235625472 943264224 10.325 100 70 140 0 1009462528 51593984 14604320
11:15:18 6189 8192000000 9942722656 10295304192 993195456 10.813 100 80 159 0 1057888192 48427056 16265680
11:16:28 2983 8192000000 9981977664 10341662720 1023031392 11.101 100 83 164 2 1097122144 57532384 16558368
11:17:33 3088 8192000000 10025211488 10390081536 1040616608 11.271 100 87 172 0 1137662656 80010896 17035152
11:18:38 1914 8192000000 10035440880 10402074624 1062979920 11.485 100 91 182 0 1151313744 69184640 19149184
11:19:41 13878 8192000000 9977682880 10372096000 1073232576 11.583 100 15 30 1 1120479680 44328576 2918528
11:20:55 5654 8192000000 9992094512 10387329024 1087247040 11.716 100 43 84 2 1117848880 21180496 9421344
11:22:03 4559 8192000000 9989765840 10389688320 1091710736 11.759 100 42 84 1 1124661376 24201872 8748768
11:23:08 10144 8192000000 10027271648 10430349312 1099505840 11.833 100 36 73 3 1127611888 20844688 7261360
11:24:37 3280 8192000000 10020045536 10424487936 1102710080 11.863 100 42 83 0 1146020816 32580176 10730560
11:25:41 5923 8192000000 10035250576 10438758400 1108785040 11.921 100 46 92 1 1138166384 19831600 9549744
11:26:50 7535 8192000000 10015387776 10422628352 1104295040 11.878 100 29 58 1 1120605264 10428576 5881648
11:27:58 20893 8192000000 9983354656 10389901312 1059093440 11.448 97 32 64 0 1086206384 20421088 6691856
11:29:52 4273 8192000000 9989676240 10381115392 1056513184 11.423 98 41 82 0 1098767632 33033968 9220480
11:30:57 5980 8192000000 9937381600 10321084416 1000069376 10.879 96 33 64 0 1028231248 20852144 7309728
11:32:04 7646 8192000000 9957234128 10343911424 1007591856 10.952 98 42 82 1 1030356512 13896464 8868192
11:33:17 45050 8192000000 9942472944 10341994496 1009255728 10.968 97 25 50 0 1044611232 29991744 5363760
11:35:03 4329 8192000000 9972406784 10358267904 1055520176 11.414 100 51 102 1 1083938976 16910128 11508672
11:36:08 10527 8192000000 9976493936 10376331264 1063354560 11.489 100 21 43 1 1094147824 26065712 4727552
11:37:21 79738 8192000000 10023054016 10421071872 1090888000 11.751 100 33 66 0 1117179680 19928960 6362720
11:39:53 15236 8192000000 10057993456 10459152384 1109138240 11.924 100 49 96 1 1161834160 42786576 9909344
11:41:16 25335 8192000000 10092647952 10501103616 1131004416 12.131 100 48 92 1 1198028736 56593376 10430944
11:42:47 36421 8192000000 10135483504 10546212864 1149375888 12.304 100 33 66 0 1237600320 80899504 7324928
11:44:37 28928 8192000000 10174168336 10583494656 1162680784 12.428 100 38 74 1 1279743328 108307152 8755392
11:46:13 11423 8192000000 10238526816 10655318016 1174140592 12.536 100 51 101 2 1344971424 159246416 11584416
11:47:34 3821 8192000000 10285991888 10690682880 1184277200 12.630 100 68 136 2 1386083808 185868784 15937824
11:48:41 6868 8192000000 10316920352 10726944768 1185328896 12.640 100 65 128 0 1419900704 220163904 14407904
11:49:59 7088 8192000000 10365093920 10770526208 1181843472 12.607 100 71 137 3 1464452864 266881280 15728112
11:51:09 4478 8192000000 10410033456 10819342336 1187093248 12.656 100 71 142 0 1514274240 309925392 17255600
11:52:24 14868 8192000000 10411213296 10833686528 1196798352 12.747 100 73 146 2 1511991728 297533152 17660224
11:53:48 1791 8192000000 10422354208 10835099648 1184464400 12.632 100 84 167 2 1518430848 314054160 19912288
11:54:54 7154 8192000000 10446506896 10863382528 1180468704 12.595 99 67 133 1 1539792640 343200144 16123792
11:56:16 8928 8192000000 10422699952 10873397248 1182573408 12.614 100 63 124 2 1520452752 323024048 14855296
11:57:33 4066 8192000000 10404640384 10822393856 1151044400 12.319 100 59 116 0 1503298048 337307104 14946544
11:58:49 9517 8192000000 10424464576 10872561664 1154570352 12.352 100 58 115 0 1529270032 360427824 14271856
12:00:08 2847 8192000000 10336879680 10743513088 1150793440 12.317 100 78 152 0 1429685632 260704336 18187856
12:01:14 8829 8192000000 10309617968 10729615360 1131082208 12.132 99 42 85 2 1401952384 260783216 10086960
12:02:29 10114 8192000000 10375097216 10799861760 1134082368 12.160 99 53 106 0 1462046704 315044048 12920288
12:03:41 21813 8192000000 10329451184 10779066368 1140256144 12.218 100 25 50 1 1413218608 266428864 6533600
12:05:07 11857 8192000000 10321475600 10772529152 1121857568 12.045 100 27 54 1 1415426016 286895552 6672896
12:06:21 29084 8192000000 10297374704 10726727680 1104157632 11.877 99 20 40 1 1378557648 268908400 5491616
12:07:53 20737 8192000000 10231151392 10642280448 1107803456 11.912 100 38 76 2 1282177744 166714240 7660048
12:09:17 72736 8192000000 10111490736 10588917760 1088621952 11.730 97 13 26 1 1173781936 82344128 2815856
12:11:33 67952 8192000000 10083301056 10550890496 1099276800 11.831 98 13 26 0 1148881600 47297584 2307216
12:13:44 74376 8192000000 10081704736 10516500480 1111987840 11.951 99 37 74 1 1163479840 41838384 9653616
12:16:05 100012 8192000000 10049963648 10497282048 1105464000 11.889 98 25 48 2 1149795360 39799200 4532160
12:19:03 50377 8192000000 10103598592 10563846144 1134689120 12.166 100 11 22 1 1197123056 60083552 2350384
12:20:54 59980 8192000000 10121688624 10577371136 1120125680 12.028 99 18 36 1 1220067440 95791328 4150432
12:22:58 52014 8192000000 10106875184 10552938496 1133924608 12.158 100 15 30 1 1227808240 90346416 3537216
12:24:53 72022 8192000000 10075867136 10556243968 1138837488 12.205 100 19 38 0 1178308608 35254176 4216944
12:27:24 46717 8192000000 10059960688 10511777792 1137457568 12.192 100 29 58 1 1165492528 22093008 5941952
12:29:11 57106 8192000000 10156249008 10622615552 1149844320 12.308 100 34 68 1 1255156752 98150016 7162416
12:31:10 56108 8192000000 10152361024 10647195648 1120997616 12.036 98 13 26 0 1234970160 111291296 2681248
12:33:20 93940 8192000000 10075946800 10536443904 1131186912 12.133 99 27 55 0 1179232896 42648928 5397056
12:35:54 47088 8192000000 10073017440 10531479552 1140576560 12.221 100 19 38 0 1178348512 33380080 4391872
12:37:49 67233 8192000000 10095196624 10539356160 1156397968 12.370 100 25 50 3 1201596896 40636576 4562352
12:39:59 60559 8192000000 10202712080 10671857664 1165098976 12.451 100 21 42 1 1277288000 107924736 4264288
12:42:16 61165 8192000000 10122213888 10587217920 1121807936 12.044 96 11 22 0 1157866864 34124656 1934272
12:44:17 47614 8192000000 10160948656 10607919104 1141223856 12.227 98 29 58 0 1203784320 56482848 6077616
12:46:05 65933 8192000000 10123257424 10579894272 1163639296 12.437 100 24 48 2 1215419760 46925616 4854848
12:48:12 77564 8192000000 10084506608 10565189632 1109954496 11.932 96 14 28 0 1155447680 42804928 2688256
12:50:32 74238 8192000000 10131478064 10591559680 1132656656 12.146 98 31 62 0 1205574016 66207984 6709376
12:52:54 15899 8192000000 10077111936 10587324416 1119275584 12.020 98 11 22 0 1215267344 93672832 2318928
12:54:15 64229 8192000000 10059126272 10514829312 1116464064 11.994 98 21 41 0 1148916752 28527024 3925664
12:56:23 51582 8192000000 10068858688 10525556736 1137627280 12.193 100 14 28 1 1176018768 35044544 3346944
12:58:18 50045 8192000000 10050316400 10496937984 1095856976 11.798 97 11 22 0 1133537664 35016608 2664080
13:00:14 51770 8192000000 10036444400 10477854720 1059809536 11.455 96 12 24 0 1093209200 30935792 2463872
13:02:08 55547 8192000000 10099555712 10580627456 1082215808 11.669 96 16 32 0 1199355408 113142192 3997408
13:04:05 70826 8192000000 10073502992 10526273536 1065042608 11.505 96 25 48 2 1145082352 74631824 5407920
13:06:22 26510 8192000000 10096291424 10524053504 1110941840 11.941 99 42 85 0 1178585856 58199200 9444816
13:07:49 65317 8192000000 10108627072 10546159616 1113696480 11.967 99 24 47 1 1161679280 42541264 5441536
13:10:03 88588 8192000000 10054968720 10526986240 1070873536 11.560 95 19 38 1 1131806640 57002224 3930880
13:12:31 135859 8192000000 10075987840 10554241024 1129432464 12.116 100 23 46 0 1183862224 49654896 4774864
13:16:12 48015 8192000000 10061472864 10530836480 1123916224 12.064 100 12 24 1 1169456480 43238288 2301968
13:18:11 56172 8192000000 10055524688 10501107712 1116579840 11.995 99 9 18 1 1156350048 37657264 2112944
13:20:11 85720 8192000000 10093176288 10541715456 1104291040 11.878 98 20 40 1 1153960752 45510672 4159040
13:22:40 74261 8192000000 10055189024 10525855744 1114197888 11.972 99 27 54 0 1162910080 42464384 6247808
13:24:56 57653 8192000000 10106760400 10543329280 1135122256 12.170 100 26 50 1 1187542672 46779120 5641296
13:26:53 156335 8192000000 10115259728 10588930048 1109983744 11.932 97 9 18 1 1227122304 115131120 2007440
13:30:30 109338 8192000000 10049046544 10501976064 1128686976 12.109 99 22 42 1 1171674928 38187856 4800096
13:33:24 89036 8192000000 10064568992 10529370112 1109169264 11.925 98 11 20 0 1131617392 20111376 2336752
13:35:53 32996 8192000000 10057053184 10536402944 1130241600 12.124 100 7 14 0 1172222032 40532160 1448272

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-11-2021 13:38:55 to 25-11-2021 13:39:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-11-2021 13:39:11 to 25-11-2021 13:39:13.

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          223216
MON$OLDEST_ACTIVE               223548
MON$OLDEST_SNAPSHOT             223548
MON$NEXT_TRANSACTION            223548
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                       2052640
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             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2668 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2668 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2668 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2668 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 ---
:348: 
:349: mscs-test-161.budzdorov.ru    Thu Nov 25 13:37:09 2021
:350:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:351: 
:352: 
:353: mscs-test-161.budzdorov.ru    Thu Nov 25 13:37:12 2021
:354:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:355: 
:356: 
:357: mscs-test-161.budzdorov.ru    Thu Nov 25 13:37:12 2021
:358:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:359: 
--- end of diff output ---

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

25.11.21 13:39:14. 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-11-25 13:39:16 No severe PSQL-related problems occured
25.11.21 13:39:17. End of report.