Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.12.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 16996408 kB
MemAvailable 31462736 kB
Buffers 0 kB
Cached 14575280 kB
SwapCached 1988 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-12-08 10:26:50
DTS_END                         2020-12-08 10:26:50.9110
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38882
TRN_ID                          224143
ATT_ID                          594
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: 8844.73 21455 1061367
customer order: creation 158.42 388 19010
customer order: refuse 13.71 349 1645
order to supplier: creation 43.47 1391 5216
order to supplier: removal 3.45 101833 414
invoice (draft): creation 41.85 3523 5022
invoice (draft): removal 51.38 820 6165
invoice accept: apply 39.51 17047 4741
invoice accept: cancel 46.10 779 5532
customer reserve: creation 3683.38 165 442006
customer reserve: removal 4482.68 62 537921
realization accept: apply 77.17 166 9260
realization accept: cancel 83.59 125 10031
payment from customer: creation 55.38 104 6646
payment from customer: removal 5.46 57 655
payment to supplier: creation 45.82 129 5498
payment to supplier: removal 3.31 85 397
service: total inventory turnovers 3.80 2813 456
service: total monetary turnovers 5.28 483 633
service: aggregate perf. data 0.96 12827 115
service: refresh index statistics 0.03 285944 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2586
TEST_TIME 2 4980
TEST_TIME 3 5767
TEST_TIME 4 4888
TEST_TIME 5 4636
TEST_TIME 6 4472
TEST_TIME 7 5083
TEST_TIME 8 5139
TEST_TIME 9 5848
TEST_TIME 10 6048
TEST_TIME 11 6137
TEST_TIME 12 6293
TEST_TIME 13 6440
TEST_TIME 14 7015
TEST_TIME 15 7416
TEST_TIME 16 7684
TEST_TIME 17 8150
TEST_TIME 18 8364
TEST_TIME 19 9120
TEST_TIME 20 9871
TEST_TIME 21 9805
TEST_TIME 22 9826
TEST_TIME 23 10379
TEST_TIME 24 11279
TEST_TIME 25 11688
TEST_TIME 26 11324
TEST_TIME 27 11028
TEST_TIME 28 10834
TEST_TIME 29 10658
TEST_TIME 30 10392
TEST_TIME 31 10282
TEST_TIME 32 10105
TEST_TIME 33 10246
TEST_TIME 34 10226
TEST_TIME 35 10038
TEST_TIME 36 9962
TEST_TIME 37 9871
TEST_TIME 38 9794
TEST_TIME 39 9815
TEST_TIME 40 9783
TEST_TIME 41 9652
TEST_TIME 42 9729
TEST_TIME 43 9817
TEST_TIME 44 9743
TEST_TIME 45 9747
TEST_TIME 46 9699
TEST_TIME 47 9742
TEST_TIME 48 9754
TEST_TIME 49 9860
TEST_TIME 50 9798
TEST_TIME 51 9826
TEST_TIME 52 10014
TEST_TIME 53 9856
TEST_TIME 54 9693
TEST_TIME 55 9594
TEST_TIME 56 9552
TEST_TIME 57 9526
TEST_TIME 58 9458
TEST_TIME 59 9388
TEST_TIME 60 9293
TEST_TIME 61 9316
TEST_TIME 62 9279
TEST_TIME 63 9317
TEST_TIME 64 9350
TEST_TIME 65 9372
TEST_TIME 66 9348
TEST_TIME 67 9335
TEST_TIME 68 9395
TEST_TIME 69 9331
TEST_TIME 70 9457
TEST_TIME 71 9401
TEST_TIME 72 9486
TEST_TIME 73 9483
TEST_TIME 74 9367
TEST_TIME 75 9294
TEST_TIME 76 9267
TEST_TIME 77 9282
TEST_TIME 78 9220
TEST_TIME 79 9248
TEST_TIME 80 9222
TEST_TIME 81 9174
TEST_TIME 82 9142
TEST_TIME 83 9143
TEST_TIME 84 9186
TEST_TIME 85 9179
TEST_TIME 86 9210
TEST_TIME 87 9284
TEST_TIME 88 9229
TEST_TIME 89 9331
TEST_TIME 90 9314
TEST_TIME 91 9239
TEST_TIME 92 9166
TEST_TIME 93 9128
TEST_TIME 94 9093
TEST_TIME 95 9024
TEST_TIME 96 8969
TEST_TIME 97 8939
TEST_TIME 98 8898
TEST_TIME 99 8905
TEST_TIME 100 8958
TEST_TIME 101 8936
TEST_TIME 102 8950
TEST_TIME 103 8974
TEST_TIME 104 9005
TEST_TIME 105 8988
TEST_TIME 106 9009
TEST_TIME 107 9090
TEST_TIME 108 9082
TEST_TIME 109 9078
TEST_TIME 110 9115
TEST_TIME 111 9158
TEST_TIME 112 9091
TEST_TIME 113 9037
TEST_TIME 114 8989
TEST_TIME 115 8939
TEST_TIME 116 8881
TEST_TIME 117 8858
TEST_TIME 118 8844
TEST_TIME 119 8889
TEST_TIME 120 8849

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-12-2020 10:28:13 to 08-12-2020 10: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 1468524 1468524 0 0.00 0 17329 6 0 0
doc_list_biud 1468516 1468516 0 0.00 0 75676 29 0 0
sp_add_invoice_to_stock 4877 4741 136 2.78 20 213066 17047 0 136
sp_cancel_adding_invoice 5532 5532 0 0.00 5 108064 780 0 0
sp_cancel_client_order 1645 1645 0 0.00 4 15132 349 0 0
sp_cancel_customer_reserve 537921 537921 0 0.00 1 75678 63 0 0
sp_cancel_pay_from_customer 655 655 0 0.00 3 2275 58 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 6916 85 0 0
sp_cancel_supplier_invoice 6178 6165 13 0.21 2 20909 820 0 13
sp_cancel_supplier_order 414 414 0 0.00 11 1077340 101834 0 0
sp_cancel_write_off 10031 10031 0 0.00 1 7677 126 0 0
sp_client_order 19010 19010 0 0.00 4 22549 389 0 0
sp_customer_reserve 442006 442006 0 0.00 0 71066 166 0 0
sp_fill_shopping_cart 29234 29234 0 0.00 1 19784 51 0 0
sp_fill_shopping_cart_clo_res 441999 441999 0 0.00 0 22086 17 0 0
sp_get_clo_for_invoice 4734 4734 0 0.00 0 46870 442 0 0
sp_kill_cost_storno 16615 16615 0 0.00 0 8482 55 0 0
sp_kill_qstorno_ret_qs2qd 1092452 1092452 0 0.00 0 75669 34 0 0
sp_kill_qty_storno 575701 575701 0 0.00 0 75676 72 0 0
sp_lock_selected_doc 19455 19455 0 0.00 0 9787 7 0 0
sp_make_cost_storno 26138 26138 0 0.00 0 15207 53 0 0
sp_multiply_rows_for_pdistr 26137 26137 0 0.00 0 9886 29 0 0
sp_multiply_rows_for_qdistr 19009 19009 0 0.00 0 22499 220 0 0
sp_pay_from_customer 6646 6646 0 0.00 4 20332 105 0 0
sp_pay_to_supplier 5498 5498 0 0.00 5 10282 129 0 0
sp_payment_common 12144 12144 0 0.00 1 5927 22 0 0
sp_qd_handle_on_cancel_clo 1645 1645 0 0.00 0 15125 309 0 0
sp_qd_handle_on_invoice_upd_sts 10265 10265 0 0.00 0 29361 320 0 0
sp_qd_handle_on_reserve_upd_sts 9260 9260 0 0.00 0 8515 40 0 0
sp_reserve_write_off 9260 9260 0 0.00 6 24504 167 0 0
sp_supplier_invoice 5022 5022 0 0.00 6 101921 3524 0 0
sp_supplier_order 5216 5216 0 0.00 5 41392 1391 0 0
srv_aggregate_perf_data 115 115 0 0.00 2410 112409 12827 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 1053 130087 52028 0 0
srv_find_qd_qs_mism 1015517 1015517 0 0.00 0 17328 5 0 0
srv_make_invnt_saldo 456 456 0 0.00 65 79377 2813 0 0
srv_make_money_saldo 633 633 0 0.00 1 13161 483 0 0
srv_recalc_idx_stat 4 4 0 0.00 137721 423950 285944 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4741 4741 0 0.00 0 7615 130 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 0 7100 96 0 0
t$perf-norm:sp_cancel_client_order 1645 1645 0 0.00 0 9707 19 0 0
t$perf-norm:sp_cancel_customer_reserve 658 658 0 0.00 0 2140 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 655 655 0 0.00 0 7705 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 397 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 1056 15 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 14741 1302 0 0
t$perf-norm:sp_cancel_write_off 909 909 0 0.00 0 5124 9 0 0
t$perf-norm:sp_client_order 19010 19010 0 0.00 0 6532 13 0 0
t$perf-norm:sp_customer_reserve 920 920 0 0.00 0 803 3 0 0
t$perf-norm:sp_pay_from_customer 6646 6646 0 0.00 0 9233 9 0 0
t$perf-norm:sp_pay_to_supplier 5498 5498 0 0.00 0 6222 10 0 0
t$perf-norm:sp_reserve_write_off 9260 9260 0 0.00 0 5366 8 0 0
t$perf-norm:sp_supplier_invoice 5022 5022 0 0.00 0 8748 17 0 0
t$perf-norm:sp_supplier_order 5216 5216 0 0.00 0 2010 11 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 885 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 160 6 0 0
t$perf-norm:srv_make_invnt_saldo 456 456 0 0.00 0 1874 11 0 0
t$perf-norm:srv_make_money_saldo 633 633 0 0.00 0 2905 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 9 4 0 0
v_add_invoice_to_stock 9736 9464 272 2.79 0 8445 17 0 272
v_all_customers 34706 34706 0 0.00 0 56 1 0 0
v_all_suppliers 28368 28368 0 0.00 0 18 1 0 0
v_all_wares 38018 38018 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 1553 6 0 0
v_cancel_client_order 3290 3290 0 0.00 0 12437 12 0 0
v_cancel_customer_prepayment 1310 1310 0 0.00 0 2264 7 0 0
v_cancel_customer_reserve 1316 1316 0 0.00 0 2304 5 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 1566 6 0 0
v_cancel_supplier_invoice 822 796 26 3.16 0 11124 31 0 26
v_cancel_supplier_order 828 828 0 0.00 0 13546 22 0 0
v_cancel_write_off 1818 1818 0 0.00 0 2589 11 0 0
v_max_id_clo_ord 5213 5213 0 0.00 0 2849 2 0 0
v_max_id_clo_res 920 920 0 0.00 0 2672 21 0 0
v_max_id_ord_sup 5012 5012 0 0.00 0 3042 5 0 0
v_max_non_paid_invoice 1539 1539 0 0.00 0 870 3 0 0
v_max_non_paid_realizn 3518 3518 0 0.00 0 1782 3 0 0
v_min_id_clo_ord 5213 5213 0 0.00 0 5337 17 0 0
v_min_id_clo_res 920 920 0 0.00 0 4431 23 0 0
v_min_id_ord_sup 5012 5012 0 0.00 0 8664 7 0 0
v_min_non_paid_invoice 5498 5498 0 0.00 0 5149 15 0 0
v_min_non_paid_realizn 6646 6646 0 0.00 0 5924 15 0 0
v_our_firm 9568 9568 0 0.00 0 27 1 0 0
v_reserve_write_off 18520 18520 0 0.00 0 12446 6 0 0
x_lock_depdocs_on_canc_invoice 11299 11299 0 0.00 0 20892 119 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 10340 321 0 0
x_make_qstorno_1000_1200 5216 5216 0 0.00 2 41351 1295 0 0
x_make_qstorno_1200_2000 5022 5022 0 0.00 3 101911 3334 0 0
x_make_qstorno_2100_3300 442006 442006 0 0.00 0 71065 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-12-2020 10:28:17 to 08-12-2020 10: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
07:56:54 10 8192000000 8803669040 8817008640 8589792 0.104 3 2 3 0 8788144 110464 87888
07:57:55 30612 8192000000 9719790768 10015330304 795735440 8.853 100 90 181 0 825530928 7754352 22041136
07:59:29 25194 8192000000 9765431280 10069118976 836802800 9.268 100 90 180 0 871584992 13053344 21728848
08:00:59 22037 8192000000 9806785200 10125463552 867479072 9.575 100 76 151 3 906537136 22099216 16958848
08:02:26 8863 8192000000 9871286896 10202148864 915451776 10.051 100 81 162 1 975003680 41576352 17975552
08:03:38 10191 8192000000 9918658224 10260803584 954168288 10.432 100 74 148 0 1025780688 56168368 15444032
08:04:51 5191 8192000000 9957948608 10309804032 993477520 10.815 100 84 168 4 1066043024 55786528 16778976
08:05:59 4670 8192000000 9992541472 10355974144 1022444208 11.096 100 83 167 0 1106664896 67699280 16521408
08:07:06 1655 8192000000 10045591136 10414571520 1049175136 11.353 100 89 178 0 1156443728 89274640 17993952
08:08:09 1941 8192000000 10038162112 10414821376 1057364224 11.431 100 86 170 0 1157285552 82859952 17061376
08:09:12 6164 8192000000 10014525120 10405580800 1061888688 11.475 100 46 92 1 1135832400 64504704 9439008
08:10:21 5146 8192000000 9990796160 10390437888 1070968320 11.561 100 33 68 3 1109105264 30834944 7302000
08:11:27 23016 8192000000 10008710656 10404737024 1079555008 11.643 100 61 121 4 1119732400 26883408 13293984
08:12:50 6860 8192000000 10016107936 10413707264 1084724816 11.692 100 36 72 1 1127320112 34838096 7757200
08:13:59 8211 8192000000 10012693584 10415661056 1090006752 11.743 100 50 101 3 1115979664 15040352 10932560
08:15:09 66833 8192000000 10035098848 10437591040 1098581248 11.824 100 39 77 4 1126719344 19927984 8210112
08:17:17 7178 8192000000 10012982800 10415939584 1101797680 11.855 100 46 92 1 1129083616 17026080 10259856
08:18:27 6322 8192000000 9981877648 10376204288 1042663696 11.290 97 38 78 0 1063664784 12948720 8052368
08:19:34 28018 8192000000 9913397568 10301198336 950984560 10.401 92 28 56 4 973851216 17558096 5308560
08:21:30 7077 8192000000 9914580624 10291372032 970657648 10.593 96 39 78 1 991746864 12287504 8801712
08:22:40 10814 8192000000 9938493280 10328551424 1020175280 11.074 99 34 69 2 1041655040 13082160 8397600
08:23:53 7421 8192000000 9968946416 10365489152 1047122096 11.333 100 46 92 0 1086765040 28590896 11052048
08:25:04 35209 8192000000 9967962400 10371555328 1057581440 11.433 100 21 42 1 1086713824 24299328 4833056
08:26:39 7317 8192000000 9989162416 10387337216 1064583952 11.500 100 22 44 0 1099822096 30576192 4661952
08:27:55 12407 8192000000 10030510736 10422898688 1078472224 11.633 100 59 119 2 1130137056 39300192 12364640
08:29:09 9552 8192000000 10066892032 10470690816 1103366128 11.870 100 54 103 6 1178004592 62877184 11761280
08:30:27 75910 8192000000 10138531248 10562916352 1120994544 12.036 100 35 70 3 1251178768 122590128 7594096
08:32:55 25351 8192000000 10171102608 10584010752 1128262224 12.105 100 38 74 1 1285064336 147925184 8876928
08:34:33 4643 8192000000 10214871632 10622976000 1143113680 12.245 100 61 122 1 1323362256 166869360 13379216
08:35:41 4577 8192000000 10206817360 10636218368 1153344608 12.341 100 42 84 2 1323101360 160397104 9359648
08:36:53 5604 8192000000 10273476704 10677477376 1177673568 12.568 100 64 127 1 1374337248 182953552 13710128
08:38:04 8678 8192000000 10317178608 10747363328 1185910992 12.645 100 60 117 1 1422756720 223842704 13003024
08:39:19 3382 8192000000 10370925808 10781081600 1195496560 12.734 100 84 167 0 1472837120 259078560 18262000
08:40:27 1049 8192000000 10427599744 10848366592 1193575280 12.717 100 89 176 2 1527449280 313677504 20196496
08:41:33 2543 8192000000 10443004272 10860670976 1182665184 12.615 99 69 137 0 1540208032 341601584 15941264
08:42:39 14346 8192000000 10421557072 10843201536 1164101008 12.442 99 45 90 2 1509452416 333833232 11518176
08:43:56 7651 8192000000 10421773136 10868690944 1148536960 12.296 99 50 98 1 1514619024 353511056 12571008
08:45:13 3714 8192000000 10404942192 10848501760 1142151456 12.236 100 61 122 2 1504769760 348092416 14525888
08:46:37 19312 8192000000 10305018384 10743828480 1133402640 12.153 99 30 60 2 1402937296 261824576 7710080
08:48:00 12098 8192000000 10347272880 10779533312 1141898112 12.233 99 25 50 2 1430950800 282741936 6310752
08:49:22 16753 8192000000 10344933232 10804281344 1110629520 11.938 98 27 54 1 1404145984 285923408 7593056
08:50:49 44210 8192000000 10072440656 10576285696 1108983312 11.923 98 16 32 1 1250646848 137982384 3681152
08:52:41 55692 8192000000 10040640688 10473934848 1096653296 11.806 97 42 82 3 1131728576 27350288 7724992
08:54:42 16900 8192000000 10073479920 10513678336 1087967040 11.723 97 39 78 1 1151889104 55485280 8436784
08:56:01 47343 8192000000 10092238160 10532085760 1123212080 12.057 100 13 26 0 1201423344 75222960 2988304
08:57:56 39296 8192000000 10102060384 10549047296 1088426224 11.728 98 11 22 1 1195072160 103917616 2728320
09:01:02 99334 8192000000 10026763344 10470486016 1054777488 11.406 95 12 24 0 1093461888 35762576 2921824
09:04:01 65808 8192000000 10048948208 10490662912 1088213504 11.726 98 14 28 1 1143963664 52888144 2862016
09:06:13 68273 8192000000 10061804528 10498326528 1109784800 11.930 99 19 38 1 1182183248 68097680 4300768
09:08:22 108977 8192000000 10055787008 10494435328 1121953744 12.045 99 10 20 0 1186478720 62345360 2179616
09:11:18 65319 8192000000 10100045232 10526003200 1143667936 12.250 100 37 73 1 1186526048 35159952 7698160
09:13:26 119176 8192000000 10129720848 10608922624 1133801072 12.157 99 22 44 0 1192013840 54162016 4050752
09:16:29 91996 8192000000 10186628656 10663714816 1154732960 12.354 100 13 26 0 1239123520 81447584 2942976
09:19:16 105387 8192000000 10094194960 10528763904 1146663168 12.278 100 16 32 1 1182515376 32422192 3430016
09:22:01 55929 8192000000 10096805392 10571001856 1142079120 12.235 99 11 22 0 1194667408 50074768 2513520
09:24:05 47510 8192000000 10073605264 10556002304 1136858816 12.186 99 7 13 2 1176123216 37368768 1895632
09:25:58 85012 8192000000 10104164720 10562805760 1132197664 12.142 98 22 45 1 1180208304 43545776 4464864
09:28:23 48231 8192000000 10120196048 10560991232 1106008640 11.895 96 33 66 2 1162465056 49405712 7050704
09:30:12 56029 8192000000 10097779136 10572816384 1119121920 12.019 98 23 46 2 1188635152 64518960 4994272
09:32:09 54837 8192000000 10086620848 10520264704 1104306320 11.878 97 21 42 2 1177600928 69054528 4240080
09:34:10 42332 8192000000 10101691440 10541469696 1114734096 11.977 98 26 52 1 1161185472 40952032 5499344
09:35:53 66855 8192000000 10075626400 10536800256 1129428016 12.116 100 22 44 1 1199599648 65974736 4196896
09:38:02 52882 8192000000 10088598592 10567557120 1137912192 12.196 100 26 52 0 1234934864 91890288 5132384
09:39:55 130087 8192000000 10104733248 10563420160 1094812320 11.788 96 26 52 1 1158056112 58132816 5110976
09:44:29 50427 8192000000 10092065344 10565033984 1130448352 12.126 99 15 30 1 1172341968 38313520 3580096
09:46:23 62265 8192000000 10074625760 10520387584 1113726288 11.968 98 16 32 2 1152336560 35410368 3199904
09:48:32 37261 8192000000 10131858352 10570063872 1128530544 12.108 99 20 40 0 1209219056 76252352 4436160
09:50:11 83664 8192000000 10119806272 10558042112 1082080064 11.667 95 19 37 1 1148347936 61910256 4357616
09:52:38 84608 8192000000 10054827584 10515664896 1061094464 11.467 95 8 16 0 1161042464 98423568 1524432
09:55:03 49700 8192000000 10059880544 10558701568 1065567248 11.510 95 18 36 2 1160305024 91220400 3517376
09:56:54 123568 8192000000 10063249520 10511298560 1097172832 11.811 98 32 64 0 1127867152 24165648 6528672
10:00:00 40484 8192000000 10044992064 10490347520 1111875120 11.950 100 12 24 1 1147450176 33243344 2331712
10:01:40 55772 8192000000 10061163952 10511859712 1095267920 11.793 98 9 18 2 1149584704 52429984 1886800
10:03:44 79851 8192000000 10104322768 10574147584 1124978352 12.074 100 8 16 0 1200100304 73245360 1876592
10:06:05 55415 8192000000 10079732096 10568298496 1123329568 12.058 98 26 52 1 1208745328 80243888 5171872
10:08:08 60888 8192000000 10105124784 10558849024 1129510432 12.117 99 17 34 0 1194476144 61470096 3495616
10:10:36 34639 8192000000 10108897440 10547138560 1120414960 12.031 98 15 29 1 1175405216 51722640 3267616
10:12:15 75729 8192000000 10095534976 10608852992 1147789120 12.289 100 17 34 0 1240625968 89452656 3384192
10:14:37 58937 8192000000 10110573904 10551562240 1120724608 12.034 97 35 70 0 1155197632 27733472 6739552
10:16:36 63573 8192000000 10113370080 10636324864 1129323584 12.115 98 25 50 0 1199633840 65380704 4929552
10:18:44 97106 8192000000 10067456432 10524561408 1148182368 12.292 100 19 36 0 1173729824 21842992 3704464
10:21:45 49901 8192000000 10093429264 10558115840 1161053360 12.413 100 8 16 0 1209755808 46783296 1919152
10:24:43 110754 8192000000 10112524080 10588598272 1146427936 12.276 99 22 44 2 1223265408 72441424 4396048

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-12-2020 10:28:23 to 08-12-2020 10:28:38.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-12-2020 10:28:38 to 08-12-2020 10:28:40.

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          223546
MON$OLDEST_ACTIVE               225377
MON$OLDEST_SNAPSHOT             225377
MON$NEXT_TRANSACTION            225377
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                       2076768
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             777
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2290 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2290 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2290 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.2290 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 ---
:151: 
:152: mscs-test-161.budzdorov.ru    Tue Dec  8 10:26:55 2020
:153:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:154: 
:155: 
:156: mscs-test-161.budzdorov.ru    Tue Dec  8 10:26:57 2020
:157:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:158: 
:159: 
:160: mscs-test-161.budzdorov.ru    Tue Dec  8 10:26:57 2020
:161:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:162: 
--- end of diff output ---

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

08.12.20 10:28:41. 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-12-08 10:28:44 No severe PSQL-related problems occured
08.12.20 10:28:45. End of report.