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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16154684 kB
MemAvailable 31358700 kB
Buffers 0 kB
Cached 15504584 kB
SwapCached 488 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.1
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-12-28 10:37:43
DTS_END                         2021-12-28 10:37:10.8370
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51178
TRN_ID                          236767
ATT_ID                          259
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: 9230.90 23145 1107708
customer order: creation 174.14 397 20897
customer order: refuse 15.43 361 1852
order to supplier: creation 47.06 1452 5647
order to supplier: removal 3.59 131624 431
invoice (draft): creation 45.71 3646 5485
invoice (draft): removal 52.44 1057 6293
invoice accept: apply 41.33 19171 4960
invoice accept: cancel 47.48 982 5698
customer reserve: creation 3812.87 189 457544
customer reserve: removal 4685.33 79 562239
realization accept: apply 84.27 202 10112
realization accept: cancel 91.16 140 10939
payment from customer: creation 60.32 113 7238
payment from customer: removal 5.66 95 679
payment to supplier: creation 49.35 136 5922
payment to supplier: removal 3.63 77 435
service: total inventory turnovers 3.77 4144 452
service: total monetary turnovers 6.39 612 767
service: aggregate perf. data 0.95 14683 114
service: refresh index statistics 0.03 283730 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-12-2021 10:38:44 to 28-12-2021 10:38:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3732
TEST_TIME 2 3856
TEST_TIME 3 4728
TEST_TIME 4 4226
TEST_TIME 5 5549
TEST_TIME 6 5342
TEST_TIME 7 5258
TEST_TIME 8 5245
TEST_TIME 9 5106
TEST_TIME 10 5478
TEST_TIME 11 5964
TEST_TIME 12 6181
TEST_TIME 13 6615
TEST_TIME 14 6763
TEST_TIME 15 6600
TEST_TIME 16 6719
TEST_TIME 17 6810
TEST_TIME 18 6870
TEST_TIME 19 7378
TEST_TIME 20 7681
TEST_TIME 21 7574
TEST_TIME 22 7647
TEST_TIME 23 7893
TEST_TIME 24 7861
TEST_TIME 25 7799
TEST_TIME 26 7880
TEST_TIME 27 7990
TEST_TIME 28 8276
TEST_TIME 29 8826
TEST_TIME 30 8931
TEST_TIME 31 9216
TEST_TIME 32 10423
TEST_TIME 33 10624
TEST_TIME 34 10724
TEST_TIME 35 10784
TEST_TIME 36 10832
TEST_TIME 37 10716
TEST_TIME 38 10541
TEST_TIME 39 10410
TEST_TIME 40 10221
TEST_TIME 41 10334
TEST_TIME 42 10244
TEST_TIME 43 10060
TEST_TIME 44 10021
TEST_TIME 45 9950
TEST_TIME 46 9835
TEST_TIME 47 9943
TEST_TIME 48 9897
TEST_TIME 49 9867
TEST_TIME 50 9977
TEST_TIME 51 9909
TEST_TIME 52 9804
TEST_TIME 53 9787
TEST_TIME 54 9956
TEST_TIME 55 9945
TEST_TIME 56 10021
TEST_TIME 57 10014
TEST_TIME 58 9988
TEST_TIME 59 10001
TEST_TIME 60 9983
TEST_TIME 61 9929
TEST_TIME 62 10027
TEST_TIME 63 10017
TEST_TIME 64 10001
TEST_TIME 65 10066
TEST_TIME 66 9956
TEST_TIME 67 9844
TEST_TIME 68 9808
TEST_TIME 69 9761
TEST_TIME 70 9753
TEST_TIME 71 9693
TEST_TIME 72 9649
TEST_TIME 73 9563
TEST_TIME 74 9517
TEST_TIME 75 9509
TEST_TIME 76 9510
TEST_TIME 77 9435
TEST_TIME 78 9460
TEST_TIME 79 9502
TEST_TIME 80 9585
TEST_TIME 81 9601
TEST_TIME 82 9615
TEST_TIME 83 9689
TEST_TIME 84 9667
TEST_TIME 85 9759
TEST_TIME 86 9695
TEST_TIME 87 9597
TEST_TIME 88 9539
TEST_TIME 89 9515
TEST_TIME 90 9543
TEST_TIME 91 9477
TEST_TIME 92 9433
TEST_TIME 93 9483
TEST_TIME 94 9445
TEST_TIME 95 9431
TEST_TIME 96 9404
TEST_TIME 97 9421
TEST_TIME 98 9392
TEST_TIME 99 9494
TEST_TIME 100 9567
TEST_TIME 101 9552
TEST_TIME 102 9560
TEST_TIME 103 9605
TEST_TIME 104 9620
TEST_TIME 105 9586
TEST_TIME 106 9613
TEST_TIME 107 9563
TEST_TIME 108 9518
TEST_TIME 109 9460
TEST_TIME 110 9471
TEST_TIME 111 9444
TEST_TIME 112 9406
TEST_TIME 113 9384
TEST_TIME 114 9364
TEST_TIME 115 9335
TEST_TIME 116 9298
TEST_TIME 117 9313
TEST_TIME 118 9330
TEST_TIME 119 9298
TEST_TIME 120 9231

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-12-2021 10:38:48 to 28-12-2021 10:38:51.

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 1531924 1531924 0 0.00 0 18382 7 0 0
doc_list_biud 1531924 1531924 0 0.00 0 71162 36 0 0
sp_add_invoice_to_stock 5128 4960 168 3.27 47 188044 19172 0 168
sp_cancel_adding_invoice 5698 5698 0 0.00 4 72828 982 0 0
sp_cancel_client_order 1852 1852 0 0.00 4 9952 362 0 0
sp_cancel_customer_reserve 562239 562239 0 0.00 1 49047 79 0 0
sp_cancel_pay_from_customer 679 679 0 0.00 3 5081 96 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 3 3848 77 0 0
sp_cancel_supplier_invoice 6307 6293 14 0.22 2 71168 1057 0 14
sp_cancel_supplier_order 431 431 0 0.00 9 1122721 131624 0 0
sp_cancel_write_off 10939 10939 0 0.00 1 13996 140 0 0
sp_client_order 20897 20897 0 0.00 4 20711 398 0 0
sp_customer_reserve 457544 457544 0 0.00 0 99621 189 0 0
sp_fill_shopping_cart 32024 32024 0 0.00 1 14196 43 0 0
sp_fill_shopping_cart_clo_res 457539 457539 0 0.00 0 16523 16 0 0
sp_get_clo_for_invoice 4955 4955 0 0.00 0 20159 352 0 0
sp_kill_cost_storno 17751 17751 0 0.00 0 12781 71 0 0
sp_kill_qstorno_ret_qs2qd 1142141 1142141 0 0.00 0 71160 43 0 0
sp_kill_qty_storno 602519 602519 0 0.00 0 71161 91 0 0
sp_lock_selected_doc 21036 21036 0 0.00 0 11760 4 0 0
sp_make_cost_storno 28227 28227 0 0.00 0 14057 61 0 0
sp_multiply_rows_for_pdistr 28227 28227 0 0.00 0 8873 32 0 0
sp_multiply_rows_for_qdistr 20897 20897 0 0.00 0 17702 204 0 0
sp_pay_from_customer 7238 7238 0 0.00 4 12401 114 0 0
sp_pay_to_supplier 5922 5922 0 0.00 4 16525 137 0 0
sp_payment_common 13160 13160 0 0.00 1 6132 18 0 0
sp_qd_handle_on_cancel_clo 1852 1852 0 0.00 0 9940 330 0 0
sp_qd_handle_on_invoice_upd_sts 10653 10653 0 0.00 0 34179 419 0 0
sp_qd_handle_on_reserve_upd_sts 10112 10112 0 0.00 0 24738 69 0 0
sp_reserve_write_off 10112 10112 0 0.00 7 24755 203 0 0
sp_supplier_invoice 5485 5485 0 0.00 6 78286 3646 0 0
sp_supplier_order 5647 5647 0 0.00 6 32735 1452 0 0
srv_aggregate_perf_data 114 114 0 0.00 2429 165626 14684 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1963 142628 41424 0 0
srv_find_qd_qs_mism 1060088 1060088 0 0.00 0 18381 6 0 0
srv_make_invnt_saldo 452 452 0 0.00 30 108613 4145 0 0
srv_make_money_saldo 767 767 0 0.00 1 14438 612 0 0
srv_recalc_idx_stat 4 4 0 0.00 126223 427439 283731 0 0
t$perf-abend:sp_add_invoice_to_stock 168 168 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4960 4960 0 0.00 0 7290 171 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 10304 97 0 0
t$perf-norm:sp_cancel_client_order 1852 1852 0 0.00 0 10169 18 0 0
t$perf-norm:sp_cancel_customer_reserve 694 694 0 0.00 0 1599 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 679 679 0 0.00 0 1445 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 13063 34 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 2594 18 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 23942 1692 0 0
t$perf-norm:sp_cancel_write_off 1035 1035 0 0.00 0 3957 14 0 0
t$perf-norm:sp_client_order 20897 20897 0 0.00 0 13315 22 0 0
t$perf-norm:sp_customer_reserve 1001 1001 0 0.00 0 2086 7 0 0
t$perf-norm:sp_pay_from_customer 7238 7238 0 0.00 0 4575 9 0 0
t$perf-norm:sp_pay_to_supplier 5922 5922 0 0.00 0 14876 13 0 0
t$perf-norm:sp_reserve_write_off 10112 10112 0 0.00 0 13346 12 0 0
t$perf-norm:sp_supplier_invoice 5485 5485 0 0.00 0 13349 20 0 0
t$perf-norm:sp_supplier_order 5647 5647 0 0.00 0 3850 20 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 495 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 222 8 0 0
t$perf-norm:srv_make_invnt_saldo 452 452 0 0.00 0 2467 21 0 0
t$perf-norm:srv_make_money_saldo 767 767 0 0.00 0 378 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10246 9910 336 3.27 0 9084 17 0 336
v_all_customers 38482 38482 0 0.00 0 22 1 0 0
v_all_suppliers 31032 31032 0 0.00 0 15 1 0 0
v_all_wares 41794 41794 0 0.00 0 34 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 1037 8 0 0
v_cancel_client_order 3704 3704 0 0.00 0 2314 5 0 0
v_cancel_customer_prepayment 1358 1358 0 0.00 0 2279 7 0 0
v_cancel_customer_reserve 1388 1388 0 0.00 0 2132 6 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 1976 7 0 0
v_cancel_supplier_invoice 876 848 28 3.19 0 2306 14 0 28
v_cancel_supplier_order 862 862 0 0.00 0 1730 7 0 0
v_cancel_write_off 2070 2070 0 0.00 0 1884 12 0 0
v_max_id_clo_ord 5647 5647 0 0.00 0 575 1 0 0
v_max_id_clo_res 1001 1001 0 0.00 0 2123 21 0 0
v_max_id_ord_sup 5480 5480 0 0.00 0 3573 3 0 0
v_max_non_paid_invoice 1533 1533 0 0.00 0 1717 4 0 0
v_max_non_paid_realizn 3716 3716 0 0.00 0 1671 3 0 0
v_min_id_clo_ord 5647 5647 0 0.00 0 1559 4 0 0
v_min_id_clo_res 1001 1001 0 0.00 0 8190 20 0 0
v_min_id_ord_sup 5480 5480 0 0.00 0 4889 9 0 0
v_min_non_paid_invoice 5922 5922 0 0.00 0 4273 9 0 0
v_min_non_paid_realizn 7238 7238 0 0.00 0 4271 12 0 0
v_our_firm 10356 10356 0 0.00 0 15 1 0 0
v_reserve_write_off 20224 20224 0 0.00 0 10237 7 0 0
x_lock_depdocs_on_canc_invoice 11567 11567 0 0.00 0 16168 126 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 7743 415 0 0
x_make_qstorno_1000_1200 5647 5647 0 0.00 2 32726 1367 0 0
x_make_qstorno_1200_2000 5485 5485 0 0.00 3 78271 3484 0 0
x_make_qstorno_2100_3300 457544 457544 0 0.00 0 99620 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-12-2021 10:38:52 to 28-12-2021 10:38:56.

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
08:07:14 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:15 23651 8192000000 9706871040 10001805312 784457696 8.739 100 87 174 0 813211136 7175616 21577824
08:09:41 23477 8192000000 9750894272 10056318976 823800240 9.137 100 90 180 0 858182000 12062640 22319120
08:11:12 21246 8192000000 9797766864 10118848512 866091280 9.561 100 80 160 0 907824768 23055216 18678272
08:12:38 14991 8192000000 9844971504 10176049152 900919616 9.907 100 77 152 0 956721696 39093824 16708256
08:13:58 8469 8192000000 9899311904 10242895872 949811920 10.389 100 69 138 0 1015854720 51324144 14718656
08:15:11 4773 8192000000 9947674304 10299195392 983538096 10.719 100 81 161 1 1056019088 56174288 16306704
08:16:20 7363 8192000000 9988898608 10350424064 1006124464 10.938 100 69 138 3 1095747920 76315504 13307952
08:17:33 1793 8192000000 10000499088 10366296064 1042194592 11.286 100 92 184 0 1119818912 58470608 19153712
08:18:36 2581 8192000000 10037290208 10406264832 1054113728 11.400 100 95 190 0 1148274896 75074816 19086352
08:19:40 28076 8192000000 9990551264 10366689280 1059893296 11.455 100 77 155 1 1099955248 23742208 16319744
08:21:09 5194 8192000000 9981321232 10366595072 1066910816 11.523 100 40 81 0 1088607232 13311744 8384672
08:22:15 6632 8192000000 9996391792 10395496448 1076580352 11.615 100 36 70 2 1102203360 18609696 7013312
08:23:25 5906 8192000000 9986067088 10397790208 1082150128 11.668 100 21 42 0 1115741360 29419840 4171392
08:24:33 70703 8192000000 10016762736 10407309312 1093913648 11.780 100 46 90 1 1117631440 13876512 9841280
08:26:44 2442 8192000000 10046532832 10442600448 1098346848 11.822 100 51 102 1 1144058112 34578032 11133232
08:27:47 5765 8192000000 10004297168 10399424512 1087882736 11.723 100 39 78 3 1111795840 14948512 8964592
08:28:56 7938 8192000000 10001278128 10403450880 1071147792 11.563 99 34 68 0 1099224976 20985792 7091392
08:30:06 6035 8192000000 9983234848 10365157376 1041385792 11.278 98 37 74 0 1069244800 19824400 8034608
08:31:13 3150 8192000000 9941367744 10309394432 1024803680 11.118 100 59 117 0 1048838784 9492384 14542720
08:32:17 25065 8192000000 9903839296 10295734272 952840976 10.419 93 27 55 1 972752640 13565760 6345904
08:33:57 8868 8192000000 9948158272 10340524032 1013812960 11.012 98 29 58 1 1042534544 22038592 6682992
08:35:07 10547 8192000000 9962360624 10358173696 1046308176 11.325 100 31 61 1 1079355168 26525040 6521952
08:36:18 5183 8192000000 9976423280 10373537792 1065262672 11.507 100 39 79 1 1081163904 6848848 9052384
08:37:28 62751 8192000000 10024044224 10415370240 1083272416 11.679 100 39 74 1 1110284768 18761488 8250864
08:39:35 24061 8192000000 10061555168 10461122560 1112577696 11.957 100 39 78 2 1167175952 46484464 8113792
08:41:02 36491 8192000000 10120026592 10519535616 1122017168 12.046 100 46 92 0 1222682208 90633184 10031856
08:42:40 15306 8192000000 10128200944 10537185280 1134857216 12.167 100 46 92 2 1238838736 93291872 10689648
08:44:03 15188 8192000000 10168946528 10584735744 1150377360 12.313 100 48 97 1 1283247728 122489552 10380816
08:45:25 11698 8192000000 10215431568 10630823936 1153235520 12.340 100 66 132 0 1321539936 153480368 14824048
08:46:54 6115 8192000000 10242110672 10652037120 1161394128 12.416 100 67 130 6 1352918592 176672288 14852176
08:48:04 9356 8192000000 10286886688 10700558336 1165138688 12.451 100 73 143 2 1386064352 204224784 16700880
08:49:17 3488 8192000000 10301580704 10710581248 1171790688 12.514 100 72 144 0 1399554816 211594336 16169792
08:50:28 5947 8192000000 10260489168 10691145728 1147736656 12.288 99 55 110 1 1356799088 195691184 13371248
08:51:41 4138 8192000000 10261839680 10667249664 1165891056 12.458 100 75 149 1 1367185840 183484288 17810496
08:52:50 6126 8192000000 10288692432 10700566528 1158170320 12.386 99 55 111 0 1378909888 207658688 13080880
08:54:03 2424 8192000000 10363311744 10768408576 1172619536 12.521 100 85 170 0 1457114208 264471888 20022784
08:55:11 9090 8192000000 10370034688 10780712960 1148414048 12.295 99 65 127 1 1462484592 298259248 15811296
08:56:28 5630 8192000000 10385000704 10839687168 1164606832 12.446 100 79 158 1 1485011168 301881488 18522848
08:57:43 1957 8192000000 10400364768 10802171904 1179650288 12.587 100 84 165 1 1490041936 291338144 19053504
08:58:52 11022 8192000000 10428266640 10834452480 1187033728 12.656 100 83 165 0 1519367168 312752752 19580688
09:00:08 8436 8192000000 10451272704 10869399552 1177039824 12.563 100 47 94 0 1545027856 356272416 11715616
09:01:25 9454 8192000000 10479181536 10900918272 1171821456 12.514 100 41 82 0 1571040448 388747856 10471136
09:02:40 7309 8192000000 10504490000 10930376704 1161124672 12.414 100 46 92 1 1603092736 429977696 11990368
09:04:06 3323 8192000000 10519485424 10949263360 1165032160 12.450 100 63 125 0 1625498448 444970208 15496080
09:05:16 17380 8192000000 10538719056 11004637184 1148821408 12.298 100 37 74 0 1639676368 480708224 10146736
09:06:37 10046 8192000000 10515116656 11012771840 1136346640 12.181 99 27 54 2 1655533856 511655568 7531648
09:07:53 24779 8192000000 10453634368 10928943104 1145152624 12.264 100 24 48 1 1567123936 415489312 6482000
09:09:20 42589 8192000000 10084548480 10620559360 1100715440 11.844 97 13 26 0 1223241696 119758448 2767808
09:11:06 52307 8192000000 10082910560 10562113536 1117628480 12.005 99 12 24 1 1167601184 47561568 2411136
09:13:02 47580 8192000000 10065060400 10537320448 1110224720 11.935 98 9 18 0 1168865392 56872496 1768176
09:14:50 18915 8192000000 10074064000 10490933248 1141318048 12.228 100 55 110 0 1164069872 11622352 11129472
09:16:10 142628 8192000000 10110053600 10566381568 1106809744 11.902 97 26 52 1 1204865856 92848256 5207856
09:19:35 97900 8192000000 10089889616 10500812800 1070392352 11.556 94 73 149 4 1119417824 33314928 15710544
09:22:13 61798 8192000000 10126420080 10567368704 1128047040 12.103 99 20 38 1 1214314976 81809168 4458768
09:24:18 32028 8192000000 10138096320 10639675392 1138756288 12.204 100 24 48 0 1249697072 105739696 5201088
09:26:00 43836 8192000000 10164989168 10658439168 1146379776 12.276 100 17 34 2 1263459408 113339904 3739728
09:27:55 45147 8192000000 10127734144 10573410304 1130391936 12.125 98 17 35 0 1196333936 62202608 3739392
09:29:40 71526 8192000000 10147529728 10644910080 1132805984 12.148 99 6 12 0 1233885792 99767824 1311984
09:32:28 43431 8192000000 10107280400 10556747776 1116336304 11.992 97 22 43 1 1187074496 66152096 4586096
09:34:21 28943 8192000000 10070952576 10510807040 1139917824 12.215 100 24 47 0 1170510096 25858304 4733968
09:35:54 48260 8192000000 10110321024 10562691072 1153058720 12.338 100 35 69 1 1199481456 39455776 6966960
09:37:47 37994 8192000000 10093529216 10525720576 1124861616 12.073 98 29 58 0 1159996976 29433232 5702128
09:39:27 40419 8192000000 10087150880 10554753024 1147429568 12.285 100 10 20 1 1199717760 50334576 1953616
09:41:23 41939 8192000000 10123779104 10558017536 1116411328 11.993 98 36 72 1 1171005904 47296336 7298240
09:43:13 102887 8192000000 10073614544 10506076160 1106261520 11.897 97 26 52 1 1141541008 29964432 5315056
09:46:26 65700 8192000000 10067421312 10551312384 1132875856 12.148 99 16 32 0 1209049408 72710640 3462912
09:48:50 42787 8192000000 10051309232 10491842560 1106965296 11.904 97 21 42 0 1127419280 15767824 4686160
09:50:35 56045 8192000000 10068020144 10512035840 1123405312 12.059 99 12 23 0 1164851520 38918784 2527424
09:52:36 44184 8192000000 10057283808 10512752640 1073278176 11.583 95 23 45 1 1111603824 33401264 4924384
09:54:25 21505 8192000000 10115437232 10542817280 1110757248 11.940 99 50 100 0 1195061392 73711184 10592960
09:55:50 103273 8192000000 10138991632 10596732928 1087330960 11.717 96 22 44 0 1213087808 120588240 5168608
09:58:34 53560 8192000000 10117790928 10565255168 1105003712 11.885 98 25 50 0 1190842448 80461872 5376864
10:00:28 36499 8192000000 10057321056 10474721280 1111059104 11.942 99 25 50 1 1152156000 36032592 5064304
10:02:08 82165 8192000000 10056137264 10503880704 1086129744 11.706 96 12 24 0 1150661680 61963504 2568432
10:04:32 111450 8192000000 10118259008 10547499008 1112417808 11.955 98 18 36 2 1177768592 61729648 3621136
10:07:28 34656 8192000000 10081783792 10531303424 1131218688 12.133 99 14 28 0 1183779056 50269984 2290384
10:09:14 57213 8192000000 10104854384 10564206592 1114696416 11.977 97 12 24 0 1192349696 74850768 2802512
10:11:11 35564 8192000000 10092537952 10528043008 1141783920 12.232 100 32 64 0 1174662688 26210208 6668560
10:12:53 49684 8192000000 10154749520 10591289344 1157083232 12.376 100 29 59 0 1244381040 81147872 6149936
10:14:48 70597 8192000000 10190775840 10635169792 1135848624 12.176 99 20 40 0 1266607616 126519824 4239168
10:17:11 44509 8192000000 10093230928 10533101568 1129139744 12.113 99 14 28 0 1190230576 58016848 3073984
10:18:57 29013 8192000000 10102362720 10535309312 1134554640 12.164 99 37 74 0 1163297632 21758064 6984928
10:20:28 85674 8192000000 10088511280 10556854272 1116370272 11.993 97 22 44 0 1175868368 55470128 4027968
10:22:54 86100 8192000000 10079315168 10532651008 1107759728 11.911 97 16 32 0 1152566864 41551408 3255728
10:25:27 79752 8192000000 10077100464 10515869696 1122218800 12.048 98 25 50 0 1168646624 41281952 5145872
10:27:47 58280 8192000000 10081797504 10540027904 1133375184 12.153 99 14 28 0 1169190832 33252640 2563008
10:29:45 54862 8192000000 10091584704 10550276096 1133308832 12.153 99 12 24 0 1195062624 59095920 2657872
10:31:40 61955 8192000000 10077283488 10572685312 1132400288 12.144 99 12 24 0 1185864256 50784432 2679536
10:33:53 55611 8192000000 10098921968 10554814464 1072110592 11.572 94 14 28 0 1141339456 66038304 3190560
10:35:49 43072 8192000000 10091045440 10522849280 1114810016 11.978 98 27 54 1 1162116720 41103232 6203472

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 168 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-12-2021 10:39:14 to 28-12-2021 10:39:16.

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          246507
MON$OLDEST_ACTIVE               246508
MON$OLDEST_SNAPSHOT             246508
MON$NEXT_TRANSACTION            246508
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                       2076576
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             801
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2697 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2697 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2697 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2697 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
--- end of diff output ---

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

28.12.21 10:39:17. 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-12-28 10:39:20 No severe PSQL-related problems occured
28.12.21 10:39:21. End of report.