Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.01.2023 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 15415208 kB
MemAvailable 30459236 kB
Buffers 0 kB
Cached 16320308 kB
SwapCached 3668 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-30 16:38:37
DTS_END                         2023-01-30 16:38:37.0850
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/35104
TRN_ID                          236829
ATT_ID                          713
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: 9354.09 28223 1122491
customer order: creation 166.48 323 19978
customer order: refuse 14.10 328 1692
order to supplier: creation 45.68 1180 5481
order to supplier: removal 3.48 98044 418
invoice (draft): creation 45.17 3115 5420
invoice (draft): removal 54.09 699 6491
invoice accept: apply 41.44 16865 4973
invoice accept: cancel 48.87 714 5864
customer reserve: creation 3840.86 166 460903
customer reserve: removal 4795.33 57 575440
realization accept: apply 79.92 149 9590
realization accept: cancel 91.23 101 10948
payment from customer: creation 57.78 103 6934
payment from customer: removal 5.62 84 674
payment to supplier: creation 48.33 131 5799
payment to supplier: removal 3.39 101 407
service: total inventory turnovers 4.97 923 597
service: total monetary turnovers 6.54 31 785
service: aggregate perf. data 0.78 22557 93
service: refresh index statistics 0.03 418796 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-01-2023 16:40:39 to 30-01-2023 16:40:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3732
TEST_TIME 2 3652
TEST_TIME 3 4185
TEST_TIME 4 4805
TEST_TIME 5 5085
TEST_TIME 6 5657
TEST_TIME 7 5327
TEST_TIME 8 5087
TEST_TIME 9 4922
TEST_TIME 10 4872
TEST_TIME 11 5226
TEST_TIME 12 5517
TEST_TIME 13 5630
TEST_TIME 14 6301
TEST_TIME 15 6632
TEST_TIME 16 6694
TEST_TIME 17 6851
TEST_TIME 18 7379
TEST_TIME 19 7632
TEST_TIME 20 7997
TEST_TIME 21 7769
TEST_TIME 22 8960
TEST_TIME 23 9024
TEST_TIME 24 9316
TEST_TIME 25 9849
TEST_TIME 26 10168
TEST_TIME 27 10872
TEST_TIME 28 10575
TEST_TIME 29 10273
TEST_TIME 30 10311
TEST_TIME 31 10269
TEST_TIME 32 10028
TEST_TIME 33 10093
TEST_TIME 34 9957
TEST_TIME 35 9810
TEST_TIME 36 9793
TEST_TIME 37 9867
TEST_TIME 38 9680
TEST_TIME 39 9555
TEST_TIME 40 9521
TEST_TIME 41 9838
TEST_TIME 42 9777
TEST_TIME 43 9670
TEST_TIME 44 9669
TEST_TIME 45 9655
TEST_TIME 46 9575
TEST_TIME 47 9642
TEST_TIME 48 9644
TEST_TIME 49 9690
TEST_TIME 50 9654
TEST_TIME 51 9717
TEST_TIME 52 9718
TEST_TIME 53 9745
TEST_TIME 54 9621
TEST_TIME 55 9512
TEST_TIME 56 9512
TEST_TIME 57 9451
TEST_TIME 58 9450
TEST_TIME 59 9405
TEST_TIME 60 9352
TEST_TIME 61 9260
TEST_TIME 62 9185
TEST_TIME 63 9166
TEST_TIME 64 9242
TEST_TIME 65 9266
TEST_TIME 66 9335
TEST_TIME 67 9295
TEST_TIME 68 9286
TEST_TIME 69 9269
TEST_TIME 70 9326
TEST_TIME 71 9391
TEST_TIME 72 9344
TEST_TIME 73 9306
TEST_TIME 74 9419
TEST_TIME 75 9450
TEST_TIME 76 9423
TEST_TIME 77 9391
TEST_TIME 78 9292
TEST_TIME 79 9235
TEST_TIME 80 9228
TEST_TIME 81 9201
TEST_TIME 82 9144
TEST_TIME 83 9148
TEST_TIME 84 9116
TEST_TIME 85 9159
TEST_TIME 86 9142
TEST_TIME 87 9176
TEST_TIME 88 9193
TEST_TIME 89 9286
TEST_TIME 90 9288
TEST_TIME 91 9345
TEST_TIME 92 9369
TEST_TIME 93 9377
TEST_TIME 94 9417
TEST_TIME 95 9470
TEST_TIME 96 9450
TEST_TIME 97 9405
TEST_TIME 98 9340
TEST_TIME 99 9325
TEST_TIME 100 9304
TEST_TIME 101 9239
TEST_TIME 102 9189
TEST_TIME 103 9205
TEST_TIME 104 9155
TEST_TIME 105 9154
TEST_TIME 106 9176
TEST_TIME 107 9180
TEST_TIME 108 9187
TEST_TIME 109 9213
TEST_TIME 110 9201
TEST_TIME 111 9242
TEST_TIME 112 9261
TEST_TIME 113 9282
TEST_TIME 114 9335
TEST_TIME 115 9384
TEST_TIME 116 9475
TEST_TIME 117 9466
TEST_TIME 118 9473
TEST_TIME 119 9404
TEST_TIME 120 9357

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 30-01-2023 16:40:42 to 30-01-2023 16:40:46.

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 1547899 1547899 0 0.00 0 13698 6 0 0
doc_list_biud 1547899 1547899 0 0.00 0 69542 26 0 0
sp_add_invoice_to_stock 5057 4973 84 1.66 30 289074 16865 0 84
sp_cancel_adding_invoice 5864 5864 0 0.00 2 85573 715 0 0
sp_cancel_client_order 1692 1692 0 0.00 4 8652 328 0 0
sp_cancel_customer_reserve 575440 575440 0 0.00 0 34868 57 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 2 5609 84 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 2 6878 101 0 0
sp_cancel_supplier_invoice 6500 6491 9 0.13 1 69556 700 0 9
sp_cancel_supplier_order 418 418 0 0.00 7 1218385 98044 0 0
sp_cancel_write_off 10948 10948 0 0.00 1 10790 101 0 0
sp_client_order 19978 19978 0 0.00 3 34191 324 0 0
sp_customer_reserve 460903 460903 0 0.00 0 49222 167 0 0
sp_fill_shopping_cart 30865 30865 0 0.00 1 12953 41 0 0
sp_fill_shopping_cart_clo_res 460901 460901 0 0.00 0 20281 13 0 0
sp_get_clo_for_invoice 4964 4964 0 0.00 0 29922 313 0 0
sp_kill_cost_storno 17893 17893 0 0.00 0 8525 47 0 0
sp_kill_qstorno_ret_qs2qd 1168737 1168737 0 0.00 0 69536 31 0 0
sp_kill_qty_storno 615404 615404 0 0.00 0 69539 65 0 0
sp_lock_selected_doc 20254 20254 0 0.00 0 8145 7 0 0
sp_make_cost_storno 27284 27284 0 0.00 0 11812 43 0 0
sp_multiply_rows_for_pdistr 27284 27284 0 0.00 0 8663 25 0 0
sp_multiply_rows_for_qdistr 19978 19978 0 0.00 0 30704 132 0 0
sp_pay_from_customer 6934 6934 0 0.00 4 16098 103 0 0
sp_pay_to_supplier 5799 5799 0 0.00 4 22272 131 0 0
sp_payment_common 12733 12733 0 0.00 1 15562 25 0 0
sp_qd_handle_on_cancel_clo 1692 1692 0 0.00 0 8644 299 0 0
sp_qd_handle_on_invoice_upd_sts 10825 10825 0 0.00 0 35686 304 0 0
sp_qd_handle_on_reserve_upd_sts 9590 9590 0 0.00 0 16128 45 0 0
sp_reserve_write_off 9590 9590 0 0.00 6 17184 150 0 0
sp_supplier_invoice 5420 5420 0 0.00 5 67476 3115 0 0
sp_supplier_order 5481 5481 0 0.00 5 31881 1180 0 0
srv_aggregate_perf_data 93 93 0 0.00 2530 468918 22557 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1430 214325 49399 0 0
srv_find_qd_qs_mism 1074752 1074752 0 0.00 0 13696 6 0 0
srv_make_invnt_saldo 597 597 0 0.00 29 60536 923 0 0
srv_make_money_saldo 785 785 0 0.00 1 2564 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 259600 747352 418796 0 0
t$perf-abend:sp_add_invoice_to_stock 84 84 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 4973 4973 0 0.00 0 8847 156 0 0
t$perf-norm:sp_cancel_adding_invoice 428 428 0 0.00 0 4005 90 0 0
t$perf-norm:sp_cancel_client_order 1692 1692 0 0.00 0 3145 12 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 927 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 6208 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 874 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 871 16 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 27853 1405 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 1411 6 0 0
t$perf-norm:sp_client_order 19978 19978 0 0.00 0 7557 16 0 0
t$perf-norm:sp_customer_reserve 1010 1010 0 0.00 0 843 4 0 0
t$perf-norm:sp_pay_from_customer 6934 6934 0 0.00 0 6026 10 0 0
t$perf-norm:sp_pay_to_supplier 5799 5799 0 0.00 0 2055 6 0 0
t$perf-norm:sp_reserve_write_off 9590 9590 0 0.00 0 6481 8 0 0
t$perf-norm:sp_supplier_invoice 5420 5420 0 0.00 0 2764 14 0 0
t$perf-norm:sp_supplier_order 5481 5481 0 0.00 0 3716 13 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 78 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 1203 26 0 0
t$perf-norm:srv_make_invnt_saldo 597 597 0 0.00 0 1693 17 0 0
t$perf-norm:srv_make_money_saldo 785 785 0 0.00 0 638 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10090 9922 168 1.66 0 16859 18 0 168
v_all_customers 36614 36614 0 0.00 0 24 1 0 0
v_all_suppliers 30236 30236 0 0.00 0 129 1 0 0
v_all_wares 39956 39956 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 856 856 0 0.00 0 765 7 0 0
v_cancel_client_order 3384 3384 0 0.00 0 5167 6 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 5141 9 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 16859 16 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 1277 4 0 0
v_cancel_supplier_invoice 836 818 18 2.15 0 6519 21 0 18
v_cancel_supplier_order 836 836 0 0.00 0 16883 36 0 0
v_cancel_write_off 1960 1960 0 0.00 0 7467 20 0 0
v_max_id_clo_ord 5481 5481 0 0.00 0 2415 2 0 0
v_max_id_clo_res 1010 1010 0 0.00 0 5580 19 0 0
v_max_id_ord_sup 5406 5406 0 0.00 0 7132 9 0 0
v_max_non_paid_invoice 1568 1568 0 0.00 0 5476 6 0 0
v_max_non_paid_realizn 3647 3647 0 0.00 0 5279 5 0 0
v_min_id_clo_ord 5481 5481 0 0.00 0 6795 7 0 0
v_min_id_clo_res 1010 1010 0 0.00 0 16044 21 0 0
v_min_id_ord_sup 5406 5406 0 0.00 0 5702 14 0 0
v_min_non_paid_invoice 5799 5799 0 0.00 0 15497 18 0 0
v_min_non_paid_realizn 6934 6934 0 0.00 0 12802 18 0 0
v_our_firm 9916 9916 0 0.00 0 17 1 0 0
v_reserve_write_off 19180 19180 0 0.00 0 16861 10 0 0
x_lock_depdocs_on_canc_invoice 11946 11946 0 0.00 0 19583 114 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 2840 304 0 0
x_make_qstorno_1000_1200 5481 5481 0 0.00 2 31876 1109 0 0
x_make_qstorno_1200_2000 5420 5420 0 0.00 2 63447 2955 0 0
x_make_qstorno_2100_3300 460903 460903 0 0.00 0 49193 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-01-2023 16:40:47 to 30-01-2023 16:40:51.

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:41 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:41 32934 8192000000 9908684304 10223230976 991035568 10.792 100 91 182 0 1021500656 6301904 24163184
14:11:20 21120 8192000000 9957743616 10279104512 1031726288 11.185 100 87 174 0 1062151424 8121040 22304096
14:12:43 13843 8192000000 9996384240 10330562560 1080834880 11.655 100 85 170 0 1111691520 9735056 21121584
14:14:01 8464 8192000000 10039783792 10386735104 1130842208 12.129 100 80 159 0 1160246432 11241920 18162304
14:15:16 6013 8192000000 10088785504 10450964480 1176826352 12.561 100 83 166 0 1208158720 12329664 19002704
14:16:24 3160 8192000000 10127464784 10494566400 1211815680 12.886 100 85 170 0 1244363856 13605008 18943168
14:17:30 2689 8192000000 10147756288 10522599424 1237129936 13.120 100 79 155 1 1266116592 12763760 16222896
14:18:35 2468 8192000000 10182858464 10560454656 1265832560 13.383 100 79 156 0 1296067184 13779376 16455248
14:19:39 848 8192000000 10205554848 10586697728 1286046016 13.568 100 93 189 0 1319815488 13018128 20751344
14:20:43 1155 8192000000 10201753344 10593202176 1293351936 13.635 100 79 159 1 1321935056 10629184 17953936
14:21:46 4622 8192000000 10191611088 10600071168 1303066944 13.723 100 37 74 0 1317702736 5640624 8995168
14:22:54 13646 8192000000 10191077264 10607263744 1309141216 13.778 100 13 26 0 1316943072 5240992 2560864
14:24:08 11818 8192000000 10206291200 10628157440 1326599952 13.936 100 16 32 0 1336017552 5352432 4065168
14:25:22 58771 8192000000 10236738992 10647494656 1333355024 13.997 100 43 86 0 1349302256 5759632 10187600
14:27:21 3866 8192000000 10236053344 10648465408 1335340224 14.015 100 42 83 1 1350748928 5881872 9526832
14:28:27 14066 8192000000 10169892928 10578018304 1235949616 13.109 94 30 61 1 1248223872 5620928 6653328
14:29:41 16272 8192000000 10188612816 10597830656 1233403296 13.085 94 21 42 0 1243277760 5076160 4798304
14:31:00 4334 8192000000 10168434608 10571878400 1265072144 13.376 99 29 58 0 1277995872 5722544 7201184
14:32:06 6140 8192000000 10146394112 10545094656 1244194736 13.185 99 23 48 1 1255728288 6216752 5316800
14:33:15 52256 8192000000 10155029888 10563891200 1271253664 13.433 100 27 54 0 1282595936 5578240 5764032
14:35:13 6445 8192000000 10167637664 10584064000 1285787968 13.566 100 20 40 0 1295794944 5787968 4219008
14:36:22 8897 8192000000 10193725792 10603536384 1299468288 13.690 100 14 29 0 1307550768 4896576 3185904
14:37:31 63341 8192000000 10209720096 10623315968 1311018032 13.795 100 18 34 1 1320290768 5018848 4253888
14:39:41 7138 8192000000 10244869520 10653728768 1339582608 14.054 100 54 101 2 1358031680 7207648 11241424
14:40:54 20328 8192000000 10274826736 10686648320 1352661248 14.171 100 57 111 1 1377931232 13170768 12099216
14:42:21 9704 8192000000 10284944368 10701213696 1368159232 14.311 100 53 104 1 1397292208 17580912 11552064
14:43:34 12685 8192000000 10292717488 10712952832 1366707584 14.298 99 47 94 0 1400328288 22972864 10647840
14:45:00 22673 8192000000 10308924144 10735816704 1394147968 14.543 100 42 84 0 1426853376 22397600 10307808
14:46:28 10039 8192000000 10304624976 10724700160 1374085296 14.364 98 51 99 0 1409480560 23306624 12088640
14:47:42 7905 8192000000 10319066144 10740461568 1389091888 14.498 99 42 79 0 1424799984 25593856 10114240
14:48:54 5770 8192000000 10336755072 10754826240 1401342624 14.607 99 67 130 0 1448729520 31334128 16052768
14:50:06 8740 8192000000 10361240000 10776604672 1418464992 14.759 100 66 132 0 1471254624 36890400 15899232
14:51:20 5895 8192000000 10353949696 10765193216 1389613424 14.502 99 77 153 2 1450586496 41991312 18981760
14:52:36 3106 8192000000 10341644032 10754883584 1383089360 14.444 99 69 136 0 1441929968 42102736 16737872
14:53:47 2281 8192000000 10348391504 10756063232 1387987344 14.488 99 80 155 1 1446302992 39525376 18790272
14:54:54 2915 8192000000 10370860256 10779054080 1409118480 14.676 100 80 157 1 1475964240 46196672 20649088
14:56:04 1787 8192000000 10367314064 10776330240 1400620320 14.601 100 84 166 1 1473556176 51109584 21826272
14:57:12 14592 8192000000 10328938288 10747408384 1368293216 14.312 99 38 72 1 1421687824 43253808 10140800
14:58:46 19288 8192000000 10345143024 10773852160 1394073488 14.542 100 43 86 0 1455622496 49336496 12212512
15:00:22 14069 8192000000 10329066496 10759041024 1372684144 14.351 100 46 92 0 1442300624 57037376 12579104
15:01:38 24622 8192000000 10303490080 10736259072 1351794272 14.164 99 30 60 0 1399961760 39500720 8666768
15:03:14 12682 8192000000 10278544176 10728251392 1341442624 14.070 99 19 38 0 1388810352 41866736 5500992
15:04:28 24921 8192000000 10282544320 10723074048 1331326832 13.979 99 21 42 0 1379560928 42751360 5482736
15:06:20 98859 8192000000 10268020912 10711568384 1357666048 14.216 100 33 64 1 1377158352 11460096 8032208
15:09:02 57304 8192000000 10274904640 10723782656 1357374176 14.214 99 16 32 0 1371189600 10328816 3486608
15:11:04 83351 8192000000 10260642768 10717089792 1331727712 13.983 97 11 22 0 1347427824 13567680 2132432
15:13:29 79397 8192000000 10273136304 10723045376 1346447632 14.116 98 19 39 0 1370299136 19320128 4531376
15:15:49 51891 8192000000 10280131280 10727256064 1363838928 14.272 99 16 32 1 1383767872 16438400 3490544
15:18:08 16813 8192000000 10283208224 10728505344 1362273088 14.258 99 33 69 1 1387154992 17563120 7318784
15:19:57 56989 8192000000 10279672864 10732986368 1358655024 14.225 99 11 22 0 1378387824 17524608 2208192
15:21:59 34375 8192000000 10289163168 10733248512 1374501872 14.367 100 38 76 2 1395748464 12420944 8825648
15:23:38 63967 8192000000 10292181616 10741436416 1352766048 14.172 98 19 38 0 1366271312 9499584 4005680
15:25:49 97603 8192000000 10285684784 10758230016 1358440464 14.223 98 20 40 0 1379197312 16390224 4366624
15:28:27 19136 8192000000 10304267872 10744729600 1389552464 14.502 100 31 58 0 1404703328 8835728 6315136
15:29:50 120003 8192000000 10299600336 10749071360 1366126400 14.292 98 16 32 1 1380896848 11540992 3229456
15:32:52 131344 8192000000 10304236448 10751660032 1365940896 14.291 98 22 44 0 1384779824 13470800 5368128
15:36:04 1430 8192000000 10307780416 10750132224 1395274736 14.553 100 45 86 3 1413584016 9140240 9169040
15:37:07 44761 8192000000 10277562880 10739601408 1377746464 14.396 99 15 28 1 1388090960 7391840 2952656
15:38:52 62990 8192000000 10280381744 10741407744 1384168944 14.454 99 10 18 0 1392470816 6377904 1923968
15:41:04 64323 8192000000 10271766144 10734637056 1302270448 13.716 94 12 24 0 1317152656 12797472 2084736
15:43:37 49632 8192000000 10275670880 10735026176 1339919360 14.057 97 17 34 0 1353650256 9757024 3973872
15:46:02 42338 8192000000 10264754208 10707288064 1315633616 13.837 96 35 70 0 1332902992 9715696 7553680
15:47:49 18234 8192000000 10267606368 10700120064 1346940112 14.120 100 41 82 2 1370672048 14767216 8964720
15:49:14 98753 8192000000 10264975056 10694483968 1288710528 13.592 95 25 50 0 1305961344 11694944 5555872
15:51:53 56491 8192000000 10264724128 10713370624 1356039808 14.202 100 38 76 0 1380152704 15396624 8716272
15:54:17 83981 8192000000 10224471472 10668630016 1259703776 13.327 93 21 42 2 1276312448 12067776 4540896
15:57:26 64725 8192000000 10258290736 10709364736 1354153824 14.185 100 21 42 0 1367812720 8613984 5044912
16:00:35 59813 8192000000 10241793552 10695393280 1317092144 13.850 97 10 20 0 1326928464 7633568 2202752
16:02:53 53411 8192000000 10262665792 10721447936 1364724368 14.280 100 14 28 0 1380428864 12286016 3418480
16:04:47 54518 8192000000 10265402064 10717061120 1324974800 13.922 97 18 37 0 1350826288 21476512 4374976
16:06:42 47380 8192000000 10252195360 10715742208 1334699824 14.010 98 24 48 1 1360816128 19565072 6551232
16:08:34 97370 8192000000 10272328560 10723360768 1350093280 14.148 98 11 22 0 1362560976 10073024 2394672
16:11:20 95852 8192000000 10288432528 10750619648 1325602224 13.927 95 9 18 0 1341207456 13698496 1906736
16:13:56 92764 8192000000 10285234848 10739585024 1360653776 14.243 98 8 14 0 1376216960 14293616 1269568
16:16:29 38740 8192000000 10282059136 10738450432 1380941984 14.425 100 24 48 0 1401692144 15410592 5339568
16:18:21 41457 8192000000 10287060640 10755002368 1389027120 14.497 100 10 20 0 1400459728 9485936 1946672
16:20:18 37480 8192000000 10281099440 10739687424 1377531344 14.394 99 17 34 0 1395053536 13907296 3614896
16:21:59 61233 8192000000 10287064032 10746105856 1376081520 14.382 99 16 33 0 1389649392 9870464 3697408
16:24:02 59441 8192000000 10291425392 10761162752 1394467968 14.546 100 8 16 0 1411683040 15090912 2124160
16:26:11 73237 8192000000 10289757088 10757595136 1333176576 13.996 95 12 24 2 1348223168 12071856 2974736
16:28:46 59986 8192000000 10302621696 10763636736 1386435552 14.474 99 15 30 0 1400288576 10293856 3559168
16:30:47 147033 8192000000 10272182528 10719420416 1231715984 13.070 88 18 34 1 1252318912 16181760 4421168
16:34:14 214325 8192000000 10262617568 10723061760 1268442640 13.407 92 10 20 0 1288444448 17658128 2343680

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-01-2023 16:40:53 to 30-01-2023 16:41:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 84 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 30-01-2023 16:41:09 to 30-01-2023 16:41:11.

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          235888
MON$OLDEST_ACTIVE               237169
MON$OLDEST_SNAPSHOT             237169
MON$NEXT_TRANSACTION            237169
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                       2024384
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.33657 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33657 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33657 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33657 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 ---
:1000: 
:1001: mscs-test-161.budzdorov.ru    Mon Jan 30 16:38:53 2023
:1002:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1003: 
--- end of diff output ---

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

30.01.23 16:41:12. 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-30 16:41:14 No severe PSQL-related problems occured
30.01.23 16:41:16. End of report.