Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.10.2020 04: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 16827036 kB
MemAvailable 31451552 kB
Buffers 20 kB
Cached 14763224 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-28 04:26:53
DTS_END                         2020-10-28 04:26:53.2880
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/35744
TRN_ID                          237498
ATT_ID                          657
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 8649.06 20097 1037887
customer order: creation 175.15 397 21018
customer order: refuse 14.90 407 1788
order to supplier: creation 44.43 1496 5332
order to supplier: removal 3.09 120605 371
invoice (draft): creation 45.95 3988 5514
invoice (draft): removal 48.27 1126 5792
invoice accept: apply 41.82 18548 5018
invoice accept: cancel 42.84 1034 5141
customer reserve: creation 3771.22 185 452546
customer reserve: removal 4169.00 68 500280
realization accept: apply 83.98 201 10077
realization accept: cancel 78.00 136 9360
payment from customer: creation 59.78 136 7173
payment from customer: removal 5.64 101 677
payment to supplier: creation 50.34 157 6041
payment to supplier: removal 3.79 66 455
service: total inventory turnovers 3.82 3711 458
service: total monetary turnovers 6.14 668 737
service: aggregate perf. data 0.88 16524 105
service: refresh index statistics 0.03 232385 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-10-2020 04:27:48 to 28-10-2020 04:27:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2678
TEST_TIME 2 4070
TEST_TIME 3 4672
TEST_TIME 4 5160
TEST_TIME 5 5266
TEST_TIME 6 5306
TEST_TIME 7 4908
TEST_TIME 8 5069
TEST_TIME 9 5340
TEST_TIME 10 5222
TEST_TIME 11 5405
TEST_TIME 12 5900
TEST_TIME 13 6113
TEST_TIME 14 6433
TEST_TIME 15 6830
TEST_TIME 16 7132
TEST_TIME 17 7138
TEST_TIME 18 7099
TEST_TIME 19 7211
TEST_TIME 20 7558
TEST_TIME 21 7521
TEST_TIME 22 7649
TEST_TIME 23 7678
TEST_TIME 24 7795
TEST_TIME 25 8177
TEST_TIME 26 8283
TEST_TIME 27 8403
TEST_TIME 28 8943
TEST_TIME 29 9627
TEST_TIME 30 9560
TEST_TIME 31 9699
TEST_TIME 32 9800
TEST_TIME 33 9930
TEST_TIME 34 9898
TEST_TIME 35 9904
TEST_TIME 36 9926
TEST_TIME 37 9904
TEST_TIME 38 9914
TEST_TIME 39 10009
TEST_TIME 40 9941
TEST_TIME 41 9889
TEST_TIME 42 9851
TEST_TIME 43 9788
TEST_TIME 44 9809
TEST_TIME 45 9612
TEST_TIME 46 9421
TEST_TIME 47 9328
TEST_TIME 48 9293
TEST_TIME 49 9255
TEST_TIME 50 9242
TEST_TIME 51 9187
TEST_TIME 52 9163
TEST_TIME 53 9134
TEST_TIME 54 9071
TEST_TIME 55 9125
TEST_TIME 56 9129
TEST_TIME 57 9256
TEST_TIME 58 9203
TEST_TIME 59 9202
TEST_TIME 60 9290
TEST_TIME 61 9303
TEST_TIME 62 9280
TEST_TIME 63 9308
TEST_TIME 64 9305
TEST_TIME 65 9260
TEST_TIME 66 9223
TEST_TIME 67 9274
TEST_TIME 68 9358
TEST_TIME 69 9314
TEST_TIME 70 9259
TEST_TIME 71 9175
TEST_TIME 72 9102
TEST_TIME 73 9085
TEST_TIME 74 9017
TEST_TIME 75 8944
TEST_TIME 76 8923
TEST_TIME 77 8914
TEST_TIME 78 8877
TEST_TIME 79 8868
TEST_TIME 80 8876
TEST_TIME 81 8893
TEST_TIME 82 8882
TEST_TIME 83 8844
TEST_TIME 84 8902
TEST_TIME 85 8866
TEST_TIME 86 8849
TEST_TIME 87 8908
TEST_TIME 88 8868
TEST_TIME 89 8945
TEST_TIME 90 8936
TEST_TIME 91 8873
TEST_TIME 92 8835
TEST_TIME 93 8777
TEST_TIME 94 8765
TEST_TIME 95 8743
TEST_TIME 96 8698
TEST_TIME 97 8695
TEST_TIME 98 8659
TEST_TIME 99 8732
TEST_TIME 100 8690
TEST_TIME 101 8709
TEST_TIME 102 8687
TEST_TIME 103 8686
TEST_TIME 104 8730
TEST_TIME 105 8718
TEST_TIME 106 8732
TEST_TIME 107 8737
TEST_TIME 108 8763
TEST_TIME 109 8685
TEST_TIME 110 8650
TEST_TIME 111 8601
TEST_TIME 112 8598
TEST_TIME 113 8607
TEST_TIME 114 8591
TEST_TIME 115 8601
TEST_TIME 116 8606
TEST_TIME 117 8631
TEST_TIME 118 8618
TEST_TIME 119 8599
TEST_TIME 120 8650

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-10-2020 04:27:52 to 28-10-2020 04:27:55.

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 1457072 1457072 0 0.00 0 22784 8 0 0
doc_list_biud 1457072 1457072 0 0.00 0 39097 31 0 0
sp_add_invoice_to_stock 5156 5018 138 2.67 17 180646 18549 0 138
sp_cancel_adding_invoice 5141 5141 0 0.00 6 82228 1034 0 0
sp_cancel_client_order 1788 1788 0 0.00 4 16249 408 0 0
sp_cancel_customer_reserve 500280 500280 0 0.00 1 29661 69 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 3 5588 102 0 0
sp_cancel_pay_to_supplier 455 455 0 0.00 3 1567 66 0 0
sp_cancel_supplier_invoice 5799 5792 7 0.12 2 30398 1127 0 7
sp_cancel_supplier_order 371 371 0 0.00 17 1208103 120606 0 0
sp_cancel_write_off 9360 9360 0 0.00 1 17776 136 0 0
sp_client_order 21018 21018 0 0.00 4 22307 397 0 0
sp_customer_reserve 452546 452546 0 0.00 0 121614 186 0 0
sp_fill_shopping_cart 31864 31864 0 0.00 1 23276 50 0 0
sp_fill_shopping_cart_clo_res 452545 452545 0 0.00 0 29200 17 0 0
sp_get_clo_for_invoice 5017 5017 0 0.00 0 53604 425 0 0
sp_kill_cost_storno 15633 15633 0 0.00 0 12983 67 0 0
sp_kill_qstorno_ret_qs2qd 1016083 1016083 0 0.00 0 39096 39 0 0
sp_kill_qty_storno 537826 537826 0 0.00 0 39097 84 0 0
sp_lock_selected_doc 21023 21023 0 0.00 0 7562 4 0 0
sp_make_cost_storno 28308 28308 0 0.00 0 13378 64 0 0
sp_multiply_rows_for_pdistr 28308 28308 0 0.00 0 13396 39 0 0
sp_multiply_rows_for_qdistr 21018 21018 0 0.00 0 22300 210 0 0
sp_pay_from_customer 7173 7173 0 0.00 4 13502 136 0 0
sp_pay_to_supplier 6041 6041 0 0.00 5 13429 158 0 0
sp_payment_common 13214 13214 0 0.00 1 13186 32 0 0
sp_qd_handle_on_cancel_clo 1788 1788 0 0.00 0 16240 378 0 0
sp_qd_handle_on_invoice_upd_sts 10158 10158 0 0.00 0 30584 419 0 0
sp_qd_handle_on_reserve_upd_sts 10077 10077 0 0.00 0 11531 57 0 0
sp_reserve_write_off 10077 10077 0 0.00 6 12337 201 0 0
sp_supplier_invoice 5514 5514 0 0.00 7 132236 3989 0 0
sp_supplier_order 5332 5332 0 0.00 4 23346 1496 0 0
srv_aggregate_perf_data 105 105 0 0.00 2258 202649 16525 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1468 192779 44665 0 0
srv_find_qd_qs_mism 990276 990276 0 0.00 0 22781 7 0 0
srv_make_invnt_saldo 458 458 0 0.00 49 120634 3711 0 0
srv_make_money_saldo 737 737 0 0.00 1 22813 669 0 0
srv_recalc_idx_stat 4 4 0 0.00 64824 372374 232385 0 0
t$perf-abend:sp_add_invoice_to_stock 138 138 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5018 5018 0 0.00 0 12941 171 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 1 3247 99 0 0
t$perf-norm:sp_cancel_client_order 1788 1788 0 0.00 0 1497 14 0 0
t$perf-norm:sp_cancel_customer_reserve 719 719 0 0.00 0 772 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 2928 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 455 455 0 0.00 0 1262 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 465 465 0 0.00 0 1614 21 0 0
t$perf-norm:sp_cancel_supplier_order 371 371 0 0.00 0 23150 1618 0 0
t$perf-norm:sp_cancel_write_off 1020 1020 0 0.00 0 2919 7 0 0
t$perf-norm:sp_client_order 21018 21018 0 0.00 0 6035 18 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 1471 5 0 0
t$perf-norm:sp_pay_from_customer 7173 7173 0 0.00 0 7579 10 0 0
t$perf-norm:sp_pay_to_supplier 6041 6041 0 0.00 0 5459 10 0 0
t$perf-norm:sp_reserve_write_off 10077 10077 0 0.00 0 5427 11 0 0
t$perf-norm:sp_supplier_invoice 5514 5514 0 0.00 0 4346 20 0 0
t$perf-norm:sp_supplier_order 5332 5332 0 0.00 0 5011 20 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 1066 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1126 18 0 0
t$perf-norm:srv_make_invnt_saldo 458 458 0 0.00 0 2608 35 0 0
t$perf-norm:srv_make_money_saldo 737 737 0 0.00 0 1866 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 21 7 0 0
v_add_invoice_to_stock 10308 10032 276 2.67 0 6394 15 0 276
v_all_customers 37616 37616 0 0.00 0 82 1 0 0
v_all_suppliers 29890 29890 0 0.00 0 61 1 0 0
v_all_wares 42036 42036 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 1156 9 0 0
v_cancel_client_order 3576 3576 0 0.00 0 3191 6 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 631 5 0 0
v_cancel_customer_reserve 1438 1438 0 0.00 0 982 5 0 0
v_cancel_payment_to_supplier 910 910 0 0.00 0 330 4 0 0
v_cancel_supplier_invoice 944 930 14 1.48 0 1640 16 0 14
v_cancel_supplier_order 742 742 0 0.00 0 583 5 0 0
v_cancel_write_off 2040 2040 0 0.00 0 4934 16 0 0
v_max_id_clo_ord 5332 5332 0 0.00 0 183 1 0 0
v_max_id_clo_res 997 997 0 0.00 0 3638 19 0 0
v_max_id_ord_sup 5514 5514 0 0.00 0 6921 7 0 0
v_max_non_paid_invoice 1942 1942 0 0.00 0 2122 3 0 0
v_max_non_paid_realizn 4053 4053 0 0.00 0 7180 4 0 0
v_min_id_clo_ord 5332 5332 0 0.00 0 13063 9 0 0
v_min_id_clo_res 997 997 0 0.00 0 12376 33 0 0
v_min_id_ord_sup 5514 5514 0 0.00 0 14983 7 0 0
v_min_non_paid_invoice 6041 6041 0 0.00 0 12772 25 0 0
v_min_non_paid_realizn 7173 7173 0 0.00 0 13182 24 0 0
v_our_firm 10660 10660 0 0.00 0 134 1 0 0
v_reserve_write_off 20154 20154 0 0.00 0 4951 7 0 0
x_lock_depdocs_on_canc_invoice 10468 10468 0 0.00 0 39930 136 0 0
x_lock_depdocs_on_canc_sup_ord 371 371 0 0.00 0 3431 339 0 0
x_make_qstorno_1000_1200 5332 5332 0 0.00 1 23321 1410 0 0
x_make_qstorno_1200_2000 5514 5514 0 0.00 3 132218 3784 0 0
x_make_qstorno_2100_3300 452546 452546 0 0.00 0 121613 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-10-2020 04:27:56 to 28-10-2020 04:28:00.

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
01:56:57 18 8192000000 8802518560 8815304704 5709120 0.069 3 1 2 0 5810368 65392 35856
01:57:58 23552 8192000000 9722638000 10022420480 802942992 8.926 100 86 172 0 831101744 7593280 20565472
01:59:34 15408 8192000000 9760034944 10066190336 836550064 9.265 100 84 168 0 869095952 12658880 19887008
02:00:54 14746 8192000000 9806861136 10125332480 869437744 9.594 100 84 168 0 913579600 24920960 19220896
02:02:14 13577 8192000000 9852106688 10182189056 904979136 9.948 100 80 160 0 962010640 39134928 17896576
02:03:34 10871 8192000000 9910657088 10254929920 951242656 10.403 100 76 148 1 1021586368 54490688 15853024
02:04:49 5463 8192000000 9968323904 10320044032 991618112 10.797 100 80 160 2 1083381824 75539408 16224304
02:05:57 3409 8192000000 9992292704 10352390144 1016839376 11.041 100 84 167 0 1100988144 67519136 16629632
02:07:02 2005 8192000000 10016826192 10384310272 1043318416 11.297 100 88 175 1 1129378656 67936912 18123328
02:08:06 2477 8192000000 10051718512 10421755904 1057540224 11.433 100 91 179 2 1161656928 85931072 18185632
02:09:10 21406 8192000000 9986114400 10369961984 1066259312 11.516 100 38 75 1 1117131872 42622704 8249856
02:10:32 2179 8192000000 9990535712 10387877888 1078600160 11.634 100 31 62 1 1099572816 14747776 6224880
02:11:35 7650 8192000000 10003576208 10411593728 1085259424 11.698 100 34 67 2 1129705504 37772352 6673728
02:12:44 71295 8192000000 10006475728 10403360768 1092647888 11.768 100 42 86 1 1113663680 11079824 9935968
02:14:56 7495 8192000000 10013511248 10415296512 1094692784 11.787 100 39 78 4 1123730560 21139440 7898336
02:16:04 5469 8192000000 10011155248 10419138560 1098504096 11.823 100 28 55 1 1124019360 19907680 5607584
02:17:12 6637 8192000000 10013795040 10403549184 1080539776 11.653 99 35 68 3 1115458672 27844240 7074656
02:18:25 34872 8192000000 9937027120 10331459584 939326144 10.286 88 16 32 0 961296576 18599888 3370544
02:20:02 7552 8192000000 9935783488 10309976064 991555632 10.797 96 27 55 0 1018654992 21484000 5615360
02:21:10 8228 8192000000 9946538448 10328571904 1016933088 11.042 99 43 86 0 1045401440 18280000 10188352
02:22:19 9375 8192000000 9943553600 10338181120 1023889216 11.110 100 31 63 1 1060300816 29550144 6861456
02:23:31 27202 8192000000 9955948192 10356494336 1029256624 11.161 99 21 42 2 1056910128 23561456 4092048
02:25:05 5602 8192000000 9991854288 10386063360 1057780224 11.435 100 43 87 2 1086935472 19802512 9352736
02:26:16 7058 8192000000 9994767072 10393792512 1078674816 11.635 100 41 81 2 1110584912 22835088 9075008
02:27:27 67530 8192000000 10022226416 10425511936 1094229168 11.783 100 41 83 1 1139845872 37217408 8399296
02:29:41 3324 8192000000 10072574368 10463555584 1115639648 11.986 100 70 140 0 1179414096 48475504 15298944
02:30:52 9766 8192000000 10078058896 10483585024 1129452208 12.116 100 50 100 1 1190428848 49894816 11081824
02:32:12 17274 8192000000 10120371664 10526978048 1142430608 12.238 100 47 94 1 1229034528 76635472 9968448
02:33:35 32249 8192000000 10155846352 10562703360 1137974352 12.196 100 46 92 1 1264317008 116277632 10065024
02:35:11 40303 8192000000 10138261408 10552573952 1149571856 12.305 100 30 60 4 1236103840 79845632 6686352
02:37:00 4022 8192000000 10218428064 10623070208 1162709200 12.429 100 74 148 1 1320806656 142323920 15773536
02:38:09 3990 8192000000 10245683728 10648641536 1166847840 12.467 100 74 148 1 1348348496 164922992 16577664
02:39:19 3230 8192000000 10251323008 10656739328 1156731216 12.373 99 78 151 0 1354142624 180950336 16461072
02:40:26 6311 8192000000 10214953216 10628780032 1144915072 12.262 99 55 107 1 1329216144 171584784 12716288
02:41:38 2154 8192000000 10239573680 10659373056 1153568208 12.343 99 77 149 2 1332045664 161231664 17245792
02:42:43 9858 8192000000 10229318752 10638573568 1138822848 12.204 99 55 110 2 1326531760 176146928 11561984
02:44:07 3157 8192000000 10259929456 10660696064 1144765264 12.260 99 66 132 2 1350895824 191798480 14332080
02:45:12 4074 8192000000 10258581312 10667442176 1143087104 12.245 100 64 128 1 1356492144 199066672 14338368
02:46:22 3214 8192000000 10305331184 10698256384 1142632320 12.240 100 84 168 2 1395930560 234632704 18665536
02:47:29 4585 8192000000 10276436416 10677960704 1122778464 12.053 100 64 130 1 1364876608 226920656 15177488
02:48:40 3297 8192000000 10321017680 10712203264 1128861088 12.111 100 81 158 1 1423619600 276740752 18017760
02:49:49 1466 8192000000 10383903264 10780278784 1143821760 12.251 100 90 180 2 1477215152 311731680 21661712
02:50:58 4719 8192000000 10392444352 10819862528 1145579152 12.268 100 78 156 0 1485272368 320826688 18866528
02:52:08 6079 8192000000 10362699696 10788257792 1139211536 12.208 100 47 94 1 1462514128 310744544 12558048
02:53:19 11961 8192000000 10425622640 10852507648 1142871600 12.243 100 43 85 1 1517094848 362726432 11496816
02:54:33 13332 8192000000 10354937888 10825269248 1141001936 12.225 100 37 73 1 1464019088 312942352 10074800
02:55:58 33722 8192000000 10229302144 10668130304 1136597920 12.184 100 19 38 3 1318101840 177221728 4282192
02:57:32 39209 8192000000 10152345264 10636746752 1129740528 12.119 100 19 37 0 1297271536 163224224 4306784
02:59:14 52132 8192000000 10100277968 10569228288 1145282128 12.265 100 6 12 0 1185638336 39264752 1091456
03:01:06 18973 8192000000 10077437408 10514513920 1134075264 12.160 99 31 60 0 1163109632 21919440 7114928
03:02:36 47862 8192000000 10143384464 10627624960 1153361552 12.341 100 10 20 2 1215449264 59827376 2260336
03:04:25 68672 8192000000 10135087456 10608160768 1151872992 12.327 100 9 18 1 1219374256 65390128 2111136
03:06:34 53996 8192000000 10085916672 10550091776 1117131136 12.000 97 11 21 0 1192646960 73431200 2084624
03:08:31 20631 8192000000 10078105616 10494816256 1130678304 12.128 99 35 69 1 1160400224 22562288 7159632
03:09:53 38185 8192000000 10088448976 10531033088 1143576976 12.249 100 11 22 0 1196901824 50924496 2400352
03:11:36 114299 8192000000 10104707136 10539323392 1152274256 12.331 100 39 78 1 1192327664 32041488 8011920
03:14:35 23875 8192000000 10126673024 10593333248 1141533696 12.230 99 32 64 3 1192731840 44517216 6680928
03:16:14 46454 8192000000 10093371328 10547662848 1121097360 12.037 98 25 50 0 1205188432 77881520 6209552
03:18:28 192779 8192000000 10137725312 10596327424 1134891136 12.167 99 15 30 0 1226701056 88380560 3429360
03:23:47 63340 8192000000 10092534560 10540130304 1107474288 11.908 96 26 52 4 1136980896 24064992 5441616
03:25:51 55067 8192000000 10093252272 10554949632 1099075360 11.829 96 19 38 1 1153187312 50485824 3626128
03:27:47 33661 8192000000 10047327088 10485436416 1084724512 11.692 96 15 30 0 1109526736 21586912 3215312
03:29:24 28623 8192000000 10084229616 10525446144 1110612864 11.938 100 41 82 1 1182147920 63233392 8301664
03:31:03 57402 8192000000 10072003696 10487181312 1028391456 11.153 91 35 70 0 1066711376 31192416 7127504
03:33:01 76501 8192000000 10030036496 10525749248 1059737584 11.454 95 12 24 0 1129687040 67427680 2521776
03:35:19 69962 8192000000 10069196496 10507984896 1087835440 11.722 98 24 47 1 1130333136 37458160 5039536
03:37:30 61541 8192000000 10050061728 10522906624 1086817296 11.712 98 25 50 2 1135838080 43339584 5681200
03:39:40 88259 8192000000 10062609200 10503688192 1087268656 11.717 97 14 27 0 1128077440 37865184 2943600
03:44:14 47056 8192000000 10041287440 10474532864 1075084624 11.601 96 23 46 2 1117110672 37193376 4832672
03:46:01 56698 8192000000 10088172896 10538442752 1114718816 11.977 99 19 38 1 1193800720 75218848 3863056
03:47:58 52198 8192000000 10091659168 10554499072 1111315648 11.945 99 23 46 0 1160143888 44214832 4613408
03:49:51 104452 8192000000 10114829056 10584965120 1132777424 12.148 100 23 46 0 1217623712 80081840 4764448
03:52:37 58555 8192000000 10076583456 10559397888 1113903552 11.969 99 24 48 0 1174800272 56260880 4635840
03:54:35 76550 8192000000 10129535968 10614509568 1118144224 12.009 99 16 32 1 1226896384 105067392 3684768
03:57:01 98616 8192000000 10068180320 10539925504 1143021792 12.244 100 17 34 2 1190079584 43684832 3372960
03:59:45 57683 8192000000 10080233232 10525708288 1112709360 11.958 97 8 16 0 1141295200 27111152 1474688
04:01:43 43788 8192000000 10075400576 10554474496 1144852576 12.261 100 8 16 2 1181467424 35031744 1583104
04:03:27 41498 8192000000 10106231376 10559352832 1150882336 12.318 100 16 32 2 1197040304 42690112 3467856
04:05:15 79465 8192000000 10093669360 10538549248 1150332128 12.313 100 13 26 0 1192592832 39493520 2767184
04:07:37 54325 8192000000 10094827856 10561290240 1145081872 12.263 99 22 45 3 1189382032 39764752 4535408
04:09:38 78882 8192000000 10077547472 10536169472 1082328784 11.670 94 22 43 3 1148841184 62501264 4011136
04:11:59 62419 8192000000 10128674352 10573803520 1095193936 11.792 95 14 28 1 1163677760 65428576 3055248
04:14:02 72909 8192000000 10061081936 10560421888 1089114128 11.734 96 8 16 1 1124816224 33907104 1794992
04:16:24 74359 8192000000 10070476368 10530242560 1105543488 11.890 98 6 11 1 1149642928 42852864 1246576
04:18:39 54739 8192000000 10081600288 10566598656 1129661104 12.118 100 6 10 1 1184732736 53227408 1844224
04:20:34 75592 8192000000 10065447440 10507882496 1115334304 11.983 99 19 38 1 1174162400 54635744 4192352
04:22:49 87941 8192000000 10041175440 10496626688 1101886992 11.856 98 16 32 0 1153302736 48100000 3315744
04:25:19 39711 8192000000 10082506928 10518110208 1103493504 11.871 99 36 72 1 1187943408 76654416 7795488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 138 v_add_invoice_to_stock 335544517
user_exc 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-10-2020 04:28:18 to 28-10-2020 04:28:20.

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          235844
MON$OLDEST_ACTIVE               237784
MON$OLDEST_SNAPSHOT             237784
MON$NEXT_TRANSACTION            237784
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                       2098560
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             790
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2238 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2238 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2238 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.2238 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Wed Oct 28 04:26:55 2020
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
:15: 
:16: mscs-test-161.budzdorov.ru    Wed Oct 28 04:26:58 2020
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Wed Oct 28 04:27:03 2020
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Wed Oct 28 04:27:03 2020
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
--- end of diff output ---

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

28.10.20 04:28:21. 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-28 04:28:24 No severe PSQL-related problems occured
28.10.20 04:28:25. End of report.