Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.12.2021 01: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 16316544 kB
MemAvailable 31356624 kB
Buffers 0 kB
Cached 15321820 kB
SwapCached 440 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-25 01:38:31
DTS_END                         2021-12-25 01:38:00.0540
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/39658
TRN_ID                          249809
ATT_ID                          274
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: 9755.05 20148 1170606
customer order: creation 185.27 324 22232
customer order: refuse 15.79 299 1895
order to supplier: creation 48.83 1224 5859
order to supplier: removal 3.98 92700 477
invoice (draft): creation 49.17 3537 5900
invoice (draft): removal 55.60 750 6672
invoice accept: apply 43.97 17928 5276
invoice accept: cancel 49.97 851 5996
customer reserve: creation 4061.56 178 487387
customer reserve: removal 4926.34 60 591161
realization accept: apply 90.38 187 10846
realization accept: cancel 85.22 114 10226
payment from customer: creation 64.09 102 7691
payment from customer: removal 6.03 71 723
payment to supplier: creation 52.01 120 6241
payment to supplier: removal 3.48 61 418
service: total inventory turnovers 5.21 1135 625
service: total monetary turnovers 7.35 27 882
service: aggregate perf. data 0.79 24911 95
service: refresh index statistics 0.03 258388 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-12-2021 01:39:04 to 25-12-2021 01:39:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1259
TEST_TIME 2 1982
TEST_TIME 3 3104
TEST_TIME 4 3752
TEST_TIME 5 4671
TEST_TIME 6 4931
TEST_TIME 7 5197
TEST_TIME 8 5221
TEST_TIME 9 5537
TEST_TIME 10 5788
TEST_TIME 11 5813
TEST_TIME 12 6208
TEST_TIME 13 6591
TEST_TIME 14 6742
TEST_TIME 15 6967
TEST_TIME 16 7052
TEST_TIME 17 7314
TEST_TIME 18 7484
TEST_TIME 19 7622
TEST_TIME 20 7789
TEST_TIME 21 8177
TEST_TIME 22 8330
TEST_TIME 23 8444
TEST_TIME 24 8283
TEST_TIME 25 8695
TEST_TIME 26 8829
TEST_TIME 27 9222
TEST_TIME 28 9285
TEST_TIME 29 9511
TEST_TIME 30 9772
TEST_TIME 31 10463
TEST_TIME 32 11024
TEST_TIME 33 10910
TEST_TIME 34 10800
TEST_TIME 35 10683
TEST_TIME 36 10463
TEST_TIME 37 10835
TEST_TIME 38 10783
TEST_TIME 39 10758
TEST_TIME 40 10653
TEST_TIME 41 10468
TEST_TIME 42 10304
TEST_TIME 43 10384
TEST_TIME 44 10329
TEST_TIME 45 10224
TEST_TIME 46 10225
TEST_TIME 47 10214
TEST_TIME 48 10059
TEST_TIME 49 10288
TEST_TIME 50 10304
TEST_TIME 51 10265
TEST_TIME 52 10268
TEST_TIME 53 10143
TEST_TIME 54 10055
TEST_TIME 55 10037
TEST_TIME 56 9989
TEST_TIME 57 9922
TEST_TIME 58 9868
TEST_TIME 59 9725
TEST_TIME 60 9713
TEST_TIME 61 9637
TEST_TIME 62 9758
TEST_TIME 63 9705
TEST_TIME 64 9788
TEST_TIME 65 9824
TEST_TIME 66 9884
TEST_TIME 67 9849
TEST_TIME 68 9890
TEST_TIME 69 9913
TEST_TIME 70 9901
TEST_TIME 71 10034
TEST_TIME 72 9956
TEST_TIME 73 10051
TEST_TIME 74 10079
TEST_TIME 75 10062
TEST_TIME 76 9952
TEST_TIME 77 9937
TEST_TIME 78 9860
TEST_TIME 79 9826
TEST_TIME 80 9792
TEST_TIME 81 9796
TEST_TIME 82 9761
TEST_TIME 83 9759
TEST_TIME 84 9730
TEST_TIME 85 9816
TEST_TIME 86 9811
TEST_TIME 87 9786
TEST_TIME 88 9789
TEST_TIME 89 9773
TEST_TIME 90 9783
TEST_TIME 91 9831
TEST_TIME 92 9846
TEST_TIME 93 9867
TEST_TIME 94 9937
TEST_TIME 95 9942
TEST_TIME 96 10022
TEST_TIME 97 9962
TEST_TIME 98 10025
TEST_TIME 99 9935
TEST_TIME 100 9890
TEST_TIME 101 9865
TEST_TIME 102 9834
TEST_TIME 103 9799
TEST_TIME 104 9772
TEST_TIME 105 9741
TEST_TIME 106 9729
TEST_TIME 107 9683
TEST_TIME 108 9638
TEST_TIME 109 9668
TEST_TIME 110 9673
TEST_TIME 111 9661
TEST_TIME 112 9669
TEST_TIME 113 9706
TEST_TIME 114 9696
TEST_TIME 115 9678
TEST_TIME 116 9669
TEST_TIME 117 9706
TEST_TIME 118 9735
TEST_TIME 119 9772
TEST_TIME 120 9757

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-12-2021 01:39:08 to 25-12-2021 01: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 1622925 1622925 0 0.00 0 20941 6 0 0
doc_list_biud 1622923 1622923 0 0.00 0 28860 27 0 0
sp_add_invoice_to_stock 5389 5276 113 2.09 27 202516 17928 0 113
sp_cancel_adding_invoice 5996 5996 0 0.00 5 88852 851 0 0
sp_cancel_client_order 1895 1895 0 0.00 4 9675 300 0 0
sp_cancel_customer_reserve 591161 591161 0 0.00 1 28862 60 0 0
sp_cancel_pay_from_customer 723 723 0 0.00 2 4160 71 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 2 1796 61 0 0
sp_cancel_supplier_invoice 6684 6672 12 0.17 1 23985 751 0 12
sp_cancel_supplier_order 477 477 0 0.00 6 1041783 92701 0 0
sp_cancel_write_off 10226 10226 0 0.00 1 23228 114 0 0
sp_client_order 22232 22232 0 0.00 3 22545 324 0 0
sp_customer_reserve 487387 487387 0 0.00 0 96697 178 0 0
sp_fill_shopping_cart 33980 33980 0 0.00 1 15503 33 0 0
sp_fill_shopping_cart_clo_res 487385 487385 0 0.00 0 23164 12 0 0
sp_get_clo_for_invoice 5274 5274 0 0.00 0 30017 275 0 0
sp_kill_cost_storno 17363 17363 0 0.00 0 8933 61 0 0
sp_kill_qstorno_ret_qs2qd 1199697 1199697 0 0.00 0 28859 32 0 0
sp_kill_qty_storno 632545 632545 0 0.00 0 28860 69 0 0
sp_lock_selected_doc 22403 22403 0 0.00 0 8911 6 0 0
sp_make_cost_storno 30052 30052 0 0.00 0 20936 59 0 0
sp_multiply_rows_for_pdistr 30050 30050 0 0.00 0 6784 28 0 0
sp_multiply_rows_for_qdistr 22232 22232 0 0.00 0 10923 138 0 0
sp_pay_from_customer 7691 7691 0 0.00 4 14249 103 0 0
sp_pay_to_supplier 6241 6241 0 0.00 5 16068 120 0 0
sp_payment_common 13932 13932 0 0.00 1 11406 23 0 0
sp_qd_handle_on_cancel_clo 1895 1895 0 0.00 0 9667 273 0 0
sp_qd_handle_on_invoice_upd_sts 11269 11269 0 0.00 0 20192 319 0 0
sp_qd_handle_on_reserve_upd_sts 10845 10845 0 0.00 0 17021 60 0 0
sp_reserve_write_off 10846 10846 0 0.00 6 20962 188 0 0
sp_supplier_invoice 5900 5900 0 0.00 7 100434 3537 0 0
sp_supplier_order 5859 5859 0 0.00 5 77732 1224 0 0
srv_aggregate_perf_data 95 95 0 0.00 3170 359742 24911 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1354 131629 45089 0 0
srv_find_qd_qs_mism 1120203 1120203 0 0.00 0 17542 5 0 0
srv_make_invnt_saldo 625 625 0 0.00 35 85348 1135 0 0
srv_make_money_saldo 882 882 0 0.00 1 2006 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 166056 373282 258389 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 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 5277 5277 0 0.00 0 14694 166 0 0
t$perf-norm:sp_cancel_adding_invoice 457 457 0 0.00 0 4216 111 0 0
t$perf-norm:sp_cancel_client_order 1895 1895 0 0.00 0 1107 10 0 0
t$perf-norm:sp_cancel_customer_reserve 724 724 0 0.00 0 1186 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 723 723 0 0.00 0 3166 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 209 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 444 444 0 0.00 0 862 20 0 0
t$perf-norm:sp_cancel_supplier_order 477 477 0 0.00 0 16400 1363 0 0
t$perf-norm:sp_cancel_write_off 1147 1147 0 0.00 0 2109 8 0 0
t$perf-norm:sp_client_order 22233 22233 0 0.00 0 24679 17 0 0
t$perf-norm:sp_customer_reserve 1081 1081 0 0.00 0 2223 8 0 0
t$perf-norm:sp_pay_from_customer 7692 7692 0 0.00 0 19906 11 0 0
t$perf-norm:sp_pay_to_supplier 6241 6241 0 0.00 0 5973 13 0 0
t$perf-norm:sp_reserve_write_off 10846 10846 0 0.00 0 3782 9 0 0
t$perf-norm:sp_supplier_invoice 5900 5900 0 0.00 0 7832 19 0 0
t$perf-norm:sp_supplier_order 5859 5859 0 0.00 0 10212 18 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 150 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 49 1 0 0
t$perf-norm:srv_make_invnt_saldo 625 625 0 0.00 0 2516 22 0 0
t$perf-norm:srv_make_money_saldo 882 882 0 0.00 0 989 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 11 4 0 0
v_add_invoice_to_stock 10772 10546 226 2.09 0 8952 21 0 226
v_all_customers 40666 40666 0 0.00 0 273 1 0 0
v_all_suppliers 32968 32968 0 0.00 0 179 1 0 0
v_all_wares 44464 44464 0 0.00 0 274 1 0 0
v_cancel_adding_invoice 914 914 0 0.00 0 11184 18 0 0
v_cancel_client_order 3790 3790 0 0.00 0 1542 3 0 0
v_cancel_customer_prepayment 1446 1446 0 0.00 0 2196 7 0 0
v_cancel_customer_reserve 1448 1448 0 0.00 0 923 3 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 1776 6 0 0
v_cancel_supplier_invoice 912 888 24 2.63 0 1954 17 0 24
v_cancel_supplier_order 954 954 0 0.00 0 592 4 0 0
v_cancel_write_off 2294 2294 0 0.00 0 3940 17 0 0
v_max_id_clo_ord 5859 5859 0 0.00 0 550 1 0 0
v_max_id_clo_res 1081 1081 0 0.00 0 1435 14 0 0
v_max_id_ord_sup 5889 5889 0 0.00 0 4111 2 0 0
v_max_non_paid_invoice 1505 1505 0 0.00 0 134 2 0 0
v_max_non_paid_realizn 3959 3959 0 0.00 0 3912 5 0 0
v_min_id_clo_ord 5859 5859 0 0.00 0 1809 5 0 0
v_min_id_clo_res 1081 1081 0 0.00 0 304 2 0 0
v_min_id_ord_sup 5889 5889 0 0.00 0 3665 5 0 0
v_min_non_paid_invoice 6241 6241 0 0.00 0 11401 18 0 0
v_min_non_paid_realizn 7691 7691 0 0.00 0 5953 15 0 0
v_our_firm 11262 11262 0 0.00 0 14 1 0 0
v_reserve_write_off 21690 21690 0 0.00 0 2930 6 0 0
x_lock_depdocs_on_canc_invoice 12224 12224 0 0.00 0 19736 119 0 0
x_lock_depdocs_on_canc_sup_ord 477 477 0 0.00 0 19765 358 0 0
x_make_qstorno_1000_1200 5859 5859 0 0.00 1 75970 1148 0 0
x_make_qstorno_1200_2000 5900 5900 0 0.00 2 100411 3420 0 0
x_make_qstorno_2100_3300 487387 487387 0 0.00 0 96695 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-12-2021 01:39:12 to 25-12-2021 01: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
23:08:03 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:04 31921 8192000000 9894805120 10205655040 981976848 10.703 100 87 174 0 1010343552 5695136 22671568
23:10:42 22332 8192000000 9943173328 10261581824 1021803584 11.089 100 93 186 0 1054193872 8529264 23861024
23:12:10 19067 8192000000 9982061024 10313191424 1060908400 11.465 100 84 168 0 1091500816 9929472 20662944
23:13:34 14691 8192000000 10032833280 10381651968 1125256304 12.077 100 76 152 0 1153488656 10584384 17647968
23:14:53 6125 8192000000 10085450048 10446278656 1175804288 12.551 100 78 156 0 1206044160 12350992 17888880
23:16:02 4140 8192000000 10127207568 10500157440 1220335968 12.965 100 79 158 0 1250597296 12855376 17405952
23:17:08 3572 8192000000 10162010624 10541596672 1256203984 13.295 100 86 171 2 1289735616 13751488 19780144
23:18:14 3691 8192000000 10197713648 10579365888 1283929248 13.549 100 83 166 0 1315041680 12706528 18405904
23:19:19 23983 8192000000 10203833136 10601189376 1299884112 13.694 100 62 125 0 1323987472 9555744 14547616
23:20:51 5277 8192000000 10193090720 10606747648 1313244176 13.815 100 29 58 0 1326782752 7076928 6461648
23:22:00 8033 8192000000 10202234880 10615988224 1316893904 13.849 100 29 55 1 1329511216 6687904 5929408
23:23:08 63988 8192000000 10204916224 10629935104 1322362240 13.898 100 26 52 1 1338873264 10090352 6420672
23:25:14 5764 8192000000 10225259200 10643271680 1329940480 13.967 100 21 42 2 1341222976 6587504 4694992
23:26:20 6120 8192000000 10220354016 10641858560 1330725456 13.974 100 25 49 2 1343177440 6796480 5655504
23:27:27 10041 8192000000 10213053888 10627801088 1302290016 13.716 98 20 40 1 1312424352 5992160 4142176
23:28:38 18807 8192000000 10231867344 10632372224 1310491872 13.791 99 77 157 0 1334102816 5746688 17864256
23:29:58 3821 8192000000 10159800288 10562703360 1248479520 13.224 98 41 82 2 1266268528 8158064 9630944
23:31:05 10992 8192000000 10132809744 10528120832 1209000688 12.860 95 24 48 1 1220493728 5683312 5809728
23:32:20 43188 8192000000 10163859088 10566471680 1246757984 13.208 98 31 63 0 1260375888 6184768 7433136
23:34:18 6009 8192000000 10186515200 10591342592 1274534816 13.463 99 34 68 1 1289429792 7345280 7549696
23:35:30 8566 8192000000 10187201120 10601607168 1295935712 13.658 100 24 48 2 1306938640 5153920 5849008
23:36:40 7787 8192000000 10203504784 10617217024 1309134928 13.778 100 25 50 0 1320461648 5226848 6099872
23:37:48 92525 8192000000 10236480528 10646937600 1329225616 13.960 100 31 60 1 1342256352 5671440 7359296
23:40:42 21491 8192000000 10272540048 10684985344 1361699408 14.253 100 59 117 0 1386272848 10912688 13660752
23:42:10 48831 8192000000 10271535056 10700771328 1369328144 14.321 100 35 70 0 1389286592 12041952 7916496
23:44:02 9991 8192000000 10292912640 10712719360 1385562208 14.466 100 54 105 0 1411836480 14038432 12235840
23:45:19 17643 8192000000 10309135136 10738184192 1396838384 14.567 100 49 99 2 1423934656 15741792 11354480
23:46:42 6127 8192000000 10327676784 10750943232 1404617184 14.636 100 53 106 0 1435560896 18247584 12696128
23:47:53 12334 8192000000 10338343760 10766139392 1417238608 14.748 100 62 123 0 1450272560 18320128 14713824
23:49:09 3845 8192000000 10336775376 10762903552 1406108592 14.649 100 66 132 0 1446038032 24240864 15688576
23:50:21 1353 8192000000 10347747504 10762682368 1409911808 14.683 100 92 183 0 1451289008 20448912 20928288
23:51:27 6053 8192000000 10345596992 10767032320 1410359360 14.687 100 70 139 1 1451060800 24279520 16421920
23:52:37 3447 8192000000 10360042848 10777174016 1416890032 14.745 100 79 152 1 1464163744 29223984 18049728
23:53:45 2263 8192000000 10382546544 10803273728 1431272992 14.873 100 80 159 2 1486341840 35072656 19996192
23:54:53 2327 8192000000 10370561312 10780385280 1410031760 14.684 100 90 182 0 1468595728 36870160 21693808
23:56:03 3061 8192000000 10345316192 10753519616 1383850592 14.451 100 78 154 1 1444330416 41069232 19410592
23:57:10 3723 8192000000 10345708672 10754207744 1382548176 14.439 100 86 170 1 1448589648 44206368 21835104
23:58:18 3409 8192000000 10364161104 10773561344 1390999360 14.515 100 83 167 2 1465407152 53435040 20972752
23:59:25 6107 8192000000 10316872800 10730762240 1356710688 14.208 98 49 98 1 1410690192 41404512 12574992
00:00:39 5494 8192000000 10347324720 10764042240 1378434512 14.403 99 48 96 1 1440399184 49222304 12742368
00:01:52 9037 8192000000 10356366192 10783277056 1396725456 14.566 100 51 102 0 1463644656 52177552 14741648
00:03:09 8405 8192000000 10372593184 10802749440 1413584112 14.716 100 47 94 0 1472993808 46585344 12824352
00:04:21 15196 8192000000 10367110768 10811228160 1414738304 14.726 100 30 58 2 1475297600 51785792 8773504
00:05:52 21641 8192000000 10356248768 10806153216 1387817792 14.486 99 21 42 0 1446558336 52508560 6231984
00:07:57 29592 8192000000 10302224080 10756902912 1342758032 14.082 98 20 38 1 1400404848 52139248 5507568
00:09:28 61948 8192000000 10271128032 10703937536 1329216800 13.960 97 17 35 0 1340779936 8177936 3385200
00:11:30 71390 8192000000 10307017200 10753175552 1386634992 14.476 100 15 29 1 1398294912 7721328 3938592
00:13:44 66438 8192000000 10317949552 10786762752 1404817328 14.638 100 18 36 0 1423810896 14899536 4094032
00:16:00 83054 8192000000 10325068560 10763030528 1381604704 14.431 99 19 38 0 1404196944 18497760 4094480
00:18:26 77101 8192000000 10326303872 10772897792 1400452256 14.599 100 34 68 0 1421016816 12513504 8051056
00:20:45 106293 8192000000 10311736304 10759622656 1391778592 14.522 99 25 50 1 1409025248 12105808 5140848
00:23:32 54816 8192000000 10325961104 10786324480 1410803568 14.691 100 19 38 0 1424745072 9377328 4564176
00:25:28 70129 8192000000 10337186880 10808905728 1411406544 14.696 99 40 80 0 1436800320 15913552 9480224
00:27:47 43271 8192000000 10332029344 10801922048 1420880528 14.781 100 11 22 0 1436218240 12953088 2384624
00:29:47 49420 8192000000 10325723056 10805723136 1420671584 14.779 100 7 14 0 1438604960 16036480 1896896
00:31:48 50258 8192000000 10320327952 10787713024 1397521632 14.573 98 21 42 0 1412076448 10147248 4407568
00:33:45 11577 8192000000 10309137008 10793504768 1400248000 14.597 99 2 4 0 1414785648 14194608 343040
00:35:13 68237 8192000000 10303916656 10780291072 1390665648 14.512 98 15 30 1 1401354272 7090736 3597888
00:37:35 88128 8192000000 10305393792 10779193344 1374958928 14.371 97 8 16 0 1387635488 11035504 1641056
00:40:12 27247 8192000000 10311900544 10763440128 1363613536 14.270 98 26 52 0 1376532080 6827888 6090656
00:41:42 26634 8192000000 10286064160 10743181312 1362526240 14.260 99 23 46 1 1381131184 13154848 5450096
00:43:12 57954 8192000000 10274532672 10731491328 1305779728 13.748 95 12 25 0 1318023360 9475568 2768064
00:45:11 48900 8192000000 10267434816 10723909632 1342745008 14.082 98 14 28 0 1355735728 9525040 3465680
00:47:06 23764 8192000000 10280156752 10722791424 1339037904 14.049 98 33 66 0 1358964592 12381920 7544768
00:48:34 50566 8192000000 10265110224 10704838656 1290632624 13.610 95 27 54 2 1307147888 9915568 6599696
00:50:25 62934 8192000000 10278868144 10732294144 1340769600 14.064 98 15 30 0 1355966928 11607072 3590256
00:52:37 131629 8192000000 10237814112 10695102464 1300968528 13.704 96 27 54 0 1315395600 8160880 6266192
00:55:49 40667 8192000000 10265302512 10718892032 1355194096 14.194 100 32 65 1 1376313504 13869248 7250160
00:57:33 63435 8192000000 10248219456 10710429696 1323839504 13.911 98 13 26 0 1346368672 19106720 3422448
00:59:37 73054 8192000000 10258951792 10714148864 1326416880 13.935 98 13 26 1 1348515936 18446288 3652768
01:02:38 86725 8192000000 10266175136 10728415232 1337783328 14.037 98 14 28 0 1351026432 10013552 3229552
01:05:10 49767 8192000000 10274418928 10720374784 1355153424 14.194 99 33 66 0 1370987792 8490448 7343920
01:07:06 83376 8192000000 10274203840 10733977600 1335776352 14.019 97 20 40 0 1354782656 14530992 4475312
01:09:55 63507 8192000000 10302343040 10765225984 1390239840 14.508 100 9 18 0 1405209056 13343872 1625344
01:12:02 82735 8192000000 10309439056 10762014720 1317568736 13.855 94 17 34 2 1332030192 10618064 3843392
01:14:29 123483 8192000000 10293801296 10758840320 1344546352 14.098 96 17 34 1 1361921680 13441872 3933456
01:18:01 53485 8192000000 10274669824 10732556288 1367101648 14.301 98 19 38 0 1381072944 9698576 4272720
01:20:03 71136 8192000000 10286903824 10760269824 1391506016 14.519 100 17 34 2 1408359440 12857088 3996336
01:22:43 76054 8192000000 10284663408 10739818496 1341281792 14.069 97 10 20 0 1355980992 12036304 2662896
01:25:06 65559 8192000000 10269973136 10731958272 1336881936 14.029 96 22 44 0 1357460752 15977280 4601536
01:27:12 59477 8192000000 10256802832 10708926464 1301378624 13.708 94 8 16 0 1312992672 9825280 1788768
01:29:18 41246 8192000000 10273272528 10725036032 1332011920 13.985 97 24 48 2 1345056448 7865872 5178656
01:31:01 29833 8192000000 10273235168 10720350208 1352275328 14.168 99 31 61 1 1369125328 9559296 7290704
01:32:33 17232 8192000000 10273988048 10714390528 1330639408 13.973 98 42 84 2 1352006512 12187696 9179408
01:33:51 47757 8192000000 10269564032 10718564352 1333435968 13.998 98 32 64 0 1357261408 16686480 7138960
01:35:42 91549 8192000000 10252259456 10704928768 1296291776 13.662 96 18 35 0 1317340208 17057936 3990496

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-12-2021 01:39:18 to 25-12-2021 01:39:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 113 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 25-12-2021 01:39:34 to 25-12-2021 01: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          261902
MON$OLDEST_ACTIVE               261903
MON$OLDEST_SNAPSHOT             261903
MON$NEXT_TRANSACTION            261903
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                       2054880
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33547 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33547 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33547 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33547 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

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

Database validation

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

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

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

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

25.12.21 01: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-12-25 01:39:39 No severe PSQL-related problems occured
25.12.21 01:39:40. End of report.