Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.02.2021 13:29
Common Performance Final Results
  1. Hardware and OS info
  2. 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 was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. 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 16296260 kB
MemAvailable 31242804 kB
Buffers 0 kB
Cached 15403052 kB
SwapCached 4876 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 2021-02-07 13:27:00
DTS_END                         2021-02-07 13:27:00.6910
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55516
TRN_ID                          241986
ATT_ID                          699
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: 9322.18 25244 1118662
customer order: creation 175.37 394 21044
customer order: refuse 15.19 325 1823
order to supplier: creation 48.06 1352 5767
order to supplier: removal 3.63 108403 436
invoice (draft): creation 47.50 3491 5700
invoice (draft): removal 53.59 842 6431
invoice accept: apply 42.28 16777 5073
invoice accept: cancel 48.38 935 5806
customer reserve: creation 3838.18 167 460581
customer reserve: removal 4751.29 66 570155
realization accept: apply 85.31 169 10237
realization accept: cancel 82.48 130 9898
payment from customer: creation 60.67 107 7280
payment from customer: removal 5.92 97 710
payment to supplier: creation 49.75 137 5970
payment to supplier: removal 3.55 81 426
service: total inventory turnovers 3.87 3719 464
service: total monetary turnovers 6.23 554 747
service: aggregate perf. data 0.91 14652 109
service: refresh index statistics 0.04 352474 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 07-02-2021 13:30:06 to 07-02-2021 13:30:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2825
TEST_TIME 2 3183
TEST_TIME 3 3882
TEST_TIME 4 4397
TEST_TIME 5 5329
TEST_TIME 6 5464
TEST_TIME 7 5550
TEST_TIME 8 5558
TEST_TIME 9 5625
TEST_TIME 10 5799
TEST_TIME 11 6219
TEST_TIME 12 6205
TEST_TIME 13 6865
TEST_TIME 14 7024
TEST_TIME 15 7100
TEST_TIME 16 7230
TEST_TIME 17 7342
TEST_TIME 18 7362
TEST_TIME 19 7429
TEST_TIME 20 7864
TEST_TIME 21 8131
TEST_TIME 22 8061
TEST_TIME 23 8563
TEST_TIME 24 8591
TEST_TIME 25 8813
TEST_TIME 26 9024
TEST_TIME 27 9773
TEST_TIME 28 10895
TEST_TIME 29 10904
TEST_TIME 30 10866
TEST_TIME 31 10724
TEST_TIME 32 10566
TEST_TIME 33 10510
TEST_TIME 34 10590
TEST_TIME 35 10605
TEST_TIME 36 10478
TEST_TIME 37 10562
TEST_TIME 38 10516
TEST_TIME 39 10519
TEST_TIME 40 10532
TEST_TIME 41 10522
TEST_TIME 42 10321
TEST_TIME 43 10091
TEST_TIME 44 10029
TEST_TIME 45 10009
TEST_TIME 46 9930
TEST_TIME 47 9864
TEST_TIME 48 9860
TEST_TIME 49 9796
TEST_TIME 50 9749
TEST_TIME 51 9722
TEST_TIME 52 9584
TEST_TIME 53 9726
TEST_TIME 54 9694
TEST_TIME 55 9790
TEST_TIME 56 9779
TEST_TIME 57 9852
TEST_TIME 58 9850
TEST_TIME 59 9790
TEST_TIME 60 9768
TEST_TIME 61 9816
TEST_TIME 62 9776
TEST_TIME 63 9792
TEST_TIME 64 9883
TEST_TIME 65 9804
TEST_TIME 66 9673
TEST_TIME 67 9680
TEST_TIME 68 9615
TEST_TIME 69 9527
TEST_TIME 70 9450
TEST_TIME 71 9424
TEST_TIME 72 9341
TEST_TIME 73 9381
TEST_TIME 74 9348
TEST_TIME 75 9307
TEST_TIME 76 9300
TEST_TIME 77 9300
TEST_TIME 78 9398
TEST_TIME 79 9355
TEST_TIME 80 9504
TEST_TIME 81 9540
TEST_TIME 82 9486
TEST_TIME 83 9617
TEST_TIME 84 9556
TEST_TIME 85 9509
TEST_TIME 86 9451
TEST_TIME 87 9389
TEST_TIME 88 9330
TEST_TIME 89 9283
TEST_TIME 90 9253
TEST_TIME 91 9209
TEST_TIME 92 9194
TEST_TIME 93 9158
TEST_TIME 94 9164
TEST_TIME 95 9187
TEST_TIME 96 9318
TEST_TIME 97 9276
TEST_TIME 98 9357
TEST_TIME 99 9342
TEST_TIME 100 9375
TEST_TIME 101 9385
TEST_TIME 102 9358
TEST_TIME 103 9276
TEST_TIME 104 9241
TEST_TIME 105 9185
TEST_TIME 106 9214
TEST_TIME 107 9186
TEST_TIME 108 9151
TEST_TIME 109 9125
TEST_TIME 110 9129
TEST_TIME 111 9176
TEST_TIME 112 9187
TEST_TIME 113 9190
TEST_TIME 114 9195
TEST_TIME 115 9215
TEST_TIME 116 9246
TEST_TIME 117 9272
TEST_TIME 118 9302
TEST_TIME 119 9317
TEST_TIME 120 9325
TEST_TIME 121 9248

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-02-2021 13:30:10 to 07-02-2021 13:30:13.

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 1547547 1547547 0 0.00 0 59104 7 0 0
doc_list_biud 1547545 1547545 0 0.00 0 64935 31 0 0
sp_add_invoice_to_stock 5198 5073 125 2.40 27 195082 16777 0 125
sp_cancel_adding_invoice 5806 5806 0 0.00 5 67756 936 0 0
sp_cancel_client_order 1823 1823 0 0.00 5 17308 325 0 0
sp_cancel_customer_reserve 570155 570155 0 0.00 1 58039 67 0 0
sp_cancel_pay_from_customer 710 710 0 0.00 3 19040 98 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 3 2400 81 0 0
sp_cancel_supplier_invoice 6444 6431 13 0.20 2 22398 842 0 13
sp_cancel_supplier_order 436 436 0 0.00 5 1043282 108403 0 0
sp_cancel_write_off 9898 9898 0 0.00 1 57683 131 0 0
sp_client_order 21044 21044 0 0.00 4 12569 395 0 0
sp_customer_reserve 460581 460581 0 0.00 0 131349 168 0 0
sp_fill_shopping_cart 32485 32485 0 0.00 1 19796 42 0 0
sp_fill_shopping_cart_clo_res 460572 460572 0 0.00 0 22098 15 0 0
sp_get_clo_for_invoice 5064 5064 0 0.00 0 34864 338 0 0
sp_kill_cost_storno 16840 16840 0 0.00 0 11991 58 0 0
sp_kill_qstorno_ret_qs2qd 1157075 1157075 0 0.00 0 58030 36 0 0
sp_kill_qty_storno 609849 609849 0 0.00 0 64933 77 0 0
sp_lock_selected_doc 21335 21335 0 0.00 0 8106 6 0 0
sp_make_cost_storno 28551 28551 0 0.00 0 15407 57 0 0
sp_multiply_rows_for_pdistr 28551 28551 0 0.00 0 12254 31 0 0
sp_multiply_rows_for_qdistr 21044 21044 0 0.00 0 11574 211 0 0
sp_pay_from_customer 7280 7280 0 0.00 4 16969 107 0 0
sp_pay_to_supplier 5970 5970 0 0.00 5 20366 137 0 0
sp_payment_common 13250 13250 0 0.00 1 18181 20 0 0
sp_qd_handle_on_cancel_clo 1823 1823 0 0.00 0 17266 299 0 0
sp_qd_handle_on_invoice_upd_sts 10870 10870 0 0.00 0 64930 351 0 0
sp_qd_handle_on_reserve_upd_sts 10236 10236 0 0.00 0 11441 46 0 0
sp_reserve_write_off 10237 10237 0 0.00 7 14142 170 0 0
sp_supplier_invoice 5700 5700 0 0.00 8 134708 3492 0 0
sp_supplier_order 5767 5767 0 0.00 6 29503 1352 0 0
srv_aggregate_perf_data 109 109 0 0.00 3020 223323 14652 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 898 154338 43075 0 0
srv_find_qd_qs_mism 1071516 1071516 0 0.00 0 59104 6 0 0
srv_make_invnt_saldo 464 464 0 0.00 46 149137 3720 0 0
srv_make_money_saldo 747 747 0 0.00 1 12027 555 0 0
srv_recalc_idx_stat 5 5 0 0.00 252522 550238 352474 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5073 5073 0 0.00 0 20459 180 0 0
t$perf-norm:sp_cancel_adding_invoice 452 452 0 0.00 0 3352 99 0 0
t$perf-norm:sp_cancel_client_order 1823 1823 0 0.00 0 4710 13 0 0
t$perf-norm:sp_cancel_customer_reserve 716 716 0 0.00 0 2546 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 710 710 0 0.00 0 16275 25 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 325 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 431 431 0 0.00 0 1068 15 0 0
t$perf-norm:sp_cancel_supplier_order 436 436 0 0.00 0 14724 1396 0 0
t$perf-norm:sp_cancel_write_off 1041 1041 0 0.00 0 666 4 0 0
t$perf-norm:sp_client_order 21044 21044 0 0.00 0 9035 17 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 7555 11 0 0
t$perf-norm:sp_pay_from_customer 7280 7280 0 0.00 0 4081 8 0 0
t$perf-norm:sp_pay_to_supplier 5970 5970 0 0.00 0 8450 9 0 0
t$perf-norm:sp_reserve_write_off 10237 10237 0 0.00 0 12628 10 0 0
t$perf-norm:sp_supplier_invoice 5700 5700 0 0.00 0 6613 16 0 0
t$perf-norm:sp_supplier_order 5767 5767 0 0.00 0 3617 15 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 3194 36 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 89 4 0 0
t$perf-norm:srv_make_invnt_saldo 464 464 0 0.00 0 1235 13 0 0
t$perf-norm:srv_make_money_saldo 747 747 0 0.00 0 339 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 24 7 0 0
v_add_invoice_to_stock 10378 10128 250 2.40 0 6912 19 0 250
v_all_customers 38136 38136 0 0.00 0 26 1 0 0
v_all_suppliers 31572 31572 0 0.00 0 43 1 0 0
v_all_wares 42088 42088 0 0.00 0 36 1 0 0
v_cancel_adding_invoice 904 904 0 0.00 0 488 6 0 0
v_cancel_client_order 3646 3646 0 0.00 0 2610 4 0 0
v_cancel_customer_prepayment 1420 1420 0 0.00 0 7039 13 0 0
v_cancel_customer_reserve 1432 1432 0 0.00 0 890 4 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 2046 11 0 0
v_cancel_supplier_invoice 888 862 26 2.92 0 1473 14 0 26
v_cancel_supplier_order 872 872 0 0.00 0 2001 7 0 0
v_cancel_write_off 2082 2082 0 0.00 0 4235 18 0 0
v_max_id_clo_ord 5754 5754 0 0.00 0 577 1 0 0
v_max_id_clo_res 997 997 0 0.00 0 2529 15 0 0
v_max_id_ord_sup 5684 5684 0 0.00 0 4806 3 0 0
v_max_non_paid_invoice 1622 1622 0 0.00 0 521 3 0 0
v_max_non_paid_realizn 4014 4014 0 0.00 0 865 2 0 0
v_min_id_clo_ord 5754 5754 0 0.00 0 3609 6 0 0
v_min_id_clo_res 997 997 0 0.00 0 12363 29 0 0
v_min_id_ord_sup 5684 5684 0 0.00 0 8055 11 0 0
v_min_non_paid_invoice 5970 5970 0 0.00 0 18040 14 0 0
v_min_non_paid_realizn 7280 7280 0 0.00 0 6882 12 0 0
v_our_firm 10484 10484 0 0.00 0 18 1 0 0
v_reserve_write_off 20472 20472 0 0.00 0 8144 6 0 0
x_lock_depdocs_on_canc_invoice 11806 11806 0 0.00 0 15225 119 0 0
x_lock_depdocs_on_canc_sup_ord 436 436 0 0.00 0 11382 360 0 0
x_make_qstorno_1000_1200 5767 5767 0 0.00 2 29440 1268 0 0
x_make_qstorno_1200_2000 5700 5700 0 0.00 3 134669 3337 0 0
x_make_qstorno_2100_3300 460581 460581 0 0.00 0 131347 150 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 07-02-2021 13:30:14 to 07-02-2021 13:30:19.

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:57:00 10 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:01 28955 8192000000 9726834240 10024415232 802348592 8.920 100 90 180 0 832533232 7660896 22523744
10:59:36 19497 8192000000 9767779520 10074075136 843312560 9.333 100 91 182 1 878663728 13534448 21816720
11:00:59 15077 8192000000 9806294064 10123612160 869767968 9.598 100 86 172 2 916197440 26724608 19704864
11:02:18 8905 8192000000 9866039264 10194800640 916510016 10.062 100 77 154 0 973834976 40459616 16865344
11:03:30 9626 8192000000 9912169648 10253500416 956648752 10.456 100 83 164 1 1024380160 50367344 17364064
11:04:42 4439 8192000000 9963709360 10318454784 990358176 10.785 100 76 151 0 1080633424 74985552 15289696
11:05:49 2881 8192000000 10005175216 10365108224 1016930464 11.042 100 85 167 1 1121272912 87299008 17043440
11:06:54 2046 8192000000 10030344624 10393849856 1044251760 11.306 100 89 178 1 1138671344 76265312 18154272
11:07:57 2348 8192000000 10032907744 10398511104 1060970128 11.466 100 89 176 0 1139876176 60920512 17985536
11:09:02 12595 8192000000 10047419008 10423762944 1078428512 11.632 100 74 146 1 1156336608 63333344 14574752
11:10:17 15640 8192000000 10005872720 10409340928 1080362864 11.651 100 38 73 2 1124857072 35927152 8567056
11:11:33 7420 8192000000 9992353360 10387079168 1085076640 11.696 100 36 71 2 1116507664 23502784 7928240
11:12:43 4305 8192000000 9993719824 10394726400 1092240848 11.764 100 45 90 2 1112844736 9073056 11530832
11:13:48 7177 8192000000 10012745920 10420584448 1096460976 11.804 100 36 72 0 1135172736 31168784 7542976
11:14:57 18258 8192000000 9997889360 10401034240 1098869792 11.827 100 28 55 2 1122194224 16747584 6576848
11:17:00 4890 8192000000 10018990112 10423058432 1099541936 11.833 100 39 78 1 1130094608 21762464 8790208
11:18:08 4948 8192000000 10003352608 10392670208 1067748224 11.531 100 58 116 2 1111040240 30926832 12365184
11:19:13 3879 8192000000 9957925008 10343088128 1034065280 11.208 98 37 75 1 1049521936 6765584 8691072
11:20:18 55815 8192000000 9957047008 10360541184 1005734288 10.934 97 30 60 1 1060514016 49023952 5755776
11:22:14 7078 8192000000 9942668928 10330255360 1000340464 10.882 97 46 91 1 1020867136 9951568 10575104
11:23:23 5761 8192000000 9958676128 10341011456 1036710608 11.233 99 38 76 2 1071681920 26898768 8072544
11:24:29 14195 8192000000 9969457696 10372980736 1065373136 11.508 100 32 57 3 1087233840 14968768 6891936
11:25:44 27631 8192000000 9984335248 10393149440 1062544544 11.481 99 29 58 2 1101948752 33656672 5747536
11:27:15 17033 8192000000 9994513328 10390638592 1083327920 11.679 100 50 99 4 1107592000 12474688 11789392
11:28:36 20300 8192000000 10046457456 10447204352 1094914448 11.789 100 57 113 0 1150040384 43139040 11986896
11:30:05 5370 8192000000 10056431808 10460856320 1115809168 11.987 100 52 105 3 1171346064 44129248 11407648
11:31:15 21153 8192000000 10129796112 10543976448 1127789296 12.101 100 59 118 0 1235958704 95399152 12770256
11:32:43 12768 8192000000 10127164704 10535530496 1138926960 12.205 100 52 103 4 1231341056 80842880 11571216
11:34:00 11551 8192000000 10154673024 10565210112 1145250400 12.265 100 55 110 0 1261206480 104742208 11213872
11:35:44 4866 8192000000 10209434816 10618413056 1159778800 12.401 100 56 112 0 1316557664 143973504 12805360
11:36:53 4265 8192000000 10245433040 10654515200 1168297392 12.481 100 69 134 1 1351161184 167813872 15049920
11:38:11 2847 8192000000 10253311120 10662580224 1164246032 12.443 100 78 151 0 1355680224 174352848 17081344
11:39:21 3329 8192000000 10266690320 10680016896 1173716448 12.532 100 68 135 2 1372674928 183997136 14961344
11:40:28 1665 8192000000 10250539424 10649497600 1151296704 12.322 99 78 156 0 1343980160 175726832 16956624
11:41:35 7552 8192000000 10271341536 10678538240 1173413904 12.529 100 73 146 0 1379865872 190174320 16277648
11:42:46 924 8192000000 10352911088 10758864896 1168976064 12.487 100 94 187 1 1448236928 258906272 20354592
11:43:51 2275 8192000000 10384003344 10785579008 1165437040 12.454 100 88 176 1 1481694224 295406480 20850704
11:45:00 890 8192000000 10444054560 10841997312 1165262752 12.453 100 92 184 0 1541361056 354967600 21130704
11:46:06 1784 8192000000 10470903168 10869690368 1164995488 12.450 100 90 180 3 1567945248 381594256 21355504
11:47:10 6223 8192000000 10441187680 10860900352 1171043728 12.507 99 52 104 2 1538426352 355130304 12252320
11:48:37 10718 8192000000 10441893232 10868146176 1154950672 12.356 99 39 78 0 1528709216 363758656 9999888
11:49:50 12426 8192000000 10403237648 10835152896 1153518288 12.343 99 35 68 0 1506906864 345004656 8383920
11:51:16 24408 8192000000 10407084784 10835308544 1126327024 12.087 99 26 52 0 1512617328 379147760 7142544
11:52:49 14853 8192000000 10371524336 10843930624 1133404640 12.153 100 26 52 1 1500104480 359261424 7438416
11:54:10 20947 8192000000 10246575408 10782638080 1137129280 12.189 99 16 32 2 1378407680 237492144 3786256
11:55:31 76809 8192000000 10060922912 10502922240 1123681792 12.062 99 18 33 3 1157978816 31412864 2884160
11:57:57 21535 8192000000 10070869248 10496516096 1120296064 12.030 99 48 94 2 1150540480 20395248 9849168
11:59:19 106978 8192000000 10082830192 10536542208 1085324992 11.698 96 17 33 0 1141818352 52990384 3502976
12:02:07 80013 8192000000 10069668576 10541158400 1107168240 11.906 98 7 14 0 1160725456 52039920 1517296
12:04:34 51774 8192000000 10070064544 10504749056 1101068032 11.848 97 23 45 2 1148752224 42323808 5360384
12:06:28 123003 8192000000 10077359056 10513838080 1119536144 12.023 98 26 52 3 1158685824 33422544 5727136
12:10:37 21232 8192000000 10081583936 10536316928 1125898976 12.083 99 10 20 1 1167468384 39446768 2122640
12:12:06 12230 8192000000 10077012544 10540363776 1130420112 12.125 100 28 56 1 1174408432 37715248 6273072
12:13:19 52311 8192000000 10103101280 10556862464 1123472032 12.060 98 25 50 2 1172299984 43385584 5442368
12:15:12 43732 8192000000 10087176608 10546962432 1131625584 12.137 99 12 24 0 1173404400 39285264 2493552
12:16:56 129255 8192000000 10167907792 10630598656 1121049552 12.037 97 12 24 0 1196243536 72628992 2564992
12:20:09 34897 8192000000 10093677472 10527023104 1104596176 11.881 96 25 47 2 1147072032 37263408 5212448
12:21:49 64882 8192000000 10113973872 10587717632 1091883936 11.761 96 14 28 0 1159537184 64701632 2951616
12:24:19 32638 8192000000 10057638432 10511560704 1083169008 11.678 96 22 44 2 1125844544 38382720 4292816
12:25:54 21020 8192000000 10092448352 10525278208 1121242112 12.039 99 66 131 2 1173448240 37989184 14216944
12:27:18 36859 8192000000 10081938656 10514702336 1111352640 11.945 98 24 48 1 1164042320 48210896 4478784
12:28:59 30251 8192000000 10127073168 10557784064 1117591552 12.004 99 30 60 0 1203750864 80315184 5844128
12:30:29 83702 8192000000 10081030768 10514817024 1068045648 11.533 94 15 31 1 1106408624 35376096 2986880
12:32:57 85029 8192000000 10036539248 10495565824 1066984736 11.523 95 25 50 1 1104036208 31724320 5327152
12:35:29 72243 8192000000 10057714912 10512887808 1098404480 11.822 98 13 27 0 1125427920 24039936 2983504
12:37:50 64869 8192000000 10100420784 10544820224 1134663088 12.165 100 9 18 0 1211973792 75265600 2045104
12:39:55 64277 8192000000 10095599232 10558509056 1121076592 12.037 98 16 31 1 1186618032 61713280 3828160
12:42:01 40065 8192000000 10138464032 10593886208 1128301520 12.105 98 18 36 0 1206251536 73976640 3973376
12:44:01 49783 8192000000 10068859552 10506444800 1094580160 11.786 96 14 28 1 1122333888 25004880 2748848
12:45:55 43335 8192000000 10137073392 10635157504 1122950672 12.055 99 26 51 0 1230580384 102253008 5376704
12:47:40 60329 8192000000 10098511504 10533826560 1074002448 11.590 94 25 50 0 1104188224 25018048 5167728
12:49:44 48423 8192000000 10094554240 10542125056 1120743776 12.034 98 13 26 0 1169096800 45936496 2416528
12:51:36 77171 8192000000 10057840016 10513457152 1132390544 12.144 99 13 26 1 1165841328 30825520 2625264
12:53:57 56045 8192000000 10087461792 10550349824 1156500496 12.370 100 10 20 0 1189937344 31522640 1914208
12:55:54 44254 8192000000 10071451264 10536468480 1130551312 12.127 98 13 27 2 1166060672 32606560 2902800
12:57:40 43932 8192000000 10160124256 10606477312 1139059264 12.207 98 29 58 0 1223535216 77941216 6534736
12:59:24 33483 8192000000 10186237008 10631888896 1146981136 12.281 98 26 52 0 1256678832 103526608 6171088
13:00:58 90181 8192000000 10149820080 10713444352 1168490384 12.483 100 24 48 2 1328878464 154830688 5557392
13:03:57 72436 8192000000 10131689424 10668969984 1141906064 12.233 98 29 58 3 1232102496 83944704 6251728
13:06:23 41358 8192000000 10131162720 10594791424 1151193408 12.321 99 20 40 3 1220044272 64193952 4656912
13:08:09 154337 8192000000 10080593904 10530476032 1109230928 11.925 96 24 46 4 1164671408 50453632 4986848
13:11:45 64989 8192000000 10103329920 10615652352 1140155024 12.217 99 8 16 1 1190760944 48917424 1688496
13:13:59 54726 8192000000 10106795328 10558746624 1126697648 12.090 99 13 26 0 1174977072 45099568 3179856
13:16:03 71430 8192000000 10170103088 10640048128 1140995728 12.225 99 21 42 1 1255313392 109508272 4809392
13:18:15 34617 8192000000 10100738528 10535473152 1128083120 12.103 98 43 83 3 1163376928 26861872 8431936
13:19:54 29984 8192000000 10127494096 10570649600 1145494688 12.267 99 26 51 1 1208499472 57609712 5395072
13:21:28 22912 8192000000 10143685632 10614046720 1141157456 12.226 100 37 74 1 1211055184 62506256 7391472
13:22:54 67864 8192000000 10080643968 10533650432 1107085600 11.905 97 27 54 1 1160893040 48598960 5208480
13:25:06 123526 8192000000 10040947120 10576752640 1067437024 11.528 93 9 17 1 1118313440 49157664 1718752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 07-02-2021 13:30:20 to 07-02-2021 13:30:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-02-2021 13:30:36 to 07-02-2021 13:30:38.

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          242192
MON$OLDEST_ACTIVE               242193
MON$OLDEST_SNAPSHOT             242193
MON$NEXT_TRANSACTION            242193
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                       2129472
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             812
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2365 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.2365 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 ---
:72: 
:73: mscs-test-161.budzdorov.ru    Sun Feb  7 13:28:14 2021
:74:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:75: 
:76: 
:77: mscs-test-161.budzdorov.ru    Sun Feb  7 13:28:14 2021
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:79: 
:80: 
:81: mscs-test-161.budzdorov.ru    Sun Feb  7 13:28:16 2021
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:83: 
--- end of diff output ---

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

07.02.21 13:30:39. 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
2021-02-07 13:30:42 No severe PSQL-related problems occured
07.02.21 13:30:43. End of report.