Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.05.2022 13:39
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 15785836 kB
MemAvailable 31465220 kB
Buffers 0 kB
Cached 15941096 kB
SwapCached 2632 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-05-18 13:37:15
DTS_END                         2022-05-18 13:37:15.0200
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45824
TRN_ID                          212784
ATT_ID                          656
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: 8266.32 27424 991958
customer order: creation 159.48 485 19138
customer order: refuse 13.88 470 1666
order to supplier: creation 43.34 1583 5201
order to supplier: removal 3.19 180840 383
invoice (draft): creation 43.45 3959 5214
invoice (draft): removal 45.38 1272 5446
invoice accept: apply 39.50 28325 4740
invoice accept: cancel 40.97 1519 4916
customer reserve: creation 3629.12 270 435494
customer reserve: removal 3976.07 111 477128
realization accept: apply 78.14 323 9377
realization accept: cancel 72.05 178 8646
payment from customer: creation 55.81 190 6697
payment from customer: removal 5.83 120 700
payment to supplier: creation 45.75 223 5490
payment to supplier: removal 3.14 115 377
service: total inventory turnovers 3.18 5732 382
service: total monetary turnovers 7.03 442 843
service: aggregate perf. data 0.96 16286 115
service: refresh index statistics 0.04 306034 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-05-2022 13:40:13 to 18-05-2022 13:40:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9913
TEST_TIME 2 7521
TEST_TIME 3 6745
TEST_TIME 4 6545
TEST_TIME 5 6043
TEST_TIME 6 5732
TEST_TIME 7 5591
TEST_TIME 8 5869
TEST_TIME 9 5907
TEST_TIME 10 6194
TEST_TIME 11 6285
TEST_TIME 12 6509
TEST_TIME 13 6551
TEST_TIME 14 6653
TEST_TIME 15 6602
TEST_TIME 16 6867
TEST_TIME 17 6878
TEST_TIME 18 7266
TEST_TIME 19 7148
TEST_TIME 20 7156
TEST_TIME 21 7252
TEST_TIME 22 7467
TEST_TIME 23 7840
TEST_TIME 24 8178
TEST_TIME 25 8356
TEST_TIME 26 8475
TEST_TIME 27 8421
TEST_TIME 28 8475
TEST_TIME 29 8903
TEST_TIME 30 9165
TEST_TIME 31 9077
TEST_TIME 32 9099
TEST_TIME 33 9270
TEST_TIME 34 9493
TEST_TIME 35 9645
TEST_TIME 36 9560
TEST_TIME 37 9713
TEST_TIME 38 9841
TEST_TIME 39 9954
TEST_TIME 40 10191
TEST_TIME 41 10222
TEST_TIME 42 10070
TEST_TIME 43 10163
TEST_TIME 44 10145
TEST_TIME 45 10258
TEST_TIME 46 10107
TEST_TIME 47 10219
TEST_TIME 48 10100
TEST_TIME 49 10011
TEST_TIME 50 9895
TEST_TIME 51 9780
TEST_TIME 52 9715
TEST_TIME 53 9624
TEST_TIME 54 9587
TEST_TIME 55 9597
TEST_TIME 56 9528
TEST_TIME 57 9412
TEST_TIME 58 9370
TEST_TIME 59 9334
TEST_TIME 60 9356
TEST_TIME 61 9349
TEST_TIME 62 9310
TEST_TIME 63 9245
TEST_TIME 64 9259
TEST_TIME 65 9175
TEST_TIME 66 9056
TEST_TIME 67 8996
TEST_TIME 68 8966
TEST_TIME 69 8901
TEST_TIME 70 8885
TEST_TIME 71 8842
TEST_TIME 72 8795
TEST_TIME 73 8752
TEST_TIME 74 8735
TEST_TIME 75 8863
TEST_TIME 76 8890
TEST_TIME 77 8838
TEST_TIME 78 8794
TEST_TIME 79 8821
TEST_TIME 80 8815
TEST_TIME 81 8791
TEST_TIME 82 8787
TEST_TIME 83 8806
TEST_TIME 84 8848
TEST_TIME 85 8853
TEST_TIME 86 8847
TEST_TIME 87 8754
TEST_TIME 88 8762
TEST_TIME 89 8706
TEST_TIME 90 8653
TEST_TIME 91 8585
TEST_TIME 92 8549
TEST_TIME 93 8585
TEST_TIME 94 8570
TEST_TIME 95 8505
TEST_TIME 96 8521
TEST_TIME 97 8508
TEST_TIME 98 8499
TEST_TIME 99 8487
TEST_TIME 100 8481
TEST_TIME 101 8486
TEST_TIME 102 8453
TEST_TIME 103 8453
TEST_TIME 104 8452
TEST_TIME 105 8471
TEST_TIME 106 8487
TEST_TIME 107 8491
TEST_TIME 108 8499
TEST_TIME 109 8494
TEST_TIME 110 8471
TEST_TIME 111 8501
TEST_TIME 112 8522
TEST_TIME 113 8476
TEST_TIME 114 8451
TEST_TIME 115 8432
TEST_TIME 116 8436
TEST_TIME 117 8398
TEST_TIME 118 8370
TEST_TIME 119 8345
TEST_TIME 120 8291

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-05-2022 13:40:16 to 18-05-2022 13:40:20.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1391947 1391947 0 0.00 0 18441 15 0 0
doc_list_biud 1391932 1391932 0 0.00 0 26122 46 0 0
sp_add_invoice_to_stock 4822 4740 82 1.70 32 274374 28326 0 82
sp_cancel_adding_invoice 4916 4916 0 0.00 6 109486 1519 0 0
sp_cancel_client_order 1666 1666 0 0.00 7 16512 470 0 0
sp_cancel_customer_reserve 477128 477128 0 0.00 1 21978 112 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 4 1950 121 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 5 1035 115 0 0
sp_cancel_supplier_invoice 5452 5446 6 0.11 2 27110 1273 0 6
sp_cancel_supplier_order 383 383 0 0.00 13 1453671 180841 0 0
sp_cancel_write_off 8646 8646 0 0.00 2 15277 179 0 0
sp_client_order 19138 19138 0 0.00 6 27728 486 0 0
sp_customer_reserve 435494 435494 0 0.00 1 109975 271 0 0
sp_fill_shopping_cart 29537 29537 0 0.00 2 26607 107 0 0
sp_fill_shopping_cart_clo_res 435451 435451 0 0.00 0 14876 20 0 0
sp_get_clo_for_invoice 4680 4680 0 0.00 0 24154 360 0 0
sp_kill_cost_storno 14639 14639 0 0.00 0 18055 91 0 0
sp_kill_qstorno_ret_qs2qd 968731 968731 0 0.00 0 26118 49 0 0
sp_kill_qty_storno 512242 512242 0 0.00 0 26121 115 0 0
sp_lock_selected_doc 19536 19536 0 0.00 0 14307 7 0 0
sp_make_cost_storno 26244 26244 0 0.00 0 18266 92 0 0
sp_multiply_rows_for_pdistr 26244 26244 0 0.00 0 10698 43 0 0
sp_multiply_rows_for_qdistr 19134 19134 0 0.00 0 16766 154 0 0
sp_pay_from_customer 6697 6697 0 0.00 6 10835 191 0 0
sp_pay_to_supplier 5490 5490 0 0.00 6 13602 223 0 0
sp_payment_common 12187 12187 0 0.00 2 13588 58 0 0
sp_qd_handle_on_cancel_clo 1666 1666 0 0.00 0 16504 390 0 0
sp_qd_handle_on_invoice_upd_sts 9596 9596 0 0.00 0 20631 664 0 0
sp_qd_handle_on_reserve_upd_sts 9377 9377 0 0.00 0 21613 73 0 0
sp_reserve_write_off 9377 9377 0 0.00 9 22308 324 0 0
sp_supplier_invoice 5214 5214 0 0.00 8 80080 3960 0 0
sp_supplier_order 5201 5201 0 0.00 7 47160 1584 0 0
srv_aggregate_perf_data 115 115 0 0.00 2439 152297 16286 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 808 139238 34844 0 0
srv_find_qd_qs_mism 945480 945480 0 0.00 0 15885 11 0 0
srv_make_invnt_saldo 382 382 0 0.00 45 145432 5732 0 0
srv_make_money_saldo 843 843 0 0.00 1 7670 442 0 0
srv_recalc_idx_stat 5 5 0 0.00 88721 558403 306035 0 0
t$perf-abend:sp_add_invoice_to_stock 82 82 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4742 4742 0 0.00 1 14082 1033 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 1 8841 512 0 0
t$perf-norm:sp_cancel_client_order 1666 1666 0 0.00 0 3102 31 0 0
t$perf-norm:sp_cancel_customer_reserve 642 642 0 0.00 0 1716 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 178 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 1024 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 1556 34 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 122455 8814 0 0
t$perf-norm:sp_cancel_write_off 933 933 0 0.00 0 1523 22 0 0
t$perf-norm:sp_client_order 19139 19139 0 0.00 0 11345 45 0 0
t$perf-norm:sp_customer_reserve 986 986 0 0.00 0 3959 22 0 0
t$perf-norm:sp_pay_from_customer 6697 6697 0 0.00 0 6516 22 0 0
t$perf-norm:sp_pay_to_supplier 5490 5490 0 0.00 0 8179 23 0 0
t$perf-norm:sp_reserve_write_off 9377 9377 0 0.00 0 7776 27 0 0
t$perf-norm:sp_supplier_invoice 5214 5214 0 0.00 0 11128 40 0 0
t$perf-norm:sp_supplier_order 5201 5201 0 0.00 0 7999 37 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 1582 29 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 4388 67 0 0
t$perf-norm:srv_make_invnt_saldo 382 382 0 0.00 0 1103 16 0 0
t$perf-norm:srv_make_money_saldo 843 843 0 0.00 0 323 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 206 110 0 0
v_add_invoice_to_stock 9516 9352 164 1.72 0 6522 27 0 164
v_all_customers 34930 34930 0 0.00 0 57 4 0 0
v_all_suppliers 28894 28894 0 0.00 0 64 5 0 0
v_all_wares 38261 38261 0 0.00 0 109 4 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 907 15 0 0
v_cancel_client_order 3332 3332 0 0.00 0 5574 12 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 680 11 0 0
v_cancel_customer_reserve 1284 1284 0 0.00 0 2430 15 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 919 12 0 0
v_cancel_supplier_invoice 782 770 12 1.53 0 642 23 0 12
v_cancel_supplier_order 766 766 0 0.00 0 3878 17 0 0
v_cancel_write_off 1866 1866 0 0.00 0 3969 29 0 0
v_max_id_clo_ord 5200 5200 0 0.00 0 416 5 0 0
v_max_id_clo_res 986 986 0 0.00 0 2261 10 0 0
v_max_id_ord_sup 5204 5204 0 0.00 0 10004 12 0 0
v_max_non_paid_invoice 1447 1447 0 0.00 0 7784 14 0 0
v_max_non_paid_realizn 3539 3539 0 0.00 0 2022 8 0 0
v_min_id_clo_ord 5200 5200 0 0.00 0 18513 13 0 0
v_min_id_clo_res 986 986 0 0.00 0 7589 30 0 0
v_min_id_ord_sup 5204 5204 0 0.00 0 8763 15 0 0
v_min_non_paid_invoice 5490 5490 0 0.00 0 9902 28 0 0
v_min_non_paid_realizn 6697 6697 0 0.00 0 7721 25 0 0
v_our_firm 9646 9646 0 0.00 0 55 5 0 0
v_reserve_write_off 18752 18752 0 0.00 0 3720 11 0 0
x_lock_depdocs_on_canc_invoice 9977 9977 0 0.00 0 12337 139 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 15540 460 0 0
x_make_qstorno_1000_1200 5201 5201 0 0.00 2 47149 1377 0 0
x_make_qstorno_1200_2000 5214 5214 0 0.00 4 75154 3651 0 0
x_make_qstorno_2100_3300 435493 435493 0 0.00 0 109969 237 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-05-2022 13:40:21 to 18-05-2022 13:40:25.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:18 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:18 33970 8192000000 9732162448 10034810880 806939264 8.967 100 86 172 0 835351792 8007856 20404672
11:09:57 19381 8192000000 9765220048 10074124288 837196288 9.272 100 86 173 0 869771952 12377056 20198608
11:11:24 19721 8192000000 9812079728 10130968576 868533504 9.585 100 88 176 0 912460128 23894464 20032160
11:12:54 9287 8192000000 9875288656 10210455552 917962960 10.076 100 80 160 0 984024064 48692240 17368864
11:14:11 5952 8192000000 9922351536 10266095616 955805376 10.448 100 85 167 0 1037065392 63125168 18134848
11:15:20 2678 8192000000 9954721984 10309386240 989739088 10.779 100 86 170 0 1067448080 60669344 17039648
11:16:24 1274 8192000000 10004728720 10365992960 1018930656 11.062 100 92 181 2 1124731168 87637136 18163376
11:17:28 1190 8192000000 10019084400 10388475904 1043969424 11.303 100 94 184 1 1139609808 76936352 18704032
11:18:30 952 8192000000 10045202320 10421018624 1059001104 11.447 100 97 188 1 1165581296 87385456 19194736
11:19:33 1220 8192000000 10033312240 10411876352 1068802912 11.541 100 90 172 3 1154023024 68432128 16787984
11:20:35 1349 8192000000 10033977008 10415833088 1076390784 11.613 100 90 172 2 1161973488 67188384 18394320
11:21:37 1113 8192000000 10044304832 10428157952 1081165824 11.659 100 94 177 1 1167287712 67273808 18848080
11:22:39 1168 8192000000 10061722400 10448089088 1087227136 11.716 100 93 179 4 1185594064 80361904 18005024
11:23:42 1039 8192000000 10051647936 10443128832 1092588048 11.767 100 100 176 2 1177160064 66569632 18002384
11:24:44 1374 8192000000 10068153424 10458550272 1098280080 11.821 100 93 179 4 1188288128 72243904 17764144
11:25:46 1325 8192000000 10071739808 10461392896 1099232160 11.830 100 97 190 0 1191702096 73471216 18998720
11:26:48 1396 8192000000 10054973056 10440552448 1102522640 11.862 100 98 194 1 1176537712 54684752 19330320
11:27:50 1275 8192000000 10066163824 10455203840 1099616336 11.834 100 95 182 0 1187138352 69051248 18470768
11:28:52 1300 8192000000 10058560352 10439802880 1083138464 11.677 100 97 181 0 1183043552 80482688 19422400
11:29:55 817 8192000000 10021219520 10406494208 1080201872 11.649 100 90 174 0 1143658080 45849376 17606832
11:30:56 1230 8192000000 10061244896 10446069760 1084912784 11.694 100 96 182 2 1182040432 78957792 18169856
11:31:59 1113 8192000000 10038856608 10416062464 1061917536 11.475 100 94 187 1 1160427376 78945040 19564800
11:33:01 1473 8192000000 9999298784 10370490368 1043360096 11.297 100 94 183 2 1117730304 56592832 17777376
11:34:04 989 8192000000 10005432064 10384560128 1054804768 11.407 100 85 160 2 1126525552 55529344 16191440
11:35:05 1014 8192000000 10013409856 10390736896 1051846848 11.378 100 90 169 1 1137710048 68466976 17396224
11:36:07 2515 8192000000 9969942624 10341408768 1023790576 11.109 100 62 116 3 1088915424 52028128 13096720
11:37:11 1191 8192000000 9988320880 10366504960 1050471024 11.365 100 75 144 0 1109314112 44634848 14208240
11:38:18 2437 8192000000 10053892080 10433433600 1070816576 11.560 100 76 147 0 1159227968 74091632 14319760
11:39:26 1982 8192000000 10051323616 10436235264 1085467696 11.700 100 93 183 3 1175085200 69853392 19764112
11:40:33 11958 8192000000 10074765504 10466172928 1099070400 11.829 100 70 133 2 1191618208 78608128 13939680
11:41:52 4276 8192000000 10091093024 10488193024 1117371328 12.002 100 60 115 1 1213083152 82941312 12770512
11:42:59 5985 8192000000 10143178688 10540306432 1130562656 12.127 100 68 124 4 1260882704 116542640 13777408
11:44:18 7189 8192000000 10193954640 10585206784 1135734752 12.175 100 88 166 1 1310720080 157803648 17181680
11:45:34 1062 8192000000 10212752112 10618949632 1147312016 12.284 100 81 145 0 1328708304 167058704 14337584
11:46:40 3041 8192000000 10235333824 10641731584 1152520064 12.333 100 77 148 1 1349816512 179989152 17307296
11:47:48 1526 8192000000 10253530944 10656780288 1156655952 12.372 100 87 171 1 1369049856 193713968 18679936
11:48:54 911 8192000000 10284909536 10686431232 1170841856 12.505 100 92 180 1 1391161200 200125536 20193808
11:49:56 1522 8192000000 10308539808 10715340800 1176269584 12.555 100 93 183 0 1422843456 225871568 20702304
11:51:01 1368 8192000000 10341739600 10744614912 1166826000 12.467 99 95 181 0 1455406784 267255648 21325136
11:52:05 798 8192000000 10377356080 10778521600 1176765056 12.560 100 97 187 1 1481726320 283150400 21810864
11:53:09 1283 8192000000 10396540512 10813870080 1182605552 12.614 100 92 177 0 1504380160 301927536 19847072
11:54:14 2529 8192000000 10414105440 10822311936 1183763440 12.625 100 95 181 0 1526820560 322099408 20957712
11:55:21 1160 8192000000 10430491104 10867884032 1188652576 12.671 100 98 192 2 1537531136 326770128 22108432
11:56:25 911 8192000000 10435670976 10843267072 1177836496 12.570 100 95 189 1 1542001696 341543440 22621760
11:57:30 1790 8192000000 10467046848 10887688192 1164634976 12.447 100 93 184 1 1572255760 385867264 21753520
11:58:38 1582 8192000000 10491636976 10921881600 1174010688 12.534 100 92 181 0 1595490352 399835616 21644048
11:59:43 1155 8192000000 10469007360 10934853632 1162645840 12.428 100 98 189 1 1571083536 385009136 23428560
12:00:46 4793 8192000000 10432667376 10894471168 1161448496 12.417 100 65 130 0 1528481024 350585968 16446560
12:01:59 8677 8192000000 10393539392 10834739200 1154481312 12.352 99 49 98 0 1487551456 320468224 12601920
12:03:13 17339 8192000000 10407281744 10875355136 1157083440 12.376 100 43 86 0 1518845568 351411040 10351088
12:04:36 7453 8192000000 10399632016 10836840448 1127933984 12.102 98 44 88 0 1498183616 359135392 11114240
12:05:56 2686 8192000000 10393037120 10859048960 1143939888 12.253 100 52 104 0 1495189328 338953776 12295664
12:07:08 9153 8192000000 10309806624 10764500992 1151050784 12.319 100 41 80 0 1413619424 253827296 8741344
12:08:24 3935 8192000000 10358680864 10779271168 1155214624 12.358 100 72 135 1 1458172800 288120288 14837888
12:09:31 2091 8192000000 10320529680 10801512448 1172835648 12.523 100 73 137 2 1420875536 232253312 15786576
12:10:36 12542 8192000000 10349679072 10829090816 1162730544 12.429 99 68 132 0 1457793968 279661072 15402352
12:11:53 7333 8192000000 10364841968 10814070784 1175608512 12.549 100 48 93 0 1463498864 276304240 11586112
12:13:02 11348 8192000000 10359398576 10799681536 1146358944 12.275 98 35 70 1 1443398240 288540368 8498928
12:14:15 15205 8192000000 10383746112 10868060160 1165530176 12.455 100 33 64 2 1506831696 332600272 8701248
12:15:31 21527 8192000000 10356838080 10812796928 1153843200 12.346 99 35 70 0 1442642112 280680208 8118704
12:17:36 20588 8192000000 10289262800 10725916672 1161965968 12.422 100 41 80 3 1393734080 220916960 10851152
12:18:58 8070 8192000000 10250265536 10675990528 1169020640 12.488 100 66 125 0 1347294160 163022064 15251456
12:20:07 35261 8192000000 10251944368 10709479424 1170075216 12.498 100 28 57 1 1358403840 181535984 6792640
12:21:45 81469 8192000000 10160949744 10696339456 1149144720 12.301 99 11 21 1 1320193280 168186448 2862112
12:24:14 85553 8192000000 10129685728 10593464320 1150757280 12.317 99 24 48 0 1244720624 88544224 5419120
12:26:54 85057 8192000000 10076519744 10517491712 1126339440 12.087 98 32 64 0 1186776240 53678768 6758032
12:29:22 112023 8192000000 10129201072 10579001344 1112636368 11.957 96 35 70 2 1180182032 59759088 7786576
12:32:51 94840 8192000000 10113105728 10566606848 1146605168 12.278 99 12 24 0 1213156608 63777744 2773696
12:35:39 16454 8192000000 10178086192 10634633216 1152676704 12.335 100 55 106 3 1261645408 98045328 10923376
12:37:11 27265 8192000000 10141990576 10612580352 1151775168 12.326 100 33 65 0 1237707008 78988480 6943360
12:38:41 139236 8192000000 10106686720 10538774528 1117388352 12.002 96 22 41 1 1164623024 42644448 4590224
12:42:01 121471 8192000000 10077511424 10549661696 1149550784 12.305 99 23 43 1 1177121280 22530176 5040320
12:45:06 79539 8192000000 10103697936 10579406848 1140202480 12.217 99 24 47 1 1192590464 48009744 4378240
12:48:08 61396 8192000000 10079386576 10531717120 1124170608 12.066 98 11 22 0 1156028912 30062560 1795744
12:50:19 63506 8192000000 10169954496 10652196864 1148446544 12.295 99 38 75 2 1256612624 100247392 7918688
12:52:28 84340 8192000000 10055496224 10510802944 1100616240 11.843 96 15 30 0 1177856608 73778016 3462352
12:54:53 14401 8192000000 10085084400 10507849728 1132576272 12.146 100 64 123 2 1177238208 32254464 12407472
12:56:10 30856 8192000000 10108110304 10546802688 1115448192 11.984 99 44 87 1 1201850784 77787216 8615376
12:57:41 1416 8192000000 10086453424 10507685888 1121390496 12.040 99 78 146 0 1175354880 38310208 15654176
12:58:47 10750 8192000000 10086342880 10510991360 1110091344 11.933 98 53 106 0 1164896368 45077152 9727872
13:00:03 77359 8192000000 10089300128 10557157376 1103404048 11.870 98 28 57 1 1188984080 79569616 6010416
13:02:29 112560 8192000000 10073749056 10533027840 1105347856 11.888 98 11 22 0 1182216128 74355600 2512672
13:05:31 68681 8192000000 10075198768 10535182336 1118831952 12.016 99 29 58 0 1185828720 60378144 6618624
13:07:47 74581 8192000000 10111522912 10571939840 1121439056 12.041 99 12 24 0 1217397248 93143312 2814880
13:10:18 46357 8192000000 10076576960 10548092928 1112434432 11.955 99 17 34 1 1203098608 86369232 4294944
13:12:05 77825 8192000000 10057293392 10547408896 1121664720 12.043 99 17 35 0 1180463392 55625264 3173408
13:14:30 80189 8192000000 10056465024 10514186240 1089526960 11.738 96 7 14 0 1129801056 38938512 1335584
13:16:50 41036 8192000000 10067316576 10530426880 1129847616 12.120 99 23 42 0 1172313872 37889968 4576288
13:18:32 36516 8192000000 10076833744 10513223680 1128786560 12.110 99 37 72 1 1176840064 40997792 7055712
13:20:09 33777 8192000000 10082204112 10515648512 1132640176 12.146 99 48 94 1 1189316544 47488688 9187680
13:21:48 37964 8192000000 10107209168 10571460608 1136032592 12.178 99 25 50 2 1189254416 48278336 4943488
13:23:30 44767 8192000000 10102234896 10543034368 1136322880 12.181 99 15 30 1 1206421856 67201376 2897600
13:25:18 59414 8192000000 10103449504 10540797952 1105824416 11.893 96 39 78 1 1159562512 45990704 7747392
13:27:21 11613 8192000000 10116038416 10540695552 1146572096 12.277 100 66 127 4 1211261488 50673216 14016176
13:28:35 112797 8192000000 10055494688 10547249152 1096684112 11.806 96 9 18 0 1166023136 67080448 2258576
13:32:13 45820 8192000000 10113832080 10603233280 1132876576 12.148 100 21 42 0 1210287824 73220544 4190704
13:33:59 115873 8192000000 10057162160 10546450432 1114840256 11.978 98 6 12 1 1161014976 44967360 1207360
13:37:01 44446 8192000000 9226169152 9545543680 379533120 4.427 34 3 6 0 406691904 26581504 577280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 18-05-2022 13:40:27 to 18-05-2022 13:40:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 82 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-05-2022 13:40:44 to 18-05-2022 13:40:46.

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          213162
MON$OLDEST_ACTIVE               213163
MON$OLDEST_SNAPSHOT             213163
MON$NEXT_TRANSACTION            213163
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                       2126400
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             760
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2764 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2764 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2764 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2764 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 ---
--- end of diff output ---

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

18.05.22 13:40:47. 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-05-18 13:40:49 No severe PSQL-related problems occured
18.05.22 13:40:51. End of report.