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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16438116 kB
MemAvailable 31558600 kB
Buffers 0 kB
Cached 15236500 kB
SwapCached 2208 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-04-02 16:38:16
DTS_END                         2022-04-02 16:38:16.7410
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/51930
TRN_ID                          245256
ATT_ID                          712
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: 9647.82 22705 1157738
customer order: creation 173.68 349 20842
customer order: refuse 15.14 325 1817
order to supplier: creation 47.27 1389 5672
order to supplier: removal 3.57 94830 428
invoice (draft): creation 46.26 3521 5551
invoice (draft): removal 55.82 753 6698
invoice accept: apply 43.45 16607 5214
invoice accept: cancel 50.75 874 6090
customer reserve: creation 3965.29 166 475835
customer reserve: removal 4948.10 54 593772
realization accept: apply 85.30 177 10236
realization accept: cancel 81.86 109 9823
payment from customer: creation 60.29 127 7235
payment from customer: removal 5.69 108 683
payment to supplier: creation 49.34 151 5921
payment to supplier: removal 3.76 67 451
service: total inventory turnovers 4.40 1189 528
service: total monetary turnovers 6.94 43 833
service: aggregate perf. data 0.88 19356 105
service: refresh index statistics 0.03 313912 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-04-2022 16:39:14 to 02-04-2022 16:39:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3112
TEST_TIME 2 5080
TEST_TIME 3 3576
TEST_TIME 4 4553
TEST_TIME 5 4313
TEST_TIME 6 5041
TEST_TIME 7 5207
TEST_TIME 8 5974
TEST_TIME 9 5883
TEST_TIME 10 5900
TEST_TIME 11 5921
TEST_TIME 12 5986
TEST_TIME 13 6126
TEST_TIME 14 6867
TEST_TIME 15 8078
TEST_TIME 16 7851
TEST_TIME 17 8124
TEST_TIME 18 8490
TEST_TIME 19 8551
TEST_TIME 20 8449
TEST_TIME 21 8701
TEST_TIME 22 8887
TEST_TIME 23 9262
TEST_TIME 24 9472
TEST_TIME 25 9387
TEST_TIME 26 9947
TEST_TIME 27 10139
TEST_TIME 28 10266
TEST_TIME 29 10823
TEST_TIME 30 10845
TEST_TIME 31 10657
TEST_TIME 32 10639
TEST_TIME 33 10519
TEST_TIME 34 10705
TEST_TIME 35 10597
TEST_TIME 36 10518
TEST_TIME 37 10606
TEST_TIME 38 10521
TEST_TIME 39 10490
TEST_TIME 40 10528
TEST_TIME 41 10494
TEST_TIME 42 10566
TEST_TIME 43 10428
TEST_TIME 44 10202
TEST_TIME 45 10134
TEST_TIME 46 10133
TEST_TIME 47 10080
TEST_TIME 48 10061
TEST_TIME 49 10030
TEST_TIME 50 9944
TEST_TIME 51 9957
TEST_TIME 52 10070
TEST_TIME 53 10085
TEST_TIME 54 10008
TEST_TIME 55 9921
TEST_TIME 56 9868
TEST_TIME 57 9873
TEST_TIME 58 9869
TEST_TIME 59 9839
TEST_TIME 60 9722
TEST_TIME 61 9739
TEST_TIME 62 9827
TEST_TIME 63 9910
TEST_TIME 64 9958
TEST_TIME 65 10022
TEST_TIME 66 10030
TEST_TIME 67 10034
TEST_TIME 68 9989
TEST_TIME 69 10145
TEST_TIME 70 10142
TEST_TIME 71 10140
TEST_TIME 72 10152
TEST_TIME 73 10146
TEST_TIME 74 10203
TEST_TIME 75 10188
TEST_TIME 76 10100
TEST_TIME 77 10092
TEST_TIME 78 10030
TEST_TIME 79 10030
TEST_TIME 80 9999
TEST_TIME 81 9945
TEST_TIME 82 9903
TEST_TIME 83 9851
TEST_TIME 84 9789
TEST_TIME 85 9792
TEST_TIME 86 9834
TEST_TIME 87 9807
TEST_TIME 88 9769
TEST_TIME 89 9747
TEST_TIME 90 9779
TEST_TIME 91 9773
TEST_TIME 92 9788
TEST_TIME 93 9827
TEST_TIME 94 9797
TEST_TIME 95 9850
TEST_TIME 96 9917
TEST_TIME 97 9930
TEST_TIME 98 9900
TEST_TIME 99 9910
TEST_TIME 100 9944
TEST_TIME 101 9956
TEST_TIME 102 9937
TEST_TIME 103 9989
TEST_TIME 104 9897
TEST_TIME 105 9864
TEST_TIME 106 9816
TEST_TIME 107 9812
TEST_TIME 108 9778
TEST_TIME 109 9738
TEST_TIME 110 9697
TEST_TIME 111 9677
TEST_TIME 112 9670
TEST_TIME 113 9710
TEST_TIME 114 9687
TEST_TIME 115 9686
TEST_TIME 116 9675
TEST_TIME 117 9688
TEST_TIME 118 9738
TEST_TIME 119 9725
TEST_TIME 120 9654

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-04-2022 16:39:18 to 02-04-2022 16:39:21.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1598731 1598731 0 0.00 0 23570 6 0 0
doc_list_biud 1598728 1598728 0 0.00 0 70311 26 0 0
sp_add_invoice_to_stock 5356 5214 142 2.65 28 160436 16608 0 142
sp_cancel_adding_invoice 6090 6090 0 0.00 3 60809 875 0 0
sp_cancel_client_order 1817 1817 0 0.00 4 11515 325 0 0
sp_cancel_customer_reserve 593772 593772 0 0.00 1 35367 54 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 3 6687 109 0 0
sp_cancel_pay_to_supplier 451 451 0 0.00 3 5470 67 0 0
sp_cancel_supplier_invoice 6711 6698 13 0.19 1 70319 753 0 13
sp_cancel_supplier_order 428 428 0 0.00 8 969077 94831 0 0
sp_cancel_write_off 9823 9823 0 0.00 1 8451 109 0 0
sp_client_order 20842 20842 0 0.00 3 21621 350 0 0
sp_customer_reserve 475835 475835 0 0.00 0 38087 167 0 0
sp_fill_shopping_cart 32062 32062 0 0.00 1 31109 40 0 0
sp_fill_shopping_cart_clo_res 475827 475827 0 0.00 0 28672 12 0 0
sp_get_clo_for_invoice 5206 5206 0 0.00 0 29488 305 0 0
sp_kill_cost_storno 17047 17047 0 0.00 0 10013 58 0 0
sp_kill_qstorno_ret_qs2qd 1204493 1204493 0 0.00 0 70307 30 0 0
sp_kill_qty_storno 634068 634068 0 0.00 0 70309 64 0 0
sp_lock_selected_doc 21408 21408 0 0.00 0 8421 5 0 0
sp_make_cost_storno 28598 28598 0 0.00 0 21571 62 0 0
sp_multiply_rows_for_pdistr 28597 28597 0 0.00 0 13890 30 0 0
sp_multiply_rows_for_qdistr 20842 20842 0 0.00 0 17316 156 0 0
sp_pay_from_customer 7235 7235 0 0.00 4 11535 127 0 0
sp_pay_to_supplier 5921 5921 0 0.00 5 18103 151 0 0
sp_payment_common 13156 13156 0 0.00 1 11354 32 0 0
sp_qd_handle_on_cancel_clo 1817 1817 0 0.00 0 11499 297 0 0
sp_qd_handle_on_invoice_upd_sts 11294 11294 0 0.00 0 39650 333 0 0
sp_qd_handle_on_reserve_upd_sts 10236 10236 0 0.00 0 13424 55 0 0
sp_reserve_write_off 10236 10236 0 0.00 6 18770 178 0 0
sp_supplier_invoice 5551 5551 0 0.00 8 89514 3521 0 0
sp_supplier_order 5672 5672 0 0.00 5 111610 1390 0 0
srv_aggregate_perf_data 105 105 0 0.00 3206 442444 19357 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2542 110901 42851 0 0
srv_find_qd_qs_mism 1109259 1109259 0 0.00 0 11770 5 0 0
srv_make_invnt_saldo 528 528 0 0.00 40 72112 1190 0 0
srv_make_money_saldo 833 833 0 0.00 1 15638 44 0 0
srv_recalc_idx_stat 4 4 0 0.00 256307 392791 313913 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5214 5214 0 0.00 0 10898 152 0 0
t$perf-norm:sp_cancel_adding_invoice 433 433 0 0.00 1 2139 78 0 0
t$perf-norm:sp_cancel_client_order 1817 1817 0 0.00 0 2305 11 0 0
t$perf-norm:sp_cancel_customer_reserve 665 665 0 0.00 0 3022 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 3931 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 451 451 0 0.00 0 714 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 3452 17 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 26831 1446 0 0
t$perf-norm:sp_cancel_write_off 1075 1075 0 0.00 0 328 3 0 0
t$perf-norm:sp_client_order 20842 20842 0 0.00 0 13065 17 0 0
t$perf-norm:sp_customer_reserve 951 951 0 0.00 0 4609 17 0 0
t$perf-norm:sp_pay_from_customer 7235 7235 0 0.00 0 3457 6 0 0
t$perf-norm:sp_pay_to_supplier 5921 5921 0 0.00 0 6579 11 0 0
t$perf-norm:sp_reserve_write_off 10236 10236 0 0.00 0 6505 11 0 0
t$perf-norm:sp_supplier_invoice 5551 5551 0 0.00 0 8713 18 0 0
t$perf-norm:sp_supplier_order 5672 5672 0 0.00 0 8937 18 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 203 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 525 13 0 0
t$perf-norm:srv_make_invnt_saldo 528 528 0 0.00 0 4347 32 0 0
t$perf-norm:srv_make_money_saldo 833 833 0 0.00 0 635 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 10692 10408 284 2.65 0 13531 19 0 284
v_all_customers 38268 38268 0 0.00 0 17 1 0 0
v_all_suppliers 30744 30744 0 0.00 0 19 1 0 0
v_all_wares 41684 41684 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 866 866 0 0.00 0 1406 6 0 0
v_cancel_client_order 3634 3634 0 0.00 0 6220 6 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 1456 5 0 0
v_cancel_customer_reserve 1330 1330 0 0.00 0 898 4 0 0
v_cancel_payment_to_supplier 902 902 0 0.00 0 5418 10 0 0
v_cancel_supplier_invoice 858 832 26 3.03 0 7192 24 0 26
v_cancel_supplier_order 856 856 0 0.00 0 3860 10 0 0
v_cancel_write_off 2150 2150 0 0.00 0 430 11 0 0
v_max_id_clo_ord 5672 5672 0 0.00 0 745 1 0 0
v_max_id_clo_res 951 951 0 0.00 0 9622 30 0 0
v_max_id_ord_sup 5548 5548 0 0.00 0 2921 3 0 0
v_max_non_paid_invoice 1769 1769 0 0.00 0 427 3 0 0
v_max_non_paid_realizn 3840 3840 0 0.00 0 4785 6 0 0
v_min_id_clo_ord 5672 5672 0 0.00 0 11678 16 0 0
v_min_id_clo_res 951 951 0 0.00 0 5398 15 0 0
v_min_id_ord_sup 5548 5548 0 0.00 0 3955 4 0 0
v_min_non_paid_invoice 5921 5921 0 0.00 0 10736 25 0 0
v_min_non_paid_realizn 7235 7235 0 0.00 0 10851 24 0 0
v_our_firm 10206 10206 0 0.00 0 11 1 0 0
v_reserve_write_off 20472 20472 0 0.00 0 5728 5 0 0
x_lock_depdocs_on_canc_invoice 12372 12372 0 0.00 0 14445 111 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 10894 333 0 0
x_make_qstorno_1000_1200 5672 5672 0 0.00 2 111599 1301 0 0
x_make_qstorno_1200_2000 5551 5551 0 0.00 4 89507 3387 0 0
x_make_qstorno_2100_3300 475835 475835 0 0.00 0 38086 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-04-2022 16:39:22 to 02-04-2022 16:39:26.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:20 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:21 16930 8192000000 9909336160 10221019136 994584208 10.826 100 87 174 1 1022974880 6021056 22369616
14:10:47 15701 8192000000 9952798992 10276016128 1034095216 11.208 100 93 186 0 1067051472 9131888 23824368
14:12:06 9508 8192000000 9964030512 10298413056 1054310240 11.402 100 81 162 0 1084551792 9592608 20648944
14:13:19 15562 8192000000 10027722288 10372173824 1114513392 11.975 100 80 161 1 1144609328 10983344 19112592
14:14:37 8107 8192000000 10078054064 10433187840 1163261600 12.434 100 86 172 0 1196861232 13826400 19773232
14:15:48 5589 8192000000 10124777904 10492538880 1213904848 12.905 100 75 150 2 1243908864 13576912 16427104
14:16:56 2452 8192000000 10172191040 10546585600 1253383136 13.269 100 90 180 0 1288326944 14935040 20008768
14:18:00 1462 8192000000 10207791008 10589769728 1287253952 13.579 100 91 184 1 1321544736 13531072 20759712
14:19:03 19071 8192000000 10209008304 10599981056 1299019136 13.686 100 56 112 0 1321794064 9615184 13159744
14:20:22 3929 8192000000 10194974464 10604064768 1306731120 13.756 100 37 73 4 1320876576 6340320 7805136
14:21:29 63061 8192000000 10210178768 10629169152 1319466320 13.872 100 27 54 0 1331067712 5739504 5861888
14:23:34 7498 8192000000 10217397040 10638979072 1328027088 13.949 100 19 38 0 1338594464 6397296 4170080
14:24:44 6168 8192000000 10218363056 10640998400 1329003664 13.958 100 26 52 1 1341106864 6278640 5824560
14:25:51 51991 8192000000 10244490128 10655731712 1337204368 14.032 100 43 86 0 1353603056 5166432 11232256
14:27:43 6720 8192000000 10205018464 10620121088 1285029264 13.559 97 20 40 1 1295006544 5345184 4632096
14:28:52 6123 8192000000 10185522384 10591039488 1276246080 13.479 99 25 50 1 1288587360 6397376 5943904
14:29:59 61448 8192000000 10152878896 10558603264 1217110016 12.935 96 32 63 1 1230363184 5205568 8047600
14:32:01 4266 8192000000 10148385408 10548711424 1256376336 13.297 100 29 58 0 1269374016 5834288 7163392
14:33:06 7989 8192000000 10161850272 10562134016 1258965536 13.321 99 15 30 0 1267536144 5004416 3566192
14:34:16 7125 8192000000 10186165776 10593878016 1290761264 13.611 100 23 46 0 1301636880 5551232 5324384
14:35:25 30587 8192000000 10184445120 10599927808 1297928880 13.676 100 21 42 0 1308182544 5393488 4860176
14:36:56 7077 8192000000 10202824608 10614218752 1304753776 13.738 100 28 55 0 1317732064 5768784 7209504
14:38:04 13674 8192000000 10211893088 10626838528 1319296880 13.870 100 20 40 1 1329156064 5563600 4295584
14:39:27 18266 8192000000 10256408080 10662895616 1340430784 14.061 100 56 107 4 1362873360 10230592 12211984
14:40:50 101499 8192000000 10256143120 10691227648 1359098384 14.229 100 39 78 0 1382921136 14599680 9223072
14:43:35 11855 8192000000 10284686016 10703773696 1367458848 14.304 100 40 78 2 1395368240 18900016 9009376
14:44:51 5237 8192000000 10306892752 10723356672 1382080688 14.435 100 67 134 1 1416896128 18398432 16417008
14:46:01 16572 8192000000 10299762944 10722574336 1381327424 14.428 100 56 112 1 1416655760 21688704 13639632
14:47:26 6950 8192000000 10330432400 10748207104 1406188624 14.650 100 54 105 1 1437435120 18674800 12571696
14:48:37 6875 8192000000 10331304208 10751066112 1405152336 14.641 100 65 130 3 1443525136 22714368 15658432
14:49:47 6611 8192000000 10317406960 10733776896 1384525088 14.457 99 59 118 1 1428240144 29579120 14135936
14:50:58 2974 8192000000 10344147984 10758176768 1400310544 14.598 100 75 151 2 1448166336 30034592 17821200
14:52:07 3290 8192000000 10326020464 10734313472 1356354256 14.205 98 71 141 2 1411545936 37304544 17887136
14:53:14 2926 8192000000 10321391648 10733498368 1364184704 14.275 100 71 141 1 1425756816 43910720 17661392
14:54:19 8996 8192000000 10312489648 10738245632 1381779856 14.432 100 43 84 1 1423665504 30860224 11025424
14:55:35 8031 8192000000 10310726288 10725232640 1368549808 14.314 100 63 126 0 1419533344 35308384 15675152
14:56:46 11762 8192000000 10329793328 10760708096 1384560896 14.457 99 40 80 1 1432522944 37713008 10249040
14:58:03 12620 8192000000 10338968960 10761203712 1393062784 14.533 100 47 92 2 1446299920 40791056 12446080
14:59:30 2539 8192000000 10355629408 10766159872 1388096880 14.489 100 77 153 1 1458026176 51074944 18854352
15:00:37 9834 8192000000 10326212144 10748207104 1360074368 14.238 98 39 76 2 1417420896 47033920 10312608
15:01:49 19751 8192000000 10328248224 10754736128 1381554784 14.430 100 33 64 2 1432063168 41789888 8718496
15:03:23 14404 8192000000 10334600704 10766401536 1386702976 14.476 100 34 68 1 1442759376 46437424 9618976
15:04:39 38654 8192000000 10328768864 10771214336 1386787008 14.477 100 28 56 1 1433816576 39318272 7711296
15:06:38 15725 8192000000 10303303808 10748702720 1372524880 14.350 100 29 58 0 1408753648 28649888 7578880
15:07:55 49292 8192000000 10286308240 10739445760 1364371744 14.277 99 6 12 0 1386773712 21377120 1024848
15:09:51 22901 8192000000 10305026736 10747437056 1380682176 14.423 99 43 84 1 1400738928 10390672 9666080
15:11:17 99020 8192000000 10297573616 10758893568 1373686080 14.360 99 9 18 1 1403088112 27141952 2260080
15:13:57 71848 8192000000 10304147344 10742636544 1352485168 14.170 97 16 32 0 1367567488 11609904 3472416
15:16:13 43471 8192000000 10312146432 10759901184 1394440080 14.545 100 26 51 0 1411366496 10947120 5979296
15:18:16 62055 8192000000 10301943616 10757050368 1379127104 14.409 99 9 17 0 1391743776 11043408 1573264
15:20:18 110901 8192000000 10313621536 10773774336 1399508064 14.591 100 15 30 0 1421326672 18352672 3465936
15:23:09 61702 8192000000 10312088896 10760732672 1392516992 14.528 99 33 66 0 1407494064 6988864 7988208
15:25:14 43183 8192000000 10321287152 10766536704 1378306496 14.401 98 46 92 0 1402598576 13756688 10535392
15:27:00 18122 8192000000 10325587904 10783404032 1408945776 14.675 100 37 72 2 1431566352 14154160 8466416
15:28:20 27248 8192000000 10303015136 10755051520 1390644800 14.512 99 24 48 0 1408651808 11832336 6174672
15:29:56 31624 8192000000 10314840784 10760650752 1382402912 14.438 99 55 110 2 1411359424 15623296 13333216
15:31:32 55715 8192000000 10299745536 10764390400 1369078752 14.319 98 14 28 2 1395851552 23033808 3738992
15:33:44 53808 8192000000 10294375168 10754666496 1388259504 14.490 100 14 28 0 1408464560 16576384 3628672
15:35:38 65212 8192000000 10273643056 10734297088 1345283744 14.105 97 14 28 0 1359981136 11863568 2833824
15:37:52 50488 8192000000 10301939552 10766995456 1393946176 14.541 100 13 26 0 1418142496 21537472 2658848
15:39:44 58388 8192000000 10291721504 10759303168 1342266896 14.078 97 7 14 0 1367842768 23594864 1981008
15:41:52 62055 8192000000 10273712064 10725679104 1307630464 13.765 95 18 36 1 1325551376 13169504 4751408
15:43:56 45700 8192000000 10279806512 10735169536 1343988448 14.093 97 10 20 0 1355983664 9658736 2336480
15:45:56 72922 8192000000 10285795408 10732322816 1312757024 13.811 95 24 48 0 1335103904 16909760 5437120
15:48:45 40001 8192000000 10278623168 10715156480 1324350768 13.916 97 24 48 0 1340035632 10585168 5099696
15:50:28 22429 8192000000 10285284368 10719035392 1351638432 14.162 99 32 64 0 1368496560 9926736 6931392
15:51:52 69049 8192000000 10273679728 10721099776 1295608624 13.655 95 19 38 0 1314016400 13776272 4631504
15:54:04 98232 8192000000 10261614960 10713763840 1348733056 14.136 99 26 52 0 1365993024 11220448 6039520
15:56:48 26723 8192000000 10273879872 10730364928 1365270496 14.285 100 24 48 1 1384747056 13961968 5514592
15:58:15 62681 8192000000 10281285312 10739097600 1364242112 14.275 99 12 24 0 1373152368 6422480 2487776
16:00:18 54547 8192000000 10276430032 10735697920 1360313008 14.240 98 10 19 0 1370857504 8452032 2092464
16:02:34 70868 8192000000 10285644016 10747854848 1368419104 14.313 99 24 48 0 1393646560 18728160 6499296
16:04:52 72596 8192000000 10288605920 10752409600 1384355328 14.455 100 25 50 1 1403473504 13646976 5471200
16:07:07 60014 8192000000 10293255088 10774491136 1389434176 14.501 100 6 12 1 1405937344 15492448 1010720
16:09:12 64052 8192000000 10311679792 10770137088 1367051488 14.301 97 15 30 2 1377907712 7784512 3071712
16:11:17 50356 8192000000 10308059360 10777694208 1402006272 14.613 100 13 26 1 1417824528 12834960 2983296
16:13:08 36473 8192000000 10320074272 10786324480 1399480384 14.590 99 33 66 1 1425620544 18816448 7323712
16:14:50 91760 8192000000 10310152112 10776461312 1370690960 14.333 97 19 38 0 1389465840 14159744 4615136
16:18:09 89748 8192000000 10296068432 10753208320 1340606800 14.063 96 17 32 0 1353843072 10033792 3202480
16:20:40 65538 8192000000 10287478336 10757312512 1380826640 14.424 99 24 48 2 1400302224 13538304 5937280
16:22:46 101833 8192000000 10257578112 10705719296 1344976736 14.102 97 26 49 1 1358985984 8348256 5660992
16:25:31 36144 8192000000 10239890144 10705334272 1331812864 13.984 98 6 12 0 1340390704 7562496 1015344
16:27:10 38329 8192000000 10263296624 10715455488 1336419920 14.025 98 10 20 0 1347872656 9255296 2197440
16:28:56 79366 8192000000 10263091312 10726703104 1326754880 13.938 97 11 22 0 1345286496 15623280 2908336
16:31:50 48267 8192000000 10272086832 10728796160 1367648288 14.306 100 9 18 0 1378115680 8865232 1602160
16:33:39 35081 8192000000 10282320784 10733735936 1347169872 14.122 98 18 36 0 1361753424 10183392 4400160
16:35:14 27361 8192000000 10297111872 10741293056 1376808176 14.388 100 38 76 2 1398650800 12632720 9209904
16:36:44 41941 8192000000 10258884752 10707763200 1323094352 13.905 97 11 22 0 1336157168 10623440 2439376

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-04-2022 16:39:28 to 02-04-2022 16:39:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 142 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-04-2022 16:39:44 to 02-04-2022 16:39:46.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          243817
MON$OLDEST_ACTIVE               245366
MON$OLDEST_SNAPSHOT             245366
MON$NEXT_TRANSACTION            245366
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                       2056352
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.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
:862: 
:863: mscs-test-161.budzdorov.ru    Sat Apr  2 16:38:18 2022
:864:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:865: 
:866: 
:867: mscs-test-161.budzdorov.ru    Sat Apr  2 16:38:20 2022
:868:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:869: 
:870: 
:871: mscs-test-161.budzdorov.ru    Sat Apr  2 16:38:21 2022
:872:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:873: 
:874: 
:875: mscs-test-161.budzdorov.ru    Sat Apr  2 16:38:25 2022
:876:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:877: 
:878: 
:879: mscs-test-161.budzdorov.ru    Sat Apr  2 16:38:26 2022
:880:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:881: 
:882: 
:883: mscs-test-161.budzdorov.ru    Sat Apr  2 16:38:26 2022
:884:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:885: 
:886: 
:887: mscs-test-161.budzdorov.ru    Sat Apr  2 16:38:26 2022
:888:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:889: 
--- end of diff output ---

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

02.04.22 16:39:47. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-04-02 16:39:49 No severe PSQL-related problems occured
02.04.22 16:39:50. End of report.