Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.06.2022 16:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16182972 kB
MemAvailable 31245848 kB
Buffers 0 kB
Cached 15502512 kB
SwapCached 2452 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.10
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 2022-06-15 16:38:24
DTS_END                         2022-06-15 16:38:24.4640
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/39394
TRN_ID                          249786
ATT_ID                          693
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: 9424.63 20360 1130956
customer order: creation 177.78 365 21333
customer order: refuse 15.08 319 1809
order to supplier: creation 47.61 1207 5713
order to supplier: removal 3.60 100473 432
invoice (draft): creation 48.13 3265 5775
invoice (draft): removal 54.00 818 6480
invoice accept: apply 43.59 17329 5231
invoice accept: cancel 48.39 823 5807
customer reserve: creation 3945.17 175 473420
customer reserve: removal 4732.34 60 567881
realization accept: apply 86.38 204 10366
realization accept: cancel 89.41 123 10729
payment from customer: creation 61.37 122 7364
payment from customer: removal 5.79 89 695
payment to supplier: creation 50.35 128 6042
payment to supplier: removal 3.56 68 427
service: total inventory turnovers 4.97 923 597
service: total monetary turnovers 6.17 25 740
service: aggregate perf. data 0.93 14295 111
service: refresh index statistics 0.03 266398 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-06-2022 16:39:41 to 15-06-2022 16:39:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3914
TEST_TIME 2 3512
TEST_TIME 3 5233
TEST_TIME 4 6191
TEST_TIME 5 6353
TEST_TIME 6 6029
TEST_TIME 7 5842
TEST_TIME 8 5797
TEST_TIME 9 5872
TEST_TIME 10 6193
TEST_TIME 11 6330
TEST_TIME 12 6516
TEST_TIME 13 6856
TEST_TIME 14 6808
TEST_TIME 15 6778
TEST_TIME 16 6928
TEST_TIME 17 7058
TEST_TIME 18 7267
TEST_TIME 19 7382
TEST_TIME 20 7974
TEST_TIME 21 7906
TEST_TIME 22 8260
TEST_TIME 23 8600
TEST_TIME 24 8824
TEST_TIME 25 9090
TEST_TIME 26 9220
TEST_TIME 27 9600
TEST_TIME 28 10307
TEST_TIME 29 10151
TEST_TIME 30 10611
TEST_TIME 31 10676
TEST_TIME 32 10523
TEST_TIME 33 10433
TEST_TIME 34 10659
TEST_TIME 35 10686
TEST_TIME 36 10481
TEST_TIME 37 10293
TEST_TIME 38 10184
TEST_TIME 39 10223
TEST_TIME 40 10211
TEST_TIME 41 10046
TEST_TIME 42 10027
TEST_TIME 43 9918
TEST_TIME 44 9928
TEST_TIME 45 10089
TEST_TIME 46 10027
TEST_TIME 47 9931
TEST_TIME 48 9886
TEST_TIME 49 9818
TEST_TIME 50 9714
TEST_TIME 51 9698
TEST_TIME 52 9655
TEST_TIME 53 9652
TEST_TIME 54 9642
TEST_TIME 55 9711
TEST_TIME 56 9647
TEST_TIME 57 9614
TEST_TIME 58 9756
TEST_TIME 59 9775
TEST_TIME 60 9937
TEST_TIME 61 9881
TEST_TIME 62 9985
TEST_TIME 63 10056
TEST_TIME 64 10038
TEST_TIME 65 10110
TEST_TIME 66 10130
TEST_TIME 67 10065
TEST_TIME 68 9961
TEST_TIME 69 9918
TEST_TIME 70 9848
TEST_TIME 71 9809
TEST_TIME 72 9766
TEST_TIME 73 9690
TEST_TIME 74 9657
TEST_TIME 75 9706
TEST_TIME 76 9691
TEST_TIME 77 9661
TEST_TIME 78 9658
TEST_TIME 79 9722
TEST_TIME 80 9709
TEST_TIME 81 9672
TEST_TIME 82 9737
TEST_TIME 83 9754
TEST_TIME 84 9718
TEST_TIME 85 9759
TEST_TIME 86 9734
TEST_TIME 87 9749
TEST_TIME 88 9853
TEST_TIME 89 9821
TEST_TIME 90 9734
TEST_TIME 91 9701
TEST_TIME 92 9665
TEST_TIME 93 9642
TEST_TIME 94 9580
TEST_TIME 95 9555
TEST_TIME 96 9543
TEST_TIME 97 9619
TEST_TIME 98 9565
TEST_TIME 99 9565
TEST_TIME 100 9614
TEST_TIME 101 9641
TEST_TIME 102 9672
TEST_TIME 103 9756
TEST_TIME 104 9759
TEST_TIME 105 9708
TEST_TIME 106 9722
TEST_TIME 107 9789
TEST_TIME 108 9761
TEST_TIME 109 9687
TEST_TIME 110 9679
TEST_TIME 111 9656
TEST_TIME 112 9608
TEST_TIME 113 9578
TEST_TIME 114 9547
TEST_TIME 115 9486
TEST_TIME 116 9455
TEST_TIME 117 9500
TEST_TIME 118 9500
TEST_TIME 119 9468
TEST_TIME 120 9425

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-06-2022 16:39:45 to 15-06-2022 16:39:48.

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 1571148 1571148 0 0.00 0 24302 7 0 0
doc_list_biud 1571148 1571148 0 0.00 0 55458 28 0 0
sp_add_invoice_to_stock 5405 5231 174 3.21 35 177115 17330 0 174
sp_cancel_adding_invoice 5807 5807 0 0.00 5 56359 823 0 0
sp_cancel_client_order 1809 1809 0 0.00 3 9885 320 0 0
sp_cancel_customer_reserve 567881 567881 0 0.00 1 55459 60 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 2 5450 90 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 4151 68 0 0
sp_cancel_supplier_invoice 6494 6480 14 0.21 2 18171 818 0 14
sp_cancel_supplier_order 432 432 0 0.00 6 1021130 100473 0 0
sp_cancel_write_off 10729 10729 0 0.00 1 11977 123 0 0
sp_client_order 21333 21333 0 0.00 3 18863 365 0 0
sp_customer_reserve 473420 473420 0 0.00 0 91617 176 0 0
sp_fill_shopping_cart 32819 32819 0 0.00 1 22383 37 0 0
sp_fill_shopping_cart_clo_res 473420 473420 0 0.00 0 23280 14 0 0
sp_get_clo_for_invoice 5230 5230 0 0.00 0 31845 299 0 0
sp_kill_cost_storno 17658 17658 0 0.00 0 7582 58 0 0
sp_kill_qstorno_ret_qs2qd 1153403 1153403 0 0.00 0 55455 33 0 0
sp_kill_qty_storno 608734 608734 0 0.00 0 55457 71 0 0
sp_lock_selected_doc 21563 21563 0 0.00 0 7657 6 0 0
sp_make_cost_storno 29002 29002 0 0.00 0 24250 60 0 0
sp_multiply_rows_for_pdistr 29002 29002 0 0.00 0 7608 28 0 0
sp_multiply_rows_for_qdistr 21333 21333 0 0.00 0 15065 170 0 0
sp_pay_from_customer 7364 7364 0 0.00 4 15196 123 0 0
sp_pay_to_supplier 6042 6042 0 0.00 4 16427 128 0 0
sp_payment_common 13406 13406 0 0.00 1 8385 31 0 0
sp_qd_handle_on_cancel_clo 1809 1809 0 0.00 0 9879 288 0 0
sp_qd_handle_on_invoice_upd_sts 11037 11037 0 0.00 0 18959 323 0 0
sp_qd_handle_on_reserve_upd_sts 10366 10366 0 0.00 0 17260 70 0 0
sp_reserve_write_off 10366 10366 0 0.00 6 24316 205 0 0
sp_supplier_invoice 5775 5775 0 0.00 6 75810 3266 0 0
sp_supplier_order 5713 5713 0 0.00 5 30569 1207 0 0
srv_aggregate_perf_data 111 111 0 0.00 2882 80308 14295 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 2082 140376 40371 0 0
srv_find_qd_qs_mism 1082678 1082678 0 0.00 0 18908 6 0 0
srv_make_invnt_saldo 597 597 0 0.00 20 25812 924 0 0
srv_make_money_saldo 740 740 0 0.00 1 1401 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 203484 318753 266399 0 0
t$perf-abend:sp_add_invoice_to_stock 174 174 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5231 5231 0 0.00 0 11154 154 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 0 5508 114 0 0
t$perf-norm:sp_cancel_client_order 1809 1809 0 0.00 0 3913 14 0 0
t$perf-norm:sp_cancel_customer_reserve 705 705 0 0.00 0 1117 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 402 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 1192 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 432 432 0 0.00 0 2347 17 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 20256 1598 0 0
t$perf-norm:sp_cancel_write_off 1032 1032 0 0.00 0 3118 10 0 0
t$perf-norm:sp_client_order 21333 21333 0 0.00 0 10246 16 0 0
t$perf-norm:sp_customer_reserve 1072 1072 0 0.00 0 10578 16 0 0
t$perf-norm:sp_pay_from_customer 7364 7364 0 0.00 0 6242 11 0 0
t$perf-norm:sp_pay_to_supplier 6042 6042 0 0.00 0 4269 10 0 0
t$perf-norm:sp_reserve_write_off 10366 10366 0 0.00 0 7869 10 0 0
t$perf-norm:sp_supplier_invoice 5775 5775 0 0.00 0 10785 19 0 0
t$perf-norm:sp_supplier_order 5713 5713 0 0.00 0 10213 16 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 937 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 2347 52 0 0
t$perf-norm:srv_make_invnt_saldo 597 597 0 0.00 0 10127 34 0 0
t$perf-norm:srv_make_money_saldo 740 740 0 0.00 0 4709 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10808 10460 348 3.21 0 9579 21 0 348
v_all_customers 39150 39150 0 0.00 0 21 0 0 0
v_all_suppliers 31472 31472 0 0.00 0 19 1 0 0
v_all_wares 42662 42662 0 0.00 0 12 1 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 2717 10 0 0
v_cancel_client_order 3618 3618 0 0.00 0 6829 7 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 4595 11 0 0
v_cancel_customer_reserve 1410 1410 0 0.00 0 3639 8 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 4145 14 0 0
v_cancel_supplier_invoice 892 864 28 3.13 0 9568 30 0 28
v_cancel_supplier_order 864 864 0 0.00 0 610 5 0 0
v_cancel_write_off 2064 2064 0 0.00 0 9821 15 0 0
v_max_id_clo_ord 5713 5713 0 0.00 0 2334 1 0 0
v_max_id_clo_res 1072 1072 0 0.00 0 16078 31 0 0
v_max_id_ord_sup 5775 5775 0 0.00 0 5478 4 0 0
v_max_non_paid_invoice 1794 1794 0 0.00 0 542 3 0 0
v_max_non_paid_realizn 3842 3842 0 0.00 0 4692 7 0 0
v_min_id_clo_ord 5713 5713 0 0.00 0 9329 9 0 0
v_min_id_clo_res 1072 1072 0 0.00 0 6258 12 0 0
v_min_id_ord_sup 5775 5775 0 0.00 0 6739 8 0 0
v_min_non_paid_invoice 6042 6042 0 0.00 0 7617 19 0 0
v_min_non_paid_realizn 7364 7364 0 0.00 0 7690 24 0 0
v_our_firm 10556 10556 0 0.00 0 10 1 0 0
v_reserve_write_off 20728 20728 0 0.00 0 9582 10 0 0
x_lock_depdocs_on_canc_invoice 11855 11855 0 0.00 0 14649 116 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 5858 322 0 0
x_make_qstorno_1000_1200 5713 5713 0 0.00 2 30555 1128 0 0
x_make_qstorno_1200_2000 5775 5775 0 0.00 2 75800 3138 0 0
x_make_qstorno_2100_3300 473420 473420 0 0.00 0 91615 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-06-2022 16:39:49 to 15-06-2022 16:39:53.

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
14:08:28 30 8192000000 8816077760 8833372160 18916832 0.230 5 5 6 0 19158096 91536 149728
14:09:29 27934 8192000000 9907204864 10216230912 982725408 10.711 100 87 174 0 1011863952 6293760 22844784
14:11:03 22079 8192000000 9937919008 10255269888 1018633216 11.059 100 94 188 0 1051505024 8511328 24360480
14:12:31 19896 8192000000 9966665456 10298814464 1047359056 11.335 100 75 150 0 1076104240 9884592 18860592
14:13:53 12561 8192000000 10023948336 10368954368 1111776544 11.949 100 83 166 0 1142584784 10987424 19820816
14:15:10 11784 8192000000 10083743456 10441195520 1170816000 12.504 100 79 158 0 1202283680 13426800 18040880
14:16:23 4625 8192000000 10136594928 10504613888 1211508976 12.883 100 81 163 0 1246335824 17231200 17595648
14:17:30 5346 8192000000 10157276352 10533109760 1246315024 13.204 100 79 158 1 1276599616 13422960 16861632
14:18:38 1409 8192000000 10198219280 10578898944 1281028640 13.522 100 95 190 0 1316263280 13724224 21510416
14:19:40 23292 8192000000 10214674384 10602000384 1296208656 13.661 100 66 132 1 1318686528 7465456 15012416
14:21:04 11753 8192000000 10190330944 10603180032 1304162336 13.733 100 15 30 0 1313306496 5745856 3398304
14:22:16 9901 8192000000 10196281264 10611621888 1309926256 13.785 100 19 38 1 1319818096 5901792 3990048
14:23:30 13363 8192000000 10215418032 10630578176 1322127744 13.896 100 17 34 0 1331863728 6168848 3567136
14:24:51 52090 8192000000 10227976128 10651934720 1334434624 14.007 100 35 70 2 1353011488 10002544 8574320
14:26:44 3018 8192000000 10231425888 10648653824 1322443984 13.899 99 30 60 1 1340039408 10518176 7077248
14:27:48 8529 8192000000 10215021632 10627678208 1296852656 13.667 97 26 52 2 1307753856 4953344 5947856
14:28:58 6174 8192000000 10164380000 10568433664 1219686736 12.959 94 15 28 4 1227793776 4194096 3912944
14:30:08 34685 8192000000 10146262656 10552979456 1232451056 13.077 97 25 50 0 1243982176 5751936 5779184
14:31:42 6993 8192000000 10143358416 10541113344 1216966800 12.934 97 29 58 2 1229419296 6252560 6199936
14:32:51 6741 8192000000 10167405168 10569101312 1255311600 13.287 98 24 48 1 1267582992 6500608 5770784
14:34:00 23689 8192000000 10186483040 10586198016 1261093520 13.340 98 26 52 1 1273285632 6004752 6187360
14:35:26 27430 8192000000 10184614240 10599464960 1296550992 13.664 100 13 26 0 1304766016 5212784 3002240
14:36:56 6566 8192000000 10196170880 10611769344 1310163600 13.788 100 21 41 1 1321181584 5615616 5402368
14:38:02 13304 8192000000 10222819008 10639687680 1327997472 13.949 100 17 34 0 1336678208 4682528 3998208
14:39:20 2081 8192000000 10267266400 10664546304 1347422928 14.124 100 69 138 2 1371188032 7400592 16364512
14:40:25 38090 8192000000 10262708864 10677510144 1356104064 14.202 100 55 110 4 1380866480 12091904 12670512
14:42:09 14967 8192000000 10272652640 10691334144 1365784192 14.289 100 49 98 0 1390239072 12625968 11828912
14:43:37 20229 8192000000 10293814640 10717736960 1387665200 14.485 100 44 88 1 1407839968 9859600 10315168
14:45:06 23755 8192000000 10312350816 10738806784 1398099440 14.578 100 47 94 1 1422445696 13083440 11262816
14:46:36 7808 8192000000 10339284240 10758795264 1405088800 14.640 99 50 100 1 1437201392 19640752 12471840
14:47:49 4640 8192000000 10325594416 10745737216 1400532224 14.600 100 65 129 1 1438009424 21722032 15755168
14:48:59 9012 8192000000 10336909840 10754891776 1396892416 14.567 99 59 118 1 1438405616 26771168 14742032
14:50:14 5162 8192000000 10342975488 10758778880 1403513680 14.626 100 63 127 0 1448427328 28867424 16046224
14:51:22 5107 8192000000 10359321904 10772238336 1411456352 14.697 100 76 152 0 1463081824 32735648 18889824
14:52:37 17572 8192000000 10345378080 10763685888 1401949488 14.612 100 60 120 1 1450120272 32751744 15419040
14:53:59 7271 8192000000 10339142336 10756763648 1386103408 14.471 99 52 103 2 1434460416 34908192 13448816
14:55:15 5627 8192000000 10372473456 10790207488 1419210144 14.766 100 65 128 1 1478255376 42200272 16844960
14:56:29 8423 8192000000 10339324528 10758774784 1382745152 14.441 99 45 90 2 1438608112 43771776 12091184
14:57:41 9615 8192000000 10346746560 10767867904 1384961728 14.461 100 52 104 0 1449582416 50182608 14438080
14:58:55 19282 8192000000 10338996080 10767142912 1378389312 14.402 99 30 60 1 1432037312 44718080 8929920
15:00:18 8072 8192000000 10335120368 10762240000 1377447824 14.394 100 36 72 0 1439575712 51171024 10956864
15:01:29 14333 8192000000 10322500384 10748014592 1371283920 14.339 100 31 60 0 1427242720 47682400 8276400
15:02:59 5477 8192000000 10299658288 10724560896 1350679680 14.154 100 41 82 2 1405500592 43646336 11174576
15:04:23 20714 8192000000 10290478320 10711306240 1302329072 13.716 97 29 58 0 1361963168 51213744 8420352
15:05:46 10387 8192000000 10291800400 10717179904 1342809808 14.083 100 35 70 1 1393139200 41100496 9228896
15:07:15 50777 8192000000 10259928080 10688610304 1331645504 13.982 100 20 40 0 1360283104 24012928 4624672
15:09:09 81740 8192000000 10273514944 10712920064 1349192080 14.140 99 25 51 2 1363463536 8993344 5278112
15:11:31 128423 8192000000 10274635584 10724413440 1324680704 13.919 96 11 22 0 1341240704 13999984 2560016
15:14:41 52857 8192000000 10290266112 10737291264 1380923648 14.425 100 47 94 1 1400291360 8537824 10829888
15:17:09 61518 8192000000 10308380720 10744201216 1376584816 14.386 99 25 50 0 1393538000 10850288 6102896
15:19:13 70449 8192000000 10292113968 10732068864 1352800608 14.173 97 37 71 1 1369314384 8228768 8285008
15:21:28 11779 8192000000 10326702976 10771939328 1401253056 14.606 100 61 118 0 1434123904 18076400 14794448
15:22:41 49973 8192000000 10300217648 10772410368 1391168736 14.516 100 21 41 1 1419599520 22626576 5804208
15:24:33 46311 8192000000 10300713248 10756661248 1384074656 14.453 99 17 34 0 1395776448 7688336 4013456
15:26:23 43767 8192000000 10305707568 10766159872 1405682448 14.646 100 16 31 0 1418705280 9169328 3853504
15:28:25 36273 8192000000 10290585296 10760024064 1388309840 14.491 99 26 50 1 1402660352 7473248 6877264
15:30:07 52532 8192000000 10298987296 10764238848 1386974320 14.479 99 13 26 3 1406692816 16508784 3209712
15:32:02 78624 8192000000 10304867424 10775728128 1384271040 14.455 99 8 16 1 1401940288 15967312 1701936
15:34:21 37317 8192000000 10321981952 10768199680 1387336912 14.482 99 23 46 0 1405442944 13327392 4778640
15:36:00 28087 8192000000 10307057728 10770698240 1388021648 14.488 99 21 41 2 1411171552 18024864 5125040
15:37:31 37792 8192000000 10304424832 10764681216 1385191856 14.463 99 26 52 1 1405761808 14334640 6235312
15:39:12 33416 8192000000 10304889264 10746765312 1364508304 14.278 98 27 54 0 1379557728 9162448 5886976
15:41:02 54027 8192000000 10289146784 10748366848 1335806144 14.020 96 16 32 0 1357430688 18175056 3449488
15:42:58 18965 8192000000 10293378240 10742706176 1369006496 14.318 99 16 32 0 1383841600 11627840 3207264
15:44:33 34693 8192000000 10289424944 10733842432 1326863408 13.939 96 23 46 0 1343336464 11569040 4904016
15:46:10 61576 8192000000 10244745152 10673446912 1249761680 13.236 91 49 98 1 1268671104 7342896 11566528
15:48:20 23004 8192000000 10245675840 10689622016 1326369520 13.934 98 23 47 4 1337984624 6161408 5453696
15:49:45 41077 8192000000 10245645616 10697469952 1339455168 14.052 99 14 28 0 1351979024 9742720 2781136
15:51:33 55973 8192000000 10262001008 10705170432 1342137408 14.077 99 17 35 1 1358291888 11974816 4179664
15:53:31 69994 8192000000 10249129088 10700992512 1327383040 13.944 98 6 12 0 1338879264 10488016 1008208
15:55:41 42520 8192000000 10285553152 10731560960 1355529120 14.197 99 28 56 0 1375038672 13294496 6215056
15:57:54 39093 8192000000 10277250592 10717306880 1349860848 14.146 98 20 38 2 1362701312 8552848 4287616
15:59:35 49268 8192000000 10286846560 10730033152 1332632560 13.991 96 39 78 1 1355461632 14678144 8150928
16:01:25 45732 8192000000 10284592960 10742378496 1381838160 14.433 100 33 64 1 1398434976 9428144 7168672
16:03:13 37077 8192000000 10294777600 10749493248 1376468976 14.385 99 27 52 1 1392296000 10051312 5775712
16:04:50 24069 8192000000 10317969216 10759733248 1387388832 14.483 100 37 74 0 1404693328 8966656 8337840
16:06:17 106557 8192000000 10287514000 10741379072 1367401760 14.304 98 14 28 1 1389215744 18007744 3806240
16:09:44 9144 8192000000 10316659600 10756988928 1381136688 14.427 98 52 104 1 1401464032 8839696 11487648
16:10:56 57448 8192000000 10312227664 10771611648 1402396880 14.616 99 26 52 0 1419348800 11252128 5699792
16:12:54 71645 8192000000 10321429664 10791260160 1418526784 14.760 100 16 32 1 1439299024 16668848 4103392
16:15:38 52921 8192000000 10315212880 10771697664 1366336640 14.294 97 11 22 1 1386252336 17565472 2350224
16:17:33 54324 8192000000 10307503616 10770452480 1359653712 14.234 96 20 40 1 1382311808 18205152 4452944
16:20:05 71540 8192000000 10308783296 10773172224 1353085488 14.175 96 16 32 0 1370515104 13753904 3675712
16:22:18 71326 8192000000 10269142208 10719023104 1271261904 13.433 92 12 24 0 1283434640 9423952 2748784
16:24:31 26126 8192000000 10274959280 10710601728 1354744560 14.190 100 38 76 0 1376767488 13713744 8309184
16:26:01 140376 8192000000 10263437920 10712014848 1286948880 13.576 94 20 40 2 1305632944 14453792 4230272
16:29:22 42489 8192000000 10238224176 10694680576 1333517520 13.999 98 14 28 0 1347076656 10143488 3415648
16:31:10 56611 8192000000 10244679616 10697027584 1332380128 13.989 98 17 35 0 1341614304 5721584 3512592
16:33:13 76243 8192000000 10284709216 10743386112 1378393152 14.402 100 20 40 0 1398705648 15479632 4832864
16:35:32 70139 8192000000 10259205248 10725240832 1322044816 13.895 96 8 16 0 1335869104 12063008 1761280
16:37:53 41583 8192000000 9290637712 9580204032 442343488 5.123 33 8 14 0 447917264 3554016 2019760

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-06-2022 16:39:55 to 15-06-2022 16:40:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 174 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-06-2022 16:40:11 to 15-06-2022 16:40:13.

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          250093
MON$OLDEST_ACTIVE               250094
MON$OLDEST_SNAPSHOT             250094
MON$NEXT_TRANSACTION            250094
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                       2041696
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
:658: 
:659: mscs-test-161.budzdorov.ru    Wed Jun 15 16:38:41 2022
:660:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:661: 
--- end of diff output ---

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

15.06.22 16:40:14. 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
2022-06-15 16:40:16 No severe PSQL-related problems occured
15.06.22 16:40:18. End of report.