Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.11.2020 10: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 16835800 kB
MemAvailable 31409176 kB
Buffers 0 kB
Cached 14798556 kB
SwapCached 2916 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-10 10:26:53
DTS_END                         2020-11-10 10:26:53.3460
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43950
TRN_ID                          233721
ATT_ID                          688
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: 8842.67 24872 1061121
customer order: creation 162.50 406 19500
customer order: refuse 14.00 333 1680
order to supplier: creation 44.08 1330 5290
order to supplier: removal 3.23 127881 388
invoice (draft): creation 42.66 3466 5119
invoice (draft): removal 49.28 1093 5914
invoice accept: apply 38.99 18457 4679
invoice accept: cancel 45.10 863 5412
customer reserve: creation 3662.63 177 439516
customer reserve: removal 4502.02 72 540242
realization accept: apply 78.79 171 9455
realization accept: cancel 75.79 117 9095
payment from customer: creation 56.64 114 6797
payment from customer: removal 5.51 108 661
payment to supplier: creation 46.67 119 5600
payment to supplier: removal 3.26 66 391
service: total inventory turnovers 4.31 2700 517
service: total monetary turnovers 6.17 536 740
service: aggregate perf. data 1.00 11991 120
service: refresh index statistics 0.04 327431 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-11-2020 10:27:57 to 10-11-2020 10:27:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3030
TEST_TIME 2 2634
TEST_TIME 3 3018
TEST_TIME 4 3158
TEST_TIME 5 3381
TEST_TIME 6 3715
TEST_TIME 7 4093
TEST_TIME 8 4332
TEST_TIME 9 4411
TEST_TIME 10 4774
TEST_TIME 11 4986
TEST_TIME 12 5385
TEST_TIME 13 5376
TEST_TIME 14 5758
TEST_TIME 15 5783
TEST_TIME 16 5965
TEST_TIME 17 6261
TEST_TIME 18 6581
TEST_TIME 19 6724
TEST_TIME 20 6852
TEST_TIME 21 6892
TEST_TIME 22 7567
TEST_TIME 23 7792
TEST_TIME 24 8136
TEST_TIME 25 8751
TEST_TIME 26 9455
TEST_TIME 27 10913
TEST_TIME 28 10873
TEST_TIME 29 10804
TEST_TIME 30 10623
TEST_TIME 31 10463
TEST_TIME 32 10222
TEST_TIME 33 10050
TEST_TIME 34 10046
TEST_TIME 35 9954
TEST_TIME 36 9990
TEST_TIME 37 9981
TEST_TIME 38 9804
TEST_TIME 39 9687
TEST_TIME 40 9833
TEST_TIME 41 9763
TEST_TIME 42 9714
TEST_TIME 43 9650
TEST_TIME 44 9520
TEST_TIME 45 9450
TEST_TIME 46 9420
TEST_TIME 47 9309
TEST_TIME 48 9385
TEST_TIME 49 9307
TEST_TIME 50 9322
TEST_TIME 51 9341
TEST_TIME 52 9360
TEST_TIME 53 9322
TEST_TIME 54 9338
TEST_TIME 55 9441
TEST_TIME 56 9400
TEST_TIME 57 9463
TEST_TIME 58 9453
TEST_TIME 59 9313
TEST_TIME 60 9223
TEST_TIME 61 9166
TEST_TIME 62 9125
TEST_TIME 63 9064
TEST_TIME 64 9204
TEST_TIME 65 9210
TEST_TIME 66 9137
TEST_TIME 67 9164
TEST_TIME 68 9221
TEST_TIME 69 9265
TEST_TIME 70 9262
TEST_TIME 71 9229
TEST_TIME 72 9267
TEST_TIME 73 9314
TEST_TIME 74 9364
TEST_TIME 75 9355
TEST_TIME 76 9318
TEST_TIME 77 9232
TEST_TIME 78 9219
TEST_TIME 79 9132
TEST_TIME 80 9092
TEST_TIME 81 9047
TEST_TIME 82 9017
TEST_TIME 83 9007
TEST_TIME 84 9025
TEST_TIME 85 8974
TEST_TIME 86 9003
TEST_TIME 87 9039
TEST_TIME 88 9064
TEST_TIME 89 9184
TEST_TIME 90 9265
TEST_TIME 91 9251
TEST_TIME 92 9245
TEST_TIME 93 9167
TEST_TIME 94 9121
TEST_TIME 95 9073
TEST_TIME 96 9024
TEST_TIME 97 8952
TEST_TIME 98 8930
TEST_TIME 99 8938
TEST_TIME 100 8990
TEST_TIME 101 8952
TEST_TIME 102 8937
TEST_TIME 103 8953
TEST_TIME 104 8929
TEST_TIME 105 9001
TEST_TIME 106 8992
TEST_TIME 107 9028
TEST_TIME 108 9049
TEST_TIME 109 9088
TEST_TIME 110 9081
TEST_TIME 111 9076
TEST_TIME 112 8996
TEST_TIME 113 8974
TEST_TIME 114 8926
TEST_TIME 115 8915
TEST_TIME 116 8870
TEST_TIME 117 8859
TEST_TIME 118 8874
TEST_TIME 119 8865
TEST_TIME 120 8846

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-11-2020 10:28:01 to 10-11-2020 10:28:04.

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 1467544 1467544 0 0.00 0 20021 7 0 0
doc_list_biud 1467539 1467539 0 0.00 0 66800 34 0 0
sp_add_invoice_to_stock 4840 4679 161 3.32 39 172240 18458 0 161
sp_cancel_adding_invoice 5412 5412 0 0.00 5 94290 863 0 0
sp_cancel_client_order 1680 1680 0 0.00 4 23293 333 0 0
sp_cancel_customer_reserve 540242 540242 0 0.00 1 66801 73 0 0
sp_cancel_pay_from_customer 661 661 0 0.00 3 6302 109 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 3072 66 0 0
sp_cancel_supplier_invoice 5922 5914 8 0.13 2 25822 1093 0 8
sp_cancel_supplier_order 388 388 0 0.00 11 1255305 127882 0 0
sp_cancel_write_off 9095 9095 0 0.00 1 13110 118 0 0
sp_client_order 19500 19500 0 0.00 4 21516 406 0 0
sp_customer_reserve 439516 439516 0 0.00 0 71043 178 0 0
sp_fill_shopping_cart 29900 29900 0 0.00 1 16616 43 0 0
sp_fill_shopping_cart_clo_res 439513 439513 0 0.00 0 29328 18 0 0
sp_get_clo_for_invoice 4675 4675 0 0.00 0 51607 482 0 0
sp_kill_cost_storno 15559 15559 0 0.00 0 6759 55 0 0
sp_kill_qstorno_ret_qs2qd 1095881 1095881 0 0.00 0 66797 40 0 0
sp_kill_qty_storno 576856 576856 0 0.00 0 66800 84 0 0
sp_lock_selected_doc 19551 19551 0 0.00 0 7668 6 0 0
sp_make_cost_storno 26526 26526 0 0.00 0 20005 54 0 0
sp_multiply_rows_for_pdistr 26526 26526 0 0.00 0 11065 30 0 0
sp_multiply_rows_for_qdistr 19500 19500 0 0.00 0 15049 228 0 0
sp_pay_from_customer 6797 6797 0 0.00 4 20205 115 0 0
sp_pay_to_supplier 5600 5600 0 0.00 5 20174 119 0 0
sp_payment_common 12396 12396 0 0.00 1 10348 23 0 0
sp_qd_handle_on_cancel_clo 1680 1680 0 0.00 0 23270 313 0 0
sp_qd_handle_on_invoice_upd_sts 10086 10086 0 0.00 0 22669 376 0 0
sp_qd_handle_on_reserve_upd_sts 9451 9451 0 0.00 0 22075 57 0 0
sp_reserve_write_off 9455 9455 0 0.00 6 22110 172 0 0
sp_supplier_invoice 5119 5119 0 0.00 5 88716 3466 0 0
sp_supplier_order 5290 5290 0 0.00 5 35249 1331 0 0
srv_aggregate_perf_data 120 120 0 0.00 2587 215637 11992 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2266 149640 50471 0 0
srv_find_qd_qs_mism 1015478 1015478 0 0.00 0 14611 6 0 0
srv_make_invnt_saldo 517 517 0 0.00 47 104035 2701 0 0
srv_make_money_saldo 740 740 0 0.00 1 16909 536 0 0
srv_recalc_idx_stat 5 5 0 0.00 138199 462874 327431 0 0
t$perf-abend:sp_add_invoice_to_stock 161 161 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4680 4680 0 0.00 0 9536 138 0 0
t$perf-norm:sp_cancel_adding_invoice 381 381 0 0.00 1 4057 74 0 0
t$perf-norm:sp_cancel_client_order 1680 1680 0 0.00 0 3491 15 0 0
t$perf-norm:sp_cancel_customer_reserve 633 633 0 0.00 0 314 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 661 661 0 0.00 0 1333 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 1626 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 343 343 0 0.00 0 1230 18 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 14499 1405 0 0
t$perf-norm:sp_cancel_write_off 949 949 0 0.00 0 1389 5 0 0
t$perf-norm:sp_client_order 19500 19500 0 0.00 0 11039 18 0 0
t$perf-norm:sp_customer_reserve 942 942 0 0.00 0 446 2 0 0
t$perf-norm:sp_pay_from_customer 6797 6797 0 0.00 0 5438 9 0 0
t$perf-norm:sp_pay_to_supplier 5600 5600 0 0.00 0 5419 8 0 0
t$perf-norm:sp_reserve_write_off 9455 9455 0 0.00 0 10405 9 0 0
t$perf-norm:sp_supplier_invoice 5119 5119 0 0.00 0 10490 20 0 0
t$perf-norm:sp_supplier_order 5290 5290 0 0.00 0 11107 16 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 414 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 4 0 0 0
t$perf-norm:srv_make_invnt_saldo 517 517 0 0.00 0 1660 8 0 0
t$perf-norm:srv_make_money_saldo 740 740 0 0.00 0 485 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 9670 9348 322 3.32 0 5452 17 0 322
v_all_customers 35458 35458 0 0.00 0 16 1 0 0
v_all_suppliers 29204 29204 0 0.00 0 14 1 0 0
v_all_wares 38996 38996 0 0.00 0 65 1 0 0
v_cancel_adding_invoice 762 762 0 0.00 0 2511 12 0 0
v_cancel_client_order 3360 3360 0 0.00 0 1707 4 0 0
v_cancel_customer_prepayment 1322 1322 0 0.00 0 4713 14 0 0
v_cancel_customer_reserve 1266 1266 0 0.00 0 1710 4 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 3018 8 0 0
v_cancel_supplier_invoice 702 686 16 2.27 0 2910 19 0 16
v_cancel_supplier_order 776 776 0 0.00 0 1931 5 0 0
v_cancel_write_off 1898 1898 0 0.00 0 5066 13 0 0
v_max_id_clo_ord 5286 5286 0 0.00 0 3238 2 0 0
v_max_id_clo_res 942 942 0 0.00 0 8672 26 0 0
v_max_id_ord_sup 5113 5113 0 0.00 0 3776 3 0 0
v_max_non_paid_invoice 1397 1397 0 0.00 0 2400 5 0 0
v_max_non_paid_realizn 3654 3654 0 0.00 0 3786 6 0 0
v_min_id_clo_ord 5286 5286 0 0.00 0 3884 6 0 0
v_min_id_clo_res 942 942 0 0.00 0 7934 27 0 0
v_min_id_ord_sup 5113 5113 0 0.00 0 4141 7 0 0
v_min_non_paid_invoice 5600 5600 0 0.00 0 10344 14 0 0
v_min_non_paid_realizn 6796 6796 0 0.00 0 7295 18 0 0
v_our_firm 9822 9822 0 0.00 0 12 1 0 0
v_reserve_write_off 18902 18902 0 0.00 0 4906 5 0 0
x_lock_depdocs_on_canc_invoice 10983 10983 0 0.00 0 27103 117 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 10830 370 0 0
x_make_qstorno_1000_1200 5290 5290 0 0.00 2 35242 1257 0 0
x_make_qstorno_1200_2000 5119 5119 0 0.00 3 84321 3294 0 0
x_make_qstorno_2100_3300 439516 439516 0 0.00 0 71042 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-11-2020 10:28:05 to 10-11-2020 10:28:09.

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:57 2 8192000000 8796633152 8807174144 3163616 0.038 1 1 2 0 3261312 61840 35856
07:57:57 14396 8192000000 9714502208 10008723456 791155104 8.807 100 88 176 0 821136304 6961728 23019472
07:59:22 20376 8192000000 9755672672 10059055104 830342672 9.203 100 85 170 1 862846256 11886064 20617520
08:00:50 20933 8192000000 9796242464 10115887104 859060448 9.491 100 78 156 0 899998512 22223024 18715040
08:02:22 12332 8192000000 9847094432 10184900608 913736048 10.034 100 80 160 1 962962256 31255296 17970912
08:03:39 7540 8192000000 9915580784 10261639168 961878912 10.507 100 76 150 0 1031945232 54486288 15580032
08:04:50 6099 8192000000 9974262096 10332098560 1001048416 10.889 100 83 164 3 1085190048 67741648 16399984
08:05:59 3335 8192000000 9994663152 10362748928 1024859472 11.119 100 80 155 1 1104368928 63755328 15754128
08:07:04 1408 8192000000 10019106464 10387570688 1052210224 11.382 100 93 185 3 1138380064 66303584 19866256
08:08:06 28336 8192000000 10013942912 10400059392 1063360960 11.489 100 53 106 0 1140377728 65999296 11017472
08:09:35 9149 8192000000 9996181360 10392616960 1072282096 11.574 100 40 80 0 1119669264 39146592 8240576
08:10:46 8572 8192000000 9987849536 10391846912 1073787904 11.588 100 34 67 2 1108207120 27379904 7039312
08:11:59 61021 8192000000 9997114880 10409783296 1083696016 11.683 100 29 58 3 1114159840 22928336 7535488
08:14:03 7038 8192000000 10009352288 10416889856 1087882976 11.723 100 42 82 1 1116962512 20199456 8880080
08:15:12 8039 8192000000 10022508704 10425757696 1080621840 11.653 99 49 101 2 1110970912 19637888 10711184
08:16:24 4388 8192000000 10007131264 10398474240 1093055024 11.772 100 33 63 3 1125033760 24749920 7228816
08:17:28 54373 8192000000 9964245440 10369376256 989535200 10.777 91 23 45 1 1010350512 15266512 5548800
08:19:25 4670 8192000000 9943045760 10326290432 1017883648 11.052 98 47 95 2 1050548080 21704656 10959776
08:20:31 5916 8192000000 9922211984 10303361024 984327584 10.726 97 44 88 2 1014725296 20120496 10277216
08:21:40 5798 8192000000 9953640512 10334056448 1020725296 11.079 99 49 98 2 1044209520 12411824 11072400
08:22:47 37261 8192000000 9953232880 10359132160 1037897152 11.244 100 31 63 0 1067149168 22213776 7038240
08:24:26 5993 8192000000 9984100256 10375528448 1057813232 11.436 100 50 101 2 1077763760 9173120 10777408
08:25:34 10173 8192000000 9987512976 10386644992 1066254944 11.516 100 35 70 1 1097470064 22286736 8928384
08:26:44 9093 8192000000 9997905952 10403319808 1086785440 11.712 100 33 66 0 1117839472 23959056 7094976
08:28:05 20429 8192000000 10040544192 10436259840 1101285856 11.850 100 58 115 2 1146882752 32731680 12865216
08:29:43 40154 8192000000 10062226512 10468679680 1114298128 11.973 100 53 106 1 1179466672 54429696 10738848
08:31:31 23614 8192000000 10136183168 10538700800 1129789376 12.119 100 39 78 3 1247640256 109486304 8364576
08:33:08 8231 8192000000 10161133408 10567532544 1131440064 12.135 99 56 112 1 1268261984 124360704 12461216
08:34:33 4374 8192000000 10182000960 10580758528 1154237888 12.349 100 63 125 1 1281181680 112759936 14183856
08:35:41 7484 8192000000 10245327456 10651648000 1154165344 12.349 99 60 120 0 1346045232 178399584 13480304
08:36:51 8921 8192000000 10274168144 10701365248 1167760960 12.476 100 55 110 0 1383412368 202650576 13000832
08:38:10 2691 8192000000 10313277504 10712313856 1174952592 12.543 99 81 162 2 1408977312 215651200 18373520
08:39:14 16178 8192000000 10330073920 10737614848 1183967120 12.627 100 61 123 2 1427735520 229644672 14123728
08:40:46 11801 8192000000 10336179344 10748407808 1173294832 12.528 100 56 111 1 1429435792 243120944 13020016
08:42:07 3346 8192000000 10432930448 10830020608 1168334096 12.481 99 86 172 0 1522635312 334800864 19500352
08:43:14 5279 8192000000 10466548960 10871271424 1173247104 12.527 100 82 161 1 1566049680 373692336 19110240
08:44:22 7148 8192000000 10442155648 10873974784 1176401632 12.557 100 35 70 0 1544222256 358997040 8823584
08:45:49 9448 8192000000 10497340960 10925993984 1158563184 12.390 99 45 90 1 1604980272 434638160 11778928
08:47:02 2264 8192000000 10515965632 10918318080 1145857616 12.271 99 75 147 2 1606176016 442091328 18227072
08:48:12 9016 8192000000 10504547056 10946445312 1137312656 12.190 99 55 110 1 1595260816 443753904 14194256
08:49:24 6413 8192000000 10526861472 10949603328 1131370128 12.134 99 44 89 0 1622440992 478918384 12152480
08:50:37 18715 8192000000 10468307392 10926366720 1128222176 12.105 99 37 74 2 1583558640 445006400 10330064
08:51:57 18941 8192000000 10415059632 10865639424 1119225200 12.020 100 28 56 0 1518163648 390587152 8351296
08:53:21 81691 8192000000 10051614496 10509869056 1073309984 11.584 96 8 16 2 1241007904 165538304 2159616
08:55:48 43567 8192000000 10045648848 10462834688 1101113968 11.848 99 41 82 0 1135597616 26042304 8441344
08:57:33 94639 8192000000 10097582960 10532724736 1122436928 12.050 100 28 54 1 1202697424 74479136 5781360
09:00:09 89564 8192000000 10101950016 10525085696 1069753872 11.550 94 28 56 1 1156161248 80162048 6245328
09:02:40 140808 8192000000 10099690928 10534039552 1071440784 11.566 94 20 40 1 1131880944 56521904 3918256
09:06:26 39683 8192000000 10054386672 10491908096 1120746800 12.034 100 23 46 2 1167340800 41041232 5552768
09:08:12 70281 8192000000 10067926592 10527223808 1115160496 11.981 98 14 28 0 1153422512 35460368 2801648
09:10:25 66152 8192000000 10040166432 10502270976 1135194352 12.170 100 18 35 0 1170381536 31838944 3348240
09:12:33 63477 8192000000 10095212864 10548375552 1145092272 12.263 100 14 28 1 1175550064 27528016 2929776
09:14:37 84429 8192000000 10102687728 10540748800 1129411280 12.116 98 16 31 0 1185085232 52738944 2935008
09:17:03 21290 8192000000 10094223744 10530783232 1145203328 12.264 100 36 72 0 1205351376 53459520 6688528
09:18:27 43437 8192000000 10088276320 10520240128 1142694032 12.241 99 25 49 0 1176069568 28758848 4616688
09:20:47 58155 8192000000 10092876112 10546765824 1119914128 12.026 97 24 49 0 1194293168 68351872 6027168
09:22:46 40711 8192000000 10082447120 10507816960 1129412304 12.116 98 22 44 1 1200438160 66916320 4109536
09:24:27 149640 8192000000 10059177168 10499330048 1105176464 11.887 96 24 48 1 1152057184 42069968 4810752
09:27:57 40465 8192000000 10082966080 10539786240 1123627824 12.061 99 6 12 0 1146711440 22229872 853744
09:29:38 65469 8192000000 10100722848 10623926272 1111474176 11.946 97 17 34 1 1227446176 112138432 3833568
09:31:52 43543 8192000000 10074808032 10505179136 1099080704 11.829 96 22 44 3 1134187504 30498960 4607840
09:33:45 63865 8192000000 10096980992 10589868032 1108173648 11.915 98 16 32 0 1187964064 76150272 3640144
09:35:49 52959 8192000000 10044087440 10493841408 1070593184 11.558 95 16 32 2 1106565344 32530992 3441168
09:37:43 26464 8192000000 10123376320 10540609536 1113583072 11.966 99 51 101 0 1208484688 84884608 10017008
09:39:12 113257 8192000000 10061779376 10534809600 1071790672 11.569 95 16 32 2 1127320016 52185120 3344224
09:42:06 43489 8192000000 10069358576 10506571776 1084876032 11.694 97 20 40 0 1125143888 36582720 3685136
09:43:50 81315 8192000000 10046726512 10513489920 1097628656 11.815 99 18 36 0 1164098656 62911424 3558576
09:46:16 44468 8192000000 10065833552 10550210560 1095052640 11.791 99 8 16 0 1154362752 57437504 1872608
09:48:01 54039 8192000000 10092662224 10526990336 1096684880 11.806 98 17 34 1 1165131536 64410288 4036368
09:49:55 76347 8192000000 10152760960 10613145600 1103546336 11.871 98 29 58 3 1212312256 102056960 6708960
09:52:12 58108 8192000000 10159599920 10615816192 1114888256 11.979 99 36 72 2 1241567696 118801520 7877920
09:54:15 71442 8192000000 10182590928 10664148992 1098973952 11.828 98 19 39 1 1248523808 145144368 4405488
09:56:34 74938 8192000000 10059618752 10480119808 1094188960 11.782 98 18 36 1 1135946512 38192800 3564752
09:59:02 101151 8192000000 10062611424 10524127232 1127521760 12.098 100 28 56 0 1192322624 59290592 5510272
10:01:52 73884 8192000000 10075598336 10544283648 1107409152 11.908 98 7 14 1 1151217856 42582944 1225760
10:04:06 69419 8192000000 10082256176 10574254080 1110264656 11.935 98 16 32 0 1199651232 86041824 3344752
10:06:16 113547 8192000000 10059772224 10509299712 1092507856 11.766 96 19 38 1 1150519024 53939328 4071840
10:09:10 44712 8192000000 10121452368 10562011136 1107849280 11.912 97 14 28 2 1172564336 61589440 3125616
10:11:03 69278 8192000000 10075833520 10555604992 1115386208 11.983 98 14 28 1 1190673872 72188320 3099344
10:13:13 104954 8192000000 10129184624 10621767680 1118706224 12.015 98 20 40 0 1206710432 83833056 4171152
10:16:02 39307 8192000000 10100114512 10521927680 1127373840 12.097 99 24 48 0 1173886416 41379568 5133008
10:17:44 123960 8192000000 10065454736 10527211520 1118589616 12.014 99 19 37 1 1149407072 26825200 3992256
10:21:27 25785 8192000000 10066795888 10560540672 1128164448 12.104 100 5 9 0 1182066640 52802528 1099664
10:22:55 86159 8192000000 10080702368 10579894272 1098862976 11.827 97 12 24 1 1169104784 67510096 2731712
10:25:22 45754 8192000000 10061646224 10510643200 1095015616 11.790 97 16 32 0 1126422288 28151376 3255296

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 161 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-11-2020 10:28:27 to 10-11-2020 10:28:29.

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          231433
MON$OLDEST_ACTIVE               233841
MON$OLDEST_SNAPSHOT             233841
MON$NEXT_TRANSACTION            233841
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                       2092416
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             799
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2249 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2249 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2249 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.2249 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 ---
:83: 
:84: mscs-test-161.budzdorov.ru    Tue Nov 10 10:26:57 2020
:85:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:86: 
:87: 
:88: mscs-test-161.budzdorov.ru    Tue Nov 10 10:27:06 2020
:89:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:90: 
--- end of diff output ---

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

10.11.20 10:28:30. 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-10 10:28:33 No severe PSQL-related problems occured
10.11.20 10:28:34. End of report.