Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.02.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 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 16553888 kB
MemAvailable 31115596 kB
Buffers 0 kB
Cached 15103076 kB
SwapCached 3944 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-02-19 13:26:58
DTS_END                         2021-02-19 13:26:58.1200
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43564
TRN_ID                          238275
ATT_ID                          677
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: 9027.32 29471 1083278
customer order: creation 167.75 430 20130
customer order: refuse 14.68 293 1762
order to supplier: creation 45.88 1314 5506
order to supplier: removal 3.03 129803 363
invoice (draft): creation 46.29 3400 5555
invoice (draft): removal 49.23 946 5908
invoice accept: apply 41.73 16868 5008
invoice accept: cancel 44.81 903 5377
customer reserve: creation 3894.78 162 467374
customer reserve: removal 4427.93 70 531351
realization accept: apply 81.89 165 9827
realization accept: cancel 82.29 120 9875
payment from customer: creation 59.23 118 7107
payment from customer: removal 5.58 75 670
payment to supplier: creation 47.49 126 5699
payment to supplier: removal 3.43 74 411
service: total inventory turnovers 4.20 2873 504
service: total monetary turnovers 6.15 415 738
service: aggregate perf. data 0.91 13560 109
service: refresh index statistics 0.03 417712 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2343
TEST_TIME 2 2419
TEST_TIME 3 2559
TEST_TIME 4 3220
TEST_TIME 5 3216
TEST_TIME 6 4292
TEST_TIME 7 4374
TEST_TIME 8 4483
TEST_TIME 9 4723
TEST_TIME 10 5255
TEST_TIME 11 5512
TEST_TIME 12 5750
TEST_TIME 13 5866
TEST_TIME 14 5959
TEST_TIME 15 6351
TEST_TIME 16 6621
TEST_TIME 17 6927
TEST_TIME 18 7297
TEST_TIME 19 8375
TEST_TIME 20 8249
TEST_TIME 21 8717
TEST_TIME 22 8792
TEST_TIME 23 9130
TEST_TIME 24 9113
TEST_TIME 25 9411
TEST_TIME 26 9911
TEST_TIME 27 10669
TEST_TIME 28 11764
TEST_TIME 29 11532
TEST_TIME 30 11224
TEST_TIME 31 10928
TEST_TIME 32 10933
TEST_TIME 33 10802
TEST_TIME 34 10688
TEST_TIME 35 10496
TEST_TIME 36 10383
TEST_TIME 37 10251
TEST_TIME 38 10112
TEST_TIME 39 10010
TEST_TIME 40 9946
TEST_TIME 41 9936
TEST_TIME 42 9804
TEST_TIME 43 9756
TEST_TIME 44 9752
TEST_TIME 45 9824
TEST_TIME 46 9746
TEST_TIME 47 9841
TEST_TIME 48 9960
TEST_TIME 49 9967
TEST_TIME 50 9944
TEST_TIME 51 10064
TEST_TIME 52 10000
TEST_TIME 53 9941
TEST_TIME 54 9853
TEST_TIME 55 9731
TEST_TIME 56 9671
TEST_TIME 57 9604
TEST_TIME 58 9520
TEST_TIME 59 9454
TEST_TIME 60 9483
TEST_TIME 61 9414
TEST_TIME 62 9451
TEST_TIME 63 9431
TEST_TIME 64 9575
TEST_TIME 65 9560
TEST_TIME 66 9643
TEST_TIME 67 9578
TEST_TIME 68 9598
TEST_TIME 69 9559
TEST_TIME 70 9574
TEST_TIME 71 9706
TEST_TIME 72 9652
TEST_TIME 73 9683
TEST_TIME 74 9602
TEST_TIME 75 9518
TEST_TIME 76 9445
TEST_TIME 77 9381
TEST_TIME 78 9329
TEST_TIME 79 9325
TEST_TIME 80 9302
TEST_TIME 81 9307
TEST_TIME 82 9298
TEST_TIME 83 9287
TEST_TIME 84 9278
TEST_TIME 85 9279
TEST_TIME 86 9269
TEST_TIME 87 9372
TEST_TIME 88 9319
TEST_TIME 89 9362
TEST_TIME 90 9303
TEST_TIME 91 9259
TEST_TIME 92 9218
TEST_TIME 93 9151
TEST_TIME 94 9142
TEST_TIME 95 9186
TEST_TIME 96 9148
TEST_TIME 97 9157
TEST_TIME 98 9126
TEST_TIME 99 9130
TEST_TIME 100 9178
TEST_TIME 101 9170
TEST_TIME 102 9188
TEST_TIME 103 9161
TEST_TIME 104 9191
TEST_TIME 105 9240
TEST_TIME 106 9245
TEST_TIME 107 9237
TEST_TIME 108 9351
TEST_TIME 109 9269
TEST_TIME 110 9203
TEST_TIME 111 9158
TEST_TIME 112 9131
TEST_TIME 113 9109
TEST_TIME 114 9104
TEST_TIME 115 9093
TEST_TIME 116 9077
TEST_TIME 117 9041
TEST_TIME 118 9034
TEST_TIME 119 9010
TEST_TIME 120 9030

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-02-2021 13:28:17 to 19-02-2021 13:28:21.

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 1513776 1513776 0 0.00 0 30150 6 0 0
doc_list_biud 1513774 1513774 0 0.00 0 98993 31 0 0
sp_add_invoice_to_stock 5131 5008 123 2.39 35 208210 16869 0 123
sp_cancel_adding_invoice 5377 5377 0 0.00 6 84513 903 0 0
sp_cancel_client_order 1762 1762 0 0.00 4 9004 294 0 0
sp_cancel_customer_reserve 531351 531351 0 0.00 1 98995 71 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 3 7217 75 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 5080 74 0 0
sp_cancel_supplier_invoice 5917 5908 9 0.15 1 69207 946 0 9
sp_cancel_supplier_order 363 363 0 0.00 9 1124233 129803 0 0
sp_cancel_write_off 9875 9875 0 0.00 1 12340 120 0 0
sp_client_order 20130 20130 0 0.00 4 23696 430 0 0
sp_customer_reserve 467374 467374 0 0.00 0 86130 163 0 0
sp_fill_shopping_cart 31184 31184 0 0.00 1 11225 47 0 0
sp_fill_shopping_cart_clo_res 467368 467368 0 0.00 0 22605 19 0 0
sp_get_clo_for_invoice 5002 5002 0 0.00 0 37519 439 0 0
sp_kill_cost_storno 16333 16333 0 0.00 0 30140 61 0 0
sp_kill_qstorno_ret_qs2qd 1078848 1078848 0 0.00 0 98990 38 0 0
sp_kill_qty_storno 569464 569464 0 0.00 0 98992 81 0 0
sp_lock_selected_doc 20491 20491 0 0.00 0 7355 4 0 0
sp_make_cost_storno 27634 27634 0 0.00 0 25832 60 0 0
sp_multiply_rows_for_pdistr 27633 27633 0 0.00 0 16057 29 0 0
sp_multiply_rows_for_qdistr 20130 20130 0 0.00 0 21209 256 0 0
sp_pay_from_customer 7107 7107 0 0.00 4 19592 118 0 0
sp_pay_to_supplier 5699 5699 0 0.00 4 20742 126 0 0
sp_payment_common 12805 12805 0 0.00 1 19570 25 0 0
sp_qd_handle_on_cancel_clo 1762 1762 0 0.00 0 8998 270 0 0
sp_qd_handle_on_invoice_upd_sts 10378 10378 0 0.00 0 19818 378 0 0
sp_qd_handle_on_reserve_upd_sts 9827 9827 0 0.00 0 10773 43 0 0
sp_reserve_write_off 9827 9827 0 0.00 7 23270 166 0 0
sp_supplier_invoice 5555 5555 0 0.00 8 116450 3401 0 0
sp_supplier_order 5506 5506 0 0.00 6 91113 1314 0 0
srv_aggregate_perf_data 109 109 0 0.00 2957 101149 13561 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1288 122351 46312 0 0
srv_find_qd_qs_mism 1034681 1034681 0 0.00 0 19838 5 0 0
srv_make_invnt_saldo 504 504 0 0.00 52 109912 2873 0 0
srv_make_money_saldo 738 738 0 0.00 1 13053 415 0 0
srv_recalc_idx_stat 4 4 0 0.00 339364 492134 417712 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 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 5008 5008 0 0.00 0 21456 159 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 3805 95 0 0
t$perf-norm:sp_cancel_client_order 1762 1762 0 0.00 0 7087 15 0 0
t$perf-norm:sp_cancel_customer_reserve 673 673 0 0.00 0 1544 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 3858 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 190 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 739 14 0 0
t$perf-norm:sp_cancel_supplier_order 363 363 0 0.00 0 17231 1556 0 0
t$perf-norm:sp_cancel_write_off 988 988 0 0.00 0 6181 13 0 0
t$perf-norm:sp_client_order 20130 20130 0 0.00 0 20274 17 0 0
t$perf-norm:sp_customer_reserve 1030 1030 0 0.00 0 736 3 0 0
t$perf-norm:sp_pay_from_customer 7107 7107 0 0.00 0 11645 13 0 0
t$perf-norm:sp_pay_to_supplier 5699 5699 0 0.00 0 5475 10 0 0
t$perf-norm:sp_reserve_write_off 9827 9827 0 0.00 0 8599 13 0 0
t$perf-norm:sp_supplier_invoice 5555 5555 0 0.00 0 9278 18 0 0
t$perf-norm:sp_supplier_order 5506 5506 0 0.00 0 4260 14 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 535 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 231 6 0 0
t$perf-norm:srv_make_invnt_saldo 504 504 0 0.00 0 1016 8 0 0
t$perf-norm:srv_make_money_saldo 738 738 0 0.00 0 393 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10248 10002 246 2.40 0 3301 16 0 246
v_all_customers 36746 36746 0 0.00 0 16 1 0 0
v_all_suppliers 30266 30266 0 0.00 0 21 1 0 0
v_all_wares 40260 40260 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 723 7 0 0
v_cancel_client_order 3524 3524 0 0.00 0 2946 6 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 1464 5 0 0
v_cancel_customer_reserve 1346 1346 0 0.00 0 2682 10 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 5072 10 0 0
v_cancel_supplier_invoice 780 762 18 2.30 0 409 11 0 18
v_cancel_supplier_order 726 726 0 0.00 0 3321 15 0 0
v_cancel_write_off 1976 1976 0 0.00 0 1149 12 0 0
v_max_id_clo_ord 5505 5505 0 0.00 0 822 1 0 0
v_max_id_clo_res 1030 1030 0 0.00 0 4479 16 0 0
v_max_id_ord_sup 5548 5548 0 0.00 0 5300 6 0 0
v_max_non_paid_invoice 1618 1618 0 0.00 0 261 2 0 0
v_max_non_paid_realizn 3769 3769 0 0.00 0 3378 5 0 0
v_min_id_clo_ord 5505 5505 0 0.00 0 9361 15 0 0
v_min_id_clo_res 1030 1030 0 0.00 0 6700 18 0 0
v_min_id_ord_sup 5548 5548 0 0.00 0 4713 7 0 0
v_min_non_paid_invoice 5697 5697 0 0.00 0 3345 13 0 0
v_min_non_paid_realizn 7107 7107 0 0.00 0 11175 18 0 0
v_our_firm 10190 10190 0 0.00 0 17 1 0 0
v_reserve_write_off 19652 19652 0 0.00 0 3727 5 0 0
x_lock_depdocs_on_canc_invoice 10904 10904 0 0.00 0 15602 120 0 0
x_lock_depdocs_on_canc_sup_ord 363 363 0 0.00 0 3482 356 0 0
x_make_qstorno_1000_1200 5506 5506 0 0.00 2 91077 1234 0 0
x_make_qstorno_1200_2000 5555 5555 0 0.00 4 116443 3224 0 0
x_make_qstorno_2100_3300 467373 467373 0 0.00 0 86128 141 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-02-2021 13:28:22 to 19-02-2021 13:28:26.

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:01 8 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:03 34981 8192000000 9721942112 10019110912 797782112 8.874 100 94 188 0 830078800 8124064 24172624
10:59:44 28943 8192000000 9770052528 10076758016 840870304 9.309 100 89 178 0 874567184 12610480 21086400
11:01:23 16753 8192000000 9812615376 10131886080 876135024 9.661 100 80 159 1 922241280 27797984 18308272
11:02:46 13423 8192000000 9889871344 10223861760 926474544 10.160 100 76 152 0 1000891872 57817984 16599344
11:04:01 6129 8192000000 9948866864 10290200576 970308480 10.590 100 92 183 0 1064859584 76117584 18433520
11:05:10 3660 8192000000 9989788016 10345095168 1010594624 10.981 100 82 165 0 1101549232 74481648 16472960
11:06:16 2869 8192000000 10006130160 10367016960 1027687328 11.146 100 82 160 0 1118558032 75914768 14955936
11:07:21 2136 8192000000 10041066208 10406932480 1063015728 11.485 100 93 186 0 1159024128 76426640 19581760
11:08:24 7806 8192000000 10063493600 10462216192 1068880848 11.541 100 70 140 0 1159549440 76477120 14191472
11:09:50 7610 8192000000 10001159328 10402791424 1078397920 11.632 100 29 58 1 1130137488 46408688 5330880
11:11:00 6619 8192000000 10002451104 10399977472 1086972960 11.714 100 35 70 0 1123079600 28438592 7668048
11:12:08 11175 8192000000 9998968960 10410749952 1091021392 11.752 100 29 56 2 1123229056 25503584 6704080
11:13:21 65461 8192000000 9998397792 10400505856 1095868528 11.798 100 24 46 1 1112710832 11136176 5706128
11:15:27 4056 8192000000 10013526752 10418679808 1102818256 11.864 100 50 98 2 1135899296 22348672 10732368
11:16:31 4888 8192000000 10025074080 10427510784 1096392944 11.803 100 45 90 1 1130949312 23579936 10976432
11:17:36 6651 8192000000 9966692208 10350546944 1033048912 11.198 96 28 56 1 1061344000 22504464 5790624
11:18:43 40458 8192000000 9937503744 10330820608 928990448 10.185 87 24 48 0 953159728 18659360 5509920
11:20:24 13163 8192000000 9926540784 10319503360 969635216 10.583 93 28 55 1 998261920 22531424 6095280
11:21:38 12347 8192000000 9942960432 10333130752 1006303184 10.940 98 33 67 0 1035286608 20824512 8158912
11:22:50 69654 8192000000 9949899888 10354925568 1030061776 11.169 99 30 59 3 1052456848 15678432 6716640
11:25:13 5606 8192000000 9975962784 10376261632 1060667136 11.463 100 45 89 0 1092810928 22070752 10073040
11:26:21 8613 8192000000 9986402928 10393944064 1070297280 11.555 100 35 71 1 1092608016 14113952 8196784
11:27:39 15671 8192000000 10021121296 10413305856 1096923856 11.808 100 50 101 2 1127220336 19084096 11212384
11:28:56 41823 8192000000 10076639984 10484613120 1118061632 12.009 100 36 72 2 1184971312 59521792 7387888
11:30:47 12324 8192000000 10108370320 10529656832 1128809200 12.110 100 27 53 1 1219150720 84488480 5853040
11:32:08 17245 8192000000 10139639680 10555379712 1145877840 12.271 100 55 109 0 1246395760 88590928 11926992
11:33:32 8931 8192000000 10172984128 10577788928 1162126512 12.423 100 52 104 0 1285708144 112341568 11240064
11:34:48 5286 8192000000 10227374832 10628321280 1160241936 12.406 100 71 141 2 1324186560 149156240 14788384
11:35:59 10624 8192000000 10247990864 10665263104 1167515008 12.474 100 56 110 2 1350987888 171399472 12073408
11:37:13 10186 8192000000 10259622784 10683613184 1179020192 12.581 100 48 96 0 1369441872 179090224 11331456
11:38:30 2717 8192000000 10291393088 10699952128 1187971712 12.664 100 70 138 2 1388753104 185517936 15263456
11:39:37 2531 8192000000 10320988464 10729422848 1179968208 12.590 100 77 151 2 1426708352 229352416 17387728
11:40:46 3856 8192000000 10393556784 10804072448 1185655808 12.643 100 85 171 2 1489319440 284077712 19585920
11:41:55 1287 8192000000 10451238544 10865123328 1205103616 12.824 100 81 161 4 1552402880 328828752 18470512
11:43:01 2364 8192000000 10457970640 10868117504 1186146688 12.647 100 87 168 0 1557221776 350804496 20270592
11:44:11 5752 8192000000 10459050336 10881814528 1168808208 12.486 100 72 143 0 1568681056 382540096 17332752
11:45:19 6163 8192000000 10484274144 10908577792 1178659952 12.578 100 61 122 0 1589549168 396193920 14695296
11:46:38 6807 8192000000 10426430528 10845179904 1165428784 12.454 100 50 100 0 1515280224 337261904 12589536
11:47:51 7191 8192000000 10412115984 10841284608 1160005984 12.403 99 40 78 1 1509099152 339653440 9439728
11:49:02 11418 8192000000 10448140608 10874277888 1169991808 12.497 100 37 74 1 1528568640 348989120 9587712
11:50:17 34712 8192000000 10460548256 10918764544 1162747328 12.429 100 32 64 1 1546374416 374818464 8808624
11:51:59 28857 8192000000 10436642656 10920030208 1129192656 12.114 99 24 48 1 1567619248 431727600 6698992
11:53:33 27545 8192000000 10236682928 10726182912 1118045680 12.009 99 23 46 0 1378686416 254901712 5739024
11:55:01 41725 8192000000 10040284224 10464993280 1089427888 11.737 98 18 37 2 1120767584 27486144 3853552
11:56:49 71342 8192000000 10061784672 10483175424 1072599552 11.577 96 39 76 1 1130840736 50696880 7544304
11:59:20 81825 8192000000 10027733008 10494681088 1080149136 11.649 97 6 12 0 1112857008 31985072 722800
12:01:49 9673 8192000000 10052713328 10494836736 1118619952 12.014 100 16 32 3 1158614544 36591760 3402832
12:03:10 25784 8192000000 10048012256 10480705536 1110963680 11.942 100 19 38 0 1148540528 33728752 3848096
12:04:36 55523 8192000000 10051054336 10510438400 1109045408 11.923 99 10 19 0 1167200352 56058352 2096592
12:06:43 42237 8192000000 10037752176 10491797504 1109268688 11.925 99 5 10 0 1153770752 43624688 877376
12:08:26 66589 8192000000 10126244768 10589995008 1129343344 12.115 100 24 48 1 1210208496 75798144 5067008
12:11:03 37252 8192000000 10087997328 10530668544 1124304384 12.068 100 16 32 1 1180124512 52421840 3398288
12:12:42 82081 8192000000 10121924016 10578526208 1100937600 11.847 97 21 42 1 1209803472 104360128 4505744
12:15:07 41155 8192000000 10101952944 10565197824 1139178480 12.208 100 32 64 2 1195814352 49831168 6804704
12:17:03 87973 8192000000 10080207728 10533720064 1138243664 12.199 100 16 32 0 1183236912 41902272 3090976
12:19:36 114506 8192000000 10074273840 10547560448 1145051536 12.263 100 20 40 1 1208094240 58666048 4376656
12:22:32 63237 8192000000 10063331648 10515288064 1117480768 12.003 97 14 27 0 1151268896 31035520 2752608
12:24:36 45819 8192000000 10090283344 10552807424 1121080576 12.037 98 11 22 1 1185350032 61835216 2434240
12:26:28 75962 8192000000 10110465056 10563022848 1085586304 11.701 95 10 20 0 1136222272 48579184 2056784
12:28:44 65222 8192000000 10157352480 10631065600 1128318768 12.106 98 17 34 2 1224427392 92644960 3463664
12:31:04 41209 8192000000 10091365120 10567581696 1116230976 11.991 98 14 28 4 1171078672 51876800 2970896
12:32:46 40951 8192000000 10058184496 10494853120 1093842864 11.779 96 16 32 0 1128648896 31960640 2845392
12:34:40 71801 8192000000 10132697008 10589229056 1118731552 12.015 98 17 34 0 1202032512 79614240 3686720
12:36:52 98909 8192000000 10068123264 10532679680 1099737456 11.835 97 10 20 0 1167847136 65468928 2640752
12:39:34 112257 8192000000 10057091920 10533212160 1120927568 12.036 99 18 36 0 1171386400 46537952 3920880
12:42:26 41049 8192000000 10025081856 10485334016 1112352384 11.955 100 8 16 0 1129753008 16036320 1364304
12:44:14 48629 8192000000 10054731232 10508300288 1069151168 11.544 95 14 28 0 1111904800 40385856 2367776
12:46:03 105880 8192000000 10073789808 10517938176 1067945728 11.532 95 18 36 0 1129251120 57696912 3608480
12:48:49 26766 8192000000 10053388464 10480693248 1099544800 11.833 98 38 74 1 1136328368 29219200 7564368
12:50:16 62473 8192000000 10080441920 10534256640 1079217280 11.640 96 27 54 1 1126720544 41572448 5930816
12:52:29 86704 8192000000 10061004448 10562658304 1055929776 11.418 94 17 34 0 1142321232 82980128 3411328
12:54:56 40361 8192000000 10074519744 10528333824 1097955200 11.818 99 25 50 0 1153552448 50582112 5015136
12:56:45 116947 8192000000 10055752992 10538872832 1111013632 11.942 100 24 48 0 1162384080 46702512 4667936
12:59:42 35201 8192000000 10056643680 10526412800 1097648624 11.815 98 8 16 0 1153952192 54599312 1704256
13:01:30 75501 8192000000 10054883904 10513465344 1095956256 11.799 97 12 24 0 1147213808 48732512 2525040
13:03:47 39228 8192000000 10078255152 10520956928 1115707776 11.986 98 16 32 0 1152549472 33173056 3668640
13:05:26 92827 8192000000 10101143984 10536865792 1123816160 12.063 98 16 32 0 1202189696 75055104 3318432
13:08:00 59396 8192000000 10089030896 10555076608 1140142480 12.217 99 28 54 0 1185456864 39569392 5744992
13:10:00 51023 8192000000 10145226464 10620932096 1145413232 12.266 99 36 72 2 1221149376 68634624 7101520
13:11:57 78804 8192000000 10141084912 10618044416 1132146320 12.142 98 23 45 0 1180850272 43536896 5167056
13:14:17 122351 8192000000 10102275264 10611019776 1140541696 12.221 99 17 34 2 1208253328 64289232 3422400
13:17:45 68586 8192000000 10067424656 10540318720 1125056240 12.075 98 7 14 0 1175123040 48149744 1917056
13:20:00 29624 8192000000 10051680464 10495885312 1061492080 11.471 93 10 20 0 1097544848 34070752 1982016
13:21:34 43965 8192000000 10051820384 10508091392 1113262112 11.963 100 18 36 0 1147292752 30126912 3903728
13:23:19 31393 8192000000 10132770496 10561572864 1121313456 12.039 99 45 90 1 1192395072 61513632 9567984
13:24:58 70874 8192000000 10171966624 10609999872 1099097616 11.829 97 30 60 1 1233318304 127903696 6316992

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-02-2021 13:28:28 to 19-02-2021 13:28:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 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 19-02-2021 13:28:44 to 19-02-2021 13:28:46.

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          232791
MON$OLDEST_ACTIVE               238398
MON$OLDEST_SNAPSHOT             238398
MON$NEXT_TRANSACTION            238398
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                       2086752
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             804
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2370 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2370 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2370 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.2370 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 ---
:24: 
:25: mscs-test-161.budzdorov.ru    Fri Feb 19 13:26:59 2021
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
:28: 
:29: mscs-test-161.budzdorov.ru    Fri Feb 19 13:27:00 2021
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Fri Feb 19 13:27:08 2021
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
--- end of diff output ---

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

19.02.21 13:28:47. 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-02-19 13:28:49 No severe PSQL-related problems occured
19.02.21 13:28:51. End of report.