Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.10.2021 19:39
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 16053676 kB
MemAvailable 30690744 kB
Buffers 0 kB
Cached 15667972 kB
SwapCached 3780 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-14 19:37:51
DTS_END                         2021-10-14 19:37:51.9820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/41542
TRN_ID                          236383
ATT_ID                          622
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: 8959.76 23133 1075171
customer order: creation 165.17 376 19820
customer order: refuse 14.17 360 1700
order to supplier: creation 43.04 1283 5165
order to supplier: removal 3.20 108189 384
invoice (draft): creation 44.31 3414 5317
invoice (draft): removal 52.78 758 6334
invoice accept: apply 39.07 18644 4688
invoice accept: cancel 48.09 819 5771
customer reserve: creation 3564.53 187 427744
customer reserve: removal 4694.28 59 563314
realization accept: apply 79.87 173 9584
realization accept: cancel 87.10 110 10452
payment from customer: creation 56.83 103 6820
payment from customer: removal 5.40 77 648
payment to supplier: creation 46.90 133 5628
payment to supplier: removal 3.60 99 432
service: total inventory turnovers 4.45 1188 534
service: total monetary turnovers 6.12 37 734
service: aggregate perf. data 0.82 24512 98
service: refresh index statistics 0.03 302140 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-10-2021 19:39:25 to 14-10-2021 19:39:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3069
TEST_TIME 2 4924
TEST_TIME 3 5480
TEST_TIME 4 4878
TEST_TIME 5 5162
TEST_TIME 6 5395
TEST_TIME 7 5403
TEST_TIME 8 5412
TEST_TIME 9 5283
TEST_TIME 10 5136
TEST_TIME 11 5265
TEST_TIME 12 5091
TEST_TIME 13 5084
TEST_TIME 14 5456
TEST_TIME 15 5621
TEST_TIME 16 5966
TEST_TIME 17 6274
TEST_TIME 18 6355
TEST_TIME 19 6836
TEST_TIME 20 7505
TEST_TIME 21 7526
TEST_TIME 22 8192
TEST_TIME 23 8219
TEST_TIME 24 8155
TEST_TIME 25 9088
TEST_TIME 26 9558
TEST_TIME 27 10001
TEST_TIME 28 10080
TEST_TIME 29 10072
TEST_TIME 30 9772
TEST_TIME 31 9713
TEST_TIME 32 9808
TEST_TIME 33 9680
TEST_TIME 34 9732
TEST_TIME 35 9628
TEST_TIME 36 9498
TEST_TIME 37 9465
TEST_TIME 38 9330
TEST_TIME 39 9335
TEST_TIME 40 9115
TEST_TIME 41 8943
TEST_TIME 42 8942
TEST_TIME 43 8980
TEST_TIME 44 8955
TEST_TIME 45 8866
TEST_TIME 46 8834
TEST_TIME 47 8752
TEST_TIME 48 8858
TEST_TIME 49 8882
TEST_TIME 50 9157
TEST_TIME 51 9140
TEST_TIME 52 9177
TEST_TIME 53 9219
TEST_TIME 54 9157
TEST_TIME 55 9275
TEST_TIME 56 9230
TEST_TIME 57 9299
TEST_TIME 58 9316
TEST_TIME 59 9294
TEST_TIME 60 9225
TEST_TIME 61 9106
TEST_TIME 62 9076
TEST_TIME 63 9074
TEST_TIME 64 9019
TEST_TIME 65 8965
TEST_TIME 66 8885
TEST_TIME 67 8825
TEST_TIME 68 8821
TEST_TIME 69 8778
TEST_TIME 70 8733
TEST_TIME 71 8741
TEST_TIME 72 8714
TEST_TIME 73 8826
TEST_TIME 74 8887
TEST_TIME 75 8922
TEST_TIME 76 8864
TEST_TIME 77 8935
TEST_TIME 78 8964
TEST_TIME 79 9066
TEST_TIME 80 9083
TEST_TIME 81 9157
TEST_TIME 82 9197
TEST_TIME 83 9138
TEST_TIME 84 9055
TEST_TIME 85 9043
TEST_TIME 86 9037
TEST_TIME 87 9067
TEST_TIME 88 9034
TEST_TIME 89 9002
TEST_TIME 90 8967
TEST_TIME 91 8951
TEST_TIME 92 8917
TEST_TIME 93 8932
TEST_TIME 94 8911
TEST_TIME 95 8934
TEST_TIME 96 8983
TEST_TIME 97 8969
TEST_TIME 98 9010
TEST_TIME 99 8974
TEST_TIME 100 9046
TEST_TIME 101 9060
TEST_TIME 102 9064
TEST_TIME 103 9036
TEST_TIME 104 9149
TEST_TIME 105 9132
TEST_TIME 106 9177
TEST_TIME 107 9222
TEST_TIME 108 9267
TEST_TIME 109 9186
TEST_TIME 110 9156
TEST_TIME 111 9127
TEST_TIME 112 9103
TEST_TIME 113 9107
TEST_TIME 114 9123
TEST_TIME 115 9102
TEST_TIME 116 9074
TEST_TIME 117 9035
TEST_TIME 118 9014
TEST_TIME 119 8994
TEST_TIME 120 8967

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-10-2021 19:39:29 to 14-10-2021 19:39:32.

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 1474126 1474126 0 0.00 0 20677 6 0 0
doc_list_biud 1474126 1474126 0 0.00 0 57944 28 0 0
sp_add_invoice_to_stock 4821 4688 133 2.75 44 186197 18645 0 133
sp_cancel_adding_invoice 5771 5771 0 0.00 5 85413 819 0 0
sp_cancel_client_order 1700 1700 0 0.00 5 10641 360 0 0
sp_cancel_customer_reserve 563314 563314 0 0.00 1 22716 59 0 0
sp_cancel_pay_from_customer 648 648 0 0.00 2 5767 78 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 3 10417 100 0 0
sp_cancel_supplier_invoice 6349 6334 15 0.23 2 46599 758 0 15
sp_cancel_supplier_order 384 384 0 0.00 8 1067105 108189 0 0
sp_cancel_write_off 10452 10452 0 0.00 1 22706 111 0 0
sp_client_order 19820 19820 0 0.00 3 35657 376 0 0
sp_customer_reserve 427744 427744 0 0.00 0 78690 187 0 0
sp_fill_shopping_cart 30293 30293 0 0.00 1 13325 39 0 0
sp_fill_shopping_cart_clo_res 427730 427730 0 0.00 0 14555 15 0 0
sp_get_clo_for_invoice 4668 4668 0 0.00 0 25307 345 0 0
sp_kill_cost_storno 17303 17303 0 0.00 0 8486 54 0 0
sp_kill_qstorno_ret_qs2qd 1143798 1143798 0 0.00 0 46595 32 0 0
sp_kill_qty_storno 602206 602206 0 0.00 0 57943 68 0 0
sp_lock_selected_doc 19979 19979 0 0.00 0 7924 7 0 0
sp_make_cost_storno 26699 26699 0 0.00 0 15058 50 0 0
sp_multiply_rows_for_pdistr 26699 26699 0 0.00 0 9253 29 0 0
sp_multiply_rows_for_qdistr 19820 19820 0 0.00 0 10958 174 0 0
sp_pay_from_customer 6820 6820 0 0.00 4 12317 103 0 0
sp_pay_to_supplier 5628 5628 0 0.00 5 15150 133 0 0
sp_payment_common 12448 12448 0 0.00 1 12198 24 0 0
sp_qd_handle_on_cancel_clo 1700 1700 0 0.00 0 10633 308 0 0
sp_qd_handle_on_invoice_upd_sts 10438 10438 0 0.00 0 57942 323 0 0
sp_qd_handle_on_reserve_upd_sts 9584 9584 0 0.00 0 9081 50 0 0
sp_reserve_write_off 9584 9584 0 0.00 6 12605 174 0 0
sp_supplier_invoice 5317 5317 0 0.00 6 79497 3414 0 0
sp_supplier_order 5165 5165 0 0.00 5 42613 1283 0 0
srv_aggregate_perf_data 98 98 0 0.00 2275 575642 24513 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1547 96742 44482 0 0
srv_find_qd_qs_mism 1031411 1031411 0 0.00 0 20675 5 0 0
srv_make_invnt_saldo 534 534 0 0.00 42 62370 1188 0 0
srv_make_money_saldo 734 734 0 0.00 1 6380 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 137094 462788 302141 0 0
t$perf-abend:sp_add_invoice_to_stock 133 133 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 4688 4688 0 0.00 0 19550 211 0 0
t$perf-norm:sp_cancel_adding_invoice 462 462 0 0.00 0 12384 102 0 0
t$perf-norm:sp_cancel_client_order 1700 1700 0 0.00 0 12223 18 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 1384 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 648 648 0 0.00 0 309 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 6490 29 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 454 9 0 0
t$perf-norm:sp_cancel_supplier_order 384 384 0 0.00 0 37784 1785 0 0
t$perf-norm:sp_cancel_write_off 1016 1016 0 0.00 0 6567 25 0 0
t$perf-norm:sp_client_order 19820 19820 0 0.00 0 10138 18 0 0
t$perf-norm:sp_customer_reserve 965 965 0 0.00 0 778 6 0 0
t$perf-norm:sp_pay_from_customer 6820 6820 0 0.00 0 11330 13 0 0
t$perf-norm:sp_pay_to_supplier 5628 5628 0 0.00 0 4910 13 0 0
t$perf-norm:sp_reserve_write_off 9584 9584 0 0.00 0 11570 13 0 0
t$perf-norm:sp_supplier_invoice 5317 5317 0 0.00 0 7314 21 0 0
t$perf-norm:sp_supplier_order 5165 5165 0 0.00 0 4948 15 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 306 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 118 3 0 0
t$perf-norm:srv_make_invnt_saldo 534 534 0 0.00 0 1090 18 0 0
t$perf-norm:srv_make_money_saldo 734 734 0 0.00 0 1574 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 9598 9332 266 2.77 0 4874 21 0 266
v_all_customers 36030 36030 0 0.00 0 17 0 0 0
v_all_suppliers 29006 29006 0 0.00 0 97 1 0 0
v_all_wares 39640 39640 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 924 924 0 0.00 0 2335 8 0 0
v_cancel_client_order 3400 3400 0 0.00 0 3374 11 0 0
v_cancel_customer_prepayment 1296 1296 0 0.00 0 2056 6 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 0 2329 5 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 7015 21 0 0
v_cancel_supplier_invoice 854 824 30 3.51 0 4706 30 0 30
v_cancel_supplier_order 768 768 0 0.00 0 837 4 0 0
v_cancel_write_off 2032 2032 0 0.00 0 3046 16 0 0
v_max_id_clo_ord 5161 5161 0 0.00 0 147 1 0 0
v_max_id_clo_res 965 965 0 0.00 0 4488 17 0 0
v_max_id_ord_sup 5312 5312 0 0.00 0 4672 3 0 0
v_max_non_paid_invoice 1598 1598 0 0.00 0 1576 3 0 0
v_max_non_paid_realizn 3573 3573 0 0.00 0 5068 5 0 0
v_min_id_clo_ord 5161 5161 0 0.00 0 6614 9 0 0
v_min_id_clo_res 965 965 0 0.00 0 528 2 0 0
v_min_id_ord_sup 5312 5312 0 0.00 0 5347 9 0 0
v_min_non_paid_invoice 5628 5628 0 0.00 0 10367 20 0 0
v_min_non_paid_realizn 6820 6820 0 0.00 0 7127 16 0 0
v_our_firm 10104 10104 0 0.00 0 159 1 0 0
v_reserve_write_off 19168 19168 0 0.00 0 4886 7 0 0
x_lock_depdocs_on_canc_invoice 11693 11693 0 0.00 0 10336 108 0 0
x_lock_depdocs_on_canc_sup_ord 384 384 0 0.00 0 7132 361 0 0
x_make_qstorno_1000_1200 5165 5165 0 0.00 2 42596 1205 0 0
x_make_qstorno_1200_2000 5317 5317 0 0.00 3 73616 3275 0 0
x_make_qstorno_2100_3300 427743 427743 0 0.00 0 78665 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-10-2021 19:39:33 to 14-10-2021 19:39:37.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:07:55 2 8192000000 8799031136 8810303488 5314912 0.064 2 1 2 0 5414512 56816 42784
17:08:56 9757 8192000000 9887244384 10197155840 979749088 10.682 100 86 172 0 1008116416 5362816 23004512
17:10:11 5749 8192000000 9941334784 10262937600 1029846592 11.167 100 87 174 0 1059243600 7057904 22339104
17:11:24 20756 8192000000 9991167648 10323890176 1071643920 11.568 100 89 178 0 1102702896 8832176 22226800
17:12:51 7332 8192000000 10040219536 10388758528 1130144192 12.123 100 80 160 0 1159397344 10284912 18968240
17:14:01 8117 8192000000 10081301936 10441416704 1169279440 12.490 100 77 154 0 1198083744 10951632 17852672
17:15:14 6723 8192000000 10126648464 10497712128 1215721216 12.922 100 70 136 3 1243344304 12981200 14641888
17:16:24 8074 8192000000 10167338176 10546298880 1255106896 13.285 100 81 162 0 1286397616 13679632 17611088
17:17:36 1495 8192000000 10198719200 10579374080 1279366432 13.507 100 89 178 2 1313161312 14364528 19430352
17:18:40 3891 8192000000 10209841568 10595364864 1292413856 13.626 100 86 170 4 1325091072 13162416 19514800
17:19:45 5574 8192000000 10186862432 10598711296 1299916784 13.694 100 41 82 3 1318787504 9190560 9680160
17:20:54 5890 8192000000 10188514432 10601521152 1304506032 13.736 100 30 60 1 1318115616 6728240 6881344
17:22:06 5865 8192000000 10213576400 10624462848 1314362512 13.826 100 26 54 0 1330420992 9902272 6156208
17:23:16 44922 8192000000 10222899056 10623836160 1307744176 13.766 99 68 138 0 1330389648 5199616 17445856
17:25:02 4019 8192000000 10224522672 10642837504 1324748784 13.920 100 29 57 0 1338403856 6863808 6791264
17:26:08 6842 8192000000 10221076656 10633863168 1302705232 13.720 98 20 40 0 1312992208 6048272 4238704
17:27:16 47980 8192000000 10194441632 10611638272 1263545536 13.363 96 31 62 0 1276513024 5613392 7354096
17:29:06 4621 8192000000 10179619168 10588344320 1288126864 13.587 100 35 70 3 1303475232 7202064 8146304
17:30:12 7992 8192000000 10149842368 10552233984 1217196272 12.936 96 17 34 2 1226682208 5361312 4124624
17:31:22 72874 8192000000 10138196736 10537558016 1203047520 12.805 95 37 74 0 1217301280 5159872 9093888
17:33:39 6996 8192000000 10181432880 10587013120 1286460592 13.572 100 20 40 0 1296145968 5067696 4617680
17:34:49 9121 8192000000 10189553984 10606800896 1300623088 13.701 100 19 38 1 1311021488 5724576 4673824
17:36:04 65799 8192000000 10215126800 10637373440 1321695888 13.892 100 28 53 4 1337108960 9282848 6130224
17:38:13 11626 8192000000 10253479552 10662629376 1344706752 14.100 100 54 108 1 1365099776 8038144 12354880
17:39:28 15481 8192000000 10264953088 10684235776 1359464080 14.233 100 56 108 1 1381701120 10506736 11730304
17:40:54 27577 8192000000 10291383376 10709381120 1375558352 14.377 100 58 116 2 1400658320 12290144 12809824
17:42:29 11664 8192000000 10312735840 10734333952 1393641328 14.538 100 57 114 1 1427419600 20060592 13717680
17:43:51 20998 8192000000 10321948672 10750349312 1403534288 14.626 100 46 88 2 1432988032 19101216 10352528
17:45:22 13889 8192000000 10332555424 10763403264 1411789584 14.700 100 41 82 1 1446575136 24981504 9804048
17:46:43 23950 8192000000 10341096592 10776559616 1418321232 14.758 100 40 80 2 1456605904 28796336 9488336
17:48:13 4519 8192000000 10349619728 10768134144 1410995072 14.693 100 70 139 0 1456618160 28148176 17474912
17:49:24 9704 8192000000 10340888480 10766581760 1394569504 14.547 98 50 100 1 1437050128 30578864 11901760
17:50:39 4510 8192000000 10377436288 10794348544 1424558976 14.813 99 69 138 1 1482340464 40484528 17296960
17:51:49 1546 8192000000 10371030928 10782543872 1422651824 14.796 100 85 164 0 1482140880 39085664 20403392
17:52:55 3429 8192000000 10376305424 10790854656 1416587200 14.742 99 74 148 0 1481867440 46631360 18648880
17:54:02 3844 8192000000 10365777056 10775609344 1388843664 14.496 99 79 155 1 1461706128 52792624 20069840
17:55:08 11260 8192000000 10364884256 10785177600 1404647664 14.636 100 56 112 0 1474261808 54643136 14971008
17:56:24 3762 8192000000 10384866512 10798649344 1395799488 14.558 99 71 142 1 1476363776 62046432 18517856
17:57:32 10844 8192000000 10355889488 10781032448 1394238448 14.544 99 49 98 0 1453021936 45383120 13400368
17:58:48 23714 8192000000 10348563792 10785218560 1397360896 14.571 100 33 66 1 1458163760 51352464 9450400
18:00:32 17639 8192000000 10302120032 10736033792 1348476144 14.134 98 26 52 1 1399097008 43626160 6994704
18:01:55 42066 8192000000 10300160576 10725699584 1318954208 13.867 97 25 50 2 1385635568 59629360 7052000
18:03:44 17003 8192000000 10283615360 10728865792 1341943200 14.075 99 18 36 2 1383951552 36807520 5200832
18:05:07 35118 8192000000 10267368256 10707431424 1341890144 14.074 98 16 32 1 1358667280 12042720 4734416
18:06:44 70532 8192000000 10272792192 10714894336 1363990880 14.273 100 35 70 0 1382654800 9852672 8811248
18:09:22 37382 8192000000 10270664320 10715521024 1359534064 14.233 99 25 48 1 1375184192 10043488 5606640
18:11:11 82647 8192000000 10271493296 10716696576 1359643600 14.234 99 8 16 0 1369333472 8309344 1380528
18:13:34 75539 8192000000 10282065136 10736648192 1368894512 14.317 99 17 34 1 1381740688 9168320 3677856
18:15:49 60979 8192000000 10277518208 10734129152 1370489552 14.331 99 30 60 0 1386752864 9028128 7235184
18:18:20 31138 8192000000 10295059568 10733105152 1371819264 14.343 99 54 108 0 1395065536 10097184 13149088
18:19:52 38449 8192000000 10284474272 10736173056 1367159168 14.302 99 16 32 1 1384411024 13154880 4096976
18:21:40 47987 8192000000 10306675360 10757505024 1391701952 14.521 100 20 40 0 1416554032 20187792 4664288
18:23:45 68050 8192000000 10322797680 10775543808 1405007968 14.640 100 21 42 0 1434071872 23474608 5589296
18:26:08 54961 8192000000 10307397488 10776186880 1392563264 14.529 99 12 24 1 1420016640 24525984 2927392
18:28:04 41836 8192000000 10305667936 10747281408 1371041552 14.336 98 23 46 1 1392168096 15858416 5268128
18:29:48 82319 8192000000 10306446272 10760540160 1376768784 14.388 98 18 36 0 1391762352 10701904 4291664
18:32:41 69494 8192000000 10315806816 10763784192 1400164864 14.596 100 28 56 0 1419494096 12918768 6410464
18:34:51 80837 8192000000 10327356176 10794434560 1407855008 14.665 100 16 32 1 1428317600 16956416 3506176
18:37:16 95192 8192000000 10311740960 10751012864 1363232496 14.266 97 30 60 1 1384737920 14308112 7197312
18:39:51 48714 8192000000 10305576064 10751279104 1358728736 14.226 97 25 50 0 1373908272 9601648 5577888
18:41:53 41610 8192000000 10264835760 10721046528 1368229552 14.311 100 15 30 0 1379101104 6955808 3915744
18:43:35 38557 8192000000 10266255104 10718896128 1359782384 14.235 99 12 25 2 1370637472 8051136 2803952
18:45:22 76882 8192000000 10261385552 10723434496 1320443616 13.881 96 6 12 1 1329521744 7806496 1271632
18:47:39 28122 8192000000 10273915536 10709991424 1355415648 14.196 100 29 58 2 1379086592 16539344 7131600
18:49:09 72268 8192000000 10275368384 10725785600 1317657680 13.855 96 14 28 1 1341090768 20059776 3373312
18:51:23 88327 8192000000 10250694256 10683281408 1229776528 13.052 90 19 38 1 1242384960 8730976 3877456
18:53:52 52250 8192000000 10267128208 10710306816 1323463456 13.908 98 38 76 1 1354317920 21872176 8982288
18:55:45 95447 8192000000 10264193984 10714501120 1326965344 13.940 98 14 28 0 1343184352 13051200 3167808
18:58:26 41673 8192000000 10283912464 10743058432 1340416016 14.061 98 16 32 0 1362989520 19237328 3336176
19:00:11 50330 8192000000 10295486960 10735353856 1368372848 14.312 99 58 116 0 1393995760 12478400 13144512
19:02:49 44240 8192000000 10276135680 10730688512 1372536784 14.350 100 27 55 2 1390514160 10737120 7240256
19:04:34 51548 8192000000 10261794992 10735165440 1352206736 14.167 98 7 14 0 1366142448 11987056 1948656
19:06:26 70708 8192000000 10245787040 10694594560 1309884320 13.785 96 13 26 0 1323250112 10119488 3246304
19:08:37 42332 8192000000 10271840208 10728595456 1366396160 14.295 99 17 34 0 1378736432 8582832 3757440
19:10:19 55553 8192000000 10271148112 10731712512 1367230864 14.302 99 8 16 1 1377772848 9037520 1504464
19:12:17 96742 8192000000 10297523072 10753445888 1363687760 14.270 98 15 30 0 1378980208 11629424 3663024
19:15:02 50779 8192000000 10305904512 10763878400 1386279840 14.473 99 25 50 1 1401586640 9775536 5531264
19:17:24 14329 8192000000 10326503328 10773078016 1392456256 14.528 99 51 102 1 1418761616 14816992 11488368
19:18:48 31795 8192000000 10308675552 10753990656 1384749472 14.459 99 28 56 1 1399355664 8761936 5844256
19:20:20 15094 8192000000 10324587328 10770698240 1393621536 14.538 99 42 84 0 1415159680 12400576 9137568
19:21:35 59164 8192000000 10294564288 10755293184 1365731472 14.289 98 21 42 0 1388372672 17765472 4875728
19:23:35 85729 8192000000 10323890304 10781589504 1407272832 14.660 100 20 41 0 1438064016 26377168 4414016
19:26:01 76829 8192000000 10308501504 10756644864 1384683712 14.458 99 8 16 0 1398488016 12123776 1680528
19:28:18 44248 8192000000 10315263264 10773311488 1395109888 14.551 100 31 62 1 1416279168 14332688 6836592
19:30:06 70137 8192000000 10295864080 10770034688 1384217552 14.454 99 11 22 0 1402367760 15300176 2850032
19:32:35 64412 8192000000 10252429872 10711113728 1317325152 13.852 95 12 22 1 1326332016 6086448 2920416
19:34:42 50201 8192000000 10262206352 10726678528 1338074000 14.040 97 10 20 0 1350812512 10694752 2043760
19:36:33 81742 8192000000 9457405888 9778081792 568826848 6.492 42 4 8 1 574034272 4376096 831328

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-10-2021 19:39:39 to 14-10-2021 19:39:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 133 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 14-10-2021 19:39:55 to 14-10-2021 19:39:57.

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          234768
MON$OLDEST_ACTIVE               236871
MON$OLDEST_SNAPSHOT             236871
MON$NEXT_TRANSACTION            236871
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                       2018432
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.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 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 ---
:319: 
:320: mscs-test-161.budzdorov.ru    Thu Oct 14 19:38:01 2021
:321:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:322: 
--- end of diff output ---

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

14.10.21 19:39:58. 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-14 19:40:00 No severe PSQL-related problems occured
14.10.21 19:40:01. End of report.