Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.01.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 16254232 kB
MemAvailable 31325068 kB
Buffers 0 kB
Cached 15466592 kB
SwapCached 2300 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-13 13:37:11
DTS_END                         2022-01-13 13:37:11.9750
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/47296
TRN_ID                          220624
ATT_ID                          581
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: 8929.56 26447 1071547
customer order: creation 170.24 466 20429
customer order: refuse 14.48 430 1737
order to supplier: creation 44.34 1395 5321
order to supplier: removal 3.39 167187 407
invoice (draft): creation 45.13 3320 5415
invoice (draft): removal 50.23 1031 6027
invoice accept: apply 41.03 26836 4924
invoice accept: cancel 45.12 1162 5414
customer reserve: creation 3860.95 252 463314
customer reserve: removal 4366.18 101 523942
realization accept: apply 82.93 281 9952
realization accept: cancel 78.47 158 9416
payment from customer: creation 57.77 181 6932
payment from customer: removal 5.80 138 696
payment to supplier: creation 47.60 184 5712
payment to supplier: removal 3.39 142 407
service: total inventory turnovers 3.96 4110 475
service: total monetary turnovers 7.45 337 894
service: aggregate perf. data 1.08 16915 129
service: refresh index statistics 0.03 304322 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-01-2022 13:38:33 to 13-01-2022 13:38:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7416
TEST_TIME 2 6095
TEST_TIME 3 6465
TEST_TIME 4 6081
TEST_TIME 5 5912
TEST_TIME 6 6223
TEST_TIME 7 6951
TEST_TIME 8 6822
TEST_TIME 9 6851
TEST_TIME 10 7015
TEST_TIME 11 7202
TEST_TIME 12 7338
TEST_TIME 13 7222
TEST_TIME 14 7336
TEST_TIME 15 7264
TEST_TIME 16 7622
TEST_TIME 17 7618
TEST_TIME 18 7734
TEST_TIME 19 7774
TEST_TIME 20 7886
TEST_TIME 21 8034
TEST_TIME 22 7977
TEST_TIME 23 8279
TEST_TIME 24 8714
TEST_TIME 25 8707
TEST_TIME 26 8868
TEST_TIME 27 8995
TEST_TIME 28 9171
TEST_TIME 29 9259
TEST_TIME 30 10163
TEST_TIME 31 10138
TEST_TIME 32 10616
TEST_TIME 33 11015
TEST_TIME 34 11233
TEST_TIME 35 11397
TEST_TIME 36 11270
TEST_TIME 37 11133
TEST_TIME 38 11005
TEST_TIME 39 10817
TEST_TIME 40 10697
TEST_TIME 41 10584
TEST_TIME 42 10560
TEST_TIME 43 10376
TEST_TIME 44 10324
TEST_TIME 45 10298
TEST_TIME 46 10204
TEST_TIME 47 10167
TEST_TIME 48 10209
TEST_TIME 49 10184
TEST_TIME 50 10149
TEST_TIME 51 10180
TEST_TIME 52 10327
TEST_TIME 53 10243
TEST_TIME 54 10190
TEST_TIME 55 10243
TEST_TIME 56 10162
TEST_TIME 57 10170
TEST_TIME 58 10164
TEST_TIME 59 10160
TEST_TIME 60 10221
TEST_TIME 61 10167
TEST_TIME 62 10302
TEST_TIME 63 10238
TEST_TIME 64 10209
TEST_TIME 65 10171
TEST_TIME 66 10077
TEST_TIME 67 9994
TEST_TIME 68 9938
TEST_TIME 69 9911
TEST_TIME 70 9841
TEST_TIME 71 9757
TEST_TIME 72 9697
TEST_TIME 73 9588
TEST_TIME 74 9625
TEST_TIME 75 9645
TEST_TIME 76 9594
TEST_TIME 77 9538
TEST_TIME 78 9571
TEST_TIME 79 9553
TEST_TIME 80 9542
TEST_TIME 81 9479
TEST_TIME 82 9543
TEST_TIME 83 9587
TEST_TIME 84 9540
TEST_TIME 85 9598
TEST_TIME 86 9561
TEST_TIME 87 9553
TEST_TIME 88 9616
TEST_TIME 89 9646
TEST_TIME 90 9603
TEST_TIME 91 9514
TEST_TIME 92 9518
TEST_TIME 93 9451
TEST_TIME 94 9426
TEST_TIME 95 9375
TEST_TIME 96 9375
TEST_TIME 97 9327
TEST_TIME 98 9270
TEST_TIME 99 9267
TEST_TIME 100 9279
TEST_TIME 101 9308
TEST_TIME 102 9299
TEST_TIME 103 9266
TEST_TIME 104 9296
TEST_TIME 105 9302
TEST_TIME 106 9283
TEST_TIME 107 9278
TEST_TIME 108 9254
TEST_TIME 109 9269
TEST_TIME 110 9321
TEST_TIME 111 9303
TEST_TIME 112 9240
TEST_TIME 113 9207
TEST_TIME 114 9150
TEST_TIME 115 9096
TEST_TIME 116 9056
TEST_TIME 117 9072
TEST_TIME 118 9060
TEST_TIME 119 9023
TEST_TIME 120 8954

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-01-2022 13:38:37 to 13-01-2022 13:38:40.

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 1497041 1497041 0 0.00 0 13171 14 0 0
doc_list_biud 1497041 1497041 0 0.00 0 34614 41 0 0
sp_add_invoice_to_stock 5072 4924 148 2.91 25 296996 26836 0 148
sp_cancel_adding_invoice 5414 5414 0 0.00 8 76041 1162 0 0
sp_cancel_client_order 1737 1737 0 0.00 6 25987 431 0 0
sp_cancel_customer_reserve 523942 523942 0 0.00 1 20397 102 0 0
sp_cancel_pay_from_customer 696 696 0 0.00 4 4576 139 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 4 11686 142 0 0
sp_cancel_supplier_invoice 6043 6027 16 0.26 3 34630 1031 0 16
sp_cancel_supplier_order 407 407 0 0.00 8 1511244 167187 0 0
sp_cancel_write_off 9416 9416 0 0.00 2 5812 159 0 0
sp_client_order 20429 20429 0 0.00 6 23425 467 0 0
sp_customer_reserve 463314 463314 0 0.00 1 185095 252 0 0
sp_fill_shopping_cart 31165 31165 0 0.00 1 13280 81 0 0
sp_fill_shopping_cart_clo_res 463295 463295 0 0.00 0 20596 18 0 0
sp_get_clo_for_invoice 4889 4889 0 0.00 0 24675 294 0 0
sp_kill_cost_storno 15933 15933 0 0.00 0 10306 75 0 0
sp_kill_qstorno_ret_qs2qd 1063734 1063734 0 0.00 0 34612 44 0 0
sp_kill_qty_storno 561783 561783 0 0.00 0 34614 101 0 0
sp_lock_selected_doc 20529 20529 0 0.00 0 11671 10 0 0
sp_make_cost_storno 27484 27484 0 0.00 0 13165 79 0 0
sp_multiply_rows_for_pdistr 27484 27484 0 0.00 0 12524 38 0 0
sp_multiply_rows_for_qdistr 20429 20429 0 0.00 0 12978 187 0 0
sp_pay_from_customer 6932 6932 0 0.00 5 16748 181 0 0
sp_pay_to_supplier 5712 5712 0 0.00 7 10822 185 0 0
sp_payment_common 12644 12644 0 0.00 2 12583 46 0 0
sp_qd_handle_on_cancel_clo 1737 1737 0 0.00 0 25972 342 0 0
sp_qd_handle_on_invoice_upd_sts 10302 10302 0 0.00 0 16363 504 0 0
sp_qd_handle_on_reserve_upd_sts 9952 9952 0 0.00 0 12168 64 0 0
sp_reserve_write_off 9952 9952 0 0.00 9 14911 281 0 0
sp_supplier_invoice 5415 5415 0 0.00 9 77633 3321 0 0
sp_supplier_order 5321 5321 0 0.00 8 38279 1396 0 0
srv_aggregate_perf_data 129 129 0 0.00 2336 155228 16915 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 912 166336 40669 0 0
srv_find_qd_qs_mism 1022545 1022545 0 0.00 0 10877 10 0 0
srv_make_invnt_saldo 475 475 0 0.00 43 87130 4111 0 0
srv_make_money_saldo 894 894 0 0.00 2 11341 337 0 0
srv_recalc_idx_stat 4 4 0 0.00 166372 445626 304322 0 0
t$perf-abend:sp_add_invoice_to_stock 148 148 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4924 4924 0 0.00 1 13277 948 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 2 6918 428 0 0
t$perf-norm:sp_cancel_client_order 1737 1737 0 0.00 0 2084 27 0 0
t$perf-norm:sp_cancel_customer_reserve 650 650 0 0.00 0 1481 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 696 696 0 0.00 0 887 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 1205 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 7714 53 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 81871 7184 0 0
t$perf-norm:sp_cancel_write_off 998 998 0 0.00 0 329 16 0 0
t$perf-norm:sp_client_order 20429 20429 0 0.00 0 6148 36 0 0
t$perf-norm:sp_customer_reserve 918 918 0 0.00 0 8867 24 0 0
t$perf-norm:sp_pay_from_customer 6932 6932 0 0.00 0 12879 24 0 0
t$perf-norm:sp_pay_to_supplier 5712 5712 0 0.00 0 6023 20 0 0
t$perf-norm:sp_reserve_write_off 9952 9952 0 0.00 0 4154 21 0 0
t$perf-norm:sp_supplier_invoice 5415 5415 0 0.00 0 2638 29 0 0
t$perf-norm:sp_supplier_order 5321 5321 0 0.00 0 5163 29 0 0
t$perf-norm:srv_aggregate_perf_data 129 129 0 0.00 0 446 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 952 24 0 0
t$perf-norm:srv_make_invnt_saldo 475 475 0 0.00 0 1175 18 0 0
t$perf-norm:srv_make_money_saldo 894 894 0 0.00 0 2144 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 130 55 0 0
v_add_invoice_to_stock 10070 9774 296 2.93 0 5435 25 0 296
v_all_customers 37344 37344 0 0.00 0 90 4 0 0
v_all_suppliers 30102 30102 0 0.00 0 98 4 0 0
v_all_wares 40858 40858 0 0.00 0 115 4 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 2801 22 0 0
v_cancel_client_order 3474 3474 0 0.00 0 4181 13 0 0
v_cancel_customer_prepayment 1392 1392 0 0.00 0 4462 14 0 0
v_cancel_customer_reserve 1300 1300 0 0.00 0 3685 13 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 1282 12 0 0
v_cancel_supplier_invoice 852 820 32 3.75 0 10168 37 0 32
v_cancel_supplier_order 814 814 0 0.00 0 1388 14 0 0
v_cancel_write_off 1996 1996 0 0.00 0 2094 24 0 0
v_max_id_clo_ord 5321 5321 0 0.00 0 934 5 0 0
v_max_id_clo_res 918 918 0 0.00 0 1056 13 0 0
v_max_id_ord_sup 5415 5415 0 0.00 0 8156 9 0 0
v_max_non_paid_invoice 1397 1397 0 0.00 0 366 7 0 0
v_max_non_paid_realizn 3675 3675 0 0.00 0 553 7 0 0
v_min_id_clo_ord 5321 5321 0 0.00 0 4333 9 0 0
v_min_id_clo_res 918 918 0 0.00 0 2715 9 0 0
v_min_id_ord_sup 5415 5415 0 0.00 0 5169 14 0 0
v_min_non_paid_invoice 5712 5712 0 0.00 0 5017 14 0 0
v_min_non_paid_realizn 6932 6932 0 0.00 0 12580 22 0 0
v_our_firm 10028 10028 0 0.00 0 88 4 0 0
v_reserve_write_off 19904 19904 0 0.00 0 3838 11 0 0
x_lock_depdocs_on_canc_invoice 11031 11031 0 0.00 0 16889 122 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 4021 302 0 0
x_make_qstorno_1000_1200 5321 5321 0 0.00 2 38268 1231 0 0
x_make_qstorno_1200_2000 5415 5415 0 0.00 4 77599 3101 0 0
x_make_qstorno_2100_3300 463314 463314 0 0.00 0 185093 222 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-01-2022 13:38:41 to 13-01-2022 13:38:45.

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:15 14 8192000000 8798448560 8809598976 4481168 0.054 2 1 2 0 4581120 64176 35776
11:08:16 27293 8192000000 9699967344 9992421376 778094080 8.674 100 87 174 0 807338928 7406048 21838800
11:09:53 22559 8192000000 9751285424 10054299648 820921872 9.108 100 87 174 1 853521920 11726000 20874048
11:11:28 15525 8192000000 9787426928 10107846656 855886976 9.459 100 73 146 0 896498400 23567456 17043968
11:13:01 8224 8192000000 9890334912 10224521216 925727824 10.153 100 79 158 0 996010896 53341248 16941824
11:14:19 3947 8192000000 9919488848 10264055808 969339968 10.580 100 84 162 1 1036458672 50671136 16447568
11:15:26 2402 8192000000 9979845312 10334912512 1007177872 10.948 100 87 168 0 1091223568 66207248 17838448
11:16:31 2002 8192000000 9984015872 10353283072 1030356800 11.172 100 78 148 2 1119919632 75277152 14285680
11:17:35 1034 8192000000 10012502128 10387222528 1051998976 11.380 100 99 186 1 1128552976 57430880 19123120
11:18:37 8505 8192000000 10035235696 10408480768 1069990208 11.552 100 88 172 2 1144100912 56124016 17986688
11:19:48 902 8192000000 10043862784 10428915712 1080675520 11.654 100 88 173 1 1166636176 68600080 17360576
11:20:54 1211 8192000000 10072225920 10454286336 1087924448 11.723 100 95 185 1 1191094736 83481152 19689136
11:21:56 1031 8192000000 10080939888 10468679680 1096753504 11.807 100 96 188 1 1203150400 87147376 19249520
11:22:58 999 8192000000 10050749696 10440400896 1100810944 11.845 100 87 169 0 1174775568 57472176 16492448
11:24:00 8366 8192000000 10060517952 10457890816 1101090992 11.848 100 63 124 2 1183706160 70278896 12336272
11:25:09 1963 8192000000 10058039248 10453012480 1105720112 11.892 100 69 129 0 1188873952 70760640 12393200
11:26:11 1188 8192000000 10059458576 10450980864 1106094352 11.895 100 97 188 2 1185624608 59699216 19831040
11:27:13 1277 8192000000 10084044528 10473918464 1107035040 11.904 100 90 174 1 1211721728 86044080 18642608
11:28:15 1309 8192000000 10027770288 10414923776 1082837168 11.674 99 89 173 1 1156728656 55718080 18173408
11:29:17 1262 8192000000 10025525072 10409263104 1074179568 11.592 99 85 161 1 1151110064 60405152 16525344
11:30:19 2515 8192000000 10029265248 10424446976 1074152768 11.592 100 62 118 2 1156294032 69325312 12815952
11:31:24 1226 8192000000 9982445984 10357125120 1041929776 11.283 99 87 165 5 1103497392 45668864 15898752
11:32:26 1677 8192000000 9998822192 10374750208 1044504224 11.308 100 85 163 1 1126905360 65574496 16826640
11:33:29 949 8192000000 10007115856 10384482304 1040716736 11.272 100 89 172 1 1126138032 67744832 17676464
11:34:35 845 8192000000 10006072480 10380128256 1040926704 11.274 100 80 146 0 1125433120 69343968 15162448
11:35:36 872 8192000000 9990043856 10362322944 1041051392 11.275 100 95 184 4 1113958208 52169312 20737504
11:36:38 1205 8192000000 9963223904 10334113792 1041456976 11.279 100 84 152 4 1081845840 25053568 15335296
11:37:44 15802 8192000000 9988740704 10372939776 1060005152 11.457 100 52 100 2 1109153648 39006624 10141872
11:39:06 12900 8192000000 10034634544 10417278976 1074329344 11.593 100 71 135 0 1148119488 60904416 12885728
11:40:29 3545 8192000000 10070884944 10464468992 1097338528 11.812 100 82 157 2 1185229856 70614864 17276464
11:41:40 10214 8192000000 10115255568 10499674112 1109891744 11.931 100 87 165 2 1230562128 104511680 16158704
11:42:54 4102 8192000000 10136377296 10537639936 1116778368 11.997 100 62 118 0 1259214112 130066944 12368800
11:44:04 1402 8192000000 10149383936 10563379200 1125801360 12.082 100 92 170 0 1267963712 122464416 19697936
11:45:08 6053 8192000000 10179943328 10577571840 1146062400 12.273 100 86 168 2 1297861600 135386000 16413200
11:46:28 906 8192000000 10236064592 10629877760 1162622816 12.428 100 92 179 1 1350162224 167103616 20435792
11:47:33 3440 8192000000 10255213472 10660110336 1173756128 12.532 100 67 131 0 1371204992 183060240 14388624
11:48:40 1292 8192000000 10297556944 10701713408 1180812416 12.598 100 94 180 1 1408201440 205836656 21552368
11:49:45 1305 8192000000 10341841680 10748776448 1194697376 12.727 100 95 176 1 1454530560 241124240 18708944
11:50:52 1456 8192000000 10375868784 10786394112 1204233424 12.816 100 86 167 2 1478871568 254964528 19673616
11:51:57 1031 8192000000 10399223184 10820177920 1211137488 12.880 100 90 173 0 1508827728 277699120 19991120
11:53:00 971 8192000000 10434024224 10874425344 1214317408 12.909 100 98 189 0 1545834752 307685888 23831456
11:54:04 1055 8192000000 10450533568 10856206336 1211103648 12.879 100 94 182 0 1557724784 324924960 21696176
11:55:08 1298 8192000000 10442305568 10872971264 1196037104 12.740 100 95 182 0 1551531488 332557056 22937328
11:56:15 1422 8192000000 10487238560 10905706496 1201483936 12.790 100 89 170 2 1597456672 375876640 20096096
11:57:21 1281 8192000000 10506211504 10927738880 1199748304 12.774 100 98 195 0 1614273376 391553792 22971280
11:58:24 1053 8192000000 10529164720 10953084928 1188724288 12.671 100 95 190 0 1630680944 419557216 22399440
11:59:27 903 8192000000 10565526336 10968260608 1196427904 12.743 100 96 188 0 1670197808 450725104 23044800
12:00:30 1258 8192000000 10554836912 11032539136 1195330032 12.733 100 87 170 1 1661193584 444106896 21756656
12:01:34 5285 8192000000 10514027120 10954772480 1179495568 12.585 100 61 122 1 1622053232 427127552 15430112
12:02:49 5976 8192000000 10530188752 10976948224 1170047296 12.497 99 49 96 0 1633486768 450967504 12471968
12:03:59 11381 8192000000 10506572768 10984603648 1171634176 12.512 100 49 97 0 1605607616 422076672 11896768
12:05:32 14044 8192000000 10496579840 10994974720 1147845424 12.289 100 43 86 1 1628847888 469047952 11954512
12:06:51 37369 8192000000 10330869696 10855583744 1158160112 12.386 100 27 54 1 1491192576 325712064 7320400
12:08:30 51944 8192000000 10265187776 10783891456 1167925952 12.477 100 22 44 0 1378466928 204729744 5811232
12:10:23 62200 8192000000 10121870768 10619850752 1149747520 12.307 99 15 31 1 1304683440 151486768 3449152
12:12:26 46431 8192000000 10085946352 10530136064 1145954144 12.272 99 14 22 1 1172762752 24256912 2551696
12:14:13 45003 8192000000 10088233008 10531418112 1119648160 12.024 97 42 83 2 1166127648 38232880 8246608
12:16:04 77144 8192000000 10064394272 10504794112 1144677888 12.260 100 43 81 1 1162125616 8670032 8777696
12:18:31 27478 8192000000 10121477248 10540138496 1142146112 12.236 99 75 140 1 1198539248 41571648 14821488
12:20:00 79925 8192000000 10135916288 10564067328 1138769504 12.204 98 29 57 1 1207755472 63356080 5629888
12:22:21 31113 8192000000 10128336928 10564857856 1115896688 11.988 97 21 41 1 1201063504 80549808 4617008
12:23:53 13262 8192000000 10212558464 10643402752 1131927552 12.140 98 59 118 1 1277088800 132731712 12429536
12:25:08 166336 8192000000 10201885504 10681667584 1150190304 12.311 99 19 38 2 1283275760 128693632 4391824
12:28:54 66089 8192000000 10108976816 10669957120 1151206304 12.321 100 15 30 0 1271067840 116618512 3243024
12:31:23 19061 8192000000 10102226208 10546532352 1141003712 12.225 99 58 111 2 1197082240 43095280 12983248
12:32:43 46667 8192000000 10087484432 10522902528 1124715248 12.072 98 36 72 0 1168279472 36446368 7117856
12:34:31 33826 8192000000 10110224208 10575536128 1149010672 12.300 100 29 58 2 1230953888 76199072 5744144
12:36:06 100795 8192000000 10138079296 10591862784 1152977968 12.337 99 25 46 2 1210410976 53137232 4295776
12:38:57 131338 8192000000 10124551024 10572873728 1092941536 11.771 94 16 30 1 1161899712 66464336 2493840
12:42:14 120689 8192000000 10049591600 10482540544 1075485840 11.604 94 26 50 1 1091209056 10815552 4907664
12:45:20 20136 8192000000 10107105456 10540085248 1106228304 11.897 98 43 86 0 1186790016 72272768 8288944
12:46:41 59114 8192000000 10079245136 10529685504 1107700128 11.911 98 18 36 2 1170381136 58785520 3895488
12:48:44 87435 8192000000 10121939824 10580496384 1131439200 12.135 99 23 46 1 1234944704 98615648 4889856
12:51:47 63365 8192000000 10109673616 10589675520 1110573888 11.938 98 23 46 1 1243079472 127097520 5408064
12:53:58 62183 8192000000 10113021120 10551263232 1104185904 11.877 98 23 46 0 1184422912 75117168 5119840
12:56:13 83253 8192000000 10102773808 10584604672 1125841408 12.082 99 16 32 0 1199997776 70002800 4153568
12:58:43 70300 8192000000 10113311664 10609664000 1108346672 11.917 97 26 52 0 1200987472 87320960 5319840
13:00:53 37935 8192000000 10092917456 10519179264 1084156992 11.687 95 36 71 1 1121838800 30235904 7445904
13:02:34 45261 8192000000 10084846288 10523598848 1136922928 12.187 100 35 70 0 1188827360 44685504 7218928
13:04:21 69728 8192000000 10111321808 10590068736 1131677472 12.137 99 36 70 0 1215879056 76726496 7475088
13:06:32 31477 8192000000 10099520016 10566762496 1143716064 12.250 100 29 58 2 1220660528 71247328 5697136
13:08:05 107440 8192000000 10041942560 10485841920 1111205152 11.944 97 31 62 1 1179884688 62367408 6312128
13:10:54 38776 8192000000 10131501056 10580946944 1147617664 12.287 100 51 101 0 1212162000 54527760 10016576
13:12:37 79013 8192000000 10093781824 10576113664 1132325008 12.143 98 12 24 0 1207895488 73169568 2400912
13:15:12 56982 8192000000 10117711536 10569310208 1135429616 12.173 99 14 28 2 1195206016 56470768 3305632
13:17:17 35594 8192000000 10107836400 10571161600 1147449152 12.286 100 23 43 2 1205655264 53658720 4547392
13:19:15 55380 8192000000 10111680416 10544300032 1132109312 12.141 98 30 50 3 1189639216 51423728 6106176
13:21:15 70503 8192000000 10081382800 10560200704 1126019712 12.084 99 13 26 0 1167618672 39212624 2386336
13:23:32 21750 8192000000 10116030960 10563297280 1139482992 12.211 99 49 99 1 1215290720 65497248 10310480
13:25:20 35124 8192000000 10132744928 10561536000 1150932512 12.318 100 38 75 0 1229674752 71163232 7579008
13:26:56 94421 8192000000 10086565296 10593865728 1111572528 11.947 97 25 50 1 1188030064 71560576 4896960
13:30:16 55738 8192000000 10059279520 10524426240 1101737920 11.854 97 9 18 2 1130137632 26744864 1654848
13:32:12 109938 8192000000 10073115904 10551861248 1096950320 11.809 97 9 18 1 1158749136 59689024 2109792
13:35:10 67705 8192000000 10034011760 10499448832 1037970208 11.245 92 9 18 2 1068853136 29144064 1738864

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 13-01-2022 13:38:47 to 13-01-2022 13:39:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 148 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

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

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          218891
MON$OLDEST_ACTIVE               221022
MON$OLDEST_SNAPSHOT             221022
MON$NEXT_TRANSACTION            221022
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                       2064416
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             780
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 ---
:436: 
:437: mscs-test-161.budzdorov.ru    Thu Jan 13 13:37:12 2022
:438:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:439: 
:440: 
:441: mscs-test-161.budzdorov.ru    Thu Jan 13 13:37:13 2022
:442:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:443: 
:444: 
:445: mscs-test-161.budzdorov.ru    Thu Jan 13 13:37:14 2022
:446:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:447: 
:448: 
:449: mscs-test-161.budzdorov.ru    Thu Jan 13 13:37:14 2022
:450:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:451: 
--- end of diff output ---

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

13.01.22 13:39:07. 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-13 13:39:09 No severe PSQL-related problems occured
13.01.22 13:39:11. End of report.