Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.10.2020 07:27
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. 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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 17081520 kB
MemAvailable 31526216 kB
Buffers 0 kB
Cached 14551836 kB
SwapCached 5432 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 4.0.0
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 2020-10-18 07:26:50
DTS_END                         2020-10-18 07:26:50.5190
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/52902
TRN_ID                          231166
ATT_ID                          641
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: 8677.93 23154 1041352
customer order: creation 167.87 405 20144
customer order: refuse 14.33 369 1719
order to supplier: creation 43.94 1396 5273
order to supplier: removal 3.35 114499 402
invoice (draft): creation 44.58 3539 5349
invoice (draft): removal 48.45 928 5814
invoice accept: apply 40.66 16833 4879
invoice accept: cancel 43.18 890 5182
customer reserve: creation 3724.53 165 446944
customer reserve: removal 4268.03 70 512164
realization accept: apply 79.96 184 9595
realization accept: cancel 74.87 118 8984
payment from customer: creation 58.48 110 7017
payment from customer: removal 5.29 75 635
payment to supplier: creation 46.51 122 5581
payment to supplier: removal 3.26 75 391
service: total inventory turnovers 3.97 3095 476
service: total monetary turnovers 5.82 581 698
service: aggregate perf. data 0.84 14686 101
service: refresh index statistics 0.03 304933 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-10-2020 07:27:40 to 18-10-2020 07:27:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4545
TEST_TIME 2 3352
TEST_TIME 3 4853
TEST_TIME 4 5072
TEST_TIME 5 4834
TEST_TIME 6 4921
TEST_TIME 7 5348
TEST_TIME 8 5346
TEST_TIME 9 5319
TEST_TIME 10 5320
TEST_TIME 11 5476
TEST_TIME 12 5515
TEST_TIME 13 5826
TEST_TIME 14 6524
TEST_TIME 15 6761
TEST_TIME 16 6955
TEST_TIME 17 7614
TEST_TIME 18 8083
TEST_TIME 19 8185
TEST_TIME 20 8172
TEST_TIME 21 8372
TEST_TIME 22 9081
TEST_TIME 23 9807
TEST_TIME 24 9828
TEST_TIME 25 10159
TEST_TIME 26 9962
TEST_TIME 27 9899
TEST_TIME 28 9863
TEST_TIME 29 9703
TEST_TIME 30 9708
TEST_TIME 31 9604
TEST_TIME 32 9514
TEST_TIME 33 9368
TEST_TIME 34 9254
TEST_TIME 35 9562
TEST_TIME 36 9839
TEST_TIME 37 9714
TEST_TIME 38 9642
TEST_TIME 39 9658
TEST_TIME 40 9655
TEST_TIME 41 9571
TEST_TIME 42 9655
TEST_TIME 43 9619
TEST_TIME 44 9497
TEST_TIME 45 9295
TEST_TIME 46 9155
TEST_TIME 47 9098
TEST_TIME 48 9227
TEST_TIME 49 9155
TEST_TIME 50 9081
TEST_TIME 51 9035
TEST_TIME 52 9034
TEST_TIME 53 8957
TEST_TIME 54 9019
TEST_TIME 55 8900
TEST_TIME 56 8949
TEST_TIME 57 8884
TEST_TIME 58 9007
TEST_TIME 59 8958
TEST_TIME 60 9010
TEST_TIME 61 9112
TEST_TIME 62 9109
TEST_TIME 63 9162
TEST_TIME 64 9138
TEST_TIME 65 9189
TEST_TIME 66 9201
TEST_TIME 67 9198
TEST_TIME 68 9243
TEST_TIME 69 9236
TEST_TIME 70 9268
TEST_TIME 71 9155
TEST_TIME 72 9056
TEST_TIME 73 9038
TEST_TIME 74 8957
TEST_TIME 75 8918
TEST_TIME 76 8864
TEST_TIME 77 8804
TEST_TIME 78 8781
TEST_TIME 79 8813
TEST_TIME 80 8811
TEST_TIME 81 8825
TEST_TIME 82 8803
TEST_TIME 83 8802
TEST_TIME 84 8886
TEST_TIME 85 8896
TEST_TIME 86 8896
TEST_TIME 87 8904
TEST_TIME 88 8946
TEST_TIME 89 8920
TEST_TIME 90 9016
TEST_TIME 91 8921
TEST_TIME 92 8863
TEST_TIME 93 8809
TEST_TIME 94 8774
TEST_TIME 95 8767
TEST_TIME 96 8760
TEST_TIME 97 8715
TEST_TIME 98 8727
TEST_TIME 99 8793
TEST_TIME 100 8762
TEST_TIME 101 8745
TEST_TIME 102 8742
TEST_TIME 103 8792
TEST_TIME 104 8756
TEST_TIME 105 8778
TEST_TIME 106 8856
TEST_TIME 107 8844
TEST_TIME 108 8839
TEST_TIME 109 8761
TEST_TIME 110 8719
TEST_TIME 111 8671
TEST_TIME 112 8671
TEST_TIME 113 8656
TEST_TIME 114 8619
TEST_TIME 115 8620
TEST_TIME 116 8603
TEST_TIME 117 8589
TEST_TIME 118 8691
TEST_TIME 119 8684
TEST_TIME 120 8678

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-10-2020 07:27:43 to 18-10-2020 07:27:47.

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 1454239 1454239 0 0.00 0 20988 7 0 0
doc_list_biud 1454239 1454239 0 0.00 0 32237 31 0 0
sp_add_invoice_to_stock 5025 4879 146 2.90 25 176142 16834 0 146
sp_cancel_adding_invoice 5182 5182 0 0.00 6 66803 890 0 0
sp_cancel_client_order 1719 1719 0 0.00 5 11261 369 0 0
sp_cancel_customer_reserve 512164 512164 0 0.00 1 32243 71 0 0
sp_cancel_pay_from_customer 635 635 0 0.00 3 1975 75 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 4152 75 0 0
sp_cancel_supplier_invoice 5821 5814 7 0.12 2 21593 929 0 7
sp_cancel_supplier_order 402 402 0 0.00 7 1167911 114500 0 0
sp_cancel_write_off 8984 8984 0 0.00 1 7742 118 0 0
sp_client_order 20144 20144 0 0.00 4 17110 405 0 0
sp_customer_reserve 446944 446944 0 0.00 0 143272 165 0 0
sp_fill_shopping_cart 30759 30759 0 0.00 1 14467 48 0 0
sp_fill_shopping_cart_clo_res 446939 446939 0 0.00 0 30708 17 0 0
sp_get_clo_for_invoice 4874 4874 0 0.00 0 47371 418 0 0
sp_kill_cost_storno 15192 15192 0 0.00 0 10591 60 0 0
sp_kill_qstorno_ret_qs2qd 1039528 1039528 0 0.00 0 32235 38 0 0
sp_kill_qty_storno 548730 548730 0 0.00 0 32237 82 0 0
sp_lock_selected_doc 20058 20058 0 0.00 0 11057 8 0 0
sp_make_cost_storno 27063 27063 0 0.00 0 16060 51 0 0
sp_multiply_rows_for_pdistr 27063 27063 0 0.00 0 9976 32 0 0
sp_multiply_rows_for_qdistr 20144 20144 0 0.00 0 12050 218 0 0
sp_pay_from_customer 7017 7017 0 0.00 4 16039 111 0 0
sp_pay_to_supplier 5581 5581 0 0.00 5 16853 122 0 0
sp_payment_common 12598 12598 0 0.00 1 9830 21 0 0
sp_qd_handle_on_cancel_clo 1719 1719 0 0.00 0 11254 326 0 0
sp_qd_handle_on_invoice_upd_sts 10052 10052 0 0.00 0 29683 396 0 0
sp_qd_handle_on_reserve_upd_sts 9595 9595 0 0.00 0 14055 55 0 0
sp_reserve_write_off 9595 9595 0 0.00 7 27260 184 0 0
sp_supplier_invoice 5349 5349 0 0.00 6 127130 3540 0 0
sp_supplier_order 5273 5273 0 0.00 5 77560 1396 0 0
srv_aggregate_perf_data 101 101 0 0.00 2581 163860 14687 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 2049 160442 51009 0 0
srv_find_qd_qs_mism 994676 994676 0 0.00 0 15218 6 0 0
srv_make_invnt_saldo 476 476 0 0.00 43 98857 3096 0 0
srv_make_money_saldo 698 698 0 0.00 1 15594 582 0 0
srv_recalc_idx_stat 4 4 0 0.00 177510 407467 304933 0 0
t$perf-abend:sp_add_invoice_to_stock 146 146 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4879 4879 0 0.00 0 8431 152 0 0
t$perf-norm:sp_cancel_adding_invoice 376 376 0 0.00 0 3070 66 0 0
t$perf-norm:sp_cancel_client_order 1719 1719 0 0.00 0 1671 8 0 0
t$perf-norm:sp_cancel_customer_reserve 623 623 0 0.00 0 1091 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 635 635 0 0.00 0 1235 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 440 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 466 9 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 17339 1464 0 0
t$perf-norm:sp_cancel_write_off 1022 1022 0 0.00 0 1606 4 0 0
t$perf-norm:sp_client_order 20144 20144 0 0.00 0 8515 17 0 0
t$perf-norm:sp_customer_reserve 969 969 0 0.00 0 1459 5 0 0
t$perf-norm:sp_pay_from_customer 7017 7017 0 0.00 0 3274 6 0 0
t$perf-norm:sp_pay_to_supplier 5581 5581 0 0.00 0 2252 7 0 0
t$perf-norm:sp_reserve_write_off 9595 9595 0 0.00 0 5383 8 0 0
t$perf-norm:sp_supplier_invoice 5349 5349 0 0.00 0 6068 19 0 0
t$perf-norm:sp_supplier_order 5273 5273 0 0.00 0 4907 13 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 184 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 152 7 0 0
t$perf-norm:srv_make_invnt_saldo 476 476 0 0.00 0 450 5 0 0
t$perf-norm:srv_make_money_saldo 698 698 0 0.00 0 606 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 2 0 0
v_add_invoice_to_stock 10032 9740 292 2.91 0 5403 16 0 292
v_all_customers 36846 36846 0 0.00 0 27 1 0 0
v_all_suppliers 29282 29282 0 0.00 0 20 1 0 0
v_all_wares 40288 40288 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 752 752 0 0.00 0 1381 9 0 0
v_cancel_client_order 3438 3438 0 0.00 0 4327 6 0 0
v_cancel_customer_prepayment 1270 1270 0 0.00 0 1951 8 0 0
v_cancel_customer_reserve 1246 1246 0 0.00 0 399 3 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 2101 7 0 0
v_cancel_supplier_invoice 864 850 14 1.62 0 946 13 0 14
v_cancel_supplier_order 804 804 0 0.00 0 3042 11 0 0
v_cancel_write_off 2044 2044 0 0.00 0 2166 14 0 0
v_max_id_clo_ord 5273 5273 0 0.00 0 630 1 0 0
v_max_id_clo_res 969 969 0 0.00 0 2367 15 0 0
v_max_id_ord_sup 5342 5342 0 0.00 0 8106 6 0 0
v_max_non_paid_invoice 1555 1555 0 0.00 0 553 2 0 0
v_max_non_paid_realizn 3661 3661 0 0.00 0 1493 3 0 0
v_min_id_clo_ord 5273 5273 0 0.00 0 4341 7 0 0
v_min_id_clo_res 969 969 0 0.00 0 2073 8 0 0
v_min_id_ord_sup 5342 5342 0 0.00 0 9516 9 0 0
v_min_non_paid_invoice 5581 5581 0 0.00 0 8953 11 0 0
v_min_non_paid_realizn 7017 7017 0 0.00 0 9828 17 0 0
v_our_firm 10154 10154 0 0.00 0 16 1 0 0
v_reserve_write_off 19190 19190 0 0.00 0 4348 5 0 0
x_lock_depdocs_on_canc_invoice 10571 10571 0 0.00 0 33807 148 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 10610 375 0 0
x_make_qstorno_1000_1200 5273 5273 0 0.00 2 77553 1324 0 0
x_make_qstorno_1200_2000 5349 5349 0 0.00 3 127118 3336 0 0
x_make_qstorno_2100_3300 446944 446944 0 0.00 0 143271 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-10-2020 07:27:48 to 18-10-2020 07:27:52.

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
04:56:53 2 8192000000 8796624000 8807108608 3156752 0.038 1 1 2 0 3254112 61504 35856
04:57:54 20290 8192000000 9725345264 10024001536 809564624 8.993 100 88 176 0 838488256 7645120 21278512
04:59:24 32888 8192000000 9769323888 10080141312 845043584 9.350 100 82 163 0 877701408 13261392 19396432
05:01:01 13386 8192000000 9819338464 10139541504 882330064 9.723 100 91 182 2 926090960 23387680 20373216
05:02:22 5589 8192000000 9874141952 10204639232 917344912 10.070 100 85 170 0 987508736 51693568 18470256
05:03:31 8194 8192000000 9925413456 10267996160 955347840 10.443 100 82 164 0 1033807552 60873696 17586016
05:04:43 5031 8192000000 9969533600 10319835136 988272960 10.765 100 84 168 0 1084491648 79137280 17081408
05:05:52 3480 8192000000 10006966416 10365325312 1014268736 11.017 100 87 174 0 1114457360 83138896 17049728
05:06:57 2364 8192000000 10034151936 10396413952 1033830928 11.205 100 91 182 0 1148570176 97055984 17683264
05:08:01 2005 8192000000 10025881696 10394746880 1055676880 11.415 100 89 178 1 1136223472 62520736 18025856
05:09:03 3892 8192000000 10007774880 10394816512 1063107216 11.486 100 50 101 2 1120768304 47826432 9834656
05:10:09 5479 8192000000 9986637056 10376429568 1070110048 11.553 100 41 77 4 1108263584 30438224 7715312
05:11:15 56134 8192000000 9999755872 10386079744 1078025552 11.629 100 51 102 2 1103221392 13777536 11418304
05:13:11 5251 8192000000 10001705232 10403676160 1084632496 11.692 100 33 66 1 1120179024 28042528 7504000
05:14:20 9680 8192000000 10011507888 10421698560 1093403216 11.775 100 31 60 0 1125792704 25599728 6789760
05:15:31 9994 8192000000 10007095056 10411679744 1071828176 11.570 98 27 54 0 1092030576 13934688 6267712
05:16:41 34664 8192000000 9993408176 10396450816 1092891984 11.770 100 52 103 3 1113153712 8861728 11400000
05:18:16 6048 8192000000 9973740992 10372620288 1053978128 11.399 98 43 89 2 1078216368 14095424 10142816
05:19:23 7774 8192000000 9958243872 10347601920 997496256 10.854 95 36 74 2 1020047936 14663872 7887808
05:20:33 5737 8192000000 9925314368 10292240384 966420848 10.552 94 39 76 2 995169040 19950336 8797856
05:21:39 53844 8192000000 9903207984 10287992832 932090016 10.215 92 34 68 0 953370432 13752160 7528256
05:23:36 5081 8192000000 9961003552 10342006784 1036176848 11.228 100 40 78 2 1059758048 15112256 8468944
05:24:44 11288 8192000000 9966806000 10362945536 1047552352 11.337 100 48 96 3 1072834608 13706832 11575424
05:25:57 8503 8192000000 9976730016 10373509120 1056641952 11.424 100 32 63 4 1083162256 19531312 6988992
05:27:08 59916 8192000000 9982027920 10381713408 1079835408 11.646 100 34 65 2 1101998720 15682496 6480816
05:29:19 3376 8192000000 10061534928 10455322624 1103400512 11.870 100 58 114 2 1170164560 54910176 11853872
05:30:28 19832 8192000000 10105630384 10505170944 1122689024 12.052 100 51 101 1 1219280032 85917872 10673136
05:31:50 12789 8192000000 10133561280 10542198784 1135453648 12.173 100 47 94 0 1240601760 94023424 11124688
05:33:19 15447 8192000000 10190394880 10594344960 1148797152 12.298 100 54 108 2 1297445792 136104512 12544128
05:34:42 12102 8192000000 10224689568 10633166848 1166930928 12.468 100 53 104 1 1330550176 152266336 11352912
05:35:58 4605 8192000000 10278757120 10683047936 1174358144 12.538 100 64 128 0 1381621264 193130640 14132480
05:37:20 5507 8192000000 10298379568 10713153536 1180912512 12.599 100 53 106 1 1408153616 213435408 13805696
05:38:30 6332 8192000000 10351873360 10760908800 1168180208 12.480 100 65 129 0 1450889376 266998704 15710464
05:39:42 2283 8192000000 10374621232 10790248448 1173149936 12.526 100 65 130 2 1471111200 282616400 15344864
05:40:47 2047 8192000000 10387962000 10794778624 1165955568 12.459 100 76 151 1 1480424752 296181872 18287312
05:41:57 3779 8192000000 10370195712 10776535040 1166137856 12.461 100 73 146 1 1480595632 297474112 16983664
05:43:10 2361 8192000000 10354183680 10808766464 1161812832 12.420 99 73 145 0 1443734304 263992688 17928784
05:44:18 8299 8192000000 10360541664 10787094528 1148397024 12.294 99 54 107 0 1463287632 301504288 13386320
05:45:32 2230 8192000000 10373247792 10773299200 1154740928 12.354 99 87 169 2 1464204800 289929552 19534320
05:46:36 10997 8192000000 10370736144 10801377280 1134177392 12.161 99 41 82 0 1458835552 313857760 10800400
05:47:56 14670 8192000000 10318346048 10783428608 1119374704 12.021 99 36 72 0 1457451104 328003104 10073296
05:49:13 33035 8192000000 10186620048 10669826048 1111969520 11.951 99 23 46 0 1339215136 220805392 6440224
05:50:48 10527 8192000000 10129260160 10576728064 1127011712 12.093 100 23 46 3 1241074800 107940016 6123072
05:52:00 57561 8192000000 10077534016 10500919296 1097431104 11.813 99 48 96 3 1178712624 71991376 9290144
05:54:01 102053 8192000000 10110438928 10568523776 1084960416 11.695 96 14 28 2 1176754528 88402016 3392096
05:57:11 104173 8192000000 10081643232 10515218432 1053034320 11.390 93 30 60 4 1108831984 48505760 7291904
06:00:23 26523 8192000000 10173618640 10601648128 1110170480 11.934 99 27 54 0 1246720736 129923344 6626912
06:01:51 84824 8192000000 10068477696 10561183744 1084507632 11.690 97 11 21 1 1197630928 110221168 2902128
06:04:16 61817 8192000000 10084131344 10536513536 1128523040 12.107 100 20 40 0 1163121200 30632992 3965168
06:06:21 62086 8192000000 10060357280 10491666432 1111644992 11.948 98 14 27 0 1147252928 32789248 2818688
06:08:27 124399 8192000000 10106699248 10553802752 1088997536 11.733 96 14 28 0 1131898432 40257776 2643120
06:11:36 160442 8192000000 10089886448 10570752000 1128667440 12.109 99 35 70 1 1211444160 74400288 8376432
06:15:23 118140 8192000000 10143169536 10593939456 1143319136 12.247 100 23 46 2 1223128240 74558160 5250944
06:18:33 67171 8192000000 10102983264 10539409408 1117200528 12.001 98 16 32 0 1202277552 81629632 3447392
06:20:46 39800 8192000000 10069727328 10507624448 1125220576 12.076 100 15 30 1 1174017104 45859472 2937056
06:22:32 79056 8192000000 10102404272 10546593792 1118141216 12.009 98 14 28 2 1181486688 60726144 2619328
06:24:56 26936 8192000000 10087665744 10519048192 1120411936 12.031 98 31 62 0 1157946224 30902320 6631968
06:26:27 65895 8192000000 10131958032 10587979776 1119352944 12.021 98 15 30 1 1195598448 73403200 2842304
06:28:38 73171 8192000000 10074052400 10533998592 1129360240 12.115 99 14 29 0 1202883744 70930096 2593408
06:30:52 60791 8192000000 10064267408 10521088000 1084895232 11.694 95 25 50 3 1156718544 66357664 5465648
06:32:54 26262 8192000000 10056080544 10481586176 1104205280 11.878 98 22 44 3 1135242272 26507840 4529152
06:34:23 67165 8192000000 10071081008 10549997568 1100391840 11.841 97 23 45 3 1150500432 45341008 4767584
06:36:42 80774 8192000000 10038689968 10490494976 1100968208 11.847 98 19 39 0 1138710528 33882064 3860256
06:39:10 33379 8192000000 10074502144 10523344896 1113347776 11.964 99 29 58 2 1151567440 32396464 5823200
06:41:46 77052 8192000000 10050257616 10482069504 1084807360 11.693 98 16 31 0 1153059616 65222336 3029920
06:44:07 84739 8192000000 10054878896 10544701440 1064606560 11.501 96 15 31 0 1154701120 86702096 3392464
06:46:33 66892 8192000000 10059883360 10502737920 1037368144 11.239 94 30 60 0 1108903568 64955888 6579536
06:48:46 71765 8192000000 10106381504 10543894528 1106825840 11.902 99 25 50 1 1198375104 86347376 5201888
06:51:02 65764 8192000000 10062330560 10503348224 1095907648 11.799 98 25 50 1 1143795072 42713744 5173680
06:53:09 42168 8192000000 10064595888 10477207552 1089504288 11.738 98 23 46 2 1148516624 54348224 4664112
06:54:52 32481 8192000000 10059888432 10491035648 1079583216 11.644 97 33 66 1 1122824224 36381152 6859856
06:56:30 52283 8192000000 10065460240 10572156928 1117981936 12.008 100 8 16 0 1173368512 53625216 1761360
06:58:50 64083 8192000000 10064098688 10514182144 1129166912 12.114 100 14 28 0 1168350688 36822000 2361776
07:02:51 44645 8192000000 10096467232 10522849280 1104191200 11.877 97 20 40 1 1147698480 39385712 4121568
07:04:50 80295 8192000000 10057945856 10528321536 1114771408 11.978 98 15 30 0 1180966736 62889472 3305856
07:07:26 17193 8192000000 10090189328 10508783616 1136205120 12.180 100 48 96 4 1174931344 29030320 9695904
07:08:51 103705 8192000000 10078323280 10514386944 1111515712 11.947 97 21 42 0 1153469344 37067504 4886128
07:11:44 61029 8192000000 10076181408 10573107200 1110029632 11.933 97 12 24 0 1179308256 66778128 2500496
07:13:46 128187 8192000000 10095432976 10551410688 1134217872 12.161 99 19 38 1 1189427376 50770496 4439008
07:16:54 58194 8192000000 10095280496 10560782336 1139960656 12.215 99 10 19 2 1198119456 55527920 2630880
07:19:27 98988 8192000000 10108365648 10572394496 1099456080 11.832 96 16 32 2 1161887408 59296560 3134768
07:22:13 63896 8192000000 10138992880 10574700544 1138753872 12.204 100 21 42 0 1215681072 72497280 4429920
07:24:23 57746 8192000000 10066729296 10495750144 1078414160 11.632 95 14 28 0 1125419952 44067312 2938480
07:26:22 36805 8192000000 9098615360 9348579328 239961728 2.845 22 8 14 1 268185904 26814624 1409552

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-10-2020 07:27:53 to 18-10-2020 07:28:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 146 v_add_invoice_to_stock 335544517
user_exc 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-10-2020 07:28:09 to 18-10-2020 07:28:11.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          229891
MON$OLDEST_ACTIVE               231456
MON$OLDEST_SNAPSHOT             231456
MON$NEXT_TRANSACTION            231456
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-30 07:30:08.6030 GMT
MON$PAGES                       2080672
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             772
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2226 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2226 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2226 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2226 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.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 ---
:162: 
:163: mscs-test-161.budzdorov.ru    Sun Oct 18 07:27:01 2020
:164:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:165: 
--- end of diff output ---

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

18.10.20 07:28:12. There are 3 logs in /var/tmp/logs-oltp40 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
2020-10-18 07:28:14 No severe PSQL-related problems occured
18.10.20 07:28:15. End of report.