Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.07.2022 22: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 15980560 kB
MemAvailable 31073904 kB
Buffers 0 kB
Cached 15712596 kB
SwapCached 2520 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.11
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-07-14 22:38:22
DTS_END                         2022-07-14 22:38:22.1820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/60418
TRN_ID                          248441
ATT_ID                          719
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: 9693.50 15172 1163220
customer order: creation 186.40 316 22368
customer order: refuse 15.52 313 1862
order to supplier: creation 48.77 1145 5852
order to supplier: removal 3.63 95985 436
invoice (draft): creation 49.04 3304 5885
invoice (draft): removal 55.36 736 6643
invoice accept: apply 43.99 16223 5279
invoice accept: cancel 49.95 739 5994
customer reserve: creation 4034.20 162 484104
customer reserve: removal 4883.36 55 586003
realization accept: apply 90.15 172 10818
realization accept: cancel 94.56 108 11347
payment from customer: creation 63.64 104 7637
payment from customer: removal 6.13 75 736
payment to supplier: creation 52.20 126 6264
payment to supplier: removal 3.50 63 420
service: total inventory turnovers 5.39 1044 647
service: total monetary turnovers 6.71 35 805
service: aggregate perf. data 0.97 16905 116
service: refresh index statistics 0.03 165827 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5129
TEST_TIME 2 3991
TEST_TIME 3 5124
TEST_TIME 4 4905
TEST_TIME 5 5438
TEST_TIME 6 5647
TEST_TIME 7 5397
TEST_TIME 8 5337
TEST_TIME 9 5838
TEST_TIME 10 5720
TEST_TIME 11 5998
TEST_TIME 12 6277
TEST_TIME 13 6794
TEST_TIME 14 7129
TEST_TIME 15 7289
TEST_TIME 16 7233
TEST_TIME 17 7789
TEST_TIME 18 7861
TEST_TIME 19 8017
TEST_TIME 20 8384
TEST_TIME 21 8472
TEST_TIME 22 9079
TEST_TIME 23 8812
TEST_TIME 24 8755
TEST_TIME 25 8763
TEST_TIME 26 8963
TEST_TIME 27 8977
TEST_TIME 28 8932
TEST_TIME 29 9104
TEST_TIME 30 8943
TEST_TIME 31 9080
TEST_TIME 32 9688
TEST_TIME 33 10445
TEST_TIME 34 10287
TEST_TIME 35 10426
TEST_TIME 36 10294
TEST_TIME 37 10295
TEST_TIME 38 10179
TEST_TIME 39 10226
TEST_TIME 40 10324
TEST_TIME 41 10131
TEST_TIME 42 10036
TEST_TIME 43 9942
TEST_TIME 44 9868
TEST_TIME 45 9780
TEST_TIME 46 9773
TEST_TIME 47 9783
TEST_TIME 48 9841
TEST_TIME 49 9819
TEST_TIME 50 9734
TEST_TIME 51 9600
TEST_TIME 52 9513
TEST_TIME 53 9562
TEST_TIME 54 9482
TEST_TIME 55 9619
TEST_TIME 56 9585
TEST_TIME 57 9575
TEST_TIME 58 9610
TEST_TIME 59 9607
TEST_TIME 60 9724
TEST_TIME 61 9728
TEST_TIME 62 9721
TEST_TIME 63 9882
TEST_TIME 64 9832
TEST_TIME 65 9847
TEST_TIME 66 9811
TEST_TIME 67 9952
TEST_TIME 68 9940
TEST_TIME 69 9898
TEST_TIME 70 10001
TEST_TIME 71 10014
TEST_TIME 72 9969
TEST_TIME 73 9852
TEST_TIME 74 9830
TEST_TIME 75 9841
TEST_TIME 76 9792
TEST_TIME 77 9811
TEST_TIME 78 9762
TEST_TIME 79 9724
TEST_TIME 80 9664
TEST_TIME 81 9669
TEST_TIME 82 9614
TEST_TIME 83 9663
TEST_TIME 84 9622
TEST_TIME 85 9622
TEST_TIME 86 9707
TEST_TIME 87 9686
TEST_TIME 88 9657
TEST_TIME 89 9741
TEST_TIME 90 9765
TEST_TIME 91 9745
TEST_TIME 92 9750
TEST_TIME 93 9818
TEST_TIME 94 9790
TEST_TIME 95 9813
TEST_TIME 96 9825
TEST_TIME 97 9870
TEST_TIME 98 9877
TEST_TIME 99 9901
TEST_TIME 100 9807
TEST_TIME 101 9784
TEST_TIME 102 9740
TEST_TIME 103 9749
TEST_TIME 104 9770
TEST_TIME 105 9728
TEST_TIME 106 9692
TEST_TIME 107 9672
TEST_TIME 108 9606
TEST_TIME 109 9570
TEST_TIME 110 9558
TEST_TIME 111 9623
TEST_TIME 112 9640
TEST_TIME 113 9616
TEST_TIME 114 9644
TEST_TIME 115 9686
TEST_TIME 116 9671
TEST_TIME 117 9651
TEST_TIME 118 9671
TEST_TIME 119 9743
TEST_TIME 120 9693

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-07-2022 22:39:05 to 14-07-2022 22:39:09.

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 1612670 1612670 0 0.00 0 29620 6 0 0
doc_list_biud 1612648 1612648 0 0.00 0 29453 26 0 0
sp_add_invoice_to_stock 5413 5279 134 2.47 30 149406 16223 0 134
sp_cancel_adding_invoice 5994 5994 0 0.00 5 61844 739 0 0
sp_cancel_client_order 1862 1862 0 0.00 4 9399 313 0 0
sp_cancel_customer_reserve 586003 586003 0 0.00 0 29454 56 0 0
sp_cancel_pay_from_customer 736 736 0 0.00 2 7063 75 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 2 1479 63 0 0
sp_cancel_supplier_invoice 6654 6643 11 0.16 2 16856 736 0 11
sp_cancel_supplier_order 436 436 0 0.00 10 815292 95985 0 0
sp_cancel_write_off 11347 11347 0 0.00 1 28505 109 0 0
sp_client_order 22368 22368 0 0.00 3 19689 316 0 0
sp_customer_reserve 484104 484104 0 0.00 0 128612 162 0 0
sp_fill_shopping_cart 34092 34092 0 0.00 1 14197 37 0 0
sp_fill_shopping_cart_clo_res 484104 484104 0 0.00 0 22640 12 0 0
sp_get_clo_for_invoice 5278 5278 0 0.00 0 16241 276 0 0
sp_kill_cost_storno 18497 18497 0 0.00 0 9295 51 0 0
sp_kill_qstorno_ret_qs2qd 1190432 1190432 0 0.00 0 29452 30 0 0
sp_kill_qty_storno 628381 628381 0 0.00 0 29453 65 0 0
sp_lock_selected_doc 22244 22244 0 0.00 0 7267 5 0 0
sp_make_cost_storno 29997 29997 0 0.00 0 17561 57 0 0
sp_multiply_rows_for_pdistr 29997 29997 0 0.00 0 6076 26 0 0
sp_multiply_rows_for_qdistr 22368 22368 0 0.00 0 13053 139 0 0
sp_pay_from_customer 7637 7637 0 0.00 3 14525 105 0 0
sp_pay_to_supplier 6264 6264 0 0.00 4 17267 126 0 0
sp_payment_common 13892 13892 0 0.00 1 17178 30 0 0
sp_qd_handle_on_cancel_clo 1862 1862 0 0.00 0 9393 293 0 0
sp_qd_handle_on_invoice_upd_sts 11272 11272 0 0.00 0 28409 313 0 0
sp_qd_handle_on_reserve_upd_sts 10818 10818 0 0.00 0 20901 60 0 0
sp_reserve_write_off 10818 10818 0 0.00 6 20939 173 0 0
sp_supplier_invoice 5885 5885 0 0.00 7 100199 3304 0 0
sp_supplier_order 5852 5852 0 0.00 5 18273 1146 0 0
srv_aggregate_perf_data 116 116 0 0.00 2374 211490 16906 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1314 123705 43925 0 0
srv_find_qd_qs_mism 1112997 1112997 0 0.00 0 29601 5 0 0
srv_make_invnt_saldo 647 647 0 0.00 26 70788 1044 0 0
srv_make_money_saldo 805 805 0 0.00 0 5876 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 82228 345889 165827 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5279 5279 0 0.00 0 21508 159 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 0 3277 69 0 0
t$perf-norm:sp_cancel_client_order 1862 1862 0 0.00 0 7840 17 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 4200 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 736 736 0 0.00 0 6381 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 262 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 460 460 0 0.00 0 3619 16 0 0
t$perf-norm:sp_cancel_supplier_order 436 436 0 0.00 0 22920 1654 0 0
t$perf-norm:sp_cancel_write_off 1085 1085 0 0.00 0 3951 13 0 0
t$perf-norm:sp_client_order 22368 22368 0 0.00 0 13116 16 0 0
t$perf-norm:sp_customer_reserve 1104 1104 0 0.00 0 2421 6 0 0
t$perf-norm:sp_pay_from_customer 7637 7637 0 0.00 0 10536 11 0 0
t$perf-norm:sp_pay_to_supplier 6264 6264 0 0.00 0 5401 10 0 0
t$perf-norm:sp_reserve_write_off 10818 10818 0 0.00 0 8520 12 0 0
t$perf-norm:sp_supplier_invoice 5885 5885 0 0.00 0 9605 21 0 0
t$perf-norm:sp_supplier_order 5852 5852 0 0.00 0 7639 16 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 351 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 707 16 0 0
t$perf-norm:srv_make_invnt_saldo 647 647 0 0.00 0 1022 20 0 0
t$perf-norm:srv_make_money_saldo 805 805 0 0.00 0 2775 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10818 10550 268 2.47 0 7016 15 0 268
v_all_customers 40890 40890 0 0.00 0 111 0 0 0
v_all_suppliers 32632 32632 0 0.00 0 111 1 0 0
v_all_wares 44720 44720 0 0.00 0 112 1 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 1503 8 0 0
v_cancel_client_order 3724 3724 0 0.00 0 2403 5 0 0
v_cancel_customer_prepayment 1472 1472 0 0.00 0 1801 5 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 1687 7 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 1461 7 0 0
v_cancel_supplier_invoice 942 920 22 2.33 0 3601 19 0 22
v_cancel_supplier_order 872 872 0 0.00 0 1390 5 0 0
v_cancel_write_off 2170 2170 0 0.00 0 1973 12 0 0
v_max_id_clo_ord 5849 5849 0 0.00 0 1231 1 0 0
v_max_id_clo_res 1104 1104 0 0.00 0 3769 15 0 0
v_max_id_ord_sup 5883 5883 0 0.00 0 4403 4 0 0
v_max_non_paid_invoice 1678 1678 0 0.00 0 262 2 0 0
v_max_non_paid_realizn 3953 3953 0 0.00 0 3471 6 0 0
v_min_id_clo_ord 5849 5849 0 0.00 0 2295 5 0 0
v_min_id_clo_res 1104 1104 0 0.00 0 6379 13 0 0
v_min_id_ord_sup 5883 5883 0 0.00 0 8698 20 0 0
v_min_non_paid_invoice 6262 6262 0 0.00 0 17168 21 0 0
v_min_non_paid_realizn 7630 7630 0 0.00 0 7768 22 0 0
v_our_firm 11184 11184 0 0.00 0 111 1 0 0
v_reserve_write_off 21635 21635 0 0.00 0 3152 6 0 0
x_lock_depdocs_on_canc_invoice 12177 12177 0 0.00 0 32255 117 0 0
x_lock_depdocs_on_canc_sup_ord 436 436 0 0.00 0 5840 315 0 0
x_make_qstorno_1000_1200 5852 5852 0 0.00 1 18258 1075 0 0
x_make_qstorno_1200_2000 5885 5885 0 0.00 3 94013 3161 0 0
x_make_qstorno_2100_3300 484104 484104 0 0.00 0 128610 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-07-2022 22:39:10 to 14-07-2022 22:39:14.

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:25 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:26 29011 8192000000 9905851200 10216210432 976747904 10.653 100 83 166 0 1004028464 5466352 21814208
20:11:04 23226 8192000000 9943783792 10264641536 1023210848 11.103 100 89 178 0 1054382208 7734800 23436560
20:12:32 10048 8192000000 9979055312 10308882432 1062681600 11.482 100 89 178 0 1094769120 9630096 22457424
20:13:49 14626 8192000000 10037688464 10384805888 1121680000 12.043 100 75 150 0 1149114864 10046896 17387968
20:15:09 5383 8192000000 10074536608 10431348736 1160354816 12.407 100 77 154 0 1190588192 12453888 17779488
20:16:17 4526 8192000000 10119260064 10487767040 1211301552 12.881 100 77 154 0 1241082960 12542768 17238640
20:17:24 3582 8192000000 10154110784 10531151872 1243034784 13.174 100 79 154 2 1273546432 13717280 16794368
20:18:30 1950 8192000000 10179063424 10562187264 1269560752 13.418 100 74 144 2 1298143456 13088352 15494352
20:19:34 968 8192000000 10207309104 10589954048 1294026352 13.641 100 85 171 0 1324831312 12387360 18417600
20:20:38 19345 8192000000 10200232368 10596769792 1298310992 13.680 100 52 103 0 1318582832 7570912 12700928
20:21:58 2307 8192000000 10206214352 10610106368 1309656240 13.783 100 42 85 1 1327041280 7980368 9404672
20:23:03 8394 8192000000 10202966544 10614829056 1316210320 13.842 100 23 46 0 1326764752 5455376 5099056
20:24:13 74469 8192000000 10210478560 10633670656 1329722416 13.965 100 26 50 0 1340760592 5772752 5265424
20:26:31 5562 8192000000 10223327168 10644271104 1335211104 14.014 100 28 54 0 1346514160 4935984 6367072
20:27:39 5810 8192000000 10216660768 10633900032 1323831200 13.911 99 21 42 0 1335234240 6627312 4775728
20:28:46 17828 8192000000 10185061568 10597597184 1250081152 13.239 94 27 55 1 1266195840 9745728 6368960
20:30:04 6181 8192000000 10189789296 10609881088 1306499808 13.754 100 17 34 0 1315924320 5726608 3697904
20:31:10 4750 8192000000 10136237376 10532597760 1216843920 12.932 97 30 58 1 1229273936 5801776 6628240
20:32:15 12401 8192000000 10132005504 10533158912 1190222736 12.685 95 25 50 0 1201599232 5557040 5819456
20:33:29 69493 8192000000 10140624208 10544926720 1239039616 13.137 98 30 60 0 1251786864 5314336 7432912
20:35:41 6895 8192000000 10185889200 10586292224 1283882080 13.548 100 24 48 0 1296001936 6820960 5298896
20:36:48 6068 8192000000 10185251824 10604740608 1300623872 13.701 100 26 52 0 1312011552 5580032 5807648
20:37:55 10783 8192000000 10204034256 10620911616 1312669504 13.810 100 20 39 0 1322259360 4816832 4773024
20:39:07 97318 8192000000 10249852528 10672414720 1343296304 14.087 100 36 71 1 1360020032 7839696 8884032
20:41:50 15048 8192000000 10276649680 10696908800 1368669616 14.315 100 47 94 0 1391541328 11984512 10887200
20:43:07 2973 8192000000 10299428784 10716090368 1376352736 14.384 100 62 124 0 1404721456 12605696 15763024
20:44:14 5272 8192000000 10289820912 10712596480 1381489104 14.430 100 54 108 0 1406930880 12552912 12888864
20:45:22 4328 8192000000 10305620768 10731417600 1394570512 14.547 100 44 86 0 1416739120 12372704 9795904
20:46:30 12717 8192000000 10323838768 10747998208 1401858816 14.612 100 43 85 1 1426906800 15096160 9951824
20:47:47 8671 8192000000 10318684864 10754904064 1408542912 14.671 100 43 87 0 1438413392 18542176 11328304
20:49:04 14425 8192000000 10326056640 10756644864 1404921952 14.639 100 60 120 1 1444652672 24283008 15447712
20:50:23 4559 8192000000 10307254144 10724597760 1371292368 14.339 98 51 102 0 1412380720 28209200 12879152
20:51:32 3343 8192000000 10348012880 10768257024 1406528288 14.653 100 64 128 0 1454387616 31184128 16675200
20:52:39 25109 8192000000 10303641168 10740736000 1362118672 14.256 97 39 78 0 1396849488 25010272 9720544
20:54:14 1313 8192000000 10312937792 10729586688 1369790160 14.325 99 60 119 0 1408817632 24471072 14556400
20:55:21 2184 8192000000 10287109632 10692468736 1350147824 14.149 99 74 147 3 1395294736 27944304 17202608
20:56:29 2224 8192000000 10291279232 10699255808 1345206096 14.104 100 63 123 0 1395006096 35021456 14778544
20:57:35 5180 8192000000 10288993552 10708013056 1345554640 14.107 99 58 116 0 1393761168 33702832 14503696
20:58:45 5143 8192000000 10317249904 10722725888 1358627648 14.225 99 61 122 2 1417967040 44017456 15321936
20:59:57 2786 8192000000 10341663376 10757431296 1387894672 14.487 100 65 126 1 1449078256 44985792 16197792
21:01:06 11042 8192000000 10314813328 10731708416 1363118576 14.265 99 52 103 1 1410073520 33579904 13375040
21:02:21 1846 8192000000 10324318496 10745896960 1380066768 14.417 100 53 108 4 1429226976 34545104 14615104
21:03:38 7803 8192000000 10328836224 10748063744 1380620464 14.422 100 47 96 0 1434581968 41653808 12307696
21:04:51 10540 8192000000 10337450640 10767429632 1392410464 14.527 100 36 71 1 1449003488 47097552 9495472
21:06:07 10109 8192000000 10333052624 10768596992 1390326912 14.509 100 46 91 2 1450549376 48070112 12152352
21:07:22 19857 8192000000 10337490752 10772078592 1387261520 14.481 100 27 52 0 1443495616 48806768 7427328
21:09:11 15914 8192000000 10345154880 10777980928 1394298208 14.544 100 23 46 0 1451461744 50375984 6787552
21:10:28 16077 8192000000 10330650928 10775056384 1372686080 14.351 99 35 68 2 1429664368 47487184 9491104
21:11:46 54052 8192000000 10297177456 10740473856 1382279056 14.437 100 11 22 0 1392429760 8192176 1958528
21:13:41 26849 8192000000 10310344480 10745864192 1389423264 14.501 100 21 42 0 1405567520 11387952 4756304
21:15:10 88272 8192000000 10317513904 10765328384 1407083088 14.658 100 30 60 1 1432033440 17918832 7031520
21:17:46 89191 8192000000 10313307936 10755596288 1389524256 14.502 99 18 34 0 1406609648 12632272 4453120
21:20:16 69449 8192000000 10310980960 10758221824 1393604080 14.538 99 38 74 0 1413118976 10482624 9032272
21:22:28 113593 8192000000 10310407200 10763374592 1380458336 14.421 98 26 50 0 1395374848 9137440 5779072
21:25:44 26782 8192000000 10309801392 10762174464 1378043568 14.399 98 20 40 0 1396711136 13777392 4890176
21:27:15 37686 8192000000 10310213024 10769272832 1400017280 14.595 100 9 18 0 1415712176 13654592 2040304
21:29:02 102464 8192000000 10313642592 10768076800 1373325456 14.357 98 16 32 0 1395596800 18376624 3894720
21:31:49 61303 8192000000 10306628880 10768822272 1400849264 14.603 100 21 42 0 1421156944 15355472 4952208
21:34:02 49267 8192000000 10291264336 10750128128 1368047184 14.310 98 9 18 0 1382065344 11826640 2191520
21:35:59 37850 8192000000 10293983280 10747977728 1379896128 14.416 99 36 72 0 1400046000 11673936 8475936
21:37:40 32260 8192000000 10305186576 10761629696 1368706768 14.315 98 31 62 0 1392946560 17522976 6716816
21:39:21 63893 8192000000 10291573104 10743283712 1325509280 13.927 95 12 24 0 1337414208 9490192 2414736
21:41:25 61593 8192000000 10248260064 10699210752 1291666640 13.619 94 11 22 0 1304385120 10257104 2461376
21:43:33 69315 8192000000 10261343488 10705494016 1275036624 13.468 94 23 46 0 1290547152 10609328 4901200
21:45:47 93192 8192000000 10249188304 10698366976 1237039744 13.119 91 13 26 0 1251326896 11848960 2438192
21:48:38 61748 8192000000 10256519696 10700558336 1293086496 13.632 96 14 26 0 1310573984 14611360 2876128
21:50:48 76909 8192000000 10250914208 10688638976 1317992560 13.859 98 21 42 0 1332340384 9624240 4723584
21:53:09 64601 8192000000 10266549696 10707804160 1344044112 14.094 99 27 54 0 1358907456 8695264 6168080
21:55:16 46851 8192000000 10247097344 10700312576 1333864864 14.002 99 12 24 0 1343777024 7305392 2606768
21:57:09 63186 8192000000 10270085984 10727936000 1365522656 14.287 100 15 30 0 1384954256 15930784 3500816
21:59:32 59025 8192000000 10262578624 10720555008 1341758576 14.073 98 7 14 0 1359760112 16048032 1953504
22:01:55 79792 8192000000 10288680144 10740367360 1359477216 14.233 99 9 18 0 1376171856 14789088 1905552
22:04:17 64267 8192000000 10287108464 10741760000 1366946768 14.300 99 19 38 0 1384439456 12883440 4609248
22:06:25 50375 8192000000 10309117104 10758860800 1393651296 14.538 100 19 36 0 1411892704 13770608 4470800
22:08:20 58113 8192000000 10311994064 10761584640 1401585312 14.609 100 24 48 2 1415256864 7816096 5855456
22:10:18 75703 8192000000 10309775584 10781384704 1380550736 14.421 98 24 48 0 1400691776 14781504 5359536
22:12:44 42736 8192000000 10305929664 10759098368 1373130592 14.355 98 22 44 0 1392391856 14659584 4601680
22:14:27 60776 8192000000 10306130960 10776969216 1376507312 14.385 98 11 22 0 1393983024 15174224 2301488
22:16:28 37136 8192000000 10290223920 10738413568 1340034192 14.058 96 16 32 0 1351713936 8172800 3506944
22:18:06 123704 8192000000 10290485936 10747277312 1319588144 13.873 95 18 36 0 1332676912 9340624 3748144
22:21:19 27636 8192000000 10278790688 10728402944 1340666608 14.063 97 22 44 1 1358103488 12191344 5245536
22:22:48 70290 8192000000 10275281472 10741547008 1376547376 14.386 100 4 8 0 1385927536 8769248 610912
22:24:59 63731 8192000000 10281830512 10742554624 1364608976 14.279 98 12 24 0 1376105008 8728320 2767712
22:27:53 50147 8192000000 10291838880 10754715648 1364958704 14.282 98 7 12 0 1377855136 11481168 1415264
22:30:07 55584 8192000000 10261292704 10711080960 1323874944 13.912 96 16 32 0 1335318928 8091136 3352848
22:32:08 50301 8192000000 10261892976 10710343680 1316368240 13.844 97 21 42 0 1329955168 9102304 4484624
22:34:11 111540 8192000000 10289130336 10733142016 1248891328 13.228 91 19 39 0 1267496768 14194080 4411360
22:37:06 78114 8192000000 9992874496 10404261888 1060070704 11.457 78 6 12 0 1077908928 16813120 1025104

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 14-07-2022 22:39:16 to 14-07-2022 22:39:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-07-2022 22:39:31 to 14-07-2022 22:39:33.

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          248440
MON$OLDEST_ACTIVE               248660
MON$OLDEST_SNAPSHOT             248660
MON$NEXT_TRANSACTION            248660
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                       2038880
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 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 ---
:1096: 
:1097: mscs-test-161.budzdorov.ru    Thu Jul 14 22:38:28 2022
:1098:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1099: 
--- end of diff output ---

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

14.07.22 22:39:34. 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-07-14 22:39:37 No severe PSQL-related problems occured
14.07.22 22:39:38. End of report.