Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.12.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 16786088 kB
MemAvailable 31460404 kB
Buffers 0 kB
Cached 14776888 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 13:26:50
DTS_END                         2020-12-08 13:26:50.0990
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/41128
TRN_ID                          229083
ATT_ID                          682
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: 9204.28 19734 1104513
customer order: creation 166.63 464 19995
customer order: refuse 14.41 334 1729
order to supplier: creation 44.96 1462 5395
order to supplier: removal 3.28 124059 394
invoice (draft): creation 44.70 3534 5364
invoice (draft): removal 54.13 928 6495
invoice accept: apply 39.71 16467 4765
invoice accept: cancel 48.49 902 5819
customer reserve: creation 3718.83 158 446259
customer reserve: removal 4774.48 68 572937
realization accept: apply 80.84 162 9701
realization accept: cancel 88.77 109 10652
payment from customer: creation 57.08 117 6849
payment from customer: removal 5.65 90 678
payment to supplier: creation 47.96 121 5755
payment to supplier: removal 3.41 63 409
service: total inventory turnovers 3.84 3233 461
service: total monetary turnovers 6.13 484 736
service: aggregate perf. data 0.96 12424 115
service: refresh index statistics 0.04 229507 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-12-2020 13:27:32 to 08-12-2020 13:27:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7044
TEST_TIME 2 6311
TEST_TIME 3 6772
TEST_TIME 4 6520
TEST_TIME 5 6072
TEST_TIME 6 6401
TEST_TIME 7 6048
TEST_TIME 8 5983
TEST_TIME 9 5763
TEST_TIME 10 6083
TEST_TIME 11 6028
TEST_TIME 12 6315
TEST_TIME 13 6621
TEST_TIME 14 6860
TEST_TIME 15 7416
TEST_TIME 16 7937
TEST_TIME 17 7867
TEST_TIME 18 8509
TEST_TIME 19 8659
TEST_TIME 20 8644
TEST_TIME 21 8743
TEST_TIME 22 9561
TEST_TIME 23 10271
TEST_TIME 24 10321
TEST_TIME 25 11067
TEST_TIME 26 11328
TEST_TIME 27 11699
TEST_TIME 28 11519
TEST_TIME 29 11236
TEST_TIME 30 11093
TEST_TIME 31 10841
TEST_TIME 32 10762
TEST_TIME 33 10580
TEST_TIME 34 10635
TEST_TIME 35 10443
TEST_TIME 36 10270
TEST_TIME 37 10160
TEST_TIME 38 10007
TEST_TIME 39 10172
TEST_TIME 40 10066
TEST_TIME 41 10079
TEST_TIME 42 10024
TEST_TIME 43 10111
TEST_TIME 44 10133
TEST_TIME 45 10295
TEST_TIME 46 10338
TEST_TIME 47 10246
TEST_TIME 48 10241
TEST_TIME 49 10372
TEST_TIME 50 10271
TEST_TIME 51 10080
TEST_TIME 52 9917
TEST_TIME 53 9908
TEST_TIME 54 9848
TEST_TIME 55 9768
TEST_TIME 56 9689
TEST_TIME 57 9628
TEST_TIME 58 9666
TEST_TIME 59 9605
TEST_TIME 60 9559
TEST_TIME 61 9622
TEST_TIME 62 9622
TEST_TIME 63 9720
TEST_TIME 64 9774
TEST_TIME 65 9694
TEST_TIME 66 9719
TEST_TIME 67 9773
TEST_TIME 68 9821
TEST_TIME 69 9763
TEST_TIME 70 9659
TEST_TIME 71 9579
TEST_TIME 72 9480
TEST_TIME 73 9414
TEST_TIME 74 9352
TEST_TIME 75 9394
TEST_TIME 76 9353
TEST_TIME 77 9326
TEST_TIME 78 9292
TEST_TIME 79 9369
TEST_TIME 80 9370
TEST_TIME 81 9307
TEST_TIME 82 9380
TEST_TIME 83 9350
TEST_TIME 84 9435
TEST_TIME 85 9495
TEST_TIME 86 9560
TEST_TIME 87 9452
TEST_TIME 88 9417
TEST_TIME 89 9341
TEST_TIME 90 9336
TEST_TIME 91 9278
TEST_TIME 92 9289
TEST_TIME 93 9293
TEST_TIME 94 9278
TEST_TIME 95 9242
TEST_TIME 96 9213
TEST_TIME 97 9333
TEST_TIME 98 9290
TEST_TIME 99 9286
TEST_TIME 100 9294
TEST_TIME 101 9361
TEST_TIME 102 9322
TEST_TIME 103 9350
TEST_TIME 104 9368
TEST_TIME 105 9390
TEST_TIME 106 9304
TEST_TIME 107 9253
TEST_TIME 108 9239
TEST_TIME 109 9191
TEST_TIME 110 9157
TEST_TIME 111 9125
TEST_TIME 112 9172
TEST_TIME 113 9149
TEST_TIME 114 9137
TEST_TIME 115 9136
TEST_TIME 116 9114
TEST_TIME 117 9096
TEST_TIME 118 9258
TEST_TIME 119 9218
TEST_TIME 120 9208

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-12-2020 13:27:36 to 08-12-2020 13:27:39.

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 1517131 1517131 0 0.00 0 17285 6 0 0
doc_list_biud 1517131 1517131 0 0.00 0 87157 33 0 0
sp_add_invoice_to_stock 4900 4765 135 2.75 27 149654 16468 0 135
sp_cancel_adding_invoice 5819 5819 0 0.00 6 51449 903 0 0
sp_cancel_client_order 1729 1729 0 0.00 4 12022 334 0 0
sp_cancel_customer_reserve 572937 572937 0 0.00 1 87161 68 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 3 7363 90 0 0
sp_cancel_pay_to_supplier 409 409 0 0.00 3 2282 64 0 0
sp_cancel_supplier_invoice 6504 6495 9 0.13 2 18497 928 0 9
sp_cancel_supplier_order 394 394 0 0.00 13 1174105 124060 0 0
sp_cancel_write_off 10652 10652 0 0.00 1 16621 110 0 0
sp_client_order 19995 19995 0 0.00 4 76662 465 0 0
sp_customer_reserve 446259 446259 0 0.00 0 68267 158 0 0
sp_fill_shopping_cart 30739 30739 0 0.00 1 17224 45 0 0
sp_fill_shopping_cart_clo_res 446255 446255 0 0.00 0 29800 19 0 0
sp_get_clo_for_invoice 4759 4759 0 0.00 0 32133 421 0 0
sp_kill_cost_storno 17558 17558 0 0.00 0 7067 51 0 0
sp_kill_qstorno_ret_qs2qd 1163415 1163415 0 0.00 0 87154 37 0 0
sp_kill_qty_storno 612486 612486 0 0.00 0 87157 80 0 0
sp_lock_selected_doc 20030 20030 0 0.00 0 9306 7 0 0
sp_make_cost_storno 27064 27064 0 0.00 0 17282 52 0 0
sp_multiply_rows_for_pdistr 27064 27064 0 0.00 0 7906 28 0 0
sp_multiply_rows_for_qdistr 19995 19995 0 0.00 0 76649 286 0 0
sp_pay_from_customer 6849 6849 0 0.00 4 31267 117 0 0
sp_pay_to_supplier 5755 5755 0 0.00 5 19833 122 0 0
sp_payment_common 12604 12604 0 0.00 1 4789 16 0 0
sp_qd_handle_on_cancel_clo 1729 1729 0 0.00 0 12015 305 0 0
sp_qd_handle_on_invoice_upd_sts 10578 10578 0 0.00 0 25059 383 0 0
sp_qd_handle_on_reserve_upd_sts 9701 9701 0 0.00 0 8340 44 0 0
sp_reserve_write_off 9701 9701 0 0.00 7 17294 162 0 0
sp_supplier_invoice 5364 5364 0 0.00 6 89913 3535 0 0
sp_supplier_order 5395 5395 0 0.00 5 71581 1462 0 0
srv_aggregate_perf_data 115 115 0 0.00 2666 147871 12424 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1339 153350 48823 0 0
srv_find_qd_qs_mism 1057970 1057970 0 0.00 0 9036 5 0 0
srv_make_invnt_saldo 461 461 0 0.00 56 101321 3234 0 0
srv_make_money_saldo 736 736 0 0.00 1 21189 485 0 0
srv_recalc_idx_stat 5 5 0 0.00 63773 394005 229507 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 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 4765 4765 0 0.00 0 13623 144 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 1 6351 86 0 0
t$perf-norm:sp_cancel_client_order 1729 1729 0 0.00 0 3389 9 0 0
t$perf-norm:sp_cancel_customer_reserve 649 649 0 0.00 0 2431 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 2388 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 409 409 0 0.00 0 2080 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 369 369 0 0.00 0 1061 21 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 13777 1387 0 0
t$perf-norm:sp_cancel_write_off 955 955 0 0.00 0 2208 7 0 0
t$perf-norm:sp_client_order 19995 19995 0 0.00 0 9500 13 0 0
t$perf-norm:sp_customer_reserve 969 969 0 0.00 0 3461 9 0 0
t$perf-norm:sp_pay_from_customer 6849 6849 0 0.00 0 4117 7 0 0
t$perf-norm:sp_pay_to_supplier 5755 5755 0 0.00 0 3452 5 0 0
t$perf-norm:sp_reserve_write_off 9701 9701 0 0.00 0 8314 7 0 0
t$perf-norm:sp_supplier_invoice 5364 5364 0 0.00 0 10106 17 0 0
t$perf-norm:sp_supplier_order 5395 5395 0 0.00 0 9993 17 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 90 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 4475 73 0 0
t$perf-norm:srv_make_invnt_saldo 461 461 0 0.00 0 7179 22 0 0
t$perf-norm:srv_make_money_saldo 736 736 0 0.00 0 810 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9788 9518 270 2.75 0 6957 16 0 270
v_all_customers 36622 36622 0 0.00 0 16 1 0 0
v_all_suppliers 29846 29846 0 0.00 0 88 1 0 0
v_all_wares 39990 39990 0 0.00 0 100 1 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 419 6 0 0
v_cancel_client_order 3458 3458 0 0.00 0 4821 8 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 2875 8 0 0
v_cancel_customer_reserve 1298 1298 0 0.00 0 98 2 0 0
v_cancel_payment_to_supplier 818 818 0 0.00 0 2247 8 0 0
v_cancel_supplier_invoice 756 738 18 2.38 0 4410 22 0 18
v_cancel_supplier_order 788 788 0 0.00 0 493 3 0 0
v_cancel_write_off 1910 1910 0 0.00 0 1412 12 0 0
v_max_id_clo_ord 5390 5390 0 0.00 0 293 1 0 0
v_max_id_clo_res 969 969 0 0.00 0 4217 32 0 0
v_max_id_ord_sup 5354 5354 0 0.00 0 5537 5 0 0
v_max_non_paid_invoice 1576 1576 0 0.00 0 257 2 0 0
v_max_non_paid_realizn 3609 3609 0 0.00 0 1528 3 0 0
v_min_id_clo_ord 5390 5390 0 0.00 0 7206 6 0 0
v_min_id_clo_res 969 969 0 0.00 0 3929 14 0 0
v_min_id_ord_sup 5354 5354 0 0.00 0 5314 6 0 0
v_min_non_paid_invoice 5755 5755 0 0.00 0 2222 9 0 0
v_min_non_paid_realizn 6849 6849 0 0.00 0 4785 10 0 0
v_our_firm 9848 9848 0 0.00 0 13 1 0 0
v_reserve_write_off 19402 19402 0 0.00 0 2977 5 0 0
x_lock_depdocs_on_canc_invoice 11945 11945 0 0.00 0 15192 90 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 8995 291 0 0
x_make_qstorno_1000_1200 5395 5395 0 0.00 2 71571 1389 0 0
x_make_qstorno_1200_2000 5364 5364 0 0.00 3 89888 3359 0 0
x_make_qstorno_2100_3300 446259 446259 0 0.00 0 68240 137 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-12-2020 13:27:40 to 08-12-2020 13:27:44.

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:53 9 8192000000 8800235072 8812220416 5546896 0.067 2 1 2 0 5716336 105088 64352
10:57:55 16593 8192000000 9717181584 10009088000 792410800 8.819 100 86 173 0 820338800 7230800 20697200
10:59:18 22063 8192000000 9762026800 10067238912 837627376 9.276 100 91 182 0 871050704 10992384 22430944
11:00:47 26279 8192000000 9800682240 10121113600 873270192 9.633 100 80 160 0 907027568 15207072 18550304
11:02:16 13033 8192000000 9864471216 10196582400 913744256 10.034 100 80 159 3 972282064 40392288 18145520
11:03:31 11309 8192000000 9911028352 10258411520 960452896 10.493 100 77 154 3 1026708864 50327568 15928400
11:04:44 4788 8192000000 9959146608 10316861440 996264720 10.842 100 80 159 0 1080306496 68272960 15768816
11:05:51 3354 8192000000 9999897280 10361192448 1016666672 11.040 100 83 166 2 1112408880 79026480 16715728
11:06:56 1549 8192000000 10002882576 10366562304 1048602896 11.347 100 91 181 1 1108147392 40912816 18631680
11:07:58 10220 8192000000 10023416528 10398760960 1064548320 11.500 100 72 144 0 1125667584 45595520 15523744
11:09:11 4679 8192000000 9985461664 10380492800 1072791328 11.579 100 37 74 2 1107333456 26122704 8419424
11:10:20 39652 8192000000 10008550992 10399346688 1085333456 11.698 100 54 108 1 1130793696 34448336 11011904
11:12:01 5996 8192000000 10012539792 10409820160 1087995136 11.724 100 54 106 1 1141259840 40943984 12320720
11:13:10 6957 8192000000 10012216528 10410659840 1095804320 11.798 100 41 82 1 1136240592 31412752 9023520
11:14:17 11774 8192000000 10012300016 10419609600 1103399472 11.870 100 28 56 2 1124628224 15195840 6032912
11:15:30 23297 8192000000 10024047440 10417733632 1103710784 11.873 100 57 114 2 1134928416 19215728 12001904
11:16:57 4455 8192000000 10012911712 10419376128 1096686224 11.806 100 37 75 1 1137268256 32508240 8073792
11:18:04 9221 8192000000 10004860640 10415271936 1049117520 11.352 96 25 50 1 1094590576 40688080 4784976
11:19:14 11160 8192000000 9942512208 10329583616 1003467984 10.912 96 30 60 0 1028941456 18566000 6907472
11:20:27 43467 8192000000 9937554816 10329640960 984530928 10.728 95 43 86 1 1011768672 17287488 9950256
11:22:11 5341 8192000000 9960632544 10340421632 1021321712 11.085 99 42 82 3 1053928288 23518720 9087856
11:23:18 6496 8192000000 9963708576 10343501824 1037160240 11.237 99 38 75 4 1068922048 23958016 7803792
11:24:26 3850 8192000000 9973770912 10356244480 1058111120 11.438 100 60 121 4 1079615936 8305536 13199280
11:25:34 31139 8192000000 9970293696 10378674176 1059946384 11.456 100 47 95 1 1090129344 19266528 10916432
11:27:11 6685 8192000000 10013608976 10413944832 1086929440 11.713 100 48 95 0 1122619760 25939632 9750688
11:28:22 1910 8192000000 10074859712 10467364864 1106046688 11.895 100 69 138 1 1180161168 59162080 14952400
11:29:29 30196 8192000000 10105651360 10519957504 1117810464 12.006 100 55 109 4 1202733696 73114464 11808768
11:31:06 10927 8192000000 10097289696 10501287936 1141307360 12.228 100 59 117 2 1204900368 51706912 11886096
11:32:22 13878 8192000000 10141030112 10555125760 1146188400 12.274 100 60 116 2 1248599616 88745120 13666096
11:33:54 11959 8192000000 10201073936 10610425856 1166415520 12.463 100 51 100 0 1301742688 124121840 11205328
11:35:08 8577 8192000000 10230007328 10642903040 1177755952 12.569 100 51 102 1 1341758896 152118816 11884128
11:36:23 8483 8192000000 10288998544 10707587072 1184219120 12.630 100 56 112 0 1403681280 205816480 13645680
11:37:35 1757 8192000000 10326250384 10727481344 1174248848 12.537 100 85 168 2 1425552624 231689088 19614688
11:38:42 3604 8192000000 10371171312 10773839872 1168002272 12.478 99 75 148 1 1462303200 277698416 16602512
11:39:50 2331 8192000000 10378690352 10793689088 1175754688 12.551 100 68 136 0 1488088016 295896976 16436352
11:40:59 3594 8192000000 10380334000 10807271424 1176123360 12.554 100 85 170 0 1489232912 293166160 19943392
11:42:07 2300 8192000000 10420707120 10823335936 1166008784 12.460 99 78 157 2 1512917712 329234928 17674000
11:43:14 3952 8192000000 10377113760 10789687296 1135244736 12.171 98 72 142 5 1465572768 313372144 16955888
11:44:23 8419 8192000000 10381788912 10828849152 1148371216 12.294 100 48 96 0 1515196528 354790448 12034864
11:45:39 1337 8192000000 10414163120 10826444800 1155448720 12.361 100 70 138 0 1509404704 336827008 17128976
11:46:43 12394 8192000000 10426324496 10849423360 1133476704 12.154 100 36 72 1 1526406704 382368352 10561648
11:48:32 21753 8192000000 10331281904 10769027072 1120305760 12.030 100 34 68 1 1456910992 327246064 9359168
11:50:00 37947 8192000000 10253405984 10707902464 1107598528 11.910 99 17 33 0 1369622272 257714608 4309136
11:51:56 32004 8192000000 10141365696 10665713664 1108616480 11.919 100 13 26 1 1247427040 135686608 3123952
11:53:31 63920 8192000000 10064601936 10515226624 1117152400 12.000 100 10 20 0 1175343616 55800112 2391104
11:55:36 139397 8192000000 10053267840 10495647744 1103609056 11.872 99 32 64 0 1145000944 34461008 6930880
11:58:56 51176 8192000000 10117467152 10578542592 1128873808 12.111 99 17 33 3 1202770128 70266384 3629936
12:00:53 41039 8192000000 10100733488 10554789888 1123760240 12.063 99 8 16 0 1187073296 61951632 1361424
12:02:35 31595 8192000000 10119100928 10560307200 1107243216 11.906 97 15 30 0 1210958320 100347296 3367808
12:04:07 45560 8192000000 10150996144 10593058816 1116199584 11.991 98 21 40 3 1237776560 116276288 5300688
12:06:03 60495 8192000000 10146359200 10570235904 1124277984 12.067 99 29 60 0 1231606912 100793520 6535408
12:08:07 52112 8192000000 10161103360 10597801984 1148686640 12.297 100 20 40 0 1243710192 90443248 4580304
12:10:03 50799 8192000000 10165391184 10657144832 1157207728 12.377 100 17 34 0 1282142704 121011968 3923008
12:12:30 39038 8192000000 10077635264 10503806976 1137940640 12.196 99 30 59 5 1169992112 26120048 5931424
12:14:11 58012 8192000000 10118772448 10641879040 1158775184 12.392 100 21 42 0 1252132720 88910016 4447520
12:16:22 109584 8192000000 10091949728 10551189504 1148607216 12.296 99 22 43 1 1190789280 38097744 4084320
12:19:31 56975 8192000000 10106750880 10590822400 1156706176 12.372 100 10 20 3 1205601296 46779776 2115344
12:21:41 27290 8192000000 10070948064 10566471680 1141823280 12.233 99 5 10 0 1171627056 28854288 949488
12:23:09 61051 8192000000 10131602704 10605821952 1132825712 12.148 98 10 20 2 1201945168 66474496 2644960
12:25:10 27817 8192000000 10108375888 10554220544 1134223936 12.161 99 41 83 0 1191276560 48049888 9002736
12:26:46 72059 8192000000 10158766688 10656911360 1134330720 12.162 98 16 32 0 1238046560 99948320 3767520
12:29:06 55647 8192000000 10083500352 10541572096 1143421904 12.248 100 15 30 0 1201621424 55446944 2752576
12:31:02 41228 8192000000 10097659040 10541989888 1119857152 12.026 97 11 22 0 1177172384 55793040 1522192
12:32:43 24449 8192000000 10092946944 10513108992 1105541840 11.890 98 52 104 1 1153463632 37471520 10450272
12:34:13 37705 8192000000 10073013120 10530344960 1119334816 12.021 99 26 52 1 1197295120 72149408 5810896
12:35:52 120505 8192000000 10082708192 10541948928 1116257776 11.992 99 32 62 1 1169327840 46372112 6697952
12:39:00 76750 8192000000 10087731600 10549227520 1121323904 12.039 99 11 22 1 1185348400 61353440 2671056
12:41:18 77443 8192000000 10052608800 10528673792 1045801040 11.320 93 16 32 2 1112838832 63750256 3287536
12:43:37 83882 8192000000 10051553984 10507907072 1077244624 11.621 97 14 28 0 1150103104 69704976 3153504
12:46:05 97209 8192000000 10117734096 10548895744 1112414640 11.955 99 29 57 1 1219580592 101006400 6159552
12:48:50 52000 8192000000 10115148320 10563485696 1090828384 11.751 97 14 28 1 1177034368 83097328 3108656
12:50:44 39204 8192000000 10127618256 10586460160 1106178352 11.896 98 24 49 0 1188492448 76609936 5704160
12:52:41 153349 8192000000 10033766976 10497826816 1103778704 11.873 99 11 22 1 1141481312 35463440 2239168
12:56:33 82173 8192000000 10078416048 10557698048 1103072976 11.867 98 5 10 0 1141656464 37652512 930976
12:58:55 104203 8192000000 10079328384 10587168768 1120457648 12.031 99 10 20 0 1171867472 49356560 2053264
13:01:42 101598 8192000000 10130152368 10637021184 1118750240 12.015 97 28 56 0 1211874928 86499088 6625600
13:04:28 28765 8192000000 10080730688 10509078528 1110718944 11.939 97 44 88 0 1155119888 34493936 9907008
13:06:03 138347 8192000000 10084782192 10518331392 1139191776 12.208 100 20 39 1 1173336768 30215600 3929392
13:09:25 61215 8192000000 10133194560 10571931648 1148597728 12.296 100 36 71 2 1201317456 45291488 7428240
13:11:27 149460 8192000000 10094923568 10556215296 1142498864 12.239 99 19 38 1 1197018832 50605696 3914272
13:15:14 45369 8192000000 10083043440 10552147968 1145098272 12.263 99 8 16 1 1172868128 26160208 1609648
13:16:59 74282 8192000000 10103015568 10577969152 1133396816 12.153 99 17 34 1 1198912448 61660928 3854704
13:19:54 24181 8192000000 10089124544 10530590720 1122190608 12.048 98 19 36 1 1172678016 46102608 4384800
13:21:18 37019 8192000000 10118312144 10578538496 1114035088 11.971 97 26 52 2 1195667136 75684208 5947840
13:22:58 69584 8192000000 10189609488 10655199232 1094185504 11.782 96 22 44 0 1249753104 149927920 5639680
13:25:23 39445 8192000000 10066717168 10503557120 1101789856 11.855 98 13 26 0 1139819936 35503312 2526768

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 13:27:46 to 08-12-2020 13:28:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 135 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:01, from 08-12-2020 13:28:02 to 08-12-2020 13:28:03.

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          229082
MON$OLDEST_ACTIVE               231510
MON$OLDEST_SNAPSHOT             231510
MON$NEXT_TRANSACTION            231510
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                       2102912
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             795
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 ---
:168: 
:169: mscs-test-161.budzdorov.ru    Tue Dec  8 13:26:50 2020
:170:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:171: 
:172: 
:173: mscs-test-161.budzdorov.ru    Tue Dec  8 13:26:53 2020
:174:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:175: 
:176: 
:177: mscs-test-161.budzdorov.ru    Tue Dec  8 13:26:59 2020
:178:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:179: 
--- end of diff output ---

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

08.12.20 13:28:04. 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 13:28:07 No severe PSQL-related problems occured
08.12.20 13:28:08. End of report.