Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.12.2022 16:39
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 15152852 kB
MemAvailable 30105208 kB
Buffers 0 kB
Cached 16571892 kB
SwapCached 3288 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-12-12 16:38:31
DTS_END                         2022-12-12 16:38:31.8000
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/48842
TRN_ID                          237444
ATT_ID                          673
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: 9074.78 20447 1088974
customer order: creation 170.15 372 20418
customer order: refuse 15.05 343 1806
order to supplier: creation 44.16 1308 5299
order to supplier: removal 3.26 99477 391
invoice (draft): creation 45.95 3405 5514
invoice (draft): removal 50.94 699 6113
invoice accept: apply 41.90 17270 5028
invoice accept: cancel 46.03 774 5523
customer reserve: creation 3883.94 169 466073
customer reserve: removal 4487.58 57 538510
realization accept: apply 82.27 186 9872
realization accept: cancel 76.09 114 9131
payment from customer: creation 58.29 118 6995
payment from customer: removal 5.85 71 702
payment to supplier: creation 47.88 138 5746
payment to supplier: removal 3.62 83 434
service: total inventory turnovers 4.71 1050 565
service: total monetary turnovers 6.27 27 752
service: aggregate perf. data 0.82 21148 98
service: refresh index statistics 0.03 262129 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-12-2022 16:39:51 to 12-12-2022 16:39:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4499
TEST_TIME 2 4236
TEST_TIME 3 4714
TEST_TIME 4 4853
TEST_TIME 5 4658
TEST_TIME 6 4994
TEST_TIME 7 5360
TEST_TIME 8 5210
TEST_TIME 9 5385
TEST_TIME 10 5736
TEST_TIME 11 6301
TEST_TIME 12 6366
TEST_TIME 13 6434
TEST_TIME 14 6471
TEST_TIME 15 6451
TEST_TIME 16 6686
TEST_TIME 17 7162
TEST_TIME 18 7264
TEST_TIME 19 7524
TEST_TIME 20 7862
TEST_TIME 21 8285
TEST_TIME 22 8434
TEST_TIME 23 8431
TEST_TIME 24 8308
TEST_TIME 25 8736
TEST_TIME 26 9278
TEST_TIME 27 9599
TEST_TIME 28 9432
TEST_TIME 29 9650
TEST_TIME 30 9763
TEST_TIME 31 10033
TEST_TIME 32 10221
TEST_TIME 33 10137
TEST_TIME 34 10025
TEST_TIME 35 9878
TEST_TIME 36 9776
TEST_TIME 37 9867
TEST_TIME 38 9803
TEST_TIME 39 9676
TEST_TIME 40 9660
TEST_TIME 41 9653
TEST_TIME 42 9535
TEST_TIME 43 9452
TEST_TIME 44 9370
TEST_TIME 45 9309
TEST_TIME 46 9294
TEST_TIME 47 9185
TEST_TIME 48 9158
TEST_TIME 49 8987
TEST_TIME 50 9033
TEST_TIME 51 9013
TEST_TIME 52 8904
TEST_TIME 53 8883
TEST_TIME 54 8916
TEST_TIME 55 8851
TEST_TIME 56 8828
TEST_TIME 57 8800
TEST_TIME 58 8816
TEST_TIME 59 8817
TEST_TIME 60 8950
TEST_TIME 61 8993
TEST_TIME 62 9011
TEST_TIME 63 9037
TEST_TIME 64 9056
TEST_TIME 65 9045
TEST_TIME 66 9145
TEST_TIME 67 9116
TEST_TIME 68 9224
TEST_TIME 69 9262
TEST_TIME 70 9272
TEST_TIME 71 9293
TEST_TIME 72 9180
TEST_TIME 73 9194
TEST_TIME 74 9193
TEST_TIME 75 9127
TEST_TIME 76 9124
TEST_TIME 77 9060
TEST_TIME 78 8990
TEST_TIME 79 9073
TEST_TIME 80 8995
TEST_TIME 81 8986
TEST_TIME 82 9026
TEST_TIME 83 9066
TEST_TIME 84 9062
TEST_TIME 85 9043
TEST_TIME 86 9074
TEST_TIME 87 9110
TEST_TIME 88 9085
TEST_TIME 89 9151
TEST_TIME 90 9182
TEST_TIME 91 9191
TEST_TIME 92 9203
TEST_TIME 93 9218
TEST_TIME 94 9277
TEST_TIME 95 9345
TEST_TIME 96 9288
TEST_TIME 97 9225
TEST_TIME 98 9193
TEST_TIME 99 9164
TEST_TIME 100 9179
TEST_TIME 101 9142
TEST_TIME 102 9101
TEST_TIME 103 9059
TEST_TIME 104 9031
TEST_TIME 105 9059
TEST_TIME 106 9053
TEST_TIME 107 9009
TEST_TIME 108 9087
TEST_TIME 109 9093
TEST_TIME 110 9085
TEST_TIME 111 9088
TEST_TIME 112 9091
TEST_TIME 113 9132
TEST_TIME 114 9148
TEST_TIME 115 9160
TEST_TIME 116 9178
TEST_TIME 117 9169
TEST_TIME 118 9168
TEST_TIME 119 9134
TEST_TIME 120 9083

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-12-2022 16:39:54 to 12-12-2022 16:39:58.

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 1517167 1517167 0 0.00 0 30420 6 0 0
doc_list_biud 1517167 1517167 0 0.00 0 25806 26 0 0
sp_add_invoice_to_stock 5158 5028 130 2.52 35 155522 17271 0 130
sp_cancel_adding_invoice 5523 5523 0 0.00 3 74009 774 0 0
sp_cancel_client_order 1806 1806 0 0.00 4 13655 344 0 0
sp_cancel_customer_reserve 538510 538510 0 0.00 0 25811 57 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 3 2525 72 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 2 4518 83 0 0
sp_cancel_supplier_invoice 6122 6113 9 0.14 1 21744 699 0 9
sp_cancel_supplier_order 391 391 0 0.00 9 973631 99477 0 0
sp_cancel_write_off 9131 9131 0 0.00 1 11111 115 0 0
sp_client_order 20418 20418 0 0.00 3 22091 373 0 0
sp_customer_reserve 466073 466073 0 0.00 0 43896 170 0 0
sp_fill_shopping_cart 31227 31227 0 0.00 1 22663 41 0 0
sp_fill_shopping_cart_clo_res 466067 466067 0 0.00 0 17559 14 0 0
sp_get_clo_for_invoice 5022 5022 0 0.00 0 25047 332 0 0
sp_kill_cost_storno 15790 15790 0 0.00 0 15979 55 0 0
sp_kill_qstorno_ret_qs2qd 1092655 1092655 0 0.00 0 25800 31 0 0
sp_kill_qty_storno 576368 576368 0 0.00 0 25805 67 0 0
sp_lock_selected_doc 20658 20658 0 0.00 0 9463 4 0 0
sp_make_cost_storno 27635 27635 0 0.00 0 29484 53 0 0
sp_multiply_rows_for_pdistr 27635 27635 0 0.00 0 22065 27 0 0
sp_multiply_rows_for_qdistr 20418 20418 0 0.00 0 13689 154 0 0
sp_pay_from_customer 6995 6995 0 0.00 3 22074 119 0 0
sp_pay_to_supplier 5746 5746 0 0.00 4 13371 139 0 0
sp_payment_common 12741 12741 0 0.00 2 10400 28 0 0
sp_qd_handle_on_cancel_clo 1806 1806 0 0.00 0 13649 315 0 0
sp_qd_handle_on_invoice_upd_sts 10545 10545 0 0.00 0 22161 341 0 0
sp_qd_handle_on_reserve_upd_sts 9872 9872 0 0.00 0 16651 66 0 0
sp_reserve_write_off 9872 9872 0 0.00 6 23332 187 0 0
sp_supplier_invoice 5514 5514 0 0.00 7 82182 3406 0 0
sp_supplier_order 5299 5299 0 0.00 6 45227 1309 0 0
srv_aggregate_perf_data 98 98 0 0.00 2289 187106 21149 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 830 124285 45859 0 0
srv_find_qd_qs_mism 1039833 1039833 0 0.00 0 25159 5 0 0
srv_make_invnt_saldo 565 565 0 0.00 28 56825 1050 0 0
srv_make_money_saldo 752 752 0 0.00 0 581 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 127467 448126 262130 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 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 5031 5031 0 0.00 0 16496 201 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 8930 150 0 0
t$perf-norm:sp_cancel_client_order 1806 1806 0 0.00 0 5243 15 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 4425 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 4294 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 8713 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 7431 49 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 25521 1603 0 0
t$perf-norm:sp_cancel_write_off 965 965 0 0.00 0 4402 9 0 0
t$perf-norm:sp_client_order 20418 20418 0 0.00 0 8481 17 0 0
t$perf-norm:sp_customer_reserve 1010 1010 0 0.00 0 1347 5 0 0
t$perf-norm:sp_pay_from_customer 6995 6995 0 0.00 0 6848 12 0 0
t$perf-norm:sp_pay_to_supplier 5746 5746 0 0.00 0 6209 11 0 0
t$perf-norm:sp_reserve_write_off 9872 9872 0 0.00 0 8676 16 0 0
t$perf-norm:sp_supplier_invoice 5514 5514 0 0.00 0 6208 15 0 0
t$perf-norm:sp_supplier_order 5299 5299 0 0.00 0 9636 21 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 114 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1180 36 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 2244 21 0 0
t$perf-norm:srv_make_money_saldo 752 752 0 0.00 0 2510 30 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 30 9 0 0
v_add_invoice_to_stock 10304 10044 260 2.52 0 9885 25 0 260
v_all_customers 36940 36940 0 0.00 0 21 1 0 0
v_all_suppliers 29846 29846 0 0.00 0 17 1 0 0
v_all_wares 40836 40836 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 3949 15 0 0
v_cancel_client_order 3612 3612 0 0.00 0 2770 5 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 1564 5 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 2317 5 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 4496 16 0 0
v_cancel_supplier_invoice 762 744 18 2.36 0 4286 21 0 18
v_cancel_supplier_order 782 782 0 0.00 0 2537 12 0 0
v_cancel_write_off 1930 1930 0 0.00 0 2700 13 0 0
v_max_id_clo_ord 5299 5299 0 0.00 0 2547 2 0 0
v_max_id_clo_res 1010 1010 0 0.00 0 1659 12 0 0
v_max_id_ord_sup 5510 5510 0 0.00 0 5001 6 0 0
v_max_non_paid_invoice 1632 1632 0 0.00 0 579 3 0 0
v_max_non_paid_realizn 3815 3815 0 0.00 0 3655 5 0 0
v_min_id_clo_ord 5299 5299 0 0.00 0 6234 9 0 0
v_min_id_clo_res 1010 1010 0 0.00 0 6245 10 0 0
v_min_id_ord_sup 5510 5510 0 0.00 0 5985 10 0 0
v_min_non_paid_invoice 5746 5746 0 0.00 0 10395 24 0 0
v_min_non_paid_realizn 6995 6995 0 0.00 0 6718 21 0 0
v_our_firm 10256 10256 0 0.00 0 15 1 0 0
v_reserve_write_off 19744 19744 0 0.00 0 9452 11 0 0
x_lock_depdocs_on_canc_invoice 11264 11264 0 0.00 0 13788 117 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 14985 382 0 0
x_make_qstorno_1000_1200 5299 5299 0 0.00 1 45095 1220 0 0
x_make_qstorno_1200_2000 5514 5514 0 0.00 2 82165 3263 0 0
x_make_qstorno_2100_3300 466073 466073 0 0.00 0 43895 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-12-2022 16:39:59 to 12-12-2022 16:40:03.

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:35 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:36 31607 8192000000 9905454816 10215510016 987467680 10.757 100 90 178 1 1017545088 6619792 23457616
14:11:17 13412 8192000000 9940362176 10262016000 1019546160 11.068 100 96 192 0 1053300016 8748288 25005568
14:12:48 15779 8192000000 9980012096 10315083776 1064937440 11.504 100 80 160 0 1094442112 9899472 19605200
14:14:11 8455 8192000000 10045022992 10395459584 1131583344 12.136 100 76 152 1 1160376768 10414000 18379424
14:15:24 5655 8192000000 10089964048 10457161728 1183289824 12.621 100 68 134 0 1210336624 11406256 15640544
14:16:32 4820 8192000000 10142026592 10514006016 1227713072 13.033 100 86 166 0 1257913472 11966800 18233600
14:17:39 3251 8192000000 10168456992 10546679808 1258328576 13.315 100 78 154 0 1288220160 13357232 16534352
14:18:45 2195 8192000000 10208401344 10590482432 1289341040 13.598 100 89 178 1 1323068960 13567616 20160304
14:19:49 5948 8192000000 10210943728 10607820800 1307016032 13.759 100 73 146 0 1336418128 13592592 15809504
14:21:02 3387 8192000000 10201691536 10607599616 1310708512 13.792 100 43 84 1 1329162688 9348128 9106048
14:22:07 21401 8192000000 10225531232 10628059136 1319461408 13.872 100 63 127 0 1345147792 11189504 14496880
14:23:30 8095 8192000000 10221110800 10630758400 1323168544 13.905 100 41 82 0 1339385728 7527136 8690048
14:24:40 3685 8192000000 10211494640 10628657152 1325560032 13.927 100 34 69 0 1339903376 6511600 7831744
14:25:49 53557 8192000000 10243079184 10650451968 1332790528 13.992 100 22 41 1 1343385536 5544464 5050544
14:27:43 6302 8192000000 10230691872 10651492352 1335197424 14.014 100 30 60 0 1348543728 7083056 6263248
14:28:52 9217 8192000000 10206979680 10623971328 1309004976 13.777 99 15 28 0 1317614704 5514128 3095600
14:30:04 21461 8192000000 10142852304 10546139136 1198576144 12.763 93 33 65 1 1210252864 4466160 7210560
14:31:30 7598 8192000000 10149202448 10554286080 1251913184 13.256 99 23 46 1 1263844928 6214512 5717232
14:32:38 6989 8192000000 10132587072 10529828864 1237273904 13.121 99 29 57 0 1250158576 5903344 6981328
14:33:47 9004 8192000000 10146170672 10543603712 1243193104 13.176 99 28 56 0 1255484960 5074064 7217792
14:34:58 58181 8192000000 10169722208 10584903680 1287411888 13.581 100 27 52 0 1298236448 4487424 6337136
14:37:00 6371 8192000000 10203483728 10606981120 1299225744 13.688 100 29 56 2 1311791856 5964992 6601120
14:38:07 12714 8192000000 10208734432 10625175552 1318793168 13.866 100 12 24 0 1325697664 4380752 2523744
14:39:29 11674 8192000000 10245917312 10658922496 1338915152 14.048 100 33 66 0 1355222816 9237440 7070224
14:40:44 51882 8192000000 10254614080 10674950144 1349303968 14.141 100 39 77 1 1368167248 9951392 8911888
14:42:43 17944 8192000000 10268769936 10691162112 1363408848 14.268 100 40 78 0 1385912688 12912208 9591632
14:44:10 33465 8192000000 10293110928 10716229632 1377428480 14.394 100 51 102 2 1408652432 19360256 11863696
14:46:10 13054 8192000000 10296425184 10722394112 1378452240 14.403 99 36 69 0 1402766592 15366112 8948240
14:47:28 3706 8192000000 10314476816 10741735424 1401455440 14.608 100 54 104 2 1427813520 13812208 12545872
14:48:35 1829 8192000000 10326326752 10750967808 1403151872 14.623 100 66 131 3 1436554080 18651088 14751120
14:49:44 2936 8192000000 10338005776 10760933376 1412716848 14.708 100 77 148 2 1449519392 19729680 17072864
14:50:54 6665 8192000000 10335576368 10764005376 1411328864 14.696 100 45 88 3 1444740176 22754000 10657312
14:52:06 3448 8192000000 10339518480 10753335296 1399333808 14.589 100 77 152 0 1442385104 24568864 18482432
14:53:13 7127 8192000000 10347489248 10758750208 1397861504 14.576 100 78 156 0 1447156912 30031280 19264128
14:54:26 6095 8192000000 10332550352 10746593280 1386651792 14.476 100 65 128 1 1438461120 36037632 15771696
14:55:41 4614 8192000000 10334668592 10757758976 1399912768 14.594 100 47 94 0 1442062288 29745104 12404416
14:56:58 10384 8192000000 10344375440 10760687616 1389091168 14.498 99 68 133 0 1437133328 31242816 16799344
14:58:23 1854 8192000000 10343467616 10749079552 1387494992 14.484 100 82 162 2 1443079408 34665776 20918640
14:59:29 7508 8192000000 10314464608 10741100544 1381520128 14.430 100 37 74 0 1423662112 32187488 9954496
15:00:46 11815 8192000000 10290217936 10712526848 1347698912 14.127 99 33 64 0 1390110944 33439760 8972272
15:02:05 8770 8192000000 10304641536 10719350784 1340665872 14.063 98 50 97 1 1391497056 37565680 13265504
15:03:18 829 8192000000 10312698208 10729062400 1353284176 14.177 100 51 102 0 1417677408 50968368 13424864
15:04:30 16611 8192000000 10306152656 10735046656 1340106704 14.058 99 32 64 0 1408542272 59155872 9279696
15:05:54 52060 8192000000 10306645728 10735890432 1359274896 14.231 100 20 40 1 1404079680 39152368 5652416
15:07:54 22965 8192000000 10315505296 10761240576 1370660256 14.333 100 37 74 0 1421403504 41753104 8990144
15:09:20 38383 8192000000 10290671840 10737590272 1351163184 14.158 99 17 34 0 1396182560 40486944 4532432
15:11:33 21873 8192000000 10284450448 10712121344 1371090128 14.337 100 40 75 1 1390623136 11040080 8492928
15:12:56 97960 8192000000 10293850720 10738540544 1382545760 14.439 100 28 56 0 1403660864 14696512 6418592
15:16:21 16999 8192000000 10303925376 10740346880 1385782912 14.468 100 49 99 1 1408525984 10395504 12347568
15:17:39 71340 8192000000 10285049232 10729865216 1358695136 14.226 98 22 45 0 1375326320 11611312 5019872
15:19:55 72993 8192000000 10290420128 10735337472 1369325152 14.321 99 21 43 0 1384737936 10524096 4888688
15:22:33 51653 8192000000 10286998000 10742337536 1390225648 14.508 100 21 40 0 1403657440 8956752 4475040
15:24:40 84257 8192000000 10300763152 10762407936 1403362480 14.625 100 12 24 0 1416005680 10094640 2548560
15:27:06 101461 8192000000 10292474576 10753224704 1401382000 14.607 100 17 33 1 1412673792 6705328 4586464
15:29:49 42771 8192000000 10297686896 10754871296 1401512784 14.608 100 14 28 0 1411957040 7438272 3005984
15:31:35 56503 8192000000 10297015248 10768076800 1405429088 14.643 100 15 30 0 1416677088 8200688 3047312
15:33:48 54338 8192000000 10286054176 10745339904 1389708224 14.503 99 15 30 0 1401823168 8604048 3510896
15:35:58 32336 8192000000 10294687776 10754473984 1383092368 14.444 99 7 14 0 1392331360 7720624 1518368
15:37:33 80432 8192000000 10294449728 10757910528 1358702896 14.226 96 12 24 0 1375694064 14109072 2882096
15:39:54 81161 8192000000 10293177728 10752159744 1358423920 14.223 96 13 26 0 1371475760 10171008 2880832
15:42:16 25284 8192000000 10316437488 10768687104 1407216736 14.659 100 45 88 0 1427464688 9911216 10336736
15:43:42 34578 8192000000 10291655344 10743599104 1326357488 13.934 94 24 48 0 1344557760 13180912 5019360
15:45:38 70603 8192000000 10297370256 10757558272 1373508576 14.358 98 17 34 0 1398239616 21127808 3603232
15:47:51 67220 8192000000 10259044656 10708766720 1278269024 13.497 92 18 36 0 1291208400 9273408 3665968
15:49:59 105946 8192000000 10256593232 10700328960 1328892208 13.957 98 27 54 0 1347019072 11413312 6713552
15:52:47 60742 8192000000 10268792896 10706423808 1338992016 14.048 98 29 58 0 1358853536 12680896 7180624
15:54:48 51290 8192000000 10249987744 10704486400 1322254096 13.897 97 10 21 1 1338058240 13430320 2373824
15:56:40 75946 8192000000 10248918976 10708623360 1315249024 13.834 97 17 34 0 1339573984 20038560 4286400
15:58:56 52078 8192000000 10218320608 10661998592 1262312176 13.351 94 21 42 0 1278655136 10713840 5629120
16:00:48 43769 8192000000 10231815584 10675449856 1304322704 13.735 98 24 48 1 1317547424 7689328 5535392
16:02:43 39331 8192000000 10274291424 10712731648 1360564528 14.242 100 29 58 0 1376854240 9816464 6473248
16:04:37 36039 8192000000 10248465360 10693603328 1327576448 13.945 98 17 34 0 1341974624 10919808 3478368
16:06:20 30933 8192000000 10271317104 10705551360 1345354016 14.106 99 34 68 0 1363055568 10253776 7447776
16:07:54 124285 8192000000 10269719168 10713780224 1303563328 13.728 95 15 30 0 1324163216 17499008 3100880
16:11:05 72929 8192000000 10306566000 10761224192 1380145568 14.418 100 10 21 0 1397211152 14980096 2085488
16:13:34 35733 8192000000 10297680480 10742452224 1351218960 14.158 97 15 30 0 1365761216 11423168 3119088
16:15:11 90978 8192000000 10285938304 10734575616 1360912896 14.246 98 26 52 0 1375521856 8791728 5817232
16:19:17 55475 8192000000 10281310144 10738700288 1375536128 14.377 99 11 23 1 1384595056 6553552 2505376
16:21:14 82268 8192000000 10299780704 10756087808 1390206864 14.508 99 21 42 0 1404157024 9304240 4645920
16:24:51 69018 8192000000 10312437376 10764353536 1400292080 14.598 100 15 30 0 1416735680 13257872 3185728
16:27:10 94483 8192000000 10290149680 10741817344 1303610096 13.728 93 4 8 0 1318015680 13798240 607344
16:29:45 40012 8192000000 10291817024 10733481984 1348912624 14.138 97 28 56 0 1365467808 10765376 5789808
16:31:35 88382 8192000000 10278040176 10728583168 1327214448 13.942 96 28 56 0 1344142592 10324848 6603296
16:34:07 91048 8192000000 10292656544 10731094016 1344048304 14.094 98 21 42 1 1357003872 8432112 4523456
16:36:39 103110 8192000000 10256261472 10701783040 1343479600 14.089 98 33 66 0 1361816752 10998736 7338416

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-12-2022 16:40:05 to 12-12-2022 16:40:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 130 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 12-12-2022 16:40:21 to 12-12-2022 16:40:23.

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          236587
MON$OLDEST_ACTIVE               237566
MON$OLDEST_SNAPSHOT             237566
MON$NEXT_TRANSACTION            237566
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                       2021504
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.33647 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33647 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33647 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33647 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 ---
:162: 
:163: mscs-test-161.budzdorov.ru    Mon Dec 12 16:38:33 2022
:164:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:165: 
:166: 
:167: mscs-test-161.budzdorov.ru    Mon Dec 12 16:38:36 2022
:168:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:169: 
:170: 
:171: mscs-test-161.budzdorov.ru    Mon Dec 12 16:38:36 2022
:172:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:173: 
--- end of diff output ---

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

12.12.22 16:40:24. 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-12-12 16:40:26 No severe PSQL-related problems occured
12.12.22 16:40:28. End of report.