Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.04.2021 07:41
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 16908408 kB
MemAvailable 31441036 kB
Buffers 0 kB
Cached 14724220 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-02 07:41:01
DTS_END                         2021-04-02 07:41:01.0730
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/50158
TRN_ID                          231355
ATT_ID                          624
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: 8645.90 24456 1037508
customer order: creation 162.06 459 19447
customer order: refuse 13.64 341 1637
order to supplier: creation 43.41 1565 5209
order to supplier: removal 3.23 129445 387
invoice (draft): creation 43.65 3865 5238
invoice (draft): removal 47.98 1053 5758
invoice accept: apply 39.99 19621 4799
invoice accept: cancel 43.18 996 5182
customer reserve: creation 3717.22 190 446066
customer reserve: removal 4259.83 77 511179
realization accept: apply 80.16 185 9619
realization accept: cancel 70.38 152 8446
payment from customer: creation 55.59 159 6671
payment from customer: removal 5.43 69 651
payment to supplier: creation 46.33 149 5559
payment to supplier: removal 3.56 92 427
service: total inventory turnovers 3.78 3486 453
service: total monetary turnovers 5.58 698 669
service: aggregate perf. data 0.89 15820 107
service: refresh index statistics 0.03 310692 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-04-2021 07:41:50 to 02-04-2021 07:41:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4063
TEST_TIME 2 3729
TEST_TIME 3 4365
TEST_TIME 4 4399
TEST_TIME 5 4884
TEST_TIME 6 4852
TEST_TIME 7 5291
TEST_TIME 8 5496
TEST_TIME 9 5390
TEST_TIME 10 5522
TEST_TIME 11 5383
TEST_TIME 12 5454
TEST_TIME 13 5756
TEST_TIME 14 5957
TEST_TIME 15 6126
TEST_TIME 16 6389
TEST_TIME 17 6650
TEST_TIME 18 6950
TEST_TIME 19 7171
TEST_TIME 20 7314
TEST_TIME 21 7705
TEST_TIME 22 8099
TEST_TIME 23 8378
TEST_TIME 24 8854
TEST_TIME 25 8977
TEST_TIME 26 9229
TEST_TIME 27 9783
TEST_TIME 28 10652
TEST_TIME 29 10452
TEST_TIME 30 10116
TEST_TIME 31 9930
TEST_TIME 32 10071
TEST_TIME 33 9909
TEST_TIME 34 10239
TEST_TIME 35 10016
TEST_TIME 36 9781
TEST_TIME 37 9721
TEST_TIME 38 9605
TEST_TIME 39 9537
TEST_TIME 40 9512
TEST_TIME 41 9475
TEST_TIME 42 9382
TEST_TIME 43 9279
TEST_TIME 44 9201
TEST_TIME 45 9088
TEST_TIME 46 8950
TEST_TIME 47 8998
TEST_TIME 48 9105
TEST_TIME 49 8985
TEST_TIME 50 8886
TEST_TIME 51 8894
TEST_TIME 52 8899
TEST_TIME 53 8842
TEST_TIME 54 8809
TEST_TIME 55 8787
TEST_TIME 56 8726
TEST_TIME 57 8795
TEST_TIME 58 8783
TEST_TIME 59 8848
TEST_TIME 60 8961
TEST_TIME 61 9007
TEST_TIME 62 9059
TEST_TIME 63 9050
TEST_TIME 64 9036
TEST_TIME 65 9046
TEST_TIME 66 9006
TEST_TIME 67 8953
TEST_TIME 68 8878
TEST_TIME 69 8788
TEST_TIME 70 8746
TEST_TIME 71 8742
TEST_TIME 72 8676
TEST_TIME 73 8657
TEST_TIME 74 8641
TEST_TIME 75 8636
TEST_TIME 76 8753
TEST_TIME 77 8742
TEST_TIME 78 8798
TEST_TIME 79 8748
TEST_TIME 80 8906
TEST_TIME 81 8926
TEST_TIME 82 8962
TEST_TIME 83 8907
TEST_TIME 84 8947
TEST_TIME 85 8853
TEST_TIME 86 8796
TEST_TIME 87 8735
TEST_TIME 88 8678
TEST_TIME 89 8648
TEST_TIME 90 8617
TEST_TIME 91 8635
TEST_TIME 92 8641
TEST_TIME 93 8663
TEST_TIME 94 8639
TEST_TIME 95 8676
TEST_TIME 96 8726
TEST_TIME 97 8734
TEST_TIME 98 8765
TEST_TIME 99 8749
TEST_TIME 100 8781
TEST_TIME 101 8784
TEST_TIME 102 8784
TEST_TIME 103 8798
TEST_TIME 104 8726
TEST_TIME 105 8676
TEST_TIME 106 8641
TEST_TIME 107 8594
TEST_TIME 108 8553
TEST_TIME 109 8519
TEST_TIME 110 8574
TEST_TIME 111 8569
TEST_TIME 112 8581
TEST_TIME 113 8572
TEST_TIME 114 8545
TEST_TIME 115 8574
TEST_TIME 116 8609
TEST_TIME 117 8630
TEST_TIME 118 8698
TEST_TIME 119 8686
TEST_TIME 120 8647

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-04-2021 07:41:54 to 02-04-2021 07:41:57.

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 1449353 1449353 0 0.00 0 25420 7 0 0
doc_list_biud 1449353 1449353 0 0.00 0 35272 34 0 0
sp_add_invoice_to_stock 4870 4799 71 1.45 48 173424 19621 0 71
sp_cancel_adding_invoice 5182 5182 0 0.00 6 74243 996 0 0
sp_cancel_client_order 1637 1637 0 0.00 4 12090 341 0 0
sp_cancel_customer_reserve 511179 511179 0 0.00 1 31529 77 0 0
sp_cancel_pay_from_customer 651 651 0 0.00 3 1808 70 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 8114 93 0 0
sp_cancel_supplier_invoice 5764 5758 6 0.10 2 35288 1054 0 6
sp_cancel_supplier_order 387 387 0 0.00 5 1277353 129445 0 0
sp_cancel_write_off 8446 8446 0 0.00 1 14445 153 0 0
sp_client_order 19447 19447 0 0.00 4 23570 459 0 0
sp_customer_reserve 446066 446066 0 0.00 0 81032 190 0 0
sp_fill_shopping_cart 29892 29892 0 0.00 1 15648 52 0 0
sp_fill_shopping_cart_clo_res 446063 446063 0 0.00 0 20452 18 0 0
sp_get_clo_for_invoice 4796 4796 0 0.00 0 41883 482 0 0
sp_kill_cost_storno 14706 14706 0 0.00 0 16400 65 0 0
sp_kill_qstorno_ret_qs2qd 1036949 1036949 0 0.00 0 35269 42 0 0
sp_kill_qty_storno 547004 547004 0 0.00 0 35272 90 0 0
sp_lock_selected_doc 19883 19883 0 0.00 0 11426 5 0 0
sp_make_cost_storno 26645 26645 0 0.00 0 19977 66 0 0
sp_multiply_rows_for_pdistr 26645 26645 0 0.00 0 25408 36 0 0
sp_multiply_rows_for_qdistr 19447 19447 0 0.00 0 15233 254 0 0
sp_pay_from_customer 6671 6671 0 0.00 4 24983 159 0 0
sp_pay_to_supplier 5559 5559 0 0.00 4 22790 150 0 0
sp_payment_common 12230 12230 0 0.00 1 24132 48 0 0
sp_qd_handle_on_cancel_clo 1637 1637 0 0.00 0 12062 314 0 0
sp_qd_handle_on_invoice_upd_sts 9978 9978 0 0.00 0 31818 436 0 0
sp_qd_handle_on_reserve_upd_sts 9619 9619 0 0.00 0 16277 52 0 0
sp_reserve_write_off 9619 9619 0 0.00 7 22795 186 0 0
sp_supplier_invoice 5238 5238 0 0.00 6 96523 3865 0 0
sp_supplier_order 5209 5209 0 0.00 6 93721 1566 0 0
srv_aggregate_perf_data 107 107 0 0.00 2728 123753 15820 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1024 142663 47484 0 0
srv_find_qd_qs_mism 991527 991527 0 0.00 0 17577 6 0 0
srv_make_invnt_saldo 453 453 0 0.00 36 113281 3486 0 0
srv_make_money_saldo 669 669 0 0.00 1 16182 698 0 0
srv_recalc_idx_stat 4 4 0 0.00 234895 496990 310692 0 0
t$perf-abend:sp_add_invoice_to_stock 71 71 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4799 4799 0 0.00 0 13932 170 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 0 7329 127 0 0
t$perf-norm:sp_cancel_client_order 1637 1637 0 0.00 0 5237 21 0 0
t$perf-norm:sp_cancel_customer_reserve 660 660 0 0.00 0 549 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 651 651 0 0.00 0 1225 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 3034 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 2523 21 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 22890 1460 0 0
t$perf-norm:sp_cancel_write_off 947 947 0 0.00 0 7600 17 0 0
t$perf-norm:sp_client_order 19447 19447 0 0.00 0 11498 20 0 0
t$perf-norm:sp_customer_reserve 991 991 0 0.00 0 5341 9 0 0
t$perf-norm:sp_pay_from_customer 6671 6671 0 0.00 0 17554 14 0 0
t$perf-norm:sp_pay_to_supplier 5559 5559 0 0.00 0 5664 12 0 0
t$perf-norm:sp_reserve_write_off 9619 9619 0 0.00 0 17691 15 0 0
t$perf-norm:sp_supplier_invoice 5238 5238 0 0.00 0 9456 17 0 0
t$perf-norm:sp_supplier_order 5209 5209 0 0.00 0 5680 16 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 276 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 51 2 0 0
t$perf-norm:srv_make_invnt_saldo 453 453 0 0.00 0 733 11 0 0
t$perf-norm:srv_make_money_saldo 669 669 0 0.00 0 5798 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 110 29 0 0
v_add_invoice_to_stock 9734 9592 142 1.45 0 4746 14 0 142
v_all_customers 35050 35050 0 0.00 0 162 1 0 0
v_all_suppliers 28942 28942 0 0.00 0 18 1 0 0
v_all_wares 38892 38892 0 0.00 0 145 1 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 1935 10 0 0
v_cancel_client_order 3274 3274 0 0.00 0 1369 5 0 0
v_cancel_customer_prepayment 1302 1302 0 0.00 0 1336 6 0 0
v_cancel_customer_reserve 1320 1320 0 0.00 0 1601 7 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 1595 6 0 0
v_cancel_supplier_invoice 762 750 12 1.57 0 525 12 0 12
v_cancel_supplier_order 774 774 0 0.00 0 2026 9 0 0
v_cancel_write_off 1894 1894 0 0.00 0 5127 22 0 0
v_max_id_clo_ord 5208 5208 0 0.00 0 166 1 0 0
v_max_id_clo_res 991 991 0 0.00 0 4525 14 0 0
v_max_id_ord_sup 5237 5237 0 0.00 0 9670 7 0 0
v_max_non_paid_invoice 1533 1533 0 0.00 0 7647 7 0 0
v_max_non_paid_realizn 3686 3686 0 0.00 0 1313 3 0 0
v_min_id_clo_ord 5208 5208 0 0.00 0 12057 14 0 0
v_min_id_clo_res 991 991 0 0.00 0 4052 16 0 0
v_min_id_ord_sup 5237 5237 0 0.00 0 7716 10 0 0
v_min_non_paid_invoice 5559 5559 0 0.00 0 22750 25 0 0
v_min_non_paid_realizn 6671 6671 0 0.00 0 24128 52 0 0
v_our_firm 9812 9812 0 0.00 0 22 1 0 0
v_reserve_write_off 19238 19238 0 0.00 0 3996 6 0 0
x_lock_depdocs_on_canc_invoice 10565 10565 0 0.00 0 17805 133 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 10411 373 0 0
x_make_qstorno_1000_1200 5209 5209 0 0.00 2 93712 1478 0 0
x_make_qstorno_1200_2000 5238 5238 0 0.00 3 89681 3662 0 0
x_make_qstorno_2100_3300 446066 446066 0 0.00 0 81031 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-04-2021 07:41:58 to 02-04-2021 07:42:02.

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:11:04 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
05:12:05 25654 8192000000 9712355776 10008166400 792431248 8.820 100 81 162 0 819631536 7386240 19814048
05:13:38 14630 8192000000 9757750448 10062053376 835740272 9.257 100 88 176 0 868417904 11634560 21043072
05:15:02 17596 8192000000 9807446032 10126196736 870932096 9.609 100 80 160 0 912797616 23432512 18433008
05:16:25 12554 8192000000 9865722704 10194964480 919707072 10.093 100 88 176 0 980338464 41181024 19450368
05:17:43 7634 8192000000 9919934688 10264379392 958770384 10.477 100 78 157 2 1032622064 57496928 16354752
05:18:52 3827 8192000000 9971762208 10327121920 998082464 10.860 100 78 153 0 1079426544 66361152 14982928
05:19:58 2280 8192000000 9991219568 10353852416 1029141072 11.160 100 85 171 1 1104636336 58169248 17326016
05:21:03 1199 8192000000 10039504976 10407567360 1057030704 11.428 100 93 189 1 1146734288 70792160 18911424
05:22:05 10025 8192000000 9995819040 10384887808 1063559584 11.491 100 56 113 1 1126540960 51815120 11166256
05:23:22 3476 8192000000 9975376656 10362372096 1067100336 11.524 100 51 104 0 1088866976 9625776 12140864
05:24:27 7114 8192000000 10000613168 10396188672 1075644368 11.606 100 33 66 0 1111942896 29568464 6730064
05:25:37 6060 8192000000 10003546784 10403667968 1088387024 11.727 100 41 82 2 1121481984 24543728 8551232
05:26:45 55744 8192000000 9999027232 10403090432 1090595632 11.748 100 41 82 1 1117691888 17897200 9199056
05:28:53 10408 8192000000 10021517296 10437881856 1097800512 11.817 100 43 86 0 1133129280 25865024 9463744
05:30:08 11747 8192000000 10024478640 10432946176 1098649184 11.825 100 25 50 2 1131806096 28264880 4892032
05:31:22 10212 8192000000 9980327792 10379509760 1022748544 11.099 96 18 37 0 1050676176 24000000 3927632
05:32:33 38056 8192000000 9965753920 10359361536 974799264 10.634 92 23 45 0 996541984 16660736 5081984
05:34:13 11029 8192000000 9930219520 10321682432 996231376 10.842 97 33 65 2 1024783744 21232256 7320112
05:35:25 4524 8192000000 9931039312 10308218880 1013227600 11.007 100 38 75 1 1036898320 15099424 8571296
05:36:32 73444 8192000000 9967019200 10360909824 1029063184 11.159 99 29 57 0 1050743312 14949984 6730144
05:38:48 8089 8192000000 9975124096 10375987200 1049640864 11.357 100 43 85 3 1072273008 13144240 9487904
05:39:57 7265 8192000000 9987852960 10388406272 1066180496 11.516 100 48 96 3 1097803040 20675712 10946832
05:41:06 5165 8192000000 10001204720 10387939328 1079044192 11.638 100 41 82 2 1105267328 17379264 8843872
05:42:21 53464 8192000000 10039393680 10439852032 1100382000 11.841 100 49 98 0 1142141760 30572496 11187264
05:44:22 4377 8192000000 10087437504 10479329280 1117664672 12.005 100 64 124 0 1192073776 61760480 12648624
05:45:32 31655 8192000000 10105469808 10516500480 1129239632 12.114 100 46 89 1 1216392128 78597280 8555216
05:47:09 29264 8192000000 10154993360 10553106432 1133820048 12.157 99 57 114 0 1260627888 114786384 12021456
05:48:41 7237 8192000000 10196628448 10602184704 1153645152 12.344 100 61 122 1 1296239312 129396704 13197456
05:49:55 14403 8192000000 10209188672 10636873728 1165347280 12.453 100 50 100 0 1322983008 146724928 10910800
05:51:15 15679 8192000000 10224265744 10654208000 1165686096 12.456 99 50 99 2 1324890640 147459680 11744864
05:52:40 4510 8192000000 10244010736 10660687872 1160665136 12.409 99 47 91 0 1342062384 170243008 11154240
05:53:50 8105 8192000000 10299388784 10709413888 1165454032 12.454 99 58 116 0 1390257776 211731520 13072224
05:55:04 4592 8192000000 10365579104 10768490496 1183759536 12.625 100 84 166 0 1458277952 256135856 18382560
05:56:11 1915 8192000000 10381907904 10779582464 1166572640 12.465 99 91 180 0 1475314928 287312528 21429760
05:57:19 5847 8192000000 10362037136 10782416896 1152976656 12.337 99 67 131 1 1463729568 293203392 17549520
05:58:32 1151 8192000000 10372836960 10772451328 1152654592 12.334 100 92 184 0 1468476672 294482432 21339648
05:59:41 1019 8192000000 10396250432 10804621312 1159675904 12.400 99 88 174 0 1488842560 309024192 20142464
06:00:47 1951 8192000000 10441785408 10838183936 1169191376 12.489 100 92 183 0 1530374736 340830992 20352368
06:01:53 8351 8192000000 10440431104 10843549696 1142253456 12.237 100 85 170 0 1530932368 368569152 20109760
06:03:06 10279 8192000000 10429103120 10859454464 1143070272 12.244 100 40 80 0 1519084928 365248624 10766032
06:04:19 8296 8192000000 10416194096 10833977344 1122045008 12.046 99 58 117 1 1497859328 362234304 13580016
06:05:30 24878 8192000000 10321576048 10755727360 1133987664 12.159 100 28 56 1 1415315472 274503504 6824304
06:07:02 23917 8192000000 10306779760 10764460032 1122708688 12.053 99 26 52 2 1400506416 271129184 6668544
06:08:36 34112 8192000000 10199933920 10685906944 1110646000 11.939 99 19 38 1 1416283424 300166768 5470656
06:10:27 82273 8192000000 10134969312 10558070784 1087804512 11.722 97 31 62 1 1186194048 90876560 7512976
06:12:57 19647 8192000000 10178731120 10628116480 1126298368 12.086 100 42 84 0 1256700272 121319296 9082608
06:14:19 142662 8192000000 10052931824 10498338816 1126333040 12.087 100 21 42 0 1263507600 132601552 4573008
06:18:04 82826 8192000000 10065253312 10518581248 1122398592 12.050 99 19 38 0 1153744800 27560688 3785520
06:20:28 47056 8192000000 10075008992 10526793728 1128307808 12.105 99 19 38 1 1178435168 45837664 4289696
06:22:16 24087 8192000000 10085042848 10531639296 1122740784 12.053 99 40 80 1 1181118432 49523120 8854528
06:23:43 51513 8192000000 10073222096 10511130624 1124243472 12.067 99 34 68 1 1157754240 25252624 8258144
06:25:36 80754 8192000000 10090812304 10534330368 1115565328 11.985 97 23 46 0 1158795648 38233696 4996624
06:28:00 117298 8192000000 10041650016 10558328832 1124424992 12.069 98 20 40 0 1233481408 104214464 4841952
06:31:10 46138 8192000000 10091231104 10537615360 1134999712 12.168 99 16 32 0 1192836608 54564576 3272320
06:32:59 72667 8192000000 10054900240 10551619584 1125748768 12.081 98 9 18 0 1182416896 54821744 1846384
06:35:12 38991 8192000000 10062182944 10504806400 1142874720 12.243 100 15 30 3 1180077264 34001296 3201248
06:36:54 15421 8192000000 10073101712 10511237120 1145700608 12.269 100 44 87 1 1176756704 22104928 8951168
06:38:11 63857 8192000000 10131400560 10598072320 1146521952 12.277 99 24 47 1 1242727632 91240560 4965120
06:40:15 96261 8192000000 10129430944 10636185600 1105343200 11.888 96 12 24 0 1191515248 83420192 2751856
06:42:51 74449 8192000000 10050688272 10516615168 1086956496 11.714 95 20 40 1 1126421184 35716720 3747968
06:45:22 46509 8192000000 10087425712 10514845696 1104552944 11.881 96 25 50 1 1140478096 31147216 4777936
06:47:11 111172 8192000000 10069386816 10556456960 1127860976 12.101 99 31 62 1 1182322688 47654288 6807424
06:50:13 49375 8192000000 10104698464 10559365120 1145126752 12.264 100 7 14 0 1209031888 62357120 1548016
06:53:25 61310 8192000000 10089759296 10549653504 1089014752 11.733 96 24 48 0 1152749984 57377808 6357424
06:55:26 63942 8192000000 10125237104 10590957568 1092820768 11.769 97 22 44 0 1202109104 104365808 4922528
06:57:32 58691 8192000000 10083289520 10509692928 1095631184 11.796 97 17 34 1 1162752000 64045936 3074880
06:59:33 32956 8192000000 10110068560 10544697344 1128462832 12.107 100 21 42 0 1192964064 60079856 4421376
07:01:16 82314 8192000000 10061530080 10534281216 1090559104 11.748 97 14 26 1 1163860720 70731200 2570416
07:03:39 40297 8192000000 10060939776 10483822592 1105237632 11.887 98 40 77 1 1144461936 30738096 8486208
07:05:23 126661 8192000000 10040953968 10496487424 1108495296 11.918 99 20 40 0 1160095648 47466304 4134048
07:08:43 62797 8192000000 10047356576 10499776512 1126328912 12.087 100 11 23 0 1149291488 20972320 1990256
07:10:53 73321 8192000000 10066542128 10500743168 1067497040 11.528 94 17 33 1 1115847584 45335744 3014800
07:13:10 84602 8192000000 10041248672 10501066752 1115334688 11.983 99 20 41 2 1190215040 70532576 4347776
07:15:35 44534 8192000000 10096151600 10546659328 1113455984 11.965 98 38 77 0 1182481776 60648528 8377264
07:17:22 73031 8192000000 10108198352 10535211008 1106777376 11.902 98 26 52 3 1163915776 51790208 5348192
07:19:36 63090 8192000000 10085393008 10546376704 1099948976 11.837 97 21 42 0 1140792512 36742112 4101424
07:21:47 67736 8192000000 10083146496 10528419840 1090822368 11.750 96 21 42 0 1142824576 47852624 4149584
07:23:59 31575 8192000000 10101944560 10542174208 1128378736 12.106 99 18 36 0 1176229616 44426400 3424480
07:25:45 102025 8192000000 10044761072 10495782912 1119869104 12.026 99 16 31 2 1142139216 18852752 3417360
07:28:30 62576 8192000000 10084477904 10554114048 1138972032 12.206 100 6 12 0 1174323712 33901552 1450128
07:30:36 79435 8192000000 10109143888 10614042624 1146014128 12.272 100 24 48 3 1239112000 87632384 5465488
07:33:04 43247 8192000000 10087701952 10527817728 1126575424 12.089 99 25 48 2 1156749936 25123664 5050848
07:34:47 52949 8192000000 10140870224 10582368256 1134195552 12.161 99 20 40 1 1215932208 77139488 4597168
07:36:49 52657 8192000000 10132926064 10595356672 1119935872 12.026 98 23 46 1 1220292112 95235184 5121056
07:38:52 68558 8192000000 10081699280 10554126336 1085277168 11.698 96 13 26 1 1151251648 63468656 2505824

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-04-2021 07:42:04 to 02-04-2021 07:42:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 71 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-04-2021 07:42:20 to 02-04-2021 07:42:22.

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          230277
MON$OLDEST_ACTIVE               231474
MON$OLDEST_SNAPSHOT             231474
MON$NEXT_TRANSACTION            231474
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                       2090560
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             792
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2399 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2399 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2399 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.2399 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    Fri Apr  2 07:41:15 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Fri Apr  2 07:41:18 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
--- end of diff output ---

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

02.04.21 07:42:23. 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-02 07:42:25 No severe PSQL-related problems occured
02.04.21 07:42:26. End of report.