Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.03.2021 10:27
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 16935972 kB
MemAvailable 31538520 kB
Buffers 0 kB
Cached 14694192 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-03-14 10:26:55
DTS_END                         2021-03-14 10:26:55.0130
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/40316
TRN_ID                          240623
ATT_ID                          703
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: 8920.11 27328 1070413
customer order: creation 178.43 442 21411
customer order: refuse 15.11 284 1813
order to supplier: creation 46.44 1352 5573
order to supplier: removal 3.27 112733 392
invoice (draft): creation 45.99 3527 5519
invoice (draft): removal 49.02 931 5882
invoice accept: apply 42.24 16546 5069
invoice accept: cancel 44.22 878 5306
customer reserve: creation 3809.83 163 457180
customer reserve: removal 4382.17 67 525860
realization accept: apply 84.71 182 10165
realization accept: cancel 84.72 122 10166
payment from customer: creation 62.29 148 7475
payment from customer: removal 5.63 132 675
payment to supplier: creation 51.32 135 6158
payment to supplier: removal 3.51 70 421
service: total inventory turnovers 4.04 3442 485
service: total monetary turnovers 6.31 552 757
service: aggregate perf. data 0.86 17518 103
service: refresh index statistics 0.03 387342 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 14-03-2021 10:28:09 to 14-03-2021 10:28:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2577
TEST_TIME 2 3922
TEST_TIME 3 3280
TEST_TIME 4 4390
TEST_TIME 5 5161
TEST_TIME 6 5088
TEST_TIME 7 5585
TEST_TIME 8 6058
TEST_TIME 9 6000
TEST_TIME 10 6116
TEST_TIME 11 6328
TEST_TIME 12 6484
TEST_TIME 13 6501
TEST_TIME 14 6427
TEST_TIME 15 6442
TEST_TIME 16 6667
TEST_TIME 17 6726
TEST_TIME 18 7017
TEST_TIME 19 7132
TEST_TIME 20 7897
TEST_TIME 21 8150
TEST_TIME 22 8377
TEST_TIME 23 8861
TEST_TIME 24 9002
TEST_TIME 25 9704
TEST_TIME 26 10634
TEST_TIME 27 10831
TEST_TIME 28 10693
TEST_TIME 29 10511
TEST_TIME 30 10392
TEST_TIME 31 10229
TEST_TIME 32 10060
TEST_TIME 33 9919
TEST_TIME 34 9790
TEST_TIME 35 9769
TEST_TIME 36 9820
TEST_TIME 37 9600
TEST_TIME 38 9492
TEST_TIME 39 9437
TEST_TIME 40 9372
TEST_TIME 41 9299
TEST_TIME 42 9329
TEST_TIME 43 9280
TEST_TIME 44 9237
TEST_TIME 45 9200
TEST_TIME 46 9139
TEST_TIME 47 9094
TEST_TIME 48 9109
TEST_TIME 49 9326
TEST_TIME 50 9274
TEST_TIME 51 9307
TEST_TIME 52 9305
TEST_TIME 53 9342
TEST_TIME 54 9287
TEST_TIME 55 9124
TEST_TIME 56 9075
TEST_TIME 57 9087
TEST_TIME 58 9017
TEST_TIME 59 8960
TEST_TIME 60 8903
TEST_TIME 61 8851
TEST_TIME 62 8928
TEST_TIME 63 8920
TEST_TIME 64 8877
TEST_TIME 65 9048
TEST_TIME 66 9046
TEST_TIME 67 9094
TEST_TIME 68 9077
TEST_TIME 69 9135
TEST_TIME 70 9109
TEST_TIME 71 9151
TEST_TIME 72 9178
TEST_TIME 73 9171
TEST_TIME 74 9112
TEST_TIME 75 8994
TEST_TIME 76 8924
TEST_TIME 77 8859
TEST_TIME 78 8825
TEST_TIME 79 8894
TEST_TIME 80 8858
TEST_TIME 81 8849
TEST_TIME 82 8856
TEST_TIME 83 8837
TEST_TIME 84 8856
TEST_TIME 85 8933
TEST_TIME 86 8987
TEST_TIME 87 9031
TEST_TIME 88 9012
TEST_TIME 89 9011
TEST_TIME 90 9080
TEST_TIME 91 9020
TEST_TIME 92 8958
TEST_TIME 93 8908
TEST_TIME 94 8915
TEST_TIME 95 8886
TEST_TIME 96 8879
TEST_TIME 97 8851
TEST_TIME 98 8854
TEST_TIME 99 8832
TEST_TIME 100 8808
TEST_TIME 101 8913
TEST_TIME 102 8921
TEST_TIME 103 8898
TEST_TIME 104 8922
TEST_TIME 105 8945
TEST_TIME 106 8985
TEST_TIME 107 9032
TEST_TIME 108 9028
TEST_TIME 109 8967
TEST_TIME 110 8906
TEST_TIME 111 8917
TEST_TIME 112 8864
TEST_TIME 113 8863
TEST_TIME 114 8854
TEST_TIME 115 8856
TEST_TIME 116 8859
TEST_TIME 117 8820
TEST_TIME 118 8832
TEST_TIME 119 8835
TEST_TIME 120 8925
TEST_TIME 121 8851

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-03-2021 10:28:13 to 14-03-2021 10:28:17.

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 1494971 1494971 0 0.00 0 31943 6 0 0
doc_list_biud 1494970 1494970 0 0.00 0 74152 30 0 0
sp_add_invoice_to_stock 5198 5069 129 2.48 29 165165 16546 0 129
sp_cancel_adding_invoice 5306 5306 0 0.00 5 103872 879 0 0
sp_cancel_client_order 1813 1813 0 0.00 4 10253 284 0 0
sp_cancel_customer_reserve 525860 525860 0 0.00 1 74153 68 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 3 18664 133 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 2050 70 0 0
sp_cancel_supplier_invoice 5895 5882 13 0.22 1 29593 931 0 13
sp_cancel_supplier_order 392 392 0 0.00 15 1153183 112733 0 0
sp_cancel_write_off 10166 10166 0 0.00 1 14534 122 0 0
sp_client_order 21411 21411 0 0.00 4 26915 442 0 0
sp_customer_reserve 457180 457180 0 0.00 0 65655 164 0 0
sp_fill_shopping_cart 32492 32492 0 0.00 1 37767 48 0 0
sp_fill_shopping_cart_clo_res 457177 457177 0 0.00 0 25263 19 0 0
sp_get_clo_for_invoice 5060 5060 0 0.00 0 42736 513 0 0
sp_kill_cost_storno 16568 16568 0 0.00 0 9138 54 0 0
sp_kill_qstorno_ret_qs2qd 1068160 1068160 0 0.00 0 74149 37 0 0
sp_kill_qty_storno 564643 564643 0 0.00 0 74151 78 0 0
sp_lock_selected_doc 21198 21198 0 0.00 0 9229 5 0 0
sp_make_cost_storno 28857 28857 0 0.00 0 31934 61 0 0
sp_multiply_rows_for_pdistr 28857 28857 0 0.00 0 21665 32 0 0
sp_multiply_rows_for_qdistr 21411 21411 0 0.00 0 23439 255 0 0
sp_pay_from_customer 7475 7475 0 0.00 4 22147 149 0 0
sp_pay_to_supplier 6158 6158 0 0.00 5 21442 136 0 0
sp_payment_common 13633 13633 0 0.00 1 21701 34 0 0
sp_qd_handle_on_cancel_clo 1813 1813 0 0.00 0 9817 256 0 0
sp_qd_handle_on_invoice_upd_sts 10365 10365 0 0.00 0 20715 351 0 0
sp_qd_handle_on_reserve_upd_sts 10165 10165 0 0.00 0 13993 46 0 0
sp_reserve_write_off 10165 10165 0 0.00 7 21808 182 0 0
sp_supplier_invoice 5519 5519 0 0.00 7 94603 3528 0 0
sp_supplier_order 5573 5573 0 0.00 5 29869 1352 0 0
srv_aggregate_perf_data 103 103 0 0.00 2837 293320 17518 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1555 150415 47310 0 0
srv_find_qd_qs_mism 1022448 1022448 0 0.00 0 12861 5 0 0
srv_make_invnt_saldo 485 485 0 0.00 59 105968 3442 0 0
srv_make_money_saldo 757 757 0 0.00 1 11656 553 0 0
srv_recalc_idx_stat 3 3 0 0.00 243766 591403 387342 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5069 5069 0 0.00 0 6373 139 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 2413 69 0 0
t$perf-norm:sp_cancel_client_order 1813 1813 0 0.00 0 10221 16 0 0
t$perf-norm:sp_cancel_customer_reserve 714 714 0 0.00 0 4310 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 2172 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 229 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 2019 13 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 16173 1294 0 0
t$perf-norm:sp_cancel_write_off 1125 1125 0 0.00 0 1471 6 0 0
t$perf-norm:sp_client_order 21411 21411 0 0.00 0 8804 16 0 0
t$perf-norm:sp_customer_reserve 969 969 0 0.00 0 1815 5 0 0
t$perf-norm:sp_pay_from_customer 7475 7475 0 0.00 0 10729 15 0 0
t$perf-norm:sp_pay_to_supplier 6158 6158 0 0.00 0 11671 10 0 0
t$perf-norm:sp_reserve_write_off 10165 10165 0 0.00 0 8000 11 0 0
t$perf-norm:sp_supplier_invoice 5519 5519 0 0.00 0 3830 15 0 0
t$perf-norm:sp_supplier_order 5573 5573 0 0.00 0 5933 16 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 146 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 93 2 0 0
t$perf-norm:srv_make_invnt_saldo 485 485 0 0.00 0 642 6 0 0
t$perf-norm:srv_make_money_saldo 757 757 0 0.00 0 1121 6 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10376 10118 258 2.48 0 17071 18 0 258
v_all_customers 39492 39492 0 0.00 0 25 1 0 0
v_all_suppliers 31116 31116 0 0.00 0 17 1 0 0
v_all_wares 42818 42818 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 2840 12 0 0
v_cancel_client_order 3626 3626 0 0.00 0 10090 7 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 18641 23 0 0
v_cancel_customer_reserve 1428 1428 0 0.00 0 5648 9 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 2019 10 0 0
v_cancel_supplier_invoice 860 834 26 3.02 0 3707 23 0 26
v_cancel_supplier_order 784 784 0 0.00 0 1520 9 0 0
v_cancel_write_off 2250 2250 0 0.00 0 14361 30 0 0
v_max_id_clo_ord 5571 5571 0 0.00 0 1842 2 0 0
v_max_id_clo_res 969 969 0 0.00 0 2796 14 0 0
v_max_id_ord_sup 5512 5512 0 0.00 0 3579 4 0 0
v_max_non_paid_invoice 1683 1683 0 0.00 0 3017 4 0 0
v_max_non_paid_realizn 3748 3748 0 0.00 0 1676 3 0 0
v_min_id_clo_ord 5571 5571 0 0.00 0 3441 10 0 0
v_min_id_clo_res 969 969 0 0.00 0 5502 26 0 0
v_min_id_ord_sup 5512 5512 0 0.00 0 5991 9 0 0
v_min_non_paid_invoice 6158 6158 0 0.00 0 9423 14 0 0
v_min_non_paid_realizn 7475 7475 0 0.00 0 12581 25 0 0
v_our_firm 10780 10780 0 0.00 0 13 1 0 0
v_reserve_write_off 20330 20330 0 0.00 0 14351 8 0 0
x_lock_depdocs_on_canc_invoice 10771 10771 0 0.00 0 23244 104 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 24865 468 0 0
x_make_qstorno_1000_1200 5573 5573 0 0.00 2 29804 1276 0 0
x_make_qstorno_1200_2000 5519 5519 0 0.00 4 81632 3331 0 0
x_make_qstorno_2100_3300 457180 457180 0 0.00 0 65655 143 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-03-2021 10:28:18 to 14-03-2021 10:28:22.

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
07:56:58 1 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
07:57:59 40075 8192000000 9729673664 10027130880 804369344 8.941 100 86 172 0 832160624 7707824 20083456
07:59:44 25761 8192000000 9770656544 10079371264 844639792 9.346 100 82 162 1 876635968 12975680 19020496
08:01:14 13805 8192000000 9817633488 10136707072 873417728 9.634 100 85 170 0 918306896 25278640 19610528
08:02:31 13305 8192000000 9872196304 10207174656 925068608 10.146 100 83 166 2 985157824 42004784 18084432
08:03:46 8491 8192000000 9919765568 10269954048 963833488 10.526 100 76 152 0 1036567664 56820544 15913632
08:04:57 5131 8192000000 9970477808 10328969216 1001481056 10.893 100 77 152 0 1083765152 66496144 15787952
08:06:06 4078 8192000000 9984407632 10350440448 1024012400 11.111 100 70 139 3 1099753472 62536272 13204800
08:07:11 2702 8192000000 10039950800 10406932480 1057033264 11.428 100 83 165 2 1159937872 86561856 16342752
08:08:16 1506 8192000000 10034522192 10405023744 1072340864 11.574 100 87 174 3 1143731056 53486768 17903424
08:09:20 6179 8192000000 10034873424 10414223360 1076648384 11.616 100 74 146 2 1143026896 51002400 15376112
08:10:27 5296 8192000000 9990057760 10378022912 1079906960 11.647 100 36 72 1 1103162992 15503552 7752480
08:11:36 5449 8192000000 9997982112 10396336128 1092551584 11.767 100 44 86 2 1125858208 24209808 9096816
08:12:42 9349 8192000000 10010485056 10422329344 1097408624 11.813 100 35 67 1 1135078160 30625632 7043904
08:13:53 25754 8192000000 10024787840 10414788608 1101159744 11.849 100 54 108 2 1129563328 16191328 12212256
08:15:20 2683 8192000000 10027742992 10420908032 1103788640 11.874 100 57 115 0 1133211552 16785056 12637856
08:16:26 5328 8192000000 10033547904 10432225280 1103726976 11.873 100 45 91 0 1147074336 33555296 9792064
08:17:32 6495 8192000000 9996800480 10390626304 1076170768 11.611 100 39 79 2 1101554624 16398304 8985552
08:18:42 29035 8192000000 9954407024 10354196480 980484976 10.689 91 16 32 1 1007182528 23241520 3456032
08:20:12 5349 8192000000 9950289152 10330972160 1033303824 11.200 99 33 66 1 1060275360 20164304 6807232
08:21:20 4145 8192000000 9947492192 10323857408 1025160768 11.122 100 33 66 0 1063813680 31307440 7345472
08:22:26 4823 8192000000 9942097584 10319884288 1015315536 11.027 99 33 66 1 1051192384 28407712 7469136
08:23:31 11091 8192000000 9967383104 10343288832 1034559632 11.212 100 69 141 1 1072955184 22914848 15480704
08:24:42 6534 8192000000 9961359312 10366464000 1037775648 11.243 99 45 91 1 1066434608 18636448 10022512
08:25:51 11386 8192000000 9977877776 10385092608 1058091664 11.438 100 31 63 2 1089734272 24377968 7264640
08:27:07 26935 8192000000 10001946336 10399383552 1081633520 11.663 100 41 82 1 1114475968 23221728 9620720
08:28:40 94513 8192000000 10071811328 10495655936 1102912960 11.865 100 34 68 1 1159945008 49903088 7128960
08:31:17 3153 8192000000 10090714112 10495840256 1120291344 12.030 100 59 117 2 1198304496 66110752 11902400
08:32:23 3415 8192000000 10132820464 10536026112 1130110960 12.122 100 68 137 0 1239265472 94454048 14700464
08:33:38 4456 8192000000 10159710544 10567610368 1141231232 12.227 100 68 135 2 1269369584 113614432 14523920
08:34:50 8636 8192000000 10154839648 10562953216 1143695136 12.250 99 55 109 0 1256190864 100655024 11840704
08:36:08 5059 8192000000 10198095056 10615336960 1170433248 12.501 100 51 101 0 1304634880 122830768 11370864
08:37:23 4722 8192000000 10224228400 10635198464 1168858656 12.486 100 63 126 0 1324433040 141247696 14326688
08:38:32 4315 8192000000 10303471904 10710679552 1178026560 12.572 100 70 140 0 1407728864 213813136 15889168
08:39:39 6897 8192000000 10296285616 10712080384 1167563680 12.474 100 55 110 0 1394614352 214307184 12743488
08:40:48 3725 8192000000 10335057168 10743984128 1162800528 12.429 99 75 150 0 1430397488 249681312 17915648
08:41:55 2694 8192000000 10381811168 10791874560 1178472864 12.576 100 76 150 1 1489597280 293293680 17830736
08:43:06 7136 8192000000 10414482256 10830381056 1162744400 12.429 99 69 137 0 1517821968 338162208 16915360
08:44:20 6335 8192000000 10453394656 10883149824 1160664672 12.409 100 72 144 1 1571490592 393863120 16962800
08:45:32 1553 8192000000 10493060160 10903879680 1158752240 12.392 100 91 181 1 1588313760 408017360 21544160
08:46:36 8554 8192000000 10407430368 10848591872 1162013264 12.422 100 48 96 0 1534552896 360449552 12090080
08:47:51 21872 8192000000 10383501840 10846478336 1148510608 12.296 100 39 78 2 1525945200 366767792 10666800
08:49:48 11861 8192000000 10331187792 10758762496 1118275520 12.011 98 32 64 1 1426822896 300681824 7865552
08:51:02 30959 8192000000 10230410256 10724450304 1078529264 11.633 97 17 32 2 1392992224 309617488 4845472
08:52:34 34586 8192000000 10141634816 10672517120 1109710608 11.930 99 16 33 1 1280388144 166763728 3913808
08:54:17 67797 8192000000 10039710000 10462334976 1090915040 11.751 98 34 66 3 1116624480 18509040 7200400
08:56:32 44075 8192000000 10046000208 10479550464 1121368288 12.040 100 19 36 1 1151092624 26599504 3124832
08:58:17 103438 8192000000 10098529008 10560237568 1115422432 11.984 99 15 29 1 1192042480 73860976 2759072
09:01:02 52224 8192000000 10105781440 10574831616 1132095056 12.141 100 26 52 1 1236727968 99163344 5469568
09:03:52 59410 8192000000 10059198576 10518048768 1116222976 11.991 98 19 39 0 1173622208 53560544 3838688
09:05:52 29992 8192000000 10071341040 10513031168 1139038144 12.206 100 42 83 1 1171386096 23099920 9248032
09:07:23 63131 8192000000 10084135584 10562211840 1135884576 12.177 99 12 23 0 1174372928 35712064 2776288
09:09:26 30795 8192000000 10079349408 10544345088 1137468976 12.192 99 45 87 2 1175081904 28313040 9299888
09:10:58 65526 8192000000 10066575488 10501447680 1109751360 11.930 97 29 56 1 1136912912 21358720 5802832
09:13:05 87592 8192000000 10136675616 10601848832 1129868640 12.120 98 22 44 2 1217775520 83644912 4261968
09:15:33 69271 8192000000 10095047936 10531930112 1112976624 11.961 97 18 35 1 1152873744 36192144 3704976
09:17:46 66593 8192000000 10112080752 10584215552 1136774864 12.185 99 21 42 0 1215471072 74096064 4600144
09:19:53 150415 8192000000 10076888800 10523488256 1108824544 11.921 97 29 58 0 1144535680 29715056 5996080
09:23:27 29324 8192000000 10073385040 10575970304 1143983632 12.253 100 5 9 2 1212886352 67818128 1084592
09:25:52 25687 8192000000 10056505488 10517716992 1131545984 12.136 100 7 14 0 1168196160 35064688 1585488
09:27:18 73886 8192000000 10122956624 10600390656 1137138640 12.189 100 21 42 1 1210271232 68905008 4227584
09:29:35 41653 8192000000 10079807424 10508947456 1095014240 11.790 96 18 36 0 1151852064 52952640 3885184
09:31:17 65378 8192000000 10047465936 10497323008 1066007328 11.514 95 11 22 0 1142660512 73928336 2724848
09:33:23 59552 8192000000 10053335520 10514911232 1092678720 11.768 97 18 36 1 1138547616 41911456 3957440
09:35:23 72277 8192000000 10052764656 10481758208 1106872448 11.903 98 16 32 2 1156032640 45784432 3375760
09:37:39 53460 8192000000 10081246832 10529710080 1101011776 11.847 97 25 50 1 1158385664 51953520 5420368
09:39:38 42173 8192000000 10055239248 10459664384 1093536512 11.776 98 40 79 1 1132986752 30684976 8765264
09:41:22 89648 8192000000 10071672368 10510467072 1098974800 11.828 99 25 50 2 1159041136 54192688 5873648
09:43:57 46582 8192000000 10075898384 10539868160 1084289760 11.688 98 11 22 1 1173316688 86451696 2575232
09:45:53 61448 8192000000 10058404128 10564030464 1070013792 11.552 97 13 26 0 1121132192 48081872 3036528
09:47:55 66746 8192000000 10102909312 10570620928 1113652192 11.967 99 22 44 2 1198653712 80083760 4917760
09:50:52 53973 8192000000 10085737328 10539622400 1091736976 11.759 97 13 26 0 1166611520 71766592 3107952
09:52:48 94292 8192000000 10067299024 10559893504 1109120672 11.924 99 14 28 1 1162072048 50375696 2575680
09:55:26 81447 8192000000 10071745120 10586595328 1134080656 12.160 100 16 32 3 1197937392 60633936 3222800
09:57:48 105356 8192000000 10070778816 10523582464 1136371504 12.181 100 15 30 0 1170562720 31288512 2902704
10:00:41 44173 8192000000 10070794496 10545324032 1128598000 12.108 99 5 10 0 1161365984 32072336 695648
10:02:25 39858 8192000000 10072034288 10506035200 1130945408 12.130 99 20 39 1 1152929776 17826688 4157680
10:04:06 41017 8192000000 10109987808 10564898816 1130324976 12.124 98 27 55 1 1192799136 56664032 5810128
10:05:48 65065 8192000000 10147304464 10608480256 1142858272 12.242 99 19 38 0 1220455824 73606256 3991296
10:07:54 59684 8192000000 10078723168 10540625920 1118316432 12.011 98 18 36 1 1160387104 38424064 3646608
10:09:54 35151 8192000000 10081281120 10542735360 1133197888 12.151 99 25 50 1 1188118624 50004896 4915840
10:11:30 35362 8192000000 10123270832 10601107456 1139354064 12.209 100 34 67 0 1212496992 66138032 7004896
10:13:08 60726 8192000000 10096443504 10587549696 1116174320 11.991 98 15 30 0 1189213488 69656848 3382320
10:15:09 102286 8192000000 10107787184 10563493888 1119789200 12.025 98 20 38 2 1186792304 63147168 3855936
10:17:53 68223 8192000000 10094257056 10596843520 1114661904 11.977 99 5 10 0 1174978976 59270880 1046192
10:20:04 49834 8192000000 10059735584 10507579392 1081705728 11.664 96 21 42 3 1132827232 46367440 4754064
10:22:03 54599 8192000000 10076213680 10529300480 1103439920 11.870 98 16 32 0 1144496496 37816928 3239648
10:23:58 73534 8192000000 10153652864 10595008512 1105258864 11.888 97 30 60 0 1204636352 92407232 6970256
10:26:16 53708 8192000000 9263472176 9687441408 369194800 4.312 33 7 14 1 457603072 86815648 1592624

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 14-03-2021 10:28:24 to 14-03-2021 10:28:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

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

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          239960
MON$OLDEST_ACTIVE               240869
MON$OLDEST_SNAPSHOT             240869
MON$NEXT_TRANSACTION            240869
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                       2094400
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             810
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2387 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.2387 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 ---
:75: 
:76: mscs-test-161.budzdorov.ru    Sun Mar 14 10:27:31 2021
:77:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:78: 
--- end of diff output ---

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

14.03.21 10:28:42. 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-03-14 10:28:45 No severe PSQL-related problems occured
14.03.21 10:28:46. End of report.