Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.09.2021 19: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 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16093028 kB
MemAvailable 30862088 kB
Buffers 0 kB
Cached 15570624 kB
SwapCached 340 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.8
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-09-06 19:37:52
DTS_END                         2021-09-06 19:37:52.0630
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42128
TRN_ID                          241176
ATT_ID                          632
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: 9417.12 22726 1130054
customer order: creation 175.34 351 21041
customer order: refuse 15.31 335 1837
order to supplier: creation 45.73 1231 5488
order to supplier: removal 3.49 104441 419
invoice (draft): creation 46.75 3237 5610
invoice (draft): removal 52.83 791 6339
invoice accept: apply 41.65 19402 4998
invoice accept: cancel 48.41 837 5809
customer reserve: creation 3915.33 191 469839
customer reserve: removal 4773.58 61 572830
realization accept: apply 83.84 174 10061
realization accept: cancel 82.97 113 9956
payment from customer: creation 60.99 108 7319
payment from customer: removal 5.98 110 718
payment to supplier: creation 49.48 138 5937
payment to supplier: removal 3.35 106 402
service: total inventory turnovers 4.68 931 562
service: total monetary turnovers 6.51 35 781
service: aggregate perf. data 0.87 18595 104
service: refresh index statistics 0.03 303339 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-09-2021 19:38:45 to 06-09-2021 19:38:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1989
TEST_TIME 2 2406
TEST_TIME 3 2970
TEST_TIME 4 4641
TEST_TIME 5 5276
TEST_TIME 6 4943
TEST_TIME 7 4933
TEST_TIME 8 5141
TEST_TIME 9 5445
TEST_TIME 10 5798
TEST_TIME 11 5745
TEST_TIME 12 5792
TEST_TIME 13 6341
TEST_TIME 14 6308
TEST_TIME 15 6597
TEST_TIME 16 6592
TEST_TIME 17 7111
TEST_TIME 18 7402
TEST_TIME 19 7462
TEST_TIME 20 7534
TEST_TIME 21 8039
TEST_TIME 22 8329
TEST_TIME 23 8733
TEST_TIME 24 9119
TEST_TIME 25 9097
TEST_TIME 26 9341
TEST_TIME 27 10474
TEST_TIME 28 10828
TEST_TIME 29 10812
TEST_TIME 30 10848
TEST_TIME 31 10583
TEST_TIME 32 10493
TEST_TIME 33 10205
TEST_TIME 34 10467
TEST_TIME 35 10266
TEST_TIME 36 10173
TEST_TIME 37 10218
TEST_TIME 38 10079
TEST_TIME 39 10077
TEST_TIME 40 10023
TEST_TIME 41 10011
TEST_TIME 42 9959
TEST_TIME 43 9828
TEST_TIME 44 9974
TEST_TIME 45 9795
TEST_TIME 46 9825
TEST_TIME 47 9857
TEST_TIME 48 9791
TEST_TIME 49 9842
TEST_TIME 50 9945
TEST_TIME 51 9883
TEST_TIME 52 9844
TEST_TIME 53 9996
TEST_TIME 54 9968
TEST_TIME 55 9944
TEST_TIME 56 9936
TEST_TIME 57 9857
TEST_TIME 58 9923
TEST_TIME 59 10023
TEST_TIME 60 10065
TEST_TIME 61 9926
TEST_TIME 62 9853
TEST_TIME 63 9739
TEST_TIME 64 9696
TEST_TIME 65 9749
TEST_TIME 66 9742
TEST_TIME 67 9701
TEST_TIME 68 9593
TEST_TIME 69 9691
TEST_TIME 70 9637
TEST_TIME 71 9589
TEST_TIME 72 9698
TEST_TIME 73 9708
TEST_TIME 74 9747
TEST_TIME 75 9818
TEST_TIME 76 9810
TEST_TIME 77 9776
TEST_TIME 78 9804
TEST_TIME 79 9832
TEST_TIME 80 9762
TEST_TIME 81 9752
TEST_TIME 82 9725
TEST_TIME 83 9641
TEST_TIME 84 9594
TEST_TIME 85 9556
TEST_TIME 86 9520
TEST_TIME 87 9528
TEST_TIME 88 9478
TEST_TIME 89 9461
TEST_TIME 90 9484
TEST_TIME 91 9483
TEST_TIME 92 9522
TEST_TIME 93 9587
TEST_TIME 94 9598
TEST_TIME 95 9593
TEST_TIME 96 9625
TEST_TIME 97 9705
TEST_TIME 98 9724
TEST_TIME 99 9701
TEST_TIME 100 9720
TEST_TIME 101 9700
TEST_TIME 102 9723
TEST_TIME 103 9727
TEST_TIME 104 9670
TEST_TIME 105 9664
TEST_TIME 106 9601
TEST_TIME 107 9569
TEST_TIME 108 9571
TEST_TIME 109 9544
TEST_TIME 110 9503
TEST_TIME 111 9486
TEST_TIME 112 9453
TEST_TIME 113 9408
TEST_TIME 114 9418
TEST_TIME 115 9408
TEST_TIME 116 9398
TEST_TIME 117 9392
TEST_TIME 118 9396
TEST_TIME 119 9454
TEST_TIME 120 9421

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-09-2021 19:38:48 to 06-09-2021 19:38:52.

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 1564831 1564831 0 0.00 0 21139 7 0 0
doc_list_biud 1564831 1564831 0 0.00 0 46573 28 0 0
sp_add_invoice_to_stock 5092 4998 94 1.84 43 184395 19402 0 94
sp_cancel_adding_invoice 5809 5809 0 0.00 6 46606 837 0 0
sp_cancel_client_order 1837 1837 0 0.00 4 9953 336 0 0
sp_cancel_customer_reserve 572830 572830 0 0.00 1 35321 61 0 0
sp_cancel_pay_from_customer 718 718 0 0.00 3 10001 111 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 7122 107 0 0
sp_cancel_supplier_invoice 6348 6339 9 0.14 2 27822 791 0 9
sp_cancel_supplier_order 419 419 0 0.00 6 1142682 104442 0 0
sp_cancel_write_off 9956 9956 0 0.00 1 10086 113 0 0
sp_client_order 21041 21041 0 0.00 4 22347 352 0 0
sp_customer_reserve 469839 469839 0 0.00 0 69547 192 0 0
sp_fill_shopping_cart 32122 32122 0 0.00 1 12700 34 0 0
sp_fill_shopping_cart_clo_res 469836 469836 0 0.00 0 10866 12 0 0
sp_get_clo_for_invoice 4992 4992 0 0.00 0 25660 268 0 0
sp_kill_cost_storno 16885 16885 0 0.00 0 7556 55 0 0
sp_kill_qstorno_ret_qs2qd 1162374 1162374 0 0.00 0 35307 33 0 0
sp_kill_qty_storno 612243 612243 0 0.00 0 46572 71 0 0
sp_lock_selected_doc 21020 21020 0 0.00 0 9203 6 0 0
sp_make_cost_storno 28309 28309 0 0.00 0 21118 47 0 0
sp_multiply_rows_for_pdistr 28309 28309 0 0.00 0 18337 28 0 0
sp_multiply_rows_for_qdistr 21041 21041 0 0.00 0 18536 150 0 0
sp_pay_from_customer 7319 7319 0 0.00 4 18347 108 0 0
sp_pay_to_supplier 5937 5937 0 0.00 5 18152 138 0 0
sp_payment_common 13256 13256 0 0.00 1 11055 21 0 0
sp_qd_handle_on_cancel_clo 1837 1837 0 0.00 0 9925 311 0 0
sp_qd_handle_on_invoice_upd_sts 10801 10801 0 0.00 0 46570 319 0 0
sp_qd_handle_on_reserve_upd_sts 10061 10061 0 0.00 0 14160 60 0 0
sp_reserve_write_off 10061 10061 0 0.00 6 18793 175 0 0
sp_supplier_invoice 5610 5610 0 0.00 6 96933 3238 0 0
sp_supplier_order 5488 5488 0 0.00 5 37883 1231 0 0
srv_aggregate_perf_data 105 104 1 0.95 2806 176355 18595 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1786 144877 45364 0 0
srv_find_qd_qs_mism 1081359 1081359 0 0.00 0 16536 6 0 0
srv_make_invnt_saldo 562 562 0 0.00 57 17462 932 0 0
srv_make_money_saldo 781 781 0 0.00 1 10104 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 197717 433904 303339 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4998 4998 0 0.00 0 7448 147 0 0
t$perf-norm:sp_cancel_adding_invoice 444 444 0 0.00 0 6587 86 0 0
t$perf-norm:sp_cancel_client_order 1837 1837 0 0.00 0 3424 13 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 5098 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 718 718 0 0.00 0 1262 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 595 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 430 430 0 0.00 0 1012 17 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 24314 1516 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 3232 11 0 0
t$perf-norm:sp_client_order 21041 21041 0 0.00 0 6334 16 0 0
t$perf-norm:sp_customer_reserve 1007 1007 0 0.00 0 2229 7 0 0
t$perf-norm:sp_pay_from_customer 7319 7319 0 0.00 0 10253 12 0 0
t$perf-norm:sp_pay_to_supplier 5937 5937 0 0.00 0 5617 9 0 0
t$perf-norm:sp_reserve_write_off 10061 10061 0 0.00 0 6268 10 0 0
t$perf-norm:sp_supplier_invoice 5610 5610 0 0.00 0 5000 16 0 0
t$perf-norm:sp_supplier_order 5488 5488 0 0.00 0 5809 16 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 57 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 64 2 0 0
t$perf-norm:srv_make_invnt_saldo 562 562 0 0.00 0 714 12 0 0
t$perf-norm:srv_make_money_saldo 781 781 0 0.00 0 1984 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 16 7 0 0
v_add_invoice_to_stock 10172 9984 188 1.84 0 12420 20 0 188
v_all_customers 38384 38384 0 0.00 0 31 1 0 0
v_all_suppliers 30890 30890 0 0.00 0 14 1 0 0
v_all_wares 42082 42082 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 888 888 0 0.00 0 171 5 0 0
v_cancel_client_order 3674 3674 0 0.00 0 590 2 0 0
v_cancel_customer_prepayment 1436 1436 0 0.00 0 7477 19 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 1587 5 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 4941 16 0 0
v_cancel_supplier_invoice 878 860 18 2.05 0 2232 16 0 18
v_cancel_supplier_order 838 838 0 0.00 0 1566 8 0 0
v_cancel_write_off 2014 2014 0 0.00 0 7792 19 0 0
v_max_id_clo_ord 5488 5488 0 0.00 0 777 1 0 0
v_max_id_clo_res 1007 1007 0 0.00 0 7398 28 0 0
v_max_id_ord_sup 5593 5593 0 0.00 0 7200 6 0 0
v_max_non_paid_invoice 1573 1573 0 0.00 0 6010 10 0 0
v_max_non_paid_realizn 3901 3901 0 0.00 0 6647 6 0 0
v_min_id_clo_ord 5488 5488 0 0.00 0 1777 6 0 0
v_min_id_clo_res 1007 1007 0 0.00 0 1029 4 0 0
v_min_id_ord_sup 5593 5593 0 0.00 0 11329 9 0 0
v_min_non_paid_invoice 5937 5937 0 0.00 0 5396 12 0 0
v_min_non_paid_realizn 7319 7319 0 0.00 0 6439 12 0 0
v_our_firm 10534 10534 0 0.00 0 42 1 0 0
v_reserve_write_off 20122 20122 0 0.00 0 18109 11 0 0
x_lock_depdocs_on_canc_invoice 11718 11718 0 0.00 0 22705 123 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 8650 343 0 0
x_make_qstorno_1000_1200 5488 5488 0 0.00 2 37818 1163 0 0
x_make_qstorno_1200_2000 5610 5610 0 0.00 3 96715 3108 0 0
x_make_qstorno_2100_3300 469839 469839 0 0.00 0 69538 178 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-09-2021 19:38:53 to 06-09-2021 19:38:57.

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
17:07:55 3 8192000000 8805021072 8817250304 10635920 0.129 2 2 3 0 10814352 66144 112288
17:08:58 16714 8192000000 10379142480 10698821632 1428766576 14.850 100 89 178 0 1462307456 5172624 28368256
17:10:19 31137 8192000000 10463625920 10795188224 1503542336 15.507 100 90 180 0 1540131936 7995152 28594448
17:12:02 18429 8192000000 10502663136 10851147776 1560018224 15.996 100 75 150 0 1592550880 9617472 22915184
17:13:26 12532 8192000000 10581559056 10939486208 1637742960 16.661 100 78 155 0 1671750368 10921536 23085872
17:14:42 7150 8192000000 10647793280 11015684096 1705617024 17.232 100 72 143 1 1738267376 12673136 19977216
17:15:51 6887 8192000000 10713495728 11090223104 1768258752 17.753 100 76 151 2 1803740064 13933040 21548272
17:17:00 6960 8192000000 10745502608 11132497920 1810496048 18.100 100 76 152 2 1845454656 14105744 20852864
17:18:10 2457 8192000000 10808405360 11193659392 1863118576 18.529 100 89 178 1 1900972912 12719024 25135312
17:19:14 26993 8192000000 10784601840 11204284416 1869728384 18.582 100 61 120 0 1897062400 10065936 17268080
17:20:41 9138 8192000000 10790550336 11215851520 1878246016 18.651 100 19 36 2 1889647488 6612768 4788704
17:21:51 7226 8192000000 10799808880 11229163520 1891450592 18.757 100 23 46 2 1905213600 6886144 6876864
17:22:59 68745 8192000000 10825559216 11249152000 1901096688 18.835 100 40 77 2 1917567968 5268576 11202704
17:25:08 7694 8192000000 10818721952 11257782272 1914897712 18.946 100 30 59 1 1930469152 6330848 9240592
17:26:20 5249 8192000000 10838247648 11267108864 1920326128 18.989 100 31 62 3 1935756064 6429984 8999952
17:27:26 14398 8192000000 10837483008 11247218688 1869230992 18.578 98 29 58 1 1883358672 5134640 8993040
17:28:40 18556 8192000000 10786102496 11223302144 1798597520 18.002 94 24 46 1 1809636096 5189136 5849440
17:30:00 7067 8192000000 10719917392 11137855488 1757098224 17.660 96 28 56 1 1771258864 5953792 8206848
17:31:07 7415 8192000000 10703316640 11117182976 1729365568 17.430 95 25 49 5 1741725104 5280448 7079088
17:32:16 64715 8192000000 10691660624 11113795584 1720343152 17.355 94 29 56 0 1734543072 5172688 9027232
17:34:24 10376 8192000000 10768918416 11187376128 1835266512 18.302 99 32 63 0 1849842400 5648928 8926960
17:35:35 7879 8192000000 10776643616 11209678848 1867480432 18.564 100 15 28 2 1876247472 5129040 3638000
17:36:44 5561 8192000000 10782320496 11219181568 1873670608 18.614 100 16 32 0 1883785792 5659856 4455328
17:37:50 55634 8192000000 10830855184 11259912192 1909528768 18.903 100 40 80 2 1927778080 5262384 12986928
17:40:00 11990 8192000000 10865093488 11292884992 1939644752 19.144 100 44 87 1 1960074272 7959056 12470464
17:41:15 19356 8192000000 10867849040 11313422336 1949667104 19.224 100 26 52 2 1969723200 12182336 7873760
17:42:36 7560 8192000000 10907857632 11339485184 1976225072 19.435 100 57 114 1 2004581216 11582928 16773216
17:43:48 8431 8192000000 10927342016 11358314496 1986955392 19.520 100 45 90 1 2017775280 18098240 12721648
17:45:01 14649 8192000000 10954232832 11388125184 2007726624 19.684 100 50 99 1 2044676560 22228848 14721088
17:46:21 4310 8192000000 10981791360 11410845696 2023754992 19.810 100 71 140 2 2069007120 24885200 20366928
17:47:35 7468 8192000000 10951577488 11374911488 1974043008 19.418 98 73 143 1 2024052032 28555280 21453744
17:48:48 5039 8192000000 10966813776 11393564672 1999107808 19.616 100 65 130 1 2048816336 30314544 19393984
17:49:57 3234 8192000000 10964618784 11389980672 1992451616 19.563 100 67 133 4 2049767760 36811984 20504160
17:51:05 10354 8192000000 10953896656 11379945472 1957941920 19.290 98 55 110 1 2010693936 35145056 17606960
17:52:21 12203 8192000000 10943068848 11372027904 1956856992 19.281 99 59 116 5 2014085216 38904992 18323232
17:53:39 8781 8192000000 10927923344 11349155840 1942491632 19.167 99 65 127 3 2014815248 51935792 20387824
17:55:00 1786 8192000000 10960719056 11383328768 1976485312 19.437 100 68 132 1 2043285360 45708160 21091888
17:56:10 3414 8192000000 10964572416 11373916160 1947606096 19.207 98 84 168 2 2022890400 48727408 26556896
17:57:19 7816 8192000000 10965793136 11397480448 1982192352 19.482 100 42 84 2 2043913056 47755936 13964768
17:58:29 9143 8192000000 10960625072 11400577024 1972084560 19.402 99 53 107 0 2042393536 52235152 18073824
17:59:41 14240 8192000000 10937404864 11377938432 1938432928 19.134 99 46 92 2 2014072208 59802608 15836672
18:00:59 17776 8192000000 10918504128 11373641728 1943860624 19.178 99 31 62 2 2001092416 46606928 10624864
18:02:18 31277 8192000000 10928276992 11385421824 1963896352 19.337 100 22 44 0 2013232320 41608016 7727952
18:03:51 40857 8192000000 10887750528 11356106752 1891775712 18.760 97 17 34 0 1946360512 47753728 6831072
18:06:26 34446 8192000000 10861181696 11319169024 1898750944 18.816 98 11 22 1 1916668016 13959312 3957760
18:08:01 118737 8192000000 10899240432 11356655616 1928208464 19.053 98 27 54 0 1952147968 15683456 8256048
18:11:54 114760 8192000000 10895155984 11352838144 1906988640 18.882 97 15 30 1 1925900192 14818976 4092576
18:14:50 43003 8192000000 10879509504 11336482816 1923993440 19.019 98 22 44 2 1937870672 7680752 6196480
18:16:37 38238 8192000000 10890788688 11357798400 1970113632 19.386 100 27 55 2 1987875648 9928480 7833536
18:18:24 23939 8192000000 10920719744 11382251520 1982727920 19.486 100 14 28 0 1998380544 11634272 4018352
18:20:49 25948 8192000000 10908250512 11384115200 1965457184 19.349 99 9 18 1 1984807840 16796176 2554480
18:22:20 76418 8192000000 10912574560 11389034496 1973473680 19.413 99 22 44 1 1995140720 14923728 6743312
18:24:37 60793 8192000000 10925816912 11402539008 1975505600 19.429 99 15 29 2 1996474944 17221792 3747552
18:26:38 33769 8192000000 10931435536 11404283904 2002560640 19.643 100 26 52 1 2023136496 13197504 7378352
18:28:11 40403 8192000000 10935332672 11399929856 1989017968 19.536 99 32 62 0 2007041296 9571296 8452032
18:29:56 61773 8192000000 10918348096 11402936320 1970171136 19.387 98 25 50 1 1989976368 12755200 7050032
18:32:07 35273 8192000000 10913518704 11361226752 1914353952 18.942 96 25 50 1 1929826672 8648896 6823824
18:33:42 68555 8192000000 10923719952 11368038400 1917892128 18.970 97 30 58 1 1939879712 13682752 8304832
18:35:53 90410 8192000000 10875681744 11324555264 1832312000 18.278 94 16 32 0 1855514496 18644144 4558352
18:38:31 144877 8192000000 10895136640 11355123712 1916162080 18.956 98 30 60 2 1935477872 11177600 8138192
18:42:00 54002 8192000000 10882168384 11356561408 1937265280 19.125 99 21 42 1 1957421920 14443520 5713120
18:44:33 70605 8192000000 10870355248 11340935168 1923288384 19.013 98 17 34 1 1937192688 8886784 5017520
18:46:47 44071 8192000000 10881011072 11338006528 1898611056 18.815 97 15 28 1 1909866160 7104384 4150720
18:48:31 61121 8192000000 10869477072 11341799424 1893214896 18.772 96 28 56 0 1915987328 14666688 8105744
18:50:34 125954 8192000000 10881063296 11370606592 1894287968 18.780 97 13 26 2 1914935456 17112768 3534720
18:53:40 40429 8192000000 10855933840 11315376128 1894530672 18.782 98 26 50 0 1912468112 10289504 7647936
18:55:21 34883 8192000000 10870036688 11325771776 1896412464 18.797 98 39 78 0 1921674480 14434576 10827440
18:57:07 22632 8192000000 10872696432 11318448128 1899997088 18.826 98 42 84 1 1921024960 9591232 11436640
18:58:30 100772 8192000000 10863381072 11340607488 1942054336 19.163 100 24 48 2 1963292816 14337344 6901136
19:01:12 7242 8192000000 10901729536 11347812352 1947335824 19.205 99 71 140 1 1976489584 8300880 20852880
19:02:21 28163 8192000000 10876394528 11337752576 1934359968 19.102 98 36 72 0 1954137248 9318240 10459040
19:03:53 85086 8192000000 10880982384 11356332032 1926056224 19.035 98 14 28 1 1940309232 10110304 4142704
19:06:18 79641 8192000000 10906987440 11389980672 1982917840 19.488 100 16 31 1 2004134976 16327536 4889600
19:09:07 71159 8192000000 10906380640 11392307200 1964128576 19.339 99 16 31 2 1983593360 14799952 4664832
19:11:56 30056 8192000000 10922123488 11386175488 1962567824 19.326 99 26 50 1 1978005472 8796432 6641216
19:13:26 39303 8192000000 10905125408 11378397184 1946693968 19.200 98 23 46 0 1968331712 15127728 6510016
19:16:25 25371 8192000000 10930533152 11391541248 1988089008 19.529 100 42 82 0 2011433648 11346960 11997680
19:17:53 60730 8192000000 10892243776 11348230144 1876150224 18.634 95 34 67 2 1898766960 12894256 9722480
19:19:55 76138 8192000000 10886920944 11358973952 1893236736 18.772 96 23 46 0 1915404160 16141680 6025744
19:22:20 113716 8192000000 10881036272 11354656768 1925581184 19.032 98 24 48 1 1941250144 9200448 6468512
19:25:18 67035 8192000000 10868045328 11355324416 1937856368 19.130 99 10 20 0 1952139280 11530896 2752016
19:27:25 66187 8192000000 10861390320 11330043904 1898324816 18.813 97 12 24 0 1910989760 9204880 3460064
19:29:32 72168 8192000000 10867686832 11351117824 1937326272 19.125 99 8 16 1 1950149440 10585952 2237216
19:31:48 49458 8192000000 10865274560 11338735616 1879505104 18.661 96 17 32 0 1896195072 12166496 4523472
19:34:18 33613 8192000000 10902107008 11365404672 1944310848 19.181 99 27 54 2 1964800528 13013360 7476320
19:35:59 91539 8192000000 10861847440 11331276800 1806194688 18.065 92 9 18 0 1817887136 9494624 2197824

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-09-2021 19:38:58 to 06-09-2021 19:39:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 94 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-09-2021 19:39:14 to 06-09-2021 19:39:16.

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          239288
MON$OLDEST_ACTIVE               241726
MON$OLDEST_SNAPSHOT             241726
MON$NEXT_TRANSACTION            241726
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                       2034656
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33491 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33491 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33491 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33491 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 ---
:267: 
:268: mscs-test-161.budzdorov.ru    Mon Sep  6 19:37:56 2021
:269:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:270: 
:271: 
:272: mscs-test-161.budzdorov.ru    Mon Sep  6 19:37:58 2021
:273:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:274: 
--- end of diff output ---

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

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