Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.10.2022 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 14853204 kB
MemAvailable 30512784 kB
Buffers 0 kB
Cached 16875584 kB
SwapCached 2080 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.3
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-10-20 13:37:22
DTS_END                         2022-10-20 13:37:22.5170
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/32856
TRN_ID                          213165
ATT_ID                          648
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: 8966.13 28721 1075936
customer order: creation 164.26 500 19711
customer order: refuse 14.33 480 1719
order to supplier: creation 43.47 1360 5216
order to supplier: removal 3.50 188426 420
invoice (draft): creation 43.62 3069 5234
invoice (draft): removal 50.73 1156 6088
invoice accept: apply 39.69 30013 4763
invoice accept: cancel 45.09 1467 5411
customer reserve: creation 3798.32 275 455798
customer reserve: removal 4479.25 114 537510
realization accept: apply 79.47 295 9536
realization accept: cancel 79.44 191 9533
payment from customer: creation 57.15 190 6858
payment from customer: removal 5.49 164 659
payment to supplier: creation 46.61 199 5593
payment to supplier: removal 3.18 203 381
service: total inventory turnovers 3.76 5252 451
service: total monetary turnovers 7.82 499 938
service: aggregate perf. data 0.94 16331 113
service: refresh index statistics 0.03 324240 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 20-10-2022 13:39:04 to 20-10-2022 13:39:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6872
TEST_TIME 2 5691
TEST_TIME 3 6331
TEST_TIME 4 5909
TEST_TIME 5 5786
TEST_TIME 6 6088
TEST_TIME 7 6037
TEST_TIME 8 5823
TEST_TIME 9 5858
TEST_TIME 10 5976
TEST_TIME 11 5860
TEST_TIME 12 5955
TEST_TIME 13 6136
TEST_TIME 14 6226
TEST_TIME 15 6329
TEST_TIME 16 6435
TEST_TIME 17 6892
TEST_TIME 18 7163
TEST_TIME 19 7398
TEST_TIME 20 7400
TEST_TIME 21 7624
TEST_TIME 22 7585
TEST_TIME 23 7537
TEST_TIME 24 7620
TEST_TIME 25 7717
TEST_TIME 26 7657
TEST_TIME 27 7586
TEST_TIME 28 7642
TEST_TIME 29 7674
TEST_TIME 30 8080
TEST_TIME 31 9029
TEST_TIME 32 8966
TEST_TIME 33 9319
TEST_TIME 34 9420
TEST_TIME 35 10079
TEST_TIME 36 9944
TEST_TIME 37 10348
TEST_TIME 38 10735
TEST_TIME 39 10684
TEST_TIME 40 10815
TEST_TIME 41 10743
TEST_TIME 42 10629
TEST_TIME 43 10480
TEST_TIME 44 10483
TEST_TIME 45 10317
TEST_TIME 46 10205
TEST_TIME 47 10127
TEST_TIME 48 10029
TEST_TIME 49 10006
TEST_TIME 50 9953
TEST_TIME 51 10037
TEST_TIME 52 10014
TEST_TIME 53 9942
TEST_TIME 54 9889
TEST_TIME 55 9775
TEST_TIME 56 9618
TEST_TIME 57 9681
TEST_TIME 58 9663
TEST_TIME 59 9650
TEST_TIME 60 9566
TEST_TIME 61 9550
TEST_TIME 62 9630
TEST_TIME 63 9683
TEST_TIME 64 9678
TEST_TIME 65 9588
TEST_TIME 66 9567
TEST_TIME 67 9610
TEST_TIME 68 9638
TEST_TIME 69 9714
TEST_TIME 70 9696
TEST_TIME 71 9644
TEST_TIME 72 9528
TEST_TIME 73 9453
TEST_TIME 74 9464
TEST_TIME 75 9425
TEST_TIME 76 9363
TEST_TIME 77 9307
TEST_TIME 78 9247
TEST_TIME 79 9183
TEST_TIME 80 9140
TEST_TIME 81 9092
TEST_TIME 82 9086
TEST_TIME 83 9123
TEST_TIME 84 9186
TEST_TIME 85 9166
TEST_TIME 86 9168
TEST_TIME 87 9164
TEST_TIME 88 9202
TEST_TIME 89 9187
TEST_TIME 90 9150
TEST_TIME 91 9217
TEST_TIME 92 9199
TEST_TIME 93 9377
TEST_TIME 94 9328
TEST_TIME 95 9241
TEST_TIME 96 9260
TEST_TIME 97 9207
TEST_TIME 98 9180
TEST_TIME 99 9158
TEST_TIME 100 9092
TEST_TIME 101 9086
TEST_TIME 102 9073
TEST_TIME 103 9031
TEST_TIME 104 9006
TEST_TIME 105 8956
TEST_TIME 106 8930
TEST_TIME 107 8920
TEST_TIME 108 8960
TEST_TIME 109 9013
TEST_TIME 110 8996
TEST_TIME 111 8956
TEST_TIME 112 8973
TEST_TIME 113 8991
TEST_TIME 114 8993
TEST_TIME 115 8997
TEST_TIME 116 8979
TEST_TIME 117 9015
TEST_TIME 118 9013
TEST_TIME 119 9057
TEST_TIME 120 8989

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-10-2022 13:39:08 to 20-10-2022 13:39:12.

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 1496464 1496464 0 0.00 0 18342 15 0 0
doc_list_biud 1496463 1496463 0 0.00 0 25837 47 0 0
sp_add_invoice_to_stock 4885 4763 122 2.49 47 254879 30014 0 122
sp_cancel_adding_invoice 5411 5411 0 0.00 4 95897 1467 0 0
sp_cancel_client_order 1719 1719 0 0.00 6 14429 481 0 0
sp_cancel_customer_reserve 537510 537510 0 0.00 1 25859 115 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 3 5482 165 0 0
sp_cancel_pay_to_supplier 381 381 0 0.00 4 9556 203 0 0
sp_cancel_supplier_invoice 6101 6088 13 0.21 2 19058 1157 0 13
sp_cancel_supplier_order 420 420 0 0.00 9 1729360 188426 0 0
sp_cancel_write_off 9533 9533 0 0.00 2 7591 192 0 0
sp_client_order 19711 19711 0 0.00 5 25530 501 0 0
sp_customer_reserve 455798 455798 0 0.00 0 47782 275 0 0
sp_fill_shopping_cart 30148 30148 0 0.00 1 14003 89 0 0
sp_fill_shopping_cart_clo_res 455773 455773 0 0.00 0 17899 20 0 0
sp_get_clo_for_invoice 4730 4730 0 0.00 0 28956 339 0 0
sp_kill_cost_storno 15984 15984 0 0.00 0 7369 92 0 0
sp_kill_qstorno_ret_qs2qd 1091061 1091061 0 0.00 0 25834 49 0 0
sp_kill_qty_storno 574947 574947 0 0.00 0 25836 114 0 0
sp_lock_selected_doc 19794 19794 0 0.00 0 9239 9 0 0
sp_make_cost_storno 26717 26717 0 0.00 0 18332 88 0 0
sp_multiply_rows_for_pdistr 26717 26717 0 0.00 0 7733 40 0 0
sp_multiply_rows_for_qdistr 19711 19711 0 0.00 0 21774 192 0 0
sp_pay_from_customer 6858 6858 0 0.00 5 11047 191 0 0
sp_pay_to_supplier 5593 5593 0 0.00 6 12139 200 0 0
sp_payment_common 12451 12451 0 0.00 2 9729 54 0 0
sp_qd_handle_on_cancel_clo 1719 1719 0 0.00 0 14421 402 0 0
sp_qd_handle_on_invoice_upd_sts 10141 10141 0 0.00 0 18058 637 0 0
sp_qd_handle_on_reserve_upd_sts 9536 9536 0 0.00 0 12185 60 0 0
sp_reserve_write_off 9536 9536 0 0.00 8 12360 296 0 0
sp_supplier_invoice 5234 5234 0 0.00 9 70454 3070 0 0
sp_supplier_order 5216 5216 0 0.00 7 32532 1361 0 0
srv_aggregate_perf_data 113 113 0 0.00 2314 226887 16332 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 735 144525 31721 0 0
srv_find_qd_qs_mism 1028970 1028970 0 0.00 0 16060 11 0 0
srv_make_invnt_saldo 451 451 0 0.00 55 293515 5253 0 0
srv_make_money_saldo 938 938 0 0.00 1 72361 499 0 0
srv_recalc_idx_stat 4 4 0 0.00 87284 635237 324240 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 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4764 4764 0 0.00 1 10034 1106 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 1 8923 617 0 0
t$perf-norm:sp_cancel_client_order 1719 1719 0 0.00 0 11880 35 0 0
t$perf-norm:sp_cancel_customer_reserve 628 628 0 0.00 0 4464 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 1440 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 381 381 0 0.00 0 2183 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 791 31 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 130977 8741 0 0
t$perf-norm:sp_cancel_write_off 940 940 0 0.00 0 1055 23 0 0
t$perf-norm:sp_client_order 19711 19711 0 0.00 0 11804 42 0 0
t$perf-norm:sp_customer_reserve 918 918 0 0.00 0 2832 18 0 0
t$perf-norm:sp_pay_from_customer 6858 6858 0 0.00 0 11824 22 0 0
t$perf-norm:sp_pay_to_supplier 5593 5593 0 0.00 0 5112 25 0 0
t$perf-norm:sp_reserve_write_off 9536 9536 0 0.00 0 5240 24 0 0
t$perf-norm:sp_supplier_invoice 5234 5234 0 0.00 0 4800 34 0 0
t$perf-norm:sp_supplier_order 5216 5216 0 0.00 0 2236 35 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 450 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 2707 47 0 0
t$perf-norm:srv_make_invnt_saldo 451 451 0 0.00 0 839 18 0 0
t$perf-norm:srv_make_money_saldo 938 938 0 0.00 0 1120 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 7 192 80 0 0
v_add_invoice_to_stock 9700 9456 244 2.51 0 3968 27 0 244
v_all_customers 36124 36124 0 0.00 0 91 4 0 0
v_all_suppliers 29480 29480 0 0.00 0 83 5 0 0
v_all_wares 39422 39422 0 0.00 0 58 4 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 2661 18 0 0
v_cancel_client_order 3438 3438 0 0.00 0 4360 12 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 4273 22 0 0
v_cancel_customer_reserve 1256 1256 0 0.00 1 4603 18 0 0
v_cancel_payment_to_supplier 762 762 0 0.00 0 5075 26 0 0
v_cancel_supplier_invoice 824 798 26 3.15 0 1835 27 0 26
v_cancel_supplier_order 840 840 0 0.00 0 5074 17 0 0
v_cancel_write_off 1880 1880 0 0.00 0 914 23 0 0
v_max_id_clo_ord 5216 5216 0 0.00 0 191 6 0 0
v_max_id_clo_res 918 918 0 0.00 0 1825 15 0 0
v_max_id_ord_sup 5220 5220 0 0.00 0 4318 7 0 0
v_max_non_paid_invoice 1289 1289 0 0.00 0 508 9 0 0
v_max_non_paid_realizn 3528 3528 0 0.00 0 1565 9 0 0
v_min_id_clo_ord 5216 5216 0 0.00 0 2203 10 0 0
v_min_id_clo_res 918 918 0 0.00 0 7375 22 0 0
v_min_id_ord_sup 5220 5220 0 0.00 0 5441 11 0 0
v_min_non_paid_invoice 5593 5593 0 0.00 0 9725 19 0 0
v_min_non_paid_realizn 6858 6858 0 0.00 0 8159 24 0 0
v_our_firm 9958 9958 0 0.00 0 91 5 0 0
v_reserve_write_off 19072 19072 0 0.00 0 5200 12 0 0
x_lock_depdocs_on_canc_invoice 11100 11100 0 0.00 0 18870 134 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 6559 388 0 0
x_make_qstorno_1000_1200 5216 5216 0 0.00 3 32520 1168 0 0
x_make_qstorno_1200_2000 5234 5234 0 0.00 4 70329 2826 0 0
x_make_qstorno_2100_3300 455798 455798 0 0.00 0 44878 241 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-10-2022 13:39:13 to 20-10-2022 13:39:17.

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:26 927 8192000000 8931911712 8989212672 25357232 0.308 5 3 6 0 26079024 196848 524944
11:08:28 24122 8192000000 9724635312 10026319872 800446464 8.901 100 79 158 0 827509088 7724096 19338528
11:09:54 6076 8192000000 9769565712 10077741056 844056032 9.340 100 89 178 0 878146544 13035232 21055280
11:11:08 7637 8192000000 9801939696 10117877760 872728016 9.627 100 87 173 1 910259712 17748720 19782976
11:12:23 13835 8192000000 9851362432 10180022272 905264992 9.950 100 90 178 0 960422896 35493024 19664880
11:13:48 5005 8192000000 9892642288 10231660544 936941824 10.263 100 81 156 0 1002940032 49250048 16748160
11:14:55 3183 8192000000 9936361104 10284134400 971680512 10.603 100 86 171 0 1057369040 67688528 18000000
11:16:01 2298 8192000000 9989262656 10345709568 1006384160 10.940 100 91 180 0 1098187296 73100896 18702240
11:17:05 1327 8192000000 9986065120 10349223936 1029767744 11.166 100 86 168 2 1103913984 57460656 16685584
11:18:08 1292 8192000000 10024647056 10399174656 1050810592 11.368 100 92 174 1 1146764624 79260544 16693488
11:19:11 1472 8192000000 10028555696 10405564416 1062856848 11.484 100 92 172 2 1146256032 65921040 17478144
11:20:15 896 8192000000 10043318032 10420506624 1068147616 11.534 100 92 175 1 1160550032 74673840 17728576
11:21:17 1147 8192000000 10048951392 10436517888 1073928048 11.590 100 94 177 2 1173450368 82187008 17335312
11:22:19 4134 8192000000 10037729936 10427379712 1081874208 11.665 100 66 127 1 1151818752 57266656 12677888
11:23:24 1266 8192000000 10072428592 10460061696 1083249936 11.678 100 96 181 3 1191512016 89249568 19012512
11:24:26 1179 8192000000 10054731360 10444611584 1088334464 11.727 100 95 184 3 1177742608 69803312 19604832
11:25:29 1293 8192000000 10074059168 10461065216 1097409680 11.813 100 98 187 2 1194564224 78774224 18380320
11:26:31 1146 8192000000 10064659728 10452455424 1101410624 11.851 100 98 182 2 1187162912 66837936 18914352
11:27:32 2009 8192000000 10064572784 10458984448 1101064880 11.848 100 84 154 1 1182696880 66254064 15377936
11:28:35 3173 8192000000 10053443696 10444570624 1101483872 11.852 100 71 126 2 1176646944 61676048 13487024
11:29:40 1444 8192000000 10056566512 10448302080 1089694000 11.740 100 81 145 2 1174840816 70876128 14270688
11:30:42 1340 8192000000 10045911616 10434945024 1079726368 11.645 99 81 160 0 1165118128 70014720 15377040
11:31:45 1638 8192000000 10023389568 10406346752 1067598464 11.529 100 78 155 3 1142031232 58917424 15515344
11:32:47 971 8192000000 10026377168 10407952384 1067193184 11.525 100 94 182 1 1146881536 61101360 18586992
11:33:50 1021 8192000000 10019207904 10397102080 1054205456 11.401 100 89 165 2 1143432496 68681984 20545056
11:34:52 884 8192000000 10000400176 10375880704 1036584160 11.232 100 92 178 1 1122265952 67166912 18514880
11:35:54 828 8192000000 9920504880 10285068288 999746592 10.876 100 80 140 1 1041944512 26802704 15395216
11:36:56 1244 8192000000 9967949904 10338889728 1028588112 11.155 100 83 149 3 1087661600 44643968 14429520
11:38:03 15912 8192000000 9983896240 10372112384 1057194976 11.430 100 51 98 1 1105164208 38404464 9564768
11:39:24 1619 8192000000 10063617632 10442743808 1072549040 11.576 100 92 174 2 1184034816 94152832 17332944
11:40:30 9639 8192000000 10042629184 10440589312 1084632512 11.692 100 70 136 1 1163998992 65219792 14146688
11:41:45 9462 8192000000 10081969504 10467647488 1096585280 11.805 100 76 148 0 1194753984 83417792 14750912
11:43:04 3113 8192000000 10085784144 10476097536 1100032256 11.838 100 65 122 2 1202407744 89761728 12613760
11:44:11 4929 8192000000 10136453776 10526142464 1120579712 12.032 100 76 151 0 1248045008 111583952 15881344
11:45:23 5802 8192000000 10165589360 10573004800 1133190976 12.151 100 63 119 0 1278776736 132568752 13017008
11:46:32 1264 8192000000 10223996896 10619076608 1132055888 12.141 99 91 181 3 1332625856 180982976 19586992
11:47:39 4298 8192000000 10210498064 10618007552 1148415968 12.295 100 73 133 3 1330021584 165511168 16094448
11:48:48 808 8192000000 10264307680 10670911488 1155544368 12.362 100 97 187 2 1375384640 198446240 21394032
11:49:54 2131 8192000000 10289234304 10688995328 1172812928 12.523 100 86 163 2 1399143424 207899664 18430832
11:51:02 1740 8192000000 10334576880 10734514176 1176045696 12.553 100 91 177 0 1437986816 242024128 19916992
11:52:07 898 8192000000 10364802928 10773274624 1182758752 12.616 100 95 181 0 1472758272 268972352 21027168
11:53:10 2123 8192000000 10410335184 10820771840 1179965472 12.590 100 91 174 0 1516463360 316433392 20064496
11:54:16 1219 8192000000 10407195008 10842038272 1183936912 12.627 100 94 179 0 1525904816 320029952 21937952
11:55:21 946 8192000000 10409150368 10842013696 1181398864 12.603 100 98 188 1 1517037120 314577408 21060848
11:56:26 1667 8192000000 10431193584 10865930240 1193850032 12.719 100 93 176 0 1532382336 318675616 19856688
11:57:31 1128 8192000000 10433672832 10871877632 1205296912 12.825 100 95 190 0 1543324272 316117072 21910288
11:58:37 954 8192000000 10462846704 10886672384 1204717008 12.820 100 97 187 1 1567962192 341534016 21711168
11:59:42 897 8192000000 10511003584 10918711296 1208292016 12.853 100 96 188 1 1614630544 384588528 21750000
12:00:45 3243 8192000000 10544025040 10972733440 1209220272 12.862 100 96 191 0 1646925904 414082448 23623184
12:01:52 797 8192000000 10563983216 10991550464 1206912704 12.840 100 98 189 0 1668037136 437775504 23348928
12:02:57 722 8192000000 10593549600 11002040320 1199176560 12.769 100 97 191 0 1694439152 472157040 23105552
12:04:00 771 8192000000 10607242752 11033411584 1175027728 12.544 100 92 181 1 1711898816 514591216 22279872
12:05:04 764 8192000000 10674138624 11108052992 1190771184 12.691 100 94 184 0 1776942416 563238496 22932736
12:06:12 1241 8192000000 10674606352 11139575808 1195097392 12.731 100 95 188 1 1775772336 557716272 22958672
12:07:15 735 8192000000 10629349264 11169689600 1185835952 12.645 100 99 193 1 1731498480 522301664 23360864
12:08:18 854 8192000000 10568251456 11115192320 1188770304 12.672 100 87 172 0 1669795472 459715856 21309312
12:09:23 21987 8192000000 10528944432 10968412160 1190857696 12.691 100 49 98 0 1620145552 416807616 12480240
12:10:46 11668 8192000000 10489166416 11002982400 1188499856 12.669 100 38 76 0 1587000272 388813376 9687040
12:12:00 22485 8192000000 10350755136 10859175936 1173696528 12.531 100 31 62 0 1482341280 300363360 8281392
12:13:32 23509 8192000000 10294576928 10786021376 1152961152 12.337 100 29 59 1 1402560384 242381632 7217600
12:15:14 40114 8192000000 10126732304 10624946176 1140166976 12.217 99 14 26 1 1247592720 104537424 2888320
12:16:56 65918 8192000000 10117986128 10578059264 1134542240 12.164 98 23 46 1 1214814208 75351328 4920640
12:19:03 91190 8192000000 10107579616 10543280128 1130680640 12.128 99 33 56 1 1182591024 46655424 5254960
12:21:59 113578 8192000000 10078859040 10532933632 1112948336 11.960 97 39 76 2 1155774960 34840448 7986176
12:25:19 52463 8192000000 10144192000 10586083328 1138612560 12.202 99 30 58 0 1220588576 76022528 5953488
12:27:16 17998 8192000000 10139997344 10620448768 1134046048 12.159 99 25 49 0 1231099760 91314048 5739664
12:29:51 67808 8192000000 10078808880 10538246144 1115099920 11.981 98 12 24 0 1179015600 61525616 2390064
12:32:11 9082 8192000000 10082687680 10543259648 1134324944 12.162 100 25 42 3 1191184240 52685088 4174208
12:33:33 47157 8192000000 10084326720 10578825216 1126468848 12.088 99 24 45 0 1200784912 69891680 4424384
12:35:32 76192 8192000000 10069710736 10547748864 1118426704 12.012 98 16 32 0 1143064784 20074000 4564080
12:38:01 62165 8192000000 10150645712 10613972992 1107037200 11.904 96 36 70 1 1201390544 86979184 7374160
12:40:14 85304 8192000000 10053745984 10522251264 1095069648 11.791 96 16 32 0 1144141600 46137024 2934928
12:42:44 19580 8192000000 10157271760 10589757440 1133232352 12.152 99 43 84 1 1225039968 82625120 9182496
12:44:10 46598 8192000000 10180640640 10663923712 1152608272 12.334 100 18 36 1 1294571488 137703456 4259760
12:45:57 31891 8192000000 10070617664 10522267648 1124505424 12.070 98 24 46 0 1171276368 41640896 5130048
12:47:30 3784 8192000000 10123240608 10548957184 1144945616 12.262 100 65 129 1 1215000672 56990928 13064128
12:48:39 144519 8192000000 10076621120 10517770240 1106699280 11.901 96 49 98 1 1183220032 66890064 9630688
12:52:04 57537 8192000000 10078696480 10576707584 1141336112 12.228 100 13 26 0 1199554480 55657280 2561088
12:54:13 40435 8192000000 10071819312 10541330432 1097253056 11.812 96 9 17 1 1155900128 57118880 1528192
12:56:20 67921 8192000000 10090923568 10563747840 1071199936 11.564 95 19 38 0 1158674192 83094656 4379600
12:58:29 71340 8192000000 10150753056 10644807680 1114201360 11.972 100 10 20 0 1246036480 129324000 2511120
13:01:16 40133 8192000000 10069972896 10510319616 1107506176 11.909 98 25 50 1 1146145040 33122080 5516784
13:02:56 22817 8192000000 10088839424 10541436928 1120445936 12.031 99 37 68 0 1191359008 64040592 6872480
13:04:24 19861 8192000000 10137603216 10579324928 1132998384 12.150 99 32 62 0 1232962608 92883744 7080480
13:06:06 42226 8192000000 10171647824 10624503808 1111830288 11.950 97 37 74 0 1218760064 98974720 7955056
13:08:19 38820 8192000000 10172275648 10644226048 1146621520 12.278 100 29 58 0 1305077840 152049904 6406416
13:10:25 36773 8192000000 10080648064 10510348288 1146505776 12.277 100 47 88 1 1172273760 17533456 8234528
13:12:03 88760 8192000000 10108134768 10583560192 1143204832 12.246 100 47 94 0 1215393872 62907184 9281856
13:15:41 49277 8192000000 10084687616 10562981888 1118777424 12.015 98 35 71 0 1185317424 59463392 7076608
13:17:30 77673 8192000000 10069690928 10537549824 1110004256 11.932 98 19 38 0 1159542992 45574784 3963952
13:19:59 75692 8192000000 10067334560 10534412288 1095064528 11.791 97 17 34 0 1153222000 54274304 3883168
13:23:21 49444 8192000000 10158524240 10640150528 1146549280 12.277 100 29 58 0 1266598656 113762336 6287040
13:26:33 95133 8192000000 10037812240 10488283136 1080201472 11.649 95 12 24 0 1123649168 40902912 2544784
13:29:10 24017 8192000000 10110006640 10568314880 1136123328 12.179 100 37 74 0 1217459264 73847360 7488576
13:30:42 65354 8192000000 10076454832 10523000832 1096132240 11.801 96 42 82 0 1139729600 35461248 8136112
13:32:57 36746 8192000000 10105834672 10537197568 1107827664 11.912 98 44 88 0 1172237152 55806496 8602992
13:35:08 61066 8192000000 10092976128 10520539136 1092013520 11.762 97 27 54 0 1152618896 55463408 5141968
13:37:10 24361 8192000000 8903593840 9083191296 73522560 0.889 7 3 6 0 81813568 7856544 434464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 20-10-2022 13:39:19 to 20-10-2022 13:39:35.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-10-2022 13:39:36 to 20-10-2022 13:39:38.

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          213592
MON$OLDEST_ACTIVE               213593
MON$OLDEST_SNAPSHOT             213593
MON$NEXT_TRANSACTION            213593
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                       2109568
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             765
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

20.10.22 13:39:39. 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-10-20 13:39:42 No severe PSQL-related problems occured
20.10.22 13:39:43. End of report.