Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.11.2020 07:27
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 16754748 kB
MemAvailable 31438364 kB
Buffers 0 kB
Cached 14889796 kB
SwapCached 2828 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-11-06 07:26:52
DTS_END                         2020-11-06 07:26:52.5820
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/35512
TRN_ID                          228180
ATT_ID                          670
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: 8891.29 27396 1066955
customer order: creation 160.04 398 19205
customer order: refuse 14.64 392 1757
order to supplier: creation 43.93 1504 5271
order to supplier: removal 3.23 117062 387
invoice (draft): creation 44.89 4287 5387
invoice (draft): removal 49.73 930 5967
invoice accept: apply 41.23 19829 4947
invoice accept: cancel 45.14 1057 5417
customer reserve: creation 3792.43 193 455092
customer reserve: removal 4415.63 67 529875
realization accept: apply 80.25 202 9630
realization accept: cancel 78.42 130 9410
payment from customer: creation 56.33 121 6759
payment from customer: removal 5.53 90 663
payment to supplier: creation 46.14 128 5537
payment to supplier: removal 3.18 117 381
service: total inventory turnovers 3.33 4377 400
service: total monetary turnovers 6.43 521 772
service: aggregate perf. data 0.78 19569 94
service: refresh index statistics 0.03 376953 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 06-11-2020 07:27:49 to 06-11-2020 07:27:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4244
TEST_TIME 2 3542
TEST_TIME 3 3771
TEST_TIME 4 2959
TEST_TIME 5 2833
TEST_TIME 6 3839
TEST_TIME 7 4120
TEST_TIME 8 4669
TEST_TIME 9 4815
TEST_TIME 10 4943
TEST_TIME 11 6129
TEST_TIME 12 6158
TEST_TIME 13 6594
TEST_TIME 14 6752
TEST_TIME 15 6990
TEST_TIME 16 7209
TEST_TIME 17 7376
TEST_TIME 18 7731
TEST_TIME 19 7908
TEST_TIME 20 7862
TEST_TIME 21 7870
TEST_TIME 22 7816
TEST_TIME 23 8004
TEST_TIME 24 8460
TEST_TIME 25 8572
TEST_TIME 26 8667
TEST_TIME 27 9237
TEST_TIME 28 9101
TEST_TIME 29 9122
TEST_TIME 30 9408
TEST_TIME 31 9755
TEST_TIME 32 10407
TEST_TIME 33 10558
TEST_TIME 34 10442
TEST_TIME 35 10245
TEST_TIME 36 10203
TEST_TIME 37 10184
TEST_TIME 38 10029
TEST_TIME 39 10279
TEST_TIME 40 10203
TEST_TIME 41 10232
TEST_TIME 42 10171
TEST_TIME 43 9954
TEST_TIME 44 9731
TEST_TIME 45 9706
TEST_TIME 46 9593
TEST_TIME 47 9646
TEST_TIME 48 9618
TEST_TIME 49 9597
TEST_TIME 50 9510
TEST_TIME 51 9489
TEST_TIME 52 9411
TEST_TIME 53 9298
TEST_TIME 54 9169
TEST_TIME 55 9351
TEST_TIME 56 9317
TEST_TIME 57 9243
TEST_TIME 58 9360
TEST_TIME 59 9338
TEST_TIME 60 9371
TEST_TIME 61 9373
TEST_TIME 62 9299
TEST_TIME 63 9229
TEST_TIME 64 9235
TEST_TIME 65 9514
TEST_TIME 66 9501
TEST_TIME 67 9424
TEST_TIME 68 9497
TEST_TIME 69 9419
TEST_TIME 70 9295
TEST_TIME 71 9262
TEST_TIME 72 9186
TEST_TIME 73 9082
TEST_TIME 74 9195
TEST_TIME 75 9147
TEST_TIME 76 9082
TEST_TIME 77 9114
TEST_TIME 78 9155
TEST_TIME 79 9105
TEST_TIME 80 9095
TEST_TIME 81 9086
TEST_TIME 82 9116
TEST_TIME 83 9101
TEST_TIME 84 9157
TEST_TIME 85 9161
TEST_TIME 86 9223
TEST_TIME 87 9222
TEST_TIME 88 9290
TEST_TIME 89 9194
TEST_TIME 90 9103
TEST_TIME 91 9111
TEST_TIME 92 9053
TEST_TIME 93 8985
TEST_TIME 94 9046
TEST_TIME 95 9013
TEST_TIME 96 8982
TEST_TIME 97 8962
TEST_TIME 98 8987
TEST_TIME 99 8992
TEST_TIME 100 8981
TEST_TIME 101 9017
TEST_TIME 102 9020
TEST_TIME 103 9028
TEST_TIME 104 9085
TEST_TIME 105 9079
TEST_TIME 106 9069
TEST_TIME 107 9125
TEST_TIME 108 9066
TEST_TIME 109 8992
TEST_TIME 110 8956
TEST_TIME 111 8937
TEST_TIME 112 8920
TEST_TIME 113 8898
TEST_TIME 114 8877
TEST_TIME 115 8859
TEST_TIME 116 8875
TEST_TIME 117 8852
TEST_TIME 118 8823
TEST_TIME 119 8850
TEST_TIME 120 8896

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-11-2020 07:27:53 to 06-11-2020 07:27:56.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1485258 1485258 0 0.00 0 25816 7 0 0
doc_list_biud 1485257 1485257 0 0.00 0 51975 31 0 0
sp_add_invoice_to_stock 5069 4947 122 2.40 27 223706 19830 0 122
sp_cancel_adding_invoice 5417 5417 0 0.00 5 89880 1058 0 0
sp_cancel_client_order 1757 1757 0 0.00 5 12997 392 0 0
sp_cancel_customer_reserve 529875 529875 0 0.00 1 44326 67 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 3 1753 91 0 0
sp_cancel_pay_to_supplier 381 381 0 0.00 3 6596 118 0 0
sp_cancel_supplier_invoice 5970 5967 3 0.05 2 51997 931 0 3
sp_cancel_supplier_order 387 387 0 0.00 9 1104600 117062 0 0
sp_cancel_write_off 9410 9410 0 0.00 1 10807 130 0 0
sp_client_order 19205 19205 0 0.00 4 19254 398 0 0
sp_customer_reserve 455092 455092 0 0.00 0 42275 194 0 0
sp_fill_shopping_cart 29849 29849 0 0.00 1 22792 51 0 0
sp_fill_shopping_cart_clo_res 455086 455086 0 0.00 0 29856 20 0 0
sp_get_clo_for_invoice 4939 4939 0 0.00 0 36697 446 0 0
sp_kill_cost_storno 15871 15871 0 0.00 0 14837 69 0 0
sp_kill_qstorno_ret_qs2qd 1075514 1075514 0 0.00 0 51972 36 0 0
sp_kill_qty_storno 567382 567382 0 0.00 0 51974 80 0 0
sp_lock_selected_doc 20102 20102 0 0.00 0 10227 6 0 0
sp_make_cost_storno 26865 26865 0 0.00 0 25800 63 0 0
sp_multiply_rows_for_pdistr 26865 26865 0 0.00 0 8911 35 0 0
sp_multiply_rows_for_qdistr 19205 19205 0 0.00 0 19215 229 0 0
sp_pay_from_customer 6759 6759 0 0.00 4 12621 121 0 0
sp_pay_to_supplier 5537 5537 0 0.00 5 16440 128 0 0
sp_payment_common 12296 12296 0 0.00 1 12592 27 0 0
sp_qd_handle_on_cancel_clo 1757 1757 0 0.00 0 12989 371 0 0
sp_qd_handle_on_invoice_upd_sts 10356 10356 0 0.00 0 36667 449 0 0
sp_qd_handle_on_reserve_upd_sts 9630 9630 0 0.00 0 13144 62 0 0
sp_reserve_write_off 9630 9630 0 0.00 7 16195 202 0 0
sp_supplier_invoice 5387 5387 0 0.00 7 134212 4287 0 0
sp_supplier_order 5271 5271 0 0.00 6 89090 1504 0 0
srv_aggregate_perf_data 94 94 0 0.00 2778 281744 19569 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1097 149486 45284 0 0
srv_find_qd_qs_mism 1019659 1019659 0 0.00 0 23136 6 0 0
srv_make_invnt_saldo 400 400 0 0.00 71 203274 4377 0 0
srv_make_money_saldo 772 772 0 0.00 1 10889 521 0 0
srv_recalc_idx_stat 4 4 0 0.00 209271 487208 376954 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4947 4947 0 0.00 0 15652 205 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 4213 87 0 0
t$perf-norm:sp_cancel_client_order 1757 1757 0 0.00 0 1846 12 0 0
t$perf-norm:sp_cancel_customer_reserve 635 635 0 0.00 0 935 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 1054 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 381 381 0 0.00 0 321 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 890 25 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 17088 1665 0 0
t$perf-norm:sp_cancel_write_off 909 909 0 0.00 0 2636 9 0 0
t$perf-norm:sp_client_order 19205 19205 0 0.00 0 14016 22 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 3235 12 0 0
t$perf-norm:sp_pay_from_customer 6759 6759 0 0.00 0 12069 14 0 0
t$perf-norm:sp_pay_to_supplier 5537 5537 0 0.00 0 4434 10 0 0
t$perf-norm:sp_reserve_write_off 9630 9630 0 0.00 0 11254 12 0 0
t$perf-norm:sp_supplier_invoice 5387 5387 0 0.00 0 10422 22 0 0
t$perf-norm:sp_supplier_order 5271 5271 0 0.00 0 3124 18 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 720 38 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 270 5 0 0
t$perf-norm:srv_make_invnt_saldo 400 400 0 0.00 0 2976 16 0 0
t$perf-norm:srv_make_money_saldo 772 772 0 0.00 0 1028 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10122 9878 244 2.41 0 6754 17 0 244
v_all_customers 34760 34760 0 0.00 0 38 1 0 0
v_all_suppliers 29020 29020 0 0.00 0 26 1 0 0
v_all_wares 38410 38410 0 0.00 0 93 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 200 6 0 0
v_cancel_client_order 3514 3514 0 0.00 0 570 3 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 693 6 0 0
v_cancel_customer_reserve 1270 1270 0 0.00 0 1841 7 0 0
v_cancel_payment_to_supplier 762 762 0 0.00 0 1349 6 0 0
v_cancel_supplier_invoice 770 764 6 0.77 0 538 13 0 6
v_cancel_supplier_order 774 774 0 0.00 0 2837 10 0 0
v_cancel_write_off 1818 1818 0 0.00 0 3489 17 0 0
v_max_id_clo_ord 5271 5271 0 0.00 0 1365 2 0 0
v_max_id_clo_res 984 984 0 0.00 0 1367 8 0 0
v_max_id_ord_sup 5372 5372 0 0.00 0 7890 7 0 0
v_max_non_paid_invoice 1670 1670 0 0.00 0 2548 4 0 0
v_max_non_paid_realizn 3777 3777 0 0.00 0 2671 6 0 0
v_min_id_clo_ord 5271 5271 0 0.00 0 10863 18 0 0
v_min_id_clo_res 984 984 0 0.00 0 5711 19 0 0
v_min_id_ord_sup 5372 5372 0 0.00 0 8043 7 0 0
v_min_non_paid_invoice 5537 5537 0 0.00 0 6716 13 0 0
v_min_non_paid_realizn 6759 6759 0 0.00 0 7051 17 0 0
v_our_firm 9614 9614 0 0.00 0 16 1 0 0
v_reserve_write_off 19260 19260 0 0.00 0 6767 6 0 0
x_lock_depdocs_on_canc_invoice 11002 11002 0 0.00 0 10100 125 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 14457 479 0 0
x_make_qstorno_1000_1200 5271 5271 0 0.00 1 89071 1398 0 0
x_make_qstorno_1200_2000 5387 5387 0 0.00 3 134204 4101 0 0
x_make_qstorno_2100_3300 455092 455092 0 0.00 0 42272 171 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 06-11-2020 07:27:57 to 06-11-2020 07:28:02.

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
04:56:56 2 8192000000 8796633152 8807174144 3163616 0.038 1 1 2 0 3261312 61840 35856
04:57:57 14213 8192000000 9707794912 10001793024 787786368 8.772 100 91 182 0 818269568 7898112 22585088
04:59:15 18736 8192000000 9753628032 10055548928 822100384 9.120 100 91 182 0 855638096 11313728 22223984
05:00:39 22628 8192000000 9780280064 10090520576 843654768 9.336 100 85 170 0 885596496 20919344 21022384
05:02:12 13917 8192000000 9846019808 10177847296 907196928 9.970 100 78 153 0 958426496 34143504 17086064
05:03:30 17670 8192000000 9915053936 10254938112 943820528 10.330 100 85 169 2 1017087312 54662144 18604640
05:04:49 7112 8192000000 9920311776 10274168832 976652784 10.652 100 75 151 2 1046486960 54305920 15528256
05:06:01 3694 8192000000 9975148864 10336043008 1008044688 10.956 100 72 143 0 1090089024 67754272 14290064
05:07:08 2918 8192000000 10009182400 10377945088 1036605568 11.232 100 86 171 1 1124877056 71325296 16946192
05:08:12 1611 8192000000 10020706816 10391257088 1058931312 11.446 100 91 182 1 1132884224 55423440 18529472
05:09:15 5362 8192000000 10006745216 10402410496 1066640736 11.520 100 55 108 2 1138196320 60268032 11287552
05:10:22 6882 8192000000 9975667664 10374492160 1075354256 11.603 100 37 74 2 1106955328 23992400 7608672
05:11:31 7435 8192000000 10002973472 10385850368 1075606288 11.606 100 64 125 3 1110064336 19477008 14981040
05:12:41 5781 8192000000 10008626000 10403819520 1083564432 11.681 100 43 86 1 1124183616 31226640 9392544
05:13:48 23698 8192000000 10022866224 10419412992 1089400080 11.737 100 55 112 4 1115539392 14171376 11967936
05:15:25 3187 8192000000 10024358912 10418343936 1094875840 11.789 100 62 124 3 1135274720 27020016 13378864
05:16:31 6291 8192000000 10016933216 10423123968 1098709008 11.825 100 25 49 1 1131073200 27119904 5244288
05:17:39 5228 8192000000 9994700336 10395934720 1090768752 11.750 100 41 80 3 1113849248 14489312 8591184
05:18:44 26523 8192000000 10003351072 10426368000 1068370352 11.537 98 47 92 0 1101352432 22437904 10544176
05:20:13 5588 8192000000 9977941392 10364588032 1043909072 11.302 98 49 94 1 1069474864 15444080 10121712
05:21:24 7500 8192000000 9945079200 10325561344 1015995024 11.033 99 29 57 1 1056725216 34666416 6063776
05:22:32 5893 8192000000 9934682592 10316333056 1030076464 11.169 100 25 51 0 1056211888 19732128 6403296
05:23:38 23240 8192000000 9927123120 10310725632 1015869072 11.032 99 60 120 2 1043417744 14976544 12572128
05:25:05 5897 8192000000 9965111376 10347225088 1037456256 11.240 99 44 85 1 1061822832 14443920 9922656
05:26:13 6026 8192000000 9970816784 10375073792 1060290224 11.459 100 35 70 1 1093349120 25546560 7512336
05:27:25 32973 8192000000 9994488000 10392330240 1080759728 11.655 100 37 72 2 1108994944 20431296 7803920
05:29:09 122684 8192000000 10086281120 10490535936 1096328336 11.803 100 38 77 3 1150249920 45342816 8578768
05:32:57 41372 8192000000 10117613632 10560573440 1112755696 11.958 100 35 70 3 1215093904 94908384 7429824
05:34:51 3178 8192000000 10106109424 10507603968 1123664560 12.062 100 61 122 0 1211015632 74154384 13196688
05:35:59 2937 8192000000 10190605648 10586869760 1135946000 12.177 100 77 151 1 1293934752 142001120 15987632
05:37:07 1995 8192000000 10239285344 10632720384 1144499648 12.258 100 81 158 0 1337131168 175799408 16832112
05:38:13 6682 8192000000 10181604544 10587521024 1167624672 12.475 100 66 130 3 1285632656 103987088 14020896
05:39:25 2000 8192000000 10243283632 10643681280 1175228544 12.546 100 80 153 3 1341594736 150281776 16084416
05:40:32 2115 8192000000 10263127056 10662031360 1168128416 12.479 100 89 175 1 1360800416 173496096 19175904
05:41:40 1601 8192000000 10268396784 10666262528 1177382624 12.566 100 88 173 4 1360528352 165016128 18129600
05:42:48 1286 8192000000 10306195904 10710532096 1169495344 12.492 100 76 150 1 1408335120 222237952 16601824
05:43:53 3193 8192000000 10326568336 10727968768 1167226256 12.471 100 89 175 1 1423039536 236814032 18999248
05:45:00 1095 8192000000 10335896176 10730438656 1151467360 12.323 99 91 182 2 1425397664 253418832 20511472
05:46:06 2649 8192000000 10332196592 10731126784 1161877056 12.421 100 76 152 1 1429847008 251254064 16715888
05:47:17 1652 8192000000 10373347280 10778918912 1167173392 12.470 100 72 144 1 1472148320 287808768 17166160
05:48:22 1864 8192000000 10375967600 10772885504 1154603712 12.353 100 83 166 0 1470180464 296222928 19353824
05:49:30 1757 8192000000 10386692496 10781646848 1145735088 12.269 99 84 166 1 1482709376 317521728 19452560
05:50:36 5194 8192000000 10342701760 10748231680 1136406320 12.182 99 53 104 2 1445511424 296066256 13038848
05:51:44 7107 8192000000 10367584624 10786033664 1131775376 12.138 100 35 70 2 1477007248 336137632 9094240
05:52:56 4249 8192000000 10391638464 10798022656 1137927696 12.196 100 71 142 3 1491226816 337109488 16189632
05:54:01 10655 8192000000 10336812208 10752847872 1136345264 12.181 100 34 68 2 1433383808 288569184 8469360
05:55:29 15812 8192000000 10358142176 10777522176 1128364224 12.106 100 36 72 1 1456102496 318642272 9096000
05:56:50 13207 8192000000 10319529808 10760159232 1129178432 12.114 100 26 52 0 1421099456 285332400 6588624
05:58:08 30825 8192000000 10169873872 10679635968 1121536976 12.042 100 8 16 1 1317104832 193346224 2221632
06:00:37 40462 8192000000 10065945168 10491977728 1121080768 12.037 99 19 35 2 1147462224 21622560 4758896
06:02:18 92687 8192000000 10124158640 10583191552 1138629552 12.203 100 14 28 0 1194057616 53060000 2368064
06:04:55 131131 8192000000 10104233344 10566053888 1097038496 11.810 96 12 24 0 1137766544 38377968 2350080
06:09:10 149486 8192000000 10121830016 10566701056 1142240416 12.237 99 24 48 1 1196111456 48807392 5063648
06:12:42 9611 8192000000 10126926608 10554994688 1154450480 12.351 100 64 126 2 1212917264 45504944 12961840
06:13:53 50353 8192000000 10103489888 10599124992 1155317360 12.359 100 3 6 0 1205328064 49459632 551072
06:15:46 64443 8192000000 10104788688 10555834368 1141154624 12.226 99 14 28 0 1187105952 42924416 3026912
06:17:58 53292 8192000000 10134106320 10604281856 1144616032 12.259 99 17 33 0 1208505264 60638192 3251040
06:19:52 74479 8192000000 10161554800 10617245696 1153016752 12.338 100 18 36 2 1261601152 104435136 4149264
06:22:13 40334 8192000000 10090498096 10556071936 1128949904 12.111 98 18 36 1 1166881056 34330912 3600240
06:23:54 35549 8192000000 10133163072 10601762816 1130005424 12.121 98 21 42 1 1193975792 59544912 4425456
06:25:33 36883 8192000000 10115650016 10552532992 1143078752 12.244 99 33 65 3 1197310864 47698128 6533984
06:27:22 37852 8192000000 10094270160 10535907328 1101627008 11.853 96 16 33 1 1148540304 43302368 3610928
06:29:14 65766 8192000000 10197875312 10675179520 1132753984 12.147 99 21 42 3 1265343616 128294928 4294704
06:31:22 48607 8192000000 10059805664 10499948544 1087485712 11.719 96 10 20 2 1121885728 32654400 1745616
06:33:13 16696 8192000000 10092029216 10515263488 1126574528 12.089 100 41 81 4 1175604304 40106000 8923776
06:34:33 125998 8192000000 10066312768 10534756352 1127605344 12.099 100 14 28 0 1173151744 42410960 3135440
06:37:44 73049 8192000000 10106604912 10567041024 1127635056 12.099 99 11 22 1 1178790224 48880000 2275168
06:39:59 45480 8192000000 10075201776 10573770752 1115203040 11.982 99 12 24 0 1212477984 94551520 2723424
06:41:44 67366 8192000000 10069312448 10497241088 1084006176 11.686 97 14 28 0 1114796528 27856128 2934224
06:44:00 73459 8192000000 10052956528 10514399232 1099911360 11.837 98 14 29 0 1133441664 30679040 2851264
06:46:21 34012 8192000000 10068359680 10503839744 1108961264 11.923 99 25 49 0 1147586912 33672112 4953536
06:47:57 50510 8192000000 10090436656 10547154944 1096514928 11.805 99 38 76 3 1197564112 93496032 7553152
06:49:49 82320 8192000000 10101304528 10581061632 1094958752 11.790 99 26 52 0 1166270384 65868848 5442784
06:52:17 48960 8192000000 10077867744 10534674432 1110547936 11.938 99 17 34 0 1194416272 79704880 4163456
06:54:06 121561 8192000000 10091866896 10535493632 1123267408 12.058 100 18 36 0 1176040240 49356576 3416256
06:58:04 66258 8192000000 10066965200 10507280384 1101179792 11.849 98 11 22 0 1162408144 58923040 2305312
07:00:11 94369 8192000000 10074322768 10578071552 1109829264 11.931 99 8 16 0 1160951248 49563120 1558864
07:02:47 59467 8192000000 10090213584 10545049600 1108819568 11.921 98 18 36 1 1181245472 68703440 3722464
07:04:47 61196 8192000000 10086733792 10551631872 1133112256 12.151 100 15 30 1 1197664048 61159808 3391984
07:06:49 37962 8192000000 10073983280 10508238848 1128849904 12.111 99 26 51 0 1152298640 18169456 5279280
07:08:31 37048 8192000000 10107142112 10556444672 1146852496 12.280 100 34 68 1 1216137136 62362512 6922128
07:10:12 40166 8192000000 10127129984 10614751232 1151450736 12.323 100 21 42 0 1235241200 79418880 4371584
07:11:53 63863 8192000000 10108618176 10556129280 1092819344 11.769 95 27 54 5 1168554336 70286272 5448720
07:13:58 143759 8192000000 10090011488 10554073088 1128499072 12.107 99 30 60 0 1183035360 47523008 7013280
07:19:14 44296 8192000000 10068829312 10513616896 1099037472 11.829 96 28 56 2 1123501040 18282560 6181008
07:21:21 102666 8192000000 10094869840 10544308224 1083648000 11.682 95 30 60 0 1147967344 58322800 5996544
07:24:04 82321 8192000000 10165245520 10601631744 1114494480 11.975 98 18 36 2 1219846320 101603568 3748272
07:26:36 31007 8192000000 9137010176 9469485056 260072912 3.077 23 6 12 0 301558704 40413232 1072560

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-11-2020 07:28:03 to 06-11-2020 07:28:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-11-2020 07:28:19 to 06-11-2020 07:28:21.

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          228450
MON$OLDEST_ACTIVE               228451
MON$OLDEST_SNAPSHOT             228451
MON$NEXT_TRANSACTION            228451
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                       2107584
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             785
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2247 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2247 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2247 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.2247 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 ---
:50: 
:51: mscs-test-161.budzdorov.ru    Fri Nov  6 07:27:14 2020
:52:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:53: 
:54: 
:55: mscs-test-161.budzdorov.ru    Fri Nov  6 07:27:16 2020
:56:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:57: 
:58: 
:59: mscs-test-161.budzdorov.ru    Fri Nov  6 07:27:17 2020
:60:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:61: 
--- end of diff output ---

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

06.11.20 07:28:22. 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-11-06 07:28:25 No severe PSQL-related problems occured
06.11.20 07:28:26. End of report.