Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.06.2021 10: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16976668 kB
MemAvailable 31390280 kB
Buffers 0 kB
Cached 14664276 kB
SwapCached 72 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.1
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-06-15 10:27:00
DTS_END                         2021-06-15 10:27:00.9660
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/33542
TRN_ID                          225979
ATT_ID                          608
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: 8643.72 23535 1037246
customer order: creation 165.50 456 19860
customer order: refuse 14.08 340 1689
order to supplier: creation 43.37 1380 5204
order to supplier: removal 3.12 122614 374
invoice (draft): creation 44.23 3983 5308
invoice (draft): removal 48.48 945 5818
invoice accept: apply 39.91 18037 4789
invoice accept: cancel 43.34 892 5201
customer reserve: creation 3728.01 173 447361
customer reserve: removal 4234.25 72 508110
realization accept: apply 79.70 180 9564
realization accept: cancel 78.91 126 9469
payment from customer: creation 55.55 119 6666
payment from customer: removal 5.50 94 660
payment to supplier: creation 46.56 164 5587
payment to supplier: removal 3.11 122 373
service: total inventory turnovers 3.61 3498 433
service: total monetary turnovers 5.70 521 684
service: aggregate perf. data 0.77 21903 92
service: refresh index statistics 0.03 295090 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-06-2021 10:28:18 to 15-06-2021 10:28:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5167
TEST_TIME 2 4013
TEST_TIME 3 3766
TEST_TIME 4 4118
TEST_TIME 5 3817
TEST_TIME 6 4115
TEST_TIME 7 4534
TEST_TIME 8 4463
TEST_TIME 9 4768
TEST_TIME 10 5558
TEST_TIME 11 5858
TEST_TIME 12 6377
TEST_TIME 13 6761
TEST_TIME 14 6965
TEST_TIME 15 7024
TEST_TIME 16 7348
TEST_TIME 17 7911
TEST_TIME 18 8271
TEST_TIME 19 8637
TEST_TIME 20 8743
TEST_TIME 21 8802
TEST_TIME 22 8743
TEST_TIME 23 9014
TEST_TIME 24 9117
TEST_TIME 25 9475
TEST_TIME 26 10155
TEST_TIME 27 11478
TEST_TIME 28 11118
TEST_TIME 29 10808
TEST_TIME 30 10822
TEST_TIME 31 10779
TEST_TIME 32 10520
TEST_TIME 33 10381
TEST_TIME 34 10154
TEST_TIME 35 10213
TEST_TIME 36 10057
TEST_TIME 37 9858
TEST_TIME 38 9738
TEST_TIME 39 9780
TEST_TIME 40 9704
TEST_TIME 41 9575
TEST_TIME 42 9541
TEST_TIME 43 9447
TEST_TIME 44 9413
TEST_TIME 45 9440
TEST_TIME 46 9369
TEST_TIME 47 9655
TEST_TIME 48 9611
TEST_TIME 49 9551
TEST_TIME 50 9539
TEST_TIME 51 9568
TEST_TIME 52 9631
TEST_TIME 53 9599
TEST_TIME 54 9735
TEST_TIME 55 9591
TEST_TIME 56 9443
TEST_TIME 57 9508
TEST_TIME 58 9404
TEST_TIME 59 9307
TEST_TIME 60 9226
TEST_TIME 61 9226
TEST_TIME 62 9260
TEST_TIME 63 9204
TEST_TIME 64 9162
TEST_TIME 65 9173
TEST_TIME 66 9300
TEST_TIME 67 9228
TEST_TIME 68 9260
TEST_TIME 69 9324
TEST_TIME 70 9355
TEST_TIME 71 9350
TEST_TIME 72 9461
TEST_TIME 73 9423
TEST_TIME 74 9381
TEST_TIME 75 9269
TEST_TIME 76 9199
TEST_TIME 77 9140
TEST_TIME 78 9080
TEST_TIME 79 9033
TEST_TIME 80 8993
TEST_TIME 81 9030
TEST_TIME 82 9041
TEST_TIME 83 9006
TEST_TIME 84 8997
TEST_TIME 85 8953
TEST_TIME 86 9058
TEST_TIME 87 9052
TEST_TIME 88 9076
TEST_TIME 89 9038
TEST_TIME 90 9031
TEST_TIME 91 9033
TEST_TIME 92 9122
TEST_TIME 93 9084
TEST_TIME 94 9021
TEST_TIME 95 8950
TEST_TIME 96 8938
TEST_TIME 97 8874
TEST_TIME 98 8821
TEST_TIME 99 8853
TEST_TIME 100 8851
TEST_TIME 101 8851
TEST_TIME 102 8909
TEST_TIME 103 8894
TEST_TIME 104 8881
TEST_TIME 105 8886
TEST_TIME 106 8900
TEST_TIME 107 8926
TEST_TIME 108 8961
TEST_TIME 109 8947
TEST_TIME 110 8930
TEST_TIME 111 8856
TEST_TIME 112 8811
TEST_TIME 113 8788
TEST_TIME 114 8764
TEST_TIME 115 8764
TEST_TIME 116 8791
TEST_TIME 117 8755
TEST_TIME 118 8735
TEST_TIME 119 8719
TEST_TIME 120 8646

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-06-2021 10:28:22 to 15-06-2021 10:28:25.

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 1450561 1450561 0 0.00 0 20019 7 0 0
doc_list_biud 1450560 1450560 0 0.00 0 80960 32 0 0
sp_add_invoice_to_stock 4929 4789 140 2.84 33 197243 18038 0 140
sp_cancel_adding_invoice 5201 5201 0 0.00 5 102643 893 0 0
sp_cancel_client_order 1689 1689 0 0.00 4 24705 341 0 0
sp_cancel_customer_reserve 508110 508110 0 0.00 1 80963 72 0 0
sp_cancel_pay_from_customer 660 660 0 0.00 3 10204 95 0 0
sp_cancel_pay_to_supplier 373 373 0 0.00 3 9915 122 0 0
sp_cancel_supplier_invoice 5827 5818 9 0.15 3 14286 945 0 9
sp_cancel_supplier_order 374 374 0 0.00 10 1192193 122614 0 0
sp_cancel_write_off 9469 9469 0 0.00 1 10427 126 0 0
sp_client_order 19860 19860 0 0.00 4 42838 456 0 0
sp_customer_reserve 447361 447361 0 0.00 0 51399 174 0 0
sp_fill_shopping_cart 30351 30351 0 0.00 1 26881 55 0 0
sp_fill_shopping_cart_clo_res 447355 447355 0 0.00 0 24716 20 0 0
sp_get_clo_for_invoice 4783 4783 0 0.00 0 40152 546 0 0
sp_kill_cost_storno 15703 15703 0 0.00 0 11056 63 0 0
sp_kill_qstorno_ret_qs2qd 1031881 1031881 0 0.00 0 80916 39 0 0
sp_kill_qty_storno 545007 545007 0 0.00 0 80960 83 0 0
sp_lock_selected_doc 19820 19820 0 0.00 0 5648 4 0 0
sp_make_cost_storno 26600 26600 0 0.00 0 17000 52 0 0
sp_multiply_rows_for_pdistr 26599 26599 0 0.00 0 11917 40 0 0
sp_multiply_rows_for_qdistr 19860 19860 0 0.00 0 24162 265 0 0
sp_pay_from_customer 6666 6666 0 0.00 4 16958 120 0 0
sp_pay_to_supplier 5587 5587 0 0.00 5 17139 165 0 0
sp_payment_common 12253 12253 0 0.00 1 16774 36 0 0
sp_qd_handle_on_cancel_clo 1689 1689 0 0.00 0 24696 316 0 0
sp_qd_handle_on_invoice_upd_sts 9983 9983 0 0.00 0 26411 362 0 0
sp_qd_handle_on_reserve_upd_sts 9564 9564 0 0.00 0 10816 51 0 0
sp_reserve_write_off 9564 9564 0 0.00 6 17158 181 0 0
sp_supplier_invoice 5308 5308 0 0.00 6 96361 3983 0 0
sp_supplier_order 5204 5204 0 0.00 5 37494 1381 0 0
srv_aggregate_perf_data 92 92 0 0.00 2517 218881 21904 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1390 125797 48387 0 0
srv_find_qd_qs_mism 991153 991153 0 0.00 0 20019 5 0 0
srv_make_invnt_saldo 433 433 0 0.00 56 110334 3498 0 0
srv_make_money_saldo 684 684 0 0.00 0 12203 522 0 0
srv_recalc_idx_stat 4 4 0 0.00 172978 392761 295090 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 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4789 4789 0 0.00 0 12764 167 0 0
t$perf-norm:sp_cancel_adding_invoice 359 359 0 0.00 0 3985 89 0 0
t$perf-norm:sp_cancel_client_order 1689 1689 0 0.00 0 6098 21 0 0
t$perf-norm:sp_cancel_customer_reserve 613 613 0 0.00 0 1119 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 660 660 0 0.00 0 676 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 373 373 0 0.00 0 613 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 679 15 0 0
t$perf-norm:sp_cancel_supplier_order 374 374 0 0.00 0 23317 1566 0 0
t$perf-norm:sp_cancel_write_off 1020 1020 0 0.00 0 2885 12 0 0
t$perf-norm:sp_client_order 19860 19860 0 0.00 0 7392 19 0 0
t$perf-norm:sp_customer_reserve 991 991 0 0.00 0 552 4 0 0
t$perf-norm:sp_pay_from_customer 6666 6666 0 0.00 0 7420 9 0 0
t$perf-norm:sp_pay_to_supplier 5587 5587 0 0.00 0 7404 16 0 0
t$perf-norm:sp_reserve_write_off 9564 9564 0 0.00 0 5846 10 0 0
t$perf-norm:sp_supplier_invoice 5308 5308 0 0.00 0 6796 17 0 0
t$perf-norm:sp_supplier_order 5204 5204 0 0.00 0 5753 18 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 720 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1135 23 0 0
t$perf-norm:srv_make_invnt_saldo 433 433 0 0.00 0 805 10 0 0
t$perf-norm:srv_make_money_saldo 684 684 0 0.00 0 4338 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9842 9562 280 2.84 0 5927 17 0 280
v_all_customers 36272 36272 0 0.00 0 86 1 0 0
v_all_suppliers 29208 29208 0 0.00 0 16 1 0 0
v_all_wares 39720 39720 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 718 718 0 0.00 0 3171 18 0 0
v_cancel_client_order 3378 3378 0 0.00 0 2372 5 0 0
v_cancel_customer_prepayment 1320 1320 0 0.00 0 4631 9 0 0
v_cancel_customer_reserve 1226 1226 0 0.00 0 327 3 0 0
v_cancel_payment_to_supplier 746 746 0 0.00 0 632 5 0 0
v_cancel_supplier_invoice 792 774 18 2.27 0 6922 35 0 18
v_cancel_supplier_order 748 748 0 0.00 0 208 4 0 0
v_cancel_write_off 2040 2040 0 0.00 0 5373 21 0 0
v_max_id_clo_ord 5204 5204 0 0.00 0 5292 2 0 0
v_max_id_clo_res 991 991 0 0.00 0 6339 25 0 0
v_max_id_ord_sup 5287 5287 0 0.00 0 8746 11 0 0
v_max_non_paid_invoice 1474 1474 0 0.00 0 118 2 0 0
v_max_non_paid_realizn 3409 3409 0 0.00 0 5957 4 0 0
v_min_id_clo_ord 5204 5204 0 0.00 0 11647 11 0 0
v_min_id_clo_res 991 991 0 0.00 0 10347 25 0 0
v_min_id_ord_sup 5287 5287 0 0.00 0 5538 10 0 0
v_min_non_paid_invoice 5587 5587 0 0.00 0 10253 32 0 0
v_min_non_paid_realizn 6666 6666 0 0.00 0 15973 24 0 0
v_our_firm 9962 9962 0 0.00 0 66 1 0 0
v_reserve_write_off 19128 19128 0 0.00 0 4991 5 0 0
x_lock_depdocs_on_canc_invoice 10632 10632 0 0.00 0 17650 118 0 0
x_lock_depdocs_on_canc_sup_ord 374 374 0 0.00 0 16132 378 0 0
x_make_qstorno_1000_1200 5204 5204 0 0.00 2 37415 1301 0 0
x_make_qstorno_1200_2000 5308 5308 0 0.00 2 88595 3753 0 0
x_make_qstorno_2100_3300 447361 447361 0 0.00 0 49143 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-06-2021 10:28:26 to 15-06-2021 10:28:30.

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
07:57:04 13 8192000000 8796668864 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
07:58:07 29086 8192000000 9726518400 10024894464 797323424 8.869 100 83 166 0 825079632 7297568 20458640
07:59:40 15851 8192000000 9765532656 10073788416 842737072 9.327 100 88 176 0 874688192 10998272 20952848
08:01:04 8669 8192000000 9812872976 10130976768 877188576 9.672 100 85 169 1 917561216 20899104 19473536
08:02:20 15828 8192000000 9876262640 10213089280 929834928 10.193 100 77 153 2 980337424 33678736 16823760
08:03:41 8432 8192000000 9913282560 10259820544 956389568 10.454 100 78 156 0 1025307536 52287408 16630560
08:04:51 5403 8192000000 9960483872 10315907072 985140416 10.734 100 81 163 2 1078310112 76814256 16355440
08:05:58 3561 8192000000 9983655440 10342891520 1016904992 11.042 100 77 152 2 1095497088 63070448 15521648
08:07:04 2198 8192000000 10000657952 10370723840 1049162432 11.353 100 87 172 2 1125037872 57964320 17911120
08:08:08 1409 8192000000 10023587824 10393915392 1064893072 11.503 100 88 177 0 1134612048 51262416 18456560
08:09:10 5263 8192000000 10002241936 10393370624 1067797296 11.531 100 42 83 2 1129723280 54115424 7810560
08:10:16 8148 8192000000 10001652048 10399948800 1078808912 11.636 100 30 60 2 1118885152 33702224 6374016
08:11:25 6094 8192000000 10018696800 10420011008 1092743568 11.769 100 43 88 1 1123932496 22132832 9056096
08:12:33 40276 8192000000 10035118592 10424860672 1101806608 11.855 100 75 150 2 1134035600 16275392 15953600
08:14:14 8112 8192000000 10036497888 10444914688 1106059232 11.895 100 22 44 0 1152020640 41116064 4845344
08:15:23 6534 8192000000 10010435760 10420670464 1110851632 11.940 100 39 78 3 1133543152 13887712 8803808
08:16:32 7131 8192000000 10014212064 10421170176 1109577744 11.928 100 36 73 0 1133205808 16312784 7315280
08:17:42 59867 8192000000 10008893136 10414592000 1086251440 11.707 99 47 94 1 1106404672 9745696 10407536
08:19:44 5320 8192000000 9954567808 10347884544 1023837040 11.109 97 35 70 1 1045960160 14520112 7603008
08:20:50 7193 8192000000 9949079952 10339418112 1001734960 10.895 97 52 104 0 1034303296 19968240 12600096
08:21:58 8639 8192000000 9944360800 10323734528 982743440 10.711 97 33 66 0 1004665824 14693280 7229104
08:23:07 72539 8192000000 9959104064 10363195392 1032071552 11.188 99 28 53 0 1066070528 27778560 6220416
08:25:20 8119 8192000000 9965939904 10358779904 1047728800 11.339 99 30 58 2 1072203072 18314224 6160048
08:26:29 7797 8192000000 9981609600 10377502720 1064336672 11.498 100 37 74 2 1089354304 15783584 9234048
08:27:57 15120 8192000000 10008782192 10402639872 1090951536 11.752 100 39 78 0 1112971920 14125264 7895120
08:29:16 87372 8192000000 10093033952 10508427264 1111044240 11.942 100 37 74 1 1194367280 75488176 7834864
08:31:49 22202 8192000000 10101197392 10510561280 1124015104 12.065 100 38 76 0 1217237600 84741824 8480672
08:33:13 10651 8192000000 10168412176 10589589504 1130584800 12.127 100 36 72 0 1281880624 143566512 7729312
08:34:27 25389 8192000000 10185195744 10603495424 1141874720 12.233 100 41 82 0 1285711312 134553488 9283104
08:35:56 4744 8192000000 10255476272 10660360192 1156389088 12.369 100 77 154 0 1352016944 179118944 16508912
08:37:06 4889 8192000000 10293338112 10706640896 1162744560 12.429 100 68 134 1 1398095072 220368912 14981600
08:38:16 7342 8192000000 10270226768 10704326656 1166339456 12.463 100 51 102 1 1382048000 203244288 12464256
08:39:31 2415 8192000000 10330376720 10744217600 1180179312 12.592 100 85 170 1 1436120720 236427920 19513488
08:40:39 1894 8192000000 10331739776 10736902144 1166284240 12.462 100 85 168 0 1432537040 248220240 18032560
08:41:44 2734 8192000000 10329724992 10736672768 1153432768 12.342 100 80 158 0 1431855808 260045552 18377488
08:42:53 4099 8192000000 10406766496 10814210048 1167783856 12.476 100 78 156 0 1497363840 311418720 18161264
08:44:00 4096 8192000000 10391277104 10803118080 1167635120 12.475 100 80 160 0 1491126336 305027152 18464064
08:45:10 5581 8192000000 10368040560 10782982144 1161752272 12.420 100 71 142 2 1476923536 298628400 16542864
08:46:18 14020 8192000000 10331718288 10757324800 1143084976 12.245 99 45 87 4 1425520608 271145792 11289840
08:47:36 1389 8192000000 10361877264 10789363712 1161296912 12.415 100 68 134 0 1462020112 284854208 15868992
08:48:41 1610 8192000000 10386862096 10798948352 1150994304 12.319 100 78 156 1 1479895424 310163648 18737472
08:49:46 10599 8192000000 10425302880 10852720640 1146608176 12.278 100 41 80 0 1512195264 354952768 10634320
08:51:02 7750 8192000000 10412376528 10868199424 1154579968 12.352 100 37 74 1 1536746208 372640656 9525584
08:52:17 22667 8192000000 10426989808 10907652096 1133157728 12.151 100 29 58 0 1529386688 388040880 8188080
08:54:11 28501 8192000000 10069645504 10629206016 1105611040 11.891 99 17 34 0 1353398816 243088160 4699616
08:55:55 50553 8192000000 10106291984 10531790848 1128397168 12.106 100 48 96 1 1195288816 56726752 10164896
08:57:46 23761 8192000000 10063602240 10507661312 1115958016 11.989 99 25 49 1 1169301904 48345184 4998704
08:59:12 16737 8192000000 10066887568 10536693760 1127111456 12.094 99 8 16 1 1169239648 40517920 1610272
09:00:38 84453 8192000000 10104788304 10550026240 1118740896 12.015 98 19 38 1 1193202720 70534960 3926864
09:03:03 62678 8192000000 10078482736 10531143680 1125761664 12.081 99 9 18 0 1179658464 51876000 2020800
09:05:06 73220 8192000000 10069754464 10559827968 1123222048 12.057 99 29 58 1 1163761568 34478832 6060688
09:07:20 55931 8192000000 10029757168 10476498944 1092421904 11.766 97 13 26 1 1122023936 26812080 2789952
09:09:16 36678 8192000000 10080554816 10537377792 1126603296 12.089 100 36 70 0 1182113840 47926768 7583776
09:10:53 45329 8192000000 10130716208 10573508608 1139400944 12.210 99 26 52 0 1221930080 76967264 5561872
09:12:40 62416 8192000000 10149701024 10592124928 1135886576 12.177 99 26 52 1 1237974192 96230560 5857056
09:14:43 119635 8192000000 10106862176 10567950336 1099173744 11.830 96 14 28 1 1161477104 59671872 2631488
09:18:01 57669 8192000000 10158423440 10641326080 1147489632 12.286 100 44 88 1 1243487568 86808928 9189008
09:20:11 92021 8192000000 10110995488 10580484096 1150605184 12.315 100 12 24 0 1229806656 76788144 2413328
09:22:43 97354 8192000000 10108182048 10566111232 1153071648 12.338 100 21 41 0 1215848128 57537168 5239312
09:25:21 54631 8192000000 10076645328 10589659136 1128161056 12.104 99 4 8 0 1169131360 40260416 709888
09:28:32 73165 8192000000 10081758976 10554941440 1111723168 11.949 97 20 40 0 1167039792 51448272 3868352
09:30:45 30274 8192000000 10111088272 10546843648 1126251808 12.086 98 21 42 0 1180026976 49478176 4296992
09:32:28 65399 8192000000 10141102160 10606505984 1119606368 12.023 98 21 42 0 1211781136 87310928 4863840
09:34:58 49807 8192000000 10113858256 10590822400 1126242592 12.086 99 16 32 0 1178646432 49083232 3320608
09:36:55 82126 8192000000 10123720224 10584657920 1118256416 12.011 99 20 40 1 1204752624 82270912 4225296
09:39:23 98730 8192000000 10052497808 10497449984 1069373152 11.546 95 9 19 0 1108020400 37177456 1469792
09:42:02 125783 8192000000 10058862832 10512150528 1097780656 11.817 99 22 44 1 1137127200 34699856 4646688
09:45:14 32203 8192000000 10025342544 10481586176 1074893872 11.599 98 6 12 0 1124275840 48160704 1221264
09:46:46 63170 8192000000 10052023680 10510499840 1073141680 11.582 98 9 18 0 1134542000 59650176 1750144
09:48:49 60085 8192000000 10012282560 10472374272 1079663648 11.644 98 11 22 0 1124347664 42534208 2149808
09:50:50 94490 8192000000 10090646336 10528620544 1120328960 12.030 100 19 38 0 1176321904 52404736 3588208
09:53:25 54177 8192000000 10051129664 10509914112 1092576576 11.767 98 13 27 1 1134608880 39368112 2664192
09:55:20 43410 8192000000 10064776656 10510700544 1107641536 11.910 99 23 47 1 1161062160 48685536 4735088
09:57:10 56036 8192000000 10079761760 10535956480 1122045424 12.046 99 24 48 0 1174939552 48401216 4492912
09:59:06 101922 8192000000 10062016144 10489708544 1087259808 11.717 96 16 32 1 1116042816 25821536 2961472
10:02:00 86721 8192000000 10061554208 10538983424 1126427808 12.088 100 33 65 0 1192393104 58644208 7321088
10:04:50 56529 8192000000 10093680320 10555887616 1106363856 11.898 98 6 12 0 1152745104 45196224 1185024
10:07:18 72944 8192000000 10108386944 10555879424 1125196464 12.076 99 20 40 1 1198404800 69055152 4153184
10:09:35 67820 8192000000 10069735152 10501963776 1113788896 11.968 98 16 32 2 1159888528 42996368 3103264
10:12:30 28127 8192000000 10100531200 10533380096 1112777216 11.959 98 36 73 1 1170273456 50238208 7258032
10:13:59 114460 8192000000 10070695232 10553360384 1110828800 11.940 98 10 20 1 1157956896 45417088 1711008
10:16:54 125797 8192000000 10068276544 10525732864 1122411280 12.050 99 24 47 1 1167768800 40447216 4910304
10:20:40 56639 8192000000 10074220368 10556944384 1114539840 11.975 98 5 10 0 1159473360 43937632 995888
10:22:37 40331 8192000000 10077809776 10525892608 1126150336 12.085 99 22 44 2 1170797232 39432016 5214880
10:24:17 44339 8192000000 10066653856 10502828032 1101453472 11.851 97 21 42 1 1131711968 25972928 4285568
10:26:21 57322 8192000000 9093567360 9385107456 220940800 2.626 20 8 14 1 279078160 56290912 1846448

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-06-2021 10:28:32 to 15-06-2021 10:28:47.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-06-2021 10:28:48 to 15-06-2021 10:28:50.

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          228087
MON$OLDEST_ACTIVE               229305
MON$OLDEST_SNAPSHOT             229305
MON$NEXT_TRANSACTION            229305
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                       2069664
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             786
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2517 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2517 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2517 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2517 Firebird 4.0/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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Tue Jun 15 10:27:28 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
--- end of diff output ---

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

15.06.21 10:28:51. 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-06-15 10:28:53 No severe PSQL-related problems occured
15.06.21 10:28:55. End of report.