Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.10.2020 10:27
Common Performance Final Results
  1. Hardware and OS info
  2. DB and test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database Statistics, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16789996 kB
MemAvailable 31481040 kB
Buffers 0 kB
Cached 14855448 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-30 10:27:08
DTS_END                         2020-10-30 10:26:49.7080
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/41112
TRN_ID                          233711
ATT_ID                          242
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: 9139.19 21779 1096703
customer order: creation 170.61 444 20473
customer order: refuse 14.23 300 1707
order to supplier: creation 43.63 1417 5236
order to supplier: removal 3.42 111364 410
invoice (draft): creation 44.91 3480 5389
invoice (draft): removal 52.54 961 6305
invoice accept: apply 40.90 18480 4908
invoice accept: cancel 47.18 863 5661
customer reserve: creation 3785.45 182 454254
customer reserve: removal 4646.86 64 557623
realization accept: apply 83.48 168 10018
realization accept: cancel 82.17 120 9860
payment from customer: creation 57.53 100 6903
payment from customer: removal 5.55 87 666
payment to supplier: creation 46.63 130 5596
payment to supplier: removal 3.06 120 367
service: total inventory turnovers 4.00 2857 480
service: total monetary turnovers 6.28 479 753
service: aggregate perf. data 0.75 17736 90
service: refresh index statistics 0.03 276226 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-10-2020 10:27:42 to 30-10-2020 10:27:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2405
TEST_TIME 2 2683
TEST_TIME 3 4164
TEST_TIME 4 4903
TEST_TIME 5 5355
TEST_TIME 6 5411
TEST_TIME 7 5369
TEST_TIME 8 5927
TEST_TIME 9 6098
TEST_TIME 10 6036
TEST_TIME 11 6634
TEST_TIME 12 6546
TEST_TIME 13 6700
TEST_TIME 14 6684
TEST_TIME 15 7270
TEST_TIME 16 7366
TEST_TIME 17 7388
TEST_TIME 18 7584
TEST_TIME 19 7759
TEST_TIME 20 8105
TEST_TIME 21 8172
TEST_TIME 22 8041
TEST_TIME 23 9042
TEST_TIME 24 9976
TEST_TIME 25 11025
TEST_TIME 26 11412
TEST_TIME 27 11262
TEST_TIME 28 11174
TEST_TIME 29 11142
TEST_TIME 30 11033
TEST_TIME 31 10739
TEST_TIME 32 10517
TEST_TIME 33 10440
TEST_TIME 34 10397
TEST_TIME 35 10445
TEST_TIME 36 10472
TEST_TIME 37 10281
TEST_TIME 38 10132
TEST_TIME 39 10024
TEST_TIME 40 9940
TEST_TIME 41 10171
TEST_TIME 42 10188
TEST_TIME 43 10133
TEST_TIME 44 10118
TEST_TIME 45 10032
TEST_TIME 46 10170
TEST_TIME 47 10234
TEST_TIME 48 10127
TEST_TIME 49 10200
TEST_TIME 50 10124
TEST_TIME 51 10043
TEST_TIME 52 10181
TEST_TIME 53 10038
TEST_TIME 54 10019
TEST_TIME 55 9955
TEST_TIME 56 9878
TEST_TIME 57 9744
TEST_TIME 58 9612
TEST_TIME 59 9604
TEST_TIME 60 9647
TEST_TIME 61 9561
TEST_TIME 62 9558
TEST_TIME 63 9646
TEST_TIME 64 9604
TEST_TIME 65 9647
TEST_TIME 66 9676
TEST_TIME 67 9696
TEST_TIME 68 9721
TEST_TIME 69 9720
TEST_TIME 70 9753
TEST_TIME 71 9747
TEST_TIME 72 9703
TEST_TIME 73 9677
TEST_TIME 74 9569
TEST_TIME 75 9540
TEST_TIME 76 9473
TEST_TIME 77 9388
TEST_TIME 78 9356
TEST_TIME 79 9304
TEST_TIME 80 9337
TEST_TIME 81 9423
TEST_TIME 82 9386
TEST_TIME 83 9412
TEST_TIME 84 9365
TEST_TIME 85 9381
TEST_TIME 86 9459
TEST_TIME 87 9426
TEST_TIME 88 9570
TEST_TIME 89 9553
TEST_TIME 90 9486
TEST_TIME 91 9390
TEST_TIME 92 9385
TEST_TIME 93 9316
TEST_TIME 94 9269
TEST_TIME 95 9219
TEST_TIME 96 9233
TEST_TIME 97 9224
TEST_TIME 98 9179
TEST_TIME 99 9164
TEST_TIME 100 9149
TEST_TIME 101 9180
TEST_TIME 102 9186
TEST_TIME 103 9254
TEST_TIME 104 9272
TEST_TIME 105 9279
TEST_TIME 106 9254
TEST_TIME 107 9282
TEST_TIME 108 9205
TEST_TIME 109 9158
TEST_TIME 110 9122
TEST_TIME 111 9080
TEST_TIME 112 9069
TEST_TIME 113 9031
TEST_TIME 114 9015
TEST_TIME 115 8983
TEST_TIME 116 8990
TEST_TIME 117 9071
TEST_TIME 118 9088
TEST_TIME 119 9091
TEST_TIME 120 9143

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-10-2020 10:27:46 to 30-10-2020 10:27:49.

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 1516690 1516690 0 0.00 0 31657 7 0 0
doc_list_biud 1516687 1516687 0 0.00 0 55881 30 0 0
sp_add_invoice_to_stock 5044 4908 136 2.69 36 209365 18481 0 136
sp_cancel_adding_invoice 5661 5661 0 0.00 7 68188 863 0 0
sp_cancel_client_order 1707 1707 0 0.00 4 7420 301 0 0
sp_cancel_customer_reserve 557623 557623 0 0.00 1 26203 64 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 3 4303 87 0 0
sp_cancel_pay_to_supplier 367 367 0 0.00 3 15911 121 0 0
sp_cancel_supplier_invoice 6315 6305 10 0.15 3 55886 961 0 10
sp_cancel_supplier_order 410 410 0 0.00 19 1143391 111365 0 0
sp_cancel_write_off 9860 9860 0 0.00 1 10014 121 0 0
sp_client_order 20473 20473 0 0.00 4 33154 445 0 0
sp_customer_reserve 454254 454254 0 0.00 0 131861 182 0 0
sp_fill_shopping_cart 31093 31093 0 0.00 1 19935 47 0 0
sp_fill_shopping_cart_clo_res 454249 454249 0 0.00 0 22798 19 0 0
sp_get_clo_for_invoice 4903 4903 0 0.00 0 39479 437 0 0
sp_kill_cost_storno 16554 16554 0 0.00 0 8656 54 0 0
sp_kill_qstorno_ret_qs2qd 1131821 1131821 0 0.00 0 55879 35 0 0
sp_kill_qty_storno 596482 596482 0 0.00 0 55880 75 0 0
sp_lock_selected_doc 20492 20492 0 0.00 0 9908 5 0 0
sp_make_cost_storno 27417 27417 0 0.00 0 8799 48 0 0
sp_multiply_rows_for_pdistr 27415 27415 0 0.00 0 7599 29 0 0
sp_multiply_rows_for_qdistr 20473 20473 0 0.00 0 33146 260 0 0
sp_pay_from_customer 6903 6903 0 0.00 4 10192 101 0 0
sp_pay_to_supplier 5596 5596 0 0.00 5 10899 131 0 0
sp_payment_common 12499 12499 0 0.00 1 7140 22 0 0
sp_qd_handle_on_cancel_clo 1707 1707 0 0.00 0 7412 279 0 0
sp_qd_handle_on_invoice_upd_sts 10559 10559 0 0.00 0 32968 346 0 0
sp_qd_handle_on_reserve_upd_sts 10018 10018 0 0.00 0 21486 52 0 0
sp_reserve_write_off 10018 10018 0 0.00 7 21527 169 0 0
sp_supplier_invoice 5389 5389 0 0.00 7 86461 3481 0 0
sp_supplier_order 5236 5236 0 0.00 5 20748 1417 0 0
srv_aggregate_perf_data 91 90 1 1.09 3006 229208 17736 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1187 187486 47000 0 0
srv_find_qd_qs_mism 1049822 1049822 0 0.00 0 31643 6 0 0
srv_make_invnt_saldo 480 480 0 0.00 37 108287 2857 0 0
srv_make_money_saldo 753 753 0 0.00 1 14054 479 0 0
srv_recalc_idx_stat 4 4 0 0.00 151150 466261 276227 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4908 4908 0 0.00 0 18448 190 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 1 1988 62 0 0
t$perf-norm:sp_cancel_client_order 1707 1707 0 0.00 0 3418 9 0 0
t$perf-norm:sp_cancel_customer_reserve 679 679 0 0.00 0 2037 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 1209 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 367 367 0 0.00 0 5449 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 1060 14 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 17014 1500 0 0
t$perf-norm:sp_cancel_write_off 959 959 0 0.00 0 7480 22 0 0
t$perf-norm:sp_client_order 20473 20473 0 0.00 0 8808 21 0 0
t$perf-norm:sp_customer_reserve 1010 1010 0 0.00 0 5341 10 0 0
t$perf-norm:sp_pay_from_customer 6903 6903 0 0.00 0 7920 10 0 0
t$perf-norm:sp_pay_to_supplier 5596 5596 0 0.00 0 8082 9 0 0
t$perf-norm:sp_reserve_write_off 10018 10018 0 0.00 0 11222 13 0 0
t$perf-norm:sp_supplier_invoice 5389 5389 0 0.00 0 6356 19 0 0
t$perf-norm:sp_supplier_order 5236 5236 0 0.00 0 7259 17 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 430 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 4 0 0 0
t$perf-norm:srv_make_invnt_saldo 480 480 0 0.00 0 2674 18 0 0
t$perf-norm:srv_make_money_saldo 753 753 0 0.00 0 1683 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10064 9792 272 2.70 0 15145 18 0 272
v_all_customers 36508 36508 0 0.00 0 83 1 0 0
v_all_suppliers 29230 29230 0 0.00 0 107 1 0 0
v_all_wares 40946 40946 0 0.00 0 109 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 109 5 0 0
v_cancel_client_order 3414 3414 0 0.00 0 1939 4 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 4229 8 0 0
v_cancel_customer_reserve 1358 1358 0 0.00 0 2118 6 0 0
v_cancel_payment_to_supplier 734 734 0 0.00 0 15895 28 0 0
v_cancel_supplier_invoice 782 762 20 2.55 0 287 10 0 20
v_cancel_supplier_order 820 820 0 0.00 0 2798 6 0 0
v_cancel_write_off 1918 1918 0 0.00 0 2069 12 0 0
v_max_id_clo_ord 5232 5232 0 0.00 0 4967 2 0 0
v_max_id_clo_res 1010 1010 0 0.00 0 1240 11 0 0
v_max_id_ord_sup 5388 5388 0 0.00 0 7277 9 0 0
v_max_non_paid_invoice 1601 1601 0 0.00 0 3542 4 0 0
v_max_non_paid_realizn 3975 3975 0 0.00 0 1259 2 0 0
v_min_id_clo_ord 5232 5232 0 0.00 0 5339 7 0 0
v_min_id_clo_res 1010 1010 0 0.00 0 5776 34 0 0
v_min_id_ord_sup 5388 5388 0 0.00 0 4670 8 0 0
v_min_non_paid_invoice 5596 5596 0 0.00 0 6446 16 0 0
v_min_non_paid_realizn 6903 6903 0 0.00 0 5032 13 0 0
v_our_firm 10294 10294 0 0.00 0 20 1 0 0
v_reserve_write_off 20036 20036 0 0.00 0 5437 6 0 0
x_lock_depdocs_on_canc_invoice 11585 11585 0 0.00 0 18051 119 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 17908 400 0 0
x_make_qstorno_1000_1200 5236 5236 0 0.00 2 20731 1343 0 0
x_make_qstorno_1200_2000 5389 5389 0 0.00 3 86184 3285 0 0
x_make_qstorno_2100_3300 454254 454254 0 0.00 0 131860 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-10-2020 10:27:50 to 30-10-2020 10:27:54.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
07:56:53 12 8192000000 8806848352 8821071872 9882912 0.120 5 2 2 0 9989840 71072 35856
07:57:55 19596 8192000000 9715785504 10008231936 792410656 8.819 100 90 180 0 821834544 7690176 21733712
07:59:27 23040 8192000000 9759627808 10066321408 834472688 9.244 100 86 172 0 867745136 12602384 20670064
08:00:56 15003 8192000000 9802045632 10123251712 870382464 9.604 100 77 154 1 911904400 23972064 17549872
08:02:17 14351 8192000000 9854712048 10189193216 921521584 10.111 100 74 148 2 971321248 33599408 16200256
08:03:39 10202 8192000000 9939282288 10282729472 965440272 10.542 100 81 162 0 1041746224 58837296 17468656
08:04:51 5024 8192000000 9958583920 10317361152 1003287600 10.910 100 80 160 0 1070897088 51316256 16293232
08:05:58 2903 8192000000 10002616672 10367139840 1026245152 11.132 100 81 160 1 1117084576 74050832 16788592
08:07:02 3063 8192000000 10033827392 10401644544 1054915312 11.408 100 92 184 4 1140248672 66597584 18735776
08:08:08 5281 8192000000 10039839776 10412003328 1066393168 11.518 100 92 185 2 1154815104 69031152 19390784
08:09:14 14082 8192000000 9990839776 10391973888 1068969248 11.542 100 43 87 2 1118855776 40965824 8920704
08:10:32 4599 8192000000 10002814864 10386812928 1078487040 11.633 100 46 93 3 1114133472 26040000 9606432
08:11:38 7895 8192000000 9993342208 10390806528 1085868992 11.703 100 43 87 2 1107025088 12186672 8969424
08:12:48 7143 8192000000 10002476320 10398994432 1096885680 11.808 100 36 76 1 1116116304 10020880 9209744
08:13:58 4000 8192000000 10024664832 10410123264 1095731296 11.797 100 68 138 0 1121060288 10802656 14526336
08:15:02 5248 8192000000 10030205424 10432024576 1095963152 11.799 100 37 74 2 1130999552 26879072 8157328
08:16:08 7269 8192000000 10030655040 10441461760 1094251248 11.783 100 33 67 1 1138410464 37193536 6965680
08:17:17 41566 8192000000 9967669648 10356600832 1019600384 11.068 94 27 54 2 1035314656 9609184 6105088
08:19:09 6930 8192000000 9962607264 10352967680 1000437328 10.883 94 34 66 1 1028731680 21995840 6298512
08:20:17 4505 8192000000 9924855392 10310311936 1009584896 10.971 98 41 81 1 1027814960 8755232 9474832
08:21:22 5404 8192000000 9939020304 10328547328 1015994288 11.033 99 47 91 2 1054380672 28403104 9983280
08:22:28 52615 8192000000 9918418864 10309996544 975866016 10.644 95 27 53 2 997501136 16124080 5511040
08:24:23 4092 8192000000 9957474384 10341785600 1042641632 11.290 100 38 76 1 1071831440 20800352 8389456
08:25:29 6656 8192000000 9970986720 10365984768 1051204736 11.372 100 40 81 2 1084449888 23282080 9963072
08:26:36 12313 8192000000 9988092000 10391543808 1074485488 11.595 100 19 33 3 1096331792 17989888 3856416
08:28:10 76742 8192000000 10050401392 10451222528 1096308656 11.803 100 44 86 2 1180848544 75243680 9296208
08:30:31 13052 8192000000 10110934592 10511556608 1115814096 11.987 100 58 116 1 1219708368 92062592 11831680
08:31:52 3430 8192000000 10108365088 10498256896 1125186512 12.076 100 77 152 0 1206549648 65890384 15472752
08:33:05 25046 8192000000 10170164704 10571300864 1143482048 12.248 100 54 108 2 1279782080 124496176 11803856
08:34:36 6821 8192000000 10185618592 10591784960 1151422320 12.323 100 61 121 1 1289929152 125708128 12798704
08:35:47 3504 8192000000 10214129840 10626314240 1155745232 12.363 100 62 124 1 1330840368 161388288 13706848
08:36:54 6565 8192000000 10274327952 10682613760 1158892560 12.393 100 61 122 0 1371629568 199114400 13622608
08:38:04 11844 8192000000 10265782976 10670620672 1158067504 12.385 100 68 136 0 1365944192 192061616 15815072
08:39:22 6851 8192000000 10264470592 10685874176 1164209952 12.443 100 71 142 0 1372334448 193122240 15002256
08:40:36 6194 8192000000 10327016224 10735210496 1167260464 12.471 100 65 129 0 1430612912 249073200 14279248
08:41:48 1185 8192000000 10361598192 10760622080 1175867408 12.552 100 88 174 1 1462873360 267538368 19467584
08:42:54 1255 8192000000 10383595728 10782224384 1160888208 12.412 100 86 172 0 1479208432 298637728 19682496
08:44:02 3208 8192000000 10409579776 10805796864 1147728736 12.288 100 83 164 1 1499737568 333132144 18876688
08:45:07 7034 8192000000 10390787856 10797428736 1128790720 12.110 99 53 102 2 1483050768 341175840 13084208
08:46:21 16892 8192000000 10446428656 10863243264 1129201376 12.114 100 36 72 2 1543441056 404611104 9628576
08:47:45 21425 8192000000 10458939616 10876993536 1099066800 11.829 98 37 74 1 1535937248 426605184 10265264
08:49:10 9464 8192000000 10529280112 10967109632 1114719136 11.977 100 42 82 1 1635652384 508922512 12010736
08:50:22 18307 8192000000 10295042528 10755784704 1099112752 11.829 100 20 40 0 1426280800 321314416 5853632
08:51:41 24046 8192000000 10106883632 10597109760 1103194272 11.868 99 20 40 3 1225722592 118171728 4356592
08:53:05 35349 8192000000 10046909280 10456064000 1081343024 11.660 97 38 76 1 1115372736 25510816 8518896
08:54:47 47639 8192000000 10092627712 10594304000 1110198736 11.934 100 9 18 0 1174194736 62234112 1761888
08:56:48 187486 8192000000 10079850704 10501087232 1054967472 11.408 94 30 59 2 1108304928 47250288 6087168
09:00:59 25896 8192000000 10106522176 10569646080 1112973408 11.961 99 30 60 1 1218702944 99449072 6280464
09:02:31 102106 8192000000 10096698112 10522976256 1084633664 11.692 96 16 32 0 1149807024 61945520 3227840
09:05:20 76391 8192000000 10155895760 10599231488 1138944208 12.206 100 21 42 1 1240024992 96447984 4632800
09:08:02 43418 8192000000 10086250256 10533257216 1131924208 12.139 100 25 50 0 1212154752 74481024 5749520
09:09:47 34907 8192000000 10084379664 10527940608 1130307424 12.124 100 28 56 0 1181652896 45767792 5577680
09:11:26 18738 8192000000 10130450208 10547752960 1136322960 12.181 100 39 78 1 1200636848 56072320 8241568
09:12:50 81179 8192000000 10086686752 10573852672 1132513520 12.145 100 20 41 0 1236513200 99368624 4631056
09:15:12 91654 8192000000 10051164976 10489221120 1089974864 11.742 96 11 23 0 1123403376 30753216 2675296
09:17:46 115302 8192000000 10082793344 10519724032 1095756592 11.797 96 17 34 0 1133691488 34294096 3640800
09:20:44 78205 8192000000 10064608656 10559963136 1133912368 12.158 100 18 36 0 1196088048 58388192 3787488
09:23:03 32559 8192000000 10058148704 10551250944 1125324384 12.077 99 6 12 0 1160957104 34320704 1312016
09:24:36 83167 8192000000 10085235072 10546491392 1123510192 12.060 99 12 24 1 1208779552 82454064 2815296
09:27:08 50882 8192000000 10098918528 10535628800 1099455936 11.832 97 15 31 0 1164166768 61794240 2916592
09:28:59 40511 8192000000 10080062560 10517626880 1089792448 11.741 97 16 33 0 1155361456 61883584 3685424
09:30:40 44194 8192000000 10076740112 10546524160 1102128320 11.858 98 40 80 3 1173318960 62564112 8626528
09:32:26 41216 8192000000 10078878752 10544914432 1093088336 11.772 98 21 42 1 1180833376 83239536 4505504
09:34:32 58908 8192000000 10106581120 10558595072 1116364160 11.993 100 27 53 2 1162055456 40209536 5481760
09:36:47 47993 8192000000 10094364752 10507083776 1092042272 11.762 97 33 66 1 1150760656 52229264 6489120
09:38:38 12990 8192000000 10087349632 10501685248 1106880176 11.903 100 46 92 0 1171595984 55737728 8978080
09:39:54 111763 8192000000 10037663888 10472509440 1079966592 11.647 97 25 50 0 1127583120 41865744 5750784
09:42:46 84871 8192000000 10084403440 10535206912 1104022448 11.876 99 10 20 2 1154904000 48647664 2233888
09:45:20 56024 8192000000 10114300128 10546057216 1105542368 11.890 99 24 48 2 1191327376 80858144 4926864
09:47:16 70753 8192000000 10045766464 10497630208 1077353632 11.622 97 25 51 0 1157449808 74451504 5644672
09:49:27 70406 8192000000 10075869760 10505203712 1109090064 11.924 99 18 36 1 1159292896 46809136 3393696
09:51:47 54633 8192000000 10082388080 10587987968 1117951008 12.008 100 15 30 0 1202945680 81890544 3104128
09:53:42 54165 8192000000 10134423568 10609332224 1110119904 11.934 98 44 84 2 1187212752 68674800 8418048
09:55:40 34421 8192000000 10076300192 10503053312 1139230576 12.208 100 20 40 0 1188453952 45367216 3856160
09:57:15 116242 8192000000 10057776352 10517991424 1142153984 12.236 100 19 38 0 1177178272 31266784 3757504
10:00:11 46089 8192000000 10050583760 10505609216 1128399664 12.106 99 8 16 0 1150818720 20839232 1579824
10:01:57 79814 8192000000 10081794112 10547720192 1109199440 11.925 97 10 20 0 1156104912 44818352 2087120
10:04:57 27976 8192000000 10119749008 10551853056 1144933824 12.262 100 27 54 5 1204328544 53277392 6117328
10:06:25 68477 8192000000 10170962912 10626715648 1127775216 12.100 98 23 46 1 1235065200 101837696 5452288
10:08:48 66375 8192000000 10105419824 10593726464 1110953088 11.941 98 15 30 0 1182339872 68025872 3360912
10:10:55 44550 8192000000 10069723328 10533740544 1123286384 12.058 99 17 34 2 1184354240 57590624 3477232
10:12:44 16935 8192000000 10137519968 10557329408 1141874336 12.233 100 48 96 0 1215495936 63595664 10025936
10:14:05 64421 8192000000 10073502864 10541670400 1134814560 12.167 100 13 26 0 1181107440 43899312 2393568
10:16:21 52964 8192000000 10067455024 10524499968 1135601040 12.174 100 8 12 3 1162060032 24570576 1888416
10:18:14 56804 8192000000 10069125888 10522681344 1079594448 11.644 95 5 10 0 1124703728 44059120 1050160
10:20:18 41642 8192000000 10099823712 10544566272 1104741792 11.883 98 26 52 5 1178564416 68375904 5446720
10:22:00 79295 8192000000 10159482320 10655006720 1108940896 11.922 98 28 57 2 1216900720 101479440 6480384
10:24:41 48751 8192000000 10118656816 10559971328 1104163888 11.877 98 17 34 1 1179435328 71594288 3677152

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-10-2020 10:27:56 to 30-10-2020 10:28:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 1 srv_aggregate_perf_data 335544517
user_exc 136 v_add_invoice_to_stock 335544517
user_exc 10 v_cancel_supplier_invoice 335544517

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

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          242230
MON$OLDEST_ACTIVE               242231
MON$OLDEST_SNAPSHOT             242231
MON$NEXT_TRANSACTION            242231
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                       2106560
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             782
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2242 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2242 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2242 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2242 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

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

Database validation

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

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

--- start of diff output ---
--- end of diff output ---

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

30.10.20 10:28:15. 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-30 10:28:18 No severe PSQL-related problems occured
30.10.20 10:28:19. End of report.