Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.11.2020 07:28
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 16741968 kB
MemAvailable 31448980 kB
Buffers 0 kB
Cached 14950148 kB
SwapCached 3488 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-15 07:26:50
DTS_END                         2020-11-15 07:26:50.7720
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/35498
TRN_ID                          234722
ATT_ID                          594
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9179.17 31202 1101500
customer order: creation 166.06 402 19927
customer order: refuse 14.43 363 1731
order to supplier: creation 46.02 1346 5522
order to supplier: removal 3.15 136510 378
invoice (draft): creation 45.31 3489 5437
invoice (draft): removal 52.17 939 6260
invoice accept: apply 41.86 19750 5023
invoice accept: cancel 47.55 920 5706
customer reserve: creation 3900.58 192 468069
customer reserve: removal 4556.29 75 546755
realization accept: apply 82.59 195 9911
realization accept: cancel 94.43 128 11331
payment from customer: creation 58.63 102 7035
payment from customer: removal 6.02 126 722
payment to supplier: creation 49.42 140 5930
payment to supplier: removal 3.72 91 446
service: total inventory turnovers 3.55 3630 426
service: total monetary turnovers 6.53 605 783
service: aggregate perf. data 0.87 12884 104
service: refresh index statistics 0.03 442151 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-11-2020 07:29:13 to 15-11-2020 07:29:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7666
TEST_TIME 2 6357
TEST_TIME 3 5271
TEST_TIME 4 5125
TEST_TIME 5 5461
TEST_TIME 6 5430
TEST_TIME 7 5347
TEST_TIME 8 5553
TEST_TIME 9 5513
TEST_TIME 10 5726
TEST_TIME 11 6231
TEST_TIME 12 6143
TEST_TIME 13 6647
TEST_TIME 14 6523
TEST_TIME 15 6536
TEST_TIME 16 6712
TEST_TIME 17 6734
TEST_TIME 18 6844
TEST_TIME 19 7226
TEST_TIME 20 7212
TEST_TIME 21 7374
TEST_TIME 22 7488
TEST_TIME 23 7671
TEST_TIME 24 7723
TEST_TIME 25 7826
TEST_TIME 26 8272
TEST_TIME 27 8305
TEST_TIME 28 8389
TEST_TIME 29 9052
TEST_TIME 30 9283
TEST_TIME 31 9725
TEST_TIME 32 10341
TEST_TIME 33 11059
TEST_TIME 34 10958
TEST_TIME 35 10743
TEST_TIME 36 10770
TEST_TIME 37 10627
TEST_TIME 38 10646
TEST_TIME 39 10696
TEST_TIME 40 10469
TEST_TIME 41 10299
TEST_TIME 42 10112
TEST_TIME 43 10029
TEST_TIME 44 9949
TEST_TIME 45 10025
TEST_TIME 46 9946
TEST_TIME 47 9995
TEST_TIME 48 9901
TEST_TIME 49 9826
TEST_TIME 50 9740
TEST_TIME 51 9667
TEST_TIME 52 9712
TEST_TIME 53 9630
TEST_TIME 54 9597
TEST_TIME 55 9598
TEST_TIME 56 9691
TEST_TIME 57 9633
TEST_TIME 58 9672
TEST_TIME 59 9677
TEST_TIME 60 9622
TEST_TIME 61 9704
TEST_TIME 62 9752
TEST_TIME 63 9684
TEST_TIME 64 9800
TEST_TIME 65 9710
TEST_TIME 66 9582
TEST_TIME 67 9536
TEST_TIME 68 9502
TEST_TIME 69 9421
TEST_TIME 70 9344
TEST_TIME 71 9264
TEST_TIME 72 9320
TEST_TIME 73 9328
TEST_TIME 74 9306
TEST_TIME 75 9265
TEST_TIME 76 9302
TEST_TIME 77 9353
TEST_TIME 78 9380
TEST_TIME 79 9366
TEST_TIME 80 9374
TEST_TIME 81 9434
TEST_TIME 82 9466
TEST_TIME 83 9470
TEST_TIME 84 9480
TEST_TIME 85 9510
TEST_TIME 86 9405
TEST_TIME 87 9354
TEST_TIME 88 9299
TEST_TIME 89 9297
TEST_TIME 90 9229
TEST_TIME 91 9228
TEST_TIME 92 9279
TEST_TIME 93 9281
TEST_TIME 94 9261
TEST_TIME 95 9269
TEST_TIME 96 9281
TEST_TIME 97 9333
TEST_TIME 98 9337
TEST_TIME 99 9328
TEST_TIME 100 9375
TEST_TIME 101 9380
TEST_TIME 102 9293
TEST_TIME 103 9214
TEST_TIME 104 9237
TEST_TIME 105 9195
TEST_TIME 106 9172
TEST_TIME 107 9158
TEST_TIME 108 9134
TEST_TIME 109 9107
TEST_TIME 110 9146
TEST_TIME 111 9151
TEST_TIME 112 9176
TEST_TIME 113 9209
TEST_TIME 114 9205
TEST_TIME 115 9171
TEST_TIME 116 9152
TEST_TIME 117 9256
TEST_TIME 118 9235
TEST_TIME 119 9247
TEST_TIME 120 9184

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-11-2020 07:29:16 to 15-11-2020 07:29:20.

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 1532768 1532768 0 0.00 0 23818 7 0 0
doc_list_biud 1532768 1532768 0 0.00 0 29194 33 0 0
sp_add_invoice_to_stock 5186 5023 163 3.14 32 176143 19751 0 163
sp_cancel_adding_invoice 5706 5706 0 0.00 4 88731 921 0 0
sp_cancel_client_order 1731 1731 0 0.00 4 7926 363 0 0
sp_cancel_customer_reserve 546755 546755 0 0.00 1 17219 75 0 0
sp_cancel_pay_from_customer 722 722 0 0.00 3 29919 126 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 3 6220 92 0 0
sp_cancel_supplier_invoice 6272 6260 12 0.19 3 29216 939 0 12
sp_cancel_supplier_order 378 378 0 0.00 7 1199243 136510 0 0
sp_cancel_write_off 11331 11331 0 0.00 1 15335 128 0 0
sp_client_order 19927 19927 0 0.00 4 35392 402 0 0
sp_customer_reserve 468069 468069 0 0.00 0 91116 192 0 0
sp_fill_shopping_cart 30885 30885 0 0.00 1 21995 41 0 0
sp_fill_shopping_cart_clo_res 468060 468060 0 0.00 0 25299 17 0 0
sp_get_clo_for_invoice 5014 5014 0 0.00 0 25709 330 0 0
sp_kill_cost_storno 18205 18205 0 0.00 0 9101 66 0 0
sp_kill_qstorno_ret_qs2qd 1111479 1111479 0 0.00 0 29193 40 0 0
sp_kill_qty_storno 587086 587086 0 0.00 0 29193 86 0 0
sp_lock_selected_doc 20791 20791 0 0.00 0 11931 9 0 0
sp_make_cost_storno 27890 27890 0 0.00 0 14849 55 0 0
sp_multiply_rows_for_pdistr 27890 27890 0 0.00 0 8196 33 0 0
sp_multiply_rows_for_qdistr 19927 19927 0 0.00 0 15895 212 0 0
sp_pay_from_customer 7035 7035 0 0.00 4 10396 102 0 0
sp_pay_to_supplier 5930 5930 0 0.00 4 29184 140 0 0
sp_payment_common 12965 12965 0 0.00 1 8528 22 0 0
sp_qd_handle_on_cancel_clo 1731 1731 0 0.00 0 7914 337 0 0
sp_qd_handle_on_invoice_upd_sts 10720 10720 0 0.00 0 17998 400 0 0
sp_qd_handle_on_reserve_upd_sts 9911 9911 0 0.00 0 28071 50 0 0
sp_reserve_write_off 9911 9911 0 0.00 7 32513 196 0 0
sp_supplier_invoice 5437 5437 0 0.00 7 90830 3490 0 0
sp_supplier_order 5522 5522 0 0.00 6 32635 1346 0 0
srv_aggregate_perf_data 104 104 0 0.00 2590 164711 12885 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 2060 121514 39074 0 0
srv_find_qd_qs_mism 1052861 1052861 0 0.00 0 23818 6 0 0
srv_make_invnt_saldo 426 426 0 0.00 40 104254 3630 0 0
srv_make_money_saldo 783 783 0 0.00 1 12492 606 0 0
srv_recalc_idx_stat 4 4 0 0.00 318179 614930 442152 0 0
t$perf-abend:sp_add_invoice_to_stock 163 163 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5023 5023 0 0.00 0 30152 200 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 0 3075 110 0 0
t$perf-norm:sp_cancel_client_order 1731 1731 0 0.00 0 1866 12 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 16984 39 0 0
t$perf-norm:sp_cancel_pay_from_customer 722 722 0 0.00 0 1339 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 765 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 526 14 0 0
t$perf-norm:sp_cancel_supplier_order 378 378 0 0.00 0 28901 1912 0 0
t$perf-norm:sp_cancel_write_off 1020 1020 0 0.00 0 2216 10 0 0
t$perf-norm:sp_client_order 19927 19927 0 0.00 0 10225 18 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 15792 27 0 0
t$perf-norm:sp_pay_from_customer 7035 7035 0 0.00 0 4494 10 0 0
t$perf-norm:sp_pay_to_supplier 5930 5930 0 0.00 0 4034 10 0 0
t$perf-norm:sp_reserve_write_off 9911 9911 0 0.00 0 16872 13 0 0
t$perf-norm:sp_supplier_invoice 5437 5437 0 0.00 0 10591 26 0 0
t$perf-norm:sp_supplier_order 5522 5522 0 0.00 0 3582 17 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 523 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 9175 134 0 0
t$perf-norm:srv_make_invnt_saldo 426 426 0 0.00 0 6011 24 0 0
t$perf-norm:srv_make_money_saldo 783 783 0 0.00 0 608 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 35 10 0 0
v_add_invoice_to_stock 10354 10028 326 3.14 0 14357 16 0 326
v_all_customers 36720 36720 0 0.00 0 104 1 0 0
v_all_suppliers 30388 30388 0 0.00 0 83 1 0 0
v_all_wares 39854 39854 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 3821 13 0 0
v_cancel_client_order 3462 3462 0 0.00 0 1710 3 0 0
v_cancel_customer_prepayment 1444 1444 0 0.00 0 29905 27 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 1046 5 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 3015 8 0 0
v_cancel_supplier_invoice 864 840 24 2.77 0 1745 16 0 24
v_cancel_supplier_order 756 756 0 0.00 0 335 5 0 0
v_cancel_write_off 2040 2040 0 0.00 0 9810 20 0 0
v_max_id_clo_ord 5522 5522 0 0.00 0 1839 2 0 0
v_max_id_clo_res 993 993 0 0.00 0 2752 11 0 0
v_max_id_ord_sup 5436 5436 0 0.00 0 6311 7 0 0
v_max_non_paid_invoice 1694 1694 0 0.00 0 1979 3 0 0
v_max_non_paid_realizn 3620 3620 0 0.00 0 1654 3 0 0
v_min_id_clo_ord 5522 5522 0 0.00 0 4311 8 0 0
v_min_id_clo_res 993 993 0 0.00 0 7198 10 0 0
v_min_id_ord_sup 5436 5436 0 0.00 0 5429 8 0 0
v_min_non_paid_invoice 5930 5930 0 0.00 0 7149 17 0 0
v_min_non_paid_realizn 7035 7035 0 0.00 0 4611 12 0 0
v_our_firm 9964 9964 0 0.00 0 65 1 0 0
v_reserve_write_off 19822 19822 0 0.00 0 32453 9 0 0
x_lock_depdocs_on_canc_invoice 11546 11546 0 0.00 0 8850 117 0 0
x_lock_depdocs_on_canc_sup_ord 378 378 0 0.00 0 36580 554 0 0
x_make_qstorno_1000_1200 5522 5522 0 0.00 2 32628 1260 0 0
x_make_qstorno_1200_2000 5437 5437 0 0.00 3 90792 3342 0 0
x_make_qstorno_2100_3300 468069 468069 0 0.00 0 91115 172 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-11-2020 07:29:21 to 15-11-2020 07:29:25.

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
04:56:53 2 8192000000 8796641312 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
04:57:54 28238 8192000000 9726422928 10023018496 800754176 8.904 100 87 174 1 829072192 7765840 20552176
04:59:34 12156 8192000000 9756654032 10067353600 837372256 9.273 100 80 160 0 868757120 12810384 18574480
05:00:59 21915 8192000000 9811755520 10131202048 874459168 9.644 100 89 178 0 919697728 24852912 20385648
05:02:29 17462 8192000000 9869774544 10200543232 905926768 9.957 100 78 156 2 972471792 49734256 16810768
05:03:52 8555 8192000000 9895718864 10239049728 946372704 10.356 100 74 148 2 1006946144 43413760 17159680
05:05:03 5595 8192000000 9944934640 10291003392 981164416 10.696 100 81 160 2 1052942832 55698320 16080096
05:06:12 3170 8192000000 9998825360 10355257344 1010139008 10.977 100 89 177 1 1112847024 84840992 17867024
05:07:19 2881 8192000000 9998935344 10359779328 1034055552 11.207 100 81 158 1 1105345024 55641072 15648400
05:08:23 2121 8192000000 10022832000 10394968064 1053931296 11.398 100 89 176 0 1137221072 65146624 18143152
05:09:27 17664 8192000000 9959055440 10354765824 1055534944 11.414 100 22 44 0 1103724368 43875568 4313856
05:10:45 5408 8192000000 10003438768 10386841600 1069980976 11.552 100 37 74 3 1130545264 52818128 7746160
05:11:53 4632 8192000000 9986637952 10379202560 1078526736 11.633 100 35 66 3 1105630416 18708208 8395472
05:12:58 3936 8192000000 10003212144 10391810048 1084992224 11.695 100 43 85 3 1113989504 18954016 10043264
05:14:06 38667 8192000000 9998506112 10392956928 1086728016 11.712 100 38 74 3 1111573856 16772384 8073456
05:15:45 6602 8192000000 10020264352 10432847872 1091060432 11.753 100 45 91 1 1134508800 33252096 10196272
05:16:54 6530 8192000000 10013564224 10403405824 1091975056 11.761 100 32 62 1 1124618736 25670320 6973360
05:18:01 5321 8192000000 9999147824 10397089792 1053126688 11.391 97 44 86 4 1080002576 16775824 10100064
05:19:08 25903 8192000000 9988832720 10369695744 1049137360 11.352 98 55 111 4 1074696208 14235712 11323136
05:20:35 6775 8192000000 9934468064 10328928256 1007699520 10.953 98 46 90 2 1032592640 14580832 10312288
05:21:45 6432 8192000000 9926618688 10310680576 985768144 10.740 96 46 91 2 1009726624 13440976 10517504
05:22:52 9884 8192000000 9940312528 10336165888 1024211872 11.113 100 23 46 0 1051474480 21823696 5438912
05:24:02 42607 8192000000 9963730336 10357886976 1028132368 11.150 98 38 76 1 1064569888 28576784 7860736
05:25:46 2330 8192000000 9979996320 10366361600 1057573312 11.433 100 52 106 2 1090095056 21804080 10717664
05:26:49 7185 8192000000 9985330672 10377334784 1064310784 11.498 100 43 86 0 1094330032 20806352 9212896
05:28:04 43264 8192000000 10007685472 10409009152 1080362848 11.651 100 31 62 1 1121248160 33917392 6967920
05:29:54 30153 8192000000 10060884064 10466570240 1095417856 11.794 100 44 88 4 1164549472 59666064 9465552
05:31:29 4110 8192000000 10084146464 10480357376 1112830272 11.959 100 64 126 0 1188539152 62712448 12996432
05:32:44 22527 8192000000 10123175088 10525528064 1124961408 12.074 100 63 125 1 1225841184 87290288 13589488
05:34:09 9591 8192000000 10159321632 10561327104 1130970560 12.131 100 63 125 1 1257466784 112804576 13691648
05:35:25 2058 8192000000 10200255712 10613346304 1139584416 12.212 100 74 148 0 1308272272 152430416 16257440
05:36:30 5795 8192000000 10228673168 10635087872 1152068192 12.329 100 64 127 2 1333091952 167223344 13800416
05:37:42 5424 8192000000 10212467344 10633678848 1170655120 12.503 100 66 129 2 1324327680 138941440 14731120
05:38:58 9562 8192000000 10289353664 10702786560 1171678304 12.513 100 65 128 0 1394536096 208037760 14820032
05:40:12 2656 8192000000 10257713776 10664624128 1158289728 12.387 99 71 133 5 1356710640 183579872 14841040
05:41:21 4144 8192000000 10308394672 10717020160 1165414848 12.454 99 75 150 0 1405485296 224106832 15963616
05:42:29 3333 8192000000 10330444752 10732896256 1179174048 12.582 99 82 161 0 1429241856 232213696 17854112
05:43:35 4296 8192000000 10382680560 10789855232 1187913232 12.664 100 78 153 0 1484095536 278721200 17461104
05:44:43 3702 8192000000 10412084624 10823077888 1182779408 12.616 100 83 164 0 1509163248 308096000 18287840
05:45:54 2652 8192000000 10370477104 10765594624 1146964800 12.281 98 88 173 1 1469549696 302281968 20302928
05:47:04 4320 8192000000 10458048128 10852876288 1155015552 12.357 98 85 170 1 1530952288 355867568 20069168
05:48:16 4439 8192000000 10512389680 10918948864 1174651168 12.540 100 81 162 0 1604802224 409948080 20202976
05:49:25 11479 8192000000 10496889088 10944876544 1176869728 12.561 100 42 84 1 1608938896 420762528 11306640
05:50:41 9751 8192000000 10563181568 10991587328 1181979536 12.609 100 48 96 1 1660597120 466508688 12108896
05:51:53 9486 8192000000 10562317456 11004256256 1174160800 12.536 99 54 107 2 1667707024 480671840 12874384
05:53:10 15474 8192000000 10560050608 10997145600 1171385152 12.510 100 47 94 1 1650857056 466979552 12492352
05:54:51 17637 8192000000 10578946928 11081625600 1165485280 12.455 100 37 75 3 1706539088 530622032 10431776
05:56:09 41002 8192000000 10460445536 10970288128 1149316304 12.303 100 34 68 0 1593259728 435359056 8584368
05:57:56 23513 8192000000 10341382272 10877628416 1127695936 12.100 100 22 44 1 1451919936 318568928 5655072
05:59:22 70046 8192000000 10078099296 10658975744 1078980848 11.638 96 7 14 0 1172570720 91701920 1887952
06:01:39 23213 8192000000 10076487744 10496704512 1111514080 11.947 99 44 86 3 1151978768 31611248 8853440
06:03:11 95404 8192000000 10125519808 10559913984 1117901408 12.007 99 17 34 1 1190106064 68876736 3327920
06:05:50 66765 8192000000 10060631616 10513375232 1105803920 11.893 98 24 48 0 1135030080 24760096 4466064
06:08:34 28424 8192000000 10049595744 10497867776 1117843296 12.007 100 17 34 0 1154422768 33221776 3357696
06:10:04 27256 8192000000 10129393728 10589347840 1139875504 12.214 100 30 58 0 1231166384 85091840 6199040
06:11:58 53146 8192000000 10088385280 10527268864 1058786256 11.445 94 12 24 1 1145020048 83682800 2550992
06:13:56 37140 8192000000 10065015328 10512781312 1123804640 12.063 100 22 45 2 1153562992 24856352 4902000
06:15:34 17853 8192000000 10070730784 10529345536 1128337232 12.106 100 13 25 0 1175602528 44587600 2677696
06:16:53 80669 8192000000 10068002288 10598121472 1135227424 12.171 100 14 28 0 1229224064 91147680 2848960
06:20:25 53275 8192000000 10076409584 10561126400 1118002944 12.008 98 18 36 2 1199373680 77248032 4122704
06:22:53 31344 8192000000 10054567008 10490281984 1110579680 11.938 98 19 38 1 1138404432 24054432 3770320
06:24:28 32586 8192000000 10086892128 10524454912 1121074896 12.037 99 30 61 0 1142884000 15918800 5890304
06:26:02 20721 8192000000 10084277488 10521944064 1127497296 12.098 99 26 51 0 1171273024 38191984 5583744
06:27:24 46110 8192000000 10120817344 10606313472 1132656592 12.146 99 38 76 1 1228390432 87597152 8136688
06:29:11 39279 8192000000 10117431888 10558836736 1126602112 12.089 98 25 50 1 1173645680 41708064 5335504
06:30:56 25598 8192000000 10076390848 10503258112 1129080896 12.113 98 39 79 1 1154753184 17507200 8165088
06:32:23 121514 8192000000 10044633312 10506698752 1093190560 11.773 96 22 44 0 1150062112 52229952 4641600
06:35:28 36252 8192000000 10049790080 10498547712 1111758880 11.949 99 10 20 1 1140483888 26451824 2273184
06:37:09 103893 8192000000 10109861760 10587774976 1136252400 12.180 100 22 43 2 1215495888 74727664 4515824
06:39:55 36196 8192000000 10099505776 10544238592 1127648128 12.099 98 31 60 0 1180734960 47115504 5971328
06:41:33 54152 8192000000 10093675152 10537189376 1101617008 11.853 97 24 48 4 1184551536 77299232 5635296
06:43:32 51689 8192000000 10067138448 10545901568 1087549552 11.719 96 19 38 1 1134115168 42378112 4187504
06:45:24 43771 8192000000 10092688160 10543869952 1127790224 12.101 100 25 50 0 1174900496 41501376 5608896
06:47:09 47141 8192000000 10133141392 10606620672 1121108768 12.037 99 30 61 3 1189243072 61677952 6456352
06:48:56 63236 8192000000 10068996944 10514554880 1068714384 11.540 94 20 40 0 1105787536 33195616 3877536
06:50:59 42019 8192000000 10092757120 10520997888 1082873872 11.675 97 37 74 1 1172271696 81977664 7420160
06:52:48 78845 8192000000 10063499680 10544623616 1114389552 11.974 100 22 44 0 1179904192 60650496 4864144
06:55:27 56104 8192000000 10078104848 10562498560 1098694000 11.825 98 8 16 1 1131107936 30650256 1763680
06:57:24 76633 8192000000 10100873504 10598477824 1110788272 11.940 98 24 47 1 1198656752 82621712 5246768
06:59:40 52531 8192000000 10082935296 10538754048 1118757568 12.015 99 26 52 1 1178105872 53188624 6159680
07:01:34 44996 8192000000 10084753760 10547257344 1097291168 11.812 97 16 32 0 1167419968 66594272 3534528
07:03:20 80059 8192000000 10046588448 10502656000 1059102624 11.448 95 25 50 1 1116974944 52800608 5071712
07:05:45 75627 8192000000 10050514800 10507005952 1091621680 11.758 99 18 36 0 1147147168 52225488 3300000
07:08:12 98906 8192000000 10085882176 10524790784 1107370784 11.908 99 12 24 0 1136306880 26795936 2140160
07:10:51 55161 8192000000 10066433200 10570579968 1123176448 12.057 100 6 12 0 1170981712 46678496 1126768
07:12:46 79472 8192000000 10060217280 10495926272 1105661584 11.891 98 28 55 1 1140474960 27702480 7110896
07:15:18 63013 8192000000 10148104272 10583928832 1115353760 11.983 98 29 58 0 1202684048 80858304 6471984
07:17:22 31699 8192000000 10084145888 10524000256 1138235616 12.199 100 25 50 0 1169558736 26185408 5137712
07:18:55 39380 8192000000 10106172992 10620071936 1124607264 12.070 99 13 26 2 1206778416 79432768 2738384
07:20:42 31650 8192000000 10078373792 10509864960 1132013168 12.140 99 23 46 0 1163064608 25902416 5149024
07:22:15 50543 8192000000 10099408672 10571890688 1110797200 11.940 98 18 36 0 1162892064 48392112 3702752
07:24:07 73517 8192000000 10083712992 10528747520 1043157888 11.295 92 10 19 1 1082621504 37548432 1915184
07:26:21 61699 8192000000 9425182208 9769144320 545845552 6.246 49 4 6 1 568318592 21771776 701264

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 15-11-2020 07:29:26 to 15-11-2020 07:29:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 163 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 15-11-2020 07:29:43 to 15-11-2020 07:29:44.

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          234721
MON$OLDEST_ACTIVE               235001
MON$OLDEST_SNAPSHOT             235001
MON$NEXT_TRANSACTION            235001
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                       2106816
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             800
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2265 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2265 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2265 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.2265 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 ---
:62: 
:63: mscs-test-161.budzdorov.ru    Sun Nov 15 07:27:29 2020
:64:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:65: 
:66: 
:67: mscs-test-161.budzdorov.ru    Sun Nov 15 07:27:29 2020
:68:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:69: 
:70: 
:71: mscs-test-161.budzdorov.ru    Sun Nov 15 07:27:29 2020
:72:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:73: 
--- end of diff output ---

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

15.11.20 07:29:45. 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-15 07:29:48 No severe PSQL-related problems occured
15.11.20 07:29:49. End of report.