Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.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 16541436 kB
MemAvailable 31330740 kB
Buffers 0 kB
Cached 15013644 kB
SwapCached 4820 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-28 13:26:53
DTS_END                         2020-12-28 13:26:53.1380
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/46054
TRN_ID                          240766
ATT_ID                          659
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: 8828.06 23920 1059367
customer order: creation 174.86 346 20983
customer order: refuse 15.10 317 1812
order to supplier: creation 46.92 1345 5630
order to supplier: removal 3.13 119847 375
invoice (draft): creation 46.63 3534 5596
invoice (draft): removal 48.65 926 5838
invoice accept: apply 42.31 17417 5077
invoice accept: cancel 43.50 980 5220
customer reserve: creation 3838.68 173 460642
customer reserve: removal 4275.41 69 513049
realization accept: apply 84.10 163 10092
realization accept: cancel 80.55 119 9666
payment from customer: creation 60.04 96 7205
payment from customer: removal 5.71 86 685
payment to supplier: creation 48.27 118 5792
payment to supplier: removal 3.25 75 390
service: total inventory turnovers 3.82 3491 458
service: total monetary turnovers 6.23 531 748
service: aggregate perf. data 0.88 14362 105
service: refresh index statistics 0.03 314406 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 28-12-2020 13:28:10 to 28-12-2020 13:28:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5547
TEST_TIME 2 4979
TEST_TIME 3 5327
TEST_TIME 4 5300
TEST_TIME 5 6168
TEST_TIME 6 6027
TEST_TIME 7 5780
TEST_TIME 8 5568
TEST_TIME 9 5717
TEST_TIME 10 5889
TEST_TIME 11 6205
TEST_TIME 12 6285
TEST_TIME 13 6178
TEST_TIME 14 6427
TEST_TIME 15 6644
TEST_TIME 16 6840
TEST_TIME 17 7344
TEST_TIME 18 7636
TEST_TIME 19 7427
TEST_TIME 20 8058
TEST_TIME 21 8051
TEST_TIME 22 8410
TEST_TIME 23 8368
TEST_TIME 24 8686
TEST_TIME 25 8637
TEST_TIME 26 9015
TEST_TIME 27 9417
TEST_TIME 28 9740
TEST_TIME 29 10320
TEST_TIME 30 10999
TEST_TIME 31 10866
TEST_TIME 32 10708
TEST_TIME 33 10549
TEST_TIME 34 10408
TEST_TIME 35 10333
TEST_TIME 36 10399
TEST_TIME 37 10410
TEST_TIME 38 10231
TEST_TIME 39 10113
TEST_TIME 40 10248
TEST_TIME 41 10101
TEST_TIME 42 10058
TEST_TIME 43 10147
TEST_TIME 44 10081
TEST_TIME 45 9956
TEST_TIME 46 9997
TEST_TIME 47 9958
TEST_TIME 48 10003
TEST_TIME 49 9844
TEST_TIME 50 9686
TEST_TIME 51 9499
TEST_TIME 52 9498
TEST_TIME 53 9418
TEST_TIME 54 9466
TEST_TIME 55 9394
TEST_TIME 56 9377
TEST_TIME 57 9392
TEST_TIME 58 9324
TEST_TIME 59 9214
TEST_TIME 60 9217
TEST_TIME 61 9131
TEST_TIME 62 9308
TEST_TIME 63 9258
TEST_TIME 64 9331
TEST_TIME 65 9269
TEST_TIME 66 9328
TEST_TIME 67 9357
TEST_TIME 68 9312
TEST_TIME 69 9432
TEST_TIME 70 9410
TEST_TIME 71 9418
TEST_TIME 72 9382
TEST_TIME 73 9495
TEST_TIME 74 9422
TEST_TIME 75 9303
TEST_TIME 76 9253
TEST_TIME 77 9309
TEST_TIME 78 9233
TEST_TIME 79 9184
TEST_TIME 80 9127
TEST_TIME 81 9120
TEST_TIME 82 9092
TEST_TIME 83 9094
TEST_TIME 84 9085
TEST_TIME 85 9088
TEST_TIME 86 9116
TEST_TIME 87 9068
TEST_TIME 88 9042
TEST_TIME 89 9032
TEST_TIME 90 9143
TEST_TIME 91 9215
TEST_TIME 92 9263
TEST_TIME 93 9209
TEST_TIME 94 9240
TEST_TIME 95 9196
TEST_TIME 96 9161
TEST_TIME 97 9144
TEST_TIME 98 9108
TEST_TIME 99 9063
TEST_TIME 100 9028
TEST_TIME 101 8994
TEST_TIME 102 9068
TEST_TIME 103 9061
TEST_TIME 104 9022
TEST_TIME 105 9141
TEST_TIME 106 9121
TEST_TIME 107 9143
TEST_TIME 108 9182
TEST_TIME 109 9150
TEST_TIME 110 9082
TEST_TIME 111 9059
TEST_TIME 112 9012
TEST_TIME 113 8984
TEST_TIME 114 8961
TEST_TIME 115 8937
TEST_TIME 116 8886
TEST_TIME 117 8841
TEST_TIME 118 8833
TEST_TIME 119 8798
TEST_TIME 120 8829

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-12-2020 13:28:14 to 28-12-2020 13:28:17.

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 1486766 1486766 0 0.00 0 22352 6 0 0
doc_list_biud 1486764 1486764 0 0.00 0 85686 30 0 0
sp_add_invoice_to_stock 5217 5077 140 2.68 22 195783 17418 0 140
sp_cancel_adding_invoice 5220 5220 0 0.00 6 74145 981 0 0
sp_cancel_client_order 1812 1812 0 0.00 4 16171 318 0 0
sp_cancel_customer_reserve 513049 513049 0 0.00 1 85687 70 0 0
sp_cancel_pay_from_customer 685 685 0 0.00 3 2998 87 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 3 3686 76 0 0
sp_cancel_supplier_invoice 5849 5838 11 0.18 2 39838 927 0 11
sp_cancel_supplier_order 375 375 0 0.00 11 1179338 119848 0 0
sp_cancel_write_off 9666 9666 0 0.00 1 23233 120 0 0
sp_client_order 20983 20983 0 0.00 4 23357 347 0 0
sp_customer_reserve 460642 460642 0 0.00 0 112975 173 0 0
sp_fill_shopping_cart 32202 32202 0 0.00 1 17269 43 0 0
sp_fill_shopping_cart_clo_res 460638 460638 0 0.00 0 25325 18 0 0
sp_get_clo_for_invoice 5073 5073 0 0.00 0 22910 346 0 0
sp_kill_cost_storno 15961 15961 0 0.00 0 8020 56 0 0
sp_kill_qstorno_ret_qs2qd 1041977 1041977 0 0.00 0 85475 37 0 0
sp_kill_qty_storno 551123 551123 0 0.00 0 85686 81 0 0
sp_lock_selected_doc 20955 20955 0 0.00 0 8212 4 0 0
sp_make_cost_storno 28162 28162 0 0.00 0 19471 47 0 0
sp_multiply_rows_for_pdistr 28161 28161 0 0.00 0 6455 28 0 0
sp_multiply_rows_for_qdistr 20983 20983 0 0.00 0 23025 180 0 0
sp_pay_from_customer 7205 7205 0 0.00 4 13127 97 0 0
sp_pay_to_supplier 5792 5792 0 0.00 5 18899 119 0 0
sp_payment_common 12997 12997 0 0.00 1 18873 17 0 0
sp_qd_handle_on_cancel_clo 1812 1812 0 0.00 0 16136 293 0 0
sp_qd_handle_on_invoice_upd_sts 10291 10291 0 0.00 0 46225 403 0 0
sp_qd_handle_on_reserve_upd_sts 10092 10092 0 0.00 0 7986 55 0 0
sp_reserve_write_off 10092 10092 0 0.00 7 11564 163 0 0
sp_supplier_invoice 5596 5596 0 0.00 8 126703 3535 0 0
sp_supplier_order 5630 5630 0 0.00 6 36435 1345 0 0
srv_aggregate_perf_data 105 105 0 0.00 2674 197554 14363 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1916 175621 46263 0 0
srv_find_qd_qs_mism 1011913 1011913 0 0.00 0 22349 5 0 0
srv_make_invnt_saldo 458 458 0 0.00 46 88215 3492 0 0
srv_make_money_saldo 748 748 0 0.00 1 14908 532 0 0
srv_recalc_idx_stat 4 4 0 0.00 60821 491967 314407 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5077 5077 0 0.00 0 16452 154 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 1 2582 70 0 0
t$perf-norm:sp_cancel_client_order 1812 1812 0 0.00 0 2450 10 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 1369 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 685 685 0 0.00 0 791 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 411 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 1928 19 0 0
t$perf-norm:sp_cancel_supplier_order 375 375 0 0.00 0 20418 1520 0 0
t$perf-norm:sp_cancel_write_off 990 990 0 0.00 0 1566 6 0 0
t$perf-norm:sp_client_order 20983 20983 0 0.00 0 8165 16 0 0
t$perf-norm:sp_customer_reserve 1031 1031 0 0.00 0 1010 5 0 0
t$perf-norm:sp_pay_from_customer 7205 7205 0 0.00 0 6628 10 0 0
t$perf-norm:sp_pay_to_supplier 5792 5792 0 0.00 0 4604 10 0 0
t$perf-norm:sp_reserve_write_off 10092 10092 0 0.00 0 19785 10 0 0
t$perf-norm:sp_supplier_invoice 5596 5596 0 0.00 0 4576 14 0 0
t$perf-norm:sp_supplier_order 5630 5630 0 0.00 0 14607 18 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 294 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 439 9 0 0
t$perf-norm:srv_make_invnt_saldo 458 458 0 0.00 0 1805 9 0 0
t$perf-norm:srv_make_money_saldo 748 748 0 0.00 0 444 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 10422 10142 280 2.68 0 7570 19 0 280
v_all_customers 38262 38262 0 0.00 0 32 1 0 0
v_all_suppliers 30656 30656 0 0.00 0 17 1 0 0
v_all_wares 41966 41966 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 1599 8 0 0
v_cancel_client_order 3624 3624 0 0.00 0 6093 5 0 0
v_cancel_customer_prepayment 1370 1370 0 0.00 0 2919 12 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 5540 12 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 2009 7 0 0
v_cancel_supplier_invoice 848 826 22 2.59 0 1410 15 0 22
v_cancel_supplier_order 750 750 0 0.00 0 6679 27 0 0
v_cancel_write_off 1980 1980 0 0.00 0 3356 16 0 0
v_max_id_clo_ord 5630 5630 0 0.00 0 253 1 0 0
v_max_id_clo_res 1031 1031 0 0.00 0 3339 21 0 0
v_max_id_ord_sup 5588 5588 0 0.00 0 6596 6 0 0
v_max_non_paid_invoice 1681 1681 0 0.00 0 2516 4 0 0
v_max_non_paid_realizn 3845 3845 0 0.00 0 2606 3 0 0
v_min_id_clo_ord 5630 5630 0 0.00 0 7182 8 0 0
v_min_id_clo_res 1031 1031 0 0.00 0 4146 15 0 0
v_min_id_ord_sup 5588 5588 0 0.00 0 5145 7 0 0
v_min_non_paid_invoice 5792 5792 0 0.00 0 18869 13 0 0
v_min_non_paid_realizn 7205 7205 0 0.00 0 3973 9 0 0
v_our_firm 10424 10424 0 0.00 0 14 1 0 0
v_reserve_write_off 20184 20184 0 0.00 0 2899 5 0 0
x_lock_depdocs_on_canc_invoice 10645 10645 0 0.00 0 30719 131 0 0
x_lock_depdocs_on_canc_sup_ord 375 375 0 0.00 0 8690 364 0 0
x_make_qstorno_1000_1200 5630 5630 0 0.00 2 36418 1267 0 0
x_make_qstorno_1200_2000 5596 5596 0 0.00 3 126189 3374 0 0
x_make_qstorno_2100_3300 460642 460642 0 0.00 0 112974 153 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 28-12-2020 13:28:18 to 28-12-2020 13:28:23.

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 8 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
10:57:57 29881 8192000000 9713632896 10009149440 790589264 8.801 100 93 186 0 822294368 8131840 23573264
10:59:33 18232 8192000000 9755967632 10064347136 836670096 9.266 100 83 166 1 869444928 12638960 20135872
11:01:01 20394 8192000000 9811177760 10129846272 872639136 9.626 100 81 162 0 916414960 25096704 18679120
11:02:26 15530 8192000000 9865916096 10199072768 918949936 10.086 100 84 164 3 977547584 40421888 18175760
11:03:46 6749 8192000000 9923412784 10266464256 951367776 10.405 100 82 160 2 1039408752 71281136 16759840
11:04:58 4958 8192000000 9961683344 10315894784 990748224 10.789 100 84 168 1 1074097760 65904176 17445360
11:06:06 13683 8192000000 9977994176 10348216320 1032187168 11.190 100 77 155 0 1107753312 59427808 16138336
11:07:24 1338 8192000000 10048666544 10418446336 1059364032 11.450 100 91 182 1 1157312592 80145248 17803312
11:08:26 39103 8192000000 9972536528 10358206464 1062689072 11.482 100 40 80 2 1103543584 31543776 9310736
11:10:05 4733 8192000000 9997591536 10382471168 1066736080 11.521 100 33 65 2 1109157312 35765248 6655984
11:11:11 4865 8192000000 9998834048 10395054080 1076605936 11.615 100 53 106 1 1117032288 29572496 10853856
11:12:19 4208 8192000000 9990011440 10388140032 1077129952 11.620 100 36 72 2 1107834448 22655440 8049056
11:13:24 70059 8192000000 9982491792 10392477696 1080986496 11.657 100 27 54 3 1103164576 15990592 6187488
11:15:37 8621 8192000000 9989558112 10388271104 1076800112 11.617 99 37 71 2 1112145664 26871680 8473872
11:16:47 4557 8192000000 10017079104 10407608320 1073169136 11.582 99 52 101 5 1111007232 26850336 10987760
11:17:55 6062 8192000000 9978576320 10366775296 1027865584 11.148 97 47 94 0 1064319360 25864288 10589488
11:19:03 23641 8192000000 9959954032 10366996480 1013080928 11.005 96 45 89 1 1041397088 16879088 11437072
11:20:32 7234 8192000000 9905975728 10288087040 941899792 10.312 92 30 61 1 965308192 16168176 7240224
11:21:43 11211 8192000000 9942404544 10328023040 1017879440 11.052 99 33 64 0 1047178352 21977056 7321856
11:22:56 6274 8192000000 9949150784 10330988544 1039608032 11.261 100 52 107 1 1064579040 12963120 12007888
11:24:06 24257 8192000000 9985341392 10382925824 1048780544 11.349 100 30 59 2 1081072496 26123568 6168384
11:25:30 5493 8192000000 9975146496 10365693952 1063210176 11.487 100 51 104 1 1085735552 10653136 11872240
11:26:40 6609 8192000000 10001022832 10399875072 1085557808 11.700 100 30 58 1 1112109584 20602224 5949552
11:27:53 35525 8192000000 10063672464 10457640960 1107544016 11.909 100 57 114 0 1162876240 43517824 11814400
11:30:08 38014 8192000000 10077718672 10492399616 1125438944 12.078 100 44 86 3 1184785792 50231824 9115024
11:31:53 3385 8192000000 10112568560 10525118464 1139916944 12.215 100 62 120 0 1217787328 65128736 12741648
11:33:04 21335 8192000000 10122097344 10537832448 1148082432 12.291 100 43 86 1 1230386560 72967264 9336864
11:34:28 19298 8192000000 10165513200 10577358848 1154758288 12.354 100 49 97 2 1271549904 105750640 11040976
11:35:53 5760 8192000000 10187984736 10596696064 1146951472 12.281 100 60 121 0 1295885120 134884016 14049632
11:37:02 8406 8192000000 10216287152 10629873664 1159825712 12.402 100 48 93 1 1329624752 159346000 10453040
11:38:14 6808 8192000000 10251021568 10656604160 1160951536 12.412 99 72 144 3 1345058016 168651888 15454592
11:39:27 10023 8192000000 10270122864 10684731392 1173613840 12.531 100 58 116 1 1373735360 186448368 13673152
11:40:39 9647 8192000000 10329122608 10766626816 1177028704 12.562 100 69 136 1 1429175488 236975680 15171104
11:41:56 2754 8192000000 10314704960 10729177088 1154307248 12.350 99 78 154 2 1419958528 248762816 16888464
11:43:03 3548 8192000000 10344166976 10742722560 1152105712 12.329 100 72 144 0 1437758752 269526912 16126128
11:44:11 1911 8192000000 10332131248 10752450560 1142692432 12.241 99 75 150 2 1428524192 269065296 16766464
11:45:16 3402 8192000000 10329340880 10735124480 1139713312 12.213 100 68 135 2 1427425584 271344736 16367536
11:46:24 1913 8192000000 10384027952 10795483136 1128441488 12.107 99 90 179 1 1471064672 321945648 20677536
11:47:31 9233 8192000000 10363427056 10772234240 1122673952 12.052 99 49 97 0 1455087312 319433696 12979664
11:48:45 7794 8192000000 10376040368 10819260416 1148797712 12.298 100 47 94 0 1494422928 333578608 12046608
11:49:54 14788 8192000000 10443535616 10872741888 1158799984 12.392 100 38 76 0 1548271328 379039184 10432160
11:51:12 6553 8192000000 10419245776 10850861056 1151861024 12.327 100 39 76 1 1524928864 363052880 10014960
11:52:26 11726 8192000000 10419475904 10896662528 1155716560 12.363 100 29 58 0 1528905920 365616976 7572384
11:53:43 12465 8192000000 10372423360 10811973632 1140897472 12.224 99 36 72 1 1459114032 309398464 8818096
11:55:01 40879 8192000000 10291128304 10800558080 1128589168 12.108 100 17 34 1 1432112160 298702048 4820944
11:56:43 24879 8192000000 10051907920 10488131584 1115580384 11.985 100 23 44 1 1145249392 25003184 4665824
11:58:16 21433 8192000000 10079667440 10522480640 1114786464 11.978 99 27 54 1 1167158016 46411312 5960240
12:00:44 58844 8192000000 10074800032 10562048000 1098219744 11.821 97 11 22 0 1156010784 55812624 1978416
12:02:43 131616 8192000000 10070539632 10517463040 1090924192 11.751 97 9 19 1 1135036352 42521264 1590896
12:05:55 117486 8192000000 10081243872 10564554752 1109273168 11.926 98 12 24 0 1166975968 55311392 2391408
12:09:21 67024 8192000000 10081340720 10565230592 1125287008 12.077 99 13 27 0 1180376192 52512608 2576576
12:11:43 155852 8192000000 10082727520 10522411008 1091343808 11.755 95 12 24 2 1144326016 50604176 2378032
12:15:20 96359 8192000000 10092166304 10534260736 1132229424 12.142 99 39 72 3 1175188864 35592720 7366720
12:18:46 44127 8192000000 10087331328 10519232512 1123960048 12.064 99 32 65 1 1172719440 41862512 6896880
12:20:32 102690 8192000000 10066262240 10503974912 1034133984 11.208 91 22 42 0 1097235568 58572656 4528928
12:23:17 36074 8192000000 10053474208 10532397056 1099046416 11.829 98 16 29 2 1143658512 41401952 3210144
12:24:55 175599 8192000000 10114642880 10597019648 1037565328 11.241 93 6 12 0 1103051328 64558528 927472
12:29:44 42950 8192000000 10065897872 10498023424 1082637968 11.673 97 27 54 0 1137411248 49879216 4894064
12:31:36 40906 8192000000 10077767328 10527576064 1085217600 11.697 98 23 46 0 1168714592 78901792 4595200
12:33:17 34176 8192000000 10069204000 10481455104 1104764928 11.883 99 28 55 0 1148363120 38048528 5549664
12:34:58 23299 8192000000 10120868256 10553085952 1115295744 11.983 100 51 101 5 1215712160 89795968 10620448
12:36:25 29342 8192000000 10046228544 10469421056 1107941408 11.913 99 40 80 2 1139325424 23133712 8250304
12:38:08 35034 8192000000 10111451648 10544152576 1107752384 11.911 99 24 49 1 1171624656 58712224 5160048
12:39:44 54914 8192000000 10106858752 10552098816 1105448192 11.889 98 15 30 2 1183847760 75770736 2628832
12:41:39 79252 8192000000 10117536160 10578010112 1124122240 12.066 100 26 52 1 1217817952 87970672 5725040
12:43:59 45175 8192000000 10047747360 10569854976 1109587696 11.929 99 4 8 0 1150333040 40084512 660832
12:45:49 49230 8192000000 10023725392 10456690688 1075057408 11.600 96 10 19 0 1107996944 30861616 2077920
12:47:38 66324 8192000000 10086423792 10518089728 1101270784 11.850 99 24 49 2 1163243072 56750752 5221536
12:49:54 49226 8192000000 10054741200 10507616256 1063362688 11.489 96 18 36 0 1125342176 58177072 3802416
12:51:45 58727 8192000000 10085899040 10551144448 1114793696 11.978 100 28 56 0 1181262912 60178160 6291056
12:53:44 47054 8192000000 10144649200 10581950464 1117020832 11.999 98 29 57 1 1215799008 92593552 6184624
12:55:32 37322 8192000000 10211218896 10664325120 1126320672 12.087 99 20 40 0 1268809296 138046928 4441696
12:57:09 54792 8192000000 10125163104 10766798848 1137711376 12.194 100 9 18 1 1299994560 160715344 1567840
12:59:12 108371 8192000000 10087633056 10586939392 1130936096 12.130 99 25 51 0 1197450096 61421728 5092272
13:02:20 38446 8192000000 10072321968 10596646912 1123497744 12.060 99 5 10 1 1176931216 52460496 972976
13:03:58 79018 8192000000 10055944112 10493194240 1090756912 11.750 96 20 39 1 1118269104 23467904 4044288
13:06:18 51355 8192000000 10117392000 10562576384 1143925904 12.252 100 27 54 1 1202992800 53475552 5591344
13:08:17 71439 8192000000 10059771136 10539692032 1113301024 11.964 98 16 32 0 1205773216 88832704 3639488
13:10:30 26914 8192000000 10148829904 10586050560 1139680928 12.213 100 38 77 0 1214304688 67040656 7583104
13:11:57 64799 8192000000 10093231520 10539941888 1081073296 11.658 96 27 54 0 1132793264 46929728 4790240
13:14:02 71937 8192000000 10068446992 10513129472 1114618928 11.976 99 19 38 0 1166677904 48053600 4005376
13:16:14 128331 8192000000 10081604304 10546544640 1126306496 12.087 100 23 46 0 1197107920 65973968 4827456
13:19:33 65740 8192000000 10047733904 10520670208 1102128832 11.858 98 6 12 0 1135171888 31825248 1217808
13:22:12 62626 8192000000 10052852208 10495385600 1092967088 11.771 97 15 29 0 1129337152 33475504 2894560
13:24:30 61104 8192000000 10077793792 10538708992 1106209168 11.897 99 12 24 1 1162010192 53377072 2423952
13:26:31 29483 8192000000 9456897520 9849597952 571171408 6.517 51 8 14 1 638316208 65516336 1628464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-12-2020 13:28:24 to 28-12-2020 13:28:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 140 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

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

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          241101
MON$OLDEST_ACTIVE               241102
MON$OLDEST_SNAPSHOT             241102
MON$NEXT_TRANSACTION            241102
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                       2117504
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             811
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2307 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2307 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2307 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.2307 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 ---
:152: 
:153: mscs-test-161.budzdorov.ru    Mon Dec 28 13:27:06 2020
:154:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:155: 
:156: 
:157: mscs-test-161.budzdorov.ru    Mon Dec 28 13:27:10 2020
:158:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:159: 
--- end of diff output ---

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

28.12.20 13:28:43. 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-28 13:28:46 No severe PSQL-related problems occured
28.12.20 13:28:47. End of report.