Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.10.2020 10: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 16850808 kB
MemAvailable 31558308 kB
Buffers 0 kB
Cached 14763640 kB
SwapCached 5544 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-12 10:26:48
DTS_END                         2020-10-12 10:26:48.8580
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/42590
TRN_ID                          232172
ATT_ID                          614
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: 9110.17 23168 1093221
customer order: creation 167.92 410 20150
customer order: refuse 13.40 323 1608
order to supplier: creation 44.07 1362 5288
order to supplier: removal 3.33 120841 400
invoice (draft): creation 44.03 3699 5284
invoice (draft): removal 54.32 903 6518
invoice accept: apply 39.23 18097 4707
invoice accept: cancel 48.88 892 5866
customer reserve: creation 3631.14 177 435737
customer reserve: removal 4776.05 66 573126
realization accept: apply 79.19 181 9503
realization accept: cancel 84.33 120 10120
payment from customer: creation 57.12 119 6854
payment from customer: removal 5.99 106 719
payment to supplier: creation 47.70 143 5724
payment to supplier: removal 3.35 101 402
service: total inventory turnovers 3.68 3224 442
service: total monetary turnovers 5.55 689 666
service: aggregate perf. data 0.85 15540 102
service: refresh index statistics 0.04 296367 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-10-2020 10:27:59 to 12-10-2020 10:28:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1133
TEST_TIME 2 3792
TEST_TIME 3 4852
TEST_TIME 4 5836
TEST_TIME 5 5836
TEST_TIME 6 5659
TEST_TIME 7 6015
TEST_TIME 8 5902
TEST_TIME 9 6097
TEST_TIME 10 6259
TEST_TIME 11 6223
TEST_TIME 12 6183
TEST_TIME 13 6374
TEST_TIME 14 6591
TEST_TIME 15 7073
TEST_TIME 16 7629
TEST_TIME 17 7797
TEST_TIME 18 8655
TEST_TIME 19 8701
TEST_TIME 20 8852
TEST_TIME 21 8765
TEST_TIME 22 8703
TEST_TIME 23 8660
TEST_TIME 24 9315
TEST_TIME 25 9326
TEST_TIME 26 10914
TEST_TIME 27 11297
TEST_TIME 28 11420
TEST_TIME 29 11309
TEST_TIME 30 11089
TEST_TIME 31 11085
TEST_TIME 32 10793
TEST_TIME 33 10552
TEST_TIME 34 10433
TEST_TIME 35 10545
TEST_TIME 36 10366
TEST_TIME 37 10207
TEST_TIME 38 10181
TEST_TIME 39 9994
TEST_TIME 40 10294
TEST_TIME 41 10242
TEST_TIME 42 10184
TEST_TIME 43 10217
TEST_TIME 44 10288
TEST_TIME 45 10309
TEST_TIME 46 10313
TEST_TIME 47 10430
TEST_TIME 48 10329
TEST_TIME 49 10445
TEST_TIME 50 10307
TEST_TIME 51 10306
TEST_TIME 52 10513
TEST_TIME 53 10430
TEST_TIME 54 10378
TEST_TIME 55 10305
TEST_TIME 56 10155
TEST_TIME 57 10018
TEST_TIME 58 10023
TEST_TIME 59 9961
TEST_TIME 60 9870
TEST_TIME 61 9803
TEST_TIME 62 9720
TEST_TIME 63 9689
TEST_TIME 64 9620
TEST_TIME 65 9662
TEST_TIME 66 9763
TEST_TIME 67 9731
TEST_TIME 68 9740
TEST_TIME 69 9803
TEST_TIME 70 9839
TEST_TIME 71 9824
TEST_TIME 72 9804
TEST_TIME 73 9888
TEST_TIME 74 9838
TEST_TIME 75 9730
TEST_TIME 76 9622
TEST_TIME 77 9678
TEST_TIME 78 9694
TEST_TIME 79 9604
TEST_TIME 80 9567
TEST_TIME 81 9513
TEST_TIME 82 9556
TEST_TIME 83 9551
TEST_TIME 84 9541
TEST_TIME 85 9538
TEST_TIME 86 9544
TEST_TIME 87 9592
TEST_TIME 88 9611
TEST_TIME 89 9650
TEST_TIME 90 9696
TEST_TIME 91 9655
TEST_TIME 92 9606
TEST_TIME 93 9520
TEST_TIME 94 9484
TEST_TIME 95 9498
TEST_TIME 96 9450
TEST_TIME 97 9424
TEST_TIME 98 9417
TEST_TIME 99 9374
TEST_TIME 100 9444
TEST_TIME 101 9418
TEST_TIME 102 9382
TEST_TIME 103 9350
TEST_TIME 104 9399
TEST_TIME 105 9386
TEST_TIME 106 9345
TEST_TIME 107 9431
TEST_TIME 108 9388
TEST_TIME 109 9406
TEST_TIME 110 9420
TEST_TIME 111 9376
TEST_TIME 112 9304
TEST_TIME 113 9271
TEST_TIME 114 9213
TEST_TIME 115 9159
TEST_TIME 116 9156
TEST_TIME 117 9116
TEST_TIME 118 9117
TEST_TIME 119 9132
TEST_TIME 120 9112

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-10-2020 10:28:03 to 12-10-2020 10:28:06.

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 1497758 1497758 0 0.00 0 20243 7 0 0
doc_list_biud 1497757 1497757 0 0.00 0 75021 32 0 0
sp_add_invoice_to_stock 4807 4707 100 2.08 54 265225 18098 0 100
sp_cancel_adding_invoice 5866 5866 0 0.00 5 57839 892 0 0
sp_cancel_client_order 1608 1608 0 0.00 5 10621 324 0 0
sp_cancel_customer_reserve 573126 573126 0 0.00 1 75022 67 0 0
sp_cancel_pay_from_customer 719 719 0 0.00 2 9478 106 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 5503 101 0 0
sp_cancel_supplier_invoice 6522 6518 4 0.06 3 19830 903 0 4
sp_cancel_supplier_order 400 400 0 0.00 7 1411879 120842 0 0
sp_cancel_write_off 10120 10120 0 0.00 1 11099 121 0 0
sp_client_order 20150 20150 0 0.00 4 16809 410 0 0
sp_customer_reserve 435737 435737 0 0.00 0 136459 177 0 0
sp_fill_shopping_cart 30706 30706 0 0.00 1 15718 41 0 0
sp_fill_shopping_cart_clo_res 435733 435733 0 0.00 0 24801 18 0 0
sp_get_clo_for_invoice 4703 4703 0 0.00 0 61850 406 0 0
sp_kill_cost_storno 17107 17107 0 0.00 0 10962 62 0 0
sp_kill_qstorno_ret_qs2qd 1163290 1163290 0 0.00 0 75010 36 0 0
sp_kill_qty_storno 611844 611844 0 0.00 0 75021 78 0 0
sp_lock_selected_doc 19737 19737 0 0.00 0 8621 5 0 0
sp_make_cost_storno 26784 26784 0 0.00 0 14724 54 0 0
sp_multiply_rows_for_pdistr 26784 26784 0 0.00 0 10966 36 0 0
sp_multiply_rows_for_qdistr 20150 20150 0 0.00 0 13602 239 0 0
sp_pay_from_customer 6854 6854 0 0.00 4 21466 119 0 0
sp_pay_to_supplier 5724 5724 0 0.00 5 18633 143 0 0
sp_payment_common 12578 12578 0 0.00 1 18584 27 0 0
sp_qd_handle_on_cancel_clo 1608 1608 0 0.00 0 10604 303 0 0
sp_qd_handle_on_invoice_upd_sts 10569 10569 0 0.00 0 20122 403 0 0
sp_qd_handle_on_reserve_upd_sts 9503 9503 0 0.00 0 6859 51 0 0
sp_reserve_write_off 9503 9503 0 0.00 6 19485 181 0 0
sp_supplier_invoice 5284 5284 0 0.00 7 100387 3700 0 0
sp_supplier_order 5288 5288 0 0.00 6 26478 1362 0 0
srv_aggregate_perf_data 102 102 0 0.00 2869 217697 15541 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1540 185479 48332 0 0
srv_find_qd_qs_mism 1047961 1047961 0 0.00 0 20242 5 0 0
srv_make_invnt_saldo 442 442 0 0.00 66 109162 3225 0 0
srv_make_money_saldo 666 666 0 0.00 1 15600 690 0 0
srv_recalc_idx_stat 5 5 0 0.00 138429 431689 296368 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4707 4707 0 0.00 0 16713 178 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 1 1608 78 0 0
t$perf-norm:sp_cancel_client_order 1608 1608 0 0.00 0 1397 10 0 0
t$perf-norm:sp_cancel_customer_reserve 659 659 0 0.00 0 2401 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 719 719 0 0.00 0 2324 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 643 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 1265 15 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 26597 1633 0 0
t$perf-norm:sp_cancel_write_off 953 953 0 0.00 0 2765 15 0 0
t$perf-norm:sp_client_order 20150 20150 0 0.00 0 16798 20 0 0
t$perf-norm:sp_customer_reserve 988 988 0 0.00 0 4278 10 0 0
t$perf-norm:sp_pay_from_customer 6854 6854 0 0.00 0 8340 9 0 0
t$perf-norm:sp_pay_to_supplier 5724 5724 0 0.00 0 27810 18 0 0
t$perf-norm:sp_reserve_write_off 9503 9503 0 0.00 0 9496 14 0 0
t$perf-norm:sp_supplier_invoice 5284 5284 0 0.00 0 8068 22 0 0
t$perf-norm:sp_supplier_order 5288 5288 0 0.00 0 7343 15 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 540 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1005 17 0 0
t$perf-norm:srv_make_invnt_saldo 442 442 0 0.00 0 634 8 0 0
t$perf-norm:srv_make_money_saldo 666 666 0 0.00 0 1226 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 8 3 0 0
v_add_invoice_to_stock 9606 9406 200 2.08 0 8358 21 0 200
v_all_customers 36476 36476 0 0.00 0 85 1 0 0
v_all_suppliers 29434 29434 0 0.00 0 27 1 0 0
v_all_wares 40300 40300 0 0.00 0 154 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 882 6 0 0
v_cancel_client_order 3216 3216 0 0.00 0 2155 4 0 0
v_cancel_customer_prepayment 1438 1438 0 0.00 0 1542 7 0 0
v_cancel_customer_reserve 1318 1318 0 0.00 0 1562 6 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 213 3 0 0
v_cancel_supplier_invoice 806 798 8 0.99 0 661 16 0 8
v_cancel_supplier_order 800 800 0 0.00 0 513 4 0 0
v_cancel_write_off 1906 1906 0 0.00 0 2642 15 0 0
v_max_id_clo_ord 5284 5284 0 0.00 0 384 1 0 0
v_max_id_clo_res 988 988 0 0.00 0 2369 13 0 0
v_max_id_ord_sup 5272 5272 0 0.00 0 5013 4 0 0
v_max_non_paid_invoice 1563 1563 0 0.00 0 497 2 0 0
v_max_non_paid_realizn 3713 3713 0 0.00 0 6750 4 0 0
v_min_id_clo_ord 5284 5284 0 0.00 0 4521 6 0 0
v_min_id_clo_res 988 988 0 0.00 0 3953 11 0 0
v_min_id_ord_sup 5272 5272 0 0.00 0 6001 8 0 0
v_min_non_paid_invoice 5724 5724 0 0.00 0 18577 18 0 0
v_min_non_paid_realizn 6854 6854 0 0.00 0 9195 19 0 0
v_our_firm 10106 10106 0 0.00 0 124 1 0 0
v_reserve_write_off 19006 19006 0 0.00 0 4464 6 0 0
x_lock_depdocs_on_canc_invoice 11985 11985 0 0.00 0 25591 111 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 10162 322 0 0
x_make_qstorno_1000_1200 5288 5288 0 0.00 2 26367 1280 0 0
x_make_qstorno_1200_2000 5284 5284 0 0.00 3 100352 3548 0 0
x_make_qstorno_2100_3300 435737 435737 0 0.00 0 136457 156 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 12-10-2020 10:28:07 to 12-10-2020 10:28:12.

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:52 4 8192000000 8798381360 8809598976 4423552 0.053 2 1 2 0 4522672 63264 35856
07:57:53 25868 8192000000 9714543088 10010861568 792692816 8.822 100 88 176 0 822157696 7636656 21828224
07:59:26 15501 8192000000 9766109808 10073407488 836351584 9.263 100 82 164 0 868355952 12412880 19591488
08:00:43 15862 8192000000 9805363008 10122866688 871818096 9.618 100 86 172 0 917422576 25939360 19665120
08:02:11 8996 8192000000 9885074192 10214703104 922029120 10.116 100 85 170 0 989799632 49942544 17827968
08:03:23 9947 8192000000 9937227200 10278264832 963694144 10.525 100 87 172 0 1043557424 62427808 17435472
08:04:37 3825 8192000000 9973158784 10326609920 993093280 10.812 100 91 180 1 1084381536 73000384 18287872
08:05:43 2300 8192000000 9989674928 10351063040 1027761056 11.147 100 89 178 3 1100617808 54270448 18586304
08:06:47 2214 8192000000 10015470816 10384666624 1050676528 11.367 100 92 185 2 1132373856 62652128 19045200
08:07:52 2299 8192000000 10038545104 10424410112 1061951648 11.475 100 64 128 1 1147707392 73104224 12651520
08:08:56 4816 8192000000 9995934432 10393919488 1073083328 11.582 100 40 78 3 1127194272 45521728 8589216
08:10:01 21618 8192000000 10023206624 10427723776 1079619248 11.644 100 44 88 0 1123691344 35039776 9032320
08:11:25 8156 8192000000 10003433792 10402721792 1079936896 11.647 100 47 93 0 1108734176 19334720 9462560
08:12:35 3099 8192000000 9996249632 10389983232 1084039472 11.686 100 41 79 2 1108018272 13728272 10250528
08:13:42 9716 8192000000 10011210544 10418016256 1092454192 11.766 100 27 54 1 1113455424 15050224 5951008
08:14:57 50111 8192000000 9993670048 10408144896 1079453712 11.642 99 33 63 1 1101039056 14178816 7406528
08:16:49 9142 8192000000 9987003056 10383507456 1039662144 11.261 96 40 80 3 1068744144 20793616 8288384
08:18:00 8947 8192000000 9964294704 10360688640 1036754640 11.233 98 26 52 0 1060368608 18215136 5398832
08:19:09 7018 8192000000 9929597424 10309328896 1000157776 10.880 98 38 76 3 1021600368 13433648 8008944
08:20:17 27902 8192000000 9922179840 10309230592 931761856 10.212 90 31 62 2 961075680 22831120 6482704
08:21:53 5588 8192000000 9934723776 10320707584 1025971920 11.130 100 31 63 4 1050008000 17012336 7023744
08:23:01 10104 8192000000 9985854608 10384855040 1053303248 11.392 100 42 84 0 1105393776 42818112 9272416
08:24:14 71860 8192000000 9973020000 10382962688 1066867872 11.522 100 20 39 1 1087277264 16402992 4006400
08:26:27 5835 8192000000 10008626576 10407530496 1087130560 11.715 100 39 77 0 1118899152 22843008 8925584
08:27:43 23021 8192000000 10039336016 10428051456 1100176800 11.839 100 46 90 2 1137768400 28851776 8739824
08:29:12 31882 8192000000 10084152752 10483339264 1127509616 12.098 100 50 100 1 1204284720 65905216 10869888
08:30:50 8563 8192000000 10150913408 10565595136 1147398896 12.285 100 59 117 3 1267724256 107693632 12631728
08:32:04 12405 8192000000 10172625952 10575683584 1157353552 12.378 100 55 110 2 1277157760 107967712 11836496
08:33:23 12082 8192000000 10215343808 10628718592 1161041520 12.413 100 52 104 1 1323297664 150409600 11846544
08:34:41 8901 8192000000 10223687488 10636005376 1170368112 12.500 100 61 118 2 1332402640 149054144 12980384
08:36:00 14572 8192000000 10249520608 10660007936 1175042256 12.544 100 70 139 0 1349941776 159992832 14906688
08:37:19 2443 8192000000 10266412464 10677411840 1174698576 12.541 100 55 108 0 1368916432 181845904 12371952
08:38:24 3197 8192000000 10307445088 10709041152 1177269488 12.565 100 70 139 0 1407155360 214174928 15710944
08:39:30 1577 8192000000 10356152928 10757074944 1174216528 12.536 100 86 172 1 1457583744 263297088 20070128
08:40:36 1911 8192000000 10375411264 10780999680 1148236752 12.293 99 66 130 0 1471956272 307567072 16152448
08:41:43 1538 8192000000 10392608576 10783875072 1154598880 12.353 100 86 173 2 1484459552 309495824 20364848
08:42:50 7442 8192000000 10404082896 10831007744 1162476944 12.426 100 62 125 1 1502758976 325746288 14535744
08:44:03 3708 8192000000 10384403792 10801795072 1140803536 12.223 100 77 155 0 1488065536 328854720 18407280
08:45:10 11115 8192000000 10354668896 10764238848 1145016800 12.263 98 38 76 2 1441295648 285990736 10288112
08:46:26 9397 8192000000 10370863072 10825396224 1165035872 12.450 100 33 66 0 1503240976 329859296 8345808
08:47:38 8030 8192000000 10435200464 10851700736 1161305408 12.415 100 48 96 1 1528632032 355281456 12045168
08:48:55 12493 8192000000 10479762480 10903044096 1132381952 12.144 97 41 82 0 1552723648 409762560 10579136
08:50:14 26614 8192000000 10488664144 10975281152 1146575104 12.277 100 26 52 0 1631443152 477421904 7446144
08:52:40 13396 8192000000 10198713344 10748493824 1117958000 12.008 98 31 63 2 1321371296 195538352 7874944
08:54:07 66634 8192000000 10057094992 10518994944 1074052064 11.591 95 19 38 2 1122514752 43730544 4732144
08:56:32 43117 8192000000 10115401136 10534551552 1114624032 11.976 99 34 67 7 1203408496 81661168 7123296
08:58:15 128668 8192000000 10112215568 10553139200 1123227712 12.057 99 26 52 1 1214573920 86069104 5277104
09:01:27 29882 8192000000 10091636544 10533625856 1118790704 12.016 98 25 46 1 1179136240 55997552 4347984
09:02:58 59488 8192000000 10173585952 10602606592 1137691248 12.194 100 40 80 0 1243835376 96557088 9587040
09:04:59 185477 8192000000 10131838032 10608435200 1138109568 12.198 99 19 38 0 1300440336 158318560 4012208
09:09:07 92533 8192000000 10121245040 10609655808 1135040160 12.169 99 9 18 0 1219582608 82516160 2026288
09:11:40 48628 8192000000 10167776688 10653859840 1153974304 12.347 100 40 80 0 1245944640 83080176 8890160
09:13:32 35809 8192000000 10076266448 10510131200 1128834192 12.110 98 32 62 0 1159856864 24608688 6413984
09:15:08 76469 8192000000 10101498000 10528555008 1142457440 12.239 99 19 38 1 1209804480 63348432 3998608
09:18:09 92128 8192000000 10067654944 10511372288 1103069552 11.867 96 7 13 1 1165712544 60956672 1686320
09:20:42 58309 8192000000 10069421408 10520879104 1116223536 11.991 98 20 40 0 1170150224 49557888 4368800
09:22:52 58846 8192000000 10066607040 10499502080 1098676320 11.825 96 35 70 1 1140501776 34338656 7486800
09:24:57 37232 8192000000 10044887360 10537164800 1108310688 11.916 99 11 22 0 1138091744 27631136 2149920
09:26:34 62732 8192000000 10081790544 10525966336 1111997504 11.951 99 17 33 0 1162394160 46269360 4127296
09:28:49 63257 8192000000 10112276784 10578767872 1121781168 12.044 99 13 26 0 1197071712 72620080 2670464
09:30:57 41039 8192000000 10138189328 10598150144 1139357008 12.209 100 29 58 2 1242518688 96171328 6990352
09:32:57 40322 8192000000 10085385968 10518081536 1078629056 11.634 95 21 41 1 1131243552 48076000 4538496
09:34:55 74098 8192000000 10089984384 10579877888 1112200496 11.953 99 20 40 1 1227808400 111357520 4250384
09:37:23 29820 8192000000 10062271872 10499973120 1076718784 11.616 96 31 62 1 1101564688 18778000 6067904
09:38:54 63287 8192000000 10064412736 10536239104 1122858944 12.054 100 16 32 1 1180303888 54515792 2929152
09:41:10 150541 8192000000 10091265008 10603282432 1126509200 12.088 100 22 44 1 1188011072 56821296 4680576
09:44:45 48441 8192000000 10059488032 10564481024 1118681648 12.015 99 5 10 0 1157834784 37989008 1164128
09:46:33 50798 8192000000 10089131888 10528374784 1110523520 11.937 98 15 30 1 1158207472 44714944 2969008
09:48:32 56153 8192000000 10059069072 10508619776 1121478208 12.041 100 14 28 0 1167519168 43101408 2939552
09:51:01 55822 8192000000 10107936128 10572361728 1111561376 11.947 98 14 28 1 1195553056 80967424 3024256
09:53:06 33004 8192000000 10140173920 10582216704 1126839056 12.092 99 27 54 0 1211662512 79292272 5531184
09:54:40 52378 8192000000 10094749424 10541117440 1089092416 11.734 96 29 58 2 1124957808 30174800 5690592
09:56:34 89421 8192000000 10091350128 10522632192 1094208992 11.783 97 21 42 2 1137509696 39353056 3947648
09:59:08 77313 8192000000 10078021168 10546089984 1123911104 12.064 100 16 32 0 1185089008 57835616 3342288
10:01:31 43302 8192000000 10031094960 10478874624 1101743936 11.854 98 11 22 0 1156003984 51907248 2352800
10:03:19 82211 8192000000 10081141536 10570469376 1126636992 12.090 99 12 24 0 1176530176 47818832 2074352
10:05:45 90654 8192000000 10062361248 10503344128 1140877872 12.224 100 12 24 1 1188004032 45050304 2075856
10:08:16 57337 8192000000 10096216336 10533617664 1106944208 11.903 97 24 48 0 1142560512 30444448 5171856
10:10:14 108491 8192000000 10092925584 10553188352 1101511168 11.852 97 15 30 0 1185720224 81185744 3023312
10:13:02 50680 8192000000 10079902896 10560606208 1125837920 12.082 99 12 24 1 1190848096 62816576 2193600
10:15:05 42305 8192000000 10114590592 10583875584 1130463792 12.126 99 35 70 1 1221563616 83447360 7652464
10:16:49 39348 8192000000 10068256448 10501296128 1102335984 11.860 97 27 54 4 1143427776 35397664 5694128
10:18:29 96588 8192000000 10069484224 10525761536 1130479008 12.126 100 21 42 0 1192504960 57855024 4170928
10:21:09 44242 8192000000 10074933712 10528280576 1109671584 11.929 98 5 10 0 1148464080 37845104 947392
10:23:17 85256 8192000000 10079999440 10522750976 1083700752 11.683 96 22 44 0 1167353776 78683024 4970000
10:25:46 40131 8192000000 10070148320 10506764288 1112462352 11.956 99 19 38 0 1163227248 46730016 4034880

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 100 v_add_invoice_to_stock 335544517
user_exc 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-10-2020 10:28:29 to 12-10-2020 10:28:31.

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          229839
MON$OLDEST_ACTIVE               232857
MON$OLDEST_SNAPSHOT             232857
MON$NEXT_TRANSACTION            232857
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                       2107328
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             779
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2225 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2225 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2225 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.2225 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 ---
:91: 
:92: mscs-test-161.budzdorov.ru    Mon Oct 12 10:26:50 2020
:93:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:94: 
:95: 
:96: mscs-test-161.budzdorov.ru    Mon Oct 12 10:26:51 2020
:97:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:98: 
:99: 
:100: mscs-test-161.budzdorov.ru    Mon Oct 12 10:26:56 2020
:101:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:102: 
:103: 
:104: mscs-test-161.budzdorov.ru    Mon Oct 12 10:26:56 2020
:105:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:106: 
:107: 
:108: mscs-test-161.budzdorov.ru    Mon Oct 12 10:26:57 2020
:109:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:110: 
--- end of diff output ---

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

12.10.20 10:28:32. 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-12 10:28:34 No severe PSQL-related problems occured
12.10.20 10:28:36. End of report.