Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.05.2022 22:40
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 16096212 kB
MemAvailable 31400412 kB
Buffers 0 kB
Cached 15638424 kB
SwapCached 2452 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-28 22:38:18
DTS_END                         2022-05-28 22:38:18.0360
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/48780
TRN_ID                          254687
ATT_ID                          669
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: 9750.73 21588 1170087
customer order: creation 183.79 357 22055
customer order: refuse 15.69 332 1883
order to supplier: creation 48.74 1247 5849
order to supplier: removal 3.39 106595 407
invoice (draft): creation 49.16 3109 5899
invoice (draft): removal 54.30 884 6516
invoice accept: apply 44.85 18065 5382
invoice accept: cancel 49.80 866 5976
customer reserve: creation 4094.23 182 491307
customer reserve: removal 4875.39 58 585047
realization accept: apply 89.88 176 10786
realization accept: cancel 102.38 100 12285
payment from customer: creation 63.67 104 7640
payment from customer: removal 5.82 67 698
payment to supplier: creation 52.71 137 6325
payment to supplier: removal 3.94 90 473
service: total inventory turnovers 5.10 1015 612
service: total monetary turnovers 6.97 24 836
service: aggregate perf. data 0.89 20289 107
service: refresh index statistics 0.03 278060 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-05-2022 22:41:03 to 28-05-2022 22:41:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5454
TEST_TIME 2 4576
TEST_TIME 3 4111
TEST_TIME 4 4493
TEST_TIME 5 4799
TEST_TIME 6 4866
TEST_TIME 7 5141
TEST_TIME 8 5306
TEST_TIME 9 5419
TEST_TIME 10 5525
TEST_TIME 11 5543
TEST_TIME 12 6352
TEST_TIME 13 6442
TEST_TIME 14 6463
TEST_TIME 15 7064
TEST_TIME 16 7151
TEST_TIME 17 7245
TEST_TIME 18 7314
TEST_TIME 19 7466
TEST_TIME 20 7825
TEST_TIME 21 8030
TEST_TIME 22 8594
TEST_TIME 23 8744
TEST_TIME 24 8687
TEST_TIME 25 8885
TEST_TIME 26 9081
TEST_TIME 27 9710
TEST_TIME 28 9871
TEST_TIME 29 10695
TEST_TIME 30 11027
TEST_TIME 31 10941
TEST_TIME 32 10974
TEST_TIME 33 10928
TEST_TIME 34 10851
TEST_TIME 35 10851
TEST_TIME 36 10689
TEST_TIME 37 10550
TEST_TIME 38 10402
TEST_TIME 39 10291
TEST_TIME 40 10310
TEST_TIME 41 10265
TEST_TIME 42 10108
TEST_TIME 43 10042
TEST_TIME 44 10083
TEST_TIME 45 10007
TEST_TIME 46 10087
TEST_TIME 47 10017
TEST_TIME 48 10062
TEST_TIME 49 9966
TEST_TIME 50 9930
TEST_TIME 51 9791
TEST_TIME 52 9672
TEST_TIME 53 9662
TEST_TIME 54 9579
TEST_TIME 55 9582
TEST_TIME 56 9626
TEST_TIME 57 9674
TEST_TIME 58 9660
TEST_TIME 59 9668
TEST_TIME 60 9657
TEST_TIME 61 9740
TEST_TIME 62 9730
TEST_TIME 63 9829
TEST_TIME 64 9905
TEST_TIME 65 10004
TEST_TIME 66 10011
TEST_TIME 67 10040
TEST_TIME 68 10009
TEST_TIME 69 10088
TEST_TIME 70 10050
TEST_TIME 71 10082
TEST_TIME 72 9946
TEST_TIME 73 10059
TEST_TIME 74 9968
TEST_TIME 75 9929
TEST_TIME 76 9929
TEST_TIME 77 9865
TEST_TIME 78 9826
TEST_TIME 79 9796
TEST_TIME 80 9772
TEST_TIME 81 9714
TEST_TIME 82 9687
TEST_TIME 83 9732
TEST_TIME 84 9712
TEST_TIME 85 9709
TEST_TIME 86 9802
TEST_TIME 87 9864
TEST_TIME 88 9809
TEST_TIME 89 9842
TEST_TIME 90 9832
TEST_TIME 91 9919
TEST_TIME 92 9922
TEST_TIME 93 9935
TEST_TIME 94 9960
TEST_TIME 95 9966
TEST_TIME 96 9939
TEST_TIME 97 9891
TEST_TIME 98 9844
TEST_TIME 99 9811
TEST_TIME 100 9789
TEST_TIME 101 9759
TEST_TIME 102 9754
TEST_TIME 103 9713
TEST_TIME 104 9682
TEST_TIME 105 9649
TEST_TIME 106 9650
TEST_TIME 107 9670
TEST_TIME 108 9706
TEST_TIME 109 9658
TEST_TIME 110 9688
TEST_TIME 111 9693
TEST_TIME 112 9660
TEST_TIME 113 9737
TEST_TIME 114 9746
TEST_TIME 115 9711
TEST_TIME 116 9718
TEST_TIME 117 9779
TEST_TIME 118 9760
TEST_TIME 119 9827
TEST_TIME 120 9751

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-05-2022 22:41:07 to 28-05-2022 22:41:10.

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 1625611 1625611 0 0.00 0 18853 6 0 0
doc_list_biud 1625610 1625610 0 0.00 0 54064 27 0 0
sp_add_invoice_to_stock 5498 5382 116 2.10 28 208658 18065 0 116
sp_cancel_adding_invoice 5976 5976 0 0.00 4 109504 867 0 0
sp_cancel_client_order 1883 1883 0 0.00 4 12732 333 0 0
sp_cancel_customer_reserve 585047 585047 0 0.00 1 54091 58 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 3 3616 68 0 0
sp_cancel_pay_to_supplier 473 473 0 0.00 3 4950 90 0 0
sp_cancel_supplier_invoice 6531 6516 15 0.22 2 17602 885 0 15
sp_cancel_supplier_order 407 407 0 0.00 5 1324559 106596 0 0
sp_cancel_write_off 12285 12285 0 0.00 1 12251 101 0 0
sp_client_order 22055 22055 0 0.00 3 31607 358 0 0
sp_customer_reserve 491307 491307 0 0.00 0 102724 182 0 0
sp_fill_shopping_cart 33802 33802 0 0.00 1 11543 35 0 0
sp_fill_shopping_cart_clo_res 491307 491307 0 0.00 0 12601 13 0 0
sp_get_clo_for_invoice 5381 5381 0 0.00 0 26103 243 0 0
sp_kill_cost_storno 19432 19432 0 0.00 0 11399 52 0 0
sp_kill_qstorno_ret_qs2qd 1189302 1189302 0 0.00 0 54051 32 0 0
sp_kill_qty_storno 628279 628279 0 0.00 0 54064 69 0 0
sp_lock_selected_doc 22325 22325 0 0.00 0 8653 5 0 0
sp_make_cost_storno 30131 30131 0 0.00 0 18806 53 0 0
sp_multiply_rows_for_pdistr 30130 30130 0 0.00 0 8235 26 0 0
sp_multiply_rows_for_qdistr 22055 22055 0 0.00 0 31599 155 0 0
sp_pay_from_customer 7640 7640 0 0.00 4 17358 105 0 0
sp_pay_to_supplier 6325 6325 0 0.00 4 12951 137 0 0
sp_payment_common 13965 13965 0 0.00 1 17345 28 0 0
sp_qd_handle_on_cancel_clo 1883 1883 0 0.00 0 12724 304 0 0
sp_qd_handle_on_invoice_upd_sts 11355 11355 0 0.00 0 34034 321 0 0
sp_qd_handle_on_reserve_upd_sts 10786 10786 0 0.00 0 27958 62 0 0
sp_reserve_write_off 10786 10786 0 0.00 6 27970 176 0 0
sp_supplier_invoice 5899 5899 0 0.00 6 87058 3110 0 0
sp_supplier_order 5849 5849 0 0.00 4 38630 1248 0 0
srv_aggregate_perf_data 107 107 0 0.00 3039 337406 20290 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1323 125388 43872 0 0
srv_find_qd_qs_mism 1119377 1119377 0 0.00 0 14372 5 0 0
srv_make_invnt_saldo 612 612 0 0.00 46 42070 1015 0 0
srv_make_money_saldo 836 836 0 0.00 0 1356 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 197516 346540 278061 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5382 5382 0 0.00 0 9453 170 0 0
t$perf-norm:sp_cancel_adding_invoice 461 461 0 0.00 0 3559 119 0 0
t$perf-norm:sp_cancel_client_order 1883 1883 0 0.00 0 1821 10 0 0
t$perf-norm:sp_cancel_customer_reserve 727 727 0 0.00 0 4430 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 4935 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 473 473 0 0.00 0 5879 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 451 451 0 0.00 0 4228 33 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 25529 1740 0 0
t$perf-norm:sp_cancel_write_off 1060 1060 0 0.00 0 848 6 0 0
t$perf-norm:sp_client_order 22055 22055 0 0.00 0 5408 18 0 0
t$perf-norm:sp_customer_reserve 1040 1040 0 0.00 0 12124 22 0 0
t$perf-norm:sp_pay_from_customer 7640 7640 0 0.00 0 5944 10 0 0
t$perf-norm:sp_pay_to_supplier 6325 6325 0 0.00 0 6186 11 0 0
t$perf-norm:sp_reserve_write_off 10786 10786 0 0.00 0 5251 12 0 0
t$perf-norm:sp_supplier_invoice 5899 5899 0 0.00 0 8211 17 0 0
t$perf-norm:sp_supplier_order 5849 5849 0 0.00 0 8180 20 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 60 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 255 9 0 0
t$perf-norm:srv_make_invnt_saldo 612 612 0 0.00 0 2043 20 0 0
t$perf-norm:srv_make_money_saldo 836 836 0 0.00 0 1251 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 12 5 0 0
v_add_invoice_to_stock 10990 10758 232 2.11 0 6546 19 0 232
v_all_customers 40574 40574 0 0.00 0 25 1 0 0
v_all_suppliers 32318 32318 0 0.00 0 137 1 0 0
v_all_wares 44110 44110 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 922 922 0 0.00 0 1010 7 0 0
v_cancel_client_order 3766 3766 0 0.00 0 5160 9 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 3600 8 0 0
v_cancel_customer_reserve 1454 1454 0 0.00 0 6462 12 0 0
v_cancel_payment_to_supplier 946 946 0 0.00 0 4853 19 0 0
v_cancel_supplier_invoice 932 902 30 3.21 0 564 12 0 30
v_cancel_supplier_order 814 814 0 0.00 0 872 5 0 0
v_cancel_write_off 2120 2120 0 0.00 0 4890 12 0 0
v_max_id_clo_ord 5848 5848 0 0.00 0 509 1 0 0
v_max_id_clo_res 1040 1040 0 0.00 0 2012 16 0 0
v_max_id_ord_sup 5898 5898 0 0.00 0 3315 4 0 0
v_max_non_paid_invoice 1912 1912 0 0.00 0 2040 4 0 0
v_max_non_paid_realizn 3835 3835 0 0.00 0 5277 5 0 0
v_min_id_clo_ord 5848 5848 0 0.00 0 3610 10 0 0
v_min_id_clo_res 1040 1040 0 0.00 0 4447 10 0 0
v_min_id_ord_sup 5898 5898 0 0.00 0 4794 6 0 0
v_min_non_paid_invoice 6325 6325 0 0.00 0 7320 19 0 0
v_min_non_paid_realizn 7640 7640 0 0.00 0 11807 21 0 0
v_our_firm 11146 11146 0 0.00 0 19 1 0 0
v_reserve_write_off 21572 21572 0 0.00 0 6600 6 0 0
x_lock_depdocs_on_canc_invoice 12041 12041 0 0.00 0 24329 116 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 11533 342 0 0
x_make_qstorno_1000_1200 5849 5849 0 0.00 2 38617 1165 0 0
x_make_qstorno_1200_2000 5899 5899 0 0.00 3 87049 2992 0 0
x_make_qstorno_2100_3300 491307 491307 0 0.00 0 102721 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-05-2022 22:41:11 to 28-05-2022 22:41:15.

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:21 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:22 25134 8192000000 9914364048 10222235648 983965584 10.723 100 80 160 0 1011050496 6156176 20928736
20:10:54 15117 8192000000 9949882528 10269245440 1029736688 11.166 100 89 178 0 1061043552 8315744 22991120
20:12:20 8784 8192000000 9981817728 10312581120 1067923184 11.532 100 86 172 0 1099590864 10264416 21403264
20:13:32 11522 8192000000 10029643664 10373120000 1113512592 11.966 100 82 165 0 1144051168 10804256 19734320
20:14:49 9791 8192000000 10082006096 10438508544 1166511664 12.464 100 81 162 1 1198033008 13260944 18260400
20:16:03 5665 8192000000 10121930848 10489401344 1209904864 12.868 100 78 156 0 1239555568 12590688 17060016
20:17:11 2826 8192000000 10156913872 10538713088 1249444448 13.233 100 74 147 2 1277550752 11619088 16487216
20:18:17 2816 8192000000 10198069792 10578591744 1280704240 13.519 100 96 187 0 1314186528 13266736 20215552
20:19:21 19157 8192000000 10221937920 10608975872 1301704096 13.711 100 83 166 2 1331905520 10921152 19280272
20:20:40 1993 8192000000 10213042096 10620571648 1319556720 13.873 100 38 76 1 1336931872 8402112 8973040
20:21:42 20861 8192000000 10243007696 10636615680 1324837248 13.920 100 71 145 1 1347227632 5431008 16959376
20:23:04 5828 8192000000 10217649120 10634694656 1327550944 13.945 100 23 46 1 1339013312 6287584 5174784
20:24:12 10011 8192000000 10222914528 10640936960 1334241328 14.005 100 20 38 2 1343608176 5405248 3961600
20:25:23 57243 8192000000 10255128448 10658791424 1343858240 14.092 100 49 98 4 1361051040 5156576 12036224
20:27:20 4703 8192000000 10232822016 10648678400 1329777024 13.965 99 40 80 1 1344558624 5164048 9617552
20:28:25 8451 8192000000 10194557696 10598719488 1286896560 13.576 98 26 52 1 1298811488 6232512 5682416
20:29:34 63149 8192000000 10124261648 10524131328 1133331424 12.153 88 19 38 0 1143550528 5054784 5164320
20:31:38 8755 8192000000 10145470240 10539974656 1224059488 12.999 98 23 46 0 1235006976 5835936 5111552
20:32:47 12328 8192000000 10158869488 10558148608 1253633728 13.272 99 29 58 0 1266315728 5584288 7097712
20:34:03 74754 8192000000 10166093520 10579697664 1257518224 13.307 98 30 59 4 1270073920 5305248 7250448
20:36:18 8425 8192000000 10188668368 10601345024 1297460592 13.672 100 18 36 2 1306597200 5118768 4017840
20:37:28 7437 8192000000 10204028128 10616094720 1310404784 13.790 100 24 48 1 1322339152 5969408 5964960
20:38:40 30593 8192000000 10251105696 10661617664 1344914128 14.102 100 45 86 3 1364871904 10405008 9552768
20:40:14 56290 8192000000 10261306784 10684080128 1358669360 14.225 100 39 77 4 1377282928 9333616 9279952
20:42:21 30053 8192000000 10274960496 10698149888 1357585632 14.216 99 47 94 1 1381486000 13483312 10417056
20:44:05 14677 8192000000 10297782192 10721722368 1381678336 14.432 100 32 64 1 1405267728 16207408 7381984
20:45:26 18248 8192000000 10328961408 10752724992 1404466592 14.635 100 61 121 2 1442312784 24060176 13786016
20:46:48 7793 8192000000 10335082624 10759299072 1414902784 14.727 100 59 117 0 1454121968 25607664 13611520
20:47:58 3424 8192000000 10338050624 10754932736 1398400560 14.581 100 71 139 1 1443484336 28761632 16322144
20:49:06 20988 8192000000 10314193232 10734592000 1374248512 14.365 99 48 96 2 1416153040 30309520 11595008
20:50:31 6550 8192000000 10305738736 10717671424 1356442336 14.205 98 64 128 0 1402010624 30560432 15007856
20:51:46 19800 8192000000 10298621328 10717327360 1334211584 14.005 97 42 84 3 1383146752 37774128 11161040
20:53:12 4367 8192000000 10337917840 10747445248 1386399136 14.474 100 76 153 1 1448261824 43175728 18686960
20:54:21 2745 8192000000 10317394128 10720899072 1360014704 14.237 100 79 157 1 1421481136 41653152 19813280
20:55:27 1321 8192000000 10348504704 10746818560 1380254080 14.419 100 86 172 1 1449133872 47513120 21366672
20:56:34 6500 8192000000 10360364704 10768560128 1391216080 14.517 100 81 162 2 1464313904 51523344 21574480
20:57:47 3183 8192000000 10357693584 10771554304 1391153200 14.516 100 66 132 1 1462237744 52374560 18709984
20:58:55 3401 8192000000 10350365136 10772561920 1388941616 14.496 100 56 110 0 1457590112 53730448 14918048
21:00:03 5470 8192000000 10359323232 10763931648 1382538064 14.439 100 80 160 1 1458545744 55159808 20847872
21:01:10 3497 8192000000 10362141280 10780585984 1401509984 14.608 100 63 125 0 1467399648 49418752 16470912
21:02:16 5331 8192000000 10335516624 10751225856 1369426624 14.322 99 58 114 0 1433370240 47332816 16610800
21:03:26 7425 8192000000 10355097200 10776997888 1384006192 14.452 100 46 93 0 1459137600 61857808 13273600
21:04:37 9343 8192000000 10348827408 10777526272 1383328240 14.446 100 36 72 2 1456331712 61952528 11050944
21:05:48 26036 8192000000 10335611616 10788405248 1392217216 14.526 100 32 64 0 1456772400 55431264 9123920
21:07:29 36416 8192000000 10277908304 10736332800 1355316832 14.195 99 17 34 0 1395606816 35465360 4824624
21:09:06 91508 8192000000 10313796432 10761887744 1374170112 14.364 99 20 40 0 1395777984 17324768 4283104
21:11:40 116173 8192000000 10302558384 10749317120 1345717952 14.109 96 8 16 0 1358661856 11626160 1317744
21:14:44 72445 8192000000 10338578640 10771374080 1393327280 14.536 99 42 84 0 1413085984 9775520 9983184
21:16:57 77515 8192000000 10308890992 10754535424 1375605408 14.377 98 40 80 1 1395434272 10643840 9185024
21:19:20 56282 8192000000 10297422304 10746589184 1362843552 14.263 98 29 57 1 1377416528 7693696 6879280
21:21:20 29047 8192000000 10313521472 10767745024 1394539856 14.546 99 25 50 0 1414780592 14524208 5716528
21:22:49 43310 8192000000 10310438672 10768117760 1392371376 14.527 99 19 38 1 1413742624 16491536 4879712
21:24:39 23334 8192000000 10320005600 10771361792 1401366928 14.607 100 35 70 0 1418807232 10343040 7097264
21:26:04 93638 8192000000 10301681456 10769858560 1389029632 14.497 99 12 24 1 1401326704 10002688 2294384
21:28:41 25552 8192000000 10303238224 10770579456 1388250592 14.490 99 15 30 0 1405145328 13367824 3526912
21:30:08 77321 8192000000 10272840272 10740187136 1304930320 13.740 93 20 39 2 1317150000 7831600 4388080
21:32:45 54748 8192000000 10292158016 10766139392 1365178992 14.284 98 17 34 0 1384530400 15358304 3993104
21:34:41 52918 8192000000 10281761168 10747215872 1333350752 13.997 97 6 12 0 1353346704 18673968 1321984
21:36:43 13880 8192000000 10267630384 10713784320 1319896768 13.876 97 33 66 2 1334587664 8003424 6687472
21:37:57 8337 8192000000 10267091232 10708062208 1342324224 14.078 99 37 74 1 1361485632 11190352 7971056
21:39:11 60490 8192000000 10260702752 10709692416 1298059856 13.678 96 19 37 1 1315362464 13410256 3892352
21:42:00 60613 8192000000 10245574864 10697363456 1267626240 13.400 94 8 16 1 1283062048 13437648 1998160
21:44:04 77387 8192000000 10280746912 10730172416 1352508176 14.170 99 18 36 0 1370582672 13909632 4164864
21:46:28 20164 8192000000 10283889472 10724864000 1366638720 14.297 100 38 76 1 1386595696 11409536 8547440
21:47:57 74178 8192000000 10279697328 10740867072 1307909392 13.767 95 13 26 1 1324824048 13543728 3370928
21:50:15 76049 8192000000 10279639216 10744758272 1368497568 14.314 100 23 46 0 1394088896 19976960 5614368
21:52:39 14469 8192000000 10286168896 10732527616 1359051792 14.229 99 57 115 1 1382075248 7672608 15350848
21:53:56 35972 8192000000 10254339248 10710491136 1332553104 13.990 98 28 56 0 1351619936 12566016 6500816
21:55:32 42371 8192000000 10239707984 10687426560 1316513440 13.845 97 20 40 0 1330360960 9283472 4564048
21:57:16 51354 8192000000 10273897568 10734325760 1371145344 14.337 100 18 36 0 1389345552 14036544 4163664
21:59:14 70970 8192000000 10294408416 10745937920 1377653760 14.396 100 17 34 0 1392377936 10823456 3900720
22:01:25 80538 8192000000 10304377920 10760572928 1380741440 14.423 99 21 42 0 1407293696 21480496 5071760
22:03:46 59114 8192000000 10292029552 10754424832 1367038880 14.301 99 7 14 2 1385409392 16683008 1687504
22:05:51 37788 8192000000 10294220176 10748420096 1357573280 14.216 98 26 52 2 1372548128 9579696 5395152
22:07:33 34514 8192000000 10299060832 10753544192 1386622112 14.476 100 18 36 0 1398858128 8033408 4202608
22:09:10 120960 8192000000 10304429568 10771963904 1375738336 14.378 98 11 22 0 1392770464 14843088 2189040
22:12:12 125388 8192000000 10304507424 10767286272 1386205072 14.472 99 9 16 1 1401080000 13161504 1713424
22:15:17 86097 8192000000 10305000368 10759909376 1386844880 14.478 99 38 76 1 1403307536 7715456 8747200
22:17:44 50141 8192000000 10265822576 10719608832 1328132880 13.950 96 32 64 0 1345369136 10082048 7154208
22:19:34 42180 8192000000 10250835152 10709655552 1338142320 14.041 98 23 46 1 1353293552 10009264 5141968
22:21:21 74775 8192000000 10272833072 10728243200 1323230848 13.906 96 17 34 0 1339283520 11950480 4102192
22:23:38 91580 8192000000 10263623840 10731458560 1339684752 14.055 98 14 28 0 1355270784 12218416 3367616
22:26:11 46940 8192000000 10244301536 10706595840 1296496496 13.663 95 7 14 0 1308266432 9585472 2184464
22:27:58 74771 8192000000 10252723776 10707484672 1286182480 13.569 95 23 46 1 1306130256 14648048 5299728
22:30:19 21107 8192000000 10264845024 10709753856 1339537072 14.053 99 32 64 1 1357918368 11495936 6885360
22:31:42 93686 8192000000 10261105040 10705641472 1291301504 13.616 95 19 38 1 1304590208 9093120 4195584
22:34:16 41437 8192000000 10270668800 10720911360 1321438976 13.890 97 22 44 1 1340096880 13957600 4700304
22:36:05 52964 8192000000 10248482816 10692358144 1285618864 13.564 95 13 26 0 1304133120 15635216 2879040
22:38:01 61845 8192000000 9472525200 9807233024 606275824 6.890 46 4 8 0 624623024 17903456 443744

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-05-2022 22:41:17 to 28-05-2022 22:41:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-05-2022 22:41:33 to 28-05-2022 22:41:35.

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          254853
MON$OLDEST_ACTIVE               255251
MON$OLDEST_SNAPSHOT             255251
MON$NEXT_TRANSACTION            255251
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                       2065568
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33597 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33597 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33597 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33597 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 ---
:72: 
:73: mscs-test-161.budzdorov.ru    Sat May 28 22:39:37 2022
:74:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:75: 
:76: 
:77: mscs-test-161.budzdorov.ru    Sat May 28 22:39:38 2022
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:79: 
:80: 
:81: mscs-test-161.budzdorov.ru    Sat May 28 22:39:38 2022
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:83: 
--- end of diff output ---

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

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