Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.03.2021 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 df9f488d08424df294c459d6c283e9c3
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 2021-03-04 16:07
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.26
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 16972436 kB
MemAvailable 31604464 kB
Buffers 0 kB
Cached 14690508 kB
SwapCached 0 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 2021-03-09 13:26:56
DTS_END                         2021-03-09 13:26:56.4270
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48974
TRN_ID                          241454
ATT_ID                          686
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: 8970.78 24809 1076493
customer order: creation 175.25 375 21030
customer order: refuse 14.48 278 1737
order to supplier: creation 47.50 1356 5700
order to supplier: removal 3.42 105777 410
invoice (draft): creation 46.70 3523 5604
invoice (draft): removal 47.59 920 5711
invoice accept: apply 42.96 16566 5155
invoice accept: cancel 43.73 870 5247
customer reserve: creation 3959.88 162 475185
customer reserve: removal 4288.52 67 514623
realization accept: apply 87.08 158 10449
realization accept: cancel 80.23 113 9628
payment from customer: creation 60.98 102 7318
payment from customer: removal 5.76 75 691
payment to supplier: creation 51.28 119 6153
payment to supplier: removal 3.48 65 418
service: total inventory turnovers 4.37 2837 524
service: total monetary turnovers 6.58 426 789
service: aggregate perf. data 0.98 14545 117
service: refresh index statistics 0.03 347848 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-03-2021 13:28:19 to 09-03-2021 13:28:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1522
TEST_TIME 2 2352
TEST_TIME 3 3037
TEST_TIME 4 3409
TEST_TIME 5 3411
TEST_TIME 6 3955
TEST_TIME 7 4387
TEST_TIME 8 4311
TEST_TIME 9 4512
TEST_TIME 10 4664
TEST_TIME 11 5147
TEST_TIME 12 5511
TEST_TIME 13 5848
TEST_TIME 14 5956
TEST_TIME 15 6484
TEST_TIME 16 6920
TEST_TIME 17 7376
TEST_TIME 18 7651
TEST_TIME 19 7908
TEST_TIME 20 8080
TEST_TIME 21 8466
TEST_TIME 22 8949
TEST_TIME 23 9005
TEST_TIME 24 10532
TEST_TIME 25 10774
TEST_TIME 26 10590
TEST_TIME 27 10462
TEST_TIME 28 10430
TEST_TIME 29 10359
TEST_TIME 30 10189
TEST_TIME 31 10158
TEST_TIME 32 9964
TEST_TIME 33 9953
TEST_TIME 34 9824
TEST_TIME 35 9813
TEST_TIME 36 9907
TEST_TIME 37 9971
TEST_TIME 38 9958
TEST_TIME 39 10072
TEST_TIME 40 9964
TEST_TIME 41 10055
TEST_TIME 42 10002
TEST_TIME 43 10103
TEST_TIME 44 10185
TEST_TIME 45 9979
TEST_TIME 46 9768
TEST_TIME 47 9719
TEST_TIME 48 9667
TEST_TIME 49 9634
TEST_TIME 50 9545
TEST_TIME 51 9542
TEST_TIME 52 9510
TEST_TIME 53 9415
TEST_TIME 54 9347
TEST_TIME 55 9333
TEST_TIME 56 9209
TEST_TIME 57 9142
TEST_TIME 58 9213
TEST_TIME 59 9297
TEST_TIME 60 9266
TEST_TIME 61 9247
TEST_TIME 62 9337
TEST_TIME 63 9256
TEST_TIME 64 9353
TEST_TIME 65 9387
TEST_TIME 66 9488
TEST_TIME 67 9501
TEST_TIME 68 9544
TEST_TIME 69 9514
TEST_TIME 70 9514
TEST_TIME 71 9489
TEST_TIME 72 9395
TEST_TIME 73 9357
TEST_TIME 74 9309
TEST_TIME 75 9257
TEST_TIME 76 9213
TEST_TIME 77 9170
TEST_TIME 78 9154
TEST_TIME 79 9145
TEST_TIME 80 9154
TEST_TIME 81 9269
TEST_TIME 82 9224
TEST_TIME 83 9231
TEST_TIME 84 9256
TEST_TIME 85 9237
TEST_TIME 86 9287
TEST_TIME 87 9244
TEST_TIME 88 9224
TEST_TIME 89 9143
TEST_TIME 90 9112
TEST_TIME 91 9051
TEST_TIME 92 9047
TEST_TIME 93 9026
TEST_TIME 94 9055
TEST_TIME 95 9044
TEST_TIME 96 9044
TEST_TIME 97 9090
TEST_TIME 98 9097
TEST_TIME 99 9076
TEST_TIME 100 9112
TEST_TIME 101 9150
TEST_TIME 102 9172
TEST_TIME 103 9170
TEST_TIME 104 9153
TEST_TIME 105 9161
TEST_TIME 106 9184
TEST_TIME 107 9149
TEST_TIME 108 9089
TEST_TIME 109 9070
TEST_TIME 110 9013
TEST_TIME 111 8958
TEST_TIME 112 8933
TEST_TIME 113 8958
TEST_TIME 114 8975
TEST_TIME 115 8977
TEST_TIME 116 9001
TEST_TIME 117 9004
TEST_TIME 118 8995
TEST_TIME 119 8972
TEST_TIME 120 8974

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-03-2021 13:28:23 to 09-03-2021 13:28:27.

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 1513592 1513592 0 0.00 0 22674 7 0 0
doc_list_biud 1513592 1513592 0 0.00 0 52493 29 0 0
sp_add_invoice_to_stock 5316 5155 161 3.02 35 179448 16566 0 161
sp_cancel_adding_invoice 5247 5247 0 0.00 5 79140 870 0 0
sp_cancel_client_order 1737 1737 0 0.00 4 7510 278 0 0
sp_cancel_customer_reserve 514623 514623 0 0.00 1 34330 67 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 3 4921 76 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 4526 65 0 0
sp_cancel_supplier_invoice 5723 5711 12 0.20 2 33793 921 0 12
sp_cancel_supplier_order 410 410 0 0.00 11 1093606 105778 0 0
sp_cancel_write_off 9628 9628 0 0.00 1 17158 114 0 0
sp_client_order 21030 21030 0 0.00 4 26962 375 0 0
sp_customer_reserve 475185 475185 0 0.00 0 96365 163 0 0
sp_fill_shopping_cart 32313 32313 0 0.00 1 14230 50 0 0
sp_fill_shopping_cart_clo_res 475181 475181 0 0.00 0 21441 18 0 0
sp_get_clo_for_invoice 5147 5147 0 0.00 0 47465 451 0 0
sp_kill_cost_storno 15984 15984 0 0.00 0 7872 55 0 0
sp_kill_qstorno_ret_qs2qd 1044995 1044995 0 0.00 0 33982 36 0 0
sp_kill_qty_storno 552947 552947 0 0.00 0 52493 78 0 0
sp_lock_selected_doc 21422 21422 0 0.00 0 10378 4 0 0
sp_make_cost_storno 29062 29062 0 0.00 0 14172 50 0 0
sp_multiply_rows_for_pdistr 29062 29062 0 0.00 0 7394 27 0 0
sp_multiply_rows_for_qdistr 21030 21030 0 0.00 0 16423 216 0 0
sp_pay_from_customer 7318 7318 0 0.00 4 13134 103 0 0
sp_pay_to_supplier 6153 6153 0 0.00 5 11856 119 0 0
sp_payment_common 13471 13471 0 0.00 1 6602 21 0 0
sp_qd_handle_on_cancel_clo 1737 1737 0 0.00 0 7166 253 0 0
sp_qd_handle_on_invoice_upd_sts 10389 10389 0 0.00 0 52492 363 0 0
sp_qd_handle_on_reserve_upd_sts 10449 10449 0 0.00 0 13709 48 0 0
sp_reserve_write_off 10449 10449 0 0.00 7 15192 159 0 0
sp_supplier_invoice 5604 5604 0 0.00 7 121488 3524 0 0
sp_supplier_order 5700 5700 0 0.00 5 47647 1357 0 0
srv_aggregate_perf_data 117 117 0 0.00 2679 226118 14546 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2566 139126 48471 0 0
srv_find_qd_qs_mism 1025992 1025992 0 0.00 0 22673 6 0 0
srv_make_invnt_saldo 524 524 0 0.00 43 89038 2838 0 0
srv_make_money_saldo 789 789 0 0.00 1 19730 426 0 0
srv_recalc_idx_stat 4 4 0 0.00 276891 480986 347848 0 0
t$perf-abend:sp_add_invoice_to_stock 161 161 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 5155 5155 0 0.00 0 8054 134 0 0
t$perf-norm:sp_cancel_adding_invoice 461 461 0 0.00 0 2387 63 0 0
t$perf-norm:sp_cancel_client_order 1737 1737 0 0.00 0 441 5 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 5584 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 2998 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 3260 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 668 11 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 14788 1319 0 0
t$perf-norm:sp_cancel_write_off 997 997 0 0.00 0 997 5 0 0
t$perf-norm:sp_client_order 21030 21030 0 0.00 0 6884 14 0 0
t$perf-norm:sp_customer_reserve 1061 1061 0 0.00 0 4371 8 0 0
t$perf-norm:sp_pay_from_customer 7318 7318 0 0.00 0 4510 6 0 0
t$perf-norm:sp_pay_to_supplier 6153 6153 0 0.00 0 4416 10 0 0
t$perf-norm:sp_reserve_write_off 10449 10449 0 0.00 0 7244 9 0 0
t$perf-norm:sp_supplier_invoice 5604 5604 0 0.00 0 7700 20 0 0
t$perf-norm:sp_supplier_order 5700 5700 0 0.00 0 3413 12 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 160 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 31 2 0 0
t$perf-norm:srv_make_invnt_saldo 524 524 0 0.00 0 971 8 0 0
t$perf-norm:srv_make_money_saldo 789 789 0 0.00 0 2906 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10606 10284 322 3.03 0 3493 16 0 322
v_all_customers 38202 38202 0 0.00 0 18 1 0 0
v_all_suppliers 31620 31620 0 0.00 0 190 1 0 0
v_all_wares 42060 42060 0 0.00 0 65 1 0 0
v_cancel_adding_invoice 922 922 0 0.00 0 1344 13 0 0
v_cancel_client_order 3474 3474 0 0.00 0 1786 4 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 3898 8 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 952 4 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 1077 5 0 0
v_cancel_supplier_invoice 838 814 24 2.86 0 3107 23 0 24
v_cancel_supplier_order 820 820 0 0.00 0 2263 8 0 0
v_cancel_write_off 1994 1994 0 0.00 0 762 11 0 0
v_max_id_clo_ord 5700 5700 0 0.00 0 271 1 0 0
v_max_id_clo_res 1061 1061 0 0.00 0 1894 11 0 0
v_max_id_ord_sup 5582 5582 0 0.00 0 6503 7 0 0
v_max_non_paid_invoice 1625 1625 0 0.00 0 574 2 0 0
v_max_non_paid_realizn 3937 3937 0 0.00 0 1825 4 0 0
v_min_id_clo_ord 5700 5700 0 0.00 0 1566 5 0 0
v_min_id_clo_res 1061 1061 0 0.00 0 5111 25 0 0
v_min_id_ord_sup 5582 5582 0 0.00 0 9848 13 0 0
v_min_non_paid_invoice 6153 6153 0 0.00 0 6077 13 0 0
v_min_non_paid_realizn 7318 7318 0 0.00 0 5590 17 0 0
v_our_firm 10620 10620 0 0.00 0 14 1 0 0
v_reserve_write_off 20898 20898 0 0.00 0 3094 5 0 0
x_lock_depdocs_on_canc_invoice 10551 10551 0 0.00 0 25691 105 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 24435 374 0 0
x_make_qstorno_1000_1200 5700 5700 0 0.00 2 47642 1282 0 0
x_make_qstorno_1200_2000 5604 5604 0 0.00 3 119581 3309 0 0
x_make_qstorno_2100_3300 475185 475185 0 0.00 0 96363 142 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-03-2021 13:28:28 to 09-03-2021 13:28:32.

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:57:00 1 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:00 19046 8192000000 9722783760 10020368384 800964640 8.906 100 86 172 0 829892576 8056800 20871136
10:59:24 34722 8192000000 9774710272 10081959936 843760160 9.338 100 92 184 1 882927376 17231040 21936176
11:01:04 18497 8192000000 9798576896 10117750784 868159776 9.582 100 77 154 0 909810704 24114896 17536032
11:02:27 16123 8192000000 9884721456 10218807296 922261024 10.118 100 81 162 1 983390400 43733152 17396224
11:03:51 7497 8192000000 9946782672 10290532352 962490208 10.513 100 83 166 2 1053991472 74579776 16921488
11:05:01 4125 8192000000 9979671776 10333413376 992721680 10.808 100 80 160 0 1085031728 75821536 16488512
11:06:07 4767 8192000000 9989083680 10355441664 1023803696 11.109 100 73 147 1 1095646768 57081488 14761584
11:07:13 1761 8192000000 10040253744 10407563264 1046126256 11.324 100 84 168 2 1148464544 84778512 17559776
11:08:17 1339 8192000000 10044929888 10413219840 1060240080 11.459 100 90 180 2 1157131792 78835040 18056672
11:09:20 6780 8192000000 9984262528 10382643200 1064749056 11.502 100 31 62 0 1121970496 50992496 6228944
11:10:28 10449 8192000000 9989063216 10397982720 1074748464 11.597 100 40 80 2 1117057296 32972432 9336400
11:11:43 7149 8192000000 9998550496 10392109056 1082157024 11.668 100 36 72 1 1114426992 24746000 7523968
11:12:53 24979 8192000000 10031176352 10449117184 1086836800 11.713 100 62 124 2 1114608912 14581440 13190672
11:14:21 4868 8192000000 10020392320 10428997632 1091442800 11.756 100 44 83 2 1125898160 25462704 8992656
11:15:27 4741 8192000000 10045505184 10439606272 1100269344 11.840 100 51 101 0 1154530976 43876352 10385280
11:16:34 11773 8192000000 10001853616 10402631680 1072794288 11.579 98 26 52 1 1106050496 27626224 5629984
11:17:46 36768 8192000000 9986072992 10392346624 1047068352 11.333 96 65 134 1 1072159648 10309552 14781744
11:19:25 3946 8192000000 9961923440 10350395392 1024090064 11.111 97 48 96 2 1060138320 24434784 11613472
11:20:29 6791 8192000000 9932627856 10321707008 998761648 10.867 97 32 64 1 1027661328 21499888 7399792
11:21:40 3928 8192000000 9942546160 10320789504 1017358496 11.047 99 54 109 2 1043670608 13877296 12434816
11:22:44 15843 8192000000 9950827776 10357567488 1012574576 11.000 99 24 48 0 1042364992 25000688 4789728
11:24:00 5944 8192000000 9964673376 10360250368 1048134880 11.343 100 45 89 1 1072651328 13691696 10824752
11:25:09 6618 8192000000 9970587472 10365349888 1059523424 11.452 100 52 105 0 1085158944 12197168 13438352
11:26:16 122430 8192000000 10015727072 10416869376 1074114832 11.591 100 38 76 2 1096972768 14127424 8730512
11:29:25 14577 8192000000 10058745856 10465644544 1112278128 11.954 100 40 80 0 1178264928 56838416 9148384
11:30:48 12674 8192000000 10104628944 10507816960 1135022160 12.169 100 43 83 4 1214017152 70337904 8657088
11:32:03 10210 8192000000 10162348608 10571124736 1145414592 12.266 100 44 88 1 1262214688 107090224 9709872
11:33:17 13789 8192000000 10197822848 10607341568 1156871568 12.374 100 52 104 2 1299845248 131710144 11263536
11:34:39 37289 8192000000 10255882304 10686627840 1169527664 12.492 100 43 86 1 1358108928 178337600 10243664
11:36:20 12511 8192000000 10294730816 10707021824 1181729616 12.606 100 61 121 2 1393775616 197595920 14450080
11:37:42 2901 8192000000 10320275008 10729791488 1172284480 12.518 99 71 141 1 1420185632 232115440 15785712
11:38:47 6243 8192000000 10363097024 10778943488 1195451248 12.734 100 68 136 2 1467766592 256289872 16025472
11:39:58 2565 8192000000 10387147984 10793558016 1177082544 12.563 100 84 169 1 1490521280 293607344 19831392
11:41:04 2844 8192000000 10427535392 10846179328 1153769424 12.345 99 80 161 0 1521773584 349499328 18504832
11:42:11 6212 8192000000 10370491984 10807349248 1152297824 12.331 100 78 156 2 1484917136 313362800 19256512
11:43:20 7179 8192000000 10388641008 10795737088 1144515216 12.258 98 64 127 1 1489341264 329386416 15439632
11:44:34 5899 8192000000 10434303648 10851848192 1168599808 12.484 100 57 113 1 1534336688 351871120 13865760
11:45:44 28723 8192000000 10463019056 10899083264 1162664832 12.428 100 41 82 1 1565950896 392369504 10916560
11:47:15 24163 8192000000 10458759008 10908868608 1166103632 12.460 100 33 66 1 1545766240 370218768 9443840
11:48:45 22359 8192000000 10365783376 10825228288 1140982640 12.225 100 25 50 0 1486229648 338891664 6355344
11:50:37 45174 8192000000 10081627184 10542694400 1090299440 11.746 97 22 44 1 1245150576 150057504 4793632
11:52:23 33140 8192000000 10076929536 10506956800 1104141056 11.877 98 37 75 0 1165516912 53076720 8299136
11:54:07 55395 8192000000 10059893408 10539266048 1115560960 11.985 100 3 6 0 1170983280 54786528 635792
11:56:03 68118 8192000000 10047824752 10472329216 1082788784 11.674 97 27 53 0 1103635088 15071440 5774864
11:58:12 47792 8192000000 10049163504 10477989888 1079112816 11.639 96 22 41 1 1111162208 27512416 4536976
12:00:00 26497 8192000000 10077216128 10509045760 1120224720 12.029 99 40 78 1 1175760448 46080000 9455728
12:01:51 92860 8192000000 10078303376 10551992320 1106163136 11.896 98 8 16 0 1171663488 63941744 1558608
12:04:26 61666 8192000000 10092087472 10586144768 1117281344 12.001 98 10 20 0 1199910320 80758656 1870320
12:06:34 22445 8192000000 10130135696 10572877824 1139265200 12.209 100 32 61 1 1191436368 45711376 6459792
12:08:08 60374 8192000000 10107270064 10571096064 1147561232 12.287 100 12 24 0 1185101456 35154640 2385584
12:10:10 94369 8192000000 10104060576 10558373888 1148470320 12.295 100 15 30 0 1183429632 32006192 2953120
12:12:46 50497 8192000000 10131030240 10568966144 1154844432 12.355 100 46 90 1 1222025824 57702976 9478416
12:14:37 64628 8192000000 10131906032 10602926080 1144835776 12.261 99 26 51 2 1204063136 53784208 5443152
12:17:02 26350 8192000000 10078575376 10577543168 1146779328 12.279 99 10 20 0 1189247216 40408912 2058976
12:18:38 125811 8192000000 10092092896 10578534400 1159271856 12.396 100 9 18 0 1193888016 32649328 1966832
12:21:48 69950 8192000000 10085472944 10547441664 1108107488 11.914 96 12 24 1 1136279712 25291168 2881056
12:24:00 53478 8192000000 10060925616 10539495424 1105545920 11.890 97 9 17 0 1165851328 58058128 2247280
12:27:20 41498 8192000000 10074343280 10510344192 1080738368 11.655 95 23 46 1 1133871904 48748256 4385280
12:29:02 50308 8192000000 10133126976 10566434816 1111829296 11.950 98 23 44 1 1196012304 79442704 4740304
12:30:53 69790 8192000000 10119394160 10550710272 1103968960 11.875 97 21 42 4 1176189472 67912368 4308144
12:33:04 19124 8192000000 10090530736 10512121856 1113198768 11.963 99 46 92 0 1161841648 39350752 9292128
12:34:45 44969 8192000000 10050009664 10479702016 1089208608 11.735 97 23 46 1 1127485488 33386848 4890032
12:36:31 11039 8192000000 10115723680 10532024320 1129413344 12.116 100 59 118 1 1197666304 56717136 11535824
12:37:45 139126 8192000000 10049675216 10521735168 1121821504 12.044 99 22 44 0 1167980016 41608736 4549776
12:41:37 49828 8192000000 10051160576 10502578176 1128284048 12.105 100 6 13 0 1150270624 20736000 1250576
12:43:35 43414 8192000000 10082527104 10520612864 1094430720 11.785 96 36 70 2 1142364640 40373920 7560000
12:45:24 65454 8192000000 10126779584 10569945088 1099782624 11.836 97 24 48 1 1181934736 76770352 5381760
12:47:31 51002 8192000000 10049219776 10479788032 1080039456 11.648 96 25 51 0 1131161584 45725280 5396848
12:49:29 58016 8192000000 10063629376 10523881472 1107049808 11.904 99 30 58 0 1178602304 65818816 5733680
12:51:29 58797 8192000000 10094981984 10533801984 1127318080 12.096 100 24 48 1 1192104208 59681104 5105024
12:53:38 13377 8192000000 10091012400 10526326784 1133461328 12.154 100 42 85 0 1180882768 38732208 8689232
12:54:53 90419 8192000000 10060102880 10513285120 1113951456 11.970 98 28 56 0 1158903696 39495168 5457072
12:57:27 82079 8192000000 10122176432 10598588416 1136665024 12.184 100 9 18 0 1186451584 48121104 1665456
12:59:49 67464 8192000000 10072077824 10516967424 1118188176 12.010 98 7 14 0 1148113664 29079824 845664
13:02:00 31982 8192000000 10110120016 10556035072 1144768144 12.260 100 28 56 0 1209600848 59073376 5759328
13:04:03 83080 8192000000 10111391952 10549919744 1098766704 11.826 96 29 58 0 1171314368 66087200 6460464
13:06:26 37904 8192000000 10120568624 10602663936 1112541328 11.956 98 34 68 0 1216425520 96700112 7184080
13:08:19 84878 8192000000 10067027376 10519568384 1106275056 11.897 98 22 44 1 1160147984 48828976 5043952
13:10:50 25357 8192000000 10109616496 10542256128 1123705344 12.062 99 41 81 0 1195697712 64105424 7886944
13:12:25 18766 8192000000 10069680000 10516676608 1105829072 11.893 98 28 54 2 1157383392 45780672 5773648
13:13:47 120734 8192000000 10054138608 10498666496 1070974944 11.561 96 23 47 0 1122408752 46551072 4882736
13:16:53 54424 8192000000 10055679040 10503794688 1099157232 11.830 99 8 17 1 1144164560 42495744 2511584
13:18:53 121902 8192000000 10101727344 10591567872 1086552720 11.710 97 14 28 0 1142857200 53334400 2970080
13:21:56 75823 8192000000 10075566192 10567622656 1102192752 11.858 99 22 44 0 1175244512 68148432 4903328
13:24:12 42449 8192000000 10112651024 10555371520 1108529504 11.918 98 22 44 3 1199466848 85739760 5197584
13:26:00 69451 8192000000 9134762272 9475567616 243792560 2.889 22 6 11 0 295309936 49749520 1767856

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-03-2021 13:28:33 to 09-03-2021 13:28:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 161 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:02, from 09-03-2021 13:28:49 to 09-03-2021 13:28:51.

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          241453
MON$OLDEST_ACTIVE               243202
MON$OLDEST_SNAPSHOT             243202
MON$NEXT_TRANSACTION            243202
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                       2099456
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             818
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2382 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2382 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2382 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.2382 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 ---
:305: 
:306: mscs-test-161.budzdorov.ru    Tue Mar  9 13:27:11 2021
:307:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:308: 
:309: 
:310: mscs-test-161.budzdorov.ru    Tue Mar  9 13:27:17 2021
:311:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:312: 
--- end of diff output ---

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

09.03.21 13:28:52. 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
2021-03-09 13:28:55 No severe PSQL-related problems occured
09.03.21 13:28:56. End of report.