Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.11.2020 13: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 17032404 kB
MemAvailable 31476560 kB
Buffers 0 kB
Cached 14633176 kB
SwapCached 2892 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-02 13:26:50
DTS_END                         2020-11-02 13:26:50.9520
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(113:22) ==> SP_CANCEL_WRITE_OFF(41:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/33362
TRN_ID                          232631
ATT_ID                          666
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: 8771.12 29239 1052534
customer order: creation 165.85 465 19902
customer order: refuse 14.62 319 1754
order to supplier: creation 45.21 1330 5425
order to supplier: removal 3.11 120943 373
invoice (draft): creation 45.88 3985 5505
invoice (draft): removal 47.39 991 5687
invoice accept: apply 41.11 17418 4933
invoice accept: cancel 42.83 1015 5140
customer reserve: creation 3810.81 166 457297
customer reserve: removal 4273.77 70 512852
realization accept: apply 79.94 210 9593
realization accept: cancel 74.88 117 8986
payment from customer: creation 57.32 145 6878
payment from customer: removal 5.61 122 673
payment to supplier: creation 48.48 159 5818
payment to supplier: removal 3.50 103 420
service: total inventory turnovers 3.85 3202 462
service: total monetary turnovers 5.99 608 719
service: aggregate perf. data 0.95 13165 114
service: refresh index statistics 0.03 420239 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-11-2020 13:28:09 to 02-11-2020 13:28:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5567
TEST_TIME 2 5562
TEST_TIME 3 4848
TEST_TIME 4 4923
TEST_TIME 5 5383
TEST_TIME 6 5476
TEST_TIME 7 5114
TEST_TIME 8 5117
TEST_TIME 9 5042
TEST_TIME 10 5366
TEST_TIME 11 5871
TEST_TIME 12 6226
TEST_TIME 13 6622
TEST_TIME 14 6812
TEST_TIME 15 7395
TEST_TIME 16 7451
TEST_TIME 17 7536
TEST_TIME 18 7854
TEST_TIME 19 7967
TEST_TIME 20 7975
TEST_TIME 21 8154
TEST_TIME 22 8590
TEST_TIME 23 8666
TEST_TIME 24 8531
TEST_TIME 25 8837
TEST_TIME 26 9316
TEST_TIME 27 10192
TEST_TIME 28 11235
TEST_TIME 29 10885
TEST_TIME 30 10891
TEST_TIME 31 10726
TEST_TIME 32 10546
TEST_TIME 33 10508
TEST_TIME 34 10437
TEST_TIME 35 10299
TEST_TIME 36 10292
TEST_TIME 37 10175
TEST_TIME 38 10043
TEST_TIME 39 9834
TEST_TIME 40 9832
TEST_TIME 41 9850
TEST_TIME 42 9718
TEST_TIME 43 9529
TEST_TIME 44 9420
TEST_TIME 45 9339
TEST_TIME 46 9313
TEST_TIME 47 9339
TEST_TIME 48 9291
TEST_TIME 49 9317
TEST_TIME 50 9305
TEST_TIME 51 9214
TEST_TIME 52 9331
TEST_TIME 53 9330
TEST_TIME 54 9309
TEST_TIME 55 9264
TEST_TIME 56 9321
TEST_TIME 57 9277
TEST_TIME 58 9304
TEST_TIME 59 9361
TEST_TIME 60 9306
TEST_TIME 61 9227
TEST_TIME 62 9123
TEST_TIME 63 9102
TEST_TIME 64 9061
TEST_TIME 65 9004
TEST_TIME 66 8967
TEST_TIME 67 8933
TEST_TIME 68 8929
TEST_TIME 69 8922
TEST_TIME 70 9017
TEST_TIME 71 9031
TEST_TIME 72 9135
TEST_TIME 73 9159
TEST_TIME 74 9119
TEST_TIME 75 9133
TEST_TIME 76 9121
TEST_TIME 77 9240
TEST_TIME 78 9171
TEST_TIME 79 9067
TEST_TIME 80 9040
TEST_TIME 81 8986
TEST_TIME 82 8956
TEST_TIME 83 8889
TEST_TIME 84 8843
TEST_TIME 85 8847
TEST_TIME 86 8899
TEST_TIME 87 8897
TEST_TIME 88 8884
TEST_TIME 89 8892
TEST_TIME 90 8905
TEST_TIME 91 8928
TEST_TIME 92 8957
TEST_TIME 93 9008
TEST_TIME 94 9036
TEST_TIME 95 9037
TEST_TIME 96 9036
TEST_TIME 97 9022
TEST_TIME 98 8973
TEST_TIME 99 8942
TEST_TIME 100 8881
TEST_TIME 101 8848
TEST_TIME 102 8857
TEST_TIME 103 8833
TEST_TIME 104 8813
TEST_TIME 105 8881
TEST_TIME 106 8896
TEST_TIME 107 8856
TEST_TIME 108 8913
TEST_TIME 109 8902
TEST_TIME 110 8896
TEST_TIME 111 8946
TEST_TIME 112 8949
TEST_TIME 113 8961
TEST_TIME 114 8905
TEST_TIME 115 8832
TEST_TIME 116 8820
TEST_TIME 117 8810
TEST_TIME 118 8787
TEST_TIME 119 8793
TEST_TIME 120 8774

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-11-2020 13:28:13 to 02-11-2020 13:28:16.

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 1475287 1475287 0 0.00 0 21012 7 0 0
doc_list_biud 1475286 1475286 0 0.00 0 35868 31 0 0
sp_add_invoice_to_stock 5106 4933 173 3.38 37 173632 17419 0 173
sp_cancel_adding_invoice 5140 5140 0 0.00 5 70339 1015 0 0
sp_cancel_client_order 1754 1754 0 0.00 5 12997 319 0 0
sp_cancel_customer_reserve 512852 512852 0 0.00 1 35870 70 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 3 12095 123 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 5046 104 0 0
sp_cancel_supplier_invoice 5706 5687 19 0.33 2 33287 992 0 19
sp_cancel_supplier_order 373 373 0 0.00 7 1125683 120944 0 0
sp_cancel_write_off 8986 8986 0 0.00 1 13275 117 0 0
sp_client_order 19902 19902 0 0.00 4 29770 465 0 0
sp_customer_reserve 457297 457297 0 0.00 0 39535 167 0 0
sp_fill_shopping_cart 30820 30820 0 0.00 1 46761 59 0 0
sp_fill_shopping_cart_clo_res 457294 457294 0 0.00 0 25370 19 0 0
sp_get_clo_for_invoice 4925 4925 0 0.00 0 46031 501 0 0
sp_kill_cost_storno 15219 15219 0 0.00 0 16062 59 0 0
sp_kill_qstorno_ret_qs2qd 1040750 1040750 0 0.00 0 35851 38 0 0
sp_kill_qty_storno 549309 549309 0 0.00 0 35867 81 0 0
sp_lock_selected_doc 20166 20166 0 0.00 0 11224 6 0 0
sp_make_cost_storno 27213 27213 0 0.00 0 20318 63 0 0
sp_multiply_rows_for_pdistr 27213 27213 0 0.00 0 20993 40 0 0
sp_multiply_rows_for_qdistr 19902 19902 0 0.00 0 29761 276 0 0
sp_pay_from_customer 6878 6878 0 0.00 4 16910 145 0 0
sp_pay_to_supplier 5818 5818 0 0.00 4 17170 160 0 0
sp_payment_common 12696 12696 0 0.00 1 14208 36 0 0
sp_qd_handle_on_cancel_clo 1754 1754 0 0.00 0 12991 282 0 0
sp_qd_handle_on_invoice_upd_sts 10064 10064 0 0.00 0 28824 374 0 0
sp_qd_handle_on_reserve_upd_sts 9593 9593 0 0.00 0 11719 56 0 0
sp_reserve_write_off 9593 9593 0 0.00 6 21040 210 0 0
sp_supplier_invoice 5505 5505 0 0.00 6 137348 3986 0 0
sp_supplier_order 5425 5425 0 0.00 5 25021 1331 0 0
srv_aggregate_perf_data 114 114 0 0.00 3100 153989 13165 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1465 121293 47463 0 0
srv_find_qd_qs_mism 1005411 1005411 0 0.00 0 17366 5 0 0
srv_make_invnt_saldo 462 462 0 0.00 38 91117 3203 0 0
srv_make_money_saldo 719 719 0 0.00 1 15924 609 0 0
srv_recalc_idx_stat 3 3 0 0.00 270930 596166 420240 0 0
t$perf-abend:sp_add_invoice_to_stock 173 173 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 19 19 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4933 4933 0 0.00 0 13282 153 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 0 2226 115 0 0
t$perf-norm:sp_cancel_client_order 1754 1754 0 0.00 0 2062 10 0 0
t$perf-norm:sp_cancel_customer_reserve 646 646 0 0.00 0 1506 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 3258 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 730 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 8457 34 0 0
t$perf-norm:sp_cancel_supplier_order 373 373 0 0.00 0 24369 1525 0 0
t$perf-norm:sp_cancel_write_off 960 960 0 0.00 0 3090 9 0 0
t$perf-norm:sp_client_order 19902 19902 0 0.00 0 5516 18 0 0
t$perf-norm:sp_customer_reserve 976 976 0 0.00 0 1926 6 0 0
t$perf-norm:sp_pay_from_customer 6878 6878 0 0.00 0 8687 11 0 0
t$perf-norm:sp_pay_to_supplier 5818 5818 0 0.00 0 3413 8 0 0
t$perf-norm:sp_reserve_write_off 9593 9593 0 0.00 0 3844 7 0 0
t$perf-norm:sp_supplier_invoice 5505 5505 0 0.00 0 5494 19 0 0
t$perf-norm:sp_supplier_order 5425 5425 0 0.00 0 3434 16 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 389 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 75 2 0 0
t$perf-norm:srv_make_invnt_saldo 462 462 0 0.00 0 991 6 0 0
t$perf-norm:srv_make_money_saldo 719 719 0 0.00 0 499 3 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 2 26 12 0 0
v_add_invoice_to_stock 10194 9848 346 3.39 0 14718 21 0 346
v_all_customers 36214 36214 0 0.00 0 20 1 0 0
v_all_suppliers 30188 30188 0 0.00 0 53 1 0 0
v_all_wares 39804 39804 0 0.00 0 48 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 5556 15 0 0
v_cancel_client_order 3508 3508 0 0.00 0 5597 8 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 3294 16 0 0
v_cancel_customer_reserve 1292 1292 0 0.00 0 3056 16 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 4231 13 0 0
v_cancel_supplier_invoice 872 834 38 4.35 0 6331 29 0 38
v_cancel_supplier_order 746 746 0 0.00 0 3238 10 0 0
v_cancel_write_off 1920 1920 0 0.00 0 3783 17 0 0
v_max_id_clo_ord 5422 5422 0 0.00 0 7826 3 0 0
v_max_id_clo_res 976 976 0 0.00 0 3776 14 0 0
v_max_id_ord_sup 5495 5495 0 0.00 0 5299 5 0 0
v_max_non_paid_invoice 1641 1641 0 0.00 0 10546 8 0 0
v_max_non_paid_realizn 3738 3738 0 0.00 0 4889 4 0 0
v_min_id_clo_ord 5422 5422 0 0.00 0 11429 15 0 0
v_min_id_clo_res 976 976 0 0.00 0 3226 12 0 0
v_min_id_ord_sup 5495 5495 0 0.00 0 8758 15 0 0
v_min_non_paid_invoice 5818 5818 0 0.00 0 11939 23 0 0
v_min_non_paid_realizn 6878 6878 0 0.00 0 14196 32 0 0
v_our_firm 9870 9870 0 0.00 0 14 1 0 0
v_reserve_write_off 19186 19186 0 0.00 0 5244 7 0 0
x_lock_depdocs_on_canc_invoice 10410 10410 0 0.00 0 30989 145 0 0
x_lock_depdocs_on_canc_sup_ord 373 373 0 0.00 0 10937 423 0 0
x_make_qstorno_1000_1200 5425 5425 0 0.00 2 24974 1239 0 0
x_make_qstorno_1200_2000 5505 5505 0 0.00 3 137338 3755 0 0
x_make_qstorno_2100_3300 457297 457297 0 0.00 0 39532 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-11-2020 13:28:17 to 02-11-2020 13:28:21.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
10:56:54 1 8192000000 8796633152 8807174144 3163616 0.038 1 1 2 0 3261312 61840 35856
10:57:56 15140 8192000000 9710996944 10008997888 789377952 8.789 100 88 176 1 819664640 8148224 22138464
10:59:20 22891 8192000000 9756637440 10062970880 830221872 9.201 100 86 172 0 863691296 12638848 20830576
11:00:56 15806 8192000000 9792155216 10110869504 860953616 9.510 100 83 166 2 899515200 19458912 19102672
11:02:18 14901 8192000000 9878292640 10209087488 917033472 10.067 100 82 164 1 980470912 45979456 17457984
11:03:36 11297 8192000000 9911344240 10258546688 955851248 10.448 100 81 160 3 1021319232 48946352 16521632
11:04:49 3934 8192000000 9956218704 10309255168 984079312 10.724 100 84 163 0 1070614192 69307360 17227520
11:05:53 2915 8192000000 9991712864 10350968832 1013193520 11.006 100 86 172 1 1108773552 78156768 17423264
11:06:59 3972 8192000000 10011984064 10382073856 1033244640 11.200 100 82 162 1 1130512624 81493872 15774112
11:08:05 1616 8192000000 10035779616 10406146048 1054993072 11.409 100 92 183 0 1152843856 79262288 18588496
11:09:08 16147 8192000000 9973607056 10373099520 1058996256 11.447 100 32 64 0 1097742720 31597024 7149440
11:10:24 3524 8192000000 9978925440 10365534208 1068214800 11.535 100 39 73 4 1103334448 26853584 8266064
11:11:29 8809 8192000000 10005590208 10409717760 1072672656 11.578 100 24 48 1 1112740496 34986432 5081408
11:12:43 59022 8192000000 10021209136 10408755200 1086618544 11.710 100 60 117 2 1112972656 13873744 12480368
11:14:42 6566 8192000000 10024164448 10430349312 1088145744 11.725 100 41 81 3 1127633568 30842096 8645728
11:15:50 9327 8192000000 10016804256 10423361536 1095190416 11.792 100 45 90 1 1122694112 17575024 9928672
11:17:04 6368 8192000000 9975387504 10367053824 1047924688 11.341 97 43 84 0 1067353856 10123456 9305712
11:18:13 27419 8192000000 10005254528 10409480192 1048567232 11.347 97 49 95 1 1078715472 20262688 9885552
11:19:43 7763 8192000000 9938722064 10317410304 980193520 10.686 93 24 47 0 1004606048 19751856 4660672
11:20:51 6777 8192000000 9932191600 10304897024 1013526064 11.009 100 48 97 0 1058223152 34531040 10166048
11:22:02 11265 8192000000 9953597696 10341117952 1033526560 11.202 100 35 70 2 1068590368 27307200 7756608
11:23:16 40327 8192000000 9947414832 10338791424 1030448016 11.173 99 22 44 0 1052021568 15660016 5913536
11:24:57 5179 8192000000 9979666848 10374180864 1059241488 11.449 100 38 75 1 1089188512 21408528 8538496
11:26:03 3924 8192000000 9986682272 10375614464 1072244784 11.574 100 46 89 0 1097097600 15033280 9819536
11:27:10 7043 8192000000 10017728176 10403229696 1095866416 11.798 100 59 119 2 1122652304 13044112 13741776
11:28:23 67302 8192000000 10022474048 10434658304 1104804976 11.883 100 36 71 1 1133922256 20410672 8706608
11:30:46 21803 8192000000 10096018144 10500325376 1122110816 12.047 100 62 122 0 1211899392 77391712 12396864
11:32:17 15276 8192000000 10122457616 10522185728 1138475776 12.201 100 60 120 0 1228303088 77227008 12600304
11:33:40 25580 8192000000 10179177968 10581450752 1146417088 12.276 100 52 104 1 1263027792 105307280 11303424
11:35:15 8768 8192000000 10193871984 10596864000 1159665376 12.400 100 61 122 0 1289160544 115465184 14029984
11:36:32 3825 8192000000 10260704112 10666213376 1160064160 12.404 100 67 134 2 1362413216 186789904 15559152
11:37:43 2402 8192000000 10273024720 10677489664 1164469280 12.445 100 78 154 2 1377176368 195520256 17186832
11:38:49 3282 8192000000 10283350256 10689966080 1138734560 12.204 98 66 129 1 1370684144 216722656 15226928
11:39:56 4895 8192000000 10291578416 10702094336 1170583536 12.502 100 78 154 2 1390167456 202093120 17490800
11:41:06 2100 8192000000 10328505776 10733490176 1182005584 12.609 100 76 153 1 1424401152 226062480 16333088
11:42:10 1459 8192000000 10357074160 10755006464 1178710336 12.578 100 86 171 0 1447149680 249513296 18926048
11:43:18 3719 8192000000 10343109056 10742767616 1147275440 12.284 99 79 159 0 1439098736 273399824 18423472
11:44:31 2750 8192000000 10375272384 10787422208 1167080864 12.470 100 85 170 1 1478105920 290382640 20642416
11:45:43 2712 8192000000 10386250432 10786566144 1152642848 12.334 99 86 171 1 1473223824 301174448 19406528
11:46:47 3856 8192000000 10430775632 10838573056 1156288368 12.368 100 59 117 1 1526609152 355760960 14559824
11:47:56 5888 8192000000 10460032144 10878021632 1150033632 12.310 100 63 125 0 1567028080 401023344 15971104
11:49:10 13793 8192000000 10413872816 10854014976 1137977888 12.197 99 41 82 2 1499705952 351220368 10507696
11:50:26 12195 8192000000 10475905168 10897293312 1150385504 12.313 100 38 76 0 1566535936 405817408 10333024
11:51:41 4943 8192000000 10470682720 10917965824 1136903040 12.186 100 37 74 0 1580306992 432997536 10406416
11:52:51 27016 8192000000 10377679264 10859319296 1104276912 11.878 99 21 40 1 1488878400 378718512 5882976
11:54:21 72267 8192000000 10058240672 10502721536 1080049440 11.648 97 16 32 0 1153423616 70007632 3366544
11:56:35 67847 8192000000 10059858496 10493042688 1117859104 12.007 100 35 70 0 1181268144 56389792 7019248
11:58:44 46817 8192000000 10094179616 10568241152 1127186832 12.095 100 14 27 1 1181411120 51315568 2908720
12:00:40 82694 8192000000 10089587072 10524348416 1107624272 11.910 98 30 57 2 1164296256 50780880 5891104
12:03:03 91920 8192000000 10126152288 10595131392 1118274000 12.011 99 22 45 0 1186414944 63460576 4680368
12:05:55 47989 8192000000 10048587200 10531201024 1113815824 11.969 98 25 50 0 1177996496 58882080 5298592
12:07:50 61531 8192000000 10073979408 10531766272 1121307536 12.039 99 12 24 1 1178848048 54926224 2614288
12:10:25 41188 8192000000 10128807952 10571378688 1129165888 12.113 100 27 54 2 1232799312 97768784 5864640
12:12:07 89257 8192000000 10093308576 10564399104 1136898880 12.186 100 14 28 0 1225345664 85397264 3049520
12:14:39 101443 8192000000 10108959920 10588454912 1128435648 12.107 99 24 48 0 1195230336 61878960 4915728
12:17:27 92837 8192000000 10093303712 10570465280 1133084064 12.150 99 34 68 1 1193363056 52774624 7504368
12:20:00 53978 8192000000 10064075968 10514911232 1097155968 11.811 96 34 68 0 1137169600 32735440 7278192
12:22:00 100353 8192000000 10100480704 10548760576 1107318368 11.907 97 16 33 1 1158610272 48132528 3159376
12:24:40 57298 8192000000 10078019616 10541568000 1097981296 11.818 96 14 28 1 1145391680 44858480 2551904
12:26:38 118803 8192000000 10083126672 10545807360 1131569152 12.136 99 27 54 1 1185481328 48214544 5697632
12:29:56 39353 8192000000 10072535952 10577821696 1128659808 12.109 99 6 9 2 1172715104 43118464 936832
12:31:36 49362 8192000000 10091436400 10549497856 1144985696 12.262 100 18 36 0 1192636032 44018320 3632016
12:33:29 23257 8192000000 10096879184 10528800768 1119850864 12.026 97 53 103 1 1171371920 40044304 11476752
12:34:55 55392 8192000000 10170318752 10605375488 1104590352 11.881 97 23 46 0 1213987664 104170992 5226320
12:36:54 91001 8192000000 10103032000 10612416512 1112572256 11.957 98 25 50 0 1185975184 68163808 5239120
12:39:40 42230 8192000000 10052867824 10478256128 1091162176 11.754 97 24 48 1 1124688192 29274496 4251520
12:41:24 38599 8192000000 10061498896 10484961280 1075271376 11.602 96 29 57 1 1116472224 35671104 5529744
12:43:03 68744 8192000000 10049323296 10523004928 1068137520 11.534 96 12 24 1 1137916544 67307616 2471408
12:45:13 89275 8192000000 10030263712 10477223936 1106166192 11.896 99 26 52 1 1135883440 24343920 5373328
12:47:43 76308 8192000000 10022469312 10483712000 1102241648 11.859 99 9 18 0 1143074048 39106064 1726336
12:50:06 49648 8192000000 10077557280 10528518144 1098483408 11.823 98 11 22 0 1160393408 59560032 2349968
12:52:36 69468 8192000000 10051642976 10501361664 1099040016 11.829 98 25 50 1 1146062160 41706304 5315840
12:54:52 58484 8192000000 10130298224 10581946368 1121186032 12.038 99 29 58 2 1228498992 101137600 6175360
12:57:11 55889 8192000000 10079618192 10544660480 1111852272 11.950 98 18 36 0 1168635152 53233088 3549792
12:59:09 43362 8192000000 10067709584 10512850944 1122634864 12.052 99 18 36 0 1163448336 37513264 3300208
13:00:53 111349 8192000000 10101346480 10537418752 1093398368 11.775 96 16 32 1 1147214880 50915808 2900704
13:04:18 76874 8192000000 10067124368 10531811328 1137766544 12.195 100 17 33 1 1172364256 31059280 3538432
13:06:35 36682 8192000000 10087505136 10537766912 1116447152 11.993 98 13 26 0 1158120336 38676496 2996688
13:08:18 48562 8192000000 10068691904 10543812608 1084104160 11.687 96 12 24 1 1139589680 52918176 2567344
13:10:06 28279 8192000000 10103590480 10531307520 1120098224 12.028 99 39 76 3 1176515280 47636208 8780848
13:11:37 67540 8192000000 10085636480 10531663872 1105592752 11.891 98 18 37 0 1165334992 55914320 3827920
13:13:57 60417 8192000000 10080087744 10561093632 1112372832 11.955 98 30 60 1 1190563168 71731984 6458352
13:16:12 50153 8192000000 10115368656 10563624960 1108458576 11.918 98 22 44 0 1174537200 61562672 4515952
13:18:13 54818 8192000000 10088901552 10521063424 1123099472 12.056 99 18 36 1 1154977840 28452592 3425776
13:20:10 121293 8192000000 10103282448 10580553728 1142073824 12.235 100 15 30 1 1208315344 62797472 3444048
13:23:13 71456 8192000000 10083268512 10556223488 1115658960 11.986 98 9 18 0 1164688640 47114800 1914880
13:25:34 45461 8192000000 10072385968 10505723904 1121822912 12.044 99 21 42 1 1159880832 33762832 4295088

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-11-2020 13:28:23 to 02-11-2020 13:28:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 173 v_add_invoice_to_stock 335544517
except 19 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-11-2020 13:28:39 to 02-11-2020 13:28:41.

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          230556
MON$OLDEST_ACTIVE               233055
MON$OLDEST_SNAPSHOT             233055
MON$NEXT_TRANSACTION            233055
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                       2079648
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             794
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 ---
:16: 
:17: mscs-test-161.budzdorov.ru    Mon Nov  2 13:26:52 2020
:18:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:19: 
:20: 
:21: mscs-test-161.budzdorov.ru    Mon Nov  2 13:26:52 2020
:22:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:23: 
:24: 
:25: mscs-test-161.budzdorov.ru    Mon Nov  2 13:26:56 2020
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
:28: 
:29: mscs-test-161.budzdorov.ru    Mon Nov  2 13:27:00 2020
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
--- end of diff output ---

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

02.11.20 13:28:42. 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-02 13:28:44 No severe PSQL-related problems occured
02.11.20 13:28:45. End of report.