Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.11.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 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 16491308 kB
MemAvailable 31521580 kB
Buffers 0 kB
Cached 15147716 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.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-11-25 19:37:57
DTS_END                         2021-11-25 19:37:57.9070
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35688
TRN_ID                          249708
ATT_ID                          745
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: 9680.59 23008 1161671
customer order: creation 187.69 319 22523
customer order: refuse 15.68 346 1882
order to supplier: creation 49.32 1246 5918
order to supplier: removal 3.65 99206 438
invoice (draft): creation 49.73 3283 5967
invoice (draft): removal 54.82 795 6578
invoice accept: apply 44.56 16905 5347
invoice accept: cancel 49.18 832 5902
customer reserve: creation 4110.83 168 493299
customer reserve: removal 4796.68 59 575602
realization accept: apply 91.22 170 10946
realization accept: cancel 84.65 109 10158
payment from customer: creation 65.38 110 7846
payment from customer: removal 5.83 77 699
payment to supplier: creation 53.57 118 6428
payment to supplier: removal 3.88 49 465
service: total inventory turnovers 5.43 1132 652
service: total monetary turnovers 7.48 33 898
service: aggregate perf. data 0.99 17622 119
service: refresh index statistics 0.03 317583 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 25-11-2021 19:39:14 to 25-11-2021 19:39:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1766
TEST_TIME 2 3110
TEST_TIME 3 3191
TEST_TIME 4 4873
TEST_TIME 5 5362
TEST_TIME 6 5454
TEST_TIME 7 6081
TEST_TIME 8 6665
TEST_TIME 9 6814
TEST_TIME 10 7034
TEST_TIME 11 6954
TEST_TIME 12 6868
TEST_TIME 13 7442
TEST_TIME 14 7572
TEST_TIME 15 7902
TEST_TIME 16 8227
TEST_TIME 17 8202
TEST_TIME 18 8332
TEST_TIME 19 8475
TEST_TIME 20 8506
TEST_TIME 21 9161
TEST_TIME 22 9067
TEST_TIME 23 9057
TEST_TIME 24 9347
TEST_TIME 25 9350
TEST_TIME 26 9846
TEST_TIME 27 10265
TEST_TIME 28 10615
TEST_TIME 29 10491
TEST_TIME 30 10790
TEST_TIME 31 11093
TEST_TIME 32 11452
TEST_TIME 33 11412
TEST_TIME 34 11339
TEST_TIME 35 11257
TEST_TIME 36 11066
TEST_TIME 37 11174
TEST_TIME 38 11057
TEST_TIME 39 11018
TEST_TIME 40 10873
TEST_TIME 41 11030
TEST_TIME 42 10959
TEST_TIME 43 11002
TEST_TIME 44 10847
TEST_TIME 45 10729
TEST_TIME 46 10590
TEST_TIME 47 10482
TEST_TIME 48 10454
TEST_TIME 49 10483
TEST_TIME 50 10391
TEST_TIME 51 10348
TEST_TIME 52 10252
TEST_TIME 53 10206
TEST_TIME 54 10363
TEST_TIME 55 10371
TEST_TIME 56 10376
TEST_TIME 57 10262
TEST_TIME 58 10284
TEST_TIME 59 10196
TEST_TIME 60 10093
TEST_TIME 61 10083
TEST_TIME 62 10026
TEST_TIME 63 9936
TEST_TIME 64 9888
TEST_TIME 65 9904
TEST_TIME 66 9908
TEST_TIME 67 9891
TEST_TIME 68 9915
TEST_TIME 69 9900
TEST_TIME 70 9956
TEST_TIME 71 9952
TEST_TIME 72 9994
TEST_TIME 73 9944
TEST_TIME 74 10012
TEST_TIME 75 10000
TEST_TIME 76 10001
TEST_TIME 77 10099
TEST_TIME 78 10100
TEST_TIME 79 10155
TEST_TIME 80 10054
TEST_TIME 81 10033
TEST_TIME 82 10001
TEST_TIME 83 9947
TEST_TIME 84 9929
TEST_TIME 85 9897
TEST_TIME 86 9888
TEST_TIME 87 9866
TEST_TIME 88 9841
TEST_TIME 89 9794
TEST_TIME 90 9794
TEST_TIME 91 9781
TEST_TIME 92 9805
TEST_TIME 93 9849
TEST_TIME 94 9821
TEST_TIME 95 9823
TEST_TIME 96 9855
TEST_TIME 97 9897
TEST_TIME 98 9869
TEST_TIME 99 9861
TEST_TIME 100 9897
TEST_TIME 101 9932
TEST_TIME 102 9895
TEST_TIME 103 9967
TEST_TIME 104 9939
TEST_TIME 105 9870
TEST_TIME 106 9833
TEST_TIME 107 9766
TEST_TIME 108 9769
TEST_TIME 109 9745
TEST_TIME 110 9732
TEST_TIME 111 9715
TEST_TIME 112 9718
TEST_TIME 113 9651
TEST_TIME 114 9623
TEST_TIME 115 9623
TEST_TIME 116 9630
TEST_TIME 117 9691
TEST_TIME 118 9664
TEST_TIME 119 9688
TEST_TIME 120 9683
TEST_TIME 121 9603

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-11-2021 19:39:18 to 25-11-2021 19:39:21.

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 1618251 1618251 0 0.00 0 38303 6 0 0
doc_list_biud 1618245 1618245 0 0.00 0 67477 27 0 0
sp_add_invoice_to_stock 5473 5347 126 2.30 33 189989 16905 0 126
sp_cancel_adding_invoice 5902 5902 0 0.00 5 60172 832 0 0
sp_cancel_client_order 1882 1882 0 0.00 4 12235 347 0 0
sp_cancel_customer_reserve 575602 575602 0 0.00 1 26428 59 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 2 4469 77 0 0
sp_cancel_pay_to_supplier 465 465 0 0.00 2 836 50 0 0
sp_cancel_supplier_invoice 6585 6578 7 0.10 2 67497 796 0 7
sp_cancel_supplier_order 438 438 0 0.00 5 1056916 99206 0 0
sp_cancel_write_off 10158 10158 0 0.00 1 25516 109 0 0
sp_client_order 22523 22523 0 0.00 3 28370 319 0 0
sp_customer_reserve 493299 493299 0 0.00 0 40461 168 0 0
sp_fill_shopping_cart 34383 34383 0 0.00 1 26075 37 0 0
sp_fill_shopping_cart_clo_res 493296 493296 0 0.00 0 11066 11 0 0
sp_get_clo_for_invoice 5335 5335 0 0.00 0 16842 248 0 0
sp_kill_cost_storno 17224 17224 0 0.00 0 28289 57 0 0
sp_kill_qstorno_ret_qs2qd 1168378 1168378 0 0.00 0 67470 32 0 0
sp_kill_qty_storno 616840 616840 0 0.00 0 67474 69 0 0
sp_lock_selected_doc 22501 22501 0 0.00 0 6605 4 0 0
sp_make_cost_storno 30555 30555 0 0.00 0 38276 53 0 0
sp_multiply_rows_for_pdistr 30554 30554 0 0.00 0 4434 26 0 0
sp_multiply_rows_for_qdistr 22523 22523 0 0.00 0 27270 140 0 0
sp_pay_from_customer 7846 7846 0 0.00 4 33518 110 0 0
sp_pay_to_supplier 6428 6428 0 0.00 5 13893 118 0 0
sp_payment_common 14269 14269 0 0.00 1 33508 28 0 0
sp_qd_handle_on_cancel_clo 1882 1882 0 0.00 0 12093 312 0 0
sp_qd_handle_on_invoice_upd_sts 11236 11236 0 0.00 0 23939 320 0 0
sp_qd_handle_on_reserve_upd_sts 10946 10946 0 0.00 0 21039 54 0 0
sp_reserve_write_off 10946 10946 0 0.00 6 21065 170 0 0
sp_supplier_invoice 5967 5967 0 0.00 6 71455 3284 0 0
sp_supplier_order 5918 5918 0 0.00 5 37719 1247 0 0
srv_aggregate_perf_data 119 119 0 0.00 2190 139707 17623 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1490 136806 43198 0 0
srv_find_qd_qs_mism 1109839 1109839 0 0.00 0 23889 5 0 0
srv_make_invnt_saldo 652 652 0 0.00 36 94309 1133 0 0
srv_make_money_saldo 898 898 0 0.00 1 4852 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 178753 497446 317584 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5347 5347 0 0.00 0 7224 161 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 4932 86 0 0
t$perf-norm:sp_cancel_client_order 1882 1882 0 0.00 0 7613 13 0 0
t$perf-norm:sp_cancel_customer_reserve 727 727 0 0.00 0 1907 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 936 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 465 465 0 0.00 0 2579 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 416 12 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 20441 1592 0 0
t$perf-norm:sp_cancel_write_off 1166 1166 0 0.00 0 816 8 0 0
t$perf-norm:sp_client_order 22527 22527 0 0.00 0 15285 17 0 0
t$perf-norm:sp_customer_reserve 1153 1153 0 0.00 0 1772 7 0 0
t$perf-norm:sp_pay_from_customer 7846 7846 0 0.00 0 4696 8 0 0
t$perf-norm:sp_pay_to_supplier 6428 6428 0 0.00 0 5991 11 0 0
t$perf-norm:sp_reserve_write_off 10946 10946 0 0.00 0 14440 13 0 0
t$perf-norm:sp_supplier_invoice 5968 5968 0 0.00 0 4541 19 0 0
t$perf-norm:sp_supplier_order 5918 5918 0 0.00 0 6331 15 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 679 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 416 11 0 0
t$perf-norm:srv_make_invnt_saldo 652 652 0 0.00 0 1307 20 0 0
t$perf-norm:srv_make_money_saldo 898 898 0 0.00 0 4121 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10916 10664 252 2.30 0 16791 21 0 252
v_all_customers 41356 41356 0 0.00 0 70 1 0 0
v_all_suppliers 33270 33270 0 0.00 0 67 1 0 0
v_all_wares 45032 45032 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 424 6 0 0
v_cancel_client_order 3764 3764 0 0.00 0 9726 9 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 3857 6 0 0
v_cancel_customer_reserve 1454 1454 0 0.00 0 419 3 0 0
v_cancel_payment_to_supplier 930 930 0 0.00 0 256 3 0 0
v_cancel_supplier_invoice 848 834 14 1.65 0 332 13 0 14
v_cancel_supplier_order 876 876 0 0.00 0 1313 5 0 0
v_cancel_write_off 2332 2332 0 0.00 0 2257 17 0 0
v_max_id_clo_ord 5909 5909 0 0.00 0 1377 2 0 0
v_max_id_clo_res 1153 1153 0 0.00 0 5148 11 0 0
v_max_id_ord_sup 5955 5955 0 0.00 0 3709 5 0 0
v_max_non_paid_invoice 1655 1655 0 0.00 0 75 2 0 0
v_max_non_paid_realizn 4087 4087 0 0.00 0 2696 6 0 0
v_min_id_clo_ord 5909 5909 0 0.00 0 2981 8 0 0
v_min_id_clo_res 1153 1153 0 0.00 0 2878 9 0 0
v_min_id_ord_sup 5955 5955 0 0.00 0 12983 13 0 0
v_min_non_paid_invoice 6426 6426 0 0.00 0 6448 15 0 0
v_min_non_paid_realizn 7843 7843 0 0.00 0 33504 26 0 0
v_our_firm 11188 11188 0 0.00 0 14 1 0 0
v_reserve_write_off 21888 21888 0 0.00 0 11313 8 0 0
x_lock_depdocs_on_canc_invoice 12063 12063 0 0.00 0 13396 121 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 7902 339 0 0
x_make_qstorno_1000_1200 5918 5918 0 0.00 2 37710 1162 0 0
x_make_qstorno_1200_2000 5967 5967 0 0.00 2 71395 3158 0 0
x_make_qstorno_2100_3300 493299 493299 0 0.00 0 40460 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-11-2021 19:39:22 to 25-11-2021 19:39:26.

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:01 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:02 28893 8192000000 9889040480 10199539712 971670304 10.603 100 89 178 0 1001967312 5962992 24334016
17:10:37 14403 8192000000 9928740512 10246000640 1011909808 10.994 100 89 178 0 1043054960 7812816 23332336
17:11:57 19722 8192000000 9971056096 10306842624 1049469424 11.356 100 75 149 1 1076930976 8682512 18779040
17:13:32 7082 8192000000 10038438944 10386571264 1124965296 12.074 100 75 149 0 1153233472 10502880 17765296
17:14:43 5526 8192000000 10085905216 10445602816 1173734704 12.532 100 84 168 0 1205575296 12755312 19085280
17:15:53 8319 8192000000 10131150320 10496364544 1209292560 12.863 100 80 160 0 1241614608 14655984 17666064
17:17:02 2698 8192000000 10153365728 10529804288 1242781120 13.172 100 87 173 1 1275062192 13309072 18972000
17:18:07 1756 8192000000 10182515392 10561409024 1271561360 13.436 100 89 178 1 1306001136 14749088 19690688
17:19:10 2216 8192000000 10203741024 10587021312 1288489664 13.590 100 90 181 3 1322226912 12823280 20913968
17:20:14 4450 8192000000 10205397216 10598080512 1299757792 13.693 100 65 129 6 1321885136 6335664 15791680
17:21:20 4872 8192000000 10203699360 10608857088 1307873424 13.767 100 40 82 2 1322336144 5465152 8997568
17:22:26 6162 8192000000 10205021728 10618052608 1314431872 13.826 100 27 54 0 1326047040 5287792 6327376
17:23:34 13523 8192000000 10206032224 10626289664 1323072192 13.905 100 18 35 1 1333146672 6174560 3899920
17:24:54 9188 8192000000 10234569408 10644230144 1326784624 13.938 100 57 112 2 1345988400 4676112 14527664
17:26:04 5728 8192000000 10227216672 10646659072 1332614768 13.991 100 36 72 0 1347766304 6955184 8196352
17:27:11 5707 8192000000 10216651104 10628853760 1302908224 13.722 98 38 77 4 1319346768 8158848 8279696
17:28:20 22481 8192000000 10195463728 10620448768 1285632352 13.564 97 22 44 1 1296973536 6249744 5091440
17:29:45 5748 8192000000 10177182848 10585812992 1262525248 13.353 97 23 46 2 1273649152 5945152 5178752
17:30:55 7088 8192000000 10149380208 10556456960 1226922480 13.026 96 24 48 1 1239268592 5800560 6545552
17:32:03 54444 8192000000 10132128832 10540531712 1158844208 12.392 91 20 39 1 1168584928 5489440 4251280
17:33:58 7182 8192000000 10160100064 10562150400 1264624160 13.372 100 24 48 1 1277378848 5933216 6821472
17:35:06 7729 8192000000 10186503024 10592079872 1289099776 13.596 100 25 49 1 1301170496 5661808 6408912
17:36:16 10796 8192000000 10189926032 10605801472 1306342272 13.753 100 19 39 2 1316014192 5505872 4166048
17:37:28 34209 8192000000 10205145744 10615185408 1307199520 13.761 100 29 58 1 1320360496 6038016 7122960
17:39:11 15738 8192000000 10247159696 10652516352 1334930992 14.012 100 47 91 3 1353822688 8216400 10675296
17:40:36 17446 8192000000 10259616592 10672951296 1352686800 14.172 100 45 88 0 1372583600 9778048 10118752
17:41:59 11489 8192000000 10276010800 10696372224 1369463008 14.322 100 43 86 2 1389242512 9300352 10479152
17:43:16 12268 8192000000 10295754336 10714038272 1383417840 14.447 100 49 98 3 1408273792 13439216 11416736
17:44:32 2318 8192000000 10309529056 10725150720 1387408336 14.483 100 65 127 1 1417232480 15292000 14532144
17:45:49 2065 8192000000 10316528464 10731569152 1387532240 14.484 100 67 131 0 1419323664 17364080 14427344
17:46:55 7624 8192000000 10330889696 10747895808 1399007168 14.586 100 71 141 0 1436849440 21502368 16339904
17:48:05 13831 8192000000 10322383744 10742202368 1394424352 14.545 100 65 127 1 1431381264 22081952 14874960
17:49:43 5231 8192000000 10356115760 10775363584 1409354016 14.678 100 68 136 1 1461797008 36294784 16148208
17:50:54 1490 8192000000 10368721136 10778550272 1413634240 14.716 100 85 167 1 1466965504 33356464 19974800
17:51:59 3294 8192000000 10342186848 10759159808 1394404976 14.545 100 75 150 2 1451870464 39106496 18358992
17:53:06 3919 8192000000 10349256320 10767806464 1401105520 14.605 100 63 124 0 1452769424 36748208 14915696
17:54:20 3860 8192000000 10347163792 10769960960 1405290384 14.642 100 54 108 0 1456746912 37132032 14324496
17:55:34 3632 8192000000 10357558896 10771714048 1401746224 14.611 100 65 128 0 1459320864 41517152 16057488
17:56:41 2992 8192000000 10346494592 10752827392 1381813760 14.433 100 81 162 2 1446247648 43104640 21329248
17:57:52 5341 8192000000 10354736848 10769240064 1388601456 14.493 100 67 133 3 1454488272 48948016 16938800
17:59:00 5957 8192000000 10346309312 10773139456 1391571184 14.520 100 62 123 3 1448905440 41963440 15370816
18:00:11 6210 8192000000 10358817248 10785243136 1399963392 14.595 100 55 108 1 1463381952 49149168 14269392
18:01:21 6248 8192000000 10356945072 10787733504 1390477120 14.510 100 46 90 0 1460089088 56946064 12665904
18:02:51 19484 8192000000 10351088048 10786082816 1384816880 14.460 100 34 68 2 1454289024 58940912 10531232
18:04:15 13078 8192000000 10348475072 10775662592 1371619168 14.342 99 45 90 2 1441355072 57609296 12126608
18:05:30 21879 8192000000 10313257296 10753626112 1368949936 14.318 100 26 52 0 1413990064 36875024 8165104
18:06:56 24729 8192000000 10309836208 10760433664 1367165200 14.302 100 20 40 0 1414144144 41461728 5517216
18:08:30 30382 8192000000 10305377824 10766393344 1377151632 14.391 100 18 37 2 1421319200 39416368 4751200
18:10:35 95086 8192000000 10294410608 10754117632 1351094832 14.157 98 13 26 0 1385686448 31762048 2829568
18:13:12 81349 8192000000 10301496288 10746818560 1382149872 14.436 100 9 18 0 1396387008 12309568 1927568
18:15:40 41832 8192000000 10285202720 10737520640 1368940640 14.318 99 15 30 1 1380740688 8652448 3147600
18:17:23 105858 8192000000 10306405440 10773176320 1360780960 14.244 98 17 34 0 1379653632 15197008 3675664
18:20:09 93631 8192000000 10310620752 10760052736 1378278832 14.401 99 23 44 2 1400918784 18551904 4088048
18:22:43 81691 8192000000 10297669216 10745540608 1375308144 14.375 99 27 54 1 1390718976 9125584 6285248
18:25:06 10259 8192000000 10308878064 10745131008 1372781472 14.352 99 63 125 5 1397973504 10597200 14594832
18:26:19 36499 8192000000 10286404224 10737848320 1360049760 14.238 98 27 54 0 1379618752 13281200 6287792
18:28:16 51212 8192000000 10301404192 10754875392 1369032464 14.318 98 20 40 1 1382260864 8355248 4873152
18:30:08 33488 8192000000 10329945696 10783592448 1407472592 14.661 100 44 87 1 1434419696 15923808 11023296
18:31:48 43703 8192000000 10288625600 10757672960 1375367664 14.375 99 12 24 0 1397887552 19396192 3123696
18:35:40 61737 8192000000 10274643488 10737205248 1359293888 14.231 98 18 36 0 1371920256 8526176 4100192
18:38:13 54093 8192000000 10253306320 10715447296 1354005952 14.184 99 27 54 1 1369258064 9311936 5940176
18:40:09 52275 8192000000 10279927424 10737704960 1355576864 14.198 98 15 30 0 1364317264 5430208 3310192
18:42:07 56637 8192000000 10268700656 10733666304 1341875024 14.074 98 11 22 0 1355930144 11742000 2313120
18:44:05 35182 8192000000 10259169184 10711838720 1317237344 13.852 96 22 43 0 1329864832 8086576 4540912
18:45:43 42570 8192000000 10261693392 10712854528 1334806352 14.011 98 34 68 1 1355801776 12998928 7996496
18:47:33 79977 8192000000 10254986400 10698514432 1249494816 13.234 92 21 42 0 1263876096 9756176 4625104
18:50:25 60426 8192000000 10252537040 10699100160 1283573840 13.546 95 35 71 2 1303196256 11888464 7733952
18:52:25 39232 8192000000 10268740160 10710257664 1348899872 14.138 100 17 34 0 1360393488 8131728 3361888
18:54:07 66820 8192000000 10267636640 10715635712 1344005760 14.094 99 16 32 1 1368366080 19662384 4697936
18:56:14 136806 8192000000 10285479392 10737516544 1364930384 14.282 100 9 18 1 1380300768 13376016 1994368
18:59:39 68642 8192000000 10278386192 10734993408 1371618032 14.342 100 28 56 0 1392541008 14994096 5928880
19:01:51 71028 8192000000 10272688576 10740645888 1368307008 14.312 99 18 36 1 1384992752 12617360 4068384
19:04:06 73047 8192000000 10267945872 10730954752 1345554304 14.107 98 17 34 0 1361025184 11080192 4390688
19:06:49 69035 8192000000 10280271360 10739245056 1366779536 14.298 99 18 36 0 1378868224 7710432 4378256
19:08:58 34784 8192000000 10301565248 10760151040 1367156768 14.302 98 28 55 3 1385233600 11663760 6413072
19:10:33 60028 8192000000 10294770944 10748116992 1339074640 14.049 96 10 20 0 1348326320 7385136 1866544
19:12:34 68871 8192000000 10304164752 10777993216 1359582848 14.234 97 14 28 0 1377751680 15163376 3005456
19:14:51 54938 8192000000 10310201616 10760491008 1378388848 14.402 99 11 22 0 1395784320 14726192 2669280
19:16:46 25309 8192000000 10303127232 10748207104 1365490336 14.287 98 37 74 1 1385682496 12162448 8029712
19:18:16 90930 8192000000 10295910816 10734800896 1301717344 13.711 94 15 30 0 1314934192 9653392 3563456
19:20:49 105315 8192000000 10278214752 10728083456 1297244256 13.670 94 13 26 0 1313239888 13384960 2610672
19:23:44 66078 8192000000 10266545104 10715910144 1330262944 13.970 97 20 40 0 1343963184 8793408 4906832
19:25:57 64970 8192000000 10243169328 10703683584 1309814784 13.784 96 19 38 2 1326325216 11740576 4769856
19:28:37 68008 8192000000 10258046240 10708004864 1343218160 14.086 99 13 26 1 1360965952 14124816 3622976
19:30:46 37000 8192000000 10265228960 10707873792 1331773024 13.983 98 21 40 1 1350665600 13749152 5143424
19:32:23 96852 8192000000 10268171136 10722963456 1317317888 13.852 96 13 23 1 1334770864 14510864 2942112
19:35:00 48610 8192000000 10278341248 10733752320 1372973232 14.354 100 29 58 0 1388651152 9217088 6460832
19:36:59 69244 8192000000 9353144608 9658896384 483806144 5.576 36 5 10 0 493678304 8669328 1202832

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-11-2021 19:39:28 to 25-11-2021 19:39:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 126 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-11-2021 19:39:44 to 25-11-2021 19:39:46.

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          248896
MON$OLDEST_ACTIVE               249951
MON$OLDEST_SNAPSHOT             249951
MON$NEXT_TRANSACTION            249951
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                       2056096
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.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
:351: 
:352: mscs-test-161.budzdorov.ru    Thu Nov 25 19:38:13 2021
:353:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:354: 
:355: 
:356: mscs-test-161.budzdorov.ru    Thu Nov 25 19:38:28 2021
:357:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:358: 
--- end of diff output ---

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

25.11.21 19:39:47. 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-11-25 19:39:50 No severe PSQL-related problems occured
25.11.21 19:39:51. End of report.