Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.01.2023 01: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 15216224 kB
MemAvailable 30462748 kB
Buffers 0 kB
Cached 16523936 kB
SwapCached 1716 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 2023-01-21 01:38:38
DTS_END                         2023-01-21 01:38:38.0550
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/57730
TRN_ID                          255607
ATT_ID                          676
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: 9420.27 15557 1130432
customer order: creation 190.18 323 22822
customer order: refuse 15.98 332 1918
order to supplier: creation 49.92 1180 5990
order to supplier: removal 3.63 93556 435
invoice (draft): creation 50.19 3747 6023
invoice (draft): removal 51.45 832 6174
invoice accept: apply 44.93 17565 5392
invoice accept: cancel 46.40 790 5568
customer reserve: creation 4127.54 174 495305
customer reserve: removal 4527.47 58 543296
realization accept: apply 92.78 174 11133
realization accept: cancel 77.18 112 9261
payment from customer: creation 65.50 92 7860
payment from customer: removal 6.44 67 773
payment to supplier: creation 54.18 123 6501
payment to supplier: removal 3.66 74 439
service: total inventory turnovers 4.79 1104 575
service: total monetary turnovers 7.24 34 869
service: aggregate perf. data 0.78 25888 94
service: refresh index statistics 0.03 164918 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-01-2023 01:41:07 to 21-01-2023 01:41:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4372
TEST_TIME 2 3182
TEST_TIME 3 3648
TEST_TIME 4 3971
TEST_TIME 5 4050
TEST_TIME 6 4080
TEST_TIME 7 4573
TEST_TIME 8 5514
TEST_TIME 9 5333
TEST_TIME 10 6007
TEST_TIME 11 6174
TEST_TIME 12 6872
TEST_TIME 13 7088
TEST_TIME 14 6955
TEST_TIME 15 7467
TEST_TIME 16 7701
TEST_TIME 17 7674
TEST_TIME 18 7686
TEST_TIME 19 7609
TEST_TIME 20 7684
TEST_TIME 21 7746
TEST_TIME 22 7853
TEST_TIME 23 8029
TEST_TIME 24 8361
TEST_TIME 25 8474
TEST_TIME 26 9001
TEST_TIME 27 9399
TEST_TIME 28 9678
TEST_TIME 29 10707
TEST_TIME 30 10577
TEST_TIME 31 10551
TEST_TIME 32 10307
TEST_TIME 33 10283
TEST_TIME 34 10414
TEST_TIME 35 10255
TEST_TIME 36 10161
TEST_TIME 37 10244
TEST_TIME 38 10261
TEST_TIME 39 10230
TEST_TIME 40 10084
TEST_TIME 41 10059
TEST_TIME 42 9975
TEST_TIME 43 9925
TEST_TIME 44 9904
TEST_TIME 45 9875
TEST_TIME 46 9879
TEST_TIME 47 9886
TEST_TIME 48 9793
TEST_TIME 49 9938
TEST_TIME 50 9849
TEST_TIME 51 9863
TEST_TIME 52 9753
TEST_TIME 53 9729
TEST_TIME 54 9623
TEST_TIME 55 9553
TEST_TIME 56 9477
TEST_TIME 57 9473
TEST_TIME 58 9482
TEST_TIME 59 9504
TEST_TIME 60 9479
TEST_TIME 61 9485
TEST_TIME 62 9603
TEST_TIME 63 9606
TEST_TIME 64 9649
TEST_TIME 65 9603
TEST_TIME 66 9648
TEST_TIME 67 9721
TEST_TIME 68 9720
TEST_TIME 69 9811
TEST_TIME 70 9840
TEST_TIME 71 9785
TEST_TIME 72 9707
TEST_TIME 73 9685
TEST_TIME 74 9665
TEST_TIME 75 9620
TEST_TIME 76 9612
TEST_TIME 77 9550
TEST_TIME 78 9513
TEST_TIME 79 9527
TEST_TIME 80 9481
TEST_TIME 81 9514
TEST_TIME 82 9506
TEST_TIME 83 9494
TEST_TIME 84 9594
TEST_TIME 85 9627
TEST_TIME 86 9626
TEST_TIME 87 9630
TEST_TIME 88 9680
TEST_TIME 89 9670
TEST_TIME 90 9681
TEST_TIME 91 9688
TEST_TIME 92 9663
TEST_TIME 93 9795
TEST_TIME 94 9709
TEST_TIME 95 9668
TEST_TIME 96 9653
TEST_TIME 97 9593
TEST_TIME 98 9628
TEST_TIME 99 9568
TEST_TIME 100 9564
TEST_TIME 101 9548
TEST_TIME 102 9547
TEST_TIME 103 9487
TEST_TIME 104 9508
TEST_TIME 105 9520
TEST_TIME 106 9511
TEST_TIME 107 9504
TEST_TIME 108 9513
TEST_TIME 109 9548
TEST_TIME 110 9566
TEST_TIME 111 9545
TEST_TIME 112 9542
TEST_TIME 113 9556
TEST_TIME 114 9572
TEST_TIME 115 9586
TEST_TIME 116 9546
TEST_TIME 117 9493
TEST_TIME 118 9473
TEST_TIME 119 9461
TEST_TIME 120 9420

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-01-2023 01:41:11 to 21-01-2023 01:41:14.

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 1589739 1589739 0 0.00 0 16756 6 0 0
doc_list_biud 1589721 1589721 0 0.00 0 61096 26 0 0
sp_add_invoice_to_stock 5550 5392 158 2.84 31 253655 17566 0 158
sp_cancel_adding_invoice 5568 5568 0 0.00 5 56336 790 0 0
sp_cancel_client_order 1918 1918 0 0.00 4 9269 333 0 0
sp_cancel_customer_reserve 543296 543296 0 0.00 0 61097 59 0 0
sp_cancel_pay_from_customer 773 773 0 0.00 3 2271 68 0 0
sp_cancel_pay_to_supplier 439 439 0 0.00 3 2885 75 0 0
sp_cancel_supplier_invoice 6183 6174 9 0.14 2 12069 832 0 9
sp_cancel_supplier_order 435 435 0 0.00 7 970641 93556 0 0
sp_cancel_write_off 9261 9261 0 0.00 1 7863 112 0 0
sp_client_order 22822 22822 0 0.00 3 27124 324 0 0
sp_customer_reserve 495305 495305 0 0.00 0 93619 174 0 0
sp_fill_shopping_cart 34831 34831 0 0.00 1 16828 40 0 0
sp_fill_shopping_cart_clo_res 495305 495305 0 0.00 0 17560 14 0 0
sp_get_clo_for_invoice 5392 5392 0 0.00 0 29897 318 0 0
sp_kill_cost_storno 16041 16041 0 0.00 0 7861 53 0 0
sp_kill_qstorno_ret_qs2qd 1102462 1102462 0 0.00 0 61092 32 0 0
sp_kill_qty_storno 583165 583165 0 0.00 0 61095 69 0 0
sp_lock_selected_doc 22788 22788 0 0.00 0 7824 4 0 0
sp_make_cost_storno 30886 30886 0 0.00 0 9800 46 0 0
sp_multiply_rows_for_pdistr 30868 30868 0 0.00 0 7996 25 0 0
sp_multiply_rows_for_qdistr 22822 22822 0 0.00 0 26694 151 0 0
sp_pay_from_customer 7860 7860 0 0.00 4 9457 92 0 0
sp_pay_to_supplier 6501 6501 0 0.00 4 8201 123 0 0
sp_payment_common 14355 14355 0 0.00 1 9362 22 0 0
sp_qd_handle_on_cancel_clo 1918 1918 0 0.00 0 9211 303 0 0
sp_qd_handle_on_invoice_upd_sts 10957 10957 0 0.00 0 13477 305 0 0
sp_qd_handle_on_reserve_upd_sts 11124 11124 0 0.00 0 17860 66 0 0
sp_reserve_write_off 11133 11133 0 0.00 6 17936 175 0 0
sp_supplier_invoice 6023 6023 0 0.00 6 89448 3747 0 0
sp_supplier_order 5990 5990 0 0.00 5 30941 1181 0 0
srv_aggregate_perf_data 94 94 0 0.00 2847 555255 25888 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1365 166939 42302 0 0
srv_find_qd_qs_mism 1078661 1078661 0 0.00 0 16756 6 0 0
srv_make_invnt_saldo 575 575 0 0.00 23 30809 1105 0 0
srv_make_money_saldo 869 869 0 0.00 1 4486 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 138514 182040 164919 0 0
t$perf-abend:sp_add_invoice_to_stock 158 158 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5392 5392 0 0.00 0 14805 188 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 2833 118 0 0
t$perf-norm:sp_cancel_client_order 1918 1918 0 0.00 0 4964 16 0 0
t$perf-norm:sp_cancel_customer_reserve 738 738 0 0.00 0 4534 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 773 773 0 0.00 0 6058 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 439 439 0 0.00 0 1483 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 1464 21 0 0
t$perf-norm:sp_cancel_supplier_order 435 435 0 0.00 0 30020 1504 0 0
t$perf-norm:sp_cancel_write_off 1138 1138 0 0.00 0 2873 12 0 0
t$perf-norm:sp_client_order 22822 22822 0 0.00 0 9782 18 0 0
t$perf-norm:sp_customer_reserve 1056 1056 0 0.00 0 6194 15 0 0
t$perf-norm:sp_pay_from_customer 7860 7860 0 0.00 0 16540 12 0 0
t$perf-norm:sp_pay_to_supplier 6501 6501 0 0.00 0 10580 13 0 0
t$perf-norm:sp_reserve_write_off 11133 11133 0 0.00 0 12113 15 0 0
t$perf-norm:sp_supplier_invoice 6023 6023 0 0.00 0 4896 17 0 0
t$perf-norm:sp_supplier_order 5990 5990 0 0.00 0 8225 19 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 93 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 732 23 0 0
t$perf-norm:srv_make_invnt_saldo 575 575 0 0.00 0 568 20 0 0
t$perf-norm:srv_make_money_saldo 869 869 0 0.00 0 3785 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 17 9 0 0
v_add_invoice_to_stock 11094 10778 316 2.84 0 6478 20 0 316
v_all_customers 41302 41302 0 0.00 0 90 1 0 0
v_all_suppliers 33020 33020 0 0.00 0 136 1 0 0
v_all_wares 45644 45644 0 0.00 0 35 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 2050 10 0 0
v_cancel_client_order 3836 3836 0 0.00 0 4906 8 0 0
v_cancel_customer_prepayment 1546 1546 0 0.00 0 2152 4 0 0
v_cancel_customer_reserve 1476 1476 0 0.00 0 3675 12 0 0
v_cancel_payment_to_supplier 878 878 0 0.00 0 2404 11 0 0
v_cancel_supplier_invoice 860 842 18 2.09 0 2828 23 0 18
v_cancel_supplier_order 870 870 0 0.00 0 3640 18 0 0
v_cancel_write_off 2276 2276 0 0.00 0 4120 16 0 0
v_max_id_clo_ord 5987 5987 0 0.00 0 308 1 0 0
v_max_id_clo_res 1056 1056 0 0.00 0 3927 21 0 0
v_max_id_ord_sup 6022 6022 0 0.00 0 5490 6 0 0
v_max_non_paid_invoice 1998 1998 0 0.00 0 1024 3 0 0
v_max_non_paid_realizn 4348 4348 0 0.00 0 4254 4 0 0
v_min_id_clo_ord 5987 5987 0 0.00 0 3641 6 0 0
v_min_id_clo_res 1056 1056 0 0.00 0 10440 22 0 0
v_min_id_ord_sup 6022 6022 0 0.00 0 5160 15 0 0
v_min_non_paid_invoice 6499 6499 0 0.00 0 5916 12 0 0
v_min_non_paid_realizn 7854 7854 0 0.00 0 7234 15 0 0
v_our_firm 11356 11356 0 0.00 0 26 1 0 0
v_reserve_write_off 22246 22246 0 0.00 0 5157 8 0 0
x_lock_depdocs_on_canc_invoice 11321 11321 0 0.00 0 25584 130 0 0
x_lock_depdocs_on_canc_sup_ord 435 435 0 0.00 0 11301 357 0 0
x_make_qstorno_1000_1200 5990 5990 0 0.00 1 30910 1103 0 0
x_make_qstorno_1200_2000 6023 6023 0 0.00 2 89435 3593 0 0
x_make_qstorno_2100_3300 495305 495305 0 0.00 0 93618 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-01-2023 01:41:15 to 21-01-2023 01:41:19.

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
23:08:42 30 8192000000 8834260720 8857448448 31495744 0.382 9 7 8 0 31853664 122144 235776
23:09:43 25824 8192000000 9914499744 10227101696 994852928 10.829 100 91 182 1 1025273536 6471808 23948800
23:11:15 21028 8192000000 9951612112 10277605376 1036718576 11.233 100 82 164 0 1066066256 8351216 20996464
23:12:39 18709 8192000000 9992395504 10326269952 1068531488 11.538 100 83 166 0 1098968688 9563520 20873680
23:14:09 10478 8192000000 10034655216 10386153472 1125782288 12.082 100 75 150 0 1154185440 10594320 17808832
23:15:22 8225 8192000000 10087266464 10454802432 1178675312 12.578 100 65 130 1 1204000704 10223600 15101792
23:16:34 3075 8192000000 10142898336 10509389824 1224984032 13.008 100 89 172 0 1257300496 13313184 19003280
23:17:39 1604 8192000000 10162270704 10537017344 1251607232 13.253 100 89 174 0 1285995120 15557824 18830064
23:18:43 1182 8192000000 10202581888 10584698880 1281979824 13.531 100 82 164 0 1315517008 14877520 18659664
23:19:45 1631 8192000000 10215284544 10603974656 1305069280 13.741 100 88 177 0 1336160208 11144352 19946576
23:20:51 12563 8192000000 10197554976 10615099392 1313337744 13.816 100 37 74 0 1327167984 4842112 8988128
23:22:05 13224 8192000000 10208545440 10623180800 1318960544 13.867 100 14 28 0 1328130976 6332672 2837760
23:23:20 15886 8192000000 10217219488 10640273408 1327152080 13.941 100 11 22 1 1339441632 10294112 1995440
23:24:37 17537 8192000000 10218119888 10649206784 1337282752 14.033 100 30 60 0 1349751440 5846288 6622400
23:26:10 5638 8192000000 10234809360 10657124352 1338721328 14.046 100 29 57 0 1352070640 6848144 6501168
23:27:18 8703 8192000000 10227588816 10649473024 1335568288 14.017 100 23 46 0 1346456448 5872016 5016144
23:28:29 9499 8192000000 10208591248 10627346432 1289208752 13.597 97 16 32 0 1298683376 5790768 3683856
23:29:42 44876 8192000000 10153448624 10566275072 1220353232 12.965 93 26 48 3 1230153232 4130976 5669024
23:31:27 7017 8192000000 10149560160 10551148544 1220950656 12.970 96 32 64 0 1235158224 6601936 7605632
23:32:36 3926 8192000000 10138287040 10536824832 1235259552 13.103 99 26 54 0 1246990240 5875584 5855104
23:33:44 35712 8192000000 10163897888 10573680640 1255373744 13.288 98 31 59 0 1267026976 4983248 6669984
23:35:35 5772 8192000000 10194947584 10598883328 1294194512 13.642 100 32 62 1 1307804992 6754768 6855712
23:36:44 7142 8192000000 10196105120 10613051392 1306599424 13.755 100 23 45 2 1316916688 5645248 4672016
23:37:52 10689 8192000000 10203324512 10620764160 1313801728 13.821 100 15 29 0 1322173472 5034176 3337568
23:39:06 33241 8192000000 10228071296 10647470080 1325882816 13.930 99 32 64 0 1338420288 5035168 7502304
23:40:44 14531 8192000000 10259763056 10680614912 1357806336 14.218 100 46 92 0 1379058016 10449984 10801696
23:42:05 16977 8192000000 10284005632 10707558400 1376333904 14.384 100 43 85 1 1397900672 11552400 10014368
23:43:35 21447 8192000000 10300395776 10726625280 1387925648 14.487 100 40 80 0 1416500432 18846960 9727824
23:45:00 5639 8192000000 10304057104 10727260160 1390818816 14.513 100 49 98 0 1418612368 16260528 11533024
23:46:11 11346 8192000000 10313483568 10734915584 1391033664 14.515 100 52 104 0 1422870352 19490464 12346224
23:47:28 7955 8192000000 10314145040 10737434624 1385927424 14.470 99 46 92 0 1414284352 16976464 11380464
23:48:38 12365 8192000000 10326607264 10751905792 1401643152 14.610 100 60 120 0 1436254160 19322240 15288768
23:50:06 2665 8192000000 10334413952 10759618560 1406388320 14.652 100 66 128 1 1448691088 27223488 15079280
23:51:13 4763 8192000000 10312993152 10729451520 1369924864 14.326 99 66 129 0 1413622992 28521104 15177024
23:52:23 10630 8192000000 10310018560 10729951232 1349763664 14.145 97 54 107 2 1391868880 29030624 13074592
23:53:37 4442 8192000000 10297038960 10709970944 1362373632 14.259 100 67 133 0 1404200528 26088016 15738880
23:54:48 2778 8192000000 10299486608 10714030080 1364912560 14.281 100 64 127 0 1406213104 25887440 15413104
23:55:56 6581 8192000000 10293142352 10706440192 1358192672 14.221 100 54 106 0 1400911328 29026272 13692384
23:57:09 1713 8192000000 10298595520 10693853184 1343665632 14.090 98 79 152 1 1394214592 31903856 18645104
23:58:20 1359 8192000000 10343138288 10739687424 1381936544 14.434 100 82 159 1 1437592320 36228464 19427312
23:59:29 3044 8192000000 10325676304 10738692096 1381749008 14.432 100 55 110 2 1433767536 38079232 13939296
00:00:49 5429 8192000000 10353112640 10772488192 1386198016 14.472 99 52 103 0 1450349136 49859056 14292064
00:02:00 5777 8192000000 10361107024 10776178688 1397211488 14.570 100 58 113 1 1462992256 50691856 15088912
00:03:08 19267 8192000000 10330020864 10757615616 1375095552 14.373 99 32 64 0 1432706608 48367056 9244000
00:04:31 10323 8192000000 10342682352 10773667840 1386922736 14.478 100 33 66 0 1443061168 46930112 9208320
00:05:59 19826 8192000000 10316146640 10750500864 1366211312 14.293 99 27 53 0 1415191808 41383776 7596720
00:07:30 25058 8192000000 10291945088 10750492672 1378388912 14.402 100 19 38 1 1419844176 36012736 5442528
00:08:55 20505 8192000000 10288062576 10740224000 1380402320 14.420 100 12 23 1 1395574992 12562480 2610192
00:10:18 37517 8192000000 10342320384 10759856128 1400451680 14.599 100 42 85 0 1425163296 14500208 10211408
00:11:59 149981 8192000000 10315644496 10773667840 1396009040 14.559 100 13 26 0 1421253920 21970400 3274480
00:15:34 96454 8192000000 10316047888 10767523840 1367841552 14.308 97 20 40 0 1384077872 11876720 4359600
00:18:11 30468 8192000000 10327748688 10773090304 1407471728 14.661 100 45 89 0 1431322096 13966944 9883424
00:19:43 6250 8192000000 10332403472 10779320320 1412048528 14.702 100 30 59 0 1434033216 15310032 6674656
00:20:50 42863 8192000000 10319537264 10772131840 1390857152 14.514 99 30 60 0 1417136400 19316096 6963152
00:22:36 36071 8192000000 10296503664 10753441792 1367588784 14.305 98 18 35 0 1390073040 18378112 4106144
00:24:13 68261 8192000000 10309209968 10762096640 1375832464 14.379 98 18 34 0 1402905200 22782880 4289856
00:26:32 38247 8192000000 10285385056 10747768832 1367717856 14.307 98 14 28 0 1389373024 17976768 3678400
00:28:21 34494 8192000000 10281419472 10735042560 1360166528 14.239 98 35 70 1 1375350576 6820448 8363600
00:29:56 70799 8192000000 10285652832 10751373312 1370997808 14.336 99 11 22 0 1385682688 12281312 2403568
00:32:16 70671 8192000000 10278637712 10742038528 1375043488 14.372 99 18 34 0 1388071040 8460592 4566960
00:34:40 60270 8192000000 10268056624 10732339200 1344327728 14.096 97 11 22 0 1356937760 10368880 2241152
00:36:42 47331 8192000000 10257987488 10722734080 1311807296 13.802 95 18 36 0 1327740816 12209488 3724032
00:38:30 23982 8192000000 10273431296 10722697216 1337401744 14.034 98 26 52 0 1354439296 11479664 5557888
00:39:56 68830 8192000000 10268510064 10718638080 1308826752 13.775 95 9 18 1 1320014832 9492608 1695472
00:42:10 55587 8192000000 10267314512 10725900288 1312273792 13.807 96 22 44 0 1328374432 11222496 4878144
00:44:11 47168 8192000000 10292093840 10746503168 1374457152 14.367 100 23 46 0 1394750624 15227024 5066448
00:45:58 166936 8192000000 10255225424 10702811136 1268190192 13.405 93 11 22 0 1287917056 17492480 2234384
00:49:57 124259 8192000000 10260148464 10704293888 1321789600 13.893 97 28 56 0 1340954992 12514848 6650544
00:53:02 27987 8192000000 10273172960 10717212672 1362244048 14.257 100 32 64 0 1377905904 8819728 6842128
00:54:33 41212 8192000000 10267835024 10724933632 1358661840 14.225 99 21 41 2 1373591968 10124416 4805712
00:56:15 58049 8192000000 10277100656 10735529984 1364099568 14.274 99 11 22 0 1378533504 11905856 2528080
00:58:13 35195 8192000000 10274938560 10738630656 1364647872 14.279 99 16 32 0 1381009216 12581904 3779440
00:59:49 62311 8192000000 10297677488 10753499136 1387928672 14.487 100 9 18 0 1404369520 14246480 2194368
01:01:52 68580 8192000000 10288394368 10757177344 1353393200 14.178 98 14 28 0 1374265424 17187232 3684992
01:04:01 95776 8192000000 10297867456 10759626752 1348741264 14.136 97 26 52 1 1366003808 10788064 6474480
01:06:38 55981 8192000000 10305586864 10766868480 1379207312 14.409 99 17 34 0 1396522320 13337344 3977664
01:08:38 55324 8192000000 10304819920 10755489792 1373125760 14.355 98 24 48 0 1387057232 8678848 5252624
01:10:59 21628 8192000000 10332894752 10785837056 1407466256 14.661 100 31 62 0 1432111264 17763808 6881200
01:12:20 103064 8192000000 10301947792 10754260992 1390282848 14.508 99 19 38 1 1405017984 10462576 4272560
01:15:04 29368 8192000000 10313621264 10759122944 1361376320 14.250 97 31 62 0 1381075552 13094992 6604240
01:16:35 43782 8192000000 10281097888 10749317120 1372467088 14.349 98 10 20 0 1383286464 8579568 2239808
01:18:19 67391 8192000000 10258385248 10715189248 1307855712 13.767 94 13 26 0 1322649744 12043328 2750704
01:20:27 46956 8192000000 10269888016 10724642816 1341099424 14.067 98 19 38 0 1355956064 9823088 5033552
01:22:19 74052 8192000000 10257864864 10728701952 1302791152 13.721 95 22 44 0 1323407200 15569248 5046800
01:24:42 31187 8192000000 10255781840 10699714560 1283119280 13.541 94 34 66 1 1298555040 8383072 7052688
01:26:17 42071 8192000000 10251391632 10697269248 1305244112 13.743 97 32 64 0 1325779808 13099936 7435760
01:28:02 66655 8192000000 10270282608 10721083392 1332150016 13.987 98 24 48 0 1348207664 10822128 5235520
01:30:15 62488 8192000000 10271195600 10709229568 1342637200 14.081 99 23 47 0 1362186256 14391920 5157136
01:32:18 60922 8192000000 10258629280 10702143488 1316735072 13.847 97 13 26 1 1331967920 12790576 2442272
01:34:19 88268 8192000000 10218327520 10664058880 1284127328 13.551 96 23 46 0 1299504240 10501776 4875136
01:36:48 51866 8192000000 10237267232 10689478656 1316817696 13.848 98 20 39 1 1332092096 10602544 4671856

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 21-01-2023 01:41:21 to 21-01-2023 01:41:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 158 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-01-2023 01:41:38 to 21-01-2023 01:41:40.

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          254179
MON$OLDEST_ACTIVE               255995
MON$OLDEST_SNAPSHOT             255995
MON$NEXT_TRANSACTION            255995
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                       2046816
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.33654 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33654 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33654 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33654 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 ---
--- end of diff output ---

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

21.01.23 01:41:41. 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
2023-01-21 01:41:43 No severe PSQL-related problems occured
21.01.23 01:41:44. End of report.