Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.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 16746124 kB
MemAvailable 31396684 kB
Buffers 0 kB
Cached 14879992 kB
SwapCached 2924 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-11 13:26:52
DTS_END                         2020-11-11 13:26:52.7390
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/33450
TRN_ID                          231687
ATT_ID                          692
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: 9308.46 25333 1117015
customer order: creation 162.67 416 19520
customer order: refuse 14.82 302 1778
order to supplier: creation 44.28 1318 5313
order to supplier: removal 3.42 120541 410
invoice (draft): creation 43.88 2995 5266
invoice (draft): removal 54.22 866 6506
invoice accept: apply 40.64 16805 4877
invoice accept: cancel 49.32 833 5918
customer reserve: creation 3816.63 162 457996
customer reserve: removal 4786.31 69 574357
realization accept: apply 81.98 161 9838
realization accept: cancel 88.35 124 10602
payment from customer: creation 56.35 99 6762
payment from customer: removal 5.29 53 635
payment to supplier: creation 46.45 109 5574
payment to supplier: removal 3.28 71 394
service: total inventory turnovers 3.74 3286 449
service: total monetary turnovers 5.94 515 713
service: aggregate perf. data 0.86 17121 103
service: refresh index statistics 0.03 340821 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-11-2020 13:27:42 to 11-11-2020 13:27:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2400
TEST_TIME 2 2955
TEST_TIME 3 3443
TEST_TIME 4 3844
TEST_TIME 5 4287
TEST_TIME 6 4449
TEST_TIME 7 4963
TEST_TIME 8 4797
TEST_TIME 9 4852
TEST_TIME 10 5018
TEST_TIME 11 5501
TEST_TIME 12 5926
TEST_TIME 13 6561
TEST_TIME 14 6643
TEST_TIME 15 7004
TEST_TIME 16 7548
TEST_TIME 17 7656
TEST_TIME 18 8160
TEST_TIME 19 8272
TEST_TIME 20 8396
TEST_TIME 21 8280
TEST_TIME 22 8839
TEST_TIME 23 9185
TEST_TIME 24 9991
TEST_TIME 25 11233
TEST_TIME 26 11377
TEST_TIME 27 11545
TEST_TIME 28 11208
TEST_TIME 29 10908
TEST_TIME 30 10639
TEST_TIME 31 10622
TEST_TIME 32 10415
TEST_TIME 33 10663
TEST_TIME 34 10565
TEST_TIME 35 10440
TEST_TIME 36 10277
TEST_TIME 37 10170
TEST_TIME 38 10012
TEST_TIME 39 10064
TEST_TIME 40 10102
TEST_TIME 41 10210
TEST_TIME 42 10359
TEST_TIME 43 10258
TEST_TIME 44 10270
TEST_TIME 45 10355
TEST_TIME 46 10397
TEST_TIME 47 10368
TEST_TIME 48 10409
TEST_TIME 49 10478
TEST_TIME 50 10537
TEST_TIME 51 10483
TEST_TIME 52 10486
TEST_TIME 53 10319
TEST_TIME 54 10234
TEST_TIME 55 10289
TEST_TIME 56 10154
TEST_TIME 57 10054
TEST_TIME 58 10020
TEST_TIME 59 10084
TEST_TIME 60 10022
TEST_TIME 61 10017
TEST_TIME 62 9993
TEST_TIME 63 9985
TEST_TIME 64 9955
TEST_TIME 65 9931
TEST_TIME 66 9978
TEST_TIME 67 10042
TEST_TIME 68 10077
TEST_TIME 69 10102
TEST_TIME 70 10118
TEST_TIME 71 9979
TEST_TIME 72 9918
TEST_TIME 73 9855
TEST_TIME 74 9826
TEST_TIME 75 9787
TEST_TIME 76 9724
TEST_TIME 77 9748
TEST_TIME 78 9706
TEST_TIME 79 9743
TEST_TIME 80 9773
TEST_TIME 81 9784
TEST_TIME 82 9729
TEST_TIME 83 9807
TEST_TIME 84 9765
TEST_TIME 85 9731
TEST_TIME 86 9767
TEST_TIME 87 9776
TEST_TIME 88 9805
TEST_TIME 89 9705
TEST_TIME 90 9613
TEST_TIME 91 9583
TEST_TIME 92 9516
TEST_TIME 93 9470
TEST_TIME 94 9402
TEST_TIME 95 9344
TEST_TIME 96 9435
TEST_TIME 97 9456
TEST_TIME 98 9426
TEST_TIME 99 9427
TEST_TIME 100 9408
TEST_TIME 101 9394
TEST_TIME 102 9467
TEST_TIME 103 9511
TEST_TIME 104 9496
TEST_TIME 105 9464
TEST_TIME 106 9518
TEST_TIME 107 9449
TEST_TIME 108 9399
TEST_TIME 109 9346
TEST_TIME 110 9300
TEST_TIME 111 9295
TEST_TIME 112 9263
TEST_TIME 113 9239
TEST_TIME 114 9219
TEST_TIME 115 9261
TEST_TIME 116 9221
TEST_TIME 117 9237
TEST_TIME 118 9229
TEST_TIME 119 9287
TEST_TIME 120 9312

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-11-2020 13:27:45 to 11-11-2020 13:27:49.

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 1539597 1539597 0 0.00 0 15891 6 0 0
doc_list_biud 1539597 1539597 0 0.00 0 95459 32 0 0
sp_add_invoice_to_stock 5018 4877 141 2.80 18 198836 16805 0 141
sp_cancel_adding_invoice 5918 5918 0 0.00 4 142792 834 0 0
sp_cancel_client_order 1778 1778 0 0.00 5 9562 302 0 0
sp_cancel_customer_reserve 574357 574357 0 0.00 1 95462 70 0 0
sp_cancel_pay_from_customer 635 635 0 0.00 3 1280 54 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 3 5961 72 0 0
sp_cancel_supplier_invoice 6521 6506 15 0.23 2 35287 866 0 15
sp_cancel_supplier_order 410 410 0 0.00 9 1194085 120541 0 0
sp_cancel_write_off 10602 10602 0 0.00 1 11688 124 0 0
sp_client_order 19520 19520 0 0.00 4 25968 417 0 0
sp_customer_reserve 457996 457996 0 0.00 0 145014 162 0 0
sp_fill_shopping_cart 30096 30096 0 0.00 1 17119 43 0 0
sp_fill_shopping_cart_clo_res 457990 457990 0 0.00 0 21387 17 0 0
sp_get_clo_for_invoice 4869 4869 0 0.00 0 30187 404 0 0
sp_kill_cost_storno 17549 17549 0 0.00 0 8282 53 0 0
sp_kill_qstorno_ret_qs2qd 1166232 1166232 0 0.00 0 95450 38 0 0
sp_kill_qty_storno 614278 614278 0 0.00 0 95458 80 0 0
sp_lock_selected_doc 20462 20462 0 0.00 0 11372 5 0 0
sp_make_cost_storno 27043 27043 0 0.00 0 13986 47 0 0
sp_multiply_rows_for_pdistr 27043 27043 0 0.00 0 8370 32 0 0
sp_multiply_rows_for_qdistr 19520 19520 0 0.00 0 25961 251 0 0
sp_pay_from_customer 6762 6762 0 0.00 4 7344 99 0 0
sp_pay_to_supplier 5574 5574 0 0.00 5 8407 109 0 0
sp_payment_common 12336 12336 0 0.00 1 7276 17 0 0
sp_qd_handle_on_cancel_clo 1778 1778 0 0.00 0 9556 274 0 0
sp_qd_handle_on_invoice_upd_sts 10787 10787 0 0.00 0 49172 343 0 0
sp_qd_handle_on_reserve_upd_sts 9838 9838 0 0.00 0 11789 45 0 0
sp_reserve_write_off 9838 9838 0 0.00 7 15015 161 0 0
sp_supplier_invoice 5266 5266 0 0.00 7 78557 2995 0 0
sp_supplier_order 5313 5313 0 0.00 5 36909 1319 0 0
srv_aggregate_perf_data 103 103 0 0.00 2481 143744 17121 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 978 184611 50075 0 0
srv_find_qd_qs_mism 1070258 1070258 0 0.00 0 15890 5 0 0
srv_make_invnt_saldo 449 449 0 0.00 50 134963 3286 0 0
srv_make_money_saldo 713 713 0 0.00 1 11187 515 0 0
srv_recalc_idx_stat 4 4 0 0.00 202988 478898 340821 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4877 4877 0 0.00 0 24331 174 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 0 6956 116 0 0
t$perf-norm:sp_cancel_client_order 1778 1778 0 0.00 0 2856 11 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 4086 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 635 635 0 0.00 0 3178 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 4046 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 861 16 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 26339 1464 0 0
t$perf-norm:sp_cancel_write_off 985 985 0 0.00 0 12590 21 0 0
t$perf-norm:sp_client_order 19520 19520 0 0.00 0 11196 15 0 0
t$perf-norm:sp_customer_reserve 1000 1000 0 0.00 0 622 3 0 0
t$perf-norm:sp_pay_from_customer 6762 6762 0 0.00 0 10917 12 0 0
t$perf-norm:sp_pay_to_supplier 5574 5574 0 0.00 0 6815 10 0 0
t$perf-norm:sp_reserve_write_off 9838 9838 0 0.00 0 10122 10 0 0
t$perf-norm:sp_supplier_invoice 5266 5266 0 0.00 0 7708 18 0 0
t$perf-norm:sp_supplier_order 5313 5313 0 0.00 0 31452 29 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 650 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 117 2 0 0
t$perf-norm:srv_make_invnt_saldo 449 449 0 0.00 0 636 5 0 0
t$perf-norm:srv_make_money_saldo 713 713 0 0.00 0 1099 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 12 5 0 0
v_add_invoice_to_stock 10018 9736 282 2.81 0 3684 14 0 282
v_all_customers 36242 36242 0 0.00 0 58 1 0 0
v_all_suppliers 29210 29210 0 0.00 0 16 1 0 0
v_all_wares 39040 39040 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 594 6 0 0
v_cancel_client_order 3556 3556 0 0.00 0 2500 3 0 0
v_cancel_customer_prepayment 1270 1270 0 0.00 0 1264 5 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 7126 9 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 718 4 0 0
v_cancel_supplier_invoice 866 836 30 3.46 0 6266 22 0 30
v_cancel_supplier_order 820 820 0 0.00 0 2090 13 0 0
v_cancel_write_off 1970 1970 0 0.00 0 4998 18 0 0
v_max_id_clo_ord 5312 5312 0 0.00 0 739 1 0 0
v_max_id_clo_res 1000 1000 0 0.00 0 7791 25 0 0
v_max_id_ord_sup 5263 5263 0 0.00 0 2410 4 0 0
v_max_non_paid_invoice 1544 1544 0 0.00 0 55 2 0 0
v_max_non_paid_realizn 3360 3360 0 0.00 0 689 2 0 0
v_min_id_clo_ord 5312 5312 0 0.00 0 3972 6 0 0
v_min_id_clo_res 1000 1000 0 0.00 0 9684 19 0 0
v_min_id_ord_sup 5263 5263 0 0.00 0 17048 16 0 0
v_min_non_paid_invoice 5574 5574 0 0.00 0 3449 9 0 0
v_min_non_paid_realizn 6762 6762 0 0.00 0 4925 11 0 0
v_our_firm 9602 9602 0 0.00 0 15 1 0 0
v_reserve_write_off 19676 19676 0 0.00 0 7081 5 0 0
x_lock_depdocs_on_canc_invoice 12006 12006 0 0.00 0 17982 100 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 3135 303 0 0
x_make_qstorno_1000_1200 5313 5313 0 0.00 2 36900 1241 0 0
x_make_qstorno_1200_2000 5266 5266 0 0.00 3 78551 2831 0 0
x_make_qstorno_2100_3300 457996 457996 0 0.00 0 145011 143 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-11-2020 13:27:50 to 11-11-2020 13:27:54.

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:56 2 8192000000 8796633152 8807174144 3163616 0.038 1 1 2 0 3261312 61840 35856
10:57:58 23150 8192000000 9706061648 10000277504 779849840 8.692 100 81 162 0 806869952 7338240 19681872
10:59:25 24158 8192000000 9749015136 10053111808 825102960 9.150 100 89 178 0 859022784 12616544 21303280
11:00:55 18706 8192000000 9806278208 10121388032 858091920 9.481 100 84 168 0 903210256 25718448 19399888
11:02:28 12798 8192000000 9862017600 10194407424 916020304 10.057 100 82 164 0 969234528 35828752 17385472
11:03:43 4724 8192000000 9937208384 10279780352 958871568 10.478 100 79 160 0 1048553936 73134016 16548352
11:04:50 4751 8192000000 9971855008 10327277568 990725296 10.789 100 82 161 1 1077597200 70399376 16472528
11:05:56 2020 8192000000 10010382096 10369888256 1017604784 11.049 100 94 186 2 1119177888 82759808 18813296
11:07:00 2018 8192000000 10022983056 10389446656 1044930784 11.312 100 89 177 2 1133546704 71405008 17210912
11:08:03 1581 8192000000 10038749840 10412851200 1055770800 11.416 100 76 152 1 1147254240 75237968 16245472
11:09:06 8653 8192000000 9987597184 10386194432 1064155472 11.496 100 33 66 1 1101805696 30528080 7122144
11:10:16 48063 8192000000 10002982352 10385817600 1073490240 11.585 100 75 151 3 1108155168 17342096 17322832
11:12:05 5774 8192000000 9986247568 10382045184 1075415120 11.604 100 34 65 3 1111211360 29087888 6708352
11:13:15 5969 8192000000 10004334512 10405441536 1085653568 11.701 100 37 74 1 1129732544 35890992 8187984
11:14:22 9615 8192000000 9985493536 10390331392 1081325712 11.660 99 21 42 0 1104690416 18730192 4634512
11:15:31 42970 8192000000 10026269184 10419638272 1093876800 11.780 100 63 126 3 1134137520 26960576 13300144
11:17:14 6965 8192000000 9995833136 10396266496 1050874592 11.369 98 42 84 2 1090397888 30968032 8555264
11:18:25 5986 8192000000 9987591552 10382790656 1053592768 11.395 98 37 74 2 1087537120 25945792 7998560
11:19:34 5590 8192000000 9927258016 10301784064 985582128 10.739 96 46 91 2 1007698496 11773824 10342544
11:20:43 61586 8192000000 9910925520 10305130496 946289888 10.355 93 38 76 3 970690000 16037616 8362496
11:22:47 4908 8192000000 9943986000 10323951616 1019072304 11.063 99 39 78 0 1048793376 21015616 8705456
11:23:53 3763 8192000000 9949415184 10336370688 1039601008 11.261 100 47 94 4 1069767888 20105264 10061616
11:25:00 8912 8192000000 9964438576 10362298368 1052968928 11.389 100 28 54 1 1074343168 14801456 6572784
11:26:12 36702 8192000000 9976387264 10385108992 1058226944 11.440 100 31 62 3 1087296688 22956800 6112944
11:27:57 33980 8192000000 10015251312 10411307008 1079115040 11.639 100 39 78 1 1116171712 29210368 7846304
11:29:35 26584 8192000000 10051980768 10450386944 1102314080 11.860 100 51 100 1 1163155216 50019040 10822096
11:31:06 6581 8192000000 10100478448 10499760128 1122760640 12.053 100 53 106 0 1219640048 85247696 11631712
11:32:18 9414 8192000000 10151669744 10552152064 1135789392 12.176 100 48 96 1 1254552880 108510800 10252688
11:33:29 6789 8192000000 10166738768 10565832704 1143140512 12.245 100 54 107 1 1270150720 114296576 12713632
11:34:39 10041 8192000000 10210386528 10623422464 1152662784 12.334 100 49 98 1 1319227408 155195664 11368960
11:35:55 10302 8192000000 10251089136 10656534528 1159350336 12.397 100 56 112 1 1344508688 173009904 12148448
11:37:14 8994 8192000000 10295867312 10707615744 1173824672 12.533 100 69 138 0 1397271952 207606336 15840944
11:38:29 6048 8192000000 10315074448 10724343808 1177663968 12.568 100 64 128 1 1416621328 224057712 14899648
11:39:40 2688 8192000000 10303836384 10710155264 1187799856 12.663 100 73 145 1 1407511200 203072656 16638688
11:40:47 5509 8192000000 10366005904 10774020096 1190693264 12.690 100 74 147 2 1465412176 258439456 16279456
11:41:59 976 8192000000 10393695472 10792685568 1188050448 12.665 100 93 184 1 1492922224 285070896 19800880
11:43:04 1819 8192000000 10340344224 10739511296 1141461424 12.229 99 85 169 2 1429638384 268336912 19840048
11:44:11 3436 8192000000 10390843136 10798612480 1166952480 12.468 100 74 146 2 1490842880 307273632 16616768
11:45:17 1368 8192000000 10374995472 10783113216 1165811264 12.458 100 68 135 0 1471112752 289211248 16090240
11:46:22 12916 8192000000 10405670624 10843443200 1167842032 12.477 100 36 72 1 1507744832 330274032 9628768
11:47:56 10744 8192000000 10484138320 10898866176 1144813056 12.261 100 48 95 1 1574476992 416481632 13182304
11:49:09 21857 8192000000 10378536784 10817351680 1124592688 12.070 99 26 52 0 1474434416 342775968 7065760
11:50:32 25460 8192000000 10351214144 10891640832 1128684784 12.109 100 28 56 3 1484521600 348004848 7831968
11:52:05 48745 8192000000 10084240800 10546204672 1066649584 11.520 95 11 22 2 1123331024 54450768 2230672
11:53:55 53885 8192000000 10064369152 10479325184 1107654544 11.910 99 38 76 1 1132400080 15550080 9195456
11:55:56 184611 8192000000 10120135152 10555686912 1083200240 11.678 96 43 86 4 1155983056 64059168 8723648
12:00:16 48393 8192000000 10044937792 10479112192 1094453456 11.785 98 19 38 0 1130753552 32448768 3851328
12:02:05 28193 8192000000 10081954416 10522550272 1114500784 11.975 99 22 44 0 1184183760 64678896 5004080
12:03:34 42246 8192000000 10163578576 10610417664 1136925712 12.187 100 17 34 1 1253239696 112391008 3922976
12:05:17 73806 8192000000 10195658720 10700021760 1141489488 12.230 100 15 30 1 1302213312 157257264 3466560
12:07:42 46125 8192000000 10063235232 10503270400 1137850272 12.195 100 25 49 1 1221706560 78247344 5608944
12:09:29 40745 8192000000 10151956624 10577829888 1127157008 12.095 98 35 70 0 1211831936 77101728 7573200
12:11:18 89106 8192000000 10118987168 10592141312 1145184112 12.264 100 17 34 1 1231006352 82122960 3699280
12:13:51 63920 8192000000 10131156880 10606600192 1158701520 12.391 100 28 55 3 1244784624 79998336 6084768
12:15:56 65828 8192000000 10097328752 10552516608 1106120576 11.896 96 18 36 1 1167213200 57536064 3556560
12:18:05 66674 8192000000 10144389712 10626961408 1112357360 11.955 97 23 46 0 1234305472 116621824 5326288
12:20:13 57867 8192000000 10106496848 10601590784 1134710496 12.166 100 15 30 0 1245492640 107112144 3670000
12:22:23 108395 8192000000 10139415776 10608828416 1145312000 12.265 100 18 35 1 1225246160 76353648 3580512
12:25:12 57899 8192000000 10096515456 10614038528 1119870544 12.026 98 8 16 1 1208079328 86261584 1947200
12:27:11 36791 8192000000 10126807584 10584010752 1139628832 12.212 99 32 64 1 1212422976 65983392 6810752
12:28:59 44149 8192000000 10081652480 10531209216 1119530992 12.023 98 17 33 4 1181114992 57930848 3653152
12:30:49 54151 8192000000 10139547280 10577625088 1105841472 11.893 97 34 68 0 1190390512 77135936 7413104
12:32:52 70017 8192000000 10079691424 10497863680 1099582560 11.834 98 23 46 1 1133025056 28702096 4740400
12:35:04 87139 8192000000 10055534176 10554335232 1072502880 11.576 96 14 28 1 1124793232 49379152 2911200
12:37:33 73926 8192000000 10046567856 10510233600 1105719040 11.892 99 20 40 0 1157020272 47352864 3948368
12:39:54 77489 8192000000 10064443616 10529042432 1106674096 11.901 99 14 28 0 1154019184 44119696 3225392
12:42:14 38838 8192000000 10069235168 10505003008 1076978464 11.619 96 16 32 1 1121268784 41174288 3116032
12:43:53 46275 8192000000 10091555920 10530689024 1060081712 11.457 94 27 53 1 1141418224 75213408 6123104
12:45:40 69798 8192000000 10060995440 10544373760 1091737232 11.759 98 15 30 0 1144103840 48735840 3630768
12:47:50 52278 8192000000 10081614400 10507558912 1096434064 11.804 98 28 57 1 1145057920 43015712 5608144
12:49:53 72495 8192000000 10065518080 10503639040 1097504592 11.814 98 10 19 2 1124811136 25439120 1867424
12:52:11 60683 8192000000 10075379280 10550255616 1105015504 11.885 98 37 74 0 1165916096 53797040 7103552
12:54:46 143920 8192000000 10059265328 10493956096 1124829440 12.073 99 34 68 1 1151361680 19723792 6808448
12:58:13 57443 8192000000 10075586896 10522640384 1108823872 11.921 98 11 23 1 1149313232 38113472 2375888
13:00:34 61787 8192000000 10140081376 10600308736 1142274304 12.237 100 14 28 1 1229669504 84331920 3063280
13:02:36 71038 8192000000 10071560736 10572443648 1126093664 12.085 99 16 32 0 1184638048 54882864 3661520
13:04:50 53964 8192000000 10078084320 10546302976 1126544896 12.089 100 27 54 2 1192901952 60354752 6002304
13:06:44 71155 8192000000 10136281248 10573746176 1153579072 12.343 100 23 46 0 1229312592 70563664 5169856
13:08:56 86406 8192000000 10080615696 10587922432 1082525408 11.672 95 9 18 1 1133552512 49415168 1611936
13:11:22 41570 8192000000 10102284784 10531614720 1128531152 12.108 99 31 62 0 1167909248 33415088 5963008
13:13:05 130409 8192000000 10057488192 10526187520 1124184944 12.067 100 8 16 0 1160452800 34797152 1470704
13:16:16 64331 8192000000 10049945968 10506002432 1112068976 11.952 98 5 10 0 1136638512 23498528 1071008
13:18:56 33957 8192000000 10072400704 10531057664 1115261808 11.982 98 16 33 2 1164411088 45742592 3406688
13:20:39 72716 8192000000 10080397200 10572169216 1091165264 11.754 96 18 36 2 1145532128 50397728 3969136
13:22:53 81454 8192000000 10128952304 10596225024 1095015600 11.790 96 22 44 1 1192030096 92061440 4953056
13:25:49 68476 8192000000 9572664912 9996111872 641895616 7.266 57 9 18 0 681608128 38103520 1608992

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 141 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

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

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          231984
MON$OLDEST_ACTIVE               231985
MON$OLDEST_SNAPSHOT             231985
MON$NEXT_TRANSACTION            231985
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                       2098112
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             792
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2250 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2250 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2250 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.2250 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 ---
:44: 
:45: mscs-test-161.budzdorov.ru    Wed Nov 11 13:27:11 2020
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:47: 
--- end of diff output ---

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

11.11.20 13:28:14. 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-11 13:28:17 No severe PSQL-related problems occured
11.11.20 13:28:18. End of report.