Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.04.2021 07:29
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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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.26
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 16788500 kB
MemAvailable 31470916 kB
Buffers 0 kB
Cached 14893248 kB
SwapCached 0 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-04-05 07:26:57
DTS_END                         2021-04-05 07:26:57.0200
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40722
TRN_ID                          230417
ATT_ID                          657
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: 8866.42 31447 1063971
customer order: creation 153.24 345 18389
customer order: refuse 13.80 303 1656
order to supplier: creation 42.23 1225 5068
order to supplier: removal 3.54 105708 425
invoice (draft): creation 43.20 3639 5184
invoice (draft): removal 51.06 821 6127
invoice accept: apply 38.91 18410 4669
invoice accept: cancel 46.24 813 5549
customer reserve: creation 3623.98 178 434877
customer reserve: removal 4571.83 65 548619
realization accept: apply 77.13 172 9255
realization accept: cancel 81.05 117 9726
payment from customer: creation 54.98 120 6597
payment from customer: removal 5.35 76 642
payment to supplier: creation 46.53 139 5583
payment to supplier: removal 3.28 66 393
service: total inventory turnovers 3.49 3540 419
service: total monetary turnovers 5.80 481 696
service: aggregate perf. data 0.78 15290 93
service: refresh index statistics 0.03 477427 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-04-2021 07:29:38 to 05-04-2021 07:29:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4317
TEST_TIME 2 3890
TEST_TIME 3 3668
TEST_TIME 4 5186
TEST_TIME 5 5521
TEST_TIME 6 6134
TEST_TIME 7 6905
TEST_TIME 8 6834
TEST_TIME 9 6941
TEST_TIME 10 6895
TEST_TIME 11 6876
TEST_TIME 12 6852
TEST_TIME 13 6956
TEST_TIME 14 7105
TEST_TIME 15 7330
TEST_TIME 16 7453
TEST_TIME 17 7410
TEST_TIME 18 8021
TEST_TIME 19 8199
TEST_TIME 20 8152
TEST_TIME 21 8532
TEST_TIME 22 8820
TEST_TIME 23 8901
TEST_TIME 24 9012
TEST_TIME 25 8940
TEST_TIME 26 9670
TEST_TIME 27 9845
TEST_TIME 28 10299
TEST_TIME 29 10719
TEST_TIME 30 10874
TEST_TIME 31 10929
TEST_TIME 32 10708
TEST_TIME 33 10523
TEST_TIME 34 10367
TEST_TIME 35 10227
TEST_TIME 36 10029
TEST_TIME 37 9873
TEST_TIME 38 9784
TEST_TIME 39 9679
TEST_TIME 40 9663
TEST_TIME 41 9759
TEST_TIME 42 9625
TEST_TIME 43 9613
TEST_TIME 44 9485
TEST_TIME 45 9515
TEST_TIME 46 9545
TEST_TIME 47 9464
TEST_TIME 48 9482
TEST_TIME 49 9442
TEST_TIME 50 9388
TEST_TIME 51 9394
TEST_TIME 52 9706
TEST_TIME 53 9653
TEST_TIME 54 9571
TEST_TIME 55 9657
TEST_TIME 56 9658
TEST_TIME 57 9675
TEST_TIME 58 9711
TEST_TIME 59 9757
TEST_TIME 60 9624
TEST_TIME 61 9477
TEST_TIME 62 9421
TEST_TIME 63 9343
TEST_TIME 64 9257
TEST_TIME 65 9184
TEST_TIME 66 9091
TEST_TIME 67 9011
TEST_TIME 68 8980
TEST_TIME 69 8954
TEST_TIME 70 9045
TEST_TIME 71 9031
TEST_TIME 72 9079
TEST_TIME 73 9135
TEST_TIME 74 9114
TEST_TIME 75 9164
TEST_TIME 76 9192
TEST_TIME 77 9185
TEST_TIME 78 9302
TEST_TIME 79 9267
TEST_TIME 80 9257
TEST_TIME 81 9262
TEST_TIME 82 9279
TEST_TIME 83 9187
TEST_TIME 84 9113
TEST_TIME 85 9050
TEST_TIME 86 8983
TEST_TIME 87 8938
TEST_TIME 88 8927
TEST_TIME 89 8947
TEST_TIME 90 8918
TEST_TIME 91 8948
TEST_TIME 92 8952
TEST_TIME 93 8920
TEST_TIME 94 8898
TEST_TIME 95 8904
TEST_TIME 96 8918
TEST_TIME 97 8912
TEST_TIME 98 8950
TEST_TIME 99 8969
TEST_TIME 100 8920
TEST_TIME 101 8883
TEST_TIME 102 8828
TEST_TIME 103 8812
TEST_TIME 104 8770
TEST_TIME 105 8770
TEST_TIME 106 8752
TEST_TIME 107 8844
TEST_TIME 108 8797
TEST_TIME 109 8847
TEST_TIME 110 8823
TEST_TIME 111 8810
TEST_TIME 112 8816
TEST_TIME 113 8829
TEST_TIME 114 8963
TEST_TIME 115 8931
TEST_TIME 116 8931
TEST_TIME 117 8984
TEST_TIME 118 8912
TEST_TIME 119 8852
TEST_TIME 120 8870

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-04-2021 07:29:42 to 05-04-2021 07:29:45.

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 1465347 1465347 0 0.00 0 26627 7 0 0
doc_list_biud 1465344 1465344 0 0.00 0 88706 30 0 0
sp_add_invoice_to_stock 4767 4669 98 2.05 52 247920 18410 0 98
sp_cancel_adding_invoice 5549 5549 0 0.00 6 60479 814 0 0
sp_cancel_client_order 1656 1656 0 0.00 4 9146 303 0 0
sp_cancel_customer_reserve 548619 548619 0 0.00 1 88707 65 0 0
sp_cancel_pay_from_customer 642 642 0 0.00 3 2565 76 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 3 1330 67 0 0
sp_cancel_supplier_invoice 6135 6127 8 0.13 2 28728 822 0 8
sp_cancel_supplier_order 425 425 0 0.00 11 1054071 105709 0 0
sp_cancel_write_off 9726 9726 0 0.00 1 12729 117 0 0
sp_client_order 18389 18389 0 0.00 4 25135 346 0 0
sp_customer_reserve 434877 434877 0 0.00 0 50648 179 0 0
sp_fill_shopping_cart 28625 28625 0 0.00 1 17531 49 0 0
sp_fill_shopping_cart_clo_res 434873 434873 0 0.00 0 25485 16 0 0
sp_get_clo_for_invoice 4664 4664 0 0.00 0 37110 471 0 0
sp_kill_cost_storno 16310 16310 0 0.00 0 16982 61 0 0
sp_kill_qstorno_ret_qs2qd 1113516 1113516 0 0.00 0 88703 35 0 0
sp_kill_qty_storno 586017 586017 0 0.00 0 88706 75 0 0
sp_lock_selected_doc 19296 19296 0 0.00 0 8279 4 0 0
sp_make_cost_storno 26098 26098 0 0.00 0 23501 59 0 0
sp_multiply_rows_for_pdistr 26096 26096 0 0.00 0 26621 37 0 0
sp_multiply_rows_for_qdistr 18389 18389 0 0.00 0 25085 198 0 0
sp_pay_from_customer 6597 6597 0 0.00 4 26635 121 0 0
sp_pay_to_supplier 5583 5583 0 0.00 4 18274 139 0 0
sp_payment_common 12180 12180 0 0.00 1 11203 25 0 0
sp_qd_handle_on_cancel_clo 1656 1656 0 0.00 0 9136 276 0 0
sp_qd_handle_on_invoice_upd_sts 10212 10212 0 0.00 0 14133 370 0 0
sp_qd_handle_on_reserve_upd_sts 9252 9252 0 0.00 0 10713 50 0 0
sp_reserve_write_off 9255 9255 0 0.00 7 23526 173 0 0
sp_supplier_invoice 5184 5184 0 0.00 7 103457 3639 0 0
sp_supplier_order 5068 5068 0 0.00 5 50370 1225 0 0
srv_aggregate_perf_data 93 93 0 0.00 2825 197043 15290 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 993 162569 53079 0 0
srv_find_qd_qs_mism 1019082 1019082 0 0.00 0 17587 6 0 0
srv_make_invnt_saldo 419 419 0 0.00 34 92078 3540 0 0
srv_make_money_saldo 696 696 0 0.00 1 12360 482 0 0
srv_recalc_idx_stat 4 4 0 0.00 289478 780839 477428 0 0
t$perf-abend:sp_add_invoice_to_stock 98 98 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4670 4670 0 0.00 0 51024 185 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 0 5932 80 0 0
t$perf-norm:sp_cancel_client_order 1656 1656 0 0.00 0 3320 11 0 0
t$perf-norm:sp_cancel_customer_reserve 616 616 0 0.00 0 4284 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 642 642 0 0.00 0 412 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 1031 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 1997 21 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 17439 1254 0 0
t$perf-norm:sp_cancel_write_off 871 871 0 0.00 0 695 5 0 0
t$perf-norm:sp_client_order 18389 18389 0 0.00 0 6035 15 0 0
t$perf-norm:sp_customer_reserve 929 929 0 0.00 0 1783 7 0 0
t$perf-norm:sp_pay_from_customer 6597 6597 0 0.00 0 26213 12 0 0
t$perf-norm:sp_pay_to_supplier 5583 5583 0 0.00 0 3332 7 0 0
t$perf-norm:sp_reserve_write_off 9255 9255 0 0.00 0 2493 9 0 0
t$perf-norm:sp_supplier_invoice 5184 5184 0 0.00 0 7513 17 0 0
t$perf-norm:sp_supplier_order 5068 5068 0 0.00 0 5315 18 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 302 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 739 15 0 0
t$perf-norm:srv_make_invnt_saldo 419 419 0 0.00 0 5312 18 0 0
t$perf-norm:srv_make_money_saldo 696 696 0 0.00 0 1720 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 24 8 0 0
v_add_invoice_to_stock 9516 9320 196 2.05 0 7602 16 0 196
v_all_customers 33962 33962 0 0.00 0 107 1 0 0
v_all_suppliers 28756 28756 0 0.00 0 46 1 0 0
v_all_wares 36778 36778 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 273 6 0 0
v_cancel_client_order 3312 3312 0 0.00 0 1962 4 0 0
v_cancel_customer_prepayment 1284 1284 0 0.00 0 1961 7 0 0
v_cancel_customer_reserve 1232 1232 0 0.00 0 3166 6 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 1319 8 0 0
v_cancel_supplier_invoice 810 794 16 1.97 0 447 15 0 16
v_cancel_supplier_order 850 850 0 0.00 0 2276 8 0 0
v_cancel_write_off 1742 1742 0 0.00 0 1921 14 0 0
v_max_id_clo_ord 5065 5065 0 0.00 0 4707 3 0 0
v_max_id_clo_res 929 929 0 0.00 0 1455 8 0 0
v_max_id_ord_sup 5170 5170 0 0.00 0 5498 6 0 0
v_max_non_paid_invoice 1440 1440 0 0.00 0 737 2 0 0
v_max_non_paid_realizn 3460 3460 0 0.00 0 6672 4 0 0
v_min_id_clo_ord 5065 5065 0 0.00 0 4859 10 0 0
v_min_id_clo_res 929 929 0 0.00 0 2974 13 0 0
v_min_id_ord_sup 5170 5170 0 0.00 0 5127 11 0 0
v_min_non_paid_invoice 5583 5583 0 0.00 0 5419 17 0 0
v_min_non_paid_realizn 6597 6597 0 0.00 0 8904 18 0 0
v_our_firm 9090 9090 0 0.00 0 22 1 0 0
v_reserve_write_off 18504 18504 0 0.00 0 4492 4 0 0
x_lock_depdocs_on_canc_invoice 11279 11279 0 0.00 0 37859 111 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 8630 314 0 0
x_make_qstorno_1000_1200 5068 5068 0 0.00 1 50357 1138 0 0
x_make_qstorno_1200_2000 5184 5184 0 0.00 3 94971 3460 0 0
x_make_qstorno_2100_3300 434877 434877 0 0.00 0 50644 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-04-2021 07:29:46 to 05-04-2021 07:29:50.

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:57:00 7 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
04:58:02 34029 8192000000 9733340224 10029936640 802580800 8.922 100 87 174 0 831878240 7929392 21368048
04:59:43 16583 8192000000 9760769008 10067406848 837228496 9.272 100 87 174 0 871207840 13417824 20561520
05:01:07 24233 8192000000 9806500816 10127343616 868576848 9.586 100 76 152 0 917543136 31739056 17227232
05:02:34 12511 8192000000 9867431328 10201071616 912615568 10.023 100 75 150 1 973980336 45057856 16306912
05:03:49 7956 8192000000 9931085024 10279886848 970380256 10.590 100 71 139 2 1037668656 52692016 14596384
05:05:00 4365 8192000000 9984801344 10336284672 1006248752 10.939 100 90 180 1 1098943504 75285568 17409184
05:06:06 2521 8192000000 9995282384 10359578624 1033909392 11.206 100 78 158 0 1111561920 61908928 15743600
05:07:10 1944 8192000000 10042417744 10409299968 1057483088 11.432 100 95 190 1 1148296944 70888400 19925456
05:08:14 37398 8192000000 10017045008 10397282304 1075427648 11.604 100 59 115 3 1138219232 50532592 12258992
05:09:52 5913 8192000000 10006298864 10395426816 1082404496 11.670 100 40 79 1 1125869008 35213600 8250912
05:10:59 4639 8192000000 9991861296 10385907712 1090323424 11.746 100 36 70 2 1115042400 16745648 7973328
05:12:04 9491 8192000000 10007802800 10412322816 1099095760 11.829 100 32 65 0 1126809904 21341152 6372992
05:13:14 54990 8192000000 10025181408 10412609536 1097686080 11.816 100 55 109 2 1126508624 16376608 12445936
05:15:09 5954 8192000000 10046363104 10444996608 1106842928 11.903 100 51 102 2 1161778256 43409120 11526208
05:16:17 5775 8192000000 10006270544 10412437504 1089251760 11.736 99 33 66 0 1119619264 22973904 7393600
05:17:26 10728 8192000000 10004530688 10408718336 1053433552 11.394 96 26 52 1 1084970688 25621648 5915488
05:18:40 22089 8192000000 9989792576 10369421312 1061717200 11.473 99 56 110 2 1084040480 9137760 13185520
05:20:04 3542 8192000000 9929622624 10307592192 1000453056 10.883 97 44 85 4 1034559216 23792128 10314032
05:21:08 6003 8192000000 9932606416 10308743168 1005279904 10.930 98 41 84 2 1021955264 7331760 9343600
05:22:15 14130 8192000000 9935135792 10333237248 1010902816 10.984 97 21 42 0 1036882976 21655840 4324320
05:23:30 36007 8192000000 9984750080 10377494528 1045228672 11.315 99 30 61 0 1072786144 21280384 6277088
05:25:09 11050 8192000000 9976643488 10384490496 1067502368 11.528 100 49 98 1 1100036464 21493104 11040992
05:26:23 7250 8192000000 9990120096 10387660800 1076793392 11.617 100 45 92 1 1108895216 21797824 10304000
05:27:33 7025 8192000000 10017305120 10417078272 1092716128 11.768 100 37 73 1 1121361520 20323056 8322336
05:28:43 72915 8192000000 10071369712 10481569792 1107203792 11.906 100 35 70 2 1188204544 73154832 7845920
05:31:02 27451 8192000000 10081489008 10496647168 1123645680 12.061 100 47 94 2 1184821264 50828976 10346608
05:32:35 3000 8192000000 10122622048 10527997952 1131973120 12.140 100 64 126 1 1236618432 90986768 13658544
05:33:41 6122 8192000000 10126127616 10525106176 1129376512 12.115 100 59 117 1 1229701264 87602032 12722720
05:34:56 17479 8192000000 10135521824 10553749504 1145957520 12.272 100 52 102 1 1246995680 89346032 11692128
05:36:21 5362 8192000000 10151194672 10566828032 1154302896 12.350 100 59 113 5 1266561120 99345120 12913104
05:37:32 5085 8192000000 10209965696 10614906880 1172368592 12.519 100 66 132 2 1311376928 124510144 14498192
05:38:44 9645 8192000000 10265722032 10681937920 1177995856 12.571 100 63 123 0 1373159776 181388688 13775232
05:39:59 8320 8192000000 10289160048 10696351744 1144873456 12.261 98 67 133 0 1381790912 221622224 15295232
05:41:12 8469 8192000000 10325912416 10731753472 1177647872 12.568 100 76 150 0 1425636432 230736400 17252160
05:42:27 4360 8192000000 10356246320 10753499136 1169779968 12.495 100 82 161 0 1451006432 263059408 18167056
05:43:37 992 8192000000 10410039280 10808872960 1164555888 12.446 100 86 170 1 1504441584 320115472 19770224
05:44:45 4361 8192000000 10390589504 10805006336 1157189808 12.377 100 67 134 1 1489058400 315986128 15882464
05:45:54 10619 8192000000 10362080720 10806145024 1140163776 12.217 99 45 90 0 1472817664 320935520 11718368
05:47:26 10740 8192000000 10332318400 10758516736 1135828640 12.176 100 40 81 0 1436931824 290675232 10427952
05:48:42 9553 8192000000 10348484864 10811179008 1152583488 12.334 100 36 72 0 1453668656 292510368 8574800
05:50:01 17493 8192000000 10367929072 10804244480 1139643152 12.212 99 27 53 1 1450460608 303502400 7315056
05:51:25 8172 8192000000 10361951056 10787794944 1134132624 12.160 99 41 82 1 1445518288 300873520 10512144
05:52:36 34915 8192000000 10280864928 10779009024 1138132528 12.198 100 24 48 1 1395095712 250605392 6357792
05:54:28 62279 8192000000 10096632624 10634805248 1083503984 11.681 96 8 16 0 1197254544 111335664 2414896
05:56:30 119729 8192000000 10075522800 10621337600 1111836160 11.950 99 11 22 0 1207126848 93017056 2273632
05:59:30 43315 8192000000 10058320816 10510209024 1121884976 12.045 100 25 50 0 1160681504 33635888 5160640
06:01:25 61657 8192000000 10075781792 10526244864 1136183312 12.180 100 10 20 0 1172482352 34401376 1897664
06:03:27 93880 8192000000 10148910080 10597281792 1128714048 12.109 99 17 34 0 1237216128 104432144 4069936
06:06:01 56175 8192000000 10161633424 10628669440 1113337104 11.964 98 19 38 2 1253115312 135126016 4652192
06:08:13 162569 8192000000 10133129408 10610331648 1140970976 12.225 100 24 48 0 1253395136 107193200 5230960
06:12:04 81416 8192000000 10064416848 10529890304 1141200256 12.227 100 10 20 0 1170393504 27263680 1929568
06:14:26 83511 8192000000 10070714704 10513428480 1098846656 11.827 96 9 18 0 1125054880 24893296 1314928
06:16:53 67298 8192000000 10191366288 10658365440 1147567648 12.287 99 24 48 1 1245155216 92237888 5349680
06:19:31 38239 8192000000 10100340576 10533347328 1152825664 12.336 100 33 65 1 1187270896 27419360 7025872
06:21:11 48817 8192000000 10088216240 10535632896 1144168384 12.255 100 21 42 2 1203902720 55463376 4270960
06:23:08 56647 8192000000 10101727104 10559930368 1128063536 12.103 98 28 56 1 1212655088 79067552 5524000
06:25:05 159839 8192000000 10088067824 10551672832 1136377584 12.181 99 18 36 1 1175349072 35717568 3253920
06:28:45 44338 8192000000 10051833216 10533216256 1117250816 12.001 98 15 30 1 1149629696 29439072 2939808
06:30:51 81417 8192000000 10078955520 10534453248 1111657600 11.948 98 10 21 0 1141830064 27948144 2224320
06:33:12 74467 8192000000 10081878272 10562867200 1101554832 11.852 97 10 20 0 1167952288 64106992 2290464
06:35:37 52143 8192000000 10136033632 10593796096 1121825264 12.044 98 20 40 0 1202914048 76855648 4233136
06:37:45 119008 8192000000 10089510400 10555203584 1085403264 11.699 96 20 40 1 1175273968 85671120 4199584
06:40:44 78203 8192000000 10073577936 10577276928 1083630400 11.682 96 26 52 2 1163666064 74637904 5397760
06:43:05 36022 8192000000 10098735696 10542542848 1077261328 11.621 96 45 90 0 1152348288 65357920 9729040
06:44:43 90092 8192000000 10068037776 10492440576 1065664464 11.511 95 27 54 1 1107058896 36540064 4854368
06:47:13 37589 8192000000 10123423056 10565980160 1089919968 11.742 97 43 86 0 1175225120 76772000 8533152
06:48:53 106104 8192000000 10029608640 10469261312 1103328816 11.869 99 21 42 1 1169710976 62152720 4229440
06:51:44 80164 8192000000 10057782496 10518503424 1097528912 11.814 98 9 18 0 1129353328 30228000 1596416
06:54:07 62099 8192000000 10086500384 10523033600 1098155936 11.820 98 12 24 0 1156858272 56135904 2566432
06:56:16 67240 8192000000 10106754624 10548592640 1124691312 12.071 100 18 36 0 1179986320 51554400 3740608
06:58:30 56081 8192000000 10055616032 10496245760 1096926496 11.808 97 23 44 0 1118576832 16940768 4709568
07:00:30 45962 8192000000 10096627968 10533474304 1118470448 12.013 100 24 48 0 1193961296 70442272 5048576
07:02:41 69156 8192000000 10087689136 10536722432 1083488816 11.681 96 26 52 0 1154724224 66213792 5021616
07:04:54 118397 8192000000 10083780976 10539966464 1095701856 11.797 97 17 34 1 1146592528 47120848 3769824
07:08:24 54461 8192000000 10072488384 10545963008 1121700272 12.043 99 5 10 0 1164068096 41567488 800336
07:10:19 82933 8192000000 10093180976 10550472704 1114034576 11.971 98 19 38 1 1161313552 43692832 3586144
07:12:47 63503 8192000000 10146484592 10634895360 1132767664 12.147 99 22 44 0 1207098368 69387840 4942864
07:15:13 131220 8192000000 10078003776 10531823616 1073533664 11.586 94 7 14 0 1116818432 41098000 2186768
07:18:37 38962 8192000000 10154876576 10609651712 1136366848 12.181 100 29 58 1 1225492672 82823856 6301968
07:20:22 45831 8192000000 10081061424 10621595648 1130557568 12.127 100 4 8 0 1213776736 82492752 726416
07:22:08 31870 8192000000 10127197216 10566332416 1114928464 11.979 98 26 52 0 1190759056 70483616 5346976
07:23:40 77169 8192000000 10061926624 10561323008 1122319248 12.049 100 7 14 0 1172344384 48454816 1570320
07:26:32 32791 8192000000 9333302000 9662476288 474150432 5.471 43 3 6 0 486870992 12252976 467584

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 05-04-2021 07:29:52 to 05-04-2021 07:30:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 98 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-04-2021 07:30:07 to 05-04-2021 07:30:09.

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          230645
MON$OLDEST_ACTIVE               230646
MON$OLDEST_SNAPSHOT             230646
MON$NEXT_TRANSACTION            230646
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                       2101632
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             788
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2404 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2404 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2404 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.2404 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 ---
:46: 
:47: mscs-test-161.budzdorov.ru    Mon Apr  5 07:27:39 2021
:48:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:49: 
:50: 
:51: mscs-test-161.budzdorov.ru    Mon Apr  5 07:27:39 2021
:52:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:53: 
--- end of diff output ---

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

05.04.21 07:30:10. 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-04-05 07:30:12 No severe PSQL-related problems occured
05.04.21 07:30:14. End of report.