Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.05.2022 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 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 16441064 kB
MemAvailable 31491580 kB
Buffers 0 kB
Cached 15243768 kB
SwapCached 3292 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-05-04 22:38:16
DTS_END                         2022-05-04 22:38:16.5930
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/51726
TRN_ID                          245974
ATT_ID                          717
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: 9478.92 18641 1137470
customer order: creation 175.90 344 21108
customer order: refuse 15.30 334 1836
order to supplier: creation 47.29 1268 5675
order to supplier: removal 3.25 116502 390
invoice (draft): creation 45.73 3354 5487
invoice (draft): removal 53.70 824 6444
invoice accept: apply 43.43 16295 5212
invoice accept: cancel 49.18 794 5901
customer reserve: creation 3964.25 161 475710
customer reserve: removal 4775.27 62 573032
realization accept: apply 85.67 190 10280
realization accept: cancel 88.82 109 10658
payment from customer: creation 60.60 136 7272
payment from customer: removal 5.42 75 650
payment to supplier: creation 49.43 156 5932
payment to supplier: removal 3.43 58 411
service: total inventory turnovers 4.47 1106 537
service: total monetary turnovers 6.83 30 820
service: aggregate perf. data 0.93 15048 111
service: refresh index statistics 0.03 215977 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 04-05-2022 22:39:32 to 04-05-2022 22:39:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7911
TEST_TIME 2 6540
TEST_TIME 3 5712
TEST_TIME 4 5546
TEST_TIME 5 5302
TEST_TIME 6 4929
TEST_TIME 7 5122
TEST_TIME 8 5348
TEST_TIME 9 5227
TEST_TIME 10 5290
TEST_TIME 11 5353
TEST_TIME 12 5291
TEST_TIME 13 5587
TEST_TIME 14 6353
TEST_TIME 15 6640
TEST_TIME 16 6843
TEST_TIME 17 7015
TEST_TIME 18 7026
TEST_TIME 19 7522
TEST_TIME 20 7929
TEST_TIME 21 8148
TEST_TIME 22 8488
TEST_TIME 23 9189
TEST_TIME 24 9060
TEST_TIME 25 9831
TEST_TIME 26 9681
TEST_TIME 27 10516
TEST_TIME 28 11044
TEST_TIME 29 10849
TEST_TIME 30 10614
TEST_TIME 31 10492
TEST_TIME 32 10487
TEST_TIME 33 10395
TEST_TIME 34 10302
TEST_TIME 35 10261
TEST_TIME 36 10161
TEST_TIME 37 10149
TEST_TIME 38 10103
TEST_TIME 39 10163
TEST_TIME 40 10193
TEST_TIME 41 10150
TEST_TIME 42 10061
TEST_TIME 43 10057
TEST_TIME 44 9941
TEST_TIME 45 9808
TEST_TIME 46 9696
TEST_TIME 47 9729
TEST_TIME 48 9582
TEST_TIME 49 9593
TEST_TIME 50 9734
TEST_TIME 51 9696
TEST_TIME 52 9729
TEST_TIME 53 9674
TEST_TIME 54 9757
TEST_TIME 55 9904
TEST_TIME 56 9962
TEST_TIME 57 10094
TEST_TIME 58 10044
TEST_TIME 59 10056
TEST_TIME 60 10045
TEST_TIME 61 10090
TEST_TIME 62 10041
TEST_TIME 63 10107
TEST_TIME 64 9977
TEST_TIME 65 9936
TEST_TIME 66 9848
TEST_TIME 67 9842
TEST_TIME 68 9806
TEST_TIME 69 9762
TEST_TIME 70 9736
TEST_TIME 71 9692
TEST_TIME 72 9603
TEST_TIME 73 9573
TEST_TIME 74 9563
TEST_TIME 75 9533
TEST_TIME 76 9538
TEST_TIME 77 9586
TEST_TIME 78 9626
TEST_TIME 79 9674
TEST_TIME 80 9695
TEST_TIME 81 9677
TEST_TIME 82 9673
TEST_TIME 83 9745
TEST_TIME 84 9724
TEST_TIME 85 9746
TEST_TIME 86 9786
TEST_TIME 87 9764
TEST_TIME 88 9699
TEST_TIME 89 9648
TEST_TIME 90 9621
TEST_TIME 91 9628
TEST_TIME 92 9572
TEST_TIME 93 9542
TEST_TIME 94 9571
TEST_TIME 95 9604
TEST_TIME 96 9607
TEST_TIME 97 9640
TEST_TIME 98 9624
TEST_TIME 99 9610
TEST_TIME 100 9668
TEST_TIME 101 9676
TEST_TIME 102 9694
TEST_TIME 103 9727
TEST_TIME 104 9772
TEST_TIME 105 9764
TEST_TIME 106 9769
TEST_TIME 107 9785
TEST_TIME 108 9705
TEST_TIME 109 9709
TEST_TIME 110 9664
TEST_TIME 111 9670
TEST_TIME 112 9641
TEST_TIME 113 9600
TEST_TIME 114 9576
TEST_TIME 115 9552
TEST_TIME 116 9492
TEST_TIME 117 9507
TEST_TIME 118 9531
TEST_TIME 119 9543
TEST_TIME 120 9492

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-05-2022 22:39:36 to 04-05-2022 22:39:39.

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 1576360 1576360 0 0.00 0 22806 6 0 0
doc_list_biud 1576360 1576360 0 0.00 0 24976 28 0 0
sp_add_invoice_to_stock 5321 5212 109 2.04 28 166670 16296 0 109
sp_cancel_adding_invoice 5901 5901 0 0.00 3 88768 795 0 0
sp_cancel_client_order 1836 1836 0 0.00 4 7721 335 0 0
sp_cancel_customer_reserve 573032 573032 0 0.00 1 19832 63 0 0
sp_cancel_pay_from_customer 650 650 0 0.00 3 9272 75 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 4097 58 0 0
sp_cancel_supplier_invoice 6450 6444 6 0.09 1 24979 825 0 6
sp_cancel_supplier_order 390 390 0 0.00 6 1094067 116502 0 0
sp_cancel_write_off 10658 10658 0 0.00 1 20426 110 0 0
sp_client_order 21108 21108 0 0.00 3 15895 345 0 0
sp_customer_reserve 475710 475710 0 0.00 0 96460 161 0 0
sp_fill_shopping_cart 32263 32263 0 0.00 1 14074 37 0 0
sp_fill_shopping_cart_clo_res 475696 475696 0 0.00 0 13431 14 0 0
sp_get_clo_for_invoice 5193 5193 0 0.00 0 20213 300 0 0
sp_kill_cost_storno 17620 17620 0 0.00 0 9255 56 0 0
sp_kill_qstorno_ret_qs2qd 1163556 1163556 0 0.00 0 24975 34 0 0
sp_kill_qty_storno 613734 613734 0 0.00 0 24976 72 0 0
sp_lock_selected_doc 21374 21374 0 0.00 0 11546 6 0 0
sp_make_cost_storno 28677 28677 0 0.00 0 17933 58 0 0
sp_multiply_rows_for_pdistr 28677 28677 0 0.00 0 13777 37 0 0
sp_multiply_rows_for_qdistr 21108 21108 0 0.00 0 11970 170 0 0
sp_pay_from_customer 7272 7272 0 0.00 4 23977 136 0 0
sp_pay_to_supplier 5932 5932 0 0.00 4 16807 156 0 0
sp_payment_common 13204 13204 0 0.00 2 23947 48 0 0
sp_qd_handle_on_cancel_clo 1836 1836 0 0.00 0 7715 297 0 0
sp_qd_handle_on_invoice_upd_sts 11094 11094 0 0.00 0 20263 314 0 0
sp_qd_handle_on_reserve_upd_sts 10280 10280 0 0.00 0 15154 48 0 0
sp_reserve_write_off 10280 10280 0 0.00 6 19017 191 0 0
sp_supplier_invoice 5487 5487 0 0.00 7 79969 3354 0 0
sp_supplier_order 5675 5675 0 0.00 5 30207 1268 0 0
srv_aggregate_perf_data 111 111 0 0.00 2644 168079 15048 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2059 137958 45573 0 0
srv_find_qd_qs_mism 1087920 1087920 0 0.00 0 17907 5 0 0
srv_make_invnt_saldo 537 537 0 0.00 32 42938 1106 0 0
srv_make_money_saldo 820 820 0 0.00 1 5828 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 110515 368086 215977 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5213 5213 0 0.00 0 13744 171 0 0
t$perf-norm:sp_cancel_adding_invoice 433 433 0 0.00 0 10959 143 0 0
t$perf-norm:sp_cancel_client_order 1836 1836 0 0.00 0 5729 21 0 0
t$perf-norm:sp_cancel_customer_reserve 737 737 0 0.00 0 2080 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 650 650 0 0.00 0 1152 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 1254 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 433 433 0 0.00 0 3876 22 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 31721 1798 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 1460 5 0 0
t$perf-norm:sp_client_order 21108 21108 0 0.00 0 17022 20 0 0
t$perf-norm:sp_customer_reserve 1002 1002 0 0.00 0 4912 16 0 0
t$perf-norm:sp_pay_from_customer 7272 7272 0 0.00 0 12188 13 0 0
t$perf-norm:sp_pay_to_supplier 5932 5932 0 0.00 0 3945 9 0 0
t$perf-norm:sp_reserve_write_off 10280 10280 0 0.00 0 14658 13 0 0
t$perf-norm:sp_supplier_invoice 5487 5487 0 0.00 0 8512 24 0 0
t$perf-norm:sp_supplier_order 5675 5675 0 0.00 0 4888 17 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 658 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 451 16 0 0
t$perf-norm:srv_make_invnt_saldo 537 537 0 0.00 0 2896 32 0 0
t$perf-norm:srv_make_money_saldo 820 820 0 0.00 0 1049 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 11 4 0 0
v_add_invoice_to_stock 10604 10386 218 2.05 0 10397 17 0 218
v_all_customers 38590 38590 0 0.00 0 95 1 0 0
v_all_suppliers 30436 30436 0 0.00 0 20 1 0 0
v_all_wares 42216 42216 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 866 866 0 0.00 0 1536 7 0 0
v_cancel_client_order 3672 3672 0 0.00 0 4839 6 0 0
v_cancel_customer_prepayment 1300 1300 0 0.00 0 2216 7 0 0
v_cancel_customer_reserve 1474 1474 0 0.00 0 1369 3 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 105 2 0 0
v_cancel_supplier_invoice 878 866 12 1.36 0 6474 22 0 12
v_cancel_supplier_order 780 780 0 0.00 0 1337 5 0 0
v_cancel_write_off 2022 2022 0 0.00 0 5719 18 0 0
v_max_id_clo_ord 5674 5674 0 0.00 0 149 1 0 0
v_max_id_clo_res 1002 1002 0 0.00 0 1691 11 0 0
v_max_id_ord_sup 5481 5481 0 0.00 0 6999 3 0 0
v_max_non_paid_invoice 1869 1869 0 0.00 0 5531 11 0 0
v_max_non_paid_realizn 3815 3815 0 0.00 0 3910 5 0 0
v_min_id_clo_ord 5674 5674 0 0.00 0 7891 9 0 0
v_min_id_clo_res 1002 1002 0 0.00 0 6841 11 0 0
v_min_id_ord_sup 5481 5481 0 0.00 0 3462 4 0 0
v_min_non_paid_invoice 5932 5932 0 0.00 0 10664 38 0 0
v_min_non_paid_realizn 7272 7272 0 0.00 0 17404 34 0 0
v_our_firm 10540 10540 0 0.00 0 16 1 0 0
v_reserve_write_off 20560 20560 0 0.00 0 4931 5 0 0
x_lock_depdocs_on_canc_invoice 11912 11912 0 0.00 0 18795 126 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 9697 396 0 0
x_make_qstorno_1000_1200 5675 5675 0 0.00 1 30182 1188 0 0
x_make_qstorno_1200_2000 5487 5487 0 0.00 3 65891 3226 0 0
x_make_qstorno_2100_3300 475709 475709 0 0.00 0 96458 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-05-2022 22:39:40 to 04-05-2022 22:39:44.

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:08:20 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:21 19204 8192000000 9899271040 10209628160 984618400 10.729 100 85 170 0 1013644784 6354800 22671584
20:10:50 19281 8192000000 9950869344 10275213312 1030513168 11.173 100 89 178 0 1061567472 8250272 22804032
20:12:16 11959 8192000000 9974334000 10309177344 1060715232 11.463 100 80 160 0 1090444688 9211520 20517936
20:13:32 14883 8192000000 10039438640 10386128896 1121583968 12.042 100 82 164 0 1151699872 9914352 20201552
20:14:50 11890 8192000000 10086042672 10445533184 1170192480 12.499 100 74 148 4 1198359008 10942480 17224048
20:16:04 5983 8192000000 10129481808 10500325376 1222681248 12.986 100 75 150 3 1251835536 13208208 15946080
20:17:11 2417 8192000000 10177852032 10553413632 1258816848 13.319 100 94 186 4 1294648016 15315792 20515376
20:18:15 2276 8192000000 10204197616 10585075712 1286970400 13.577 100 94 188 0 1320745488 12914240 20860848
20:19:18 2408 8192000000 10211792400 10602323968 1298598224 13.682 100 79 158 2 1328813008 11954928 18259856
20:20:21 7002 8192000000 10195414480 10604179456 1307270944 13.761 100 32 59 1 1320716128 6667232 6777952
20:21:34 61878 8192000000 10212251200 10626486272 1315505664 13.836 100 44 88 1 1331700352 5384320 10810368
20:23:36 3399 8192000000 10216491936 10633109504 1320795936 13.884 100 34 66 2 1335519648 7330512 7393200
20:24:42 4279 8192000000 10233145488 10645934080 1331657648 13.982 100 34 68 1 1345567024 5632688 8276688
20:25:47 13202 8192000000 10220753040 10644688896 1322026640 13.895 99 17 34 1 1332020320 6369024 3624656
20:27:01 28589 8192000000 10212059216 10635034624 1294020768 13.641 97 31 62 0 1307011936 5341760 7649408
20:28:33 7930 8192000000 10199372864 10613915648 1296530048 13.664 99 35 69 1 1310437152 4915936 8991168
20:29:43 9887 8192000000 10122820032 10516299776 1163990128 12.441 91 24 48 1 1174506016 4449680 6066208
20:30:53 13670 8192000000 10130942224 10526085120 1198824080 12.765 96 15 29 0 1207552592 5476880 3251632
20:32:09 17602 8192000000 10152195344 10551320576 1223756592 12.996 97 27 54 1 1236264512 6520448 5987472
20:33:27 7651 8192000000 10169010608 10572083200 1274057200 13.459 100 30 61 0 1287733648 6215824 7460624
20:34:35 10179 8192000000 10186432960 10591920128 1289564272 13.600 100 30 59 1 1302456752 5583328 7309152
20:35:47 41003 8192000000 10192923072 10603311104 1299529600 13.691 100 26 51 1 1311028128 5912976 5585552
20:37:47 7605 8192000000 10203082640 10615963648 1311014000 13.795 100 31 63 0 1323918464 6453728 6450736
20:39:00 9811 8192000000 10257153056 10662256640 1344942032 14.102 100 62 123 0 1366154736 7739664 13473040
20:40:13 12084 8192000000 10275094416 10686509056 1358069872 14.220 100 59 118 2 1386107120 14605296 13431952
20:41:29 25808 8192000000 10285123216 10703491072 1372766720 14.352 100 49 98 1 1402398624 18373136 11258768
20:43:01 21240 8192000000 10290911024 10708369408 1371129296 14.337 99 42 84 1 1402277440 21403152 9744992
20:44:24 27476 8192000000 10317460272 10735550464 1390495024 14.510 100 44 88 2 1427691968 25367776 11829168
20:46:27 9101 8192000000 10313486960 10729480192 1385076736 14.462 99 54 108 1 1424158080 25462064 13619280
20:47:48 14934 8192000000 10344947360 10771841024 1414356832 14.723 100 51 102 1 1461360880 33739008 13265040
20:49:06 2057 8192000000 10336610480 10745126912 1381793792 14.433 98 80 160 1 1438300704 36642496 19864416
20:50:13 10301 8192000000 10363626352 10776846336 1407935552 14.666 100 65 131 2 1466153360 41672560 16545248
20:51:33 3308 8192000000 10360051520 10770022400 1386694496 14.476 99 75 150 2 1455927472 49780416 19452560
20:52:42 4627 8192000000 10355482448 10765574144 1389050592 14.497 100 74 147 2 1462062048 53978112 19033344
20:53:52 4894 8192000000 10360531872 10766585856 1381699040 14.432 99 82 164 0 1460804336 57749760 21355536
20:55:06 7320 8192000000 10354252576 10759929856 1375009872 14.372 99 71 142 1 1448789744 55467872 18312000
20:56:17 2898 8192000000 10356930160 10763206656 1358027744 14.220 98 79 158 1 1439747584 61708304 20011536
20:57:32 2774 8192000000 10375807776 10774511616 1384106032 14.453 100 94 190 2 1475253872 68006928 23140912
20:58:39 2826 8192000000 10358638432 10774401024 1387609072 14.485 100 69 136 0 1465537232 59406000 18522160
20:59:47 11108 8192000000 10354137872 10779058176 1386115632 14.471 100 42 84 1 1459796704 62112352 11568720
21:01:20 19734 8192000000 10309705664 10742927360 1361236752 14.248 100 31 62 0 1422560192 52363088 8960352
21:02:47 39405 8192000000 10320583088 10761428992 1353046896 14.175 99 24 48 0 1415829456 55554640 7227920
21:04:27 45260 8192000000 10310178832 10751811584 1354042512 14.184 100 19 38 0 1413253792 53565040 5646240
21:06:19 34060 8192000000 10252303408 10688094208 1325426704 13.926 98 18 36 0 1340081440 11295648 3359088
21:08:29 89934 8192000000 10263395280 10699247616 1319260656 13.870 97 29 59 0 1332581456 6639952 6680848
21:11:09 56868 8192000000 10279017392 10717315072 1336621808 14.027 97 28 56 0 1352725648 10196752 5907088
21:13:11 53377 8192000000 10280546256 10730889216 1378886608 14.407 100 14 28 0 1394193008 12253488 3052912
21:15:07 21916 8192000000 10285637296 10736275456 1381573712 14.431 100 27 54 0 1402112784 14541664 5997408
21:16:32 42432 8192000000 10302010224 10753527808 1392349712 14.527 100 46 90 4 1419370640 15306656 11714272
21:18:22 24449 8192000000 10290505936 10762440704 1392088688 14.524 100 8 16 1 1406644704 12765456 1790560
21:19:47 110886 8192000000 10290105664 10742308864 1348140160 14.131 96 10 20 0 1358503152 8136880 2226112
21:22:39 91965 8192000000 10298602528 10757746688 1395999440 14.559 100 25 50 2 1411326912 9912432 5415040
21:25:15 40098 8192000000 10300973568 10762301440 1389625056 14.503 99 12 24 0 1407283136 15024192 2633888
21:26:56 64693 8192000000 10290318272 10761654272 1388191472 14.490 99 11 22 0 1409833248 18663744 2978032
21:29:41 40016 8192000000 10289517376 10746855424 1361810240 14.254 98 23 46 1 1380986464 13048624 6127600
21:32:06 43305 8192000000 10299308416 10756280320 1368022016 14.309 98 14 29 1 1387116848 15632560 3462272
21:33:53 56795 8192000000 10291392624 10757160960 1354201648 14.185 98 15 30 1 1379116064 21411968 3502448
21:36:02 24353 8192000000 10286808464 10727600128 1349220896 14.140 98 42 83 1 1366875456 8267616 9386944
21:37:28 42968 8192000000 10284820368 10711539712 1295879808 13.658 94 22 44 1 1307935120 7382400 4672912
21:39:15 45186 8192000000 10278449472 10712608768 1340067008 14.058 97 27 54 1 1354953952 9371824 5515120
21:41:03 67176 8192000000 10268960912 10730369024 1358323440 14.222 99 21 42 0 1378657600 15843600 4490560
21:43:27 88078 8192000000 10275621120 10714132480 1327199552 13.942 96 43 85 2 1346596288 9837504 9559232
21:45:56 66148 8192000000 10246477792 10702770176 1342321728 14.078 99 6 12 0 1355663152 12172320 1169104
21:48:03 37311 8192000000 10243789328 10691215360 1324407280 13.917 98 14 28 2 1339659136 12175568 3076288
21:49:40 59785 8192000000 10222362608 10671673344 1308971728 13.777 97 11 21 1 1321792272 10234640 2585904
21:51:51 72795 8192000000 10236932512 10678046720 1292356368 13.626 96 9 18 0 1301947232 7870000 1720864
21:54:08 77469 8192000000 10268708608 10723741696 1351171952 14.158 99 22 44 0 1371283552 14745456 5366144
21:56:26 73368 8192000000 10263906400 10713751552 1315453120 13.836 96 8 16 0 1332583840 15073216 2057504
21:58:39 74229 8192000000 10277811840 10722910208 1337782960 14.037 98 16 32 0 1349685776 7924624 3978192
22:01:00 60133 8192000000 10278588928 10730381312 1356954768 14.210 99 17 34 1 1373905296 13058912 3891616
22:03:02 12441 8192000000 10304720224 10738335744 1367700080 14.306 99 44 88 1 1391095184 13788480 9606624
22:04:56 110194 8192000000 10277694896 10738180096 1321177312 13.887 96 18 36 0 1339904752 14319120 4408320
22:08:12 39301 8192000000 10264930224 10722611200 1369026496 14.318 100 18 37 0 1382589744 9336704 4226544
22:09:53 46041 8192000000 10283364832 10750291968 1379218176 14.410 100 7 14 0 1395880848 15145808 1516864
22:11:39 56935 8192000000 10312712640 10765922304 1392563248 14.529 100 18 36 0 1411994192 15310160 4120784
22:13:42 76013 8192000000 10302883792 10756685824 1373460832 14.358 98 23 46 1 1395951376 17079552 5410992
22:15:58 72633 8192000000 10306469888 10764222464 1371540000 14.341 98 17 34 0 1388416480 12928528 3947952
22:18:12 66531 8192000000 10285110352 10754170880 1360230432 14.239 98 5 10 0 1375501216 14221536 1049248
22:20:26 137958 8192000000 10285183168 10756046848 1316576720 13.846 95 19 38 1 1336706368 16013248 4116400
22:23:44 76017 8192000000 10280182800 10731294720 1299639888 13.692 94 15 30 1 1312099472 9173696 3285888
22:26:19 90114 8192000000 10274925952 10723688448 1344283392 14.096 98 37 74 0 1360576496 7398224 8894880
22:28:54 36222 8192000000 10278165392 10718896128 1341399072 14.070 98 13 26 0 1351784000 7568208 2816720
22:30:33 50210 8192000000 10263988304 10722615296 1348953344 14.138 99 7 14 0 1360156400 9740256 1462800
22:32:28 53568 8192000000 10273556560 10727747584 1374888016 14.371 100 14 27 1 1390338352 11667456 3782880
22:34:23 71984 8192000000 10292860544 10753683456 1387510432 14.484 100 16 31 1 1407506304 15935216 4060656
22:36:36 38143 8192000000 10282648288 10740641792 1359910400 14.237 98 13 26 1 1371486192 8849568 2726224

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-05-2022 22:39:46 to 04-05-2022 22:40:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-05-2022 22:40:02 to 04-05-2022 22:40:04.

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          244575
MON$OLDEST_ACTIVE               246084
MON$OLDEST_SNAPSHOT             246084
MON$NEXT_TRANSACTION            246084
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                       2044256
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.33585 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33585 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33585 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33585 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 ---
:56: 
:57: mscs-test-161.budzdorov.ru    Wed May  4 22:38:20 2022
:58:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:59: 
:60: 
:61: mscs-test-161.budzdorov.ru    Wed May  4 22:38:20 2022
:62:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:63: 
:64: 
:65: mscs-test-161.budzdorov.ru    Wed May  4 22:38:22 2022
:66:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:67: 
--- end of diff output ---

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

04.05.22 22:40:05. 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-05-04 22:40:07 No severe PSQL-related problems occured
04.05.22 22:40:09. End of report.