Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.08.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 16035540 kB
MemAvailable 30954948 kB
Buffers 0 kB
Cached 15601852 kB
SwapCached 2580 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-08-17 19:37:45
DTS_END                         2021-08-17 19:37:45.1830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36258
TRN_ID                          246255
ATT_ID                          640
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: 9612.27 20935 1153472
customer order: creation 177.41 333 21289
customer order: refuse 15.03 363 1804
order to supplier: creation 47.48 1207 5697
order to supplier: removal 3.38 111782 406
invoice (draft): creation 48.51 3464 5821
invoice (draft): removal 55.48 848 6657
invoice accept: apply 43.38 17782 5206
invoice accept: cancel 50.31 767 6037
customer reserve: creation 3922.99 181 470759
customer reserve: removal 4940.20 59 592824
realization accept: apply 86.79 154 10415
realization accept: cancel 88.60 96 10632
payment from customer: creation 61.33 104 7359
payment from customer: removal 6.02 78 722
payment to supplier: creation 49.18 119 5901
payment to supplier: removal 3.57 91 428
service: total inventory turnovers 4.97 1157 596
service: total monetary turnovers 6.84 22 821
service: aggregate perf. data 0.78 20790 94
service: refresh index statistics 0.03 259304 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 17-08-2021 19:38:57 to 17-08-2021 19:39:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2513
TEST_TIME 2 2351
TEST_TIME 3 3017
TEST_TIME 4 3493
TEST_TIME 5 3509
TEST_TIME 6 3914
TEST_TIME 7 4404
TEST_TIME 8 4749
TEST_TIME 9 4797
TEST_TIME 10 5044
TEST_TIME 11 5087
TEST_TIME 12 5259
TEST_TIME 13 5560
TEST_TIME 14 5742
TEST_TIME 15 5947
TEST_TIME 16 6542
TEST_TIME 17 6544
TEST_TIME 18 7308
TEST_TIME 19 7765
TEST_TIME 20 7894
TEST_TIME 21 8231
TEST_TIME 22 8492
TEST_TIME 23 8843
TEST_TIME 24 9382
TEST_TIME 25 9702
TEST_TIME 26 10040
TEST_TIME 27 9840
TEST_TIME 28 10028
TEST_TIME 29 10395
TEST_TIME 30 10689
TEST_TIME 31 10823
TEST_TIME 32 10704
TEST_TIME 33 10699
TEST_TIME 34 10697
TEST_TIME 35 10707
TEST_TIME 36 10681
TEST_TIME 37 10488
TEST_TIME 38 10321
TEST_TIME 39 10112
TEST_TIME 40 10183
TEST_TIME 41 10037
TEST_TIME 42 10134
TEST_TIME 43 10040
TEST_TIME 44 9987
TEST_TIME 45 9955
TEST_TIME 46 10151
TEST_TIME 47 10113
TEST_TIME 48 10055
TEST_TIME 49 10044
TEST_TIME 50 9989
TEST_TIME 51 9979
TEST_TIME 52 9829
TEST_TIME 53 9751
TEST_TIME 54 9752
TEST_TIME 55 9779
TEST_TIME 56 9799
TEST_TIME 57 9723
TEST_TIME 58 9671
TEST_TIME 59 9647
TEST_TIME 60 9702
TEST_TIME 61 9804
TEST_TIME 62 9869
TEST_TIME 63 9939
TEST_TIME 64 10052
TEST_TIME 65 10069
TEST_TIME 66 10015
TEST_TIME 67 10041
TEST_TIME 68 9960
TEST_TIME 69 10057
TEST_TIME 70 10145
TEST_TIME 71 10127
TEST_TIME 72 10096
TEST_TIME 73 10013
TEST_TIME 74 9964
TEST_TIME 75 9967
TEST_TIME 76 9892
TEST_TIME 77 9824
TEST_TIME 78 9898
TEST_TIME 79 9862
TEST_TIME 80 9818
TEST_TIME 81 9780
TEST_TIME 82 9798
TEST_TIME 83 9764
TEST_TIME 84 9691
TEST_TIME 85 9667
TEST_TIME 86 9686
TEST_TIME 87 9655
TEST_TIME 88 9676
TEST_TIME 89 9654
TEST_TIME 90 9664
TEST_TIME 91 9713
TEST_TIME 92 9682
TEST_TIME 93 9726
TEST_TIME 94 9769
TEST_TIME 95 9768
TEST_TIME 96 9797
TEST_TIME 97 9833
TEST_TIME 98 9775
TEST_TIME 99 9895
TEST_TIME 100 9892
TEST_TIME 101 9814
TEST_TIME 102 9797
TEST_TIME 103 9765
TEST_TIME 104 9748
TEST_TIME 105 9724
TEST_TIME 106 9657
TEST_TIME 107 9674
TEST_TIME 108 9685
TEST_TIME 109 9638
TEST_TIME 110 9579
TEST_TIME 111 9629
TEST_TIME 112 9583
TEST_TIME 113 9650
TEST_TIME 114 9641
TEST_TIME 115 9642
TEST_TIME 116 9637
TEST_TIME 117 9663
TEST_TIME 118 9649
TEST_TIME 119 9618
TEST_TIME 120 9614

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-08-2021 19:39:01 to 17-08-2021 19:39:04.

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 1590023 1590023 0 0.00 0 23588 6 0 0
doc_list_biud 1590023 1590023 0 0.00 0 25937 28 0 0
sp_add_invoice_to_stock 5288 5206 82 1.55 22 179872 17782 0 82
sp_cancel_adding_invoice 6037 6037 0 0.00 5 65898 768 0 0
sp_cancel_client_order 1804 1804 0 0.00 3 19148 364 0 0
sp_cancel_customer_reserve 592824 592824 0 0.00 1 25938 60 0 0
sp_cancel_pay_from_customer 722 722 0 0.00 3 5040 78 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 3630 92 0 0
sp_cancel_supplier_invoice 6658 6657 1 0.01 2 23525 849 0 1
sp_cancel_supplier_order 406 406 0 0.00 5 968099 111782 0 0
sp_cancel_write_off 10632 10632 0 0.00 1 14101 96 0 0
sp_client_order 21289 21289 0 0.00 3 24212 333 0 0
sp_customer_reserve 470759 470759 0 0.00 0 43342 182 0 0
sp_fill_shopping_cart 32794 32794 0 0.00 1 23827 37 0 0
sp_fill_shopping_cart_clo_res 470757 470757 0 0.00 0 30596 12 0 0
sp_get_clo_for_invoice 5200 5200 0 0.00 0 32450 252 0 0
sp_kill_cost_storno 17819 17819 0 0.00 0 8299 50 0 0
sp_kill_qstorno_ret_qs2qd 1203343 1203343 0 0.00 0 25935 33 0 0
sp_kill_qty_storno 633974 633974 0 0.00 0 25937 70 0 0
sp_lock_selected_doc 21551 21551 0 0.00 0 7258 5 0 0
sp_make_cost_storno 28874 28874 0 0.00 0 11915 41 0 0
sp_multiply_rows_for_pdistr 28874 28874 0 0.00 0 7645 25 0 0
sp_multiply_rows_for_qdistr 21289 21289 0 0.00 0 14596 148 0 0
sp_pay_from_customer 7359 7359 0 0.00 4 18119 105 0 0
sp_pay_to_supplier 5901 5901 0 0.00 5 13615 119 0 0
sp_payment_common 13260 13260 0 0.00 2 17769 27 0 0
sp_qd_handle_on_cancel_clo 1804 1804 0 0.00 0 19139 332 0 0
sp_qd_handle_on_invoice_upd_sts 11236 11236 0 0.00 0 24321 315 0 0
sp_qd_handle_on_reserve_upd_sts 10415 10415 0 0.00 0 10863 53 0 0
sp_reserve_write_off 10415 10415 0 0.00 7 11961 154 0 0
sp_supplier_invoice 5821 5821 0 0.00 8 83628 3464 0 0
sp_supplier_order 5697 5697 0 0.00 6 34568 1208 0 0
srv_aggregate_perf_data 94 94 0 0.00 3190 214458 20791 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1874 147498 43180 0 0
srv_find_qd_qs_mism 1104798 1104798 0 0.00 0 23517 6 0 0
srv_make_invnt_saldo 596 596 0 0.00 42 36875 1157 0 0
srv_make_money_saldo 821 821 0 0.00 0 1751 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 112540 361375 259305 0 0
t$perf-abend:sp_add_invoice_to_stock 82 82 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5206 5206 0 0.00 0 16521 143 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 0 2960 70 0 0
t$perf-norm:sp_cancel_client_order 1804 1804 0 0.00 0 2557 14 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 5879 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 722 722 0 0.00 0 393 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 3586 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 2083 20 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 22753 1661 0 0
t$perf-norm:sp_cancel_write_off 1048 1048 0 0.00 0 3203 12 0 0
t$perf-norm:sp_client_order 21289 21289 0 0.00 0 10854 17 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 4879 19 0 0
t$perf-norm:sp_pay_from_customer 7359 7359 0 0.00 0 16050 9 0 0
t$perf-norm:sp_pay_to_supplier 5901 5901 0 0.00 0 3301 10 0 0
t$perf-norm:sp_reserve_write_off 10415 10415 0 0.00 0 10704 8 0 0
t$perf-norm:sp_supplier_invoice 5821 5821 0 0.00 0 4463 16 0 0
t$perf-norm:sp_supplier_order 5697 5697 0 0.00 0 6581 16 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 2273 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 217 6 0 0
t$perf-norm:srv_make_invnt_saldo 596 596 0 0.00 0 930 20 0 0
t$perf-norm:srv_make_money_saldo 821 821 0 0.00 0 418 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 21 7 0 0
v_add_invoice_to_stock 10562 10398 164 1.55 0 4132 18 0 164
v_all_customers 38530 38530 0 0.00 0 14 1 0 0
v_all_suppliers 31458 31458 0 0.00 0 19 1 0 0
v_all_wares 42578 42578 0 0.00 0 39 1 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 1533 10 0 0
v_cancel_client_order 3608 3608 0 0.00 0 4704 7 0 0
v_cancel_customer_prepayment 1444 1444 0 0.00 0 5025 8 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 1327 5 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 3553 19 0 0
v_cancel_supplier_invoice 812 810 2 0.24 0 2608 20 0 2
v_cancel_supplier_order 812 812 0 0.00 0 1549 8 0 0
v_cancel_write_off 2096 2096 0 0.00 0 1833 13 0 0
v_max_id_clo_ord 5694 5694 0 0.00 0 350 1 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 3447 15 0 0
v_max_id_ord_sup 5808 5808 0 0.00 0 4078 6 0 0
v_max_non_paid_invoice 1674 1674 0 0.00 0 6160 9 0 0
v_max_non_paid_realizn 4086 4086 0 0.00 0 1887 3 0 0
v_min_id_clo_ord 5694 5694 0 0.00 0 3074 5 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 29016 128 0 0
v_min_id_ord_sup 5808 5808 0 0.00 0 5552 8 0 0
v_min_non_paid_invoice 5901 5901 0 0.00 0 13592 17 0 0
v_min_non_paid_realizn 7359 7359 0 0.00 0 17764 17 0 0
v_our_firm 10594 10594 0 0.00 0 14 1 0 0
v_reserve_write_off 20830 20830 0 0.00 0 3298 6 0 0
x_lock_depdocs_on_canc_invoice 12289 12289 0 0.00 0 21280 133 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 21391 436 0 0
x_make_qstorno_1000_1200 5697 5697 0 0.00 2 34522 1128 0 0
x_make_qstorno_1200_2000 5821 5821 0 0.00 4 83619 3337 0 0
x_make_qstorno_2100_3300 470759 470759 0 0.00 0 42083 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-08-2021 19:39:05 to 17-08-2021 19:39:09.

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:49 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:49 33142 8192000000 10359443392 10675990528 1407440992 14.661 100 92 184 0 1444011216 6294896 30275328
17:10:26 23995 8192000000 10410930896 10737278976 1459812976 15.124 100 92 184 4 1498776496 8858064 30105456
17:11:53 18717 8192000000 10490443440 10831265792 1538590592 15.811 100 86 172 2 1574950832 9403520 26956720
17:13:14 11935 8192000000 10549727776 10909212672 1615766864 16.474 100 76 152 1 1649720336 11195920 22757552
17:14:28 8971 8192000000 10634681424 11000823808 1687769712 17.083 100 77 154 1 1723155296 13122416 22263168
17:15:39 5093 8192000000 10701759984 11076071424 1753602032 17.631 100 91 181 1 1793274320 13910048 25762240
17:16:47 2163 8192000000 10740512160 11119656960 1799880176 18.013 100 81 162 0 1835331152 12429776 23021200
17:17:50 1973 8192000000 10793817616 11176787968 1847427792 18.401 100 94 189 4 1886898928 12448384 27022752
17:18:53 20740 8192000000 10807371664 11209478144 1870430512 18.588 100 65 129 0 1896699920 6538128 19731280
17:20:14 7789 8192000000 10794192176 11218345984 1878556544 18.653 100 19 38 0 1890286080 6049232 5680304
17:21:24 9902 8192000000 10808985616 11235721216 1892812800 18.768 100 18 36 1 1903812864 5956608 5043456
17:22:35 8806 8192000000 10816941904 11242102784 1902197024 18.844 100 19 38 0 1912808032 5332656 5278352
17:23:49 50050 8192000000 10857790416 11266576384 1915810320 18.953 100 62 124 1 1939014736 4920256 18284160
17:25:39 4053 8192000000 10833022160 11259457536 1896463440 18.798 99 38 76 1 1914234496 6918480 10852576
17:26:45 4761 8192000000 10829742400 11269873664 1917755328 18.969 100 21 42 1 1929790656 6126624 5908704
17:27:56 46252 8192000000 10794382576 11229835264 1799294016 18.008 95 19 38 2 1810989728 5286272 6409440
17:30:07 5953 8192000000 10692403376 11106025472 1747609216 17.582 98 24 48 0 1761190640 6655792 6925632
17:31:16 7466 8192000000 10725281360 11141795840 1778163344 17.834 98 30 61 2 1793389616 6149120 9077152
17:32:25 11514 8192000000 10725411776 11148783616 1798131840 17.999 99 16 32 1 1807903216 5410368 4361008
17:33:37 43787 8192000000 10755867360 11186315264 1845415504 18.385 100 31 62 0 1860876576 6317376 9143696
17:35:22 8325 8192000000 10786550464 11210395648 1868557776 18.573 100 39 78 2 1886695712 6306960 11830976
17:36:34 12222 8192000000 10793111424 11224182784 1879394560 18.660 100 26 52 3 1892881056 5907440 7579056
17:37:47 57321 8192000000 10851103136 11271254016 1920631696 18.992 100 46 92 3 1944441104 10643088 13166320
17:39:52 20801 8192000000 10869015712 11294744576 1941649680 19.160 100 56 110 2 1967433344 10114544 15669120
17:41:18 30419 8192000000 10899535776 11326271488 1960566672 19.311 100 47 94 1 1989654144 15815616 13271856
17:42:55 6252 8192000000 10923112544 11348807680 1974620992 19.422 100 51 98 5 2011143152 22418672 14103488
17:44:04 8084 8192000000 10928432640 11355095040 1972036192 19.402 99 55 110 0 2008757728 19866464 16855072
17:45:17 5468 8192000000 10929133856 11363213312 1986125088 19.513 100 50 98 2 2022423904 20349984 15948832
17:46:28 7340 8192000000 10959046192 11393671168 2007789984 19.684 100 56 113 1 2047283584 22643488 16850112
17:47:41 13675 8192000000 10958633952 11403399168 2015810208 19.747 100 42 84 1 2054278176 25382208 13085760
17:49:02 13227 8192000000 10969024496 11395125248 1994879360 19.582 99 65 130 1 2048097056 33361280 19856416
17:50:23 3623 8192000000 10990863632 11418300416 2019239520 19.774 100 79 157 1 2079230416 35561728 24429168
17:51:30 2508 8192000000 10979862624 11400343552 1997205824 19.601 100 80 159 0 2062426128 40738848 24481456
17:52:40 1868 8192000000 10988761392 11406577664 1999105952 19.616 100 77 152 1 2067376608 44353360 23917296
17:53:47 5196 8192000000 10984117808 11395842048 1990740848 19.550 100 75 149 1 2059862864 45606080 23515936
17:55:04 5256 8192000000 10983817952 11411210240 1996877712 19.598 100 68 136 0 2069964320 50557808 22528800
17:56:15 6283 8192000000 10971572704 11398500352 1970097872 19.386 99 56 110 1 2034124352 45830112 18196368
17:57:29 3061 8192000000 10962151088 11399331840 1990770512 19.550 100 53 106 0 2047286992 39268464 17248016
17:58:38 13208 8192000000 10920634400 11353505792 1935669296 19.112 99 36 72 0 1989682896 42035696 11977904
17:59:56 19546 8192000000 10917274720 11358601216 1930352192 19.070 99 29 57 1 1988249728 48069680 9827856
18:01:20 15853 8192000000 10897416128 11349987328 1917618128 18.968 99 28 56 0 1979614208 52141456 9854624
18:02:53 22623 8192000000 10882094272 11321692160 1890680816 18.751 98 21 40 3 1933845680 36334672 6830192
18:04:21 21031 8192000000 10891843472 11340533760 1938394432 19.134 100 17 34 4 1973721584 29948720 5378432
18:05:46 39341 8192000000 10872880832 11327569920 1867259856 18.562 97 20 39 0 1912321232 37853536 7207840
18:07:30 100843 8192000000 10868416752 11322028032 1866496640 18.556 96 21 41 1 1888991488 16660032 5834816
18:10:16 74551 8192000000 10880763696 11338194944 1880060288 18.666 96 12 24 2 1892405648 9441424 2903936
18:12:35 92726 8192000000 10952318688 11376250880 1985861424 19.511 100 39 78 1 2007040560 10698432 10480704
18:15:12 86873 8192000000 10926957360 11381026816 1973155040 19.410 99 24 48 1 1994781504 14919232 6707232
18:17:53 68175 8192000000 10927169440 11394277376 1972494608 19.405 99 23 44 3 1997412592 17321472 7596512
18:20:10 19736 8192000000 10924073408 11378577408 1955064896 19.267 98 16 32 1 1974739952 15117376 4557680
18:21:50 19253 8192000000 10923830480 11387629568 1993759344 19.573 100 44 81 2 2019372720 13995488 11617888
18:23:12 34060 8192000000 10930293552 11403698176 1994740144 19.581 100 31 62 1 2021058304 16763280 9554880
18:25:11 77596 8192000000 10903642768 11373084672 1936165776 19.116 97 10 20 1 1949770672 10534864 3070032
18:27:35 45970 8192000000 10909566256 11367579648 1963908528 19.337 99 25 50 2 1979082960 8386624 6787808
18:29:23 94341 8192000000 10918622384 11404701696 1977405936 19.444 99 17 34 0 1997906304 15065968 5434400
18:31:58 89927 8192000000 10899444672 11394113536 1966724912 19.359 99 11 22 1 1982456848 12790784 2941152
18:34:39 21863 8192000000 10920713152 11375931392 1976926976 19.440 100 28 56 0 1992228240 7034176 8267088
18:36:03 37259 8192000000 10914247104 11382439936 1967861216 19.368 99 30 60 1 1990165056 13588368 8715472
18:38:04 73503 8192000000 10918722496 11388616704 1919385392 18.982 96 16 32 2 1943257168 19085904 4785872
18:40:27 41746 8192000000 10914894704 11388010496 1946845904 19.201 98 14 28 0 1959959808 9689168 3424736
18:42:09 71934 8192000000 10895906192 11359080448 1851704144 18.436 94 12 24 2 1867536608 12579344 3253120
18:44:21 37005 8192000000 10898930128 11360808960 1944463648 19.182 99 27 54 2 1962785504 11009440 7312416
18:45:59 39707 8192000000 10890223760 11359780864 1927457168 19.047 98 31 62 0 1950293504 14378736 8457600
18:47:42 66973 8192000000 10864614640 11333898240 1881870304 18.680 97 12 22 1 1896341440 11275632 3195504
18:49:49 99831 8192000000 10857969088 11303006208 1860699280 18.509 96 25 50 1 1877484704 9399952 7385472
18:52:56 27924 8192000000 10871189616 11329277952 1939488288 19.143 100 39 78 1 1959612144 8798608 11325248
18:54:28 27902 8192000000 10878689344 11325095936 1934069776 19.099 100 59 116 2 1962363824 11610272 16683776
18:56:00 43505 8192000000 10855935696 11321524224 1911934560 18.922 99 20 39 1 1924959360 7933504 5091296
18:57:45 55346 8192000000 10826489968 11303649280 1915299072 18.949 100 14 28 1 1928407280 9378656 3729552
18:59:42 38572 8192000000 10822226480 11305705472 1896120000 18.795 99 8 16 0 1908846304 10309648 2416656
19:01:21 56717 8192000000 10838664176 11324026880 1922665360 19.008 100 6 12 0 1933237728 8444384 2127984
19:03:28 58379 8192000000 10877507744 11356778496 1950378016 19.229 100 10 20 1 1961434240 8093408 2962816
19:05:34 38612 8192000000 10905098960 11367616512 1926367488 19.038 98 24 48 1 1947640864 14845040 6428336
19:07:12 43868 8192000000 10900532400 11356778496 1944108560 19.180 99 33 66 0 1962538592 9390384 9039648
19:08:59 36947 8192000000 10921177168 11361976320 1938243760 19.133 98 49 98 1 1960441952 8862928 13335264
19:10:40 93502 8192000000 10884458752 11370520576 1884131808 18.698 95 15 29 0 1900540016 12713392 3694816
19:13:14 21516 8192000000 10923398224 11382448128 1963577168 19.334 99 39 78 0 1985794064 10765456 11451440
19:15:01 28728 8192000000 10943700560 11394904064 1980848000 19.471 99 50 98 3 2007099520 11463088 14788432
19:16:32 147497 8192000000 10906119584 11380895744 1886118976 18.714 95 11 22 0 1902876400 13687760 3069664
19:20:12 71012 8192000000 10899719568 11391029248 1968085200 19.370 100 11 22 0 1986771600 15448720 3237680
19:22:24 64403 8192000000 10885833408 11362242560 1923934400 19.018 97 9 18 0 1939992240 13379104 2678736
19:25:24 57825 8192000000 10879251680 11359260672 1958218112 19.292 100 23 46 2 1975922016 10884352 6819552
19:27:22 66289 8192000000 10868360272 11358248960 1908268944 18.893 97 25 50 0 1933693520 18417472 7007104
19:29:32 94384 8192000000 10878482288 11350257664 1900399296 18.830 97 10 20 0 1918251024 15107408 2744320
19:32:07 50065 8192000000 10871401584 11333373952 1900332560 18.829 98 23 46 1 1914604736 7741776 6530400
19:33:58 55487 8192000000 10865053536 11341418496 1909994176 18.907 98 20 40 0 1929612560 14150672 5467712
19:35:57 8442 8192000000 10889732672 11335831552 1938284384 19.133 100 65 123 3 1964149920 9644768 16220768
19:37:09 47016 8192000000 10242347920 10654478336 1328333536 13.952 69 24 47 0 1348817648 13872880 6611232

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-08-2021 19:39:11 to 17-08-2021 19:39:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 82 v_add_invoice_to_stock 335544517
except 1 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-08-2021 19:39:27 to 17-08-2021 19:39:29.

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          245750
MON$OLDEST_ACTIVE               247415
MON$OLDEST_SNAPSHOT             247415
MON$NEXT_TRANSACTION            247415
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                       2055968
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.33488 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33488 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33488 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33488 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 ---
:395: 
:396: mscs-test-161.budzdorov.ru    Tue Aug 17 19:38:02 2021
:397:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:398: 
:399: 
:400: mscs-test-161.budzdorov.ru    Tue Aug 17 19:38:11 2021
:401:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:402: 
:403: 
:404: mscs-test-161.budzdorov.ru    Tue Aug 17 19:38:14 2021
:405:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:406: 
--- end of diff output ---

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

17.08.21 19:39:30. 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-08-17 19:39:32 No severe PSQL-related problems occured
17.08.21 19:39:34. End of report.