Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.12.2021 19: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 17061988 kB
MemAvailable 31495508 kB
Buffers 0 kB
Cached 14651896 kB
SwapCached 0 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-10 19:37:56
DTS_END                         2021-12-10 19:37:56.4380
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/44492
TRN_ID                          240817
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: 8821.58 19907 1058590
customer order: creation 175.11 322 21013
customer order: refuse 14.93 327 1791
order to supplier: creation 45.93 1208 5512
order to supplier: removal 3.38 99743 406
invoice (draft): creation 46.12 3479 5534
invoice (draft): removal 48.42 854 5810
invoice accept: apply 41.35 17541 4962
invoice accept: cancel 43.78 814 5254
customer reserve: creation 3808.73 174 457048
customer reserve: removal 4297.54 61 515705
realization accept: apply 86.45 167 10374
realization accept: cancel 78.42 113 9410
payment from customer: creation 60.47 119 7256
payment from customer: removal 5.90 77 708
payment to supplier: creation 49.49 137 5939
payment to supplier: removal 3.35 70 402
service: total inventory turnovers 4.90 1070 588
service: total monetary turnovers 6.54 23 785
service: aggregate perf. data 0.74 23231 89
service: refresh index statistics 0.03 248616 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-12-2021 19:39:53 to 10-12-2021 19:39:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4079
TEST_TIME 2 4598
TEST_TIME 3 3868
TEST_TIME 4 4252
TEST_TIME 5 4210
TEST_TIME 6 4454
TEST_TIME 7 4347
TEST_TIME 8 5021
TEST_TIME 9 5105
TEST_TIME 10 6007
TEST_TIME 11 6135
TEST_TIME 12 6055
TEST_TIME 13 6133
TEST_TIME 14 6541
TEST_TIME 15 6596
TEST_TIME 16 6824
TEST_TIME 17 7071
TEST_TIME 18 7167
TEST_TIME 19 7750
TEST_TIME 20 8248
TEST_TIME 21 8912
TEST_TIME 22 9073
TEST_TIME 23 9515
TEST_TIME 24 9509
TEST_TIME 25 9525
TEST_TIME 26 10046
TEST_TIME 27 10613
TEST_TIME 28 10464
TEST_TIME 29 10446
TEST_TIME 30 10108
TEST_TIME 31 10091
TEST_TIME 32 9820
TEST_TIME 33 9772
TEST_TIME 34 9799
TEST_TIME 35 9782
TEST_TIME 36 9631
TEST_TIME 37 9559
TEST_TIME 38 9519
TEST_TIME 39 9523
TEST_TIME 40 9403
TEST_TIME 41 9281
TEST_TIME 42 9443
TEST_TIME 43 9277
TEST_TIME 44 9227
TEST_TIME 45 9222
TEST_TIME 46 9125
TEST_TIME 47 9011
TEST_TIME 48 8931
TEST_TIME 49 8841
TEST_TIME 50 8845
TEST_TIME 51 9057
TEST_TIME 52 8968
TEST_TIME 53 8886
TEST_TIME 54 8930
TEST_TIME 55 8901
TEST_TIME 56 8885
TEST_TIME 57 8875
TEST_TIME 58 8873
TEST_TIME 59 9027
TEST_TIME 60 9034
TEST_TIME 61 9001
TEST_TIME 62 9099
TEST_TIME 63 9102
TEST_TIME 64 9116
TEST_TIME 65 9099
TEST_TIME 66 9122
TEST_TIME 67 9146
TEST_TIME 68 9193
TEST_TIME 69 9168
TEST_TIME 70 9051
TEST_TIME 71 9006
TEST_TIME 72 9005
TEST_TIME 73 8967
TEST_TIME 74 9027
TEST_TIME 75 8965
TEST_TIME 76 8928
TEST_TIME 77 8919
TEST_TIME 78 8831
TEST_TIME 79 8787
TEST_TIME 80 8799
TEST_TIME 81 8845
TEST_TIME 82 8821
TEST_TIME 83 8885
TEST_TIME 84 8877
TEST_TIME 85 8869
TEST_TIME 86 8938
TEST_TIME 87 8951
TEST_TIME 88 8956
TEST_TIME 89 9018
TEST_TIME 90 8995
TEST_TIME 91 9038
TEST_TIME 92 9070
TEST_TIME 93 9036
TEST_TIME 94 8974
TEST_TIME 95 8957
TEST_TIME 96 8909
TEST_TIME 97 8890
TEST_TIME 98 8887
TEST_TIME 99 8843
TEST_TIME 100 8820
TEST_TIME 101 8762
TEST_TIME 102 8824
TEST_TIME 103 8848
TEST_TIME 104 8830
TEST_TIME 105 8843
TEST_TIME 106 8857
TEST_TIME 107 8858
TEST_TIME 108 8843
TEST_TIME 109 8947
TEST_TIME 110 8929
TEST_TIME 111 8938
TEST_TIME 112 8940
TEST_TIME 113 8935
TEST_TIME 114 8993
TEST_TIME 115 8999
TEST_TIME 116 8932
TEST_TIME 117 8896
TEST_TIME 118 8891
TEST_TIME 119 8864
TEST_TIME 120 8825

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-12-2021 19:39:56 to 10-12-2021 19:40:00.

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 1482535 1482535 0 0.00 0 25071 6 0 0
doc_list_biud 1482531 1482531 0 0.00 0 27781 27 0 0
sp_add_invoice_to_stock 5150 4962 188 3.65 47 132672 17542 0 188
sp_cancel_adding_invoice 5254 5254 0 0.00 6 54290 814 0 0
sp_cancel_client_order 1791 1791 0 0.00 4 14073 328 0 0
sp_cancel_customer_reserve 515705 515705 0 0.00 1 21702 61 0 0
sp_cancel_pay_from_customer 708 708 0 0.00 3 6409 78 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 2 4459 71 0 0
sp_cancel_supplier_invoice 5822 5810 12 0.20 2 27795 854 0 12
sp_cancel_supplier_order 406 406 0 0.00 5 882395 99744 0 0
sp_cancel_write_off 9410 9410 0 0.00 1 7611 113 0 0
sp_client_order 21013 21013 0 0.00 3 19299 322 0 0
sp_customer_reserve 457048 457048 0 0.00 0 35486 174 0 0
sp_fill_shopping_cart 32057 32057 0 0.00 1 15257 40 0 0
sp_fill_shopping_cart_clo_res 457034 457034 0 0.00 0 21099 15 0 0
sp_get_clo_for_invoice 4948 4948 0 0.00 0 27790 343 0 0
sp_kill_cost_storno 15774 15774 0 0.00 0 11266 60 0 0
sp_kill_qstorno_ret_qs2qd 1047036 1047036 0 0.00 0 27775 33 0 0
sp_kill_qty_storno 553694 553694 0 0.00 0 27779 72 0 0
sp_lock_selected_doc 21059 21059 0 0.00 0 9037 6 0 0
sp_make_cost_storno 28517 28517 0 0.00 0 15288 51 0 0
sp_multiply_rows_for_pdistr 28515 28515 0 0.00 0 9204 29 0 0
sp_multiply_rows_for_qdistr 21013 21013 0 0.00 0 14959 135 0 0
sp_pay_from_customer 7256 7256 0 0.00 4 13796 119 0 0
sp_pay_to_supplier 5939 5939 0 0.00 5 14200 137 0 0
sp_payment_common 13195 13195 0 0.00 2 14138 33 0 0
sp_qd_handle_on_cancel_clo 1791 1791 0 0.00 0 14064 307 0 0
sp_qd_handle_on_invoice_upd_sts 10199 10199 0 0.00 0 15928 328 0 0
sp_qd_handle_on_reserve_upd_sts 10373 10373 0 0.00 0 21624 53 0 0
sp_reserve_write_off 10374 10374 0 0.00 6 21645 167 0 0
sp_supplier_invoice 5534 5534 0 0.00 6 80877 3479 0 0
sp_supplier_order 5512 5512 0 0.00 5 51777 1208 0 0
srv_aggregate_perf_data 89 89 0 0.00 2801 263609 23231 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 2539 137785 51931 0 0
srv_find_qd_qs_mism 1010967 1010967 0 0.00 0 25071 5 0 0
srv_make_invnt_saldo 588 588 0 0.00 38 39949 1070 0 0
srv_make_money_saldo 785 785 0 0.00 1 1017 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 127817 491836 248616 0 0
t$perf-abend:sp_add_invoice_to_stock 188 188 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4963 4963 0 0.00 0 23126 173 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 0 5919 92 0 0
t$perf-norm:sp_cancel_client_order 1791 1791 0 0.00 0 4248 13 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 524 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 708 708 0 0.00 0 4691 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 2931 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 2557 17 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 39887 1857 0 0
t$perf-norm:sp_cancel_write_off 975 975 0 0.00 0 6754 20 0 0
t$perf-norm:sp_client_order 21013 21013 0 0.00 0 11150 20 0 0
t$perf-norm:sp_customer_reserve 986 986 0 0.00 0 313 2 0 0
t$perf-norm:sp_pay_from_customer 7256 7256 0 0.00 0 6370 10 0 0
t$perf-norm:sp_pay_to_supplier 5939 5939 0 0.00 0 8545 12 0 0
t$perf-norm:sp_reserve_write_off 10374 10374 0 0.00 0 8819 15 0 0
t$perf-norm:sp_supplier_invoice 5534 5534 0 0.00 0 14410 21 0 0
t$perf-norm:sp_supplier_order 5512 5512 0 0.00 0 7578 17 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 369 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 205 7 0 0
t$perf-norm:srv_make_invnt_saldo 588 588 0 0.00 0 841 22 0 0
t$perf-norm:srv_make_money_saldo 785 785 0 0.00 0 8678 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10264 9888 376 3.66 0 6362 20 0 376
v_all_customers 38210 38210 0 0.00 0 12 0 0 0
v_all_suppliers 30892 30892 0 0.00 0 18 1 0 0
v_all_wares 42026 42026 0 0.00 0 50 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 6334 15 0 0
v_cancel_client_order 3582 3582 0 0.00 0 3023 5 0 0
v_cancel_customer_prepayment 1416 1416 0 0.00 0 6400 8 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 2929 9 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 4429 11 0 0
v_cancel_supplier_invoice 844 820 24 2.84 0 1202 14 0 24
v_cancel_supplier_order 812 812 0 0.00 0 3099 13 0 0
v_cancel_write_off 1950 1950 0 0.00 0 2068 12 0 0
v_max_id_clo_ord 5512 5512 0 0.00 0 1203 2 0 0
v_max_id_clo_res 986 986 0 0.00 0 1330 14 0 0
v_max_id_ord_sup 5532 5532 0 0.00 0 4538 7 0 0
v_max_non_paid_invoice 1537 1537 0 0.00 0 827 3 0 0
v_max_non_paid_realizn 3921 3921 0 0.00 0 13784 8 0 0
v_min_id_clo_ord 5512 5512 0 0.00 0 2448 8 0 0
v_min_id_clo_res 986 986 0 0.00 0 6864 21 0 0
v_min_id_ord_sup 5532 5532 0 0.00 0 6549 10 0 0
v_min_non_paid_invoice 5939 5939 0 0.00 0 14136 26 0 0
v_min_non_paid_realizn 7256 7256 0 0.00 0 7258 23 0 0
v_our_firm 10486 10486 0 0.00 0 19 1 0 0
v_reserve_write_off 20746 20746 0 0.00 0 5725 5 0 0
x_lock_depdocs_on_canc_invoice 10654 10654 0 0.00 0 21950 111 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 5561 280 0 0
x_make_qstorno_1000_1200 5512 5512 0 0.00 1 51766 1137 0 0
x_make_qstorno_1200_2000 5534 5534 0 0.00 3 80011 3327 0 0
x_make_qstorno_2100_3300 457048 457048 0 0.00 0 35485 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-12-2021 19:40:01 to 10-12-2021 19:40:05.

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:08:00 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:00 25671 8192000000 9907493312 10220544000 990548768 10.787 100 86 172 0 1019226896 6259248 22418880
17:10:30 26156 8192000000 9947938240 10271977472 1029788512 11.166 100 86 172 0 1060554352 8731152 22034688
17:12:36 10186 8192000000 9990320176 10330402816 1078587072 11.634 100 80 158 1 1108213728 9741184 19885472
17:14:00 9568 8192000000 10054775328 10407251968 1140420000 12.219 100 83 165 2 1172978256 12983584 19574672
17:15:14 7259 8192000000 10099208352 10462154752 1187263136 12.658 100 81 160 1 1218676480 13093328 18320016
17:16:25 3246 8192000000 10136093648 10510331904 1225973088 13.017 100 79 159 0 1255696176 11825904 17897184
17:17:31 10277 8192000000 10163886928 10540507136 1254876224 13.283 100 76 152 0 1285829952 14934208 16019520
17:18:43 3367 8192000000 10192235184 10575220736 1281997936 13.531 100 93 186 0 1317192368 14111376 21083056
17:19:48 24258 8192000000 10206147456 10601369600 1297185872 13.670 100 70 138 0 1324267344 11148224 15933248
17:21:13 5210 8192000000 10198246272 10604290048 1302766016 13.720 100 36 72 0 1319627968 9215408 7646544
17:22:20 7178 8192000000 10212120368 10623635456 1315179296 13.833 100 31 62 2 1333954896 11567856 7207744
17:23:29 13084 8192000000 10227329232 10632687616 1321056144 13.886 100 36 72 2 1334961056 5086272 8818640
17:24:46 21940 8192000000 10238594960 10636959744 1325665440 13.928 100 50 100 1 1345553120 6241376 13646304
17:26:08 11305 8192000000 10214027792 10633535488 1325032560 13.922 100 31 63 4 1339157216 7225008 6899648
17:27:20 13840 8192000000 10214811648 10633433088 1316923744 13.849 99 19 38 0 1326264912 4724624 4616544
17:28:35 10152 8192000000 10195196672 10607575040 1283615680 13.546 98 15 30 2 1292840000 5623024 3601296
17:29:47 26842 8192000000 10182656208 10591367168 1235925968 13.109 94 35 70 2 1250097168 6385088 7786112
17:31:20 3385 8192000000 10145739920 10541400064 1240253920 13.149 99 34 67 0 1255209024 6836912 8118192
17:32:25 5639 8192000000 10142337440 10545164288 1238525616 13.133 98 30 61 0 1251511232 5714016 7271600
17:33:32 12211 8192000000 10150745088 10556628992 1254687360 13.281 99 23 44 2 1265622752 5437232 5498160
17:34:44 38618 8192000000 10179196720 10594107392 1288277536 13.589 100 22 41 0 1302117872 8874480 4965856
17:36:23 7814 8192000000 10197432128 10613272576 1304234128 13.734 100 32 64 0 1317845024 6328880 7282016
17:37:33 14840 8192000000 10200154784 10619305984 1311309440 13.798 100 22 44 2 1322091216 5442608 5339168
17:38:53 57747 8192000000 10247150496 10661859328 1321692080 13.892 98 43 86 1 1339905424 8510656 9702688
17:40:59 41325 8192000000 10269560800 10691960832 1361216320 14.248 100 31 62 0 1378076448 9614784 7245344
17:43:16 9122 8192000000 10298238848 10717954048 1382716432 14.441 100 46 92 0 1413128224 19644112 10767680
17:44:29 13276 8192000000 10316451904 10732589056 1391679888 14.521 100 50 100 0 1421328672 17568736 12080048
17:45:47 2591 8192000000 10327767168 10744700928 1405043440 14.640 100 66 131 1 1438134288 17567616 15523232
17:46:52 16066 8192000000 10327058800 10753986560 1404173264 14.632 100 35 70 0 1440968256 27516640 9278352
17:48:19 8509 8192000000 10347726240 10775396352 1425541856 14.822 100 53 104 0 1460695200 22228336 12925008
17:49:30 4970 8192000000 10376045488 10798796800 1434974464 14.905 100 67 133 3 1482887712 31276608 16636640
17:50:44 8337 8192000000 10383586416 10805170176 1433749568 14.894 99 62 124 0 1483616928 34041488 15825872
17:51:57 8299 8192000000 10374061728 10801627136 1435836576 14.913 99 52 102 2 1483517552 34222640 13458336
17:53:10 3174 8192000000 10381184032 10794852352 1425308592 14.820 100 75 150 1 1485126080 41389392 18428096
17:54:20 4456 8192000000 10371645504 10777817088 1403969152 14.630 100 81 157 1 1468395904 44473104 19953648
17:55:30 2754 8192000000 10361616768 10772795392 1385567264 14.466 99 77 154 3 1453839984 48829680 19443040
17:56:39 2539 8192000000 10363945696 10779713536 1399394288 14.590 100 73 144 2 1466156352 47365776 19396288
17:57:52 14090 8192000000 10337973296 10774896640 1387304640 14.482 100 47 94 0 1452758240 52248112 13205488
17:59:11 12773 8192000000 10324056240 10765578240 1374987184 14.372 99 28 56 1 1428253808 45497728 7768896
18:00:53 25439 8192000000 10309292896 10748870656 1368676544 14.315 99 19 38 0 1401670272 28139280 4854448
18:02:21 29935 8192000000 10286514352 10723840000 1343738528 14.091 99 16 32 1 1384348976 35799840 4810608
18:03:53 17095 8192000000 10274701024 10711523328 1334578688 14.009 99 26 52 0 1373443312 32223072 6641552
18:05:15 58984 8192000000 10266150912 10706042880 1331173008 13.978 98 37 72 1 1354527200 14253584 9100608
18:07:18 66588 8192000000 10273182992 10714169344 1349171200 14.140 99 34 71 2 1380442640 23296832 7974608
18:09:26 66737 8192000000 10266697408 10709909504 1344838704 14.101 99 28 56 0 1369234048 18161328 6234016
18:11:36 34637 8192000000 10245573168 10701979648 1338861584 14.047 99 21 41 2 1362076960 18386576 4828800
18:13:16 56308 8192000000 10269877888 10721533952 1375215376 14.374 100 17 35 2 1388455952 8806240 4434336
18:15:31 45200 8192000000 10279615088 10726191104 1374807616 14.370 100 22 46 0 1395990848 16105632 5077600
18:17:17 86758 8192000000 10274566384 10732711936 1360356880 14.241 99 19 38 1 1382952192 17979024 4616288
18:19:54 54068 8192000000 10267663088 10720370688 1366500048 14.296 99 12 24 2 1379100928 9788256 2812624
18:21:55 89164 8192000000 10274843520 10726572032 1338498560 14.044 97 23 45 1 1357285648 13646416 5140672
18:24:26 107976 8192000000 10297868480 10747953152 1385050832 14.462 100 17 34 0 1408290416 19487824 3751760
18:27:25 71295 8192000000 10276067856 10745344000 1366542784 14.296 99 18 36 0 1397694080 26867840 4283456
18:29:48 53303 8192000000 10276908880 10736967680 1369708112 14.324 99 11 22 2 1381338672 9210064 2420496
18:31:42 41376 8192000000 10287190512 10738143232 1376643904 14.387 99 23 46 0 1389178352 7098160 5436288
18:33:25 44534 8192000000 10292988512 10745663488 1376991952 14.390 99 20 40 0 1388892912 7564624 4336336
18:35:10 31003 8192000000 10301247504 10750169088 1375791952 14.379 99 32 64 1 1402219056 19436736 6990368
18:37:29 53283 8192000000 10280698208 10726432768 1333759088 14.001 96 16 32 0 1345010640 8088784 3162768
18:39:23 58014 8192000000 10289783136 10737881088 1366116384 14.292 99 24 48 1 1384540928 13284688 5139856
18:41:21 74924 8192000000 10295797152 10735198208 1335107248 14.013 96 30 60 1 1358342000 16418688 6816064
18:43:42 137785 8192000000 10271801520 10723745792 1256388640 13.297 91 13 26 0 1280865472 22010496 2466336
18:47:02 64603 8192000000 10266033312 10700734464 1334020640 14.003 98 20 40 0 1349608736 10958160 4629936
18:49:07 66804 8192000000 10273795632 10726076416 1349591328 14.144 98 24 48 0 1367779056 12523376 5664352
18:51:29 57068 8192000000 10245401808 10709561344 1353009168 14.175 100 7 14 0 1365725776 11163424 1553184
18:53:26 75604 8192000000 10251380880 10706014208 1324497248 13.917 97 18 35 0 1338480032 9558320 4424464
18:55:44 81469 8192000000 10253776384 10706812928 1342015744 14.076 98 23 47 1 1364371312 16760480 5595088
18:58:44 42508 8192000000 10248530736 10702876672 1313672880 13.819 96 16 32 0 1329983232 12241536 4068816
19:00:31 55211 8192000000 10228003344 10653798400 1244544928 13.188 92 23 46 0 1259931040 10140144 5245968
19:02:27 50942 8192000000 10260875328 10697867264 1349855424 14.146 100 23 46 0 1369234096 14175216 5203456
19:04:18 43659 8192000000 10260674720 10699112448 1330497376 13.972 98 33 66 0 1349593056 10874496 8221184
19:06:03 99323 8192000000 10277854432 10725965824 1367061344 14.301 100 33 66 1 1389076624 14753216 7262064
19:08:57 113626 8192000000 10294307952 10734333952 1315293088 13.834 95 14 28 1 1329773456 12305216 2175152
19:11:51 96916 8192000000 10253188832 10706751488 1311861600 13.803 95 23 44 0 1324232288 7289968 5080720
19:14:29 52010 8192000000 10257340752 10715185152 1363939248 14.273 99 15 30 0 1375741712 8545488 3256976
19:16:22 40700 8192000000 10269814304 10729644032 1364227712 14.275 99 8 16 0 1375146688 9076464 1842512
19:18:02 61010 8192000000 10287978048 10741649408 1384903648 14.460 100 30 61 1 1405123712 13080416 7139648
19:20:11 94261 8192000000 10286910560 10741198848 1345581424 14.108 97 14 29 0 1361005312 12164416 3259472
19:22:48 82659 8192000000 10281062608 10729037824 1333567392 13.999 96 14 28 1 1344569808 7912464 3089952
19:25:16 88411 8192000000 10280605088 10737672192 1346463760 14.116 97 13 26 2 1363637200 14320048 2853392
19:28:07 40152 8192000000 10258336736 10704044032 1332649888 13.991 97 13 26 1 1344500560 9028560 2822112
19:30:09 104201 8192000000 10268226784 10727444480 1299389968 13.690 95 10 20 3 1312365776 10575008 2400800
19:32:56 126782 8192000000 10261621824 10705309696 1344694496 14.100 99 14 28 1 1362314624 14393472 3226656
19:36:04 63309 8192000000 10249240368 10714603520 1354190160 14.185 100 4 8 0 1369541232 13909168 1441904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 10-12-2021 19:40:07 to 10-12-2021 19:40:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 188 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-12-2021 19:40:22 to 10-12-2021 19:40:24.

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          240382
MON$OLDEST_ACTIVE               240935
MON$OLDEST_SNAPSHOT             240935
MON$NEXT_TRANSACTION            240935
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                       1988736
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.33542 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33542 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33542 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33542 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

10.12.21 19:40:25. 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-10 19:40:27 No severe PSQL-related problems occured
10.12.21 19:40:29. End of report.