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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16141500 kB
MemAvailable 30834780 kB
Buffers 0 kB
Cached 15530604 kB
SwapCached 4900 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-10-26 22:37:55
DTS_END                         2021-10-26 22:37:55.6530
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/34298
TRN_ID                          246868
ATT_ID                          718
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: 9102.17 18167 1092260
customer order: creation 179.38 375 21525
customer order: refuse 15.03 341 1803
order to supplier: creation 46.57 1370 5588
order to supplier: removal 3.52 101311 422
invoice (draft): creation 46.35 3442 5562
invoice (draft): removal 52.80 807 6336
invoice accept: apply 41.14 17042 4937
invoice accept: cancel 47.17 808 5660
customer reserve: creation 3762.49 169 451499
customer reserve: removal 4604.42 61 552530
realization accept: apply 86.21 199 10345
realization accept: cancel 86.18 117 10342
payment from customer: creation 59.64 136 7157
payment from customer: removal 5.88 87 706
payment to supplier: creation 49.75 154 5970
payment to supplier: removal 3.60 78 432
service: total inventory turnovers 4.85 1222 582
service: total monetary turnovers 6.35 28 762
service: aggregate perf. data 0.82 22250 98
service: refresh index statistics 0.03 213349 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-10-2021 22:38:45 to 26-10-2021 22:38:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1676
TEST_TIME 2 3290
TEST_TIME 3 3242
TEST_TIME 4 3753
TEST_TIME 5 4457
TEST_TIME 6 4535
TEST_TIME 7 4535
TEST_TIME 8 4525
TEST_TIME 9 4375
TEST_TIME 10 4466
TEST_TIME 11 4628
TEST_TIME 12 4917
TEST_TIME 13 5238
TEST_TIME 14 5532
TEST_TIME 15 5534
TEST_TIME 16 5661
TEST_TIME 17 5991
TEST_TIME 18 6305
TEST_TIME 19 6880
TEST_TIME 20 7220
TEST_TIME 21 7478
TEST_TIME 22 8214
TEST_TIME 23 8304
TEST_TIME 24 8583
TEST_TIME 25 8823
TEST_TIME 26 9178
TEST_TIME 27 10129
TEST_TIME 28 9978
TEST_TIME 29 9866
TEST_TIME 30 9982
TEST_TIME 31 9799
TEST_TIME 32 9526
TEST_TIME 33 9504
TEST_TIME 34 9536
TEST_TIME 35 9532
TEST_TIME 36 9567
TEST_TIME 37 9470
TEST_TIME 38 9305
TEST_TIME 39 9153
TEST_TIME 40 9106
TEST_TIME 41 8946
TEST_TIME 42 8888
TEST_TIME 43 8917
TEST_TIME 44 9176
TEST_TIME 45 9119
TEST_TIME 46 9277
TEST_TIME 47 9199
TEST_TIME 48 9176
TEST_TIME 49 9112
TEST_TIME 50 9054
TEST_TIME 51 9005
TEST_TIME 52 8931
TEST_TIME 53 8869
TEST_TIME 54 8822
TEST_TIME 55 8922
TEST_TIME 56 8866
TEST_TIME 57 8898
TEST_TIME 58 8849
TEST_TIME 59 8813
TEST_TIME 60 8687
TEST_TIME 61 8714
TEST_TIME 62 8700
TEST_TIME 63 8671
TEST_TIME 64 8696
TEST_TIME 65 8731
TEST_TIME 66 8712
TEST_TIME 67 8789
TEST_TIME 68 8812
TEST_TIME 69 8764
TEST_TIME 70 8840
TEST_TIME 71 9022
TEST_TIME 72 9122
TEST_TIME 73 9073
TEST_TIME 74 9106
TEST_TIME 75 9111
TEST_TIME 76 9034
TEST_TIME 77 8967
TEST_TIME 78 8966
TEST_TIME 79 9024
TEST_TIME 80 8967
TEST_TIME 81 8946
TEST_TIME 82 8997
TEST_TIME 83 8950
TEST_TIME 84 8916
TEST_TIME 85 8932
TEST_TIME 86 8886
TEST_TIME 87 8865
TEST_TIME 88 8940
TEST_TIME 89 8880
TEST_TIME 90 8966
TEST_TIME 91 8963
TEST_TIME 92 8976
TEST_TIME 93 9052
TEST_TIME 94 9095
TEST_TIME 95 9111
TEST_TIME 96 9136
TEST_TIME 97 9170
TEST_TIME 98 9184
TEST_TIME 99 9202
TEST_TIME 100 9282
TEST_TIME 101 9269
TEST_TIME 102 9213
TEST_TIME 103 9137
TEST_TIME 104 9169
TEST_TIME 105 9134
TEST_TIME 106 9128
TEST_TIME 107 9112
TEST_TIME 108 9130
TEST_TIME 109 9117
TEST_TIME 110 9073
TEST_TIME 111 9012
TEST_TIME 112 9043
TEST_TIME 113 9022
TEST_TIME 114 8997
TEST_TIME 115 9057
TEST_TIME 116 9043
TEST_TIME 117 9083
TEST_TIME 118 9065
TEST_TIME 119 9086
TEST_TIME 120 9103

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-10-2021 22:38:48 to 26-10-2021 22: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 1511828 1511828 0 0.00 0 19125 6 0 0
doc_list_biud 1511798 1511798 0 0.00 0 24073 28 0 0
sp_add_invoice_to_stock 5047 4937 110 2.17 42 147334 17042 0 110
sp_cancel_adding_invoice 5660 5660 0 0.00 5 73048 809 0 0
sp_cancel_client_order 1803 1803 0 0.00 4 8648 342 0 0
sp_cancel_customer_reserve 552530 552530 0 0.00 1 24077 61 0 0
sp_cancel_pay_from_customer 706 706 0 0.00 3 4209 88 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 3 3964 79 0 0
sp_cancel_supplier_invoice 6342 6336 6 0.09 2 20167 807 0 6
sp_cancel_supplier_order 422 422 0 0.00 8 1069516 101312 0 0
sp_cancel_write_off 10342 10342 0 0.00 1 9551 118 0 0
sp_client_order 21525 21525 0 0.00 3 46617 376 0 0
sp_customer_reserve 451499 451499 0 0.00 0 33079 170 0 0
sp_fill_shopping_cart 32672 32672 0 0.00 1 21739 40 0 0
sp_fill_shopping_cart_clo_res 451496 451496 0 0.00 0 20232 16 0 0
sp_get_clo_for_invoice 4934 4934 0 0.00 0 29015 323 0 0
sp_kill_cost_storno 17140 17140 0 0.00 0 9736 60 0 0
sp_kill_qstorno_ret_qs2qd 1122160 1122160 0 0.00 0 24065 33 0 0
sp_kill_qty_storno 592353 592353 0 0.00 0 24068 71 0 0
sp_lock_selected_doc 21212 21212 0 0.00 0 8714 8 0 0
sp_make_cost_storno 28406 28406 0 0.00 0 19123 64 0 0
sp_multiply_rows_for_pdistr 28393 28393 0 0.00 0 12611 36 0 0
sp_multiply_rows_for_qdistr 21525 21525 0 0.00 0 46593 168 0 0
sp_pay_from_customer 7157 7157 0 0.00 4 20840 136 0 0
sp_pay_to_supplier 5970 5970 0 0.00 5 19128 155 0 0
sp_payment_common 13116 13116 0 0.00 1 20765 34 0 0
sp_qd_handle_on_cancel_clo 1803 1803 0 0.00 0 8639 307 0 0
sp_qd_handle_on_invoice_upd_sts 10585 10585 0 0.00 0 13016 326 0 0
sp_qd_handle_on_reserve_upd_sts 10335 10335 0 0.00 0 15557 60 0 0
sp_reserve_write_off 10345 10345 0 0.00 6 15584 200 0 0
sp_supplier_invoice 5562 5562 0 0.00 6 84912 3442 0 0
sp_supplier_order 5588 5588 0 0.00 5 39195 1370 0 0
srv_aggregate_perf_data 99 98 1 1.01 2418 400311 22251 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1884 125661 43290 0 0
srv_find_qd_qs_mism 1044951 1044951 0 0.00 0 12825 5 0 0
srv_make_invnt_saldo 582 582 0 0.00 27 91786 1223 0 0
srv_make_money_saldo 762 762 0 0.00 1 2967 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 65027 423552 213349 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 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 4937 4937 0 0.00 0 17941 168 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 7503 81 0 0
t$perf-norm:sp_cancel_client_order 1803 1803 0 0.00 0 5016 20 0 0
t$perf-norm:sp_cancel_customer_reserve 707 707 0 0.00 0 5594 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 706 706 0 0.00 0 1965 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 1174 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 450 450 0 0.00 0 541 5 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 34011 1742 0 0
t$perf-norm:sp_cancel_write_off 1017 1017 0 0.00 0 3780 10 0 0
t$perf-norm:sp_client_order 21525 21525 0 0.00 0 13517 18 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 2291 8 0 0
t$perf-norm:sp_pay_from_customer 7157 7157 0 0.00 0 18883 16 0 0
t$perf-norm:sp_pay_to_supplier 5970 5970 0 0.00 0 6907 12 0 0
t$perf-norm:sp_reserve_write_off 10345 10345 0 0.00 0 6187 14 0 0
t$perf-norm:sp_supplier_invoice 5562 5562 0 0.00 0 13566 23 0 0
t$perf-norm:sp_supplier_order 5588 5588 0 0.00 0 6361 17 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 213 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 492 11 0 0
t$perf-norm:srv_make_invnt_saldo 582 582 0 0.00 0 2043 28 0 0
t$perf-norm:srv_make_money_saldo 762 762 0 0.00 0 1933 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10066 9846 220 2.18 0 6180 18 0 220
v_all_customers 38488 38488 0 0.00 0 16 1 0 0
v_all_suppliers 31140 31140 0 0.00 0 17 1 0 0
v_all_wares 43050 43050 0 0.00 0 91 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 72 6 0 0
v_cancel_client_order 3606 3606 0 0.00 0 2424 5 0 0
v_cancel_customer_prepayment 1412 1412 0 0.00 0 3914 8 0 0
v_cancel_customer_reserve 1414 1414 0 0.00 0 2418 6 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 3706 11 0 0
v_cancel_supplier_invoice 912 900 12 1.31 0 1710 16 0 12
v_cancel_supplier_order 844 844 0 0.00 0 2447 9 0 0
v_cancel_write_off 2034 2034 0 0.00 0 2150 13 0 0
v_max_id_clo_ord 5588 5588 0 0.00 0 6665 2 0 0
v_max_id_clo_res 997 997 0 0.00 0 1086 6 0 0
v_max_id_ord_sup 5559 5559 0 0.00 0 5938 4 0 0
v_max_non_paid_invoice 1538 1538 0 0.00 0 120 2 0 0
v_max_non_paid_realizn 4011 4011 0 0.00 0 6892 6 0 0
v_min_id_clo_ord 5588 5588 0 0.00 0 7221 11 0 0
v_min_id_clo_res 997 997 0 0.00 0 2419 9 0 0
v_min_id_ord_sup 5559 5559 0 0.00 0 8074 11 0 0
v_min_non_paid_invoice 5961 5961 0 0.00 0 10115 23 0 0
v_min_non_paid_realizn 7155 7155 0 0.00 0 8436 23 0 0
v_our_firm 10850 10850 0 0.00 0 16 1 0 0
v_reserve_write_off 20670 20670 0 0.00 0 2579 5 0 0
x_lock_depdocs_on_canc_invoice 11546 11546 0 0.00 0 15203 124 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 7206 355 0 0
x_make_qstorno_1000_1200 5588 5588 0 0.00 1 39184 1284 0 0
x_make_qstorno_1200_2000 5562 5562 0 0.00 3 84887 3302 0 0
x_make_qstorno_2100_3300 451499 451499 0 0.00 0 32714 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-10-2021 22:38:53 to 26-10-2021 22: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
20:07:59 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:00 27499 8192000000 9901324464 10212642816 982699184 10.710 100 90 181 0 1011538320 5528608 23310528
20:10:38 18609 8192000000 9949923008 10271096832 1028413632 11.153 100 90 180 0 1059724832 8032432 23278768
20:12:07 14187 8192000000 9986827136 10324361216 1063885440 11.494 100 77 154 1 1092767568 9618848 19263280
20:13:29 9677 8192000000 10050990096 10401923072 1131449408 12.135 100 71 142 0 1159023248 10914400 16659440
20:14:45 8179 8192000000 10104672416 10466902016 1187463728 12.660 100 85 168 1 1220553712 13242560 19847424
20:15:55 5358 8192000000 10148352640 10524135424 1238207376 13.130 100 80 155 1 1268041600 12632688 17201536
20:17:03 5771 8192000000 10165058368 10546982912 1260442576 13.334 100 79 157 4 1290288480 12339280 17506624
20:18:10 1855 8192000000 10216363824 10602553344 1300583936 13.701 100 93 186 0 1333765424 12509728 20671760
20:19:14 18337 8192000000 10212509824 10616799232 1315737216 13.838 100 52 104 0 1338584976 10619968 12227792
20:20:32 8680 8192000000 10213956032 10628546560 1326138960 13.932 100 26 51 2 1338620656 7026192 5455504
20:21:48 11020 8192000000 10217517408 10640281600 1336217824 14.023 100 15 30 0 1346104224 6104864 3781536
20:23:02 9124 8192000000 10215916144 10644307968 1339632800 14.054 100 14 28 2 1348078112 5280496 3164816
20:24:11 25815 8192000000 10245323504 10667216896 1342919152 14.084 100 43 85 2 1363649184 11058656 9671376
20:25:38 4337 8192000000 10239661312 10661900288 1344334192 14.096 100 25 50 0 1356047120 6179136 5533792
20:26:45 8912 8192000000 10234015808 10656239616 1330005200 13.967 99 17 34 0 1340174560 6276256 3893104
20:27:55 16739 8192000000 10161901888 10568945664 1175159664 12.545 89 19 37 1 1184158016 4573728 4424624
20:29:13 9822 8192000000 10203075616 10601025536 1284261680 13.552 99 61 119 1 1304641808 6155632 14224496
20:30:23 4510 8192000000 10144018336 10535817216 1220854928 12.970 97 26 51 1 1233049280 5706000 6488352
20:31:28 5461 8192000000 10155486704 10548568064 1253054208 13.266 100 43 85 2 1270071312 7337472 9679632
20:32:35 6981 8192000000 10166621072 10570149888 1269644992 13.418 100 28 54 1 1280991968 4985504 6361472
20:33:44 42254 8192000000 10174315696 10580086784 1267900656 13.402 99 29 59 2 1279533360 5770992 5861712
20:35:27 7080 8192000000 10199721216 10608607232 1297085216 13.669 100 45 90 2 1318637728 11772608 9779904
20:36:35 11474 8192000000 10196677376 10611486720 1303981008 13.731 100 16 32 1 1313354240 4970496 4402736
20:37:47 11618 8192000000 10224303616 10634571776 1323498912 13.908 100 22 43 2 1332838384 3877584 5461888
20:39:03 13383 8192000000 10235246672 10651181056 1334455520 14.007 100 27 54 0 1347146752 6614096 6077136
20:41:01 20362 8192000000 10276053824 10692091904 1362619264 14.261 100 52 104 0 1389407216 14676544 12111408
20:42:24 26409 8192000000 10281775632 10713227264 1377011984 14.390 100 32 64 1 1400217216 16154848 7050384
20:43:54 6736 8192000000 10322099712 10741903360 1399823056 14.593 100 47 92 2 1430602752 19247008 11532688
20:45:05 7238 8192000000 10328427360 10751565824 1408193312 14.668 100 53 105 2 1442871248 22214048 12463888
20:46:18 25015 8192000000 10314944160 10744819712 1397663536 14.574 99 37 73 1 1434266128 27107952 9494640
20:47:51 11235 8192000000 10306671888 10725384192 1373596400 14.359 99 47 91 1 1409047152 24082768 11367984
20:49:07 3265 8192000000 10308088624 10717175808 1369107856 14.319 99 64 128 1 1413911152 29643936 15159360
20:50:16 3352 8192000000 10334911440 10737065984 1390696080 14.512 100 83 161 1 1440323776 30348912 19278784
20:51:24 1883 8192000000 10345275392 10751275008 1393692848 14.539 100 86 173 1 1449313360 34047280 21573232
20:52:33 2846 8192000000 10322202432 10728390656 1374655648 14.369 100 68 136 1 1429066784 36890272 17520864
20:53:39 3038 8192000000 10341896576 10752995328 1387438848 14.483 100 68 134 0 1448047616 43284400 17324368
20:54:47 3635 8192000000 10348903904 10755813376 1372017088 14.345 99 81 161 1 1443285328 50718480 20549760
20:55:54 3261 8192000000 10353245056 10760757248 1392168912 14.525 100 88 174 4 1462515088 48385264 21960912
20:57:03 12136 8192000000 10330519376 10753474560 1373059568 14.354 99 43 84 2 1432218656 47404592 11754496
20:58:17 10930 8192000000 10315681376 10732404736 1358967552 14.228 99 47 92 1 1416741632 45328368 12445712
20:59:40 20104 8192000000 10305067712 10737426432 1363147696 14.266 99 37 74 1 1407671840 33950224 10573920
21:01:13 26343 8192000000 10325227632 10761592832 1372059392 14.345 99 30 59 0 1416950720 37167632 7723696
21:02:44 35737 8192000000 10301972752 10737287168 1351004752 14.157 99 22 44 0 1399418544 41728112 6685680
21:04:33 11984 8192000000 10278725872 10714267648 1347656704 14.126 99 23 46 3 1379303664 25334224 6312736
21:05:56 16572 8192000000 10276677664 10708168704 1350975568 14.156 99 40 81 2 1377010320 16191168 9843584
21:07:14 39582 8192000000 10284791696 10741469184 1376589056 14.386 100 9 18 0 1397515408 18676512 2249840
21:08:58 102273 8192000000 10303563664 10748260352 1378425952 14.402 99 31 62 0 1398015184 12559168 7030064
21:11:51 93856 8192000000 10287279136 10736422912 1359463376 14.233 98 17 34 0 1375118400 12103408 3551616
21:14:26 31440 8192000000 10296823904 10748551168 1390930128 14.514 100 11 22 1 1409465840 16091104 2444608
21:15:58 11158 8192000000 10303236944 10742583296 1391944048 14.523 100 28 52 1 1407216192 9618752 5653392
21:17:20 45625 8192000000 10322832784 10771365888 1405858608 14.647 100 19 38 0 1430548112 19890864 4798640
21:19:23 60849 8192000000 10327517104 10794278912 1409901808 14.683 100 15 30 2 1442966128 29206720 3857600
21:21:43 82517 8192000000 10316389680 10778636288 1407073680 14.658 100 13 26 2 1437662992 27376768 3212544
21:25:27 41669 8192000000 10306466640 10772258816 1408633664 14.672 100 17 35 0 1426294736 13351008 4310064
21:27:20 87885 8192000000 10302841760 10768367616 1395648144 14.556 99 8 16 0 1407359776 10102912 1608720
21:30:02 58867 8192000000 10304360640 10754355200 1385943872 14.470 99 17 32 2 1399006512 9296480 3766160
21:32:01 87810 8192000000 10305724544 10755280896 1393303984 14.535 99 20 40 0 1410346272 11817024 5225264
21:34:37 22142 8192000000 10302294976 10751643648 1390544016 14.511 99 33 67 0 1406335008 7690128 8100864
21:36:00 59741 8192000000 10272373520 10735779840 1366968608 14.300 98 15 30 0 1382669152 11716352 3984192
21:38:03 68316 8192000000 10259833712 10722115584 1320054512 13.877 95 13 26 0 1330693328 8049136 2589680
21:40:11 34480 8192000000 10282600976 10735616000 1382254816 14.437 100 33 66 0 1396457984 6424592 7778576
21:41:51 42404 8192000000 10273829408 10725892096 1342556960 14.080 97 22 44 0 1356782336 9343312 4882064
21:43:36 18485 8192000000 10280879968 10729869312 1372075552 14.346 99 30 59 2 1388276144 9035856 7164736
21:44:57 49343 8192000000 10271866128 10725822464 1322795488 13.902 96 22 44 1 1342650528 14527920 5327120
21:46:50 125661 8192000000 10288813168 10759520256 1320526544 13.881 95 24 48 1 1351305136 24954496 5824096
21:50:06 124748 8192000000 10279310144 10725900288 1302320976 13.716 94 23 45 1 1320519648 12777856 5420816
21:53:17 89620 8192000000 10275420688 10708959232 1331685360 13.982 97 36 72 1 1348658720 8226816 8746544
21:55:48 50051 8192000000 10297808784 10734182400 1347800192 14.128 98 40 79 1 1368651584 12495328 8356064
21:57:40 32150 8192000000 10287768736 10730123264 1367902384 14.308 99 15 30 2 1386717984 15541344 3274256
21:59:23 58462 8192000000 10262587872 10716463104 1350594704 14.153 99 9 18 0 1364494480 11817888 2081888
22:01:27 48863 8192000000 10265235440 10721452032 1361359344 14.250 99 7 14 2 1375423088 12369840 1693904
22:03:23 40438 8192000000 10271395056 10725879808 1368663856 14.315 100 12 24 1 1379450096 8076320 2709920
22:06:15 31944 8192000000 10295196640 10736840704 1380325088 14.419 100 43 85 1 1398825984 7856368 10644528
22:07:47 46124 8192000000 10291118496 10738266112 1384534112 14.457 100 13 26 0 1394888256 7525104 2829040
22:09:36 104574 8192000000 10319534992 10779926528 1394518928 14.546 99 12 24 0 1413379776 16262320 2598528
22:12:20 79697 8192000000 10332102736 10784600064 1413280784 14.713 100 13 26 0 1429484800 13666256 2537760
22:14:42 57154 8192000000 10327706480 10791317504 1402672544 14.619 99 19 38 1 1422589968 15673440 4243984
22:16:43 64783 8192000000 10301083616 10769895424 1316408576 13.844 93 5 10 0 1334770688 17265200 1096912
22:19:04 103364 8192000000 10309498784 10745425920 1388143328 14.489 100 30 60 1 1403548640 8895184 6510128
22:21:54 62742 8192000000 10302048624 10744516608 1378679328 14.405 99 34 68 0 1394962432 8856816 7426288
22:24:49 49513 8192000000 10279398576 10739941376 1378718320 14.405 100 10 19 0 1391374336 10135776 2520240
22:26:40 49667 8192000000 10267514176 10724753408 1343139280 14.086 97 14 29 0 1353782064 7748864 2893920
22:28:30 67777 8192000000 10247917424 10708180992 1345976192 14.111 98 11 23 0 1360603840 11932528 2695120
22:31:24 46674 8192000000 10258888064 10706599936 1325835984 13.930 97 19 38 3 1341287808 10676112 4775712
22:33:15 56542 8192000000 10265521216 10712367104 1330703808 13.974 97 26 52 1 1345295392 8652576 5939008
22:35:27 24489 8192000000 10268069568 10705088512 1343713040 14.091 99 38 76 1 1361803152 9020832 9069280
22:36:59 39920 8192000000 10254043568 10702843904 1321111488 13.887 97 20 40 0 1339168512 13355440 4701584

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-10-2021 22:38:58 to 26-10-2021 22:39:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 110 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-10-2021 22:39:14 to 26-10-2021 22: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          247153
MON$OLDEST_ACTIVE               247154
MON$OLDEST_SNAPSHOT             247154
MON$NEXT_TRANSACTION            247154
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                       2025056
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.33524 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33524 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33524 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33524 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 ---
:320: 
:321: mscs-test-161.budzdorov.ru    Tue Oct 26 22:38:04 2021
:322:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:323: 
--- end of diff output ---

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

26.10.21 22: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-10-26 22:39:20 No severe PSQL-related problems occured
26.10.21 22:39:21. End of report.