Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.10.2020 07:36
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 16887972 kB
MemAvailable 31585812 kB
Buffers 0 kB
Cached 14821848 kB
SwapCached 3048 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-24 07:35:20
DTS_END                         2020-10-24 07:35:20.4690
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/36546
TRN_ID                          233093
ATT_ID                          605
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: 8922.59 23444 1070711
customer order: creation 164.18 403 19701
customer order: refuse 13.56 330 1627
order to supplier: creation 44.35 1423 5322
order to supplier: removal 3.42 117881 410
invoice (draft): creation 44.11 3763 5293
invoice (draft): removal 50.27 1006 6032
invoice accept: apply 41.13 17894 4936
invoice accept: cancel 45.48 882 5458
customer reserve: creation 3791.86 175 455023
customer reserve: removal 4440.21 72 532825
realization accept: apply 80.08 176 9610
realization accept: cancel 80.36 120 9643
payment from customer: creation 57.01 115 6841
payment from customer: removal 5.31 99 637
payment to supplier: creation 47.63 115 5716
payment to supplier: removal 3.29 69 395
service: total inventory turnovers 3.75 3373 450
service: total monetary turnovers 5.83 591 699
service: aggregate perf. data 0.74 21669 89
service: refresh index statistics 0.03 298720 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-10-2020 07:36:49 to 24-10-2020 07:36:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6787
TEST_TIME 2 4684
TEST_TIME 3 4400
TEST_TIME 4 4646
TEST_TIME 5 5003
TEST_TIME 6 5345
TEST_TIME 7 5525
TEST_TIME 8 5511
TEST_TIME 9 5522
TEST_TIME 10 5737
TEST_TIME 11 5846
TEST_TIME 12 6434
TEST_TIME 13 6373
TEST_TIME 14 6692
TEST_TIME 15 7003
TEST_TIME 16 7226
TEST_TIME 17 7370
TEST_TIME 18 7594
TEST_TIME 19 7797
TEST_TIME 20 8115
TEST_TIME 21 8273
TEST_TIME 22 8392
TEST_TIME 23 8599
TEST_TIME 24 8790
TEST_TIME 25 9303
TEST_TIME 26 9837
TEST_TIME 27 10516
TEST_TIME 28 10731
TEST_TIME 29 10666
TEST_TIME 30 10529
TEST_TIME 31 10348
TEST_TIME 32 10118
TEST_TIME 33 10055
TEST_TIME 34 9899
TEST_TIME 35 9838
TEST_TIME 36 9699
TEST_TIME 37 9673
TEST_TIME 38 9819
TEST_TIME 39 9820
TEST_TIME 40 9751
TEST_TIME 41 9860
TEST_TIME 42 9785
TEST_TIME 43 9708
TEST_TIME 44 9619
TEST_TIME 45 9813
TEST_TIME 46 9864
TEST_TIME 47 9837
TEST_TIME 48 9925
TEST_TIME 49 9847
TEST_TIME 50 9843
TEST_TIME 51 9810
TEST_TIME 52 9799
TEST_TIME 53 9985
TEST_TIME 54 9950
TEST_TIME 55 9818
TEST_TIME 56 9711
TEST_TIME 57 9661
TEST_TIME 58 9641
TEST_TIME 59 9562
TEST_TIME 60 9470
TEST_TIME 61 9399
TEST_TIME 62 9401
TEST_TIME 63 9474
TEST_TIME 64 9457
TEST_TIME 65 9407
TEST_TIME 66 9424
TEST_TIME 67 9474
TEST_TIME 68 9522
TEST_TIME 69 9457
TEST_TIME 70 9539
TEST_TIME 71 9564
TEST_TIME 72 9576
TEST_TIME 73 9588
TEST_TIME 74 9620
TEST_TIME 75 9673
TEST_TIME 76 9662
TEST_TIME 77 9566
TEST_TIME 78 9517
TEST_TIME 79 9448
TEST_TIME 80 9358
TEST_TIME 81 9407
TEST_TIME 82 9395
TEST_TIME 83 9388
TEST_TIME 84 9353
TEST_TIME 85 9315
TEST_TIME 86 9337
TEST_TIME 87 9355
TEST_TIME 88 9326
TEST_TIME 89 9325
TEST_TIME 90 9397
TEST_TIME 91 9404
TEST_TIME 92 9439
TEST_TIME 93 9473
TEST_TIME 94 9494
TEST_TIME 95 9412
TEST_TIME 96 9362
TEST_TIME 97 9308
TEST_TIME 98 9285
TEST_TIME 99 9219
TEST_TIME 100 9194
TEST_TIME 101 9164
TEST_TIME 102 9121
TEST_TIME 103 9134
TEST_TIME 104 9135
TEST_TIME 105 9127
TEST_TIME 106 9159
TEST_TIME 107 9131
TEST_TIME 108 9155
TEST_TIME 109 9149
TEST_TIME 110 9197
TEST_TIME 111 9243
TEST_TIME 112 9231
TEST_TIME 113 9173
TEST_TIME 114 9148
TEST_TIME 115 9113
TEST_TIME 116 9053
TEST_TIME 117 9017
TEST_TIME 118 8974
TEST_TIME 119 8986
TEST_TIME 120 8928

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-10-2020 07:36:52 to 24-10-2020 07:36:56.

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 1491535 1491535 0 0.00 0 15014 6 0 0
doc_list_biud 1491529 1491529 0 0.00 0 72333 33 0 0
sp_add_invoice_to_stock 5082 4936 146 2.87 24 156167 17894 0 146
sp_cancel_adding_invoice 5458 5458 0 0.00 5 68211 883 0 0
sp_cancel_client_order 1627 1627 0 0.00 4 10227 330 0 0
sp_cancel_customer_reserve 532825 532825 0 0.00 1 72335 73 0 0
sp_cancel_pay_from_customer 637 637 0 0.00 3 15016 99 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 3690 70 0 0
sp_cancel_supplier_invoice 6046 6032 14 0.23 2 23765 1006 0 14
sp_cancel_supplier_order 410 410 0 0.00 6 1160686 117882 0 0
sp_cancel_write_off 9643 9643 0 0.00 1 7482 121 0 0
sp_client_order 19701 19701 0 0.00 4 44722 403 0 0
sp_customer_reserve 455023 455023 0 0.00 0 96538 175 0 0
sp_fill_shopping_cart 30293 30293 0 0.00 1 15017 44 0 0
sp_fill_shopping_cart_clo_res 455015 455015 0 0.00 0 21707 22 0 0
sp_get_clo_for_invoice 4924 4924 0 0.00 0 33894 418 0 0
sp_kill_cost_storno 16133 16133 0 0.00 0 11831 59 0 0
sp_kill_qstorno_ret_qs2qd 1081735 1081735 0 0.00 0 72327 40 0 0
sp_kill_qty_storno 570529 570529 0 0.00 0 72332 84 0 0
sp_lock_selected_doc 20027 20027 0 0.00 0 9518 7 0 0
sp_make_cost_storno 27091 27091 0 0.00 0 13793 52 0 0
sp_multiply_rows_for_pdistr 27091 27091 0 0.00 0 12184 32 0 0
sp_multiply_rows_for_qdistr 19700 19700 0 0.00 0 13901 251 0 0
sp_pay_from_customer 6841 6841 0 0.00 4 12366 115 0 0
sp_pay_to_supplier 5716 5716 0 0.00 5 16882 115 0 0
sp_payment_common 12557 12557 0 0.00 1 12230 28 0 0
sp_qd_handle_on_cancel_clo 1627 1627 0 0.00 0 10220 305 0 0
sp_qd_handle_on_invoice_upd_sts 10382 10382 0 0.00 0 23723 362 0 0
sp_qd_handle_on_reserve_upd_sts 9610 9610 0 0.00 0 11379 51 0 0
sp_reserve_write_off 9610 9610 0 0.00 7 17404 176 0 0
sp_supplier_invoice 5293 5293 0 0.00 6 79653 3764 0 0
sp_supplier_order 5322 5322 0 0.00 6 29035 1423 0 0
srv_aggregate_perf_data 89 89 0 0.00 2844 156011 21669 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1616 148589 47759 0 0
srv_find_qd_qs_mism 1024252 1024252 0 0.00 0 15013 5 0 0
srv_make_invnt_saldo 450 450 0 0.00 46 91938 3373 0 0
srv_make_money_saldo 699 699 0 0.00 1 17653 591 0 0
srv_recalc_idx_stat 4 4 0 0.00 109559 408457 298721 0 0
t$perf-abend:sp_add_invoice_to_stock 146 146 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4936 4936 0 0.00 0 16552 173 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 1 7279 87 0 0
t$perf-norm:sp_cancel_client_order 1627 1627 0 0.00 0 3310 15 0 0
t$perf-norm:sp_cancel_customer_reserve 679 679 0 0.00 0 4058 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 637 637 0 0.00 0 1794 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 868 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 1042 8 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 18419 1267 0 0
t$perf-norm:sp_cancel_write_off 964 964 0 0.00 0 1639 8 0 0
t$perf-norm:sp_client_order 19701 19701 0 0.00 0 14750 19 0 0
t$perf-norm:sp_customer_reserve 1010 1010 0 0.00 0 674 4 0 0
t$perf-norm:sp_pay_from_customer 6841 6841 0 0.00 0 3867 8 0 0
t$perf-norm:sp_pay_to_supplier 5716 5716 0 0.00 0 4576 8 0 0
t$perf-norm:sp_reserve_write_off 9610 9610 0 0.00 0 7114 11 0 0
t$perf-norm:sp_supplier_invoice 5293 5293 0 0.00 0 6860 22 0 0
t$perf-norm:sp_supplier_order 5322 5322 0 0.00 0 2156 14 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 518 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 17 1 0 0
t$perf-norm:srv_make_invnt_saldo 450 450 0 0.00 0 5219 16 0 0
t$perf-norm:srv_make_money_saldo 699 699 0 0.00 0 1853 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 49 14 0 0
v_add_invoice_to_stock 10140 9848 292 2.87 0 4809 16 0 292
v_all_customers 35628 35628 0 0.00 0 48 1 0 0
v_all_suppliers 29138 29138 0 0.00 0 16 1 0 0
v_all_wares 39400 39400 0 0.00 0 63 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 173 6 0 0
v_cancel_client_order 3254 3254 0 0.00 0 2443 5 0 0
v_cancel_customer_prepayment 1274 1274 0 0.00 0 3170 9 0 0
v_cancel_customer_reserve 1358 1358 0 0.00 0 1578 4 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 474 3 0 0
v_cancel_supplier_invoice 808 780 28 3.46 0 950 13 0 28
v_cancel_supplier_order 820 820 0 0.00 0 1126 6 0 0
v_cancel_write_off 1928 1928 0 0.00 0 3115 16 0 0
v_max_id_clo_ord 5309 5309 0 0.00 0 294 1 0 0
v_max_id_clo_res 1010 1010 0 0.00 0 2556 14 0 0
v_max_id_ord_sup 5283 5283 0 0.00 0 4731 6 0 0
v_max_non_paid_invoice 1739 1739 0 0.00 0 657 3 0 0
v_max_non_paid_realizn 3715 3715 0 0.00 0 2042 4 0 0
v_min_id_clo_ord 5309 5309 0 0.00 0 1511 4 0 0
v_min_id_clo_res 1010 1010 0 0.00 0 3281 15 0 0
v_min_id_ord_sup 5283 5283 0 0.00 0 8401 13 0 0
v_min_non_paid_invoice 5716 5716 0 0.00 0 6734 15 0 0
v_min_non_paid_realizn 6841 6841 0 0.00 0 12206 24 0 0
v_our_firm 10024 10024 0 0.00 0 19 1 0 0
v_reserve_write_off 19220 19220 0 0.00 0 3716 5 0 0
x_lock_depdocs_on_canc_invoice 11100 11100 0 0.00 0 17875 113 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 13209 366 0 0
x_make_qstorno_1000_1200 5322 5322 0 0.00 2 29019 1349 0 0
x_make_qstorno_1200_2000 5293 5293 0 0.00 3 75093 3592 0 0
x_make_qstorno_2100_3300 455023 455023 0 0.00 0 96508 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-10-2020 07:36:57 to 24-10-2020 07:37:01.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:05:24 3 8192000000 8800704768 8812814336 6358336 0.077 2 2 3 0 6510368 90592 61440
05:06:25 22775 8192000000 9711657312 10004668416 788943632 8.784 100 91 182 0 819319856 7829824 22546400
05:07:56 21464 8192000000 9762284464 10065350656 830402144 9.203 100 86 172 0 862260000 11546592 20311264
05:09:22 17873 8192000000 9802132096 10120888320 870770528 9.608 100 80 160 0 909934336 20644128 18519680
05:10:47 13371 8192000000 9867075696 10200969216 925146032 10.147 100 79 158 1 974177744 31481152 17550560
05:12:05 10779 8192000000 9920829744 10266796032 960981792 10.499 100 81 161 2 1034875712 56897312 16996608
05:13:20 4798 8192000000 9964470672 10320715776 1000925968 10.888 100 80 160 0 1079687536 61741488 17020080
05:14:28 2711 8192000000 10009842832 10370289664 1027790672 11.147 100 85 168 2 1127543328 82995008 16757648
05:15:32 1739 8192000000 10021201376 10385125376 1048714048 11.348 100 94 186 1 1129016496 61492304 18810144
05:16:35 2852 8192000000 10015911232 10392928256 1064509904 11.500 100 80 160 0 1139215840 58270576 16435360
05:17:38 16579 8192000000 9970306336 10370895872 1070728608 11.559 100 31 60 0 1103305856 25466160 7111088
05:18:56 5668 8192000000 9991504912 10394234880 1077223488 11.621 100 36 72 2 1104985040 19725520 8036032
05:20:04 5611 8192000000 10003433632 10404040704 1087301728 11.717 100 34 67 5 1125121872 30810032 7010112
05:21:14 4181 8192000000 10024817744 10420338688 1093906512 11.780 100 50 101 0 1138151664 33905968 10339184
05:22:19 33851 8192000000 10015859344 10424070144 1097851536 11.817 100 39 78 1 1123246912 16831040 8564336
05:23:58 3503 8192000000 10033828464 10423353344 1108633008 11.919 100 57 115 2 1149079728 26947408 13499312
05:25:02 4639 8192000000 10009610736 10401218560 1071839808 11.570 98 49 95 4 1101371168 18132880 11398480
05:26:07 79302 8192000000 9957497440 10355310592 978411728 10.669 91 31 62 1 1017165680 31869056 6884896
05:28:27 6134 8192000000 9959704976 10337398784 1014444512 11.018 98 44 90 0 1044341408 20070512 9826384
05:29:34 6689 8192000000 9953173664 10339098624 1020133616 11.073 99 46 93 3 1047323536 16674304 10515616
05:30:42 14545 8192000000 9962946432 10354827264 1031737296 11.185 100 39 79 2 1063591152 22617712 9236144
05:32:24 6741 8192000000 9961790608 10353639424 1045254032 11.315 100 25 50 1 1071975440 20881184 5840224
05:33:31 7333 8192000000 9981376592 10365411328 1059459264 11.451 100 37 74 0 1091279296 23483008 8337024
05:34:39 3235 8192000000 9981695712 10368630784 1068928512 11.542 100 45 91 4 1093116896 12726608 11461776
05:35:46 4611 8192000000 10014076688 10406559744 1090120624 11.744 100 50 97 2 1119819072 19616448 10082000
05:36:55 62768 8192000000 10043842144 10444574720 1098686256 11.825 100 34 68 2 1129940016 24202512 7051248
05:39:05 20243 8192000000 10090166080 10504826880 1118161200 12.010 100 41 82 2 1204687168 77390736 9135232
05:40:27 22257 8192000000 10109899568 10527514624 1132242464 12.142 100 32 64 1 1224864656 85554064 7068128
05:41:52 1612 8192000000 10164107600 10561650688 1143157488 12.245 100 67 132 1 1264598992 107058640 14382864
05:43:00 20822 8192000000 10183725248 10606084096 1151616400 12.325 100 39 77 2 1312340880 151374080 9350400
05:44:27 14426 8192000000 10194881072 10617634816 1158792208 12.392 100 41 80 2 1317596048 149329088 9474752
05:46:03 2407 8192000000 10272110352 10669453312 1167167280 12.470 100 82 164 4 1372933280 188164672 17601328
05:47:10 5482 8192000000 10290325328 10694512640 1162108512 12.423 100 76 150 1 1403761520 224323216 17329792
05:48:28 16338 8192000000 10310215472 10725974016 1162500032 12.427 99 57 114 1 1407096288 230913616 13682640
05:49:52 2979 8192000000 10324063120 10722308096 1161831808 12.420 100 71 142 0 1419032096 240589872 16610416
05:51:03 9915 8192000000 10323311504 10728382464 1155142672 12.358 100 63 126 0 1419784320 249342640 15299008
05:52:16 3308 8192000000 10346624256 10743713792 1150654768 12.316 100 81 163 1 1437282208 267291600 19335840
05:53:25 4783 8192000000 10338617056 10735906816 1145317056 12.266 100 79 156 1 1436714080 272860592 18536432
05:54:35 5132 8192000000 10381421760 10799308800 1135436368 12.173 100 69 138 1 1472644240 321324880 15882992
05:55:46 7181 8192000000 10324867632 10742833152 1097805568 11.817 98 39 78 1 1408481296 300479456 10196272
05:56:59 10801 8192000000 10369032416 10783399936 1122495648 12.051 100 39 78 0 1470603808 338721664 9386496
05:58:16 10804 8192000000 10352416176 10770321408 1102874304 11.865 99 36 71 4 1446515536 334501328 9139904
05:59:36 14448 8192000000 10376857600 10843205632 1110384304 11.936 100 32 64 0 1498807040 379906464 8516272
06:00:53 25255 8192000000 10247105728 10710102016 1080250096 11.650 98 25 50 0 1337592480 250456944 6885440
06:02:20 79904 8192000000 10082099184 10572369920 1078238448 11.631 98 8 16 2 1136688080 56616576 1833056
06:04:41 33327 8192000000 10060318496 10473664512 1112438800 11.956 100 46 94 3 1144698528 23415920 8843808
06:06:24 70746 8192000000 10051977760 10463105024 1106878512 11.903 99 33 66 0 1126643232 13151840 6612880
06:08:36 131640 8192000000 10127860304 10555809792 1102493328 11.861 97 31 62 1 1186969616 77842640 6633648
06:11:49 53536 8192000000 10185288512 10657452032 1131215552 12.133 99 38 74 0 1289587472 149254880 9117040
06:13:48 35320 8192000000 10128759088 10607099904 1140813376 12.223 100 13 26 0 1260492144 116409184 3269584
06:15:23 24116 8192000000 10118601520 10557972480 1124375360 12.068 99 12 24 0 1211580016 84299904 2904752
06:16:50 23222 8192000000 10076459040 10565296128 1136319888 12.181 100 11 22 1 1183090528 44374128 2396512
06:18:14 38246 8192000000 10145694976 10599337984 1146940160 12.281 100 15 30 0 1236151040 85727360 3483520
06:20:07 43140 8192000000 10136362912 10628255744 1137204736 12.189 100 12 24 2 1279091168 139009200 2877232
06:22:06 41392 8192000000 10119842512 10586374144 1153939200 12.346 100 21 42 3 1237725600 79316320 4470080
06:24:04 36720 8192000000 10093437280 10518618112 1127335120 12.096 98 32 63 0 1165765120 31632528 6797472
06:25:43 59217 8192000000 10134935120 10586402816 1159138560 12.395 100 30 60 1 1220750048 55370480 6241008
06:27:58 62761 8192000000 10114868768 10589466624 1110157072 11.934 96 8 15 1 1155869280 44453824 1258384
06:30:01 94878 8192000000 10090112880 10559262720 1129691840 12.118 98 19 38 1 1182688736 49084448 3912448
06:32:43 53191 8192000000 10087493520 10563301376 1130039152 12.122 99 11 21 2 1193861808 61332016 2490640
06:34:37 58139 8192000000 10090443088 10533883904 1116268944 11.992 98 14 27 1 1178428624 59469088 2690592
06:36:54 73676 8192000000 10097412640 10565595136 1102206320 11.859 97 13 26 0 1205143920 100152416 2785184
06:39:08 55268 8192000000 10064560368 10512625664 1104224896 11.878 97 13 25 0 1141597056 34989248 2382912
06:41:05 55375 8192000000 10086040816 10543689728 1055029680 11.409 93 19 38 1 1139596832 80013056 4554096
06:43:03 87003 8192000000 10060118560 10486386688 1046159264 11.324 94 21 42 2 1130282416 79667456 4455696
06:45:35 119796 8192000000 10085708864 10533789696 1056055184 11.419 95 14 28 1 1095831984 37076704 2700096
06:48:45 66899 8192000000 10053394432 10528350208 1065365312 11.508 96 6 12 0 1137641568 71128768 1147488
06:50:52 58075 8192000000 10078195888 10552958976 1084230448 11.688 98 13 26 0 1146904512 60231728 2442336
06:52:56 148588 8192000000 10115610848 10578042880 1098478576 11.823 98 18 36 1 1195542800 93205648 3858576
06:56:39 67649 8192000000 10106189760 10639060992 1106865504 11.903 99 6 12 0 1175186672 66975872 1345296
06:59:08 66140 8192000000 10108262304 10541621248 1123017456 12.055 99 26 51 0 1191562224 63021600 5523168
07:01:18 84456 8192000000 10119161760 10562052096 1123126400 12.057 99 22 44 0 1208240832 80357120 4757312
07:03:46 29307 8192000000 10133060800 10592755712 1139969184 12.215 100 23 46 4 1222099280 77419520 4710576
07:05:28 53806 8192000000 10131449888 10562195456 1145273472 12.265 100 26 52 0 1222196704 71851824 5071408
07:07:32 59852 8192000000 10124412736 10588065792 1140124496 12.217 99 24 48 2 1196260976 51575376 4561104
07:09:32 99683 8192000000 10082571712 10535870464 1142428304 12.238 100 12 24 0 1206620608 61310448 2881856
07:12:12 81238 8192000000 10093657248 10551181312 1136899680 12.186 99 9 18 0 1172258736 33335232 2023824
07:14:38 73595 8192000000 10082377392 10536452096 1122235792 12.048 98 23 45 0 1166355296 39311616 4807888
07:16:58 60356 8192000000 10124523456 10556530688 1114337440 11.973 97 26 52 1 1182985152 63546976 5100736
07:19:04 56545 8192000000 10093125472 10532532224 1133744880 12.157 99 20 40 0 1183254736 45384096 4125760
07:21:14 74259 8192000000 10133706288 10585649152 1154680272 12.353 100 17 33 1 1213335456 55051712 3603472
07:23:32 45018 8192000000 10116858592 10560679936 1140706528 12.222 99 34 68 1 1200284896 52844432 6733936
07:25:18 40051 8192000000 10139977536 10626056192 1142737424 12.241 99 19 38 2 1209641600 62746832 4157344
07:26:58 39071 8192000000 10098865904 10532392960 1128175536 12.104 98 32 65 0 1184978656 50879344 5923776
07:28:37 87799 8192000000 10036788768 10484641792 1093378464 11.775 95 30 60 0 1149845712 50043264 6423984
07:31:05 53716 8192000000 10082016480 10544504832 1128012592 12.103 99 5 10 0 1176402208 47283264 1106352
07:33:24 66376 8192000000 10065886000 10542080000 1091647456 11.758 96 11 23 0 1144394384 50596752 2150176

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 24-10-2020 07:37:03 to 24-10-2020 07:37:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 146 v_add_invoice_to_stock 335544517
user_exc 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-10-2020 07:37:18 to 24-10-2020 07:37: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          231716
MON$OLDEST_ACTIVE               233745
MON$OLDEST_SNAPSHOT             233745
MON$NEXT_TRANSACTION            233745
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                       2106368
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             774
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2235 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2235 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2235 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.2235 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Oct 24 07:35:21 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sat Oct 24 07:35:29 2020
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Sat Oct 24 07:35:29 2020
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

24.10.20 07:37: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-24 07:37:24 No severe PSQL-related problems occured
24.10.20 07:37:25. End of report.