Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.12.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 16222740 kB
MemAvailable 31408112 kB
Buffers 0 kB
Cached 15405676 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.9
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-12-13 19:37:55
DTS_END                         2021-12-13 19:37:55.5930
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/34822
TRN_ID                          239367
ATT_ID                          711
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: 9297.47 21954 1115696
customer order: creation 176.12 347 21134
customer order: refuse 14.35 344 1722
order to supplier: creation 47.74 1303 5729
order to supplier: removal 3.76 105669 451
invoice (draft): creation 45.78 3250 5493
invoice (draft): removal 54.89 888 6587
invoice accept: apply 41.93 16676 5032
invoice accept: cancel 49.20 817 5904
customer reserve: creation 3830.60 167 459672
customer reserve: removal 4734.13 65 568096
realization accept: apply 83.42 171 10010
realization accept: cancel 84.26 115 10111
payment from customer: creation 60.02 130 7202
payment from customer: removal 6.02 73 722
payment to supplier: creation 49.95 125 5994
payment to supplier: removal 3.11 74 373
service: total inventory turnovers 4.88 1161 585
service: total monetary turnovers 6.45 69 774
service: aggregate perf. data 0.84 17047 101
service: refresh index statistics 0.03 290591 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-12-2021 19:39:11 to 13-12-2021 19:39:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3139
TEST_TIME 2 3160
TEST_TIME 3 3908
TEST_TIME 4 4422
TEST_TIME 5 3840
TEST_TIME 6 3565
TEST_TIME 7 3848
TEST_TIME 8 4129
TEST_TIME 9 4398
TEST_TIME 10 4809
TEST_TIME 11 5024
TEST_TIME 12 5060
TEST_TIME 13 5456
TEST_TIME 14 5848
TEST_TIME 15 6252
TEST_TIME 16 6427
TEST_TIME 17 6635
TEST_TIME 18 6580
TEST_TIME 19 6672
TEST_TIME 20 7226
TEST_TIME 21 7480
TEST_TIME 22 7885
TEST_TIME 23 8001
TEST_TIME 24 8224
TEST_TIME 25 8348
TEST_TIME 26 8489
TEST_TIME 27 8934
TEST_TIME 28 8945
TEST_TIME 29 9410
TEST_TIME 30 10978
TEST_TIME 31 11004
TEST_TIME 32 11019
TEST_TIME 33 11336
TEST_TIME 34 11108
TEST_TIME 35 11058
TEST_TIME 36 10951
TEST_TIME 37 10810
TEST_TIME 38 10646
TEST_TIME 39 10625
TEST_TIME 40 10602
TEST_TIME 41 10638
TEST_TIME 42 10492
TEST_TIME 43 10429
TEST_TIME 44 10717
TEST_TIME 45 10608
TEST_TIME 46 10529
TEST_TIME 47 10562
TEST_TIME 48 10493
TEST_TIME 49 10444
TEST_TIME 50 10486
TEST_TIME 51 10378
TEST_TIME 52 10214
TEST_TIME 53 10071
TEST_TIME 54 10071
TEST_TIME 55 10044
TEST_TIME 56 10000
TEST_TIME 57 9987
TEST_TIME 58 9914
TEST_TIME 59 9881
TEST_TIME 60 9844
TEST_TIME 61 9859
TEST_TIME 62 9858
TEST_TIME 63 9790
TEST_TIME 64 9702
TEST_TIME 65 9700
TEST_TIME 66 9726
TEST_TIME 67 9649
TEST_TIME 68 9556
TEST_TIME 69 9669
TEST_TIME 70 9634
TEST_TIME 71 9600
TEST_TIME 72 9516
TEST_TIME 73 9445
TEST_TIME 74 9458
TEST_TIME 75 9405
TEST_TIME 76 9392
TEST_TIME 77 9380
TEST_TIME 78 9440
TEST_TIME 79 9533
TEST_TIME 80 9515
TEST_TIME 81 9526
TEST_TIME 82 9499
TEST_TIME 83 9502
TEST_TIME 84 9578
TEST_TIME 85 9636
TEST_TIME 86 9627
TEST_TIME 87 9757
TEST_TIME 88 9704
TEST_TIME 89 9624
TEST_TIME 90 9617
TEST_TIME 91 9594
TEST_TIME 92 9601
TEST_TIME 93 9551
TEST_TIME 94 9513
TEST_TIME 95 9472
TEST_TIME 96 9465
TEST_TIME 97 9442
TEST_TIME 98 9433
TEST_TIME 99 9448
TEST_TIME 100 9434
TEST_TIME 101 9443
TEST_TIME 102 9460
TEST_TIME 103 9507
TEST_TIME 104 9493
TEST_TIME 105 9492
TEST_TIME 106 9487
TEST_TIME 107 9524
TEST_TIME 108 9520
TEST_TIME 109 9531
TEST_TIME 110 9503
TEST_TIME 111 9469
TEST_TIME 112 9430
TEST_TIME 113 9406
TEST_TIME 114 9370
TEST_TIME 115 9383
TEST_TIME 116 9375
TEST_TIME 117 9331
TEST_TIME 118 9333
TEST_TIME 119 9338
TEST_TIME 120 9300

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-12-2021 19:39:15 to 13-12-2021 19:39:18.

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 1543460 1543460 0 0.00 0 21947 6 0 0
doc_list_biud 1543459 1543459 0 0.00 0 30702 30 0 0
sp_add_invoice_to_stock 5115 5032 83 1.62 39 188049 16677 0 83
sp_cancel_adding_invoice 5904 5904 0 0.00 5 77393 817 0 0
sp_cancel_client_order 1722 1722 0 0.00 4 8943 344 0 0
sp_cancel_customer_reserve 568096 568096 0 0.00 1 25339 65 0 0
sp_cancel_pay_from_customer 722 722 0 0.00 3 11477 73 0 0
sp_cancel_pay_to_supplier 373 373 0 0.00 3 3399 75 0 0
sp_cancel_supplier_invoice 6590 6587 3 0.04 2 20862 889 0 3
sp_cancel_supplier_order 451 451 0 0.00 6 1135058 105669 0 0
sp_cancel_write_off 10111 10111 0 0.00 1 13174 116 0 0
sp_client_order 21134 21134 0 0.00 3 26085 347 0 0
sp_customer_reserve 459672 459672 0 0.00 0 141802 167 0 0
sp_fill_shopping_cart 32346 32346 0 0.00 1 18409 43 0 0
sp_fill_shopping_cart_clo_res 459670 459670 0 0.00 0 21300 13 0 0
sp_get_clo_for_invoice 5027 5027 0 0.00 0 32028 291 0 0
sp_kill_cost_storno 17110 17110 0 0.00 0 21930 62 0 0
sp_kill_qstorno_ret_qs2qd 1153341 1153341 0 0.00 0 25318 36 0 0
sp_kill_qty_storno 607907 607907 0 0.00 0 30702 76 0 0
sp_lock_selected_doc 20931 20931 0 0.00 0 7709 4 0 0
sp_make_cost_storno 28233 28233 0 0.00 0 15627 58 0 0
sp_multiply_rows_for_pdistr 28233 28233 0 0.00 0 13551 34 0 0
sp_multiply_rows_for_qdistr 21134 21134 0 0.00 0 16259 153 0 0
sp_pay_from_customer 7202 7202 0 0.00 4 14909 131 0 0
sp_pay_to_supplier 5994 5994 0 0.00 5 13741 126 0 0
sp_payment_common 13196 13196 0 0.00 1 14899 35 0 0
sp_qd_handle_on_cancel_clo 1722 1722 0 0.00 0 8933 325 0 0
sp_qd_handle_on_invoice_upd_sts 10930 10930 0 0.00 0 30700 360 0 0
sp_qd_handle_on_reserve_upd_sts 10010 10010 0 0.00 0 23726 54 0 0
sp_reserve_write_off 10010 10010 0 0.00 6 23759 172 0 0
sp_supplier_invoice 5493 5493 0 0.00 6 80215 3250 0 0
sp_supplier_order 5729 5729 0 0.00 5 41013 1304 0 0
srv_aggregate_perf_data 101 101 0 0.00 2502 127902 17048 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2144 197613 47887 0 0
srv_find_qd_qs_mism 1068541 1068541 0 0.00 0 15985 5 0 0
srv_make_invnt_saldo 585 585 0 0.00 32 38059 1162 0 0
srv_make_money_saldo 774 774 0 0.00 1 33846 70 0 0
srv_recalc_idx_stat 4 4 0 0.00 62861 708006 290592 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5032 5032 0 0.00 0 7820 150 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 1 8832 99 0 0
t$perf-norm:sp_cancel_client_order 1722 1722 0 0.00 0 9456 16 0 0
t$perf-norm:sp_cancel_customer_reserve 727 727 0 0.00 0 2405 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 722 722 0 0.00 0 2110 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 373 373 0 0.00 0 3257 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 441 441 0 0.00 0 542 12 0 0
t$perf-norm:sp_cancel_supplier_order 451 451 0 0.00 0 17496 1460 0 0
t$perf-norm:sp_cancel_write_off 1045 1045 0 0.00 0 2343 8 0 0
t$perf-norm:sp_client_order 21134 21134 0 0.00 0 15439 17 0 0
t$perf-norm:sp_customer_reserve 1042 1042 0 0.00 0 2334 9 0 0
t$perf-norm:sp_pay_from_customer 7202 7202 0 0.00 0 3508 9 0 0
t$perf-norm:sp_pay_to_supplier 5994 5994 0 0.00 0 3278 7 0 0
t$perf-norm:sp_reserve_write_off 10010 10010 0 0.00 0 5554 10 0 0
t$perf-norm:sp_supplier_invoice 5493 5493 0 0.00 0 4021 16 0 0
t$perf-norm:sp_supplier_order 5729 5729 0 0.00 0 3562 14 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 339 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 61 1 0 0
t$perf-norm:srv_make_invnt_saldo 585 585 0 0.00 0 1859 22 0 0
t$perf-norm:srv_make_money_saldo 774 774 0 0.00 0 3784 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10218 10052 166 1.62 0 6359 16 0 166
v_all_customers 38703 38703 0 0.00 0 15 1 0 0
v_all_suppliers 31386 31386 0 0.00 0 23 1 0 0
v_all_wares 42268 42268 0 0.00 0 52 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 1532 8 0 0
v_cancel_client_order 3444 3444 0 0.00 0 1891 4 0 0
v_cancel_customer_prepayment 1444 1444 0 0.00 0 1821 4 0 0
v_cancel_customer_reserve 1454 1454 0 0.00 0 3353 7 0 0
v_cancel_payment_to_supplier 746 746 0 0.00 0 3353 7 0 0
v_cancel_supplier_invoice 888 882 6 0.67 0 1971 18 0 6
v_cancel_supplier_order 902 902 0 0.00 0 3259 9 0 0
v_cancel_write_off 2090 2090 0 0.00 0 3652 12 0 0
v_max_id_clo_ord 5721 5721 0 0.00 0 5625 3 0 0
v_max_id_clo_res 1042 1042 0 0.00 0 6330 34 0 0
v_max_id_ord_sup 5490 5490 0 0.00 0 5586 6 0 0
v_max_non_paid_invoice 1512 1512 0 0.00 0 4721 7 0 0
v_max_non_paid_realizn 3677 3677 0 0.00 0 14894 9 0 0
v_min_id_clo_ord 5721 5721 0 0.00 0 14357 28 0 0
v_min_id_clo_res 1042 1042 0 0.00 0 1594 8 0 0
v_min_id_ord_sup 5490 5490 0 0.00 0 5869 4 0 0
v_min_non_paid_invoice 5994 5994 0 0.00 0 12147 24 0 0
v_min_non_paid_realizn 7202 7202 0 0.00 0 12160 30 0 0
v_our_firm 10614 10614 0 0.00 0 17 1 0 0
v_reserve_write_off 20018 20018 0 0.00 0 4740 6 0 0
x_lock_depdocs_on_canc_invoice 12050 12050 0 0.00 0 18225 125 0 0
x_lock_depdocs_on_canc_sup_ord 451 451 0 0.00 0 8069 372 0 0
x_make_qstorno_1000_1200 5729 5729 0 0.00 1 40990 1191 0 0
x_make_qstorno_1200_2000 5493 5493 0 0.00 2 80194 3121 0 0
x_make_qstorno_2100_3300 459672 459672 0 0.00 0 141798 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-12-2021 19:39:19 to 13-12-2021 19:39:23.

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:07:59 14 8192000000 8807636944 8821903360 11458560 0.139 5 1 2 0 11562208 60864 42784
17:09:00 22073 8192000000 9897418304 10210971648 980846480 10.692 100 83 167 0 1008918112 5744048 22327584
17:10:26 24118 8192000000 9955064720 10280275968 1037363440 11.239 100 88 176 0 1068902032 8660992 22877600
17:12:04 24820 8192000000 9999481552 10339647488 1083251472 11.678 100 79 158 0 1112222688 9711920 19259296
17:13:37 14877 8192000000 10058032816 10406240256 1135404768 12.172 100 84 169 0 1168060032 11342288 21312976
17:14:57 11504 8192000000 10093316864 10457022464 1180650976 12.596 100 82 164 1 1213093632 13651184 18791472
17:16:10 5408 8192000000 10130936000 10499133440 1219490752 12.957 100 80 159 0 1251180432 13835424 17854256
17:17:18 3801 8192000000 10169380224 10544746496 1252767072 13.264 100 79 158 0 1284202688 13426320 18009296
17:18:24 1721 8192000000 10192332768 10578538496 1284418912 13.553 100 92 182 0 1319013920 14480864 20114144
17:19:27 1954 8192000000 10214492768 10600402944 1294763120 13.648 100 94 190 0 1329206304 12805536 21637648
17:20:30 4255 8192000000 10203003344 10605289472 1299851520 13.694 100 46 92 1 1320503440 10630704 10021216
17:21:37 5941 8192000000 10211908160 10620964864 1312483584 13.809 100 43 86 2 1329028464 7385840 9159040
17:22:46 35834 8192000000 10237226112 10636058624 1322338816 13.898 100 77 155 4 1347151536 6483936 18328784
17:24:22 4214 8192000000 10217068880 10637926400 1330942256 13.976 100 34 68 1 1345595664 6800144 7853264
17:25:30 6228 8192000000 10232344208 10648870912 1333220208 13.996 100 34 68 0 1348406016 7673024 7512784
17:26:36 39708 8192000000 10211345360 10638290944 1309746208 13.784 98 42 83 1 1326580352 5870688 10963456
17:28:20 7557 8192000000 10213505200 10631774208 1320481248 13.881 100 21 42 1 1331344000 6139424 4723328
17:29:28 10699 8192000000 10176481632 10583715840 1256227344 13.295 96 22 44 2 1270959408 9658912 5073152
17:30:39 51128 8192000000 10140139328 10551779328 1236823440 13.117 97 33 64 0 1249797888 4936480 8037968
17:32:33 5683 8192000000 10117706560 10508095488 1208911360 12.859 98 26 52 1 1220718928 5246160 6561408
17:33:39 6658 8192000000 10161740832 10554277888 1248418512 13.224 99 27 52 0 1259731360 5032560 6280288
17:34:50 6889 8192000000 10172210768 10580590592 1281937600 13.531 100 19 37 2 1292104336 5695072 4471664
17:36:01 13892 8192000000 10196928480 10589114368 1289479744 13.599 100 62 129 1 1309782656 5402336 14900576
17:37:15 10577 8192000000 10198347328 10615418880 1311745008 13.802 100 35 70 2 1329720176 10775504 7199664
17:38:31 16518 8192000000 10225612784 10635776000 1327709888 13.946 100 48 96 1 1346390032 7596624 11083520
17:39:59 35506 8192000000 10249615952 10667028480 1344796832 14.101 100 52 104 2 1370015728 13275824 11943072
17:41:36 54517 8192000000 10257340176 10681405440 1355687328 14.199 100 41 82 4 1376420192 10371472 10361392
17:44:05 24126 8192000000 10295806048 10718543872 1377528304 14.394 100 36 72 0 1405976384 19786112 8661968
17:45:37 4756 8192000000 10304903168 10721759232 1381845008 14.433 100 56 111 0 1416306816 21000928 13460880
17:46:50 10614 8192000000 10308015408 10730893312 1389401840 14.501 100 40 80 0 1422384752 22952864 10030048
17:48:04 23176 8192000000 10324303920 10756452352 1403299360 14.624 100 46 89 1 1437039744 22298512 11441872
17:49:35 5659 8192000000 10361776864 10782773248 1420744608 14.779 100 71 142 0 1472030640 34281648 17004384
17:50:48 3038 8192000000 10367071328 10785140736 1418855376 14.763 100 82 163 1 1477054816 37955904 20243536
17:51:56 8669 8192000000 10377261424 10799149056 1425306448 14.820 100 61 122 1 1485635776 44736768 15592560
17:53:13 2142 8192000000 10375441152 10786557952 1410706208 14.690 100 89 176 1 1477935296 45304160 21924928
17:54:17 8707 8192000000 10368723664 10795544576 1422781488 14.797 100 45 90 3 1482942960 48855312 11306160
17:55:34 2400 8192000000 10389079760 10798292992 1389322048 14.500 98 88 178 2 1467926880 56104688 22500144
17:56:41 2298 8192000000 10385344272 10792755200 1395509840 14.555 99 80 158 1 1479111200 62272400 21328960
17:57:48 4950 8192000000 10379633424 10803724288 1402607648 14.618 99 47 94 1 1483357232 67433824 13315760
17:59:04 16661 8192000000 10365569344 10800312320 1407360448 14.660 100 43 86 0 1482215200 62606768 12247984
18:00:33 7801 8192000000 10363949296 10785574912 1384644096 14.458 99 47 93 2 1460289664 63108272 12537296
18:01:45 19025 8192000000 10378046960 10814128128 1402248528 14.615 99 43 85 3 1486504848 71797632 12458688
18:03:08 9316 8192000000 10375424448 10809749504 1404534144 14.635 100 37 74 1 1481479856 65945328 11000384
18:04:25 21313 8192000000 10348081872 10804609024 1382428272 14.438 99 28 56 0 1459288864 68133216 8727376
18:07:21 29565 8192000000 10296444912 10759913472 1363009872 14.264 99 19 38 1 1416859376 48974704 4874800
18:08:52 56036 8192000000 10286134080 10725720064 1347692480 14.127 98 10 20 1 1365236480 15182096 2361904
18:12:25 65608 8192000000 10272747920 10733334528 1361924560 14.255 99 14 28 0 1376931552 11356816 3650176
18:14:31 105469 8192000000 10273041312 10721521664 1313855104 13.821 95 19 37 0 1330735808 12897824 3982880
18:17:16 56893 8192000000 10282413824 10729963520 1359033760 14.229 99 16 32 1 1372844624 10598000 3212864
18:19:14 63151 8192000000 10301650752 10743517184 1371261712 14.338 99 38 76 1 1395410720 15214336 8934672
18:21:31 98692 8192000000 10295006176 10740244480 1348973904 14.138 98 6 12 0 1363189808 12951024 1264880
18:24:33 117462 8192000000 10309783440 10759688192 1377094944 14.391 99 26 51 2 1394855056 11988096 5772016
18:27:32 137490 8192000000 10296311488 10757230592 1353903440 14.183 97 10 20 0 1370049632 13345280 2800912
18:31:00 74212 8192000000 10316642752 10764623872 1359640832 14.234 97 24 48 1 1375984960 10982048 5362080
18:33:30 197613 8192000000 10285237088 10741510144 1321866368 13.894 94 31 62 1 1339046800 10251104 6929328
18:37:55 9037 8192000000 10314264336 10760077312 1398468576 14.581 100 63 126 3 1420648416 7735248 14444592
18:39:05 2626 8192000000 10320407600 10750595072 1386598768 14.476 99 86 176 1 1413617664 7319648 19699248
18:40:12 134702 8192000000 10293366496 10759925760 1354061248 14.184 96 16 32 1 1369643872 11546144 4036480
18:43:27 56642 8192000000 10314186368 10782044160 1391092976 14.516 99 18 36 0 1404948864 9689232 4166656
18:45:26 106086 8192000000 10278039680 10747183104 1312810192 13.812 94 17 34 0 1330259408 13774512 3674704
18:48:22 46374 8192000000 10269661744 10732064768 1340986352 14.066 97 14 27 0 1350548192 6715424 2846416
18:50:13 88334 8192000000 10264792336 10731483136 1323887360 13.912 95 8 16 0 1332704496 7173488 1643648
18:52:42 40754 8192000000 10265936640 10722238464 1330751600 13.974 97 21 42 0 1344545728 8461120 5333008
18:54:27 63604 8192000000 10274468128 10740342784 1360274432 14.240 99 16 32 0 1380433952 16285968 3873552
18:56:33 39900 8192000000 10279188368 10722222080 1325356240 13.925 96 20 40 0 1336951632 7148688 4446704
18:58:13 37544 8192000000 10297454128 10748125184 1382656480 14.440 100 29 58 0 1398463952 9178560 6628912
18:59:54 23202 8192000000 10298424880 10738003968 1363180000 14.266 98 44 88 0 1386631328 13911856 9539472
19:01:17 46758 8192000000 10293739008 10733506560 1357235968 14.213 99 19 38 0 1379976384 18361424 4378992
19:03:05 18206 8192000000 10308291360 10751094784 1379214304 14.410 100 38 76 0 1401259280 13519520 8525456
19:04:41 66909 8192000000 10288979616 10739474432 1366791936 14.298 99 17 34 0 1385098496 14793056 3513504
19:06:48 57215 8192000000 10316680240 10761641984 1386078448 14.471 100 35 71 1 1404021904 10077984 7865472
19:08:46 67527 8192000000 10275070240 10740789248 1341557424 14.071 96 29 58 0 1359685424 11969328 6158672
19:10:54 67839 8192000000 10273880848 10736431104 1368747824 14.316 99 15 30 0 1378774432 7046800 2979808
19:13:10 64195 8192000000 10270832224 10742743040 1344543472 14.098 97 8 16 0 1352745728 6223120 1979136
19:15:15 51086 8192000000 10282050960 10738356224 1345549696 14.107 97 15 30 2 1357287360 8503120 3234544
19:17:16 26519 8192000000 10287611728 10741809152 1369884384 14.326 99 20 40 1 1383533744 9089312 4560048
19:18:44 58611 8192000000 10302036176 10758938624 1381126336 14.427 99 16 32 1 1396373520 11573488 3673696
19:21:06 22691 8192000000 10304157424 10762989568 1394500624 14.546 100 27 52 1 1409134256 9089280 5544352
19:22:36 37448 8192000000 10285984960 10739949568 1316800816 13.848 94 30 61 2 1332376848 9157456 6418576
19:24:13 53997 8192000000 10278026240 10735525888 1337741648 14.037 97 25 50 2 1354019888 10982592 5295648
19:26:07 30761 8192000000 10274982416 10717237248 1316948400 13.849 96 31 62 3 1332710784 9108240 6654144
19:27:48 100047 8192000000 10262367920 10721181696 1338982320 14.048 98 23 46 0 1354085712 9873584 5229808
19:30:35 62822 8192000000 10255265184 10712334336 1327822160 13.947 97 22 42 1 1343522432 11077392 4622880
19:32:38 66266 8192000000 10257843888 10726703104 1355877232 14.200 99 12 25 0 1370984864 12282048 2825584
19:34:49 89930 8192000000 10260390880 10727866368 1342292448 14.078 98 22 45 0 1355687728 8447232 4948048

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 83 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-12-2021 19:39:41 to 13-12-2021 19:39:43.

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          239092
MON$OLDEST_ACTIVE               241514
MON$OLDEST_SNAPSHOT             241514
MON$NEXT_TRANSACTION            241514
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                       2075488
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33543 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33543 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33543 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33543 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 ---
:123: 
:124: mscs-test-161.budzdorov.ru    Mon Dec 13 19:37:57 2021
:125:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:126: 
:127: 
:128: mscs-test-161.budzdorov.ru    Mon Dec 13 19:37:58 2021
:129:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:130: 
:131: 
:132: mscs-test-161.budzdorov.ru    Mon Dec 13 19:38:00 2021
:133:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:134: 
:135: 
:136: mscs-test-161.budzdorov.ru    Mon Dec 13 19:38:00 2021
:137:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:138: 
--- end of diff output ---

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

13.12.21 19:39:44. 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-12-13 19:39:46 No severe PSQL-related problems occured
13.12.21 19:39:47. End of report.