Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.10.2022 16:40
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 15420084 kB
MemAvailable 30575016 kB
Buffers 0 kB
Cached 16321784 kB
SwapCached 2168 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.11
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 2022-10-06 16:38:29
DTS_END                         2022-10-06 16:38:29.1690
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/58946
TRN_ID                          245545
ATT_ID                          699
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: 9419.98 17185 1130397
customer order: creation 179.21 350 21505
customer order: refuse 15.21 358 1825
order to supplier: creation 48.01 1269 5761
order to supplier: removal 3.54 98858 425
invoice (draft): creation 47.86 3313 5743
invoice (draft): removal 52.66 760 6319
invoice accept: apply 43.63 16528 5235
invoice accept: cancel 48.18 798 5782
customer reserve: creation 3938.87 167 472664
customer reserve: removal 4734.43 59 568132
realization accept: apply 87.34 182 10481
realization accept: cancel 87.32 112 10478
payment from customer: creation 61.19 92 7343
payment from customer: removal 5.77 99 692
payment to supplier: creation 51.08 114 6129
payment to supplier: removal 3.34 65 401
service: total inventory turnovers 4.88 1154 585
service: total monetary turnovers 6.60 27 792
service: aggregate perf. data 0.84 20802 101
service: refresh index statistics 0.03 198597 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-10-2022 16:40:21 to 06-10-2022 16:40:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6775
TEST_TIME 2 4696
TEST_TIME 3 4334
TEST_TIME 4 3874
TEST_TIME 5 4048
TEST_TIME 6 4416
TEST_TIME 7 4817
TEST_TIME 8 4949
TEST_TIME 9 5084
TEST_TIME 10 5239
TEST_TIME 11 5453
TEST_TIME 12 5485
TEST_TIME 13 6008
TEST_TIME 14 5857
TEST_TIME 15 6234
TEST_TIME 16 6565
TEST_TIME 17 7219
TEST_TIME 18 7489
TEST_TIME 19 7592
TEST_TIME 20 8132
TEST_TIME 21 8182
TEST_TIME 22 8446
TEST_TIME 23 8736
TEST_TIME 24 8835
TEST_TIME 25 8904
TEST_TIME 26 9014
TEST_TIME 27 9481
TEST_TIME 28 9637
TEST_TIME 29 10445
TEST_TIME 30 10306
TEST_TIME 31 10194
TEST_TIME 32 10322
TEST_TIME 33 10449
TEST_TIME 34 10246
TEST_TIME 35 10271
TEST_TIME 36 10435
TEST_TIME 37 10182
TEST_TIME 38 10095
TEST_TIME 39 10074
TEST_TIME 40 10002
TEST_TIME 41 9834
TEST_TIME 42 9820
TEST_TIME 43 9753
TEST_TIME 44 9740
TEST_TIME 45 9953
TEST_TIME 46 9847
TEST_TIME 47 9776
TEST_TIME 48 9720
TEST_TIME 49 9598
TEST_TIME 50 9562
TEST_TIME 51 9632
TEST_TIME 52 9592
TEST_TIME 53 9512
TEST_TIME 54 9425
TEST_TIME 55 9367
TEST_TIME 56 9406
TEST_TIME 57 9466
TEST_TIME 58 9435
TEST_TIME 59 9457
TEST_TIME 60 9436
TEST_TIME 61 9494
TEST_TIME 62 9577
TEST_TIME 63 9668
TEST_TIME 64 9612
TEST_TIME 65 9588
TEST_TIME 66 9653
TEST_TIME 67 9677
TEST_TIME 68 9690
TEST_TIME 69 9736
TEST_TIME 70 9754
TEST_TIME 71 9729
TEST_TIME 72 9655
TEST_TIME 73 9616
TEST_TIME 74 9612
TEST_TIME 75 9573
TEST_TIME 76 9564
TEST_TIME 77 9516
TEST_TIME 78 9437
TEST_TIME 79 9475
TEST_TIME 80 9478
TEST_TIME 81 9468
TEST_TIME 82 9550
TEST_TIME 83 9635
TEST_TIME 84 9599
TEST_TIME 85 9598
TEST_TIME 86 9686
TEST_TIME 87 9696
TEST_TIME 88 9668
TEST_TIME 89 9719
TEST_TIME 90 9750
TEST_TIME 91 9798
TEST_TIME 92 9783
TEST_TIME 93 9712
TEST_TIME 94 9654
TEST_TIME 95 9661
TEST_TIME 96 9623
TEST_TIME 97 9627
TEST_TIME 98 9601
TEST_TIME 99 9560
TEST_TIME 100 9531
TEST_TIME 101 9492
TEST_TIME 102 9457
TEST_TIME 103 9479
TEST_TIME 104 9474
TEST_TIME 105 9459
TEST_TIME 106 9487
TEST_TIME 107 9519
TEST_TIME 108 9529
TEST_TIME 109 9511
TEST_TIME 110 9512
TEST_TIME 111 9527
TEST_TIME 112 9491
TEST_TIME 113 9573
TEST_TIME 114 9579
TEST_TIME 115 9585
TEST_TIME 116 9583
TEST_TIME 117 9524
TEST_TIME 118 9509
TEST_TIME 119 9483
TEST_TIME 120 9421

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-10-2022 16:40:24 to 06-10-2022 16:40:28.

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 1570333 1570333 0 0.00 0 26591 6 0 0
doc_list_biud 1570333 1570333 0 0.00 0 25219 27 0 0
sp_add_invoice_to_stock 5322 5235 87 1.63 38 154404 16529 0 87
sp_cancel_adding_invoice 5782 5782 0 0.00 4 55897 798 0 0
sp_cancel_client_order 1825 1825 0 0.00 4 12975 358 0 0
sp_cancel_customer_reserve 568132 568132 0 0.00 0 25221 59 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 2 3673 99 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 3 3892 66 0 0
sp_cancel_supplier_invoice 6322 6319 3 0.04 1 19088 760 0 3
sp_cancel_supplier_order 425 425 0 0.00 6 892559 98858 0 0
sp_cancel_write_off 10478 10478 0 0.00 1 13567 113 0 0
sp_client_order 21505 21505 0 0.00 3 34178 350 0 0
sp_customer_reserve 472664 472664 0 0.00 0 114706 168 0 0
sp_fill_shopping_cart 32996 32996 0 0.00 1 22714 37 0 0
sp_fill_shopping_cart_clo_res 472660 472660 0 0.00 0 20328 13 0 0
sp_get_clo_for_invoice 5231 5231 0 0.00 0 24440 305 0 0
sp_kill_cost_storno 17353 17353 0 0.00 0 8440 50 0 0
sp_kill_qstorno_ret_qs2qd 1153486 1153486 0 0.00 0 25069 32 0 0
sp_kill_qty_storno 608673 608673 0 0.00 0 25219 69 0 0
sp_lock_selected_doc 21606 21606 0 0.00 0 10491 9 0 0
sp_make_cost_storno 29184 29184 0 0.00 0 26087 47 0 0
sp_multiply_rows_for_pdistr 29184 29184 0 0.00 0 9471 25 0 0
sp_multiply_rows_for_qdistr 21505 21505 0 0.00 0 17131 156 0 0
sp_pay_from_customer 7343 7343 0 0.00 4 8999 92 0 0
sp_pay_to_supplier 6129 6129 0 0.00 4 9481 114 0 0
sp_payment_common 13472 13472 0 0.00 1 7231 24 0 0
sp_qd_handle_on_cancel_clo 1825 1825 0 0.00 0 12953 327 0 0
sp_qd_handle_on_invoice_upd_sts 11013 11013 0 0.00 0 16522 307 0 0
sp_qd_handle_on_reserve_upd_sts 10481 10481 0 0.00 0 14903 70 0 0
sp_reserve_write_off 10481 10481 0 0.00 6 16316 183 0 0
sp_supplier_invoice 5743 5743 0 0.00 6 90836 3313 0 0
sp_supplier_order 5761 5761 0 0.00 5 42870 1269 0 0
srv_aggregate_perf_data 101 101 0 0.00 2620 313745 20803 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2762 130780 45858 0 0
srv_find_qd_qs_mism 1082229 1082229 0 0.00 0 17071 5 0 0
srv_make_invnt_saldo 585 585 0 0.00 35 75107 1154 0 0
srv_make_money_saldo 792 792 0 0.00 0 1853 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 119510 246299 198598 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 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 5235 5235 0 0.00 0 15169 156 0 0
t$perf-norm:sp_cancel_adding_invoice 449 449 0 0.00 0 10654 105 0 0
t$perf-norm:sp_cancel_client_order 1825 1825 0 0.00 0 6933 16 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 2952 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 2634 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 8793 61 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 1929 21 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 34702 1470 0 0
t$perf-norm:sp_cancel_write_off 1014 1014 0 0.00 0 602 5 0 0
t$perf-norm:sp_client_order 21505 21505 0 0.00 0 18975 18 0 0
t$perf-norm:sp_customer_reserve 992 992 0 0.00 0 1474 6 0 0
t$perf-norm:sp_pay_from_customer 7343 7343 0 0.00 0 12005 13 0 0
t$perf-norm:sp_pay_to_supplier 6129 6129 0 0.00 0 10773 15 0 0
t$perf-norm:sp_reserve_write_off 10481 10481 0 0.00 0 16548 14 0 0
t$perf-norm:sp_supplier_invoice 5743 5743 0 0.00 0 10758 20 0 0
t$perf-norm:sp_supplier_order 5761 5761 0 0.00 0 5411 16 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 319 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 315 6 0 0
t$perf-norm:srv_make_invnt_saldo 585 585 0 0.00 0 1043 15 0 0
t$perf-norm:srv_make_money_saldo 792 792 0 0.00 0 1931 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 10630 10456 174 1.63 0 6536 18 0 174
v_all_customers 39254 39254 0 0.00 0 182 1 0 0
v_all_suppliers 31698 31698 0 0.00 0 210 1 0 0
v_all_wares 43010 43010 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 898 898 0 0.00 0 1245 7 0 0
v_cancel_client_order 3650 3650 0 0.00 0 3536 7 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 3657 10 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 3930 8 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 3875 11 0 0
v_cancel_supplier_invoice 768 762 6 0.78 0 1588 20 0 6
v_cancel_supplier_order 850 850 0 0.00 0 6173 13 0 0
v_cancel_write_off 2028 2028 0 0.00 0 2649 15 0 0
v_max_id_clo_ord 5757 5757 0 0.00 0 2521 2 0 0
v_max_id_clo_res 992 992 0 0.00 0 1797 12 0 0
v_max_id_ord_sup 5734 5734 0 0.00 0 5386 7 0 0
v_max_non_paid_invoice 1771 1771 0 0.00 0 491 2 0 0
v_max_non_paid_realizn 3839 3839 0 0.00 0 2038 4 0 0
v_min_id_clo_ord 5757 5757 0 0.00 0 12169 13 0 0
v_min_id_clo_res 992 992 0 0.00 0 8896 26 0 0
v_min_id_ord_sup 5734 5734 0 0.00 0 6831 8 0 0
v_min_non_paid_invoice 6129 6129 0 0.00 0 5664 20 0 0
v_min_non_paid_realizn 7343 7343 0 0.00 0 6788 17 0 0
v_our_firm 10764 10764 0 0.00 0 19 1 0 0
v_reserve_write_off 20962 20962 0 0.00 0 3856 7 0 0
x_lock_depdocs_on_canc_invoice 11720 11720 0 0.00 0 20240 121 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 13109 324 0 0
x_make_qstorno_1000_1200 5761 5761 0 0.00 1 42865 1193 0 0
x_make_qstorno_1200_2000 5743 5743 0 0.00 2 90818 3180 0 0
x_make_qstorno_2100_3300 472664 472664 0 0.00 0 114703 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-10-2022 16:40:29 to 06-10-2022 16:40:33.

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
14:08:33 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:33 29566 8192000000 9905350000 10216325120 980254416 10.687 100 81 163 0 1007473600 6062432 21156752
14:11:08 22086 8192000000 9953294784 10274340864 1030016496 11.169 100 87 175 0 1061172144 8746832 22408816
14:12:38 9529 8192000000 9980972112 10315964416 1062279600 11.478 100 86 172 0 1094590384 10587392 21723392
14:13:55 10676 8192000000 10021410880 10369757184 1109025984 11.923 100 79 158 0 1139771696 11911728 18833984
14:15:08 5369 8192000000 10073770320 10432757760 1162998064 12.431 100 78 156 0 1193142064 11653952 18490048
14:16:15 5979 8192000000 10122108048 10492948480 1210211456 12.871 100 75 149 0 1238082992 11364928 16506608
14:17:23 2358 8192000000 10158714176 10531901440 1247254944 13.213 100 89 179 0 1280507040 14172496 19079600
14:18:34 2467 8192000000 10176836816 10565386240 1278352512 13.498 100 67 134 0 1303746896 10630448 14763936
14:19:39 1374 8192000000 10203559424 10586636288 1289335040 13.598 100 84 164 0 1320600096 13304000 17961056
14:20:41 30808 8192000000 10173628304 10587131904 1294716928 13.647 100 30 60 1 1307567360 6137952 6712480
14:22:15 6593 8192000000 10183941008 10596687872 1302720480 13.720 100 34 68 0 1317262384 7839328 6702576
14:23:23 8112 8192000000 10194166304 10610675712 1310810848 13.793 100 29 59 1 1323154016 5930048 6413120
14:24:36 10864 8192000000 10201183408 10625191936 1324446064 13.917 100 16 32 0 1333803392 5338528 4018800
14:25:47 59755 8192000000 10236282160 10646851584 1331487104 13.981 100 40 78 1 1346623488 5411744 9724640
14:27:48 4900 8192000000 10236679136 10649255936 1333616480 14.000 100 35 68 0 1349208592 7587904 8004208
14:28:55 8542 8192000000 10210515728 10622619648 1303619504 13.728 99 23 45 1 1313786128 5502992 4663632
14:30:06 51392 8192000000 10142160608 10544852992 1196349088 12.742 93 19 35 0 1204808864 4621024 3838752
14:31:58 6334 8192000000 10145820304 10534006784 1219728704 12.959 98 22 45 1 1231034016 6166592 5138720
14:33:07 7223 8192000000 10149755536 10549121024 1255530560 13.289 100 24 48 0 1266766416 5290016 5945840
14:34:19 21667 8192000000 10167237904 10582175744 1282165872 13.533 100 25 49 0 1293657120 5231392 6259856
14:35:56 9095 8192000000 10190858032 10595708928 1291165936 13.615 100 27 54 0 1303739568 6443824 6129808
14:37:07 6759 8192000000 10190024096 10603941888 1300249648 13.698 100 20 37 1 1310477808 5532512 4695648
14:38:15 10392 8192000000 10210582400 10629214208 1322992128 13.904 100 21 43 0 1332391248 4895472 4503648
14:39:31 27558 8192000000 10250257168 10665328640 1347600784 14.126 100 44 88 2 1369650448 11254752 10794912
14:41:01 35645 8192000000 10253438480 10676723712 1359581024 14.234 100 33 66 1 1375053424 7982816 7489584
14:42:45 25550 8192000000 10272062432 10696753152 1363032320 14.265 99 44 86 0 1383724048 10346416 10345312
14:44:15 4886 8192000000 10304347248 10723233792 1388329344 14.491 100 58 113 0 1417732912 16746672 12656896
14:45:33 13075 8192000000 10320455408 10743377920 1401668608 14.610 100 45 89 0 1428103600 16081104 10353888
14:46:48 18571 8192000000 10323473440 10755563520 1411316736 14.696 100 40 76 2 1440388736 19410848 9661152
14:48:15 4542 8192000000 10338922256 10766950400 1418271984 14.757 100 58 116 1 1458165520 25723072 14170464
14:49:28 5939 8192000000 10352381728 10777849856 1424209568 14.810 100 53 103 0 1463697920 26915168 12573184
14:50:38 36318 8192000000 10348152160 10785353728 1422565824 14.795 100 38 76 0 1460869296 28452752 9850720
14:52:23 14646 8192000000 10323496448 10748452864 1393295104 14.535 99 51 99 1 1431875824 25583248 12997472
14:53:46 5918 8192000000 10339535200 10757591040 1399179296 14.588 100 71 142 1 1450344112 33872640 17292176
14:54:59 3331 8192000000 10328497776 10744143872 1368127936 14.310 99 66 132 1 1426484128 41689328 16666864
14:56:05 3646 8192000000 10348191760 10756739072 1389960640 14.506 100 84 167 1 1450550416 40378736 20211040
14:57:12 2761 8192000000 10338869392 10754920448 1383510688 14.448 100 58 117 0 1441168496 42917632 14740176
14:58:22 3772 8192000000 10330855024 10753449984 1381965920 14.434 100 62 120 0 1439096144 41373216 15757008
14:59:28 15501 8192000000 10324138448 10742956032 1375254592 14.374 100 39 76 0 1429414864 43468720 10691552
15:00:58 8663 8192000000 10338561264 10763317248 1373867184 14.362 99 50 100 0 1435499168 48554000 13077984
15:02:12 7092 8192000000 10328825248 10746777600 1378370592 14.402 99 54 105 0 1428205728 36313424 13521712
15:03:25 19263 8192000000 10326080976 10751856640 1380704384 14.423 100 28 57 1 1429293728 40668720 7920624
15:04:55 11249 8192000000 10310850016 10752573440 1368006384 14.309 100 17 34 0 1417465440 44362080 5096976
15:06:08 26366 8192000000 10294435824 10722033664 1317966864 13.858 97 20 40 0 1363215568 39607360 5641344
15:07:37 56319 8192000000 10270071104 10708131840 1333796992 14.001 98 13 26 0 1347601824 11148080 2656752
15:09:34 26789 8192000000 10281485152 10710429696 1336921456 14.030 97 34 66 1 1354989024 10064704 8002864
15:11:05 61176 8192000000 10275452432 10708246528 1317223872 13.852 96 12 24 0 1329305520 9273600 2808048
15:13:08 121433 8192000000 10277805680 10733613056 1372332352 14.348 100 24 48 1 1398388368 20527920 5528096
15:16:52 27593 8192000000 10307745504 10748915712 1388112960 14.489 100 23 46 0 1400159776 7363536 4683280
15:18:24 120290 8192000000 10308631056 10757443584 1359966160 14.237 97 21 41 0 1375978192 11400112 4611920
15:21:29 43697 8192000000 10315960560 10766024704 1398868544 14.585 100 14 28 0 1414940032 12360624 3710864
15:23:18 29506 8192000000 10319431744 10775121920 1399739488 14.593 100 12 24 0 1423185808 20423200 3023120
15:24:54 102763 8192000000 10312253808 10776551424 1408095456 14.667 100 13 26 0 1417952256 6953376 2903424
15:27:45 38943 8192000000 10328509840 10790748160 1418197584 14.757 100 21 43 1 1440575024 17258432 5119008
15:29:33 58454 8192000000 10303075760 10770284544 1364120400 14.274 96 34 68 0 1382395200 11334304 6940496
15:31:52 45439 8192000000 10305776352 10764865536 1395178208 14.552 99 16 30 0 1407781216 9142992 3460016
15:33:41 66972 8192000000 10287095984 10753134592 1361892016 14.254 97 11 22 0 1374802976 10403216 2507744
15:35:57 25547 8192000000 10284123696 10730422272 1348553584 14.134 98 41 82 0 1365756256 8165952 9036720
15:37:24 22879 8192000000 10270716624 10717401088 1352847248 14.173 99 21 42 0 1366123888 8751216 4525424
15:38:47 52828 8192000000 10256028176 10704924672 1262628240 13.354 92 19 38 0 1287207360 19653296 4925824
15:40:40 55788 8192000000 10261444688 10712199168 1309426992 13.781 96 12 24 0 1319625040 7741840 2456208
15:42:46 9352 8192000000 10291609056 10714157056 1349900144 14.147 99 58 115 0 1371455424 8889216 12666064
15:43:56 35881 8192000000 10244260000 10693890048 1322421520 13.899 98 34 68 0 1343247824 13061616 7764688
15:45:37 34645 8192000000 10277438896 10721882112 1350319456 14.150 99 28 56 0 1369166000 12666208 6180336
15:47:17 21609 8192000000 10233625840 10669441024 1270479936 13.426 95 16 31 0 1282156960 8228928 3448096
15:48:50 130779 8192000000 10255558112 10697547776 1303489120 13.727 97 15 30 0 1319729872 12902784 3337968
15:52:06 75055 8192000000 10273158784 10731044864 1339833440 14.056 99 35 70 0 1367116256 19134400 8148416
15:54:22 94490 8192000000 10263914704 10718662656 1337911776 14.039 98 16 32 0 1358593904 16742928 3939200
15:56:57 62090 8192000000 10273970624 10736300032 1361007264 14.246 100 20 40 0 1392987712 26771024 5209424
15:59:07 50659 8192000000 10295912992 10739974144 1368805392 14.316 100 21 42 0 1397655808 24060688 4789728
16:00:59 119849 8192000000 10292142160 10753486848 1374371488 14.366 100 21 42 1 1393561920 14252288 4938144
16:04:03 36825 8192000000 10287218560 10745241600 1372401520 14.349 99 31 62 0 1393204240 14017520 6785200
16:05:44 68937 8192000000 10308884192 10753802240 1370534144 14.332 99 16 31 1 1387821072 13761376 3525552
16:07:59 97252 8192000000 10310313696 10784260096 1402744480 14.619 100 12 24 0 1421364736 16114224 2506032
16:10:37 90937 8192000000 10287763488 10744696832 1361391488 14.250 97 15 29 0 1372569648 8255280 2922880
16:13:27 71597 8192000000 10297722640 10764988416 1371510080 14.341 98 29 56 1 1388650976 10897104 6243792
16:15:51 62916 8192000000 10271878672 10738630656 1354500672 14.188 98 19 38 0 1366992576 8186368 4305536
16:17:55 61535 8192000000 10271304864 10726969344 1354079392 14.184 97 20 40 0 1367172448 8344240 4748816
16:21:10 58542 8192000000 10292723216 10770608128 1391657824 14.521 100 10 18 0 1402217984 8329632 2230528
16:23:09 43129 8192000000 10298612880 10758164480 1359651168 14.234 97 14 28 0 1370834000 8068496 3114336
16:24:54 82700 8192000000 10303853152 10770337792 1394094832 14.542 100 13 26 0 1407225312 10511792 2618688
16:27:50 57818 8192000000 10279924848 10732294144 1320990288 13.886 95 23 47 0 1334406864 8171984 5244592
16:30:00 68961 8192000000 10279764432 10735058944 1331401760 13.980 97 20 40 0 1357826032 21980656 4443616
16:32:14 27301 8192000000 10282478256 10720796672 1356116528 14.202 100 32 62 1 1371732896 8825936 6790432
16:33:47 83797 8192000000 10271811680 10706157568 1273273904 13.452 93 25 48 0 1287585664 9124192 5187568
16:36:11 91558 8192000000 10234938880 10680176640 1308308176 13.771 98 18 36 1 1322566352 9952064 4306112

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-10-2022 16:40:35 to 06-10-2022 16:40:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 87 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 06-10-2022 16:40:51 to 06-10-2022 16:40:53.

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          244343
MON$OLDEST_ACTIVE               245864
MON$OLDEST_SNAPSHOT             245864
MON$NEXT_TRANSACTION            245864
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                       2043104
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33632 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33632 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33632 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33632 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 ---
:278: 
:279: mscs-test-161.budzdorov.ru    Thu Oct  6 16:38:39 2022
:280:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:281: 
:282: 
:283: mscs-test-161.budzdorov.ru    Thu Oct  6 16:38:39 2022
:284:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:285: 
:286: 
:287: mscs-test-161.budzdorov.ru    Thu Oct  6 16:38:40 2022
:288:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:289: 
--- end of diff output ---

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

06.10.22 16:40:54. 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
2022-10-06 16:40:57 No severe PSQL-related problems occured
06.10.22 16:40:58. End of report.