Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.01.2021 07:37
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 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 16703228 kB
MemAvailable 31253620 kB
Buffers 0 kB
Cached 14951056 kB
SwapCached 4868 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 2021-01-26 07:36:42
DTS_END                         2021-01-26 07:36:42.9390
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37680
TRN_ID                          225651
ATT_ID                          590
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: 8541.42 26032 1024971
customer order: creation 155.38 414 18646
customer order: refuse 13.53 307 1623
order to supplier: creation 40.80 1341 4896
order to supplier: removal 3.33 126446 400
invoice (draft): creation 40.34 3319 4841
invoice (draft): removal 48.71 964 5845
invoice accept: apply 38.08 17535 4570
invoice accept: cancel 44.26 851 5311
customer reserve: creation 3493.53 172 419224
customer reserve: removal 4395.54 77 527465
realization accept: apply 73.18 193 8782
realization accept: cancel 77.43 114 9291
payment from customer: creation 54.60 121 6552
payment from customer: removal 5.27 122 632
payment to supplier: creation 43.71 120 5245
payment to supplier: removal 3.39 76 407
service: total inventory turnovers 3.53 3345 423
service: total monetary turnovers 5.84 508 701
service: aggregate perf. data 0.94 15244 113
service: refresh index statistics 0.03 349362 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-01-2021 07:37:55 to 26-01-2021 07:37:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1308
TEST_TIME 2 1963
TEST_TIME 3 3172
TEST_TIME 4 4036
TEST_TIME 5 4806
TEST_TIME 6 5331
TEST_TIME 7 5090
TEST_TIME 8 5116
TEST_TIME 9 5181
TEST_TIME 10 5525
TEST_TIME 11 5578
TEST_TIME 12 5762
TEST_TIME 13 5978
TEST_TIME 14 6346
TEST_TIME 15 6838
TEST_TIME 16 7328
TEST_TIME 17 7441
TEST_TIME 18 8015
TEST_TIME 19 8064
TEST_TIME 20 8471
TEST_TIME 21 8520
TEST_TIME 22 8368
TEST_TIME 23 8916
TEST_TIME 24 9087
TEST_TIME 25 9320
TEST_TIME 26 9693
TEST_TIME 27 10425
TEST_TIME 28 11014
TEST_TIME 29 10797
TEST_TIME 30 10552
TEST_TIME 31 10308
TEST_TIME 32 10079
TEST_TIME 33 10240
TEST_TIME 34 10192
TEST_TIME 35 10095
TEST_TIME 36 9898
TEST_TIME 37 9770
TEST_TIME 38 9716
TEST_TIME 39 9702
TEST_TIME 40 9642
TEST_TIME 41 9610
TEST_TIME 42 9438
TEST_TIME 43 9367
TEST_TIME 44 9189
TEST_TIME 45 9321
TEST_TIME 46 9250
TEST_TIME 47 9184
TEST_TIME 48 9079
TEST_TIME 49 8990
TEST_TIME 50 9168
TEST_TIME 51 9189
TEST_TIME 52 9275
TEST_TIME 53 9227
TEST_TIME 54 9261
TEST_TIME 55 9228
TEST_TIME 56 9191
TEST_TIME 57 9176
TEST_TIME 58 9218
TEST_TIME 59 9252
TEST_TIME 60 9146
TEST_TIME 61 9001
TEST_TIME 62 8885
TEST_TIME 63 8816
TEST_TIME 64 8745
TEST_TIME 65 8818
TEST_TIME 66 8855
TEST_TIME 67 8806
TEST_TIME 68 8782
TEST_TIME 69 8836
TEST_TIME 70 8767
TEST_TIME 71 8869
TEST_TIME 72 8860
TEST_TIME 73 8944
TEST_TIME 74 8965
TEST_TIME 75 8972
TEST_TIME 76 8993
TEST_TIME 77 8957
TEST_TIME 78 8993
TEST_TIME 79 8900
TEST_TIME 80 8812
TEST_TIME 81 8762
TEST_TIME 82 8714
TEST_TIME 83 8643
TEST_TIME 84 8682
TEST_TIME 85 8670
TEST_TIME 86 8643
TEST_TIME 87 8638
TEST_TIME 88 8638
TEST_TIME 89 8646
TEST_TIME 90 8675
TEST_TIME 91 8755
TEST_TIME 92 8809
TEST_TIME 93 8816
TEST_TIME 94 8815
TEST_TIME 95 8834
TEST_TIME 96 8791
TEST_TIME 97 8752
TEST_TIME 98 8703
TEST_TIME 99 8647
TEST_TIME 100 8655
TEST_TIME 101 8655
TEST_TIME 102 8640
TEST_TIME 103 8630
TEST_TIME 104 8628
TEST_TIME 105 8606
TEST_TIME 106 8586
TEST_TIME 107 8591
TEST_TIME 108 8722
TEST_TIME 109 8773
TEST_TIME 110 8758
TEST_TIME 111 8781
TEST_TIME 112 8734
TEST_TIME 113 8675
TEST_TIME 114 8660
TEST_TIME 115 8614
TEST_TIME 116 8589
TEST_TIME 117 8581
TEST_TIME 118 8565
TEST_TIME 119 8570
TEST_TIME 120 8541

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-01-2021 07:37:58 to 26-01-2021 07:38:02.

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 1413824 1413824 0 0.00 0 16665 6 0 0
doc_list_biud 1413805 1413805 0 0.00 0 51976 35 0 0
sp_add_invoice_to_stock 4672 4570 102 2.18 41 172641 17535 0 102
sp_cancel_adding_invoice 5311 5311 0 0.00 6 71363 852 0 0
sp_cancel_client_order 1623 1623 0 0.00 5 12805 307 0 0
sp_cancel_customer_reserve 527465 527465 0 0.00 1 35159 78 0 0
sp_cancel_pay_from_customer 632 632 0 0.00 3 9391 122 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 2525 77 0 0
sp_cancel_supplier_invoice 5850 5845 5 0.08 3 51985 965 0 5
sp_cancel_supplier_order 400 400 0 0.00 9 1050105 126447 0 0
sp_cancel_write_off 9291 9291 0 0.00 1 10246 114 0 0
sp_client_order 18646 18646 0 0.00 4 21422 414 0 0
sp_customer_reserve 419224 419224 0 0.00 0 44386 173 0 0
sp_fill_shopping_cart 28377 28377 0 0.00 1 18028 42 0 0
sp_fill_shopping_cart_clo_res 419224 419224 0 0.00 0 18074 21 0 0
sp_get_clo_for_invoice 4570 4570 0 0.00 0 37557 467 0 0
sp_kill_cost_storno 15641 15641 0 0.00 0 9024 60 0 0
sp_kill_qstorno_ret_qs2qd 1070466 1070466 0 0.00 0 51975 41 0 0
sp_kill_qty_storno 563277 563277 0 0.00 0 51976 88 0 0
sp_lock_selected_doc 18639 18639 0 0.00 0 15316 7 0 0
sp_make_cost_storno 25149 25149 0 0.00 0 16436 53 0 0
sp_multiply_rows_for_pdistr 25138 25138 0 0.00 0 7529 30 0 0
sp_multiply_rows_for_qdistr 18646 18646 0 0.00 0 15085 227 0 0
sp_pay_from_customer 6552 6552 0 0.00 4 13241 121 0 0
sp_pay_to_supplier 5245 5245 0 0.00 5 12645 121 0 0
sp_payment_common 11788 11788 0 0.00 1 10269 17 0 0
sp_qd_handle_on_cancel_clo 1623 1623 0 0.00 0 12798 275 0 0
sp_qd_handle_on_invoice_upd_sts 9878 9878 0 0.00 0 34212 375 0 0
sp_qd_handle_on_reserve_upd_sts 8775 8775 0 0.00 0 16537 64 0 0
sp_reserve_write_off 8782 8782 0 0.00 7 16550 194 0 0
sp_supplier_invoice 4841 4841 0 0.00 7 84615 3319 0 0
sp_supplier_order 4896 4896 0 0.00 5 28385 1341 0 0
srv_aggregate_perf_data 113 113 0 0.00 2241 191208 15244 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1353 132460 48274 0 0
srv_find_qd_qs_mism 982130 982130 0 0.00 0 9357 5 0 0
srv_make_invnt_saldo 423 423 0 0.00 58 134725 3346 0 0
srv_make_money_saldo 701 701 0 0.00 1 12939 508 0 0
srv_recalc_idx_stat 4 4 0 0.00 198386 498100 349363 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4570 4570 0 0.00 0 9891 149 0 0
t$perf-norm:sp_cancel_adding_invoice 361 361 0 0.00 0 2428 71 0 0
t$perf-norm:sp_cancel_client_order 1623 1623 0 0.00 0 4437 14 0 0
t$perf-norm:sp_cancel_customer_reserve 620 620 0 0.00 0 1057 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 632 632 0 0.00 0 1576 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 496 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 338 338 0 0.00 0 424 13 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 18893 1341 0 0
t$perf-norm:sp_cancel_write_off 916 916 0 0.00 0 3731 16 0 0
t$perf-norm:sp_client_order 18646 18646 0 0.00 0 9095 16 0 0
t$perf-norm:sp_customer_reserve 905 905 0 0.00 0 142 2 0 0
t$perf-norm:sp_pay_from_customer 6552 6552 0 0.00 0 5356 10 0 0
t$perf-norm:sp_pay_to_supplier 5245 5245 0 0.00 0 9968 17 0 0
t$perf-norm:sp_reserve_write_off 8782 8782 0 0.00 0 7224 10 0 0
t$perf-norm:sp_supplier_invoice 4841 4841 0 0.00 0 12747 12 0 0
t$perf-norm:sp_supplier_order 4896 4896 0 0.00 0 11710 19 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 577 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 441 10 0 0
t$perf-norm:srv_make_invnt_saldo 423 423 0 0.00 0 259 4 0 0
t$perf-norm:srv_make_money_saldo 701 701 0 0.00 0 629 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9334 9130 204 2.18 0 6884 19 0 204
v_all_customers 34148 34148 0 0.00 0 16 1 0 0
v_all_suppliers 27084 27084 0 0.00 0 20 1 0 0
v_all_wares 37292 37292 0 0.00 0 102 1 0 0
v_cancel_adding_invoice 722 722 0 0.00 0 1652 8 0 0
v_cancel_client_order 3246 3246 0 0.00 0 8053 8 0 0
v_cancel_customer_prepayment 1264 1264 0 0.00 0 9300 20 0 0
v_cancel_customer_reserve 1240 1240 0 0.00 0 6272 11 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 1063 8 0 0
v_cancel_supplier_invoice 686 676 10 1.45 0 5639 22 0 10
v_cancel_supplier_order 800 800 0 0.00 0 892 4 0 0
v_cancel_write_off 1832 1832 0 0.00 0 2691 13 0 0
v_max_id_clo_ord 4896 4896 0 0.00 0 141 1 0 0
v_max_id_clo_res 905 905 0 0.00 0 2252 15 0 0
v_max_id_ord_sup 4835 4835 0 0.00 0 2989 3 0 0
v_max_non_paid_invoice 1432 1432 0 0.00 0 96 2 0 0
v_max_non_paid_realizn 3392 3392 0 0.00 0 2257 4 0 0
v_min_id_clo_ord 4896 4896 0 0.00 0 4632 6 0 0
v_min_id_clo_res 905 905 0 0.00 0 4806 18 0 0
v_min_id_ord_sup 4835 4835 0 0.00 0 9195 15 0 0
v_min_non_paid_invoice 5243 5243 0 0.00 0 6371 11 0 0
v_min_non_paid_realizn 6545 6545 0 0.00 0 6147 10 0 0
v_our_firm 9450 9450 0 0.00 0 16 1 0 0
v_reserve_write_off 17550 17550 0 0.00 0 11012 8 0 0
x_lock_depdocs_on_canc_invoice 10818 10818 0 0.00 0 13598 117 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 6238 371 0 0
x_make_qstorno_1000_1200 4896 4896 0 0.00 2 28369 1269 0 0
x_make_qstorno_1200_2000 4841 4841 0 0.00 3 83291 3157 0 0
x_make_qstorno_2100_3300 419224 419224 0 0.00 0 44366 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-01-2021 07:38:03 to 26-01-2021 07:38:07.

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
05:06:46 1 8192000000 8796640128 8807108608 3168304 0.038 1 1 2 0 3265920 61840 35776
05:07:47 24267 8192000000 9722432720 10018127872 799136352 8.888 100 84 168 0 826658672 7458496 20063824
05:09:18 19176 8192000000 9767545680 10074189824 843173936 9.332 100 90 179 0 877508544 13087696 21246912
05:10:46 14174 8192000000 9820541968 10138685440 882655520 9.726 100 89 178 0 925099664 22268160 20175984
05:12:02 13939 8192000000 9864512752 10197573632 915178512 10.048 100 78 156 1 975563904 43431440 16953952
05:13:23 11933 8192000000 9916037616 10256326656 959860256 10.488 100 84 168 0 1032118448 54702704 17555488
05:14:47 4431 8192000000 9978856800 10328981504 993274416 10.813 100 84 167 0 1092769104 82190608 17304080
05:15:55 2087 8192000000 10014274304 10370420736 1024425968 11.115 100 89 177 1 1120618752 78449504 17743280
05:16:59 2336 8192000000 10038209696 10408112128 1055282784 11.411 100 87 173 3 1150873968 78256960 17334224
05:18:02 27755 8192000000 10000232624 10380357632 1066180832 11.516 100 64 128 2 1129930592 50613600 13136160
05:19:30 5344 8192000000 9986792448 10378670080 1077085488 11.620 100 32 64 0 1111157472 27645536 6426448
05:20:36 7072 8192000000 9986101312 10380222464 1080746752 11.655 100 41 82 1 1116178896 25682400 9749744
05:21:46 62524 8192000000 9991576080 10386911232 1085156976 11.697 100 26 52 1 1114864448 24494928 5212544
05:23:50 5282 8192000000 10046434048 10439266304 1094954848 11.790 100 58 114 2 1153182336 46768032 11459456
05:24:57 2747 8192000000 10001522256 10400464896 1083678560 11.683 99 40 80 1 1119997552 27327056 8991936
05:26:02 5885 8192000000 9984935280 10370494464 1062663664 11.482 98 38 76 2 1089531680 18726224 8141792
05:27:11 33846 8192000000 9977928112 10385031168 1027185984 11.141 96 28 55 1 1065289504 32360576 5742944
05:28:45 6764 8192000000 9955378112 10341953536 991505760 10.796 94 35 69 1 1025129424 25709440 7914224
05:29:55 6391 8192000000 9971800656 10365820928 1027142736 11.141 99 51 102 1 1067728864 29332304 11253824
05:31:02 12373 8192000000 9935239248 10328076288 1027891904 11.148 100 24 48 1 1060215504 26817824 5505776
05:32:15 33342 8192000000 9951353248 10357014528 1023874352 11.109 99 41 82 0 1051531344 19061264 8595728
05:33:52 2566 8192000000 9972044064 10350665728 1047232080 11.334 100 52 104 3 1076397536 18035120 11130336
05:34:55 5866 8192000000 9986022576 10378493952 1063784960 11.493 100 44 89 1 1090293168 17006784 9501424
05:36:03 6481 8192000000 9994080992 10393382912 1073575696 11.586 100 40 79 2 1104687200 22082048 9029456
05:37:10 100096 8192000000 10043894512 10447192064 1096184320 11.801 100 43 86 1 1136779648 31686928 8908400
05:39:55 23227 8192000000 10091477264 10500481024 1120665424 12.033 100 54 108 2 1204366128 72188720 11511984
05:41:23 10981 8192000000 10112664032 10520690688 1124702800 12.071 99 52 102 1 1212197856 75842240 11652816
05:42:42 7399 8192000000 10141152032 10559660032 1134576960 12.164 99 49 97 3 1249582992 104510000 10496032
05:43:57 4549 8192000000 10173545200 10580578304 1152037600 12.329 100 56 112 0 1288858464 124704256 12116608
05:45:03 9565 8192000000 10212798112 10618617856 1161247776 12.415 100 60 119 0 1319393824 146028352 12117696
05:46:18 13869 8192000000 10229222336 10638835712 1150030800 12.310 99 62 124 0 1328859328 164858240 13970288
05:47:37 4308 8192000000 10261367760 10671038464 1155763600 12.364 100 70 139 2 1366909376 195329520 15816256
05:48:47 3035 8192000000 10271579232 10667679744 1155379184 12.360 99 73 145 2 1374298080 202151632 16767264
05:49:56 3802 8192000000 10328675088 10739154944 1176225120 12.555 100 66 132 2 1430650576 238450272 15975184
05:51:03 2059 8192000000 10316374352 10753290240 1165182928 12.452 99 72 141 1 1426212352 244647232 16382192
05:52:09 1348 8192000000 10331745456 10741329920 1147707280 12.288 97 76 152 3 1420939408 255802368 17429760
05:53:14 4563 8192000000 10370391232 10774978560 1165439104 12.454 100 77 154 1 1464062624 280664432 17959088
05:54:23 13946 8192000000 10325041616 10797989888 1166480240 12.464 100 37 73 0 1462821936 287376272 8965424
05:55:49 18034 8192000000 10316005376 10790789120 1166213792 12.461 100 35 70 1 1437844176 262274000 9356384
05:57:13 12898 8192000000 10342552880 10761674752 1144766576 12.260 99 46 92 2 1435380848 279314224 11300048
05:58:29 4170 8192000000 10366428416 10776719360 1135012288 12.169 100 64 128 1 1462438784 311681712 15744784
05:59:36 14163 8192000000 10338003184 10773274624 1134269296 12.162 99 41 82 0 1435203344 290806992 10127056
06:00:52 17785 8192000000 10342372432 10811871232 1130817296 12.129 99 31 61 2 1448903296 310132592 7953408
06:02:14 19989 8192000000 10338709104 10817191936 1118746352 12.015 99 23 46 1 1462689936 337592352 6351232
06:03:36 29831 8192000000 10135571776 10735747072 1110134896 11.934 99 16 32 0 1365746736 251469952 4141888
06:05:31 10943 8192000000 10064949952 10492174336 1118526336 12.013 100 48 96 4 1161730496 33523104 9681056
06:06:48 132459 8192000000 10186418400 10639781888 1137193280 12.189 100 27 55 0 1251008432 108207824 5607328
06:11:24 61204 8192000000 10094906720 10522341376 1112315456 11.954 97 26 51 1 1168766400 51497808 4953136
06:13:35 50085 8192000000 10135805296 10585235456 1142857376 12.242 100 27 54 0 1229128304 80990464 5280464
06:15:56 63011 8192000000 10063574368 10513416192 1105142624 11.886 98 12 24 0 1167977328 59960576 2874128
06:18:05 46579 8192000000 10119011184 10589847552 1131039376 12.131 100 17 34 0 1200295040 65529200 3726464
06:20:27 49785 8192000000 10137938032 10576891904 1138970320 12.206 100 32 64 1 1225691040 79736384 6984336
06:22:55 44416 8192000000 10086404384 10589110272 1125099712 12.075 99 7 14 0 1184734800 58328240 1306848
06:24:48 66425 8192000000 10163599648 10610896896 1136512496 12.183 99 23 46 1 1264474272 122614768 5347008
06:27:00 77802 8192000000 10124129856 10580578304 1124884272 12.073 98 19 38 0 1202751168 73661280 4205616
06:29:30 36568 8192000000 10088505872 10533699584 1138889952 12.205 99 14 28 1 1169005328 27497200 2618176
06:31:17 50434 8192000000 10097194784 10537148416 1156896624 12.374 100 20 40 3 1177520544 16778368 3845552
06:33:10 77365 8192000000 10148626160 10586423296 1169286928 12.490 100 30 60 0 1233976624 58349104 6340592
06:35:32 131439 8192000000 10080496864 10560000000 1095498640 11.795 94 10 20 0 1129485200 31643600 2342960
06:38:43 55798 8192000000 10121706016 10583465984 1153721024 12.344 100 11 22 0 1230267344 74283536 2262784
06:41:02 37792 8192000000 10116106064 10636820480 1159084336 12.395 100 7 14 1 1237707328 77217504 1405488
06:42:40 58928 8192000000 10091939328 10538291200 1125058368 12.075 97 10 19 0 1161517984 34801152 1658464
06:44:40 91083 8192000000 10158577920 10610532352 1149724208 12.307 99 17 34 1 1244187232 90804688 3658336
06:47:27 77459 8192000000 10165264336 10658144256 1153816304 12.345 99 15 30 0 1278721360 121292368 3612688
06:49:45 50329 8192000000 10112868112 10578276352 1100300256 11.840 95 13 26 0 1149599536 46974000 2325280
06:51:37 41979 8192000000 10085327968 10538823680 1122624544 12.052 98 34 69 0 1191064512 61453968 6986000
06:53:22 22791 8192000000 10046716544 10459623424 1060757920 11.464 94 44 86 1 1091029808 21146576 9125312
06:54:52 89668 8192000000 10014734880 10451984384 1080164880 11.649 97 19 37 0 1104390784 20313424 3912480
06:57:39 70319 8192000000 10016672112 10454818816 1011413616 10.989 91 11 22 0 1049461264 35668864 2378784
06:59:49 59348 8192000000 10049342528 10511654912 1086853392 11.713 99 11 22 1 1159062192 70062240 2146560
07:01:49 78490 8192000000 10094032656 10522726400 1096260656 11.802 98 24 48 0 1151572288 50136384 5175248
07:04:26 33512 8192000000 10111768736 10535649280 1104142176 11.877 98 32 64 1 1185879008 75150272 6586560
07:06:06 49289 8192000000 10125882512 10575142912 1111096032 11.943 98 22 44 0 1201475264 85646448 4732784
07:07:56 107175 8192000000 10071120512 10508431360 1084635072 11.692 96 11 20 2 1150632032 63751680 2245280
07:10:43 63117 8192000000 10079510064 10526179328 1123192816 12.057 99 23 46 0 1182294496 54506144 4595536
07:12:50 97840 8192000000 10050603296 10503757824 1132718992 12.147 100 18 36 0 1164732576 28422576 3591008
07:16:51 64520 8192000000 10090953952 10587963392 1132030208 12.140 99 14 28 1 1195708272 60519744 3158320
07:18:56 40724 8192000000 10079055472 10530598912 1124994096 12.074 99 19 37 1 1169648576 40136896 4517584
07:20:38 68246 8192000000 10138428304 10574118912 1151184816 12.321 100 31 62 0 1223757472 65684768 6887888
07:22:47 33831 8192000000 10202209552 10635386880 1163696496 12.438 100 31 61 2 1284712160 113739376 7276288
07:24:36 93942 8192000000 10081061648 10554437632 1111265344 11.944 97 9 18 0 1185511072 72180608 2065120
07:27:13 46186 8192000000 10082479360 10537947136 1103318992 11.869 96 25 50 1 1142558016 34304304 4934720
07:29:01 101094 8192000000 10068157856 10517630976 1126529360 12.089 99 19 38 0 1183540864 52803536 4207968
07:31:44 74045 8192000000 10095450336 10590715904 1138500592 12.201 99 6 12 0 1189412352 49591488 1320272
07:33:58 119018 8192000000 10070140320 10514001920 1081920640 11.666 94 10 19 2 1113693072 29713632 2058800

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-01-2021 07:38:09 to 26-01-2021 07:38:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-01-2021 07:38:24 to 26-01-2021 07:38:26.

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          223489
MON$OLDEST_ACTIVE               225772
MON$OLDEST_SNAPSHOT             225772
MON$NEXT_TRANSACTION            225772
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                       2088480
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             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2342 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2342 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2342 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.2342 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Tue Jan 26 07:36:46 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

26.01.21 07:38:27. 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
2021-01-26 07:38:30 No severe PSQL-related problems occured
26.01.21 07:38:31. End of report.