Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.12.2021 07:53
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 16363980 kB
MemAvailable 31473272 kB
Buffers 0 kB
Cached 15347588 kB
SwapCached 0 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-17 07:51:56
DTS_END                         2021-12-17 07:51:56.8360
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48080
TRN_ID                          240058
ATT_ID                          701
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: 9151.51 27676 1098181
customer order: creation 170.56 370 20467
customer order: refuse 15.18 332 1821
order to supplier: creation 47.22 1350 5666
order to supplier: removal 3.51 104545 421
invoice (draft): creation 45.95 3433 5514
invoice (draft): removal 49.64 916 5957
invoice accept: apply 43.34 20501 5201
invoice accept: cancel 44.43 919 5332
customer reserve: creation 4007.30 202 480876
customer reserve: removal 4423.59 65 530831
realization accept: apply 85.14 194 10217
realization accept: cancel 83.24 121 9989
payment from customer: creation 60.83 123 7300
payment from customer: removal 6.15 97 738
payment to supplier: creation 50.83 133 6099
payment to supplier: removal 3.24 73 389
service: total inventory turnovers 3.91 3767 469
service: total monetary turnovers 6.48 593 778
service: aggregate perf. data 0.93 16185 112
service: refresh index statistics 0.03 399603 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-12-2021 07:53:42 to 17-12-2021 07:53:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2360
TEST_TIME 2 2979
TEST_TIME 3 4774
TEST_TIME 4 5248
TEST_TIME 5 5750
TEST_TIME 6 5426
TEST_TIME 7 5430
TEST_TIME 8 5501
TEST_TIME 9 6083
TEST_TIME 10 6310
TEST_TIME 11 6324
TEST_TIME 12 6735
TEST_TIME 13 7152
TEST_TIME 14 7691
TEST_TIME 15 7633
TEST_TIME 16 8025
TEST_TIME 17 8043
TEST_TIME 18 8123
TEST_TIME 19 8269
TEST_TIME 20 8521
TEST_TIME 21 8743
TEST_TIME 22 8813
TEST_TIME 23 8936
TEST_TIME 24 8871
TEST_TIME 25 9012
TEST_TIME 26 9274
TEST_TIME 27 9579
TEST_TIME 28 9697
TEST_TIME 29 9423
TEST_TIME 30 9456
TEST_TIME 31 9819
TEST_TIME 32 10924
TEST_TIME 33 11043
TEST_TIME 34 10843
TEST_TIME 35 10676
TEST_TIME 36 10622
TEST_TIME 37 10663
TEST_TIME 38 10679
TEST_TIME 39 10644
TEST_TIME 40 10629
TEST_TIME 41 10502
TEST_TIME 42 10378
TEST_TIME 43 10172
TEST_TIME 44 10172
TEST_TIME 45 10013
TEST_TIME 46 9996
TEST_TIME 47 9961
TEST_TIME 48 9987
TEST_TIME 49 9971
TEST_TIME 50 9931
TEST_TIME 51 9940
TEST_TIME 52 9872
TEST_TIME 53 9810
TEST_TIME 54 9717
TEST_TIME 55 9663
TEST_TIME 56 9539
TEST_TIME 57 9559
TEST_TIME 58 9538
TEST_TIME 59 9458
TEST_TIME 60 9482
TEST_TIME 61 9506
TEST_TIME 62 9468
TEST_TIME 63 9472
TEST_TIME 64 9561
TEST_TIME 65 9531
TEST_TIME 66 9603
TEST_TIME 67 9545
TEST_TIME 68 9548
TEST_TIME 69 9591
TEST_TIME 70 9689
TEST_TIME 71 9684
TEST_TIME 72 9691
TEST_TIME 73 9635
TEST_TIME 74 9527
TEST_TIME 75 9472
TEST_TIME 76 9461
TEST_TIME 77 9368
TEST_TIME 78 9382
TEST_TIME 79 9377
TEST_TIME 80 9341
TEST_TIME 81 9480
TEST_TIME 82 9437
TEST_TIME 83 9421
TEST_TIME 84 9449
TEST_TIME 85 9517
TEST_TIME 86 9522
TEST_TIME 87 9511
TEST_TIME 88 9561
TEST_TIME 89 9564
TEST_TIME 90 9472
TEST_TIME 91 9404
TEST_TIME 92 9386
TEST_TIME 93 9378
TEST_TIME 94 9323
TEST_TIME 95 9288
TEST_TIME 96 9284
TEST_TIME 97 9231
TEST_TIME 98 9191
TEST_TIME 99 9183
TEST_TIME 100 9190
TEST_TIME 101 9214
TEST_TIME 102 9210
TEST_TIME 103 9241
TEST_TIME 104 9275
TEST_TIME 105 9231
TEST_TIME 106 9204
TEST_TIME 107 9224
TEST_TIME 108 9330
TEST_TIME 109 9328
TEST_TIME 110 9338
TEST_TIME 111 9406
TEST_TIME 112 9348
TEST_TIME 113 9278
TEST_TIME 114 9244
TEST_TIME 115 9239
TEST_TIME 116 9196
TEST_TIME 117 9185
TEST_TIME 118 9148
TEST_TIME 119 9151
TEST_TIME 120 9159

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-12-2021 07:53:45 to 17-12-2021 07:53:49.

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 1541784 1541784 0 0.00 0 21862 8 0 0
doc_list_biud 1541779 1541779 0 0.00 0 87576 29 0 0
sp_add_invoice_to_stock 5322 5201 121 2.27 42 186928 20501 0 121
sp_cancel_adding_invoice 5332 5332 0 0.00 6 58268 920 0 0
sp_cancel_client_order 1821 1821 0 0.00 4 10446 332 0 0
sp_cancel_customer_reserve 530831 530831 0 0.00 1 21898 66 0 0
sp_cancel_pay_from_customer 738 738 0 0.00 3 7870 97 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 2 1553 73 0 0
sp_cancel_supplier_invoice 5961 5957 4 0.06 3 87920 917 0 4
sp_cancel_supplier_order 421 421 0 0.00 8 1004680 104546 0 0
sp_cancel_write_off 9989 9989 0 0.00 1 13500 122 0 0
sp_client_order 20467 20467 0 0.00 4 43010 370 0 0
sp_customer_reserve 480876 480876 0 0.00 0 51713 203 0 0
sp_fill_shopping_cart 31636 31636 0 0.00 1 14907 46 0 0
sp_fill_shopping_cart_clo_res 480870 480870 0 0.00 0 18029 15 0 0
sp_get_clo_for_invoice 5192 5192 0 0.00 0 31014 337 0 0
sp_kill_cost_storno 16448 16448 0 0.00 0 7442 58 0 0
sp_kill_qstorno_ret_qs2qd 1078029 1078029 0 0.00 0 87574 35 0 0
sp_kill_qty_storno 569760 569760 0 0.00 0 87574 77 0 0
sp_lock_selected_doc 21316 21316 0 0.00 0 7862 6 0 0
sp_make_cost_storno 28808 28808 0 0.00 0 21851 68 0 0
sp_multiply_rows_for_pdistr 28808 28808 0 0.00 0 7349 33 0 0
sp_multiply_rows_for_qdistr 20467 20467 0 0.00 0 12015 188 0 0
sp_pay_from_customer 7300 7300 0 0.00 4 15986 123 0 0
sp_pay_to_supplier 6099 6099 0 0.00 5 15994 134 0 0
sp_payment_common 13399 13399 0 0.00 1 7915 24 0 0
sp_qd_handle_on_cancel_clo 1821 1821 0 0.00 0 10438 302 0 0
sp_qd_handle_on_invoice_upd_sts 10524 10524 0 0.00 0 22747 369 0 0
sp_qd_handle_on_reserve_upd_sts 10217 10217 0 0.00 0 22999 57 0 0
sp_reserve_write_off 10217 10217 0 0.00 7 23027 194 0 0
sp_supplier_invoice 5514 5514 0 0.00 6 101690 3433 0 0
sp_supplier_order 5666 5666 0 0.00 6 56579 1351 0 0
srv_aggregate_perf_data 112 112 0 0.00 3087 125625 16185 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1208 145092 45210 0 0
srv_find_qd_qs_mism 1048514 1048514 0 0.00 0 18312 7 0 0
srv_make_invnt_saldo 469 469 0 0.00 57 113016 3768 0 0
srv_make_money_saldo 778 778 0 0.00 1 13736 594 0 0
srv_recalc_idx_stat 4 4 0 0.00 257909 479121 399603 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5201 5201 0 0.00 0 12792 169 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 4289 89 0 0
t$perf-norm:sp_cancel_client_order 1821 1821 0 0.00 0 4633 15 0 0
t$perf-norm:sp_cancel_customer_reserve 736 736 0 0.00 0 7483 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 738 738 0 0.00 0 1593 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 2358 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 1254 18 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 22809 1497 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 2655 10 0 0
t$perf-norm:sp_client_order 20467 20467 0 0.00 0 21540 19 0 0
t$perf-norm:sp_customer_reserve 1101 1101 0 0.00 0 1643 8 0 0
t$perf-norm:sp_pay_from_customer 7300 7300 0 0.00 0 20017 12 0 0
t$perf-norm:sp_pay_to_supplier 6099 6099 0 0.00 0 7543 8 0 0
t$perf-norm:sp_reserve_write_off 10217 10217 0 0.00 0 5042 9 0 0
t$perf-norm:sp_supplier_invoice 5514 5514 0 0.00 0 3764 15 0 0
t$perf-norm:sp_supplier_order 5666 5666 0 0.00 0 5137 17 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 350 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 288 9 0 0
t$perf-norm:srv_make_invnt_saldo 469 469 0 0.00 0 767 8 0 0
t$perf-norm:srv_make_money_saldo 778 778 0 0.00 0 2612 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10626 10384 242 2.27 0 7186 19 0 242
v_all_customers 37716 37716 0 0.00 0 18 1 0 0
v_all_suppliers 31034 31034 0 0.00 0 22 1 0 0
v_all_wares 40928 40928 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 2384 13 0 0
v_cancel_client_order 3642 3642 0 0.00 0 2368 8 0 0
v_cancel_customer_prepayment 1476 1476 0 0.00 0 1691 6 0 0
v_cancel_customer_reserve 1472 1472 0 0.00 0 4536 8 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 685 5 0 0
v_cancel_supplier_invoice 840 832 8 0.95 0 8006 23 0 8
v_cancel_supplier_order 842 842 0 0.00 0 5405 17 0 0
v_cancel_write_off 1972 1972 0 0.00 0 4298 17 0 0
v_max_id_clo_ord 5662 5662 0 0.00 0 1527 1 0 0
v_max_id_clo_res 1101 1101 0 0.00 0 4232 14 0 0
v_max_id_ord_sup 5510 5510 0 0.00 0 4929 6 0 0
v_max_non_paid_invoice 1754 1754 0 0.00 0 789 3 0 0
v_max_non_paid_realizn 3796 3796 0 0.00 0 572 3 0 0
v_min_id_clo_ord 5662 5662 0 0.00 0 3054 7 0 0
v_min_id_clo_res 1101 1101 0 0.00 0 8658 23 0 0
v_min_id_ord_sup 5510 5510 0 0.00 0 6230 8 0 0
v_min_non_paid_invoice 6099 6099 0 0.00 0 7912 16 0 0
v_min_non_paid_realizn 7300 7300 0 0.00 0 7115 19 0 0
v_our_firm 10220 10220 0 0.00 0 19 1 0 0
v_reserve_write_off 20434 20434 0 0.00 0 5112 5 0 0
x_lock_depdocs_on_canc_invoice 10873 10873 0 0.00 0 17278 110 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 11817 331 0 0
x_make_qstorno_1000_1200 5666 5666 0 0.00 2 56570 1256 0 0
x_make_qstorno_1200_2000 5514 5514 0 0.00 3 94762 3270 0 0
x_make_qstorno_2100_3300 480876 480876 0 0.00 0 46725 185 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-12-2021 07:53:50 to 17-12-2021 07:53:54.

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:22:00 1134 8192000000 8965994144 9033089024 10849520 0.132 3 1 2 0 10977344 92048 35776
05:23:02 34224 8192000000 9733162352 10036248576 795974928 8.855 100 82 164 1 822973472 7513632 19484912
05:24:41 8552 8192000000 9768919296 10077933568 835741424 9.257 100 88 176 0 869984112 13436016 20806672
05:25:53 18484 8192000000 9810653904 10127798272 871527312 9.615 100 87 174 0 913494912 22016512 19951088
05:27:14 12122 8192000000 9865366256 10194214912 903758928 9.936 100 83 166 0 967382848 45156736 18467184
05:28:32 10830 8192000000 9920926016 10265149440 942163776 10.314 100 84 168 0 1027689184 68010752 17514656
05:29:48 4399 8192000000 9965250992 10317070336 978687360 10.671 100 83 167 1 1064826880 69234400 16905120
05:30:54 2248 8192000000 9978293744 10340433920 1012221680 10.997 100 79 156 1 1078237056 50041328 15974048
05:31:57 1733 8192000000 10041428976 10406219776 1040608544 11.271 100 92 180 4 1141485472 82392992 18483936
05:33:01 1892 8192000000 10032189792 10405900288 1061075184 11.467 100 91 181 1 1135609056 55886624 18647248
05:34:04 8009 8192000000 9976872960 10378272768 1060282048 11.459 100 24 47 1 1093801600 28717056 4802496
05:35:13 3608 8192000000 10000868256 10390781952 1068333808 11.536 100 39 77 3 1094774400 18492720 7947872
05:36:17 6758 8192000000 9991462480 10393833472 1075152624 11.601 100 23 46 0 1099195632 19267264 4775744
05:37:25 27248 8192000000 10017670384 10403983360 1080000400 11.647 100 62 123 3 1110977408 17843136 13133872
05:38:52 5371 8192000000 10011297344 10410491904 1084379456 11.689 100 38 76 2 1108502336 15861072 8261808
05:39:59 6021 8192000000 10022364000 10429550592 1092290352 11.764 100 45 92 1 1120096832 18274336 9532144
05:41:05 6190 8192000000 10025803552 10433118208 1076603456 11.615 98 39 78 0 1108630624 23372592 8654576
05:42:17 45019 8192000000 9995103872 10395734016 1061073264 11.467 98 39 78 2 1090935920 20864272 8998384
05:44:05 6545 8192000000 9959301824 10337488896 1014097856 11.015 97 41 83 0 1038843680 14677344 10068480
05:45:13 11896 8192000000 9931416272 10319900672 968150576 10.569 95 24 46 1 1000436608 27378064 4907968
05:46:29 8163 8192000000 9930211904 10316541952 996239904 10.842 98 33 65 4 1023109456 19874976 6994576
05:47:39 45636 8192000000 9935287328 10331389952 1005049504 10.927 98 28 56 0 1037216208 26564464 5602240
05:49:30 5984 8192000000 9961433456 10337312768 1043370592 11.297 100 52 102 2 1066879824 11681504 11827728
05:50:37 4771 8192000000 9973227616 10360782848 1056948784 11.427 100 45 89 2 1078630688 11817456 9864448
05:51:45 6126 8192000000 9971825232 10371698688 1067556256 11.529 100 35 70 3 1090575024 15514576 7504192
05:52:59 12776 8192000000 10005649456 10397024256 1073655872 11.587 100 57 112 1 1108563392 22788944 12118576
05:54:18 13670 8192000000 10056514656 10451439616 1087248880 11.716 100 55 109 0 1162249744 64245584 10755280
05:55:36 11481 8192000000 10065439104 10463809536 1108912336 11.922 100 48 96 2 1176632048 57089488 10630224
05:56:53 10842 8192000000 10079827552 10484383744 1122163216 12.047 100 37 71 3 1183572384 53320176 8088992
05:58:10 7036 8192000000 10145615616 10564558848 1132412560 12.144 100 40 80 0 1257398896 116626416 8359920
05:59:33 1207 8192000000 10162506176 10558189568 1133800000 12.157 100 68 137 2 1263074560 114481312 14793248
06:00:39 23757 8192000000 10173633136 10585620480 1135712704 12.175 100 52 103 2 1281221088 134431200 11077184
06:02:07 4396 8192000000 10203331168 10613403648 1155575552 12.362 100 64 124 3 1303819104 134835312 13408240
06:03:17 1623 8192000000 10241314000 10642784256 1169189648 12.489 100 83 166 5 1343499552 156633680 17676224
06:04:22 3364 8192000000 10269174336 10671710208 1158533232 12.390 100 76 149 0 1360456944 185878704 16045008
06:05:35 4423 8192000000 10216719152 10620575744 1146715520 12.279 99 74 147 2 1307613440 144796016 16101904
06:06:47 4163 8192000000 10248493632 10657550336 1157509328 12.380 100 57 110 0 1358483888 188991680 11982880
06:07:55 14073 8192000000 10258786240 10684579840 1166406416 12.463 100 32 64 0 1379290592 204970976 7913200
06:09:18 4793 8192000000 10265882224 10687959040 1139578720 12.212 99 58 115 2 1363560176 210478336 13503120
06:10:29 4518 8192000000 10312676528 10713899008 1138701600 12.203 100 75 150 0 1403596960 247477728 17417632
06:11:41 11896 8192000000 10310186320 10724458496 1142330528 12.237 99 41 82 0 1413544896 260676112 10538256
06:12:59 6291 8192000000 10334279776 10741080064 1140198512 12.217 100 46 90 1 1429407424 278445232 10763680
06:14:10 5088 8192000000 10362719520 10771603456 1147021056 12.282 99 58 114 1 1451519200 290945760 13552384
06:15:30 3516 8192000000 10390006608 10790092800 1150904496 12.318 100 84 167 1 1483954560 313345936 19704128
06:16:45 7321 8192000000 10437949680 10879139840 1155031952 12.357 100 56 109 2 1559392160 390781456 13578752
06:18:17 6527 8192000000 10414181232 10854567936 1149047936 12.301 100 46 92 0 1503304768 342937840 11318992
06:19:32 44115 8192000000 10363925488 10798632960 1149189040 12.302 99 29 58 0 1458960176 302751200 7019936
06:22:03 35249 8192000000 10321310720 10812514304 1129401248 12.116 100 18 36 0 1469248752 334339936 5507568
06:23:43 94398 8192000000 10055743696 10497069056 1119531664 12.023 99 16 32 0 1186388224 62823008 4033552
06:26:19 49665 8192000000 10093381616 10507829248 1120336816 12.030 99 32 64 0 1179617328 52540448 6740064
06:28:12 142590 8192000000 10104125248 10592059392 1135614896 12.174 100 5 10 0 1194416016 57929440 871680
06:31:40 68286 8192000000 10068860544 10509090816 1140079696 12.216 99 19 38 0 1166454176 22517920 3856560
06:33:54 64662 8192000000 10127735120 10558398464 1162394432 12.426 100 46 88 0 1200880432 28640800 9845200
06:36:05 38521 8192000000 10099179536 10552279040 1160252720 12.406 100 18 36 1 1200866704 37138240 3475744
06:37:45 24096 8192000000 10126275952 10569740288 1159723088 12.401 100 37 75 0 1235908704 68591536 7594080
06:39:20 22813 8192000000 10139770832 10583293952 1148744272 12.298 99 45 88 2 1236434896 78343216 9347408
06:40:51 62941 8192000000 10088813296 10560102400 1131562336 12.136 99 16 32 0 1208938832 73884064 3492432
06:43:47 52313 8192000000 10058017328 10515488768 1102798992 11.864 96 13 26 1 1134832160 29021824 3011344
06:46:48 111672 8192000000 10089538720 10583375872 1103336656 11.869 96 17 34 1 1174170880 67236336 3597888
06:49:57 76808 8192000000 10072389760 10571821056 1145621712 12.268 100 14 28 0 1193816832 45012912 3182208
06:52:14 37834 8192000000 10086049712 10548486144 1130089296 12.122 98 22 43 1 1178607392 43851584 4666512
06:53:59 9828 8192000000 10073170288 10501455872 1120713392 12.034 98 42 84 3 1158007616 29002256 8291968
06:55:09 35447 8192000000 10088885824 10538582016 1105901920 11.894 98 37 74 1 1183891456 70563456 7426080
06:56:47 53664 8192000000 10026344048 10489659392 1041467920 11.279 93 12 24 0 1122829072 78488400 2872752
06:58:48 12925 8192000000 10084082944 10508185600 1109704992 11.930 100 59 118 0 1172169888 50708768 11756128
07:00:02 55948 8192000000 10095931744 10558029824 1086906880 11.713 97 36 72 0 1167659008 73229792 7522336
07:02:04 78484 8192000000 10080595360 10562437120 1099768208 11.835 98 11 22 0 1135221872 33722400 1731264
07:04:27 99134 8192000000 10065009296 10500493312 1118112768 12.009 99 23 47 0 1148605968 26042208 4450992
07:07:13 66093 8192000000 10120667200 10614362112 1128655952 12.109 99 13 26 0 1191220496 59616848 2947696
07:09:39 79441 8192000000 10111104880 10548383744 1107437392 11.908 97 25 50 3 1201678848 88644016 5597440
07:12:01 57312 8192000000 10086784992 10601721856 1136588032 12.183 100 21 42 1 1243808496 102607744 4612720
07:14:00 38953 8192000000 10099134096 10533302272 1117668208 12.005 98 30 60 0 1166047744 42009456 6370080
07:15:45 91661 8192000000 10114408464 10633183232 1139111936 12.207 99 17 34 1 1240054128 97331680 3610512
07:18:25 25145 8192000000 10116082096 10565169152 1137627696 12.193 99 52 104 0 1221339184 72594848 11116640
07:19:53 132008 8192000000 10085861312 10539311104 1110634096 11.938 97 14 28 0 1151545616 38013088 2898432
07:23:06 59286 8192000000 10091620960 10561679360 1106446576 11.899 97 28 56 1 1153588672 41377616 5764480
07:25:17 67996 8192000000 10062338928 10509619200 1116080752 11.990 99 13 26 0 1159726672 40460672 3185248
07:27:25 64859 8192000000 10076781392 10550403072 1131566896 12.136 100 13 25 2 1197210320 62884528 2758896
07:29:37 69531 8192000000 10060245104 10516381696 1123473728 12.060 99 18 36 1 1165039632 38037808 3528096
07:32:21 65668 8192000000 10108444416 10551554048 1137672352 12.194 99 24 49 1 1199609616 57371952 4565312
07:34:27 62391 8192000000 10064690112 10532876288 1107691680 11.911 97 17 34 0 1160676096 49308192 3676224
07:36:37 31069 8192000000 10116646512 10542460928 1108355328 11.917 97 48 98 0 1164834960 46815312 9664320
07:38:12 80224 8192000000 10131529072 10641678336 1113507824 11.966 98 8 16 0 1207083760 91992928 1583008
07:40:33 42703 8192000000 10094010928 10544513024 1072075792 11.572 95 36 71 1 1114975008 35837392 7061824
07:42:19 79355 8192000000 10055195904 10482372608 1094875856 11.789 98 10 20 0 1145096976 48106160 2114960
07:44:39 145092 8192000000 10054137088 10513383424 1109667840 11.929 99 24 48 0 1170372464 55937456 4767168
07:48:12 64643 8192000000 10063289216 10559598592 1110627072 11.938 99 4 8 0 1150498512 39179200 692240
07:50:18 90606 8192000000 10116585424 10631229440 1122144752 12.047 99 17 33 1 1233856784 107978736 3733296

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-12-2021 07:53:56 to 17-12-2021 07:54:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-12-2021 07:54:11 to 17-12-2021 07:54:13.

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          239960
MON$OLDEST_ACTIVE               241679
MON$OLDEST_SNAPSHOT             241679
MON$NEXT_TRANSACTION            241679
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                       2068128
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             810
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2691 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2691 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2691 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2691 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Dec 17 07:51:58 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Fri Dec 17 07:52:18 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Fri Dec 17 07:52:18 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Fri Dec 17 07:52:18 2021
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Fri Dec 17 07:52:18 2021
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:25: 
:26: 
:27: mscs-test-161.budzdorov.ru    Fri Dec 17 07:52:19 2021
:28:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:29: 
--- end of diff output ---

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

17.12.21 07:54:14. 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-17 07:54:17 No severe PSQL-related problems occured
17.12.21 07:54:18. End of report.