Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.11.2021 19: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 16654700 kB
MemAvailable 31518852 kB
Buffers 0 kB
Cached 14995712 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-11-27 19:37:56
DTS_END                         2021-11-27 19:37:56.5560
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(57:17)
IP                              ::1/38890
TRN_ID                          250272
ATT_ID                          743
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: 9495.13 16523 1139416
customer order: creation 192.83 329 23139
customer order: refuse 15.49 325 1859
order to supplier: creation 51.04 1374 6125
order to supplier: removal 3.64 91489 437
invoice (draft): creation 50.21 3449 6025
invoice (draft): removal 53.43 774 6412
invoice accept: apply 44.01 15582 5281
invoice accept: cancel 47.21 787 5665
customer reserve: creation 4073.78 153 488854
customer reserve: removal 4653.65 57 558438
realization accept: apply 89.98 169 10798
realization accept: cancel 77.91 100 9349
payment from customer: creation 65.21 106 7825
payment from customer: removal 5.83 63 700
payment to supplier: creation 54.11 120 6493
payment to supplier: removal 3.81 74 457
service: total inventory turnovers 4.82 1089 578
service: total monetary turnovers 7.03 21 844
service: aggregate perf. data 1.11 13157 133
service: refresh index statistics 0.03 201235 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-11-2021 19:38:47 to 27-11-2021 19:38:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4489
TEST_TIME 2 3987
TEST_TIME 3 4451
TEST_TIME 4 4573
TEST_TIME 5 4964
TEST_TIME 6 4980
TEST_TIME 7 5448
TEST_TIME 8 5516
TEST_TIME 9 6213
TEST_TIME 10 6642
TEST_TIME 11 6600
TEST_TIME 12 7039
TEST_TIME 13 6945
TEST_TIME 14 7160
TEST_TIME 15 6944
TEST_TIME 16 7088
TEST_TIME 17 7278
TEST_TIME 18 7431
TEST_TIME 19 7664
TEST_TIME 20 7678
TEST_TIME 21 7784
TEST_TIME 22 8014
TEST_TIME 23 8278
TEST_TIME 24 8725
TEST_TIME 25 8696
TEST_TIME 26 9378
TEST_TIME 27 9372
TEST_TIME 28 9768
TEST_TIME 29 10001
TEST_TIME 30 10146
TEST_TIME 31 10120
TEST_TIME 32 10067
TEST_TIME 33 10106
TEST_TIME 34 10083
TEST_TIME 35 10093
TEST_TIME 36 10059
TEST_TIME 37 10039
TEST_TIME 38 10089
TEST_TIME 39 10098
TEST_TIME 40 10125
TEST_TIME 41 10134
TEST_TIME 42 10141
TEST_TIME 43 10077
TEST_TIME 44 9884
TEST_TIME 45 9854
TEST_TIME 46 9815
TEST_TIME 47 9685
TEST_TIME 48 9705
TEST_TIME 49 9636
TEST_TIME 50 9712
TEST_TIME 51 9802
TEST_TIME 52 9757
TEST_TIME 53 9727
TEST_TIME 54 9636
TEST_TIME 55 9553
TEST_TIME 56 9491
TEST_TIME 57 9405
TEST_TIME 58 9448
TEST_TIME 59 9481
TEST_TIME 60 9487
TEST_TIME 61 9442
TEST_TIME 62 9515
TEST_TIME 63 9601
TEST_TIME 64 9715
TEST_TIME 65 9731
TEST_TIME 66 9688
TEST_TIME 67 9761
TEST_TIME 68 9766
TEST_TIME 69 9791
TEST_TIME 70 9778
TEST_TIME 71 9768
TEST_TIME 72 9782
TEST_TIME 73 9852
TEST_TIME 74 9871
TEST_TIME 75 9747
TEST_TIME 76 9672
TEST_TIME 77 9625
TEST_TIME 78 9615
TEST_TIME 79 9557
TEST_TIME 80 9570
TEST_TIME 81 9559
TEST_TIME 82 9598
TEST_TIME 83 9524
TEST_TIME 84 9504
TEST_TIME 85 9458
TEST_TIME 86 9439
TEST_TIME 87 9411
TEST_TIME 88 9397
TEST_TIME 89 9449
TEST_TIME 90 9439
TEST_TIME 91 9454
TEST_TIME 92 9504
TEST_TIME 93 9476
TEST_TIME 94 9550
TEST_TIME 95 9554
TEST_TIME 96 9603
TEST_TIME 97 9592
TEST_TIME 98 9629
TEST_TIME 99 9638
TEST_TIME 100 9622
TEST_TIME 101 9660
TEST_TIME 102 9687
TEST_TIME 103 9606
TEST_TIME 104 9583
TEST_TIME 105 9563
TEST_TIME 106 9549
TEST_TIME 107 9502
TEST_TIME 108 9487
TEST_TIME 109 9472
TEST_TIME 110 9447
TEST_TIME 111 9437
TEST_TIME 112 9443
TEST_TIME 113 9423
TEST_TIME 114 9449
TEST_TIME 115 9406
TEST_TIME 116 9419
TEST_TIME 117 9495
TEST_TIME 118 9536
TEST_TIME 119 9529
TEST_TIME 120 9497

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-11-2021 19:38:51 to 27-11-2021 19:38:54.

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 1591832 1591832 0 0.00 0 23769 6 0 0
doc_list_biud 1591832 1591832 0 0.00 0 40927 26 0 0
sp_add_invoice_to_stock 5385 5281 104 1.93 32 154400 15582 0 104
sp_cancel_adding_invoice 5665 5665 0 0.00 4 68382 788 0 0
sp_cancel_client_order 1859 1859 0 0.00 3 11372 325 0 0
sp_cancel_customer_reserve 558438 558438 0 0.00 1 22466 57 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 2 3742 64 0 0
sp_cancel_pay_to_supplier 457 457 0 0.00 3 6490 74 0 0
sp_cancel_supplier_invoice 6416 6412 4 0.06 2 15460 775 0 4
sp_cancel_supplier_order 437 437 0 0.00 5 863801 91489 0 0
sp_cancel_write_off 9349 9349 0 0.00 1 10247 100 0 0
sp_client_order 23139 23139 0 0.00 3 34689 330 0 0
sp_customer_reserve 488854 488854 0 0.00 0 60734 154 0 0
sp_fill_shopping_cart 35264 35264 0 0.00 1 15467 36 0 0
sp_fill_shopping_cart_clo_res 488842 488842 0 0.00 0 12333 11 0 0
sp_get_clo_for_invoice 5264 5264 0 0.00 0 23650 268 0 0
sp_kill_cost_storno 16171 16171 0 0.00 0 11948 49 0 0
sp_kill_qstorno_ret_qs2qd 1133074 1133074 0 0.00 0 22447 31 0 0
sp_kill_qty_storno 598221 598221 0 0.00 0 40927 67 0 0
sp_lock_selected_doc 22220 22220 0 0.00 0 7114 4 0 0
sp_make_cost_storno 30379 30379 0 0.00 0 9935 47 0 0
sp_multiply_rows_for_pdistr 30379 30379 0 0.00 0 23408 26 0 0
sp_multiply_rows_for_qdistr 23139 23139 0 0.00 0 23336 148 0 0
sp_pay_from_customer 7825 7825 0 0.00 4 14947 106 0 0
sp_pay_to_supplier 6493 6493 0 0.00 5 23775 120 0 0
sp_payment_common 14318 14318 0 0.00 1 8138 22 0 0
sp_qd_handle_on_cancel_clo 1859 1859 0 0.00 0 11367 277 0 0
sp_qd_handle_on_invoice_upd_sts 10928 10928 0 0.00 0 40926 309 0 0
sp_qd_handle_on_reserve_upd_sts 10798 10798 0 0.00 0 39187 64 0 0
sp_reserve_write_off 10798 10798 0 0.00 6 39196 169 0 0
sp_supplier_invoice 6025 6025 0 0.00 6 84379 3449 0 0
sp_supplier_order 6125 6125 0 0.00 5 23128 1375 0 0
srv_aggregate_perf_data 133 133 0 0.00 2110 123674 13157 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 3920 127013 44845 0 0
srv_find_qd_qs_mism 1087302 1087302 0 0.00 0 9624 5 0 0
srv_make_invnt_saldo 578 578 0 0.00 31 109093 1089 0 0
srv_make_money_saldo 844 844 0 0.00 0 2442 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 132374 322508 201235 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 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 5281 5281 0 0.00 0 10006 142 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 3567 95 0 0
t$perf-norm:sp_cancel_client_order 1859 1859 0 0.00 0 5036 14 0 0
t$perf-norm:sp_cancel_customer_reserve 751 751 0 0.00 0 1078 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 1097 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 457 457 0 0.00 0 1676 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 473 473 0 0.00 0 1358 14 0 0
t$perf-norm:sp_cancel_supplier_order 437 437 0 0.00 0 19511 1485 0 0
t$perf-norm:sp_cancel_write_off 1054 1054 0 0.00 0 1321 7 0 0
t$perf-norm:sp_client_order 23139 23139 0 0.00 0 12862 19 0 0
t$perf-norm:sp_customer_reserve 1130 1130 0 0.00 0 2478 9 0 0
t$perf-norm:sp_pay_from_customer 7825 7825 0 0.00 0 7006 12 0 0
t$perf-norm:sp_pay_to_supplier 6493 6493 0 0.00 0 6967 11 0 0
t$perf-norm:sp_reserve_write_off 10798 10798 0 0.00 0 12809 13 0 0
t$perf-norm:sp_supplier_invoice 6025 6025 0 0.00 0 12772 22 0 0
t$perf-norm:sp_supplier_order 6125 6125 0 0.00 0 6855 16 0 0
t$perf-norm:srv_aggregate_perf_data 133 133 0 0.00 0 202 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 59 2 0 0
t$perf-norm:srv_make_invnt_saldo 578 578 0 0.00 0 1645 18 0 0
t$perf-norm:srv_make_money_saldo 844 844 0 0.00 0 12871 30 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 147 38 0 0
v_add_invoice_to_stock 10734 10526 208 1.93 0 10479 20 0 208
v_all_customers 41878 41878 0 0.00 0 13 0 0 0
v_all_suppliers 33918 33918 0 0.00 0 16 1 0 0
v_all_wares 46278 46278 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 5400 21 0 0
v_cancel_client_order 3718 3718 0 0.00 0 6667 15 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 694 3 0 0
v_cancel_customer_reserve 1502 1502 0 0.00 0 2547 9 0 0
v_cancel_payment_to_supplier 914 914 0 0.00 0 6477 13 0 0
v_cancel_supplier_invoice 954 946 8 0.83 0 2808 21 0 8
v_cancel_supplier_order 874 874 0 0.00 0 7152 15 0 0
v_cancel_write_off 2108 2108 0 0.00 0 2933 17 0 0
v_max_id_clo_ord 6124 6124 0 0.00 0 1534 1 0 0
v_max_id_clo_res 1130 1130 0 0.00 0 4753 19 0 0
v_max_id_ord_sup 6001 6001 0 0.00 0 4223 3 0 0
v_max_non_paid_invoice 1659 1659 0 0.00 0 2079 6 0 0
v_max_non_paid_realizn 4222 4222 0 0.00 0 4129 4 0 0
v_min_id_clo_ord 6124 6124 0 0.00 0 2631 7 0 0
v_min_id_clo_res 1130 1130 0 0.00 0 3918 13 0 0
v_min_id_ord_sup 6001 6001 0 0.00 0 5184 9 0 0
v_min_non_paid_invoice 6493 6493 0 0.00 0 5533 16 0 0
v_min_non_paid_realizn 7825 7825 0 0.00 0 8121 16 0 0
v_our_firm 11606 11606 0 0.00 0 89 1 0 0
v_reserve_write_off 21592 21592 0 0.00 0 6667 6 0 0
x_lock_depdocs_on_canc_invoice 11604 11604 0 0.00 0 15730 105 0 0
x_lock_depdocs_on_canc_sup_ord 437 437 0 0.00 0 10941 343 0 0
x_make_qstorno_1000_1200 6125 6125 0 0.00 2 23118 1302 0 0
x_make_qstorno_1200_2000 6025 6025 0 0.00 3 84371 3309 0 0
x_make_qstorno_2100_3300 488854 488854 0 0.00 0 53875 141 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-11-2021 19:38:55 to 27-11-2021 19:38:59.

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
17:08:00 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:01 35661 8192000000 9903359696 10215497728 980376560 10.688 100 86 172 1 1009335936 6132976 22826400
17:10:48 21156 8192000000 9965182000 10292625408 1046220688 11.324 100 85 170 0 1075808416 8005808 21581920
17:12:15 18487 8192000000 10007005968 10348593152 1092495888 11.766 100 85 170 1 1123066848 9801216 20769744
17:13:37 18587 8192000000 10052173616 10405101568 1139076304 12.207 100 80 160 2 1169062928 11171792 18814832
17:15:01 10696 8192000000 10095094656 10460606464 1185577920 12.642 100 72 144 0 1214232640 12097760 16556960
17:16:14 7030 8192000000 10130176432 10501922816 1217037872 12.934 100 74 148 0 1247052880 13411936 16603072
17:17:24 5574 8192000000 10158501808 10543362048 1250867152 13.246 100 69 138 0 1278774432 11986752 15920528
17:18:31 2071 8192000000 10207815104 10589831168 1293296496 13.634 100 94 187 4 1326733168 12764480 20672192
17:19:35 28093 8192000000 10205397296 10610769920 1308271568 13.770 100 58 116 0 1332270256 10245040 13753648
17:21:18 14668 8192000000 10207007712 10626035712 1319981888 13.877 100 25 50 2 1331252016 5788976 5481152
17:22:34 11700 8192000000 10213364848 10631360512 1325242224 13.924 100 18 36 0 1335092800 5723408 4127168
17:23:46 32650 8192000000 10239494064 10645229568 1330140384 13.968 100 54 107 0 1349156688 5707616 13308688
17:25:19 8904 8192000000 10228057696 10650013696 1337854784 14.038 100 26 52 1 1349844000 5580528 6408688
17:26:30 9123 8192000000 10222654208 10641510400 1315959664 13.840 98 19 37 2 1324715456 4788512 3967280
17:27:43 20007 8192000000 10212178272 10635284480 1308255184 13.770 98 19 36 1 1317912464 5378448 4278832
17:29:32 3209 8192000000 10198999744 10608082944 1289731600 13.602 99 49 99 3 1307383952 7001856 10650496
17:30:38 4999 8192000000 10152543504 10554912768 1249387184 13.233 98 24 48 0 1261047744 6003408 5657152
17:31:44 9711 8192000000 10135583040 10539671552 1218383600 12.947 97 26 52 1 1230989344 5446928 7158816
17:32:54 48138 8192000000 10158631168 10566664192 1267913216 13.403 100 22 43 0 1278213936 5291920 5008800
17:34:47 5186 8192000000 10176717184 10584596480 1282857120 13.539 100 32 64 0 1297066048 6350784 7858144
17:35:53 6704 8192000000 10195201504 10607452160 1297828368 13.675 100 29 58 0 1315084304 10066624 7189312
17:37:01 8029 8192000000 10201015056 10613501952 1307576160 13.764 100 20 40 0 1317534368 5500512 4457696
17:38:10 43813 8192000000 10212010576 10629709824 1322901856 13.903 100 31 61 0 1335524912 5555440 7067616
17:39:56 4802 8192000000 10259106464 10670395392 1352377888 14.169 100 53 104 3 1372606784 8072736 12156160
17:41:05 30469 8192000000 10281918288 10702368768 1378373296 14.402 100 44 88 3 1399295472 10462464 10459712
17:42:39 20301 8192000000 10303334496 10722492416 1390931296 14.514 100 43 86 1 1413105648 11358000 10816352
17:44:06 10178 8192000000 10309273664 10734170112 1395596192 14.556 100 48 96 0 1422602912 15132944 11873776
17:45:21 15604 8192000000 10312173968 10734714880 1396331440 14.562 100 54 106 0 1425205584 15657056 13217088
17:46:43 4047 8192000000 10318665440 10739507200 1380875840 14.424 99 68 134 3 1418105968 20657072 16573056
17:47:54 5119 8192000000 10336553840 10759380992 1414166832 14.721 100 68 136 3 1448928352 18421248 16340272
17:49:03 4984 8192000000 10341484416 10764075008 1412105280 14.703 100 61 119 2 1447437856 19965344 15367232
17:50:12 3920 8192000000 10356000112 10771533824 1411416816 14.697 100 68 136 2 1457001392 28882832 16701744
17:51:22 6322 8192000000 10347223088 10775080960 1419558448 14.769 100 55 110 1 1458837728 25421216 13858064
17:52:33 7871 8192000000 10353150064 10783182848 1419441728 14.768 100 50 100 3 1467101456 34671056 12988672
17:53:47 5375 8192000000 10378571904 10801651712 1428814400 14.851 100 64 126 2 1480296512 35624144 15857968
17:54:57 5412 8192000000 10357554032 10780176384 1402728816 14.619 99 61 122 2 1457993088 39165312 16098960
17:56:07 12685 8192000000 10348359840 10774269952 1404130400 14.632 100 45 90 1 1457010400 40478896 12401104
17:57:27 5126 8192000000 10361172992 10780569600 1405807984 14.647 100 55 110 1 1461160000 40808272 14543744
17:58:37 5020 8192000000 10350271376 10766807040 1394283728 14.544 100 61 121 1 1454834592 44285088 16265776
17:59:47 30588 8192000000 10332625312 10765291520 1383888224 14.451 100 32 64 0 1440370160 47177376 9304560
18:01:21 27527 8192000000 10322681856 10754510848 1363524672 14.269 99 36 72 0 1421441712 47232480 10684560
18:02:53 18890 8192000000 10311008560 10738827264 1352815680 14.173 99 26 50 4 1405203440 44869584 7518176
18:04:13 40226 8192000000 10294770624 10730680320 1334248048 14.006 98 19 36 1 1373832304 34318592 5265664
18:05:59 11735 8192000000 10275457168 10699649024 1340692816 14.064 100 34 68 1 1377036512 27783968 8559728
18:07:14 41795 8192000000 10258637472 10684674048 1298542832 13.682 96 35 70 0 1323404560 16318528 8543200
18:09:46 56685 8192000000 10279655968 10723450880 1373266864 14.356 100 33 67 1 1391198960 10775600 7156496
18:12:00 53684 8192000000 10260831440 10700533760 1323892592 13.912 97 14 28 0 1335151520 8655440 2603488
18:13:58 126982 8192000000 10295891440 10726711296 1347958208 14.129 98 16 33 1 1363056256 11153184 3944864
18:17:09 93966 8192000000 10299127040 10753093632 1377152672 14.391 99 12 25 1 1390368576 10753952 2461952
18:19:49 100730 8192000000 10311423904 10763284480 1407856912 14.665 100 16 32 1 1426945056 14262592 4825552
18:22:44 35798 8192000000 10325998736 10770345984 1384685552 14.458 98 39 78 0 1401527152 7674752 9166848
18:24:21 38665 8192000000 10315670816 10768367616 1398216208 14.579 99 17 34 1 1411273760 9109536 3948016
18:26:02 20130 8192000000 10315468928 10762399744 1383761936 14.450 98 42 81 2 1406708832 11842816 11104080
18:27:28 27643 8192000000 10312534816 10772799488 1403251152 14.624 100 41 81 2 1429064448 16245616 9567680
18:28:58 59596 8192000000 10298244320 10759757824 1388595312 14.493 99 23 47 2 1406355536 12417408 5342816
18:31:00 24184 8192000000 10307109056 10768171008 1402000512 14.613 100 11 23 0 1417325920 13369792 1955616
18:32:25 81718 8192000000 10274651136 10741092352 1345939472 14.111 96 17 34 0 1366005344 15661840 4404032
18:34:47 58219 8192000000 10273478816 10746736640 1351243696 14.159 97 9 18 0 1368358912 14867456 2247760
18:36:53 42107 8192000000 10266119920 10727350272 1342308816 14.078 97 14 28 1 1354011776 8412512 3290448
18:38:36 40337 8192000000 10307536416 10770067456 1399597712 14.591 100 32 64 1 1424905088 17420416 7886960
18:40:23 41669 8192000000 10268028432 10719694848 1314853568 13.830 95 19 38 1 1330000688 11236768 3910352
18:42:05 55075 8192000000 10249793216 10700099584 1259484864 13.325 92 13 26 1 1271922912 9644896 2793152
18:44:02 78268 8192000000 10273189600 10731266048 1307296224 13.762 96 17 34 1 1330937472 19814144 3827104
18:46:21 78470 8192000000 10270521280 10712252416 1331755392 13.983 98 21 40 3 1346804928 10911872 4137664
18:48:39 49541 8192000000 10259275984 10703237120 1295897424 13.658 96 10 20 0 1311421232 13126304 2397504
18:50:33 115168 8192000000 10263498976 10717024256 1347405328 14.124 99 16 32 1 1368275824 17290192 3580304
18:53:52 26795 8192000000 10288234176 10719035392 1363686544 14.270 100 50 100 1 1387487568 12613072 11187952
18:55:47 85416 8192000000 10281555712 10744705024 1374763408 14.370 100 18 36 0 1403387008 24614144 4009456
18:58:14 30076 8192000000 10270398192 10715967488 1351724688 14.163 99 12 26 0 1370436608 15893936 2817984
19:00:01 89993 8192000000 10274440352 10732548096 1364178544 14.275 99 24 48 1 1381241616 11693728 5369344
19:02:42 53263 8192000000 10263733488 10722201600 1354129936 14.185 99 20 40 0 1368033312 9243680 4659696
19:04:42 34666 8192000000 10268793680 10729947136 1360349184 14.240 99 19 37 2 1372166752 7467104 4350464
19:06:17 32564 8192000000 10286682208 10746957824 1387867808 14.487 100 29 58 0 1404405104 9624128 6913168
19:07:55 53169 8192000000 10305086736 10755665920 1389024320 14.497 100 25 50 2 1400980032 6836640 5119072
19:09:52 56500 8192000000 10314860608 10770952192 1389678320 14.503 99 19 38 1 1403772432 9875136 4218976
19:11:48 59621 8192000000 10300975328 10754801664 1370391520 14.331 98 21 42 0 1388207408 12969904 4845984
19:13:48 49990 8192000000 10303794640 10763362304 1396212128 14.561 100 23 44 2 1415063696 14271408 4580160
19:15:38 88392 8192000000 10296857344 10763157504 1349009584 14.139 96 22 44 0 1366556640 12678192 4868864
19:18:10 54881 8192000000 10305171712 10764701696 1360907792 14.246 97 24 48 1 1381129936 14833504 5388640
19:20:08 106095 8192000000 10270167344 10720423936 1332846656 13.993 97 18 36 0 1349236608 12297776 4092176
19:23:10 32798 8192000000 10266521216 10721878016 1352705744 14.172 99 13 26 2 1367928608 12189504 3033360
19:25:02 98148 8192000000 10261009424 10709315584 1296052384 13.659 94 10 21 0 1312202384 13474480 2675520
19:27:49 57389 8192000000 10259859184 10730266624 1357664960 14.216 100 9 18 1 1371447728 11419680 2363088
19:30:06 67422 8192000000 10266870992 10725851136 1296279904 13.661 95 20 40 0 1319320416 18214480 4826032
19:32:20 53685 8192000000 10278671536 10726191104 1335194368 14.014 98 29 58 0 1358202448 15665296 7342784
19:34:20 101296 8192000000 10257645680 10697449472 1291222096 13.615 95 10 20 0 1309083104 15510352 2350656
19:37:01 42211 8192000000 10281606608 10729385984 1356192464 14.203 99 34 68 1 1376460240 12755168 7512608

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-11-2021 19:39:01 to 27-11-2021 19:39:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 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 27-11-2021 19:39:17 to 27-11-2021 19:39:19.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          247113
MON$OLDEST_ACTIVE               250392
MON$OLDEST_SNAPSHOT             250392
MON$NEXT_TRANSACTION            250392
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-03 16:41:55.3470
MON$PAGES                       2038176
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:439: 
:440: mscs-test-161.budzdorov.ru    Sat Nov 27 19:38:01 2021
:441:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:442: 
:443: 
:444: mscs-test-161.budzdorov.ru    Sat Nov 27 19:38:03 2021
:445:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:446: 
:447: 
:448: mscs-test-161.budzdorov.ru    Sat Nov 27 19:38:04 2021
:449:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:450: 
:451: 
:452: mscs-test-161.budzdorov.ru    Sat Nov 27 19:38:04 2021
:453:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:454: 
:455: 
:456: mscs-test-161.budzdorov.ru    Sat Nov 27 19:38:06 2021
:457:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:458: 
--- end of diff output ---

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

27.11.21 19:39:20. There are 3 logs in /var/tmp/logs-oltp30 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-11-27 19:39:22 No severe PSQL-related problems occured
27.11.21 19:39:24. End of report.