Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.01.2022 07: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 16194920 kB
MemAvailable 31289716 kB
Buffers 0 kB
Cached 15481544 kB
SwapCached 1176 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 2022-01-11 07:37:11
DTS_END                         2022-01-11 07:37:11.1030
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/41424
TRN_ID                          219353
ATT_ID                          634
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: 8773.01 21748 1052761
customer order: creation 166.65 445 19998
customer order: refuse 14.18 424 1701
order to supplier: creation 44.52 1467 5342
order to supplier: removal 3.22 155090 386
invoice (draft): creation 45.19 3384 5423
invoice (draft): removal 48.09 975 5771
invoice accept: apply 41.43 25412 4972
invoice accept: cancel 43.40 1274 5208
customer reserve: creation 3863.38 239 463605
customer reserve: removal 4223.11 93 506773
realization accept: apply 80.77 289 9692
realization accept: cancel 72.67 170 8720
payment from customer: creation 57.91 185 6949
payment from customer: removal 5.61 148 673
payment to supplier: creation 47.60 198 5712
payment to supplier: removal 3.33 156 399
service: total inventory turnovers 3.74 4142 449
service: total monetary turnovers 7.29 377 875
service: aggregate perf. data 0.90 16744 108
service: refresh index statistics 0.04 223744 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-01-2022 07:38:30 to 11-01-2022 07:38:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8664
TEST_TIME 2 7059
TEST_TIME 3 5910
TEST_TIME 4 5941
TEST_TIME 5 5911
TEST_TIME 6 6041
TEST_TIME 7 6496
TEST_TIME 8 6965
TEST_TIME 9 7007
TEST_TIME 10 7404
TEST_TIME 11 7349
TEST_TIME 12 7358
TEST_TIME 13 7559
TEST_TIME 14 7661
TEST_TIME 15 7628
TEST_TIME 16 7849
TEST_TIME 17 7977
TEST_TIME 18 8434
TEST_TIME 19 8488
TEST_TIME 20 8958
TEST_TIME 21 9065
TEST_TIME 22 9229
TEST_TIME 23 9326
TEST_TIME 24 9291
TEST_TIME 25 9223
TEST_TIME 26 9296
TEST_TIME 27 9735
TEST_TIME 28 9804
TEST_TIME 29 9855
TEST_TIME 30 10072
TEST_TIME 31 10273
TEST_TIME 32 10613
TEST_TIME 33 11025
TEST_TIME 34 10798
TEST_TIME 35 10622
TEST_TIME 36 10660
TEST_TIME 37 10722
TEST_TIME 38 10610
TEST_TIME 39 10657
TEST_TIME 40 10575
TEST_TIME 41 10356
TEST_TIME 42 10203
TEST_TIME 43 10069
TEST_TIME 44 10025
TEST_TIME 45 9966
TEST_TIME 46 9954
TEST_TIME 47 9834
TEST_TIME 48 9688
TEST_TIME 49 9570
TEST_TIME 50 9529
TEST_TIME 51 9655
TEST_TIME 52 9620
TEST_TIME 53 9477
TEST_TIME 54 9380
TEST_TIME 55 9309
TEST_TIME 56 9289
TEST_TIME 57 9378
TEST_TIME 58 9447
TEST_TIME 59 9367
TEST_TIME 60 9286
TEST_TIME 61 9390
TEST_TIME 62 9447
TEST_TIME 63 9462
TEST_TIME 64 9430
TEST_TIME 65 9461
TEST_TIME 66 9470
TEST_TIME 67 9402
TEST_TIME 68 9339
TEST_TIME 69 9507
TEST_TIME 70 9540
TEST_TIME 71 9506
TEST_TIME 72 9561
TEST_TIME 73 9506
TEST_TIME 74 9389
TEST_TIME 75 9302
TEST_TIME 76 9285
TEST_TIME 77 9295
TEST_TIME 78 9317
TEST_TIME 79 9291
TEST_TIME 80 9241
TEST_TIME 81 9206
TEST_TIME 82 9170
TEST_TIME 83 9093
TEST_TIME 84 9106
TEST_TIME 85 9086
TEST_TIME 86 9055
TEST_TIME 87 9012
TEST_TIME 88 9012
TEST_TIME 89 8994
TEST_TIME 90 8949
TEST_TIME 91 8937
TEST_TIME 92 8928
TEST_TIME 93 8912
TEST_TIME 94 8963
TEST_TIME 95 8933
TEST_TIME 96 9013
TEST_TIME 97 9025
TEST_TIME 98 9150
TEST_TIME 99 9160
TEST_TIME 100 9220
TEST_TIME 101 9205
TEST_TIME 102 9232
TEST_TIME 103 9228
TEST_TIME 104 9213
TEST_TIME 105 9146
TEST_TIME 106 9094
TEST_TIME 107 9070
TEST_TIME 108 9052
TEST_TIME 109 9041
TEST_TIME 110 9014
TEST_TIME 111 8985
TEST_TIME 112 8937
TEST_TIME 113 8878
TEST_TIME 114 8863
TEST_TIME 115 8903
TEST_TIME 116 8894
TEST_TIME 117 8889
TEST_TIME 118 8860
TEST_TIME 119 8866
TEST_TIME 120 8800

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-01-2022 07:38:33 to 11-01-2022 07:38:37.

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 1478534 1478534 0 0.00 0 20790 13 0 0
doc_list_biud 1478518 1478518 0 0.00 0 37642 38 0 0
sp_add_invoice_to_stock 5061 4972 89 1.75 42 196009 25413 0 89
sp_cancel_adding_invoice 5208 5208 0 0.00 5 83876 1275 0 0
sp_cancel_client_order 1701 1701 0 0.00 6 14847 425 0 0
sp_cancel_customer_reserve 506773 506773 0 0.00 1 37694 93 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 4 11810 148 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 3 6977 156 0 0
sp_cancel_supplier_invoice 5778 5771 7 0.12 2 18028 975 0 7
sp_cancel_supplier_order 386 386 0 0.00 14 1226749 155091 0 0
sp_cancel_write_off 8720 8720 0 0.00 2 15355 170 0 0
sp_client_order 19998 19998 0 0.00 6 37474 446 0 0
sp_customer_reserve 463605 463605 0 0.00 1 70800 240 0 0
sp_fill_shopping_cart 30746 30746 0 0.00 1 32434 91 0 0
sp_fill_shopping_cart_clo_res 463572 463572 0 0.00 0 19043 18 0 0
sp_get_clo_for_invoice 4932 4932 0 0.00 0 48105 359 0 0
sp_kill_cost_storno 15000 15000 0 0.00 0 15332 75 0 0
sp_kill_qstorno_ret_qs2qd 1028423 1028423 0 0.00 0 37522 41 0 0
sp_kill_qty_storno 543180 543180 0 0.00 0 37639 95 0 0
sp_lock_selected_doc 20216 20216 0 0.00 0 5863 7 0 0
sp_make_cost_storno 27284 27284 0 0.00 0 20755 84 0 0
sp_multiply_rows_for_pdistr 27282 27282 0 0.00 0 15644 37 0 0
sp_multiply_rows_for_qdistr 19998 19998 0 0.00 0 11683 154 0 0
sp_pay_from_customer 6949 6949 0 0.00 6 14432 185 0 0
sp_pay_to_supplier 5712 5712 0 0.00 6 15679 199 0 0
sp_payment_common 12661 12661 0 0.00 2 8930 47 0 0
sp_qd_handle_on_cancel_clo 1701 1701 0 0.00 1 14840 335 0 0
sp_qd_handle_on_invoice_upd_sts 10139 10139 0 0.00 0 14984 521 0 0
sp_qd_handle_on_reserve_upd_sts 9690 9690 0 0.00 0 12968 60 0 0
sp_reserve_write_off 9692 9692 0 0.00 8 13396 289 0 0
sp_supplier_invoice 5423 5423 0 0.00 12 90311 3385 0 0
sp_supplier_order 5342 5342 0 0.00 7 59673 1467 0 0
srv_aggregate_perf_data 108 108 0 0.00 2528 175220 16745 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 958 164914 40178 0 0
srv_find_qd_qs_mism 1004001 1004001 0 0.00 0 18395 10 0 0
srv_make_invnt_saldo 449 449 0 0.00 77 83275 4142 0 0
srv_make_money_saldo 875 875 0 0.00 1 13958 377 0 0
srv_recalc_idx_stat 5 5 0 0.00 94337 357665 223745 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4976 4976 0 0.00 0 9933 904 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 1 4798 427 0 0
t$perf-norm:sp_cancel_client_order 1701 1701 0 0.00 0 5760 28 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 1243 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 366 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 540 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 2516 37 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 1 90814 6983 0 0
t$perf-norm:sp_cancel_write_off 964 964 0 0.00 0 804 18 0 0
t$perf-norm:sp_client_order 20000 20000 0 0.00 0 6541 37 0 0
t$perf-norm:sp_customer_reserve 986 986 0 0.00 0 2748 20 0 0
t$perf-norm:sp_pay_from_customer 6949 6949 0 0.00 0 3023 17 0 0
t$perf-norm:sp_pay_to_supplier 5712 5712 0 0.00 0 5217 22 0 0
t$perf-norm:sp_reserve_write_off 9693 9693 0 0.00 0 5119 23 0 0
t$perf-norm:sp_supplier_invoice 5423 5423 0 0.00 0 6215 32 0 0
t$perf-norm:sp_supplier_order 5342 5342 0 0.00 0 7774 33 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 520 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 394 9 0 0
t$perf-norm:srv_make_invnt_saldo 449 449 0 0.00 0 1088 18 0 0
t$perf-norm:srv_make_money_saldo 875 875 0 0.00 0 796 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 174 40 0 0
v_add_invoice_to_stock 10028 9850 178 1.77 0 23334 32 0 178
v_all_customers 36496 36496 0 0.00 0 76 4 0 0
v_all_suppliers 30000 30000 0 0.00 0 126 4 0 0
v_all_wares 39988 39988 0 0.00 0 137 4 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 3362 21 0 0
v_cancel_client_order 3402 3402 0 0.00 0 7586 22 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 4114 19 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 1493 11 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 4472 30 0 0
v_cancel_supplier_invoice 770 756 14 1.81 0 1396 23 0 14
v_cancel_supplier_order 772 772 0 0.00 0 1413 15 0 0
v_cancel_write_off 1928 1928 0 0.00 0 10988 45 0 0
v_max_id_clo_ord 5339 5339 0 0.00 0 848 5 0 0
v_max_id_clo_res 986 986 0 0.00 0 2150 13 0 0
v_max_id_ord_sup 5411 5411 0 0.00 0 7308 8 0 0
v_max_non_paid_invoice 1462 1462 0 0.00 0 5069 11 0 0
v_max_non_paid_realizn 3636 3636 0 0.00 0 811 6 0 0
v_min_id_clo_ord 5339 5339 0 0.00 0 7592 17 0 0
v_min_id_clo_res 986 986 0 0.00 0 4123 19 0 0
v_min_id_ord_sup 5411 5411 0 0.00 0 8970 15 0 0
v_min_non_paid_invoice 5712 5712 0 0.00 0 8911 19 0 0
v_min_non_paid_realizn 6948 6948 0 0.00 0 8150 18 0 0
v_our_firm 10118 10118 0 0.00 0 57 5 0 0
v_reserve_write_off 19378 19378 0 0.00 0 13177 17 0 0
x_lock_depdocs_on_canc_invoice 10601 10601 0 0.00 0 20481 132 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 4995 322 0 0
x_make_qstorno_1000_1200 5342 5342 0 0.00 3 59631 1281 0 0
x_make_qstorno_1200_2000 5423 5423 0 0.00 5 89778 3134 0 0
x_make_qstorno_2100_3300 463605 463605 0 0.00 0 70797 210 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-01-2022 07:38:38 to 11-01-2022 07:38:42.

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
05:07:14 23 8192000000 8808767504 8823758848 11279920 0.137 5 2 2 0 11418032 75888 62224
05:08:16 35678 8192000000 9733683712 10030895104 799137808 8.888 100 87 174 1 828419888 8116272 21165808
05:09:55 17168 8192000000 9762585392 10067628032 836272224 9.262 100 85 168 1 868316352 12383024 19661104
05:11:21 23472 8192000000 9815440240 10136846336 878175088 9.682 100 80 160 0 916475424 20578000 17722336
05:12:50 8045 8192000000 9877264560 10209501184 930542896 10.200 100 87 174 0 987118880 37696080 18879904
05:14:01 6104 8192000000 9906780800 10252001280 960580480 10.495 100 74 146 1 1027638480 52320912 14737088
05:15:10 3227 8192000000 9953333104 10307469312 995701024 10.837 100 84 162 2 1082971792 71153536 16117232
05:16:16 1664 8192000000 10001972096 10365480960 1026228528 11.132 100 90 174 0 1123915392 80512016 17174848
05:17:20 1339 8192000000 10037413232 10412503040 1044713376 11.310 100 94 179 0 1159651984 96385648 18552960
05:18:22 1129 8192000000 10037017008 10412527616 1064387968 11.498 100 93 176 0 1159163808 76135488 18640352
05:19:24 9520 8192000000 10033215056 10412929024 1066292064 11.517 100 77 143 1 1162734816 82197040 14245712
05:20:35 1254 8192000000 10025992928 10410323968 1074325680 11.593 100 81 154 2 1145417504 56074576 15017248
05:21:38 4235 8192000000 10029259040 10411008000 1078252240 11.631 100 86 160 1 1150354528 55809152 16293136
05:22:43 995 8192000000 10058687392 10443653120 1082095216 11.667 100 90 176 2 1176404880 76255776 18053888
05:23:46 1040 8192000000 10065886320 10453868544 1085332304 11.698 100 91 175 1 1184347840 81925136 17090400
05:24:48 7250 8192000000 10027801632 10423984128 1090710944 11.749 100 81 156 2 1161887152 53867728 17308480
05:26:01 1686 8192000000 10040145072 10430697472 1093443488 11.775 100 83 158 1 1158836032 47999824 17392720
05:27:03 952 8192000000 10031612352 10420822016 1094939200 11.790 100 85 160 3 1153145872 39938880 18267792
05:28:05 997 8192000000 10043963456 10431889408 1091444832 11.756 100 90 177 0 1161955200 51994144 18516224
05:29:08 2529 8192000000 10038740368 10430881792 1084158768 11.687 100 65 125 5 1156956256 60040736 12756752
05:30:12 962 8192000000 10032250256 10416369664 1073764944 11.588 99 83 163 0 1151136032 59692112 17678976
05:31:13 1124 8192000000 9989292400 10364829696 1045800944 11.320 100 96 187 3 1113349808 47895536 19653328
05:32:15 1086 8192000000 9970457488 10337091584 1025301472 11.123 100 86 159 0 1089512992 49010160 15201360
05:33:18 1220 8192000000 10011133808 10379452416 1035609568 11.222 100 97 183 2 1134912800 79775088 19528144
05:34:21 1195 8192000000 9991849984 10367102976 1032132432 11.189 100 88 168 1 1121905328 71141520 18631376
05:35:23 1019 8192000000 9990734096 10363670528 1042427424 11.288 100 93 177 1 1113351056 52179152 18744480
05:36:24 1421 8192000000 9993415264 10363785216 1042635792 11.290 100 96 184 3 1123534800 61513200 19385808
05:37:29 3464 8192000000 9988881328 10361294848 1044776352 11.311 100 78 135 3 1106362976 49008912 12577712
05:38:41 1652 8192000000 10002823696 10384359424 1065586976 11.510 100 76 145 0 1128652528 47381264 15684288
05:39:46 1186 8192000000 10053951120 10435932160 1086233408 11.707 100 88 175 1 1184972352 79894016 18844928
05:40:51 14095 8192000000 10095695568 10490626048 1093503392 11.776 100 67 130 0 1215486560 108621344 13361824
05:42:16 4436 8192000000 10105416656 10497474560 1110799840 11.940 100 65 126 0 1219241552 94329872 14111840
05:43:24 1891 8192000000 10174293600 10560688128 1119385520 12.021 100 95 190 1 1292579728 153405136 19789072
05:44:29 2637 8192000000 10206059344 10603491328 1134081232 12.160 100 80 156 0 1313247280 162574688 16591360
05:45:35 3898 8192000000 10182957616 10593284096 1139054336 12.207 100 77 148 2 1298823824 143640032 16129456
05:46:45 1332 8192000000 10224775520 10615291904 1149014640 12.300 100 90 174 1 1332075744 165538848 17522256
05:47:49 1204 8192000000 10236358176 10632151040 1159308672 12.397 100 87 161 1 1343195600 164806096 19080832
05:48:53 2167 8192000000 10263754432 10656837632 1157887424 12.383 100 93 183 1 1373042208 195624384 19530400
05:49:58 1052 8192000000 10277487904 10685681664 1174354688 12.538 100 96 188 1 1390485408 194561232 21569488
05:51:04 3200 8192000000 10333400688 10736996352 1179394832 12.585 100 81 160 0 1444270624 246904208 17971584
05:52:10 1563 8192000000 10361027376 10767536128 1181751360 12.607 100 86 166 0 1472091152 271572704 18767088
05:53:15 942 8192000000 10366530944 10790563840 1182405424 12.613 100 92 181 1 1476735104 272894976 21434704
05:54:19 1737 8192000000 10386880400 10811867136 1189328624 12.677 100 90 174 3 1494869488 285000720 20540144
05:55:23 1443 8192000000 10368580128 10812432384 1175489824 12.548 100 90 180 0 1479628176 282844880 21293472
05:56:28 1645 8192000000 10350796880 10798698496 1177814464 12.570 100 90 178 1 1457394624 258840288 20739872
05:57:32 1005 8192000000 10364507744 10819653632 1173913824 12.533 100 98 192 0 1472994176 276526992 22553360
05:58:36 17621 8192000000 10326326416 10781392896 1156429552 12.370 100 43 86 2 1446105280 278643472 11032256
05:59:57 8285 8192000000 10347050368 10772709376 1161650688 12.419 100 46 92 0 1460468688 287828592 10989408
06:01:13 9388 8192000000 10335013024 10768416768 1162428544 12.426 100 43 82 0 1434177968 261883808 9865616
06:02:28 12231 8192000000 10368419072 10800660480 1149538784 12.305 100 35 70 1 1476943184 318119200 9285200
06:03:41 4605 8192000000 10384584320 10815934464 1152540880 12.333 100 60 117 2 1477513904 311546336 13426688
06:04:55 14350 8192000000 10345186080 10774245376 1157946256 12.384 100 36 72 2 1452492992 285791472 8755264
06:06:10 5398 8192000000 10359923072 10786861056 1134971696 12.168 100 41 79 0 1467112992 322106784 10034512
06:07:24 7492 8192000000 10328684560 10795552768 1142326240 12.237 99 52 93 1 1416478400 261165776 12986384
06:08:39 12837 8192000000 10292874992 10758840320 1144040176 12.254 99 34 68 0 1385360704 233025520 8295008
06:09:53 27823 8192000000 10186012560 10675019776 1135137296 12.170 99 21 42 1 1332370352 191954352 5278704
06:11:30 36838 8192000000 10170092368 10594041856 1141140688 12.226 100 44 83 2 1266780080 116072592 9566800
06:13:15 5045 8192000000 10157624288 10582032384 1140487568 12.220 100 80 149 4 1264839344 107491184 16860592
06:14:22 74880 8192000000 10123744160 10591969280 1132518560 12.145 100 16 32 1 1231242288 95020928 3702800
06:16:38 83074 8192000000 10071830240 10530095104 1105065008 11.886 98 12 25 1 1183095024 74780624 3249392
06:19:10 66903 8192000000 10081977744 10514907136 1111904880 11.950 99 40 78 1 1155524816 36121328 7498608
06:21:24 68660 8192000000 10082023888 10505351168 1129634064 12.118 100 45 86 1 1209595392 71044400 8916928
06:23:34 141683 8192000000 10116074144 10584076288 1116844160 11.997 98 19 38 1 1190591568 69706816 4040592
06:27:03 30866 8192000000 10127027984 10565844992 1121490720 12.041 99 26 51 2 1243274496 115823856 5959920
06:28:54 77482 8192000000 10097045600 10571689984 1125086528 12.075 99 12 23 2 1218916416 90400624 3429264
06:31:12 110109 8192000000 10106037648 10584842240 1102074816 11.857 97 15 30 1 1199397296 93953840 3368640
06:34:03 125123 8192000000 10058026144 10543497216 1110409760 11.936 98 8 16 0 1158898208 46306352 2182096
06:37:13 125877 8192000000 10083915872 10564079616 1111945760 11.951 98 22 43 0 1194341408 78245840 4149808
06:40:19 111852 8192000000 10112410160 10588028928 1119339840 12.021 98 9 19 1 1176511072 55441024 1730208
06:43:13 35261 8192000000 10078956128 10512982016 1116827520 11.997 99 21 42 0 1155159984 33729184 4603280
06:45:13 20089 8192000000 10164763040 10600902656 1136652496 12.184 100 46 88 0 1246723984 100442688 9628800
06:46:34 164914 8192000000 10077055408 10549186560 1062637216 11.482 94 21 42 1 1127636944 61074896 3924832
06:50:20 124998 8192000000 10135086960 10567688192 1113183808 11.963 98 28 56 0 1178254096 58716816 6353472
06:53:25 30039 8192000000 10144811216 10612322304 1137104304 12.188 100 25 50 0 1238757920 96533760 5119856
06:55:02 67583 8192000000 10041845632 10494767104 1075112512 11.601 95 15 29 0 1127585264 49515216 2957536
06:57:10 51647 8192000000 10053552144 10491564032 1104220368 11.878 98 21 40 2 1132338336 23935616 4182352
06:59:02 45340 8192000000 10059322288 10520363008 1118537408 12.013 99 8 16 0 1156010448 35964832 1508208
07:00:48 93513 8192000000 10027990352 10481164288 1087045952 11.715 96 18 36 0 1146956800 55966752 3944096
07:03:35 82257 8192000000 10065326176 10536620032 1103321712 11.869 98 13 26 0 1149921168 44118128 2481328
07:06:20 63879 8192000000 10076768400 10506629120 1089310400 11.736 97 19 33 3 1136560608 44541808 2708400
07:08:25 27873 8192000000 10123122112 10555592704 1092456720 11.766 96 26 50 0 1166941984 69646864 4838400
07:09:58 9836 8192000000 10166230160 10595790848 1128815360 12.110 100 52 100 2 1261643136 121634624 11193152
07:11:08 15568 8192000000 10219207744 10645504000 1138410976 12.201 100 54 108 2 1315573264 165742032 11420256
07:12:26 14504 8192000000 10189418960 10619305984 1107169088 11.906 98 50 100 0 1268607808 150469680 10969040
07:13:44 42828 8192000000 10191460016 10640502784 1136307504 12.181 100 33 66 1 1325809072 181986224 7515344
07:15:27 50424 8192000000 10123636304 10554748928 1090294720 11.745 97 22 44 2 1222456208 127088784 5072704
07:17:23 31498 8192000000 10079426112 10511151104 1104883808 11.884 99 42 84 0 1162399344 49445392 8070144
07:18:57 21392 8192000000 10100700368 10541953024 1123942144 12.064 100 46 90 0 1211508240 78473840 9092256
07:20:20 23768 8192000000 10091864832 10519818240 1128824736 12.110 100 42 82 1 1184525344 47753648 7946960
07:21:44 135979 8192000000 10071783120 10511994880 1127663840 12.099 99 31 62 0 1175066656 41454672 5948144
07:25:11 65429 8192000000 10073605296 10540883968 1141070992 12.226 100 11 19 1 1174506368 31686528 1748848
07:27:28 43264 8192000000 10065404160 10518695936 1135441920 12.173 99 23 46 0 1169846448 29769232 4635296
07:29:25 99152 8192000000 10135314064 10600366080 1146675360 12.278 100 17 34 0 1222296752 71789008 3832384
07:32:07 93080 8192000000 10046457344 10529570816 1084948016 11.695 95 5 10 0 1115471296 29600256 923024
07:34:52 36118 8192000000 10127027392 10553020416 1141976096 12.234 100 51 100 2 1198436400 45010480 11449824
07:36:28 52885 8192000000 9100962960 9379139584 230864576 2.740 20 11 21 0 290114528 56520832 2729120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-01-2022 07:38:44 to 11-01-2022 07:39:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 11-01-2022 07:39:01 to 11-01-2022 07:39:02.

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          220038
MON$OLDEST_ACTIVE               220456
MON$OLDEST_SNAPSHOT             220456
MON$NEXT_TRANSACTION            220456
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                       2067872
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             773
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2704 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2704 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2704 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2704 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 ---
:310: 
:311: mscs-test-161.budzdorov.ru    Tue Jan 11 07:37:39 2022
:312:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:313: 
--- end of diff output ---

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

11.01.22 07:39:04. 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-01-11 07:39:06 No severe PSQL-related problems occured
11.01.22 07:39:07. End of report.