Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.11.2020 04:28
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 16611320 kB
MemAvailable 31479512 kB
Buffers 20 kB
Cached 15024524 kB
SwapCached 2868 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-03 04:26:52
DTS_END                         2020-11-03 04:26:52.8060
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/43130
TRN_ID                          226691
ATT_ID                          626
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: 8822.98 24492 1058758
customer order: creation 160.40 388 19248
customer order: refuse 13.93 396 1671
order to supplier: creation 44.19 1342 5303
order to supplier: removal 3.25 118560 390
invoice (draft): creation 44.48 3739 5337
invoice (draft): removal 51.38 977 6165
invoice accept: apply 39.62 18492 4754
invoice accept: cancel 45.68 915 5481
customer reserve: creation 3631.99 179 435839
customer reserve: removal 4498.05 66 539766
realization accept: apply 78.87 206 9464
realization accept: cancel 88.83 128 10659
payment from customer: creation 56.49 118 6779
payment from customer: removal 5.61 104 673
payment to supplier: creation 46.93 156 5631
payment to supplier: removal 3.24 131 389
service: total inventory turnovers 3.56 4186 427
service: total monetary turnovers 5.69 666 683
service: aggregate perf. data 0.79 22271 95
service: refresh index statistics 0.03 316823 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-11-2020 04:28:49 to 03-11-2020 04:28:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3713
TEST_TIME 2 2724
TEST_TIME 3 3736
TEST_TIME 4 4571
TEST_TIME 5 4457
TEST_TIME 6 4675
TEST_TIME 7 4770
TEST_TIME 8 5605
TEST_TIME 9 5901
TEST_TIME 10 5944
TEST_TIME 11 6403
TEST_TIME 12 6569
TEST_TIME 13 6483
TEST_TIME 14 6560
TEST_TIME 15 6604
TEST_TIME 16 6838
TEST_TIME 17 6988
TEST_TIME 18 7155
TEST_TIME 19 7238
TEST_TIME 20 7571
TEST_TIME 21 7685
TEST_TIME 22 8558
TEST_TIME 23 8458
TEST_TIME 24 8737
TEST_TIME 25 8820
TEST_TIME 26 9078
TEST_TIME 27 9617
TEST_TIME 28 9806
TEST_TIME 29 9662
TEST_TIME 30 9727
TEST_TIME 31 9722
TEST_TIME 32 10108
TEST_TIME 33 9917
TEST_TIME 34 9939
TEST_TIME 35 10020
TEST_TIME 36 10308
TEST_TIME 37 10122
TEST_TIME 38 9954
TEST_TIME 39 10003
TEST_TIME 40 9914
TEST_TIME 41 9807
TEST_TIME 42 9679
TEST_TIME 43 9971
TEST_TIME 44 9858
TEST_TIME 45 9980
TEST_TIME 46 10058
TEST_TIME 47 9949
TEST_TIME 48 9836
TEST_TIME 49 9682
TEST_TIME 50 9659
TEST_TIME 51 9526
TEST_TIME 52 9498
TEST_TIME 53 9446
TEST_TIME 54 9360
TEST_TIME 55 9303
TEST_TIME 56 9288
TEST_TIME 57 9167
TEST_TIME 58 9108
TEST_TIME 59 9195
TEST_TIME 60 9339
TEST_TIME 61 9267
TEST_TIME 62 9239
TEST_TIME 63 9198
TEST_TIME 64 9153
TEST_TIME 65 9128
TEST_TIME 66 9077
TEST_TIME 67 9147
TEST_TIME 68 9151
TEST_TIME 69 9142
TEST_TIME 70 9224
TEST_TIME 71 9205
TEST_TIME 72 9242
TEST_TIME 73 9246
TEST_TIME 74 9233
TEST_TIME 75 9145
TEST_TIME 76 9053
TEST_TIME 77 8983
TEST_TIME 78 8982
TEST_TIME 79 8920
TEST_TIME 80 8890
TEST_TIME 81 8882
TEST_TIME 82 8811
TEST_TIME 83 8759
TEST_TIME 84 8802
TEST_TIME 85 8793
TEST_TIME 86 8843
TEST_TIME 87 8903
TEST_TIME 88 8892
TEST_TIME 89 8884
TEST_TIME 90 8897
TEST_TIME 91 9029
TEST_TIME 92 9055
TEST_TIME 93 9076
TEST_TIME 94 9053
TEST_TIME 95 8966
TEST_TIME 96 8947
TEST_TIME 97 8987
TEST_TIME 98 8927
TEST_TIME 99 8930
TEST_TIME 100 8920
TEST_TIME 101 8891
TEST_TIME 102 8872
TEST_TIME 103 8867
TEST_TIME 104 8892
TEST_TIME 105 8875
TEST_TIME 106 8882
TEST_TIME 107 8990
TEST_TIME 108 9003
TEST_TIME 109 8956
TEST_TIME 110 8996
TEST_TIME 111 9057
TEST_TIME 112 9075
TEST_TIME 113 9075
TEST_TIME 114 9033
TEST_TIME 115 8958
TEST_TIME 116 8965
TEST_TIME 117 8919
TEST_TIME 118 8872
TEST_TIME 119 8856
TEST_TIME 120 8827

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-11-2020 04:28:53 to 03-11-2020 04:28: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 1461763 1461763 0 0.00 0 22242 7 0 0
doc_list_biud 1461763 1461763 0 0.00 0 42459 32 0 0
sp_add_invoice_to_stock 4910 4754 156 3.17 35 180155 18493 0 156
sp_cancel_adding_invoice 5481 5481 0 0.00 5 54898 915 0 0
sp_cancel_client_order 1671 1671 0 0.00 5 22578 396 0 0
sp_cancel_customer_reserve 539766 539766 0 0.00 1 32980 66 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 2 6779 105 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 3 10267 132 0 0
sp_cancel_supplier_invoice 6174 6165 9 0.14 2 21515 978 0 9
sp_cancel_supplier_order 390 390 0 0.00 7 1055622 118560 0 0
sp_cancel_write_off 10659 10659 0 0.00 1 13568 129 0 0
sp_client_order 19248 19248 0 0.00 4 46522 388 0 0
sp_customer_reserve 435839 435839 0 0.00 0 72466 180 0 0
sp_fill_shopping_cart 29871 29871 0 0.00 1 31738 49 0 0
sp_fill_shopping_cart_clo_res 435825 435825 0 0.00 0 23594 16 0 0
sp_get_clo_for_invoice 4740 4740 0 0.00 0 37503 377 0 0
sp_kill_cost_storno 17202 17202 0 0.00 0 10263 70 0 0
sp_kill_qstorno_ret_qs2qd 1096746 1096746 0 0.00 0 32757 36 0 0
sp_kill_qty_storno 578336 578336 0 0.00 0 42459 79 0 0
sp_lock_selected_doc 19760 19760 0 0.00 0 11259 6 0 0
sp_make_cost_storno 26614 26614 0 0.00 0 14461 66 0 0
sp_multiply_rows_for_pdistr 26614 26614 0 0.00 0 19947 36 0 0
sp_multiply_rows_for_qdistr 19248 19248 0 0.00 0 46426 211 0 0
sp_pay_from_customer 6779 6779 0 0.00 4 11308 119 0 0
sp_pay_to_supplier 5631 5631 0 0.00 5 33989 156 0 0
sp_payment_common 12410 12410 0 0.00 1 33957 24 0 0
sp_qd_handle_on_cancel_clo 1671 1671 0 0.00 0 22570 369 0 0
sp_qd_handle_on_invoice_upd_sts 10221 10221 0 0.00 0 42458 438 0 0
sp_qd_handle_on_reserve_upd_sts 9464 9464 0 0.00 0 29258 65 0 0
sp_reserve_write_off 9464 9464 0 0.00 6 29286 206 0 0
sp_supplier_invoice 5337 5337 0 0.00 9 80903 3739 0 0
sp_supplier_order 5303 5303 0 0.00 6 24397 1343 0 0
srv_aggregate_perf_data 95 95 0 0.00 2437 721317 22271 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1454 133065 45343 0 0
srv_find_qd_qs_mism 1013327 1013327 0 0.00 0 22241 5 0 0
srv_make_invnt_saldo 427 427 0 0.00 55 129151 4187 0 0
srv_make_money_saldo 683 683 0 0.00 1 18698 667 0 0
srv_recalc_idx_stat 4 4 0 0.00 114638 532294 316823 0 0
t$perf-abend:sp_add_invoice_to_stock 156 156 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4754 4754 0 0.00 0 18634 206 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 0 2164 103 0 0
t$perf-norm:sp_cancel_client_order 1671 1671 0 0.00 0 5671 19 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 3053 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 681 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 440 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 655 12 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 22383 1856 0 0
t$perf-norm:sp_cancel_write_off 942 942 0 0.00 0 1175 8 0 0
t$perf-norm:sp_client_order 19248 19248 0 0.00 0 15345 24 0 0
t$perf-norm:sp_customer_reserve 1031 1031 0 0.00 0 2205 8 0 0
t$perf-norm:sp_pay_from_customer 6779 6779 0 0.00 0 6534 11 0 0
t$perf-norm:sp_pay_to_supplier 5631 5631 0 0.00 0 6189 10 0 0
t$perf-norm:sp_reserve_write_off 9464 9464 0 0.00 0 4490 11 0 0
t$perf-norm:sp_supplier_invoice 5337 5337 0 0.00 0 16813 23 0 0
t$perf-norm:sp_supplier_order 5303 5303 0 0.00 0 10750 21 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 210 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1585 29 0 0
t$perf-norm:srv_make_invnt_saldo 427 427 0 0.00 0 1700 19 0 0
t$perf-norm:srv_make_money_saldo 683 683 0 0.00 0 1401 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 17 8 0 0
v_add_invoice_to_stock 9792 9480 312 3.18 0 6977 18 0 312
v_all_customers 35392 35392 0 0.00 0 20 1 0 0
v_all_suppliers 29018 29018 0 0.00 0 48 1 0 0
v_all_wares 38496 38496 0 0.00 0 42 1 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 501 7 0 0
v_cancel_client_order 3342 3342 0 0.00 0 2449 5 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 6765 8 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 0 4006 8 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 4276 11 0 0
v_cancel_supplier_invoice 886 868 18 2.03 0 1213 16 0 18
v_cancel_supplier_order 780 780 0 0.00 0 417 5 0 0
v_cancel_write_off 1884 1884 0 0.00 0 2511 13 0 0
v_max_id_clo_ord 5303 5303 0 0.00 0 997 1 0 0
v_max_id_clo_res 1031 1031 0 0.00 0 1526 14 0 0
v_max_id_ord_sup 5320 5320 0 0.00 0 4135 4 0 0
v_max_non_paid_invoice 1745 1745 0 0.00 0 866 4 0 0
v_max_non_paid_realizn 3458 3458 0 0.00 0 2932 4 0 0
v_min_id_clo_ord 5303 5303 0 0.00 0 1383 7 0 0
v_min_id_clo_res 1031 1031 0 0.00 0 2626 8 0 0
v_min_id_ord_sup 5320 5320 0 0.00 0 6198 7 0 0
v_min_non_paid_invoice 5631 5631 0 0.00 0 33954 19 0 0
v_min_non_paid_realizn 6779 6779 0 0.00 0 5207 13 0 0
v_our_firm 9746 9746 0 0.00 0 17 1 0 0
v_reserve_write_off 18928 18928 0 0.00 0 4620 6 0 0
x_lock_depdocs_on_canc_invoice 11212 11212 0 0.00 0 11880 115 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 6944 369 0 0
x_make_qstorno_1000_1200 5303 5303 0 0.00 1 24386 1253 0 0
x_make_qstorno_1200_2000 5337 5337 0 0.00 2 68997 3543 0 0
x_make_qstorno_2100_3300 435839 435839 0 0.00 0 72465 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-11-2020 04:28:57 to 03-11-2020 04:29:01.

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
01:56:56 1 8192000000 8796633152 8807174144 3163616 0.038 1 1 2 0 3261312 61840 35856
01:57:57 25691 8192000000 9724905680 10022281216 801643632 8.913 100 83 166 0 829244848 7726800 19874416
01:59:27 17790 8192000000 9765094112 10071642112 840825856 9.308 100 88 176 0 875058272 13183040 21049376
02:00:54 20844 8192000000 9794348704 10112077824 860085136 9.501 100 85 170 1 899116400 19382160 19649104
02:02:21 15139 8192000000 9857627584 10190819328 909021056 9.988 100 77 154 0 968838576 43079488 16738032
02:03:40 12721 8192000000 9905967392 10245955584 949800000 10.389 100 88 176 0 1021085600 52553056 18732544
02:04:55 6814 8192000000 9965227440 10317705216 985688720 10.740 100 76 152 0 1071514480 70506512 15319248
02:06:03 4266 8192000000 9992210336 10353778688 1012591392 11.000 100 86 172 0 1108382368 78674288 17116688
02:07:10 4513 8192000000 10016288080 10379026432 1027397216 11.143 100 91 184 1 1113815008 69238256 17179536
02:08:16 1537 8192000000 10016335824 10387021824 1052212544 11.382 100 94 186 0 1133400240 61850368 19337328
02:09:19 7241 8192000000 9982941680 10378977280 1066413248 11.518 100 31 60 2 1101223600 28745216 6065136
02:10:28 27122 8192000000 9996407200 10378031104 1068003312 11.533 100 73 144 4 1098421888 15211632 15206944
02:11:55 6706 8192000000 10000949680 10394644480 1074472064 11.595 100 40 79 0 1113069712 30203664 8393984
02:13:04 10965 8192000000 9987100368 10398904320 1084131376 11.687 100 18 36 2 1102074000 14173488 3769136
02:14:17 5077 8192000000 9998011472 10404552704 1090717344 11.749 100 51 102 5 1114527584 12360448 11449792
02:15:24 71457 8192000000 10001136880 10418806784 1099599344 11.834 100 29 58 1 1134951664 29186352 6165968
02:17:42 9346 8192000000 10011186896 10423250944 1095831728 11.798 100 28 56 3 1121610944 19136432 6642784
02:18:52 5604 8192000000 9971004816 10368417792 1047350464 11.335 98 35 70 2 1071630304 16749920 7529920
02:19:58 6304 8192000000 9956388800 10340622336 1024735024 11.118 99 41 83 1 1060223904 26333792 9155088
02:21:05 43465 8192000000 9969910688 10350465024 988465728 10.767 95 32 65 1 1024281472 29256176 6559568
02:22:49 6120 8192000000 9918355072 10295259136 992416048 10.805 97 29 57 2 1021409152 21954256 7038848
02:23:56 6051 8192000000 9952563936 10339078144 1032246912 11.190 99 52 103 2 1062143152 18208176 11688064
02:25:05 11219 8192000000 9975389968 10368192512 1058130224 11.439 100 20 39 3 1086195536 23696128 4369184
02:26:18 73196 8192000000 9967300016 10372587520 1067752944 11.531 100 35 69 2 1095352496 20079776 7519776
02:28:42 23297 8192000000 10040928320 10436059136 1086452160 11.709 100 48 96 1 1143061088 46496992 10111936
02:30:09 12882 8192000000 10059823920 10458959872 1100529168 11.843 100 50 99 2 1172672544 61980736 10162640
02:31:27 15213 8192000000 10079755360 10478784512 1113104304 11.962 100 56 113 0 1181761024 56310336 12346384
02:32:52 20541 8192000000 10099058528 10510499840 1122111840 12.047 100 47 93 1 1213008656 80690592 10206224
02:34:20 8489 8192000000 10149565824 10561499136 1133201456 12.152 100 55 108 1 1258578800 114203824 11173520
02:35:33 3165 8192000000 10176896816 10577928192 1147850976 12.289 100 73 146 0 1280583600 117665632 15066992
02:36:43 21426 8192000000 10215049040 10616995840 1148679232 12.297 100 70 140 2 1315245856 151993680 14572944
02:38:08 6962 8192000000 10220777008 10626527232 1161898736 12.421 100 63 126 0 1332051792 156728112 13424944
02:39:18 9023 8192000000 10251499680 10673573888 1174264112 12.537 100 51 102 2 1356152096 170774112 11113872
02:40:35 2822 8192000000 10244997008 10642939904 1175211328 12.546 100 75 149 1 1342422224 151214560 15996336
02:41:42 4083 8192000000 10302911632 10704908288 1182736896 12.616 100 77 154 1 1399871360 199140880 17993584
02:42:51 1453 8192000000 10332281648 10731683840 1185508992 12.642 100 93 185 2 1430967056 225508672 19949392
02:43:58 1969 8192000000 10350876192 10746335232 1173910320 12.533 100 86 172 1 1440775488 247782064 19083104
02:45:06 2315 8192000000 10379378688 10773995520 1181405808 12.603 100 92 175 2 1475998064 275348208 19244048
02:46:13 1605 8192000000 10377394880 10784268288 1180540528 12.595 100 92 183 0 1483582688 281862480 21179680
02:47:17 9470 8192000000 10408096352 10825519104 1172934592 12.524 100 53 106 1 1506021008 319807552 13278864
02:48:38 5827 8192000000 10406572272 10827792384 1159008240 12.394 100 55 110 0 1505758928 334107984 12642704
02:49:49 6890 8192000000 10341276672 10767589376 1153422208 12.342 100 44 86 2 1440883040 276305312 11155520
02:50:59 4861 8192000000 10313563856 10746482688 1140114592 12.217 100 36 71 1 1432105248 283331136 8659520
02:52:13 17595 8192000000 10314068736 10742341632 1136557376 12.183 100 31 62 1 1428529600 283307376 8664848
02:53:36 7023 8192000000 10265682912 10685460480 1141950656 12.234 100 32 64 1 1370131824 220090496 8090672
02:54:50 24265 8192000000 10256595584 10673733632 1122873904 12.054 99 25 50 3 1346204928 217119552 6211472
02:56:17 23845 8192000000 10255145040 10730704896 1131215712 12.133 100 14 28 0 1340473184 205941680 3315792
02:57:47 25465 8192000000 10254045440 10684710912 1129363264 12.115 100 21 42 1 1354318064 219450992 5503808
02:59:35 37423 8192000000 10145305376 10628624384 1115880176 11.988 99 24 47 0 1267134480 145814240 5440064
03:01:13 47485 8192000000 10185198816 10665271296 1134834704 12.167 100 13 26 1 1319493888 181291184 3368000
03:03:12 63458 8192000000 10096306928 10511912960 1130770512 12.129 100 26 52 1 1162271424 26152512 5348400
03:05:47 48032 8192000000 10090820544 10513022976 1142620240 12.240 100 40 80 4 1175797328 24971600 8205488
03:07:35 56824 8192000000 10138370416 10569080832 1146320240 12.275 100 17 34 0 1217745616 67730320 3695056
03:09:59 34028 8192000000 10084452944 10504892416 1122812192 12.054 98 30 61 4 1147093216 18216896 6064128
03:11:33 124434 8192000000 10054839904 10485010432 1075905664 11.608 94 5 10 1 1123606768 46193184 1507920
03:14:44 103536 8192000000 10106785808 10543890432 1126781744 12.091 99 38 76 3 1169567056 35265472 7519840
03:17:34 71502 8192000000 10086383072 10556809216 1126617376 12.089 98 20 40 2 1177685360 47021136 4046848
03:19:48 44865 8192000000 10109371760 10546466816 1140319024 12.219 99 29 58 0 1199793392 52735808 6738560
03:21:34 103881 8192000000 10160707152 10646749184 1113563104 11.966 96 16 32 0 1229254256 111842448 3848704
03:25:22 46564 8192000000 10151079984 10665926656 1142147728 12.236 100 15 30 0 1286239440 140405728 3685984
03:27:36 32378 8192000000 10085798272 10589933568 1107498320 11.909 97 5 10 0 1145116800 36788848 829632
03:29:09 89673 8192000000 10100508048 10535292928 1138584656 12.202 99 11 22 0 1175684752 35017536 2082560
03:31:42 37220 8192000000 10046666864 10471030784 1106525792 11.900 97 28 57 0 1125624640 13252416 5846432
03:33:21 63100 8192000000 10077462064 10550190080 1125629552 12.080 99 16 32 0 1189039984 60250112 3160320
03:35:35 133065 8192000000 10045946272 10502737920 1032661408 11.194 91 12 23 2 1119189040 83876560 2651072
03:38:49 46420 8192000000 10061927744 10526027776 1103030752 11.866 98 8 15 1 1136294304 31471952 1791600
03:40:40 68165 8192000000 10068445504 10524389376 1125623376 12.080 99 28 56 1 1164137440 32700400 5813664
03:43:12 26405 8192000000 10074491552 10493636608 1131772112 12.138 100 59 118 1 1174174544 29626864 12775568
03:44:40 48866 8192000000 10080122656 10533109760 1132153008 12.142 99 16 31 3 1189624048 53911056 3559984
03:46:30 76001 8192000000 10076245696 10552541184 1103143904 11.867 97 14 28 0 1179563104 73250752 3168448
03:48:50 69244 8192000000 10121120416 10573512704 1096732592 11.807 97 15 31 0 1197433344 97107648 3593104
03:51:00 68880 8192000000 10123040048 10588123136 1092602352 11.767 97 29 58 1 1213067392 113913760 6551280
03:53:14 61672 8192000000 10087741040 10507014144 1087529488 11.719 96 22 43 0 1136738192 44276064 4932640
03:55:17 65338 8192000000 10173978656 10622914560 1128489424 12.107 99 21 42 0 1265779504 132609472 4680608
03:57:28 40590 8192000000 10093234416 10524844032 1097211712 11.811 97 21 42 0 1187836304 85754240 4870352
03:59:13 42403 8192000000 10073002384 10510319616 1104703696 11.882 98 45 91 2 1152444752 38089648 9651408
04:01:00 100306 8192000000 10145277760 10611875840 1124035824 12.065 99 23 46 2 1244256896 115435872 4785200
04:04:09 111193 8192000000 10068535808 10548854784 1117836272 12.007 98 12 24 0 1207556848 86931856 2788720
04:07:52 27811 8192000000 10095951136 10538319872 1142276272 12.237 100 26 51 0 1184852064 37835808 4739984
04:09:21 72290 8192000000 10105495312 10559205376 1111057296 11.942 97 19 38 0 1158072864 43094208 3921360
04:11:48 100444 8192000000 10148156320 10645733376 1112806080 11.959 96 13 26 1 1204096064 88164832 3125152
04:14:29 69495 8192000000 10123981376 10603835392 1141139696 12.226 100 7 14 0 1192589312 49951280 1498336
04:16:38 58214 8192000000 10130555824 10600751104 1136545024 12.183 100 34 68 1 1216339696 73012848 6781824
04:19:21 82787 8192000000 10094705552 10562936832 1129764880 12.119 99 11 22 0 1190656288 58495808 2395600
04:21:47 88983 8192000000 10071154832 10537689088 1128802848 12.110 99 19 38 0 1194858512 62029728 4025936
04:26:00 59823 8192000000 9332018144 9667555328 467806272 5.402 41 5 10 1 489492672 20944736 741664

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 156 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-11-2020 04:29:19 to 03-11-2020 04:29: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          226922
MON$OLDEST_ACTIVE               226923
MON$OLDEST_SNAPSHOT             226923
MON$NEXT_TRANSACTION            226923
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                       2123776
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             781
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2243 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2243 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2243 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.2243 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 ---
:37: 
:38: mscs-test-161.budzdorov.ru    Tue Nov  3 04:27:12 2020
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
--- end of diff output ---

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

03.11.20 04:29: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-03 04:29:24 No severe PSQL-related problems occured
03.11.20 04:29:25. End of report.