Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.10.2020 13:27
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16906356 kB
MemAvailable 31511184 kB
Buffers 0 kB
Cached 14779032 kB
SwapCached 3064 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 2020-10-31 13:26:49
DTS_END                         2020-10-31 13:26:49.1450
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/59940
TRN_ID                          233357
ATT_ID                          591
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: 8937.46 21325 1072495
customer order: creation 167.37 425 20084
customer order: refuse 14.20 380 1704
order to supplier: creation 45.57 1426 5468
order to supplier: removal 3.48 132894 417
invoice (draft): creation 44.39 3538 5327
invoice (draft): removal 49.95 1158 5994
invoice accept: apply 40.69 18343 4883
invoice accept: cancel 44.96 928 5395
customer reserve: creation 3807.03 178 456843
customer reserve: removal 4437.64 81 532517
realization accept: apply 81.75 196 9810
realization accept: cancel 72.96 142 8755
payment from customer: creation 58.15 116 6978
payment from customer: removal 5.78 99 693
payment to supplier: creation 48.91 140 5869
payment to supplier: removal 3.73 111 447
service: total inventory turnovers 4.25 3027 510
service: total monetary turnovers 5.87 599 704
service: aggregate perf. data 0.78 16205 93
service: refresh index statistics 0.03 246511 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 31-10-2020 13:28:09 to 31-10-2020 13:28:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2187
TEST_TIME 2 2861
TEST_TIME 3 2547
TEST_TIME 4 2974
TEST_TIME 5 3617
TEST_TIME 6 4065
TEST_TIME 7 4326
TEST_TIME 8 4687
TEST_TIME 9 4855
TEST_TIME 10 4959
TEST_TIME 11 4886
TEST_TIME 12 4922
TEST_TIME 13 5007
TEST_TIME 14 5130
TEST_TIME 15 5248
TEST_TIME 16 5344
TEST_TIME 17 5544
TEST_TIME 18 6010
TEST_TIME 19 6719
TEST_TIME 20 6664
TEST_TIME 21 7083
TEST_TIME 22 7287
TEST_TIME 23 7674
TEST_TIME 24 7747
TEST_TIME 25 8029
TEST_TIME 26 8179
TEST_TIME 27 8528
TEST_TIME 28 9763
TEST_TIME 29 10964
TEST_TIME 30 11044
TEST_TIME 31 10999
TEST_TIME 32 10849
TEST_TIME 33 10807
TEST_TIME 34 10726
TEST_TIME 35 10640
TEST_TIME 36 10382
TEST_TIME 37 10250
TEST_TIME 38 10114
TEST_TIME 39 10104
TEST_TIME 40 9979
TEST_TIME 41 9969
TEST_TIME 42 9995
TEST_TIME 43 10003
TEST_TIME 44 9902
TEST_TIME 45 9782
TEST_TIME 46 9718
TEST_TIME 47 9748
TEST_TIME 48 9686
TEST_TIME 49 9642
TEST_TIME 50 9580
TEST_TIME 51 9578
TEST_TIME 52 9668
TEST_TIME 53 9620
TEST_TIME 54 9575
TEST_TIME 55 9602
TEST_TIME 56 9723
TEST_TIME 57 9704
TEST_TIME 58 9709
TEST_TIME 59 9703
TEST_TIME 60 9769
TEST_TIME 61 9829
TEST_TIME 62 9747
TEST_TIME 63 9623
TEST_TIME 64 9559
TEST_TIME 65 9484
TEST_TIME 66 9395
TEST_TIME 67 9432
TEST_TIME 68 9382
TEST_TIME 69 9411
TEST_TIME 70 9368
TEST_TIME 71 9314
TEST_TIME 72 9349
TEST_TIME 73 9499
TEST_TIME 74 9462
TEST_TIME 75 9490
TEST_TIME 76 9454
TEST_TIME 77 9487
TEST_TIME 78 9481
TEST_TIME 79 9373
TEST_TIME 80 9318
TEST_TIME 81 9287
TEST_TIME 82 9206
TEST_TIME 83 9152
TEST_TIME 84 9164
TEST_TIME 85 9116
TEST_TIME 86 9103
TEST_TIME 87 9060
TEST_TIME 88 9081
TEST_TIME 89 9067
TEST_TIME 90 9126
TEST_TIME 91 9168
TEST_TIME 92 9193
TEST_TIME 93 9271
TEST_TIME 94 9263
TEST_TIME 95 9276
TEST_TIME 96 9326
TEST_TIME 97 9308
TEST_TIME 98 9229
TEST_TIME 99 9171
TEST_TIME 100 9116
TEST_TIME 101 9062
TEST_TIME 102 9106
TEST_TIME 103 9089
TEST_TIME 104 9094
TEST_TIME 105 9067
TEST_TIME 106 9077
TEST_TIME 107 9064
TEST_TIME 108 9041
TEST_TIME 109 9090
TEST_TIME 110 9130
TEST_TIME 111 9176
TEST_TIME 112 9143
TEST_TIME 113 9185
TEST_TIME 114 9140
TEST_TIME 115 9087
TEST_TIME 116 9048
TEST_TIME 117 9020
TEST_TIME 118 8991
TEST_TIME 119 8955
TEST_TIME 120 8940

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-10-2020 13:28:13 to 31-10-2020 13:28:16.

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 1494176 1494176 0 0.00 0 23103 7 0 0
doc_list_biud 1494173 1494173 0 0.00 0 26783 37 0 0
sp_add_invoice_to_stock 5013 4883 130 2.59 35 189625 18343 0 130
sp_cancel_adding_invoice 5395 5395 0 0.00 3 61100 929 0 0
sp_cancel_client_order 1704 1704 0 0.00 4 8684 380 0 0
sp_cancel_customer_reserve 532517 532517 0 0.00 1 24944 81 0 0
sp_cancel_pay_from_customer 693 693 0 0.00 3 9280 100 0 0
sp_cancel_pay_to_supplier 447 447 0 0.00 3 5859 111 0 0
sp_cancel_supplier_invoice 6002 5994 8 0.13 2 18119 1158 0 8
sp_cancel_supplier_order 417 417 0 0.00 9 1295814 132895 0 0
sp_cancel_write_off 8755 8755 0 0.00 1 24664 142 0 0
sp_client_order 20084 20084 0 0.00 4 28134 426 0 0
sp_customer_reserve 456843 456843 0 0.00 0 64374 178 0 0
sp_fill_shopping_cart 30866 30866 0 0.00 1 20596 43 0 0
sp_fill_shopping_cart_clo_res 456833 456833 0 0.00 0 24906 18 0 0
sp_get_clo_for_invoice 4872 4872 0 0.00 0 27465 387 0 0
sp_kill_cost_storno 15290 15290 0 0.00 0 8922 67 0 0
sp_kill_qstorno_ret_qs2qd 1080200 1080200 0 0.00 0 24935 44 0 0
sp_kill_qty_storno 569464 569464 0 0.00 0 26782 95 0 0
sp_lock_selected_doc 20367 20367 0 0.00 0 7848 5 0 0
sp_make_cost_storno 27529 27529 0 0.00 0 22614 52 0 0
sp_multiply_rows_for_pdistr 27529 27529 0 0.00 0 22345 34 0 0
sp_multiply_rows_for_qdistr 20084 20084 0 0.00 0 23289 246 0 0
sp_pay_from_customer 6978 6978 0 0.00 4 23097 116 0 0
sp_pay_to_supplier 5869 5869 0 0.00 4 23115 140 0 0
sp_payment_common 12845 12845 0 0.00 1 22349 28 0 0
sp_qd_handle_on_cancel_clo 1704 1704 0 0.00 0 8678 359 0 0
sp_qd_handle_on_invoice_upd_sts 10267 10267 0 0.00 0 26782 424 0 0
sp_qd_handle_on_reserve_upd_sts 9810 9810 0 0.00 0 14885 62 0 0
sp_reserve_write_off 9810 9810 0 0.00 6 23146 196 0 0
sp_supplier_invoice 5327 5327 0 0.00 6 81689 3539 0 0
sp_supplier_order 5468 5468 0 0.00 6 35134 1427 0 0
srv_aggregate_perf_data 93 93 0 0.00 2496 171007 16206 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1441 104546 41681 0 0
srv_find_qd_qs_mism 1024834 1024834 0 0.00 0 16036 6 0 0
srv_make_invnt_saldo 510 510 0 0.00 42 129087 3027 0 0
srv_make_money_saldo 704 704 0 0.00 0 18707 600 0 0
srv_recalc_idx_stat 4 4 0 0.00 100904 398675 246512 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 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 4883 4883 0 0.00 0 20416 197 0 0
t$perf-norm:sp_cancel_adding_invoice 354 354 0 0.00 0 14270 106 0 0
t$perf-norm:sp_cancel_client_order 1704 1704 0 0.00 0 2153 11 0 0
t$perf-norm:sp_cancel_customer_reserve 704 704 0 0.00 0 2053 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 693 693 0 0.00 0 588 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 447 447 0 0.00 0 3294 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 376 376 0 0.00 0 856 13 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 27964 1646 0 0
t$perf-norm:sp_cancel_write_off 993 993 0 0.00 0 9677 12 0 0
t$perf-norm:sp_client_order 20084 20084 0 0.00 0 14221 20 0 0
t$perf-norm:sp_customer_reserve 996 996 0 0.00 0 888 5 0 0
t$perf-norm:sp_pay_from_customer 6978 6978 0 0.00 0 4495 11 0 0
t$perf-norm:sp_pay_to_supplier 5869 5869 0 0.00 0 15673 16 0 0
t$perf-norm:sp_reserve_write_off 9810 9810 0 0.00 0 5888 11 0 0
t$perf-norm:sp_supplier_invoice 5327 5327 0 0.00 0 4318 19 0 0
t$perf-norm:sp_supplier_order 5468 5468 0 0.00 0 8470 15 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 347 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 813 13 0 0
t$perf-norm:srv_make_invnt_saldo 510 510 0 0.00 0 917 10 0 0
t$perf-norm:srv_make_money_saldo 704 704 0 0.00 0 1957 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 9998 9738 260 2.60 0 3088 17 0 260
v_all_customers 36502 36502 0 0.00 0 77 1 0 0
v_all_suppliers 30086 30086 0 0.00 0 23 1 0 0
v_all_wares 40164 40164 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 708 708 0 0.00 0 6303 29 0 0
v_cancel_client_order 3408 3408 0 0.00 0 3510 4 0 0
v_cancel_customer_prepayment 1386 1386 0 0.00 0 2958 10 0 0
v_cancel_customer_reserve 1408 1408 0 0.00 0 1119 4 0 0
v_cancel_payment_to_supplier 894 894 0 0.00 0 2901 14 0 0
v_cancel_supplier_invoice 768 752 16 2.08 0 556 14 0 16
v_cancel_supplier_order 834 834 0 0.00 0 2590 7 0 0
v_cancel_write_off 1986 1986 0 0.00 0 2943 17 0 0
v_max_id_clo_ord 5467 5467 0 0.00 0 3641 2 0 0
v_max_id_clo_res 996 996 0 0.00 0 3519 16 0 0
v_max_id_ord_sup 5316 5316 0 0.00 0 6344 10 0 0
v_max_non_paid_invoice 1608 1608 0 0.00 0 713 3 0 0
v_max_non_paid_realizn 3808 3808 0 0.00 0 2018 5 0 0
v_min_id_clo_ord 5467 5467 0 0.00 0 3998 7 0 0
v_min_id_clo_res 996 996 0 0.00 0 5977 14 0 0
v_min_id_ord_sup 5316 5316 0 0.00 0 5421 5 0 0
v_min_non_paid_invoice 5868 5868 0 0.00 0 22334 22 0 0
v_min_non_paid_realizn 6977 6977 0 0.00 0 22082 19 0 0
v_our_firm 10000 10000 0 0.00 0 40 1 0 0
v_reserve_write_off 19620 19620 0 0.00 0 5862 7 0 0
x_lock_depdocs_on_canc_invoice 11013 11013 0 0.00 0 23747 120 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 2790 309 0 0
x_make_qstorno_1000_1200 5468 5468 0 0.00 2 35091 1344 0 0
x_make_qstorno_1200_2000 5327 5327 0 0.00 3 81655 3375 0 0
x_make_qstorno_2100_3300 456843 456843 0 0.00 0 64373 158 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 31-10-2020 13:28:17 to 31-10-2020 13: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
10:56:53 5 8192000000 8800421552 8812417024 6209792 0.075 2 2 3 0 6361840 90592 61456
10:57:53 35142 8192000000 9719577184 10016534528 790829152 8.803 100 83 166 1 818703792 7721792 20152848
10:59:35 18143 8192000000 9762428832 10070028288 837062656 9.270 100 87 174 2 871051568 13545696 20443216
11:00:58 24362 8192000000 9811001968 10129969152 866209136 9.562 100 82 164 0 911962864 26412416 19341312
11:02:27 13120 8192000000 9877446160 10208202752 913166272 10.029 100 85 170 0 986580288 54826576 18587440
11:03:47 10217 8192000000 9921196608 10268196864 944288496 10.335 100 80 160 1 1041052656 79622528 17141632
11:05:01 4887 8192000000 9960675360 10314305536 997584432 10.855 100 85 166 2 1076245872 61544416 17117024
11:06:07 1607 8192000000 9998226800 10356559872 1021115968 11.083 100 91 183 3 1110851520 71362736 18372816
11:07:11 3773 8192000000 10022385920 10390736896 1039053168 11.256 100 86 171 2 1133381632 77274624 17053840
11:08:16 22674 8192000000 10025137248 10408931328 1063034688 11.486 100 65 131 1 1142587136 66361312 13191136
11:09:40 5799 8192000000 10002928192 10388381696 1071858592 11.570 100 46 92 1 1108386864 27134208 9394064
11:10:48 6201 8192000000 9991069952 10392608768 1084360048 11.689 100 44 87 2 1120029280 26397808 9271424
11:11:58 8000 8192000000 9991298848 10383831040 1088308432 11.727 100 40 80 1 1113434832 16552768 8573632
11:13:07 71896 8192000000 10007720592 10424197120 1094078000 11.781 100 39 78 1 1125132336 22631104 8423232
11:15:19 10115 8192000000 10010449376 10402402304 1088152784 11.725 99 49 97 1 1123231600 24179088 10899728
11:16:31 5574 8192000000 10019271712 10415861760 1098781936 11.826 100 42 84 3 1126713296 18211472 9719888
11:17:41 8847 8192000000 10000509920 10408341504 1071231376 11.564 99 25 49 2 1099767136 22971296 5564464
11:18:53 22883 8192000000 9975214528 10369556480 1046798464 11.330 98 39 75 2 1070072224 15901776 7371984
11:20:16 5768 8192000000 9933847712 10312065024 962660384 10.515 94 37 74 0 996143536 25231056 8252096
11:21:22 8917 8192000000 9915499280 10304430080 989505632 10.777 97 38 76 3 1013586240 15892672 8187936
11:22:31 9790 8192000000 9936035360 10323292160 994833472 10.828 96 34 64 3 1013868864 11549744 7485648
11:23:42 21059 8192000000 9958909984 10356363264 1032177696 11.189 99 16 32 3 1059926528 24349920 3398912
11:25:03 7803 8192000000 9974771152 10370170880 1057163328 11.429 100 38 75 1 1090911840 25497008 8251504
11:26:13 4407 8192000000 9992496224 10380046336 1079330368 11.641 100 42 83 3 1103453408 15095504 9027536
11:27:25 8924 8192000000 10023567104 10412630016 1097376944 11.813 100 60 120 0 1127244848 16262848 13605056
11:28:42 84984 8192000000 10062512896 10475823104 1105870560 11.893 100 37 71 0 1152866448 39499824 7496064
11:31:13 13647 8192000000 10107769024 10512068608 1123399824 12.059 100 39 78 3 1212157136 80248704 8508608
11:32:29 20253 8192000000 10148909584 10550792192 1144296304 12.256 100 62 121 3 1264366032 106919616 13150112
11:33:52 2960 8192000000 10195421504 10599579648 1163425760 12.435 100 68 134 3 1308466016 130410384 14629872
11:35:01 2103 8192000000 10251027232 10649497600 1164583104 12.446 100 70 137 0 1352531392 173228384 14719904
11:36:07 14811 8192000000 10264505456 10682163200 1171800480 12.514 100 44 88 0 1364751744 182390256 10561008
11:37:35 7985 8192000000 10305359248 10718547968 1186971280 12.655 100 47 94 2 1408423840 210133856 11318704
11:38:56 7701 8192000000 10358059440 10769592320 1188901328 12.673 100 56 112 3 1461366144 259039120 13425696
11:40:14 4535 8192000000 10419597472 10830848000 1195710176 12.736 100 76 152 0 1522341488 308628096 18003216
11:41:24 2366 8192000000 10451912160 10853158912 1179968448 12.590 100 72 141 1 1558358816 361320528 17069840
11:42:32 4475 8192000000 10493086016 10900987904 1191102800 12.694 100 69 138 1 1597455984 389767792 16585392
11:43:43 1886 8192000000 10552706992 10960621568 1187613184 12.661 100 83 166 1 1650143456 442409360 20120912
11:44:51 3758 8192000000 10543203616 10951225344 1188651520 12.671 100 80 160 0 1637024416 428800736 19572160
11:45:59 1440 8192000000 10522346624 10936766464 1192113312 12.703 100 69 138 1 1614969232 405684272 17171648
11:47:05 5299 8192000000 10600198992 11017428992 1184723632 12.634 100 67 133 1 1695433952 494377824 16332496
11:48:16 5748 8192000000 10532954144 10995257344 1169540288 12.493 100 49 99 0 1639289280 457030544 12718448
11:49:31 11137 8192000000 10577076096 11036450816 1156591968 12.371 100 48 96 2 1701091120 531853888 12645264
11:50:57 21933 8192000000 10525502272 10978680832 1113845184 11.969 98 42 84 1 1631688592 506920672 10922736
11:52:22 19038 8192000000 10564945088 11011592192 1123583216 12.061 100 36 72 0 1648449472 514749104 10117152
11:53:47 22847 8192000000 10471032160 10958860288 1124492496 12.069 99 31 62 0 1617267712 483951792 8823424
11:55:17 17198 8192000000 10132632432 10695610368 1092724272 11.769 97 25 49 0 1251804928 153280624 5800032
11:56:40 78982 8192000000 10071265072 10528788480 1085698720 11.702 96 18 33 2 1122953072 33011872 4242480
11:59:00 5461 8192000000 10069460432 10485723136 1123899600 12.064 100 50 95 4 1159596160 25816080 9880480
12:00:09 21822 8192000000 10064917856 10490294272 1112210912 11.953 99 47 91 1 1144041408 22391760 9438736
12:01:32 90757 8192000000 10039864448 10479661056 1082684128 11.673 97 16 32 0 1131231488 44442240 4105120
12:04:18 26751 8192000000 10055762320 10496000000 1110151120 11.934 99 21 41 3 1149367088 34859824 4356144
12:05:47 21001 8192000000 10059511152 10502737920 1093025200 11.771 97 28 56 0 1140728928 42169088 5534640
12:07:26 35986 8192000000 10117073376 10551545856 1119625216 12.023 99 47 91 1 1181352512 51559008 10168288
12:09:15 38206 8192000000 10082335856 10558980096 1113852064 11.969 99 15 31 3 1187426416 70335584 3238768
12:10:54 100998 8192000000 10123426336 10559705088 1102160208 11.858 97 23 45 1 1167112400 60063296 4888896
12:13:53 68033 8192000000 10055380688 10550812672 1133051264 12.150 100 8 16 1 1184239424 49573376 1614784
12:16:07 54716 8192000000 10095868288 10555686912 1128280128 12.105 99 13 26 0 1178978752 47952448 2746176
12:18:04 43852 8192000000 10064238672 10566299648 1120152960 12.028 98 20 40 0 1183381360 58661392 4567008
12:19:49 29276 8192000000 10077311648 10514464768 1089370480 11.737 97 39 78 0 1153398304 56088080 7939744
12:21:31 86936 8192000000 10045384176 10504810496 1117416432 12.003 99 15 30 2 1204984144 84079472 3488240
12:23:59 46235 8192000000 10074021600 10520993792 1086158928 11.706 96 37 74 2 1143014128 49256224 7598976
12:25:45 69319 8192000000 10073222720 10542989312 1106511984 11.899 98 36 72 0 1161468832 48119104 6837744
12:27:58 104546 8192000000 10056183216 10497691648 1123259248 12.058 99 27 54 2 1163273664 34649600 5364816
12:30:59 46961 8192000000 10066105600 10532237312 1128729472 12.109 100 6 12 0 1163806864 34445040 632352
12:32:46 56539 8192000000 10097422512 10560741376 1113151440 11.962 98 23 46 1 1189870192 71847392 4871360
12:35:11 45333 8192000000 10073818320 10536747008 1103507168 11.871 98 17 34 0 1151161408 43877408 3776832
12:37:01 37123 8192000000 10111766736 10542215168 1110485488 11.937 99 48 95 3 1190060000 68854624 10719888
12:38:41 80043 8192000000 10052211408 10536767488 1091932912 11.761 98 19 38 0 1210353264 113907264 4513088
12:41:03 41552 8192000000 10065561760 10506518528 1091505072 11.757 99 39 78 1 1142036976 42516848 8015056
12:42:45 37210 8192000000 10066317104 10527051776 1098923952 11.827 99 22 42 1 1147030896 43657088 4449856
12:44:24 95289 8192000000 10031962944 10490490880 1099057424 11.829 99 12 24 0 1150329872 48758848 2513600
12:47:12 68902 8192000000 10040173328 10482073600 1117008112 11.999 100 20 40 0 1167014880 46092272 3914496
12:49:34 66838 8192000000 10057376304 10541019136 1125874304 12.082 100 11 22 1 1190093360 61480992 2738064
12:51:41 44023 8192000000 10086203936 10519478272 1106033072 11.895 97 21 42 1 1152878000 42306896 4538032
12:53:29 31411 8192000000 10132661472 10566877184 1132495808 12.145 99 36 70 3 1234509920 94532896 7481216
12:55:10 44994 8192000000 10144395296 10573631488 1101270912 11.850 97 28 56 1 1215478320 107684464 6522944
12:56:59 60509 8192000000 10144787808 10601406464 1125533408 12.079 98 23 46 2 1250217376 119224976 5458992
12:59:06 67082 8192000000 10091336336 10524016640 1116994064 11.999 99 13 26 0 1169413152 50199248 2219840
13:01:19 30616 8192000000 10114109328 10567233536 1128562800 12.108 100 36 71 3 1200406512 64090192 7753520
13:02:52 82760 8192000000 10084110320 10576252928 1136556928 12.183 100 4 8 0 1190628784 53207472 864384
13:05:56 72505 8192000000 10104476272 10550710272 1141344928 12.228 100 8 16 0 1197266720 54370416 1551376
13:08:10 78826 8192000000 10103835984 10611605504 1117116208 12.000 98 12 24 1 1200488144 80583600 2788336
13:10:31 61825 8192000000 10077627584 10510475264 1084208656 11.688 95 29 58 0 1118757168 28139520 6408992
13:12:34 48388 8192000000 10095351648 10544111616 1126758032 12.091 99 31 62 1 1220537888 86874800 6905056
13:14:32 83439 8192000000 10167425312 10664927232 1143118848 12.245 99 22 44 0 1272191664 124098912 4973904
13:16:59 45829 8192000000 10092050816 10540306432 1127615360 12.099 99 26 51 1 1174380144 41529216 5235568
13:19:07 19541 8192000000 10117371024 10547081216 1084008720 11.686 95 49 98 1 1153700064 59782768 9908576
13:20:28 94057 8192000000 10068422128 10532794368 1130324336 12.124 100 24 48 0 1193124352 57641440 5158576
13:23:02 58051 8192000000 10046352720 10505658368 1087228928 11.716 96 8 16 2 1105757504 17043712 1484864
13:25:03 43383 8192000000 10071314720 10520690688 1119861840 12.026 99 9 18 0 1158501776 36857648 1782288

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 31-10-2020 13:28:23 to 31-10-2020 13:28:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 130 v_add_invoice_to_stock 335544517
user_exc 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-10-2020 13:28:39 to 31-10-2020 13: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          232227
MON$OLDEST_ACTIVE               233930
MON$OLDEST_SNAPSHOT             233930
MON$NEXT_TRANSACTION            233930
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                       2096896
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             778
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2242 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2242 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2242 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.2242 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 ---
:76: 
:77: mscs-test-161.budzdorov.ru    Sat Oct 31 13:26:53 2020
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:79: 
:80: 
:81: mscs-test-161.budzdorov.ru    Sat Oct 31 13:26:56 2020
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:83: 
:84: 
:85: mscs-test-161.budzdorov.ru    Sat Oct 31 13:26:57 2020
:86:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:87: 
--- end of diff output ---

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

31.10.20 13: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
2020-10-31 13:28:45 No severe PSQL-related problems occured
31.10.20 13:28:46. End of report.