Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.10.2021 22: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 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15800948 kB
MemAvailable 30631944 kB
Buffers 0 kB
Cached 15914152 kB
SwapCached 3704 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-10-23 22:37:54
DTS_END                         2021-10-23 22:37:54.4670
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43814
TRN_ID                          249213
ATT_ID                          739
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: 9739.30 17412 1168716
customer order: creation 188.17 307 22580
customer order: refuse 15.37 288 1844
order to supplier: creation 48.91 1292 5869
order to supplier: removal 3.52 102696 422
invoice (draft): creation 47.84 3171 5741
invoice (draft): removal 53.48 868 6417
invoice accept: apply 44.77 18513 5372
invoice accept: cancel 48.10 847 5772
customer reserve: creation 4188.23 183 502587
customer reserve: removal 4790.06 59 574807
realization accept: apply 89.82 171 10778
realization accept: cancel 82.09 95 9851
payment from customer: creation 63.41 108 7609
payment from customer: removal 6.00 65 720
payment to supplier: creation 52.59 133 6311
payment to supplier: removal 3.62 101 434
service: total inventory turnovers 5.28 930 633
service: total monetary turnovers 7.12 28 854
service: aggregate perf. data 0.93 17547 111
service: refresh index statistics 0.03 200839 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2864
TEST_TIME 2 4197
TEST_TIME 3 5205
TEST_TIME 4 6031
TEST_TIME 5 5866
TEST_TIME 6 6409
TEST_TIME 7 6332
TEST_TIME 8 6239
TEST_TIME 9 6262
TEST_TIME 10 6197
TEST_TIME 11 6208
TEST_TIME 12 6461
TEST_TIME 13 6576
TEST_TIME 14 6880
TEST_TIME 15 7185
TEST_TIME 16 7302
TEST_TIME 17 7958
TEST_TIME 18 7929
TEST_TIME 19 8300
TEST_TIME 20 8570
TEST_TIME 21 8691
TEST_TIME 22 8575
TEST_TIME 23 8667
TEST_TIME 24 8519
TEST_TIME 25 8664
TEST_TIME 26 9071
TEST_TIME 27 9298
TEST_TIME 28 9287
TEST_TIME 29 9472
TEST_TIME 30 9368
TEST_TIME 31 9970
TEST_TIME 32 10408
TEST_TIME 33 11022
TEST_TIME 34 10901
TEST_TIME 35 10735
TEST_TIME 36 10743
TEST_TIME 37 10977
TEST_TIME 38 10914
TEST_TIME 39 10749
TEST_TIME 40 10826
TEST_TIME 41 10674
TEST_TIME 42 10475
TEST_TIME 43 10404
TEST_TIME 44 10343
TEST_TIME 45 10285
TEST_TIME 46 10215
TEST_TIME 47 10137
TEST_TIME 48 10033
TEST_TIME 49 10032
TEST_TIME 50 10115
TEST_TIME 51 10226
TEST_TIME 52 10259
TEST_TIME 53 10155
TEST_TIME 54 10115
TEST_TIME 55 10064
TEST_TIME 56 9920
TEST_TIME 57 10035
TEST_TIME 58 10009
TEST_TIME 59 9931
TEST_TIME 60 9832
TEST_TIME 61 9786
TEST_TIME 62 9702
TEST_TIME 63 9710
TEST_TIME 64 9793
TEST_TIME 65 9860
TEST_TIME 66 9857
TEST_TIME 67 9828
TEST_TIME 68 9805
TEST_TIME 69 9794
TEST_TIME 70 9910
TEST_TIME 71 9931
TEST_TIME 72 9933
TEST_TIME 73 9939
TEST_TIME 74 9900
TEST_TIME 75 10017
TEST_TIME 76 9938
TEST_TIME 77 9875
TEST_TIME 78 9822
TEST_TIME 79 9788
TEST_TIME 80 9823
TEST_TIME 81 9789
TEST_TIME 82 9736
TEST_TIME 83 9697
TEST_TIME 84 9697
TEST_TIME 85 9663
TEST_TIME 86 9642
TEST_TIME 87 9665
TEST_TIME 88 9698
TEST_TIME 89 9721
TEST_TIME 90 9752
TEST_TIME 91 9782
TEST_TIME 92 9787
TEST_TIME 93 9844
TEST_TIME 94 9861
TEST_TIME 95 9871
TEST_TIME 96 9905
TEST_TIME 97 9896
TEST_TIME 98 9934
TEST_TIME 99 9840
TEST_TIME 100 9865
TEST_TIME 101 9845
TEST_TIME 102 9815
TEST_TIME 103 9762
TEST_TIME 104 9703
TEST_TIME 105 9668
TEST_TIME 106 9735
TEST_TIME 107 9732
TEST_TIME 108 9714
TEST_TIME 109 9700
TEST_TIME 110 9757
TEST_TIME 111 9737
TEST_TIME 112 9703
TEST_TIME 113 9761
TEST_TIME 114 9777
TEST_TIME 115 9767
TEST_TIME 116 9777
TEST_TIME 117 9756
TEST_TIME 118 9849
TEST_TIME 119 9813
TEST_TIME 120 9747

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-10-2021 22:39:56 to 23-10-2021 22: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 1633731 1633731 0 0.00 0 57423 6 0 0
doc_list_biud 1633731 1633731 0 0.00 0 24047 27 0 0
sp_add_invoice_to_stock 5464 5372 92 1.68 26 169696 18513 0 92
sp_cancel_adding_invoice 5772 5772 0 0.00 4 82178 848 0 0
sp_cancel_client_order 1844 1844 0 0.00 4 6742 288 0 0
sp_cancel_customer_reserve 574807 574807 0 0.00 1 24049 60 0 0
sp_cancel_pay_from_customer 720 720 0 0.00 2 1917 65 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 3 9493 102 0 0
sp_cancel_supplier_invoice 6429 6417 12 0.18 1 19906 869 0 12
sp_cancel_supplier_order 422 422 0 0.00 6 795705 102697 0 0
sp_cancel_write_off 9851 9851 0 0.00 1 8195 95 0 0
sp_client_order 22580 22580 0 0.00 3 25530 308 0 0
sp_customer_reserve 502587 502587 0 0.00 0 102260 183 0 0
sp_fill_shopping_cart 34184 34184 0 0.00 1 13786 37 0 0
sp_fill_shopping_cart_clo_res 502577 502577 0 0.00 0 16232 12 0 0
sp_get_clo_for_invoice 5359 5359 0 0.00 0 28650 273 0 0
sp_kill_cost_storno 16777 16777 0 0.00 0 8707 54 0 0
sp_kill_qstorno_ret_qs2qd 1166304 1166304 0 0.00 0 24044 33 0 0
sp_kill_qty_storno 615249 615249 0 0.00 0 24047 70 0 0
sp_lock_selected_doc 22196 22196 0 0.00 0 9477 6 0 0
sp_make_cost_storno 30056 30056 0 0.00 0 57417 57 0 0
sp_multiply_rows_for_pdistr 30056 30056 0 0.00 0 8721 27 0 0
sp_multiply_rows_for_qdistr 22580 22580 0 0.00 0 25517 135 0 0
sp_pay_from_customer 7609 7609 0 0.00 4 11539 109 0 0
sp_pay_to_supplier 6311 6311 0 0.00 5 13090 133 0 0
sp_payment_common 13920 13920 0 0.00 1 8558 30 0 0
sp_qd_handle_on_cancel_clo 1844 1844 0 0.00 0 6732 264 0 0
sp_qd_handle_on_invoice_upd_sts 11130 11130 0 0.00 0 18598 335 0 0
sp_qd_handle_on_reserve_upd_sts 10778 10778 0 0.00 0 14790 50 0 0
sp_reserve_write_off 10778 10778 0 0.00 6 14918 172 0 0
sp_supplier_invoice 5741 5741 0 0.00 6 70596 3171 0 0
sp_supplier_order 5869 5869 0 0.00 5 54548 1292 0 0
srv_aggregate_perf_data 111 111 0 0.00 2497 153742 17548 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 1655 110965 38757 0 0
srv_find_qd_qs_mism 1116957 1116957 0 0.00 0 9363 5 0 0
srv_make_invnt_saldo 633 633 0 0.00 24 39074 931 0 0
srv_make_money_saldo 854 854 0 0.00 0 6437 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 100211 330737 200840 0 0
t$perf-abend:sp_add_invoice_to_stock 92 92 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 5372 5372 0 0.00 0 11629 158 0 0
t$perf-norm:sp_cancel_adding_invoice 450 450 0 0.00 0 11701 124 0 0
t$perf-norm:sp_cancel_client_order 1844 1844 0 0.00 0 3164 10 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 2143 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 720 720 0 0.00 0 557 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 2513 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 475 475 0 0.00 0 754 10 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 26905 1608 0 0
t$perf-norm:sp_cancel_write_off 1023 1023 0 0.00 0 2494 8 0 0
t$perf-norm:sp_client_order 22580 22580 0 0.00 0 6946 16 0 0
t$perf-norm:sp_customer_reserve 1078 1078 0 0.00 0 1794 8 0 0
t$perf-norm:sp_pay_from_customer 7609 7609 0 0.00 0 6779 10 0 0
t$perf-norm:sp_pay_to_supplier 6311 6311 0 0.00 0 6195 9 0 0
t$perf-norm:sp_reserve_write_off 10778 10778 0 0.00 0 3877 11 0 0
t$perf-norm:sp_supplier_invoice 5741 5741 0 0.00 0 4814 15 0 0
t$perf-norm:sp_supplier_order 5869 5869 0 0.00 0 2825 13 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 587 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 1618 25 0 0
t$perf-norm:srv_make_invnt_saldo 633 633 0 0.00 0 1619 15 0 0
t$perf-norm:srv_make_money_saldo 854 854 0 0.00 0 1382 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 10900 10716 184 1.68 0 7473 19 0 184
v_all_customers 41006 41006 0 0.00 0 19 1 0 0
v_all_suppliers 32530 32530 0 0.00 0 75 1 0 0
v_all_wares 45160 45160 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 900 900 0 0.00 0 2527 11 0 0
v_cancel_client_order 3688 3688 0 0.00 0 6389 6 0 0
v_cancel_customer_prepayment 1440 1440 0 0.00 0 1278 8 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 2110 6 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 6098 13 0 0
v_cancel_supplier_invoice 974 950 24 2.46 0 1944 16 0 24
v_cancel_supplier_order 844 844 0 0.00 0 652 5 0 0
v_cancel_write_off 2046 2046 0 0.00 0 6236 17 0 0
v_max_id_clo_ord 5869 5869 0 0.00 0 1256 1 0 0
v_max_id_clo_res 1078 1078 0 0.00 0 1331 15 0 0
v_max_id_ord_sup 5735 5735 0 0.00 0 10806 7 0 0
v_max_non_paid_invoice 1650 1650 0 0.00 0 681 3 0 0
v_max_non_paid_realizn 4044 4044 0 0.00 0 4716 7 0 0
v_min_id_clo_ord 5869 5869 0 0.00 0 5187 13 0 0
v_min_id_clo_res 1078 1078 0 0.00 0 2596 8 0 0
v_min_id_ord_sup 5735 5735 0 0.00 0 4912 6 0 0
v_min_non_paid_invoice 6311 6311 0 0.00 0 6538 19 0 0
v_min_non_paid_realizn 7609 7609 0 0.00 0 8503 22 0 0
v_our_firm 11284 11284 0 0.00 0 15 1 0 0
v_reserve_write_off 21556 21556 0 0.00 0 6212 7 0 0
x_lock_depdocs_on_canc_invoice 11714 11714 0 0.00 0 16129 113 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 8771 317 0 0
x_make_qstorno_1000_1200 5869 5869 0 0.00 1 54506 1210 0 0
x_make_qstorno_1200_2000 5741 5741 0 0.00 2 70584 3036 0 0
x_make_qstorno_2100_3300 502587 502587 0 0.00 0 102258 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-10-2021 22:40:01 to 23-10-2021 22: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
20:07:58 8 8192000000 8803668208 8816726016 9156288 0.111 3 2 3 0 9299264 68032 74944
20:08:59 28940 8192000000 9894210896 10207449088 969664768 10.583 100 83 166 0 998301392 6235680 22400944
20:10:34 18003 8192000000 9943409664 10266652672 1025609584 11.126 100 87 174 0 1056683632 8251552 22822496
20:12:06 9505 8192000000 9974758752 10308063232 1063899248 11.494 100 85 170 0 1094517344 9454608 21163488
20:13:28 13686 8192000000 10034143328 10384203776 1119148160 12.019 100 76 152 1 1148490160 10834832 18507168
20:14:48 5338 8192000000 10094221024 10455560192 1181630704 12.605 100 75 150 4 1211184064 12146896 17406464
20:15:57 3336 8192000000 10129176736 10502123520 1223791776 12.997 100 75 148 1 1252762368 12726464 16244128
20:17:02 4945 8192000000 10172535440 10551312384 1252098880 13.258 100 78 153 1 1281358048 13082256 16176912
20:18:09 1667 8192000000 10208414240 10588225536 1291841792 13.621 100 91 182 1 1324740160 12726848 20171520
20:19:12 34881 8192000000 10203159568 10603356160 1300522048 13.700 100 65 131 1 1328762192 13720384 14519760
20:20:50 3784 8192000000 10199051184 10611892224 1311121424 13.796 100 44 87 3 1327621200 6146544 10353232
20:21:54 7857 8192000000 10205070480 10622304256 1318915952 13.867 100 28 56 1 1331967232 6901152 6150128
20:23:05 23797 8192000000 10211431360 10633650176 1324631296 13.919 100 12 24 0 1332802224 5259152 2911776
20:24:30 7193 8192000000 10220134080 10643197952 1333837120 14.002 100 15 30 1 1343289264 5750560 3701584
20:25:38 12271 8192000000 10238593552 10665234432 1342754016 14.082 100 17 34 0 1356747472 9552592 4440864
20:26:50 41214 8192000000 10244862320 10645708800 1331437744 13.980 100 67 134 4 1354617648 6008368 17171536
20:28:31 5315 8192000000 10203884944 10621431808 1305547856 13.746 99 21 42 0 1316708656 6247744 4913056
20:29:37 5729 8192000000 10123639680 10516475904 1198920656 12.766 95 16 32 1 1207175664 4757600 3497408
20:30:47 6499 8192000000 10127703024 10527416320 1219578448 12.958 98 22 44 0 1230710880 5766160 5366272
20:31:54 35192 8192000000 10128949392 10527465472 1199186128 12.769 96 23 46 1 1209346960 4367936 5792896
20:33:33 8960 8192000000 10154978688 10564562944 1256527136 13.298 99 25 50 1 1268151152 6003584 5620432
20:34:43 9230 8192000000 10181592256 10597584896 1296034432 13.659 100 14 27 1 1304097904 5026160 3037312
20:35:52 16959 8192000000 10214762848 10622259200 1305393136 13.744 100 20 41 1 1315725712 5362560 4970016
20:37:58 15036 8192000000 10236001600 10645504000 1332310416 13.988 100 38 75 0 1346642624 5541872 8790336
20:39:22 14938 8192000000 10268385952 10677346304 1350980192 14.156 100 48 96 3 1370989072 9388752 10620128
20:40:50 8370 8192000000 10278822352 10695098368 1368352736 14.312 100 57 112 3 1395562320 14077840 13131744
20:42:02 11398 8192000000 10283535920 10709917696 1377721568 14.396 100 36 72 0 1405092928 18848912 8522448
20:43:18 5853 8192000000 10310085680 10725625856 1386315216 14.473 100 59 117 0 1418861968 17998848 14547904
20:44:29 11100 8192000000 10319293104 10742140928 1393887280 14.541 100 57 115 4 1426721776 19624576 13209920
20:45:44 22314 8192000000 10304813152 10734608384 1392829120 14.531 100 38 76 2 1421736224 19596208 9310896
20:47:12 11761 8192000000 10330140960 10754146304 1395828832 14.558 99 53 106 1 1429238720 21627712 11782176
20:48:29 4957 8192000000 10340195264 10759905280 1406009328 14.648 100 70 139 3 1449221312 26784016 16427968
20:49:39 4962 8192000000 10338138688 10757623808 1390928848 14.514 99 66 132 0 1436853872 29796160 16128864
20:50:51 13158 8192000000 10331069200 10757193728 1381834496 14.433 98 41 82 5 1422658672 29780320 11043856
20:52:09 4654 8192000000 10326526480 10744233984 1384359392 14.456 100 68 136 1 1433928592 33794512 15774688
20:53:21 6697 8192000000 10336892096 10759127040 1395988432 14.559 100 56 112 2 1444830368 34617872 14224064
20:54:31 9700 8192000000 10300934448 10731556864 1378938288 14.407 100 26 53 0 1411657776 25911792 6807696
20:55:45 12373 8192000000 10310435824 10727206912 1367530304 14.305 100 53 106 2 1412665440 31206832 13928304
20:57:01 1652 8192000000 10349348000 10759462912 1389615648 14.502 100 90 180 2 1451712016 39902544 22193824
20:58:05 9217 8192000000 10310810144 10734485504 1368919984 14.317 99 44 89 0 1410914016 30596480 11397552
20:59:19 11180 8192000000 10295236256 10717134848 1351187600 14.158 99 45 90 2 1395481312 32868320 11425392
21:00:42 12304 8192000000 10301841616 10720755712 1362675712 14.261 100 39 76 1 1409866240 36302288 10888240
21:02:00 3304 8192000000 10331900032 10742292480 1370106720 14.328 100 64 128 2 1433650560 46715120 16828720
21:03:05 18627 8192000000 10323813968 10753806336 1358349200 14.223 99 40 80 1 1426741088 57061568 11330320
21:04:29 9256 8192000000 10337756720 10768535552 1385111136 14.462 100 34 68 1 1443755936 49535552 9109248
21:05:40 34434 8192000000 10337163664 10767761408 1369834016 14.326 99 30 60 2 1434671936 56127152 8710768
21:07:16 24007 8192000000 10347933296 10776596480 1382495504 14.439 100 42 84 1 1452339296 58348144 11495648
21:08:47 46405 8192000000 10333526240 10788782080 1359965184 14.237 99 20 40 0 1423017312 57427632 5624496
21:11:57 56162 8192000000 10291507904 10723028992 1318903056 13.867 96 22 43 0 1333283088 8717760 5662272
21:13:58 59389 8192000000 10280763120 10746822656 1357312704 14.213 99 7 14 0 1380637808 21478560 1846544
21:15:58 61016 8192000000 10309281024 10744963072 1384837088 14.460 100 27 53 0 1400014896 9770560 5407248
21:18:01 41648 8192000000 10302076112 10743443456 1389353088 14.500 100 38 76 4 1405057984 7621776 8083120
21:19:43 62240 8192000000 10310559360 10751922176 1381960352 14.434 99 38 75 1 1397995952 8109872 7925728
21:21:47 5553 8192000000 10325201680 10766032896 1401468800 14.608 100 45 90 0 1420103488 7648160 10986528
21:22:55 68434 8192000000 10314695968 10775003136 1391821792 14.522 99 23 46 0 1416236000 18650304 5763904
21:25:04 37416 8192000000 10321949296 10776064000 1410868560 14.692 100 27 54 0 1430097728 12622176 6606992
21:26:50 52044 8192000000 10324554752 10782441472 1398531744 14.582 99 36 72 2 1426235488 18824800 8878944
21:29:01 43975 8192000000 10316118176 10790498304 1401711904 14.610 100 9 18 0 1428135424 24475248 1948272
21:30:45 62849 8192000000 10311306576 10777329664 1409831792 14.682 100 18 36 0 1425917968 11970080 4116096
21:33:35 103782 8192000000 10308082448 10784608256 1395704464 14.557 100 20 40 1 1412573840 12136656 4732720
21:36:20 28709 8192000000 10289962064 10757128192 1371668448 14.342 98 12 24 1 1383438288 9069552 2700288
21:37:52 63045 8192000000 10278611648 10750812160 1376933328 14.389 99 20 39 0 1388494112 7092224 4468560
21:39:55 100775 8192000000 10277943168 10751053824 1332364288 13.989 95 7 14 0 1351782848 18008720 1409840
21:42:36 24433 8192000000 10276082016 10746585088 1355366928 14.196 98 20 40 2 1371755440 11860512 4528000
21:44:05 19193 8192000000 10272859968 10715762688 1320684768 13.883 96 42 84 3 1338260400 7642032 9933600
21:45:29 25825 8192000000 10287399856 10736508928 1363944160 14.273 99 17 34 0 1377913808 10217488 3752160
21:46:56 84863 8192000000 10252423504 10712662016 1260680752 13.336 92 20 40 0 1277791072 12988960 4121360
21:49:21 76818 8192000000 10254230560 10689499136 1283706512 13.547 95 21 42 1 1299224816 10265408 5252896
21:51:42 32667 8192000000 10246661584 10689716224 1301174864 13.706 96 36 73 2 1324128752 14572224 8381664
21:53:38 110965 8192000000 10231898080 10674065408 1275172400 13.469 95 25 47 2 1286553968 5643568 5738000
21:56:29 18471 8192000000 10246976144 10684878848 1332496816 13.990 100 40 80 0 1352940432 11974112 8469504
21:57:50 34044 8192000000 10228438768 10682241024 1332340688 13.988 100 16 32 2 1344257040 8424944 3491408
21:59:25 56735 8192000000 10245147376 10704261120 1349276288 14.141 100 13 26 0 1362959936 10710800 2972848
22:01:22 78613 8192000000 10229667120 10697363456 1295563104 13.655 96 7 14 1 1312033264 14886768 1583392
22:03:41 27770 8192000000 10263508992 10717995008 1360148192 14.239 100 19 38 0 1374647584 10075056 4424336
22:05:09 42376 8192000000 10270149648 10728960000 1354706656 14.190 99 20 40 1 1372511872 13269424 4535792
22:06:58 69717 8192000000 10289816432 10743226368 1356052832 14.202 98 23 46 0 1378899664 17941440 4905392
22:09:08 20467 8192000000 10296836400 10745438208 1367899904 14.308 98 45 90 0 1388156112 10177952 10078256
22:10:30 54160 8192000000 10300133712 10763567104 1384903360 14.460 99 19 38 0 1398206992 9201232 4102400
22:12:26 84782 8192000000 10306353136 10769735680 1358539392 14.224 97 18 36 0 1373772592 11396896 3836304
22:14:53 90659 8192000000 10261412448 10716811264 1328641792 13.955 95 7 14 0 1344383088 14488896 1252400
22:18:06 61378 8192000000 10295195536 10767138816 1394998608 14.550 100 21 42 0 1413417328 13731536 4687184
22:20:07 55566 8192000000 10275113312 10732920832 1330064208 13.968 95 10 21 0 1338853216 6273184 2515824
22:22:03 53233 8192000000 10280186912 10744721408 1375636144 14.378 99 18 35 0 1389778032 9973344 4168544
22:23:57 49591 8192000000 10255796048 10722951168 1310081168 13.787 95 6 12 0 1320559216 9319232 1158816
22:26:16 53396 8192000000 10269080592 10726649856 1335121296 14.013 97 18 36 0 1351579424 12082192 4375936
22:28:17 21444 8192000000 10278520896 10728009728 1356089376 14.202 99 24 48 0 1370629552 9073056 5467120
22:29:41 35478 8192000000 10283129296 10725163008 1343176928 14.086 98 45 88 1 1367978544 15062256 9739360
22:31:18 36003 8192000000 10259222224 10711351296 1346317280 14.114 100 23 46 1 1360627136 9587408 4722448
22:33:01 79854 8192000000 10255011536 10696085504 1256004160 13.293 93 25 50 1 1271871792 10557680 5309952
22:35:24 85345 8192000000 10248229568 10708275200 1328549056 13.954 99 9 18 1 1346817072 16572432 1695584
22:37:49 15560 8192000000 9131958432 9374466048 297417088 3.503 22 1 2 0 299569200 2109760 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-10-2021 22:40:07 to 23-10-2021 22:40:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 92 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:01, from 23-10-2021 22:40:23 to 23-10-2021 22: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          249486
MON$OLDEST_ACTIVE               249487
MON$OLDEST_SNAPSHOT             249487
MON$NEXT_TRANSACTION            249487
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                       2039648
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.33524 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33524 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33524 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33524 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 ---
:104: 
:105: mscs-test-161.budzdorov.ru    Sat Oct 23 22:38:06 2021
:106:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:107: 
:108: 
:109: mscs-test-161.budzdorov.ru    Sat Oct 23 22:38:06 2021
:110:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:111: 
:112: 
:113: mscs-test-161.budzdorov.ru    Sat Oct 23 22:38:06 2021
:114:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:115: 
--- end of diff output ---

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

23.10.21 22:40:26. 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-10-23 22:40:28 No severe PSQL-related problems occured
23.10.21 22:40:29. End of report.