Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.12.2021 16:46
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 16612484 kB
MemAvailable 31400976 kB
Buffers 0 kB
Cached 15076388 kB
SwapCached 444 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.9
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-12-23 16:45:10
DTS_END                         2021-12-23 16:45:10.8920
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_CLIENT_ORDER(32:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47484
TRN_ID                          239726
ATT_ID                          647
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: 9219.08 23663 1106289
customer order: creation 169.76 360 20371
customer order: refuse 14.58 344 1750
order to supplier: creation 44.44 1316 5333
order to supplier: removal 3.32 103709 398
invoice (draft): creation 45.27 3280 5432
invoice (draft): removal 53.30 825 6396
invoice accept: apply 41.49 17747 4979
invoice accept: cancel 48.68 827 5842
customer reserve: creation 3830.56 174 459667
customer reserve: removal 4663.18 57 559581
realization accept: apply 83.27 177 9992
realization accept: cancel 90.70 102 10884
payment from customer: creation 59.22 133 7106
payment from customer: removal 6.39 93 767
payment to supplier: creation 48.97 158 5876
payment to supplier: removal 3.33 142 400
service: total inventory turnovers 5.03 1081 603
service: total monetary turnovers 6.74 22 809
service: aggregate perf. data 0.83 24725 99
service: refresh index statistics 0.03 317993 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-12-2021 16:46:40 to 23-12-2021 16:46:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7722
TEST_TIME 2 8399
TEST_TIME 3 6633
TEST_TIME 4 6513
TEST_TIME 5 6720
TEST_TIME 6 6559
TEST_TIME 7 6311
TEST_TIME 8 6957
TEST_TIME 9 6837
TEST_TIME 10 7455
TEST_TIME 11 7205
TEST_TIME 12 7137
TEST_TIME 13 7250
TEST_TIME 14 7101
TEST_TIME 15 7438
TEST_TIME 16 7388
TEST_TIME 17 7795
TEST_TIME 18 8138
TEST_TIME 19 8460
TEST_TIME 20 8689
TEST_TIME 21 9085
TEST_TIME 22 9060
TEST_TIME 23 9100
TEST_TIME 24 9434
TEST_TIME 25 10096
TEST_TIME 26 10616
TEST_TIME 27 10682
TEST_TIME 28 10715
TEST_TIME 29 10947
TEST_TIME 30 11000
TEST_TIME 31 10754
TEST_TIME 32 10735
TEST_TIME 33 10654
TEST_TIME 34 10406
TEST_TIME 35 10469
TEST_TIME 36 10335
TEST_TIME 37 10377
TEST_TIME 38 10270
TEST_TIME 39 10072
TEST_TIME 40 10096
TEST_TIME 41 10068
TEST_TIME 42 10082
TEST_TIME 43 9917
TEST_TIME 44 9974
TEST_TIME 45 9855
TEST_TIME 46 9741
TEST_TIME 47 9617
TEST_TIME 48 9623
TEST_TIME 49 9597
TEST_TIME 50 9528
TEST_TIME 51 9406
TEST_TIME 52 9408
TEST_TIME 53 9385
TEST_TIME 54 9301
TEST_TIME 55 9290
TEST_TIME 56 9305
TEST_TIME 57 9219
TEST_TIME 58 9116
TEST_TIME 59 9081
TEST_TIME 60 9077
TEST_TIME 61 9072
TEST_TIME 62 9182
TEST_TIME 63 9189
TEST_TIME 64 9261
TEST_TIME 65 9220
TEST_TIME 66 9213
TEST_TIME 67 9322
TEST_TIME 68 9299
TEST_TIME 69 9439
TEST_TIME 70 9394
TEST_TIME 71 9523
TEST_TIME 72 9478
TEST_TIME 73 9490
TEST_TIME 74 9409
TEST_TIME 75 9335
TEST_TIME 76 9322
TEST_TIME 77 9279
TEST_TIME 78 9237
TEST_TIME 79 9204
TEST_TIME 80 9193
TEST_TIME 81 9191
TEST_TIME 82 9109
TEST_TIME 83 9099
TEST_TIME 84 9109
TEST_TIME 85 9125
TEST_TIME 86 9101
TEST_TIME 87 9087
TEST_TIME 88 9183
TEST_TIME 89 9201
TEST_TIME 90 9209
TEST_TIME 91 9236
TEST_TIME 92 9210
TEST_TIME 93 9267
TEST_TIME 94 9287
TEST_TIME 95 9324
TEST_TIME 96 9308
TEST_TIME 97 9353
TEST_TIME 98 9353
TEST_TIME 99 9334
TEST_TIME 100 9262
TEST_TIME 101 9242
TEST_TIME 102 9208
TEST_TIME 103 9154
TEST_TIME 104 9209
TEST_TIME 105 9172
TEST_TIME 106 9114
TEST_TIME 107 9144
TEST_TIME 108 9140
TEST_TIME 109 9114
TEST_TIME 110 9151
TEST_TIME 111 9146
TEST_TIME 112 9179
TEST_TIME 113 9177
TEST_TIME 114 9269
TEST_TIME 115 9242
TEST_TIME 116 9268
TEST_TIME 117 9253
TEST_TIME 118 9307
TEST_TIME 119 9302
TEST_TIME 120 9232

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-12-2021 16:46:44 to 23-12-2021 16:46:47.

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 1531261 1531261 0 0.00 0 22751 6 0 0
doc_list_biud 1531259 1531259 0 0.00 0 56368 27 0 0
sp_add_invoice_to_stock 5068 4979 89 1.75 26 185933 17748 0 89
sp_cancel_adding_invoice 5842 5842 0 0.00 5 71940 828 0 0
sp_cancel_client_order 1750 1750 0 0.00 4 26494 344 0 0
sp_cancel_customer_reserve 559581 559581 0 0.00 1 56369 58 0 0
sp_cancel_pay_from_customer 767 767 0 0.00 2 14524 93 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 3 14375 143 0 0
sp_cancel_supplier_invoice 6404 6396 8 0.12 2 26815 825 0 8
sp_cancel_supplier_order 398 398 0 0.00 7 1113824 103709 0 0
sp_cancel_write_off 10884 10884 0 0.00 1 14824 103 0 0
sp_client_order 20371 20371 0 0.00 3 24218 361 0 0
sp_customer_reserve 459667 459667 0 0.00 0 36608 175 0 0
sp_fill_shopping_cart 31130 31130 0 0.00 1 18301 41 0 0
sp_fill_shopping_cart_clo_res 459646 459646 0 0.00 0 14058 13 0 0
sp_get_clo_for_invoice 4958 4958 0 0.00 0 25659 350 0 0
sp_kill_cost_storno 17893 17893 0 0.00 0 7518 54 0 0
sp_kill_qstorno_ret_qs2qd 1136840 1136840 0 0.00 0 56365 32 0 0
sp_kill_qty_storno 599799 599799 0 0.00 0 56367 69 0 0
sp_lock_selected_doc 20781 20781 0 0.00 0 7769 4 0 0
sp_make_cost_storno 27932 27932 0 0.00 0 22749 56 0 0
sp_multiply_rows_for_pdistr 27930 27930 0 0.00 0 9879 29 0 0
sp_multiply_rows_for_qdistr 20371 20371 0 0.00 0 10316 162 0 0
sp_pay_from_customer 7106 7106 0 0.00 4 29035 133 0 0
sp_pay_to_supplier 5876 5876 0 0.00 5 22763 158 0 0
sp_payment_common 12982 12982 0 0.00 1 29026 38 0 0
sp_qd_handle_on_cancel_clo 1750 1750 0 0.00 0 26485 315 0 0
sp_qd_handle_on_invoice_upd_sts 10799 10799 0 0.00 0 28062 346 0 0
sp_qd_handle_on_reserve_upd_sts 9991 9991 0 0.00 0 23795 54 0 0
sp_reserve_write_off 9992 9992 0 0.00 6 23851 178 0 0
sp_supplier_invoice 5432 5432 0 0.00 5 69790 3281 0 0
sp_supplier_order 5333 5333 0 0.00 5 30150 1316 0 0
srv_aggregate_perf_data 99 99 0 0.00 2825 881610 24725 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1485 163967 47310 0 0
srv_find_qd_qs_mism 1058458 1058458 0 0.00 0 20514 5 0 0
srv_make_invnt_saldo 603 603 0 0.00 46 53603 1082 0 0
srv_make_money_saldo 809 809 0 0.00 1 416 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 140165 553104 317993 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4979 4979 0 0.00 0 11359 159 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 0 5826 97 0 0
t$perf-norm:sp_cancel_client_order 1750 1750 0 0.00 0 4814 13 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 8053 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 767 767 0 0.00 0 1098 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 2256 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 1190 15 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 23324 1476 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 1877 9 0 0
t$perf-norm:sp_client_order 20371 20371 0 0.00 0 8626 16 0 0
t$perf-norm:sp_customer_reserve 1000 1000 0 0.00 0 936 5 0 0
t$perf-norm:sp_pay_from_customer 7106 7106 0 0.00 0 10944 13 0 0
t$perf-norm:sp_pay_to_supplier 5876 5876 0 0.00 0 7517 9 0 0
t$perf-norm:sp_reserve_write_off 9992 9992 0 0.00 0 13877 13 0 0
t$perf-norm:sp_supplier_invoice 5432 5432 0 0.00 0 6409 18 0 0
t$perf-norm:sp_supplier_order 5333 5333 0 0.00 0 11792 18 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 407 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 158 3 0 0
t$perf-norm:srv_make_invnt_saldo 603 603 0 0.00 0 1615 17 0 0
t$perf-norm:srv_make_money_saldo 809 809 0 0.00 0 1205 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10092 9914 178 1.76 0 14827 21 0 178
v_all_customers 36942 36942 0 0.00 0 83 1 0 0
v_all_suppliers 29988 29988 0 0.00 0 83 1 0 0
v_all_wares 40742 40742 0 0.00 0 85 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 1121 9 0 0
v_cancel_client_order 3500 3500 0 0.00 0 2540 6 0 0
v_cancel_customer_prepayment 1534 1534 0 0.00 0 14326 14 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 0 10673 13 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 14349 26 0 0
v_cancel_supplier_invoice 810 794 16 1.97 0 1394 16 0 16
v_cancel_supplier_order 796 796 0 0.00 0 2038 9 0 0
v_cancel_write_off 2022 2022 0 0.00 0 2141 12 0 0
v_max_id_clo_ord 5329 5329 0 0.00 0 1825 2 0 0
v_max_id_clo_res 1000 1000 0 0.00 0 4116 17 0 0
v_max_id_ord_sup 5430 5430 0 0.00 0 5434 5 0 0
v_max_non_paid_invoice 1641 1641 0 0.00 0 4242 6 0 0
v_max_non_paid_realizn 3801 3801 0 0.00 0 12364 7 0 0
v_min_id_clo_ord 5329 5329 0 0.00 0 3185 5 0 0
v_min_id_clo_res 1000 1000 0 0.00 0 431 2 0 0
v_min_id_ord_sup 5430 5430 0 0.00 0 5442 6 0 0
v_min_non_paid_invoice 5876 5876 0 0.00 0 9852 30 0 0
v_min_non_paid_realizn 7106 7106 0 0.00 0 29019 30 0 0
v_our_firm 10410 10410 0 0.00 0 20 1 0 0
v_reserve_write_off 19982 19982 0 0.00 0 14346 9 0 0
x_lock_depdocs_on_canc_invoice 11841 11841 0 0.00 0 15299 113 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 3333 337 0 0
x_make_qstorno_1000_1200 5333 5333 0 0.00 2 30099 1233 0 0
x_make_qstorno_1200_2000 5432 5432 0 0.00 2 67609 3129 0 0
x_make_qstorno_2100_3300 459667 459667 0 0.00 0 36607 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-12-2021 16:46:48 to 23-12-2021 16:46:52.

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:15:14 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:16:16 28150 8192000000 9901644528 10210676736 980465120 10.689 100 90 180 1 1010296640 6204080 23627440
14:17:53 28450 8192000000 9942919632 10262417408 1016395296 11.037 100 89 178 0 1048042704 8203424 23443984
14:19:26 25661 8192000000 9981499248 10316152832 1068621168 11.539 100 85 170 1 1099423088 9832784 20969136
14:20:54 9256 8192000000 10040874304 10388344832 1123131888 12.057 100 82 164 0 1154118464 11477536 19509040
14:22:06 10976 8192000000 10089678880 10450223104 1173960336 12.534 100 82 162 0 1205535952 13213952 18361664
14:23:19 4417 8192000000 10126432000 10495967232 1211485920 12.883 100 81 162 2 1243666976 14399952 17781104
14:24:25 4175 8192000000 10156588304 10533113856 1244265872 13.185 100 80 160 1 1275440256 13866160 17308224
14:25:31 1484 8192000000 10203492048 10586169344 1286320960 13.571 100 95 190 0 1321954096 14270016 21363120
14:26:33 1786 8192000000 10215972832 10609475584 1305895104 13.749 100 85 170 5 1337360736 12742960 18722672
14:27:36 5074 8192000000 10198287472 10607341568 1309420192 13.781 100 34 68 0 1324768272 7771104 7576976
14:28:45 35014 8192000000 10213453008 10626248704 1316120240 13.842 100 35 71 1 1329927248 6616896 7190112
14:30:23 5711 8192000000 10212155168 10633842688 1322300496 13.898 100 25 50 1 1341797344 14336768 5160080
14:31:30 6040 8192000000 10223866048 10642010112 1329166496 13.960 100 31 62 0 1343632544 6937488 7528560
14:32:40 64520 8192000000 10220682000 10646310912 1332912688 13.993 100 31 64 0 1345799552 5668736 7218128
14:34:46 7142 8192000000 10227113376 10650701824 1332894304 13.993 100 24 48 0 1348202352 9910272 5397776
14:35:54 5129 8192000000 10191831728 10604134400 1289585280 13.600 99 22 44 0 1300067168 5645856 4836032
14:37:05 11075 8192000000 10131994016 10530410496 1207649056 12.847 97 20 42 0 1218082928 5748064 4685808
14:38:17 21780 8192000000 10118702160 10497703936 1147711920 12.288 92 50 98 1 1164482432 5632688 11137824
14:39:39 6472 8192000000 10142969680 10537385984 1216259600 12.927 97 27 54 3 1228136656 4816592 7060464
14:40:46 7612 8192000000 10163858688 10571747328 1271474832 13.435 100 39 78 2 1288170000 6904832 9790336
14:41:55 5771 8192000000 10173490160 10586759168 1287960896 13.586 100 22 44 0 1298762752 5414560 5387296
14:43:04 53075 8192000000 10195557360 10605768704 1301626256 13.710 100 49 95 2 1318189664 4859056 11704352
14:45:10 7490 8192000000 10221373408 10625122304 1317846832 13.857 100 48 95 1 1335480560 6598848 11034880
14:46:30 7213 8192000000 10250399552 10660061184 1338534064 14.044 100 60 120 3 1364083184 12373104 13176016
14:47:46 8378 8192000000 10273117552 10679603200 1353550016 14.179 100 59 116 0 1379092576 12408016 13134544
14:49:07 30600 8192000000 10277028240 10696151040 1366503168 14.296 100 56 113 1 1393785408 14580368 12701872
14:50:39 14721 8192000000 10282164752 10709745664 1375067632 14.372 100 39 78 4 1398525520 14418672 9039216
14:52:09 20739 8192000000 10303210880 10730123264 1389277264 14.499 100 54 107 2 1417976000 15793744 12904992
14:53:44 7794 8192000000 10328765568 10746855424 1397160656 14.570 100 53 105 0 1429092672 19825792 12106224
14:54:58 7211 8192000000 10352477248 10774183936 1414429008 14.723 100 62 124 3 1461694224 32399168 14866048
14:56:13 22770 8192000000 10330469904 10754629632 1384680448 14.458 98 52 104 0 1428540576 30908512 12951616
14:57:39 1483 8192000000 10333266528 10745683968 1389006416 14.497 99 71 138 1 1439927456 33915536 17005504
14:58:45 2793 8192000000 10335584752 10743144448 1376521152 14.385 99 80 160 1 1431974496 35349712 20103632
14:59:52 10679 8192000000 10356765088 10773774336 1408759792 14.673 100 64 128 2 1465486592 40758416 15968384
15:01:10 6294 8192000000 10325690192 10732892160 1373837248 14.361 99 73 145 1 1431512176 39287808 18387120
15:02:22 2411 8192000000 10350709648 10749485056 1370130624 14.328 99 91 183 0 1439905728 46308864 23466240
15:03:28 1698 8192000000 10346525328 10747203584 1377849904 14.397 100 90 181 0 1445451872 45884448 21717520
15:04:36 5385 8192000000 10316294480 10732371968 1364785616 14.280 100 55 111 1 1424815232 44927264 15102352
15:05:45 5901 8192000000 10341666080 10761768960 1382530496 14.439 100 63 126 1 1451731328 52773296 16427536
15:06:56 20395 8192000000 10332214976 10759122944 1377901664 14.398 100 33 66 2 1436112112 49247520 8962928
15:08:28 5871 8192000000 10323871568 10734444544 1348024192 14.130 99 61 120 1 1426966032 62859392 16082448
15:09:40 7464 8192000000 10317329344 10744864768 1360112976 14.238 100 30 60 0 1424234464 54839280 9282208
15:10:52 3413 8192000000 10339905824 10771963904 1373008384 14.354 100 69 137 1 1446227520 55560208 17658928
15:11:59 27684 8192000000 10299786736 10734809088 1350316128 14.150 99 24 48 1 1389744576 33073792 6354656
15:13:40 17972 8192000000 10284332736 10730119168 1342099792 14.076 98 22 43 1 1374008800 26679712 5229296
15:15:16 34026 8192000000 10265750176 10710130688 1344319040 14.096 98 14 28 0 1359562048 12368448 2874560
15:17:02 163967 8192000000 10288951056 10730459136 1348291008 14.132 98 18 36 2 1364027744 11662432 4074304
15:20:48 57962 8192000000 10292050128 10738274304 1370357152 14.330 99 15 29 0 1389197136 15814976 3025008
15:22:49 79244 8192000000 10291349104 10737897472 1371952112 14.345 99 19 38 1 1390907776 14999712 3955952
15:25:10 71590 8192000000 10293286720 10747105280 1384484272 14.457 99 26 52 2 1402467072 11490928 6491872
15:27:23 71334 8192000000 10307359280 10762010624 1395198224 14.552 100 12 24 0 1412759856 14924592 2637040
15:30:02 91021 8192000000 10297234560 10751795200 1369492816 14.323 98 20 39 0 1384415168 10632384 4289968
15:34:05 79451 8192000000 10299333312 10751819776 1395353616 14.554 100 27 54 0 1413366960 12069376 5943968
15:36:26 40715 8192000000 10300367488 10759397376 1401756784 14.611 100 31 62 1 1419351632 10180480 7414368
15:38:13 80521 8192000000 10296571904 10759630848 1388917440 14.496 99 9 19 0 1402570320 11246128 2406752
15:40:33 63083 8192000000 10285754752 10743353344 1386481536 14.474 100 20 40 1 1401509328 10324144 4703648
15:43:25 71527 8192000000 10276751424 10747060224 1363172544 14.266 98 4 8 0 1371184064 7288000 723520
15:45:48 56154 8192000000 10296626512 10760790016 1364140080 14.275 97 12 24 0 1378269216 11569744 2559392
15:47:47 64169 8192000000 10277356608 10730610688 1276806656 13.484 92 15 30 1 1290796400 10790672 3199072
15:50:20 67878 8192000000 10298738864 10736959488 1338235040 14.041 96 27 54 0 1355921600 11124960 6561600
15:52:31 110352 8192000000 10252285968 10690043904 1201720240 12.792 88 22 44 1 1219813376 13293440 4799696
15:55:25 40361 8192000000 10245682640 10684456960 1301179104 13.706 97 31 61 0 1318204496 10196112 6829280
15:57:06 94049 8192000000 10255523392 10708463616 1302168416 13.715 96 13 26 1 1321059120 15815184 3075520
15:59:40 90577 8192000000 10235261296 10673774592 1297133072 13.669 97 34 68 0 1312721680 6984448 8604160
16:02:26 4369 8192000000 10242627344 10685526016 1334472240 14.008 100 27 54 2 1354595328 14544304 5578784
16:03:31 62930 8192000000 10230510544 10679992320 1318287840 13.861 98 14 28 0 1329897632 8478320 3131472
16:05:53 46590 8192000000 10240354864 10697682944 1348914224 14.138 100 6 12 0 1362266672 11797504 1554944
16:07:43 87987 8192000000 10251051456 10713559040 1346752048 14.118 99 5 10 0 1355775872 7911552 1112272
16:11:07 54861 8192000000 10284957344 10732720128 1380566912 14.422 100 24 48 0 1398339792 12162704 5610176
16:13:02 89847 8192000000 10265311296 10723241984 1352271888 14.168 98 21 42 0 1370580640 13521248 4787504
16:15:32 126425 8192000000 10288689600 10741440512 1341655136 14.072 97 11 22 0 1354788496 10936864 2196496
16:18:39 44374 8192000000 10278432192 10716495872 1329478976 13.962 96 28 54 1 1343150736 7199808 6471952
16:21:51 149733 8192000000 10297021424 10752966656 1376149424 14.382 99 10 20 0 1394694000 16473232 2071344
16:25:39 62071 8192000000 10282991600 10739679232 1390544432 14.511 100 37 75 1 1407148608 8350560 8253616
16:27:45 61473 8192000000 10297942656 10760294400 1397214128 14.570 100 6 12 0 1413600144 14778224 1607792
16:30:15 63579 8192000000 10294584640 10761158656 1391393136 14.518 99 12 24 0 1404284624 10314944 2576544
16:32:22 69893 8192000000 10289645904 10749173760 1363275408 14.267 98 5 10 0 1372806720 8527280 1004032
16:34:38 70185 8192000000 10268699968 10718765056 1319858336 13.875 95 21 42 0 1339278224 14392256 5027632
16:36:55 70248 8192000000 10289945952 10749440000 1342427616 14.079 96 14 27 1 1363231808 17626384 3177808
16:39:05 32646 8192000000 10274330752 10741747712 1349712608 14.145 98 7 14 1 1371933216 20625728 1594880
16:40:49 15431 8192000000 10295335024 10728591360 1375347152 14.375 100 50 100 0 1397264080 10834608 11082320
16:42:05 36782 8192000000 10277836208 10713042944 1337199408 14.032 98 26 52 0 1352351680 9482144 5670128
16:44:24 62037 8192000000 9285741056 9564823552 432316640 5.012 32 3 4 1 446180176 13461632 401904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-12-2021 16:46:54 to 23-12-2021 16:47:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-12-2021 16:47:10 to 23-12-2021 16:47:12.

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          239989
MON$OLDEST_ACTIVE               239990
MON$OLDEST_SNAPSHOT             239990
MON$NEXT_TRANSACTION            239990
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                       2026976
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33547 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33547 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33547 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33547 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Dec 23 16:45:30 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Thu Dec 23 16:45:30 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Thu Dec 23 16:45:30 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Thu Dec 23 16:45:30 2021
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:21: 
--- end of diff output ---

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

23.12.21 16:47: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
2021-12-23 16:47:15 No severe PSQL-related problems occured
23.12.21 16:47:16. End of report.