Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.12.2021 13:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16499164 kB
MemAvailable 31389748 kB
Buffers 0 kB
Cached 15226616 kB
SwapCached 1796 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-12-31 13:37:08
DTS_END                         2021-12-31 13:37:08.4270
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/44946
TRN_ID                          233014
ATT_ID                          609
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: 9201.58 23608 1104189
customer order: creation 172.48 397 20698
customer order: refuse 15.37 376 1844
order to supplier: creation 46.43 1483 5572
order to supplier: removal 3.07 132209 368
invoice (draft): creation 46.12 3434 5534
invoice (draft): removal 50.65 1178 6078
invoice accept: apply 42.98 20514 5158
invoice accept: cancel 46.13 942 5535
customer reserve: creation 3954.95 203 474594
customer reserve: removal 4527.50 68 543300
realization accept: apply 84.00 176 10080
realization accept: cancel 83.43 128 10011
payment from customer: creation 58.77 122 7052
payment from customer: removal 6.07 93 728
payment to supplier: creation 48.96 141 5875
payment to supplier: removal 3.38 95 406
service: total inventory turnovers 4.20 3463 504
service: total monetary turnovers 6.09 665 731
service: aggregate perf. data 0.98 14847 117
service: refresh index statistics 0.03 291630 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-12-2021 13:40:10 to 31-12-2021 13:40:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3139
TEST_TIME 2 3910
TEST_TIME 3 5456
TEST_TIME 4 6058
TEST_TIME 5 5807
TEST_TIME 6 5722
TEST_TIME 7 5670
TEST_TIME 8 6018
TEST_TIME 9 6000
TEST_TIME 10 6432
TEST_TIME 11 6387
TEST_TIME 12 6519
TEST_TIME 13 6794
TEST_TIME 14 6813
TEST_TIME 15 7007
TEST_TIME 16 7139
TEST_TIME 17 7445
TEST_TIME 18 7479
TEST_TIME 19 7584
TEST_TIME 20 7745
TEST_TIME 21 7811
TEST_TIME 22 8614
TEST_TIME 23 8905
TEST_TIME 24 9084
TEST_TIME 25 9131
TEST_TIME 26 10452
TEST_TIME 27 10912
TEST_TIME 28 11093
TEST_TIME 29 10897
TEST_TIME 30 10694
TEST_TIME 31 10546
TEST_TIME 32 10425
TEST_TIME 33 10331
TEST_TIME 34 10518
TEST_TIME 35 10305
TEST_TIME 36 10313
TEST_TIME 37 10241
TEST_TIME 38 10145
TEST_TIME 39 10135
TEST_TIME 40 10117
TEST_TIME 41 10064
TEST_TIME 42 10027
TEST_TIME 43 9952
TEST_TIME 44 9897
TEST_TIME 45 9812
TEST_TIME 46 9710
TEST_TIME 47 9768
TEST_TIME 48 9675
TEST_TIME 49 9615
TEST_TIME 50 9581
TEST_TIME 51 9514
TEST_TIME 52 9560
TEST_TIME 53 9511
TEST_TIME 54 9512
TEST_TIME 55 9627
TEST_TIME 56 9682
TEST_TIME 57 9745
TEST_TIME 58 9669
TEST_TIME 59 9678
TEST_TIME 60 9796
TEST_TIME 61 9890
TEST_TIME 62 9931
TEST_TIME 63 9783
TEST_TIME 64 9731
TEST_TIME 65 9688
TEST_TIME 66 9614
TEST_TIME 67 9557
TEST_TIME 68 9507
TEST_TIME 69 9508
TEST_TIME 70 9512
TEST_TIME 71 9549
TEST_TIME 72 9494
TEST_TIME 73 9511
TEST_TIME 74 9514
TEST_TIME 75 9603
TEST_TIME 76 9542
TEST_TIME 77 9646
TEST_TIME 78 9633
TEST_TIME 79 9679
TEST_TIME 80 9607
TEST_TIME 81 9559
TEST_TIME 82 9469
TEST_TIME 83 9448
TEST_TIME 84 9415
TEST_TIME 85 9376
TEST_TIME 86 9349
TEST_TIME 87 9311
TEST_TIME 88 9212
TEST_TIME 89 9165
TEST_TIME 90 9158
TEST_TIME 91 9191
TEST_TIME 92 9204
TEST_TIME 93 9234
TEST_TIME 94 9306
TEST_TIME 95 9281
TEST_TIME 96 9315
TEST_TIME 97 9377
TEST_TIME 98 9356
TEST_TIME 99 9324
TEST_TIME 100 9366
TEST_TIME 101 9344
TEST_TIME 102 9352
TEST_TIME 103 9284
TEST_TIME 104 9263
TEST_TIME 105 9227
TEST_TIME 106 9179
TEST_TIME 107 9131
TEST_TIME 108 9112
TEST_TIME 109 9175
TEST_TIME 110 9146
TEST_TIME 111 9162
TEST_TIME 112 9155
TEST_TIME 113 9147
TEST_TIME 114 9168
TEST_TIME 115 9189
TEST_TIME 116 9169
TEST_TIME 117 9153
TEST_TIME 118 9212
TEST_TIME 119 9263
TEST_TIME 120 9205

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-12-2021 13:40:14 to 31-12-2021 13:40:17.

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 1541965 1541965 0 0.00 0 18304 8 0 0
doc_list_biud 1541965 1541965 0 0.00 0 89671 31 0 0
sp_add_invoice_to_stock 5281 5158 123 2.32 62 329476 20515 0 123
sp_cancel_adding_invoice 5535 5535 0 0.00 7 113982 942 0 0
sp_cancel_client_order 1844 1844 0 0.00 5 13698 376 0 0
sp_cancel_customer_reserve 543300 543300 0 0.00 1 23160 69 0 0
sp_cancel_pay_from_customer 728 728 0 0.00 3 9032 93 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 5097 96 0 0
sp_cancel_supplier_invoice 6089 6078 11 0.18 2 89687 1179 0 11
sp_cancel_supplier_order 368 368 0 0.00 9 1266419 132209 0 0
sp_cancel_write_off 10011 10011 0 0.00 1 13198 128 0 0
sp_client_order 20698 20698 0 0.00 4 18268 398 0 0
sp_customer_reserve 474594 474594 0 0.00 0 98269 204 0 0
sp_fill_shopping_cart 31795 31795 0 0.00 1 45506 45 0 0
sp_fill_shopping_cart_clo_res 474589 474589 0 0.00 0 15027 16 0 0
sp_get_clo_for_invoice 5147 5147 0 0.00 0 20956 339 0 0
sp_kill_cost_storno 16680 16680 0 0.00 0 6767 67 0 0
sp_kill_qstorno_ret_qs2qd 1103057 1103057 0 0.00 0 89668 38 0 0
sp_kill_qty_storno 582363 582363 0 0.00 0 89671 82 0 0
sp_lock_selected_doc 21118 21118 0 0.00 0 9021 5 0 0
sp_make_cost_storno 28154 28154 0 0.00 0 16200 61 0 0
sp_multiply_rows_for_pdistr 28154 28154 0 0.00 0 8606 32 0 0
sp_multiply_rows_for_qdistr 20698 20698 0 0.00 0 14235 207 0 0
sp_pay_from_customer 7052 7052 0 0.00 4 16209 123 0 0
sp_pay_to_supplier 5875 5875 0 0.00 4 16031 142 0 0
sp_payment_common 12927 12927 0 0.00 1 10313 23 0 0
sp_qd_handle_on_cancel_clo 1844 1844 0 0.00 0 13688 341 0 0
sp_qd_handle_on_invoice_upd_sts 10682 10682 0 0.00 0 36393 376 0 0
sp_qd_handle_on_reserve_upd_sts 10080 10080 0 0.00 0 16463 54 0 0
sp_reserve_write_off 10080 10080 0 0.00 7 16482 177 0 0
sp_supplier_invoice 5534 5534 0 0.00 6 95889 3435 0 0
sp_supplier_order 5572 5572 0 0.00 6 27941 1484 0 0
srv_aggregate_perf_data 117 117 0 0.00 2841 156720 14847 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1765 143889 43440 0 0
srv_find_qd_qs_mism 1055140 1055140 0 0.00 0 18303 7 0 0
srv_make_invnt_saldo 504 504 0 0.00 36 113908 3464 0 0
srv_make_money_saldo 731 731 0 0.00 1 14755 665 0 0
srv_recalc_idx_stat 4 4 0 0.00 178954 389797 291630 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5158 5158 0 0.00 0 19581 159 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 0 3333 96 0 0
t$perf-norm:sp_cancel_client_order 1844 1844 0 0.00 0 1043 9 0 0
t$perf-norm:sp_cancel_customer_reserve 704 704 0 0.00 0 394 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 728 728 0 0.00 0 9253 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 126 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 442 442 0 0.00 0 2286 21 0 0
t$perf-norm:sp_cancel_supplier_order 368 368 0 0.00 0 27650 1736 0 0
t$perf-norm:sp_cancel_write_off 974 974 0 0.00 0 944 6 0 0
t$perf-norm:sp_client_order 20698 20698 0 0.00 0 15892 20 0 0
t$perf-norm:sp_customer_reserve 960 960 0 0.00 0 855 5 0 0
t$perf-norm:sp_pay_from_customer 7052 7052 0 0.00 0 6340 11 0 0
t$perf-norm:sp_pay_to_supplier 5875 5875 0 0.00 0 10117 11 0 0
t$perf-norm:sp_reserve_write_off 10080 10080 0 0.00 0 6950 9 0 0
t$perf-norm:sp_supplier_invoice 5534 5534 0 0.00 0 12304 16 0 0
t$perf-norm:sp_supplier_order 5572 5572 0 0.00 0 16742 21 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 1109 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 123 4 0 0
t$perf-norm:srv_make_invnt_saldo 504 504 0 0.00 0 3032 12 0 0
t$perf-norm:srv_make_money_saldo 731 731 0 0.00 0 3341 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 10540 10294 246 2.33 0 9609 18 0 246
v_all_customers 37236 37236 0 0.00 0 28 1 0 0
v_all_suppliers 30468 30468 0 0.00 0 15 1 0 0
v_all_wares 41396 41396 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 1121 6 0 0
v_cancel_client_order 3688 3688 0 0.00 0 6100 7 0 0
v_cancel_customer_prepayment 1456 1456 0 0.00 0 1440 6 0 0
v_cancel_customer_reserve 1408 1408 0 0.00 0 2976 8 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 1674 11 0 0
v_cancel_supplier_invoice 906 884 22 2.42 0 2156 20 0 22
v_cancel_supplier_order 736 736 0 0.00 0 6336 14 0 0
v_cancel_write_off 1948 1948 0 0.00 0 5068 15 0 0
v_max_id_clo_ord 5572 5572 0 0.00 0 1593 2 0 0
v_max_id_clo_res 960 960 0 0.00 0 4092 21 0 0
v_max_id_ord_sup 5524 5524 0 0.00 0 5406 7 0 0
v_max_non_paid_invoice 1737 1737 0 0.00 0 286 2 0 0
v_max_non_paid_realizn 3947 3947 0 0.00 0 1650 3 0 0
v_min_id_clo_ord 5572 5572 0 0.00 0 2219 7 0 0
v_min_id_clo_res 960 960 0 0.00 0 3004 15 0 0
v_min_id_ord_sup 5524 5524 0 0.00 0 7802 9 0 0
v_min_non_paid_invoice 5875 5875 0 0.00 0 7389 15 0 0
v_min_non_paid_realizn 7052 7052 0 0.00 0 7303 16 0 0
v_our_firm 10370 10370 0 0.00 0 18 1 0 0
v_reserve_write_off 20158 20158 0 0.00 0 3999 6 0 0
x_lock_depdocs_on_canc_invoice 11171 11171 0 0.00 0 30915 130 0 0
x_lock_depdocs_on_canc_sup_ord 368 368 0 0.00 0 17947 398 0 0
x_make_qstorno_1000_1200 5572 5572 0 0.00 2 27933 1397 0 0
x_make_qstorno_1200_2000 5534 5534 0 0.00 2 90463 3268 0 0
x_make_qstorno_2100_3300 474594 474594 0 0.00 0 98266 185 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-12-2021 13:40:18 to 31-12-2021 13:40:22.

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:12 3 8192000000 8799338256 8810844160 5349552 0.065 2 1 2 0 5447168 61840 35776
11:08:13 24318 8192000000 9722399936 10019594240 802713744 8.924 100 88 176 0 831675584 7777728 21184112
11:09:48 11878 8192000000 9762897120 10067931136 834642912 9.246 100 93 186 0 869486240 13047376 21795952
11:11:05 22729 8192000000 9796526192 10111750144 864383168 9.544 100 89 178 0 904870400 19742656 20744576
11:12:35 21552 8192000000 9845435264 10172407808 895105216 9.850 100 82 164 0 956444352 43293792 18045344
11:14:02 6926 8192000000 9907955328 10245386240 934124672 10.235 100 85 169 1 1025042544 73176864 17741008
11:15:13 6728 8192000000 9936834112 10293338112 976085136 10.646 100 68 136 0 1052836880 62809712 13942032
11:16:24 2610 8192000000 9986221936 10346323968 1013177680 11.006 100 85 169 0 1097253040 66869520 17205840
11:17:28 1693 8192000000 10025837824 10390974464 1037304608 11.239 100 93 188 2 1142738432 86527232 18906592
11:18:31 5915 8192000000 10025349664 10417012736 1066366112 11.517 100 73 146 0 1150309056 69270288 14672656
11:19:42 4392 8192000000 10024659920 10421686272 1072457856 11.576 100 44 88 0 1142930496 61661872 8810768
11:20:49 7949 8192000000 9987498112 10388361216 1078936624 11.637 100 35 70 0 1115669520 29214944 7517952
11:21:59 5686 8192000000 10016058528 10412036096 1087470608 11.719 100 41 82 1 1133172768 37242128 8460032
11:23:05 20169 8192000000 10009236944 10400382976 1089390768 11.737 100 54 108 0 1116664688 14080096 13193824
11:24:25 6805 8192000000 10014235216 10414301184 1094204464 11.783 100 45 91 0 1128053584 23641056 10208064
11:25:33 5518 8192000000 10008299024 10405367808 1098998832 11.828 100 34 68 0 1115660144 7890800 8770512
11:26:39 53882 8192000000 9999158464 10404384768 1075760000 11.607 98 24 48 0 1107854816 25567568 6527248
11:28:33 4194 8192000000 9990519472 10385195008 1066473920 11.518 99 39 78 2 1089679360 14546704 8658736
11:29:38 5616 8192000000 9961574464 10347274240 1007810464 10.954 95 31 63 1 1043255344 29030752 6414128
11:30:45 4398 8192000000 9960820768 10332721152 1026825232 11.138 100 55 110 2 1065733248 25859632 13048384
11:31:50 32155 8192000000 9930452544 10327625728 1009234704 10.968 98 37 76 2 1039890448 23467536 7188208
11:33:24 6624 8192000000 9960434896 10335866880 1034064496 11.208 100 40 80 1 1064708672 22096384 8547792
11:34:32 6984 8192000000 9947876496 10326204416 1037401344 11.240 99 22 45 1 1056045904 14122944 4521616
11:35:40 9386 8192000000 9982596896 10394963968 1057135840 11.429 100 29 58 2 1084262832 20907392 6219600
11:36:50 82769 8192000000 9999170320 10395619328 1069263120 11.545 100 26 52 0 1103193104 28530528 5399456
11:39:21 6810 8192000000 10074819328 10465116160 1096928864 11.808 100 56 113 1 1176806912 68461312 11416736
11:40:32 12387 8192000000 10122690128 10523566080 1113686512 11.967 100 58 114 1 1239834864 114689616 11458736
11:41:59 12497 8192000000 10121106672 10524930048 1126175968 12.085 100 61 121 1 1228125312 88991184 12958160
11:43:15 11897 8192000000 10165728208 10571067392 1138689344 12.203 100 59 118 1 1277194064 124934864 13569856
11:44:32 21274 8192000000 10222779504 10633154560 1148818528 12.298 100 54 106 1 1326458608 166539872 11100208
11:45:57 3767 8192000000 10237806048 10646417408 1156728160 12.373 100 67 126 1 1342561776 171818656 14014960
11:47:07 5219 8192000000 10257055856 10665992192 1162982320 12.431 100 61 121 1 1368879088 192352992 13543776
11:48:18 1765 8192000000 10262046512 10677354496 1158887200 12.393 99 63 125 2 1366577184 194056016 13633968
11:49:25 4307 8192000000 10335214832 10739982336 1175312784 12.546 100 71 140 0 1434107312 243500448 15294080
11:50:35 5215 8192000000 10363016992 10774228992 1177167248 12.564 100 79 157 0 1471266640 275797568 18301824
11:51:47 2888 8192000000 10390607280 10792628224 1157219296 12.377 98 90 179 2 1481282144 302572816 21490032
11:52:56 3899 8192000000 10417840464 10815217664 1174097920 12.535 99 84 168 3 1504891872 311274256 19519696
11:54:08 3141 8192000000 10439000384 10871693312 1180638496 12.596 100 83 167 0 1538084288 337861824 19583968
11:55:17 2910 8192000000 10437455088 10844766208 1170309856 12.500 100 82 161 1 1536062400 346111104 19641440
11:56:24 1811 8192000000 10489857696 10905235456 1177288960 12.565 99 85 171 0 1586277200 389040224 19948016
11:57:32 3723 8192000000 10489531664 10939781120 1184601584 12.633 100 50 98 0 1592420256 394950784 12867888
11:58:43 7360 8192000000 10525702736 10948759552 1182341856 12.612 100 55 110 1 1625907520 428948816 14616848
11:59:56 7111 8192000000 10432249136 10878087168 1170276496 12.499 100 43 86 0 1537910848 356352848 11281504
12:01:06 12271 8192000000 10372872064 10833620992 1150048608 12.310 100 33 66 1 1504979216 346132160 8798448
12:02:42 22158 8192000000 10372554320 10844618752 1135087712 12.169 100 38 76 0 1504607056 359500288 10019056
12:04:14 32566 8192000000 10109287216 10607194112 1095906176 11.799 98 12 23 1 1197254704 98949248 2399280
12:06:01 40952 8192000000 10053827888 10496364544 1109348976 11.926 99 24 47 2 1139827920 25593632 4885312
12:07:45 59483 8192000000 10137790208 10577788928 1112564368 11.957 98 31 61 1 1224112624 105396464 6151792
12:10:59 102307 8192000000 10040641760 10474262528 1106167088 11.896 98 21 42 0 1146069088 35866880 4035120
12:13:44 25294 8192000000 10062138624 10502139904 1112837136 11.959 98 18 34 1 1158327600 42054400 3436064
12:15:10 25887 8192000000 10119516080 10557874176 1141422032 12.229 100 34 68 1 1204698560 55966592 7309936
12:16:39 57233 8192000000 10126385408 10601218048 1143019824 12.244 99 18 34 2 1227937792 81097984 3819984
12:18:38 143846 8192000000 10094208304 10548555776 1102247200 11.859 96 22 44 2 1187163424 79839648 5076576
12:22:10 98090 8192000000 10126934016 10590519296 1126366192 12.087 98 21 42 0 1184372208 53064800 4941216
12:24:49 25951 8192000000 10062266784 10503438336 1130607968 12.127 99 21 42 0 1165575392 30425168 4542256
12:26:33 34968 8192000000 10075249120 10551513088 1120577232 12.032 98 14 28 0 1175257200 51755344 2924624
12:28:08 25466 8192000000 10104766128 10554691584 1136753872 12.185 100 53 106 0 1196749600 48962752 11032976
12:29:41 52697 8192000000 10128226128 10560143360 1139288064 12.209 99 25 50 1 1212811312 67568624 5954624
12:31:38 71974 8192000000 10123342832 10563043328 1127915728 12.102 98 15 30 0 1259109408 127553408 3640272
12:34:30 36135 8192000000 10158241072 10628354048 1139334224 12.209 99 21 42 0 1225125728 81002880 4788624
12:36:11 46581 8192000000 10180059472 10618257408 1115471808 11.984 97 34 68 0 1242504816 119718336 7314672
12:37:59 63598 8192000000 10099566224 10567712768 1080799488 11.655 96 16 32 2 1176062832 92336768 2926576
12:40:08 74937 8192000000 10053418480 10475278336 1105101968 11.886 98 40 80 3 1143001776 29535392 8364416
12:42:24 61887 8192000000 10032587600 10480336896 1104837728 11.884 98 11 22 0 1136815856 29753360 2224768
12:44:27 61179 8192000000 10070146992 10520625152 1120802032 12.035 99 15 30 1 1172083968 48242304 3039632
12:46:28 26864 8192000000 10080106784 10527137792 1137789136 12.195 100 28 57 0 1180821968 37028080 6004752
12:48:04 73312 8192000000 10072612416 10535669760 1094546672 11.786 97 13 26 0 1145366320 48300256 2519392
12:50:34 71024 8192000000 10110997296 10604920832 1120315504 12.030 99 8 16 0 1212532688 90546464 1670720
12:52:49 81877 8192000000 10134273120 10574639104 1113797712 11.968 98 17 34 0 1154500384 37195264 3507408
12:55:12 47886 8192000000 10069704256 10501705728 1073691872 11.587 94 25 48 0 1101149680 22335184 5122624
12:57:03 87656 8192000000 10063509632 10550378496 1127697264 12.100 100 15 30 0 1186700720 55912000 3091456
12:59:45 26071 8192000000 10052534560 10478718976 1124739056 12.072 99 47 94 1 1157665856 23590496 9336304
13:01:12 55051 8192000000 10064931120 10533064704 1122484496 12.050 99 12 23 1 1160824352 35220080 3119776
13:03:07 50003 8192000000 10058494384 10497093632 1107514288 11.909 98 14 28 0 1149395712 38991040 2890384
13:05:02 52665 8192000000 10090042064 10532794368 1133282416 12.152 100 12 24 0 1182021392 46098032 2640944
13:06:55 87899 8192000000 10141151888 10611834880 1089724096 11.740 95 11 22 0 1182373648 90219584 2429968
13:09:24 45953 8192000000 10136113184 10573484032 1135195424 12.170 99 30 59 0 1223934576 82507296 6231856
13:11:10 86233 8192000000 10120367376 10556084224 1139868656 12.214 99 12 24 1 1182226368 39902512 2455200
13:13:42 86480 8192000000 10107673632 10615443456 1113615584 11.967 96 14 28 0 1198270688 81688880 2966224
13:16:09 34624 8192000000 10127643984 10554712064 1141206160 12.227 100 36 72 0 1221163440 72309840 7647440
13:17:47 31092 8192000000 10083034112 10503741440 1077385600 11.623 95 37 74 1 1135421328 50479728 7556000
13:19:22 77445 8192000000 10117015552 10572988416 1133885056 12.158 100 45 90 1 1218699248 76267584 8546608
13:21:41 48301 8192000000 10061486928 10526461952 1115452800 11.984 98 14 28 1 1150988976 32873760 2662416
13:24:00 66563 8192000000 10083958976 10544590848 1105063504 11.886 98 8 16 0 1161793344 54890032 1839808
13:26:08 64844 8192000000 10059830608 10537201664 1095114512 11.791 98 9 18 0 1178741152 81506176 2120464
13:28:13 48372 8192000000 10069561456 10542505984 1102043632 11.857 98 32 62 1 1155831744 46602160 7185952
13:30:19 37598 8192000000 10094674992 10529624064 1116954816 11.998 99 31 62 1 1185872560 62653392 6264352
13:32:08 109659 8192000000 10078800960 10530660352 1105981696 11.894 98 25 50 4 1142431984 31536224 4914064
13:35:07 46900 8192000000 10125289328 10630045696 1130381776 12.125 100 17 34 0 1238720976 104748736 3590464
13:37:05 46348 8192000000 9554684544 9952972800 655507328 7.408 59 2 4 0 682863520 26980128 376064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-12-2021 13:40:24 to 31-12-2021 13:40:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-12-2021 13:40:40 to 31-12-2021 13:40:42.

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          233309
MON$OLDEST_ACTIVE               233310
MON$OLDEST_SNAPSHOT             233310
MON$NEXT_TRANSACTION            233310
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                       2042848
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             798
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2697 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2697 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2697 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2697 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 ---
:200: 
:201: mscs-test-161.budzdorov.ru    Fri Dec 31 13:38:21 2021
:202:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:203: 
:204: 
:205: mscs-test-161.budzdorov.ru    Fri Dec 31 13:38:24 2021
:206:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:207: 
:208: 
:209: mscs-test-161.budzdorov.ru    Fri Dec 31 13:38:30 2021
:210:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:211: 
:212: 
:213: mscs-test-161.budzdorov.ru    Fri Dec 31 13:38:30 2021
:214:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:215: 
--- end of diff output ---

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

31.12.21 13:40:43. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-12-31 13:40:45 No severe PSQL-related problems occured
31.12.21 13:40:47. End of report.