Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.09.2021 19:38
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16074848 kB
MemAvailable 30739112 kB
Buffers 0 kB
Cached 15604608 kB
SwapCached 2964 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.8
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-09-28 19:37:53
DTS_END                         2021-09-28 19:37:53.6360
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/46906
TRN_ID                          244615
ATT_ID                          664
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: 9188.03 25120 1102563
customer order: creation 173.97 363 20876
customer order: refuse 14.96 320 1795
order to supplier: creation 46.25 1339 5550
order to supplier: removal 3.33 103166 399
invoice (draft): creation 47.60 3376 5712
invoice (draft): removal 52.22 781 6266
invoice accept: apply 42.71 17883 5125
invoice accept: cancel 46.33 820 5560
customer reserve: creation 3970.35 177 476442
customer reserve: removal 4491.30 61 538956
realization accept: apply 85.92 178 10310
realization accept: cancel 81.23 115 9748
payment from customer: creation 60.01 108 7201
payment from customer: removal 5.87 73 704
payment to supplier: creation 50.36 137 6043
payment to supplier: removal 3.54 73 425
service: total inventory turnovers 5.13 1034 615
service: total monetary turnovers 6.11 30 733
service: aggregate perf. data 0.83 19164 99
service: refresh index statistics 0.03 353208 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-09-2021 19:39:04 to 28-09-2021 19:39:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2044
TEST_TIME 2 2932
TEST_TIME 3 3165
TEST_TIME 4 3736
TEST_TIME 5 4701
TEST_TIME 6 4883
TEST_TIME 7 4997
TEST_TIME 8 5551
TEST_TIME 9 5678
TEST_TIME 10 5863
TEST_TIME 11 5777
TEST_TIME 12 5824
TEST_TIME 13 5877
TEST_TIME 14 5984
TEST_TIME 15 6284
TEST_TIME 16 6479
TEST_TIME 17 6959
TEST_TIME 18 7586
TEST_TIME 19 7436
TEST_TIME 20 7666
TEST_TIME 21 7761
TEST_TIME 22 8009
TEST_TIME 23 8242
TEST_TIME 24 8679
TEST_TIME 25 8837
TEST_TIME 26 9066
TEST_TIME 27 9042
TEST_TIME 28 10362
TEST_TIME 29 10402
TEST_TIME 30 10387
TEST_TIME 31 10499
TEST_TIME 32 10602
TEST_TIME 33 10393
TEST_TIME 34 10161
TEST_TIME 35 9971
TEST_TIME 36 10129
TEST_TIME 37 9937
TEST_TIME 38 9919
TEST_TIME 39 9766
TEST_TIME 40 9774
TEST_TIME 41 9867
TEST_TIME 42 9827
TEST_TIME 43 9719
TEST_TIME 44 9661
TEST_TIME 45 9535
TEST_TIME 46 9526
TEST_TIME 47 9439
TEST_TIME 48 9633
TEST_TIME 49 9548
TEST_TIME 50 9516
TEST_TIME 51 9454
TEST_TIME 52 9535
TEST_TIME 53 9562
TEST_TIME 54 9544
TEST_TIME 55 9532
TEST_TIME 56 9525
TEST_TIME 57 9620
TEST_TIME 58 9622
TEST_TIME 59 9625
TEST_TIME 60 9692
TEST_TIME 61 9724
TEST_TIME 62 9816
TEST_TIME 63 9705
TEST_TIME 64 9614
TEST_TIME 65 9528
TEST_TIME 66 9508
TEST_TIME 67 9498
TEST_TIME 68 9411
TEST_TIME 69 9341
TEST_TIME 70 9295
TEST_TIME 71 9385
TEST_TIME 72 9376
TEST_TIME 73 9317
TEST_TIME 74 9413
TEST_TIME 75 9343
TEST_TIME 76 9330
TEST_TIME 77 9447
TEST_TIME 78 9434
TEST_TIME 79 9409
TEST_TIME 80 9525
TEST_TIME 81 9516
TEST_TIME 82 9510
TEST_TIME 83 9506
TEST_TIME 84 9417
TEST_TIME 85 9341
TEST_TIME 86 9323
TEST_TIME 87 9289
TEST_TIME 88 9287
TEST_TIME 89 9240
TEST_TIME 90 9269
TEST_TIME 91 9252
TEST_TIME 92 9208
TEST_TIME 93 9210
TEST_TIME 94 9229
TEST_TIME 95 9249
TEST_TIME 96 9282
TEST_TIME 97 9256
TEST_TIME 98 9300
TEST_TIME 99 9296
TEST_TIME 100 9256
TEST_TIME 101 9336
TEST_TIME 102 9396
TEST_TIME 103 9426
TEST_TIME 104 9382
TEST_TIME 105 9451
TEST_TIME 106 9444
TEST_TIME 107 9382
TEST_TIME 108 9354
TEST_TIME 109 9319
TEST_TIME 110 9303
TEST_TIME 111 9268
TEST_TIME 112 9256
TEST_TIME 113 9237
TEST_TIME 114 9196
TEST_TIME 115 9205
TEST_TIME 116 9202
TEST_TIME 117 9217
TEST_TIME 118 9206
TEST_TIME 119 9189
TEST_TIME 120 9189

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-09-2021 19:39:08 to 28-09-2021 19:39:11.

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 1542205 1542205 0 0.00 0 26436 7 0 0
doc_list_biud 1542203 1542203 0 0.00 0 49280 27 0 0
sp_add_invoice_to_stock 5247 5125 122 2.32 24 151749 17883 0 122
sp_cancel_adding_invoice 5560 5560 0 0.00 2 55774 821 0 0
sp_cancel_client_order 1795 1795 0 0.00 5 5774 321 0 0
sp_cancel_customer_reserve 538956 538956 0 0.00 1 49282 61 0 0
sp_cancel_pay_from_customer 704 704 0 0.00 3 3759 74 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 4108 73 0 0
sp_cancel_supplier_invoice 6276 6266 10 0.15 1 19264 782 0 10
sp_cancel_supplier_order 399 399 0 0.00 10 1034550 103167 0 0
sp_cancel_write_off 9748 9748 0 0.00 1 9641 115 0 0
sp_client_order 20876 20876 0 0.00 4 31206 364 0 0
sp_customer_reserve 476442 476442 0 0.00 0 41452 177 0 0
sp_fill_shopping_cart 32133 32133 0 0.00 1 8492 38 0 0
sp_fill_shopping_cart_clo_res 476436 476436 0 0.00 0 12480 14 0 0
sp_get_clo_for_invoice 5119 5119 0 0.00 0 25221 280 0 0
sp_kill_cost_storno 16437 16437 0 0.00 0 8380 54 0 0
sp_kill_qstorno_ret_qs2qd 1094325 1094325 0 0.00 0 49274 33 0 0
sp_kill_qty_storno 578153 578153 0 0.00 0 49280 71 0 0
sp_lock_selected_doc 21315 21315 0 0.00 0 6025 4 0 0
sp_make_cost_storno 28673 28673 0 0.00 0 26432 58 0 0
sp_multiply_rows_for_pdistr 28673 28673 0 0.00 0 13044 30 0 0
sp_multiply_rows_for_qdistr 20876 20876 0 0.00 0 12532 148 0 0
sp_pay_from_customer 7201 7201 0 0.00 4 13051 108 0 0
sp_pay_to_supplier 6043 6043 0 0.00 5 19148 138 0 0
sp_payment_common 13244 13244 0 0.00 1 7329 33 0 0
sp_qd_handle_on_cancel_clo 1795 1795 0 0.00 0 5766 300 0 0
sp_qd_handle_on_invoice_upd_sts 10679 10679 0 0.00 0 18111 307 0 0
sp_qd_handle_on_reserve_upd_sts 10310 10310 0 0.00 0 13396 58 0 0
sp_reserve_write_off 10310 10310 0 0.00 6 26448 179 0 0
sp_supplier_invoice 5712 5712 0 0.00 7 79225 3376 0 0
sp_supplier_order 5550 5550 0 0.00 5 50600 1339 0 0
srv_aggregate_perf_data 99 99 0 0.00 2903 272050 19165 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1412 141900 43001 0 0
srv_find_qd_qs_mism 1052997 1052997 0 0.00 0 10860 6 0 0
srv_make_invnt_saldo 615 615 0 0.00 24 45759 1034 0 0
srv_make_money_saldo 733 733 0 0.00 1 1939 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 111616 501795 353208 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5126 5126 0 0.00 0 16998 170 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 1 4175 116 0 0
t$perf-norm:sp_cancel_client_order 1795 1795 0 0.00 0 2401 11 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 856 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 704 704 0 0.00 0 3358 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 6086 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 2756 20 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 25525 1765 0 0
t$perf-norm:sp_cancel_write_off 1044 1044 0 0.00 0 8666 18 0 0
t$perf-norm:sp_client_order 20876 20876 0 0.00 0 5454 16 0 0
t$perf-norm:sp_customer_reserve 1010 1010 0 0.00 0 954 5 0 0
t$perf-norm:sp_pay_from_customer 7201 7201 0 0.00 0 18896 21 0 0
t$perf-norm:sp_pay_to_supplier 6043 6043 0 0.00 0 4719 12 0 0
t$perf-norm:sp_reserve_write_off 10310 10310 0 0.00 0 7938 13 0 0
t$perf-norm:sp_supplier_invoice 5713 5713 0 0.00 0 6008 20 0 0
t$perf-norm:sp_supplier_order 5550 5550 0 0.00 0 4830 18 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 765 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 3119 49 0 0
t$perf-norm:srv_make_invnt_saldo 615 615 0 0.00 0 1783 25 0 0
t$perf-norm:srv_make_money_saldo 733 733 0 0.00 0 777 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10480 10236 244 2.32 0 7557 18 0 244
v_all_customers 37686 37686 0 0.00 0 26 1 0 0
v_all_suppliers 31428 31428 0 0.00 0 91 1 0 0
v_all_wares 41752 41752 0 0.00 0 209 1 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 2711 12 0 0
v_cancel_client_order 3590 3590 0 0.00 0 1873 4 0 0
v_cancel_customer_prepayment 1408 1408 0 0.00 0 3021 7 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 0 2210 5 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 4094 8 0 0
v_cancel_supplier_invoice 876 856 20 2.28 0 1611 16 0 20
v_cancel_supplier_order 798 798 0 0.00 0 600 4 0 0
v_cancel_write_off 2088 2088 0 0.00 0 1622 13 0 0
v_max_id_clo_ord 5549 5549 0 0.00 0 1721 2 0 0
v_max_id_clo_res 1010 1010 0 0.00 0 3047 11 0 0
v_max_id_ord_sup 5707 5707 0 0.00 0 4378 4 0 0
v_max_non_paid_invoice 1585 1585 0 0.00 0 5893 14 0 0
v_max_non_paid_realizn 3978 3978 0 0.00 0 1497 3 0 0
v_min_id_clo_ord 5549 5549 0 0.00 0 5343 8 0 0
v_min_id_clo_res 1010 1010 0 0.00 0 10424 22 0 0
v_min_id_ord_sup 5707 5707 0 0.00 0 6385 7 0 0
v_min_non_paid_invoice 6043 6043 0 0.00 0 7321 25 0 0
v_min_non_paid_realizn 7201 7201 0 0.00 0 6897 20 0 0
v_our_firm 10512 10512 0 0.00 0 13 1 0 0
v_reserve_write_off 20620 20620 0 0.00 0 3107 4 0 0
x_lock_depdocs_on_canc_invoice 11398 11398 0 0.00 0 11667 120 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 9577 347 0 0
x_make_qstorno_1000_1200 5550 5550 0 0.00 2 50590 1259 0 0
x_make_qstorno_1200_2000 5712 5712 0 0.00 3 76915 3242 0 0
x_make_qstorno_2100_3300 476442 476442 0 0.00 0 41452 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-09-2021 19:39:12 to 28-09-2021 19:39:16.

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
17:07:57 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:58 14100 8192000000 10373533760 10693382144 1434735184 14.903 100 91 182 0 1470781024 6325072 29720768
17:10:17 16251 8192000000 10427720240 10757365760 1481346512 15.313 100 88 176 0 1518828032 8753808 28727712
17:11:37 14371 8192000000 10480680320 10821337088 1533349824 15.766 100 87 174 1 1571553712 10804912 27398976
17:12:56 13683 8192000000 10546337568 10896605184 1597172832 16.315 100 83 166 2 1634618096 12371568 25073696
17:14:14 8927 8192000000 10618155888 10983698432 1669832272 16.932 100 74 149 0 1703834496 12326256 21675968
17:15:25 7570 8192000000 10669748000 11046895616 1732083392 17.453 100 80 158 1 1769534096 13631552 23819152
17:16:33 3900 8192000000 10724839920 11102851072 1790395136 17.935 100 94 187 1 1830646000 14658992 25591872
17:17:39 2264 8192000000 10758669136 11145134080 1829027008 18.251 100 86 172 1 1865005072 11982064 23996000
17:18:44 1691 8192000000 10814373952 11200258048 1866835296 18.559 100 93 186 0 1907108432 13476912 26796224
17:19:48 1193 8192000000 10830811520 11223994368 1888091680 18.730 100 94 188 0 1929089216 13443920 27553616
17:20:50 9015 8192000000 10804102064 11224252416 1883546352 18.694 100 30 58 1 1900201024 8681072 7973600
17:22:00 8717 8192000000 10803492624 11237597184 1900020576 18.826 100 23 46 0 1913434544 6476304 6937664
17:23:10 12378 8192000000 10810464352 11249307648 1906991952 18.882 100 14 29 0 1916414912 5417456 4005504
17:24:23 6377 8192000000 10813032480 11251535872 1910247424 18.909 100 16 32 1 1921145856 6590368 4308064
17:25:32 28086 8192000000 10865677216 11275108352 1922489200 19.007 100 68 136 0 1948888768 6663072 19736496
17:27:00 2453 8192000000 10835514480 11260194816 1898083040 18.811 99 48 96 0 1919323648 6101744 15138864
17:28:06 4118 8192000000 10775557840 11203526656 1863919312 18.535 100 25 49 3 1877631584 5939056 7773216
17:29:11 12204 8192000000 10742876240 11152367616 1761243920 17.695 95 29 56 0 1775023248 5236336 8542992
17:30:23 26127 8192000000 10730611360 11153788928 1722447472 17.373 93 19 38 1 1732827600 4895744 5484384
17:31:50 5126 8192000000 10733264016 11143307264 1779955200 17.849 98 25 49 0 1792835120 6273632 6606288
17:32:56 9026 8192000000 10707444336 11128094720 1783752640 17.880 98 26 52 0 1797301616 5680688 7868288
17:34:05 5829 8192000000 10750982592 11177697280 1841595792 18.354 100 32 64 3 1856366928 5258320 9512816
17:35:13 44996 8192000000 10780340048 11207491584 1860476320 18.507 100 30 60 2 1875456128 6131520 8848288
17:37:00 10780 8192000000 10805353472 11233079296 1881475760 18.677 100 26 52 1 1893932528 5464512 6992256
17:38:14 15592 8192000000 10835915968 11258867712 1912425264 18.926 100 39 77 2 1930258128 6395088 11437776
17:39:33 12282 8192000000 10858277424 11286011904 1931224656 19.077 100 38 76 0 1951885888 9740336 10920896
17:40:55 20252 8192000000 10887628848 11312013312 1953770928 19.256 100 57 112 1 1984317824 14051136 16495760
17:42:17 25477 8192000000 10882929232 11324809216 1956094736 19.275 100 27 54 1 1977790416 13594672 8101008
17:43:47 14605 8192000000 10911592400 11347337216 1972016240 19.401 100 49 98 1 2004017776 18201472 13800064
17:45:06 11914 8192000000 10921897072 11361742848 1981692400 19.478 100 45 90 2 2010753296 15734992 13325904
17:46:21 9660 8192000000 10950190592 11376766976 1997390752 19.602 100 66 132 2 2034783376 16434944 20957680
17:48:01 11699 8192000000 10960194256 11390332928 2008802928 19.692 100 62 123 1 2049987472 22110688 19073856
17:49:17 5646 8192000000 10957422736 11396026368 1994152800 19.577 99 57 114 1 2037378432 25957616 17268016
17:50:27 4555 8192000000 10963485904 11389861888 1984643648 19.501 99 58 113 2 2033015232 31101328 17270256
17:51:36 3198 8192000000 10991429088 11411701760 2013330592 19.728 100 85 170 2 2077477808 39266784 24880432
17:52:47 4407 8192000000 10975888192 11386003456 1992024240 19.560 100 73 143 1 2050476112 36633632 21818240
17:53:57 1412 8192000000 10993688320 11403837440 1999338976 19.618 100 94 189 1 2071687712 43537664 28811072
17:55:01 4827 8192000000 10975151344 11394211840 1988397360 19.531 100 69 139 0 2054890784 43555936 22937488
17:56:10 3863 8192000000 10932141776 11357417472 1956265440 19.276 99 73 141 1 2016697792 38245232 22187120
17:57:26 3219 8192000000 10952321888 11368452096 1955501840 19.270 99 78 155 1 2029643824 49094912 25047072
17:58:32 7295 8192000000 10968455680 11400458240 1948733504 19.216 98 54 108 1 2018274784 51882240 17659040
17:59:43 5012 8192000000 10952446368 11377606656 1947713136 19.208 99 59 116 0 2025563856 57806736 20043984
18:00:59 27145 8192000000 10955165744 11395219456 1959508976 19.302 99 42 84 3 2034142432 60084256 14549200
18:02:28 16846 8192000000 10941564976 11386363904 1961606336 19.319 100 30 60 0 2021317600 49301024 10410240
18:04:05 26093 8192000000 10906198736 11375083520 1936002624 19.115 100 24 48 0 1998508480 53891520 8614336
18:05:48 35558 8192000000 10884894000 11320520704 1918903504 18.978 99 14 28 4 1952459520 29051280 4504736
18:07:31 11790 8192000000 10897129968 11333218304 1941404512 19.158 99 56 110 4 1970852176 14412832 15034832
18:08:44 26178 8192000000 10888235264 11320442880 1940459024 19.150 100 33 68 2 1958443472 7997712 9986736
18:10:14 80126 8192000000 10883640816 11335290880 1922768240 19.009 98 25 49 0 1941698464 11962880 6967344
18:12:38 77742 8192000000 10892405984 11347722240 1957046544 19.283 100 18 36 0 1978188928 15470384 5672000
18:15:06 22374 8192000000 10904205136 11363610624 1972227952 19.403 100 32 64 1 1993279120 11859696 9191472
18:16:32 16073 8192000000 10928476432 11378298880 1986325360 19.515 100 63 126 1 2017324032 12931072 18067600
18:17:55 55100 8192000000 10912149536 11395129344 1983575984 19.493 100 12 24 0 2003847760 16444512 3827264
18:19:55 28170 8192000000 10887807008 11359145984 1938351008 19.134 98 7 14 1 1947941264 7563456 2026800
18:22:03 28616 8192000000 10914699456 11375726592 1965707728 19.351 99 15 30 1 1978696608 8175872 4813008
18:23:42 97782 8192000000 10935459008 11398193152 1996456128 19.595 100 31 62 1 2021060656 15224960 9379568
18:26:37 35999 8192000000 10895918192 11360378880 1931273872 19.077 97 23 46 0 1945684288 7117504 7292912
18:28:14 57116 8192000000 10907220176 11387691008 1985690688 19.510 100 12 24 0 2003541264 14216976 3633600
18:30:29 27762 8192000000 10910319168 11386900480 1986687952 19.518 100 22 42 3 2000820368 8099280 6033136
18:32:02 43298 8192000000 10937173696 11400359936 1983439936 19.492 99 28 56 0 2000104000 9087408 7576656
18:33:46 27266 8192000000 10903624912 11375329280 1928821440 19.057 97 24 48 1 1946104208 10586704 6696064
18:35:26 74442 8192000000 10910721856 11378864128 1922691296 19.008 97 10 20 0 1939493664 14044016 2758352
18:37:44 93101 8192000000 10863955712 11345969152 1835969040 18.308 93 15 29 1 1852439392 12998720 3471632
18:40:17 92273 8192000000 10891042208 11352518656 1941189216 19.156 99 28 56 0 1955754752 6824688 7740848
18:43:07 32767 8192000000 10881225312 11343613952 1945558000 19.191 99 21 43 1 1960000080 8913808 5528272
18:44:43 45861 8192000000 10870885520 11358011392 1954964160 19.266 100 9 18 0 1967847552 10406176 2477216
18:46:35 59899 8192000000 10891105952 11364175872 1948652384 19.216 99 19 37 1 1964816576 10950208 5213984
18:48:44 60505 8192000000 10874722240 11349868544 1914837120 18.945 98 12 25 2 1932049568 13673632 3538816
18:50:51 61074 8192000000 10861796944 11342536704 1860870352 18.510 95 19 38 0 1879426064 13315872 5239840
18:52:56 41134 8192000000 10859739632 11308302336 1843862416 18.372 95 35 70 1 1864892640 11249488 9780736
18:54:47 50671 8192000000 10856551856 11317108736 1881103712 18.674 97 28 57 0 1897220288 8643264 7473312
18:56:41 28124 8192000000 10900479600 11356626944 1946268672 19.197 100 40 80 1 1976837472 19652592 10916208
18:58:09 84539 8192000000 10869001072 11322994688 1830119840 18.260 94 12 24 0 1851600576 18475712 3005024
19:00:35 141900 8192000000 10890154704 11348242432 1935758800 19.113 99 24 49 0 1949927152 7996656 6171696
19:03:59 72637 8192000000 10852183872 11338833920 1923794880 19.017 98 18 36 0 1944117952 14723744 5599328
19:06:52 76689 8192000000 10882625488 11372220416 1967122224 19.363 100 16 31 2 1982699296 11199616 4377456
19:09:11 76699 8192000000 10897934848 11368202240 1967687632 19.367 100 4 8 0 1976904272 8328816 887824
19:11:28 61077 8192000000 10907708032 11378503680 1942176240 19.164 98 14 28 1 1953374448 7577008 3621200
19:13:46 81811 8192000000 10884259264 11364859904 1944236560 19.181 98 17 32 1 1961615376 12496592 4882224
19:16:28 58266 8192000000 10932770944 11393245184 1941949920 19.162 97 23 46 0 1961136208 13205728 5980560
19:18:27 79686 8192000000 10912533760 11401834496 1951940896 19.242 98 18 36 1 1978862896 21753920 5168080
19:20:47 39265 8192000000 10927823280 11377147904 1958024496 19.290 99 34 67 0 1977805136 9859424 9921216
19:22:28 70323 8192000000 10888438864 11365707776 1909569680 18.903 97 23 46 0 1929293120 13471920 6251520
19:26:07 46696 8192000000 10879197552 11357487104 1929382368 19.062 98 18 36 0 1946695488 11997696 5315424
19:27:54 89848 8192000000 10866499552 11348344832 1892525744 18.766 97 11 22 0 1911152752 15537088 3089920
19:30:24 44073 8192000000 10868193504 11350388736 1934081472 19.099 99 14 28 0 1947845712 9822000 3942240
19:32:10 83500 8192000000 10842912144 11332431872 1883196400 18.691 97 7 14 0 1896329712 10923168 2210144
19:34:38 64053 8192000000 10825976480 11297255424 1816293744 18.147 94 10 20 0 1832131920 12865376 2972800
19:36:48 78146 8192000000 9675540032 10016346112 808219760 8.980 42 8 17 0 816633968 6668176 1746032

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-09-2021 19:39:18 to 28-09-2021 19:39:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-09-2021 19:39:34 to 28-09-2021 19:39:36.

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          243268
MON$OLDEST_ACTIVE               244847
MON$OLDEST_SNAPSHOT             244847
MON$NEXT_TRANSACTION            244847
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                       2022976
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33501 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33501 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33501 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33501 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 ---
:998: 
:999: mscs-test-161.budzdorov.ru    Tue Sep 28 19:38:09 2021
:1000:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1001: 
--- end of diff output ---

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

28.09.21 19:39:37. 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-09-28 19:39:39 No severe PSQL-related problems occured
28.09.21 19:39:41. End of report.