Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.10.2020 13:29
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 16692548 kB
MemAvailable 31594276 kB
Buffers 0 kB
Cached 14997464 kB
SwapCached 5440 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-19 13:26:53
DTS_END                         2020-10-19 13:26:53.1700
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/44954
TRN_ID                          242064
ATT_ID                          659
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: 9268.29 21301 1112195
customer order: creation 182.30 427 21876
customer order: refuse 15.32 341 1838
order to supplier: creation 47.38 1421 5686
order to supplier: removal 3.53 105392 423
invoice (draft): creation 46.14 3784 5537
invoice (draft): removal 52.51 891 6301
invoice accept: apply 43.23 17298 5188
invoice accept: cancel 47.28 825 5674
customer reserve: creation 3928.30 173 471396
customer reserve: removal 4606.80 63 552816
realization accept: apply 83.97 174 10076
realization accept: cancel 78.89 112 9467
payment from customer: creation 60.90 113 7308
payment from customer: removal 6.13 98 735
payment to supplier: creation 51.14 112 6137
payment to supplier: removal 3.37 92 404
service: total inventory turnovers 3.83 3461 460
service: total monetary turnovers 6.33 532 759
service: aggregate perf. data 0.91 14288 109
service: refresh index statistics 0.04 276415 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-10-2020 13:29:33 to 19-10-2020 13:29:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4086
TEST_TIME 2 4194
TEST_TIME 3 4558
TEST_TIME 4 4724
TEST_TIME 5 5360
TEST_TIME 6 5411
TEST_TIME 7 5474
TEST_TIME 8 5489
TEST_TIME 9 6007
TEST_TIME 10 6396
TEST_TIME 11 6861
TEST_TIME 12 7174
TEST_TIME 13 7245
TEST_TIME 14 7474
TEST_TIME 15 7619
TEST_TIME 16 7847
TEST_TIME 17 7973
TEST_TIME 18 8026
TEST_TIME 19 8469
TEST_TIME 20 8920
TEST_TIME 21 9055
TEST_TIME 22 9268
TEST_TIME 23 9227
TEST_TIME 24 9606
TEST_TIME 25 10021
TEST_TIME 26 10052
TEST_TIME 27 10234
TEST_TIME 28 10982
TEST_TIME 29 10939
TEST_TIME 30 10888
TEST_TIME 31 10763
TEST_TIME 32 10701
TEST_TIME 33 10634
TEST_TIME 34 10503
TEST_TIME 35 10456
TEST_TIME 36 10648
TEST_TIME 37 10536
TEST_TIME 38 10373
TEST_TIME 39 10121
TEST_TIME 40 10075
TEST_TIME 41 9988
TEST_TIME 42 9844
TEST_TIME 43 9856
TEST_TIME 44 9922
TEST_TIME 45 9805
TEST_TIME 46 9771
TEST_TIME 47 9661
TEST_TIME 48 9562
TEST_TIME 49 9592
TEST_TIME 50 9652
TEST_TIME 51 9810
TEST_TIME 52 9940
TEST_TIME 53 9930
TEST_TIME 54 9887
TEST_TIME 55 9884
TEST_TIME 56 9912
TEST_TIME 57 9870
TEST_TIME 58 9828
TEST_TIME 59 9878
TEST_TIME 60 9865
TEST_TIME 61 9792
TEST_TIME 62 9716
TEST_TIME 63 9684
TEST_TIME 64 9681
TEST_TIME 65 9651
TEST_TIME 66 9543
TEST_TIME 67 9539
TEST_TIME 68 9523
TEST_TIME 69 9488
TEST_TIME 70 9459
TEST_TIME 71 9462
TEST_TIME 72 9434
TEST_TIME 73 9455
TEST_TIME 74 9476
TEST_TIME 75 9520
TEST_TIME 76 9498
TEST_TIME 77 9509
TEST_TIME 78 9575
TEST_TIME 79 9664
TEST_TIME 80 9646
TEST_TIME 81 9731
TEST_TIME 82 9708
TEST_TIME 83 9606
TEST_TIME 84 9523
TEST_TIME 85 9511
TEST_TIME 86 9458
TEST_TIME 87 9399
TEST_TIME 88 9379
TEST_TIME 89 9293
TEST_TIME 90 9288
TEST_TIME 91 9240
TEST_TIME 92 9311
TEST_TIME 93 9290
TEST_TIME 94 9313
TEST_TIME 95 9286
TEST_TIME 96 9246
TEST_TIME 97 9355
TEST_TIME 98 9390
TEST_TIME 99 9385
TEST_TIME 100 9411
TEST_TIME 101 9401
TEST_TIME 102 9361
TEST_TIME 103 9346
TEST_TIME 104 9273
TEST_TIME 105 9273
TEST_TIME 106 9225
TEST_TIME 107 9177
TEST_TIME 108 9137
TEST_TIME 109 9154
TEST_TIME 110 9141
TEST_TIME 111 9181
TEST_TIME 112 9141
TEST_TIME 113 9127
TEST_TIME 114 9191
TEST_TIME 115 9167
TEST_TIME 116 9183
TEST_TIME 117 9242
TEST_TIME 118 9271
TEST_TIME 119 9258
TEST_TIME 120 9270

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-10-2020 13:29:36 to 19-10-2020 13:29:39.

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 1549357 1549357 0 0.00 0 29451 7 0 0
doc_list_biud 1549355 1549355 0 0.00 0 36332 29 0 0
sp_add_invoice_to_stock 5273 5188 85 1.61 34 197854 17298 0 85
sp_cancel_adding_invoice 5674 5674 0 0.00 6 55109 825 0 0
sp_cancel_client_order 1838 1838 0 0.00 5 16099 341 0 0
sp_cancel_customer_reserve 552816 552816 0 0.00 1 17473 64 0 0
sp_cancel_pay_from_customer 735 735 0 0.00 3 7670 99 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 4445 92 0 0
sp_cancel_supplier_invoice 6307 6301 6 0.09 2 24998 892 0 6
sp_cancel_supplier_order 423 423 0 0.00 8 935417 105392 0 0
sp_cancel_write_off 9467 9467 0 0.00 1 7846 113 0 0
sp_client_order 21876 21876 0 0.00 3 22800 427 0 0
sp_customer_reserve 471396 471396 0 0.00 0 98091 174 0 0
sp_fill_shopping_cart 33089 33089 0 0.00 1 22942 37 0 0
sp_fill_shopping_cart_clo_res 471393 471393 0 0.00 0 33292 16 0 0
sp_get_clo_for_invoice 5185 5185 0 0.00 0 37643 343 0 0
sp_kill_cost_storno 16280 16280 0 0.00 0 8541 52 0 0
sp_kill_qstorno_ret_qs2qd 1121823 1121823 0 0.00 0 24985 35 0 0
sp_kill_qty_storno 591780 591780 0 0.00 0 36332 75 0 0
sp_lock_selected_doc 21315 21315 0 0.00 0 7914 5 0 0
sp_make_cost_storno 28706 28706 0 0.00 0 14203 55 0 0
sp_multiply_rows_for_pdistr 28706 28706 0 0.00 0 9575 31 0 0
sp_multiply_rows_for_qdistr 21875 21875 0 0.00 0 12634 233 0 0
sp_pay_from_customer 7308 7308 0 0.00 4 16990 113 0 0
sp_pay_to_supplier 6137 6137 0 0.00 5 17175 112 0 0
sp_payment_common 13445 13445 0 0.00 1 10732 22 0 0
sp_qd_handle_on_cancel_clo 1838 1838 0 0.00 0 16093 318 0 0
sp_qd_handle_on_invoice_upd_sts 10859 10859 0 0.00 0 36332 367 0 0
sp_qd_handle_on_reserve_upd_sts 10076 10076 0 0.00 0 22767 50 0 0
sp_reserve_write_off 10076 10076 0 0.00 6 22778 175 0 0
sp_supplier_invoice 5537 5537 0 0.00 7 125894 3784 0 0
sp_supplier_order 5686 5686 0 0.00 6 26922 1421 0 0
srv_aggregate_perf_data 109 109 0 0.00 2889 130499 14288 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1817 132197 42637 0 0
srv_find_qd_qs_mism 1063282 1063282 0 0.00 0 29449 6 0 0
srv_make_invnt_saldo 460 460 0 0.00 46 83114 3462 0 0
srv_make_money_saldo 759 759 0 0.00 1 13822 532 0 0
srv_recalc_idx_stat 5 5 0 0.00 105343 401346 276415 0 0
t$perf-abend:sp_add_invoice_to_stock 85 85 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5189 5189 0 0.00 0 12114 153 0 0
t$perf-norm:sp_cancel_adding_invoice 445 445 0 0.00 0 3698 97 0 0
t$perf-norm:sp_cancel_client_order 1838 1838 0 0.00 0 2200 9 0 0
t$perf-norm:sp_cancel_customer_reserve 727 727 0 0.00 0 5085 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 735 735 0 0.00 0 2316 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 1162 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 3525 42 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 17545 1396 0 0
t$perf-norm:sp_cancel_write_off 1061 1061 0 0.00 0 8811 20 0 0
t$perf-norm:sp_client_order 21876 21876 0 0.00 0 8189 16 0 0
t$perf-norm:sp_customer_reserve 1110 1110 0 0.00 0 3205 10 0 0
t$perf-norm:sp_pay_from_customer 7308 7308 0 0.00 0 8196 10 0 0
t$perf-norm:sp_pay_to_supplier 6137 6137 0 0.00 0 13194 11 0 0
t$perf-norm:sp_reserve_write_off 10076 10076 0 0.00 0 8827 9 0 0
t$perf-norm:sp_supplier_invoice 5537 5537 0 0.00 0 2877 14 0 0
t$perf-norm:sp_supplier_order 5686 5686 0 0.00 0 6463 14 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 628 31 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 232 8 0 0
t$perf-norm:srv_make_invnt_saldo 460 460 0 0.00 0 558 7 0 0
t$perf-norm:srv_make_money_saldo 759 759 0 0.00 0 5727 13 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 65 15 0 0
v_add_invoice_to_stock 10538 10368 170 1.61 0 3400 14 0 170
v_all_customers 39568 39568 0 0.00 0 67 1 0 0
v_all_suppliers 31292 31292 0 0.00 0 24 1 0 0
v_all_wares 43750 43750 0 0.00 0 43 1 0 0
v_cancel_adding_invoice 890 890 0 0.00 0 2077 10 0 0
v_cancel_client_order 3676 3676 0 0.00 0 5192 5 0 0
v_cancel_customer_prepayment 1470 1470 0 0.00 0 5852 17 0 0
v_cancel_customer_reserve 1454 1454 0 0.00 0 533 3 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 4424 11 0 0
v_cancel_supplier_invoice 856 844 12 1.40 0 813 14 0 12
v_cancel_supplier_order 846 846 0 0.00 0 117 2 0 0
v_cancel_write_off 2122 2122 0 0.00 0 1230 12 0 0
v_max_id_clo_ord 5685 5685 0 0.00 0 916 2 0 0
v_max_id_clo_res 1110 1110 0 0.00 0 1928 16 0 0
v_max_id_ord_sup 5529 5529 0 0.00 0 4803 3 0 0
v_max_non_paid_invoice 1705 1705 0 0.00 0 545 3 0 0
v_max_non_paid_realizn 3965 3965 0 0.00 0 2204 4 0 0
v_min_id_clo_ord 5685 5685 0 0.00 0 1446 5 0 0
v_min_id_clo_res 1110 1110 0 0.00 0 621 2 0 0
v_min_id_ord_sup 5529 5529 0 0.00 0 6070 11 0 0
v_min_non_paid_invoice 6137 6137 0 0.00 0 5885 13 0 0
v_min_non_paid_realizn 7308 7308 0 0.00 0 5729 17 0 0
v_our_firm 10868 10868 0 0.00 0 16 1 0 0
v_reserve_write_off 20152 20152 0 0.00 0 5352 6 0 0
x_lock_depdocs_on_canc_invoice 11553 11553 0 0.00 0 19574 116 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 6862 296 0 0
x_make_qstorno_1000_1200 5686 5686 0 0.00 1 26909 1343 0 0
x_make_qstorno_1200_2000 5537 5537 0 0.00 3 125882 3645 0 0
x_make_qstorno_2100_3300 471396 471396 0 0.00 0 98090 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-10-2020 13:29:40 to 19-10-2020 13:29:44.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
10:56:56 8 8192000000 8796624000 8807108608 3156752 0.038 1 1 2 0 3254112 61504 35856
10:57:57 14468 8192000000 9729065904 10027884544 808747776 8.985 100 89 178 0 837386608 7367488 21271344
10:59:19 15945 8192000000 9761090624 10070142976 839983104 9.300 100 82 164 0 871014688 12038880 18992704
11:00:42 14939 8192000000 9809569184 10129682432 880930096 9.709 100 82 164 1 918122640 18564784 18627760
11:01:59 13164 8192000000 9880683856 10213146624 922729376 10.123 100 84 168 0 981910320 41195808 17985136
11:03:20 10514 8192000000 9922276256 10271457280 958738240 10.477 100 80 160 1 1036943584 60869488 17335856
11:04:32 5931 8192000000 9962570592 10317361152 990868592 10.790 100 82 164 0 1077599168 69790656 16939920
11:05:40 3902 8192000000 9978953792 10340773888 1021167968 11.083 100 84 167 3 1097924896 60017296 16739632
11:06:45 2297 8192000000 10021138416 10388824064 1046050512 11.323 100 94 187 1 1130639408 65317888 19271008
11:07:49 9954 8192000000 10043589808 10414432256 1062308464 11.479 100 80 161 2 1149104224 70206608 16589152
11:08:59 4647 8192000000 9982944864 10374189056 1070652560 11.558 100 48 91 2 1096830256 16576784 9600912
11:10:08 4562 8192000000 9989539168 10382381056 1077513872 11.624 100 41 82 2 1109418032 23049984 8854176
11:11:15 3751 8192000000 10001888352 10396676096 1085905056 11.704 100 45 87 1 1120090336 25506432 8678848
11:12:19 50111 8192000000 10000279024 10407972864 1085168336 11.697 100 37 74 5 1113464224 20634432 7661456
11:14:10 7290 8192000000 10016259424 10415992832 1089423056 11.737 100 32 64 2 1116869712 19692800 7753856
11:15:17 9741 8192000000 9997547584 10412912640 1077708784 11.626 99 16 31 3 1103626640 21916640 4001216
11:16:29 6011 8192000000 9995067424 10402500608 1081328112 11.660 100 32 65 1 1107994304 19400144 7266048
11:17:39 65724 8192000000 9962124480 10363101184 968891968 10.576 90 28 56 0 994343952 19216208 6235776
11:19:45 8577 8192000000 9944632496 10320936960 944381216 10.336 91 37 74 1 977025632 24712048 7932368
11:20:54 6731 8192000000 9952724016 10323714048 1026958624 11.139 100 34 65 5 1048489312 13436464 8094224
11:22:04 6998 8192000000 9960047664 10344558592 1038214176 11.247 100 39 78 0 1071005424 23938336 8852912
11:23:12 44065 8192000000 9962886416 10355757056 1041368448 11.278 99 29 58 0 1058863600 11198960 6296192
11:24:58 10194 8192000000 10000331664 10393718784 1065145680 11.506 100 46 92 2 1097458432 21647328 10665424
11:26:08 10273 8192000000 10004906688 10412879872 1083442336 11.680 100 27 54 0 1110281728 21033392 5806000
11:27:24 9913 8192000000 10029084144 10416029696 1100034576 11.838 100 52 104 0 1126546640 15614368 10897696
11:28:38 40938 8192000000 10041214128 10459787264 1109548432 11.928 100 27 54 1 1170050080 54704544 5797104
11:30:53 4557 8192000000 10100818928 10494676992 1131087952 12.132 100 66 129 2 1208273744 63046512 14139280
11:32:00 16484 8192000000 10142773680 10553995264 1146816576 12.280 100 47 92 3 1244541088 87470000 10254512
11:33:24 6749 8192000000 10170491056 10585067520 1153899600 12.346 100 59 116 1 1281516368 114507456 13109312
11:34:43 4250 8192000000 10205776560 10613788672 1159740608 12.401 100 57 113 0 1304690224 132209872 12739744
11:35:58 4519 8192000000 10210948800 10632806400 1168902896 12.487 100 63 119 1 1313995664 131118480 13974288
11:37:10 6439 8192000000 10212901200 10639884288 1160431968 12.407 99 49 98 0 1312384592 140637552 11315072
11:38:21 3887 8192000000 10212348528 10616229888 1140398208 12.219 99 64 126 1 1301040000 147498208 13143584
11:39:31 11537 8192000000 10260287552 10665844736 1160266704 12.406 100 66 132 2 1351545392 176185664 15093024
11:40:48 3033 8192000000 10265994048 10672107520 1145208976 12.265 100 88 174 1 1360161920 195361616 19591328
11:41:57 6476 8192000000 10266924320 10667020288 1128745824 12.110 99 67 134 1 1353509952 209626128 15138000
11:43:09 1816 8192000000 10281725744 10676350976 1132584944 12.146 100 76 149 2 1378024000 228520096 16918960
11:44:17 2454 8192000000 10298403424 10695929856 1144204768 12.255 100 74 148 2 1395475008 234137648 17132592
11:45:27 17741 8192000000 10298988112 10708168704 1127820768 12.101 99 45 89 0 1395828304 256784480 11223056
11:46:49 8800 8192000000 10301677456 10723794944 1150488528 12.314 100 45 91 0 1397367792 235479648 11399616
11:48:03 11992 8192000000 10315404240 10760527872 1145780672 12.270 100 33 64 1 1440918160 286584032 8553456
11:49:22 14572 8192000000 10325254688 10739875840 1122529504 12.051 98 40 80 3 1411216864 278477744 10209616
11:50:39 20545 8192000000 10312726832 10788847616 1137895136 12.196 100 28 56 0 1452157200 306815648 7446416
11:52:09 22753 8192000000 10201276800 10663600128 1111658608 11.948 99 34 68 2 1303529776 183529088 8342080
11:54:00 25663 8192000000 10148584688 10651541504 1121202368 12.038 100 15 30 0 1263790256 138299984 4287904
11:55:40 69885 8192000000 10068968608 10484817920 1122231152 12.048 99 31 59 2 1167747904 38913232 6603520
11:57:51 47888 8192000000 10068486320 10488942592 1115331792 11.983 98 43 84 1 1145957984 21677968 8948224
11:59:43 58082 8192000000 10074652880 10493816832 1092067504 11.762 96 21 42 2 1127759824 31482880 4209440
12:01:44 65477 8192000000 10092546416 10581598208 1122625760 12.052 99 10 20 1 1187148272 62594560 1927952
12:03:51 63838 8192000000 10106747344 10550415360 1137650544 12.193 100 31 62 1 1187008544 42640048 6717952
12:06:48 23813 8192000000 10091537152 10573205504 1140242560 12.218 100 25 50 1 1180386176 35088864 5054752
12:08:19 27602 8192000000 10149459056 10586124288 1136914208 12.186 99 51 102 0 1223923456 76157648 10851600
12:10:05 37959 8192000000 10109476704 10603200512 1143679712 12.250 100 25 51 1 1242031840 92401680 5950448
12:11:52 57624 8192000000 10148906816 10628825088 1137655264 12.193 99 23 46 2 1265378032 122306464 5416304
12:13:57 77500 8192000000 10186490368 10625429504 1121386320 12.040 98 19 38 0 1257912224 131929696 4596208
12:16:40 97156 8192000000 10074386928 10536923136 1116600672 11.995 98 26 52 0 1244107968 121392416 6114880
12:19:37 35224 8192000000 10092529184 10526183424 1137508208 12.192 100 38 76 1 1183576544 38313312 7755024
12:21:17 38851 8192000000 10102214080 10547187712 1130997232 12.131 99 34 68 0 1203406176 65627360 6781584
12:23:01 51886 8192000000 10063531472 10477502464 1088239232 11.726 96 24 48 1 1119991616 26271232 5481152
12:25:03 52096 8192000000 10062492464 10488971264 1019749568 11.070 91 26 52 1 1069648224 44606976 5291680
12:27:34 132196 8192000000 10112599584 10558619648 1096292256 11.802 98 26 52 2 1201214784 99365392 5557136
12:31:08 71018 8192000000 10049284688 10511437824 1109059296 11.924 99 18 36 0 1171236448 58306784 3870368
12:33:41 65505 8192000000 10063143072 10537750528 1116583936 11.995 100 9 18 0 1171370272 53141536 1644800
12:35:46 44768 8192000000 10098941504 10537000960 1113714624 11.968 99 23 46 3 1170738736 52022176 5001936
12:37:31 34451 8192000000 10079054032 10514403328 1099266848 11.831 98 24 47 2 1166065584 62093696 4705040
12:39:06 45064 8192000000 10115528144 10557476864 1113012256 11.961 100 33 67 1 1214292576 93960432 7319888
12:40:52 44242 8192000000 10118665280 10553360384 1090228464 11.745 98 45 90 3 1170522288 71325952 8967872
12:42:40 56722 8192000000 10145230400 10601168896 1096138800 11.801 98 20 40 0 1210696368 110396000 4161568
12:44:51 49209 8192000000 10097127424 10566144000 1117995408 12.008 100 13 25 1 1212552704 91739104 2818192
12:46:56 74807 8192000000 10074887936 10532163584 1070488272 11.557 95 19 38 0 1132263680 57917408 3858000
12:49:13 77889 8192000000 10051933808 10490208256 1123724352 12.062 100 30 60 1 1156747712 25594848 7428512
12:51:32 20682 8192000000 10044381600 10506088448 1118631248 12.014 99 6 10 1 1142964848 23458800 874800
12:52:53 50724 8192000000 10075098816 10538737664 1139560032 12.211 100 12 24 1 1170232144 28058672 2613440
12:54:47 50203 8192000000 10108619200 10577563648 1136771168 12.185 99 12 24 2 1199112944 60104624 2237152
12:57:10 73357 8192000000 10124174288 10588725248 1130386032 12.125 99 16 32 0 1199778064 65729120 3662912
12:59:24 42713 8192000000 10098157712 10544832512 1133855808 12.158 99 31 60 3 1181563472 41246192 6461472
13:01:09 30600 8192000000 10119602832 10562056192 1130556096 12.127 99 18 36 2 1203426128 69065040 3804992
13:02:41 53779 8192000000 10121764272 10651299840 1134212560 12.161 98 22 44 1 1231766400 93181136 4372704
13:04:50 63450 8192000000 10098027776 10567495680 1146701184 12.279 100 19 38 2 1186162960 36039152 3422624
13:07:04 67573 8192000000 10079175536 10513526784 1103005728 11.866 97 16 31 0 1137083280 31251952 2825600
13:09:12 66790 8192000000 10119902144 10578264064 1139051440 12.207 100 41 82 0 1215738080 68247728 8438912
13:11:52 59342 8192000000 10048420352 10522533888 1091958816 11.761 96 8 16 2 1127724496 33661680 2104000
13:13:51 67568 8192000000 10108064144 10568032256 1116199040 11.991 98 13 26 1 1178377344 59275136 2903168
13:15:59 75172 8192000000 10098693600 10541645824 1110056080 11.933 98 20 40 0 1206149120 91861936 4231104
13:18:17 63339 8192000000 10105344688 10534490112 1043782608 11.301 92 19 38 0 1094482736 46798768 3901360
13:20:21 68170 8192000000 10114393312 10548682752 1094197216 11.783 98 30 58 3 1158258368 58078944 5982208
13:22:42 55037 8192000000 10110100560 10563960832 1062586352 11.481 95 14 28 1 1148320336 82712400 3021584
13:24:45 74313 8192000000 10091284640 10535038976 1060218192 11.459 95 10 20 0 1119450112 56980704 2251216

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-10-2020 13:29:46 to 19-10-2020 13:30:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 85 v_add_invoice_to_stock 335544517
user_exc 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-10-2020 13:30:02 to 19-10-2020 13:30:04.

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          241847
MON$OLDEST_ACTIVE               242576
MON$OLDEST_SNAPSHOT             242576
MON$NEXT_TRANSACTION            242576
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                       2126592
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             801
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2228 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2228 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2228 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.2228 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 ---
:32: 
:33: mscs-test-161.budzdorov.ru    Mon Oct 19 13:27:01 2020
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Mon Oct 19 13:27:02 2020
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Mon Oct 19 13:27:12 2020
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Mon Oct 19 13:27:51 2020
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Mon Oct 19 13:27:53 2020
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:51: 
:52: 
:53: mscs-test-161.budzdorov.ru    Mon Oct 19 13:27:53 2020
:54:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:55: 
--- end of diff output ---

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

19.10.20 13:30:05. 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-19 13:30:07 No severe PSQL-related problems occured
19.10.20 13:30:08. End of report.