Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.11.2021 07:37
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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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.33
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 16600100 kB
MemAvailable 31548280 kB
Buffers 0 kB
Cached 15052896 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.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-11-22 07:37:03
DTS_END                         2021-11-22 07:37:03.3660
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52466
TRN_ID                          238977
ATT_ID                          707
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: 9105.49 23325 1092659
customer order: creation 176.56 427 21187
customer order: refuse 15.16 401 1819
order to supplier: creation 47.75 1508 5730
order to supplier: removal 3.38 119909 406
invoice (draft): creation 46.98 3669 5637
invoice (draft): removal 48.44 1115 5813
invoice accept: apply 43.45 22095 5214
invoice accept: cancel 44.26 1079 5311
customer reserve: creation 4031.10 217 483732
customer reserve: removal 4358.01 73 522961
realization accept: apply 85.40 200 10248
realization accept: cancel 72.21 149 8665
payment from customer: creation 60.29 124 7235
payment from customer: removal 5.43 99 652
payment to supplier: creation 50.97 154 6116
payment to supplier: removal 4.07 113 488
service: total inventory turnovers 4.08 3643 490
service: total monetary turnovers 6.97 592 836
service: aggregate perf. data 0.96 14068 115
service: refresh index statistics 0.03 296868 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-11-2021 07:38:00 to 22-11-2021 07:38:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2986
TEST_TIME 2 3343
TEST_TIME 3 3683
TEST_TIME 4 4468
TEST_TIME 5 5065
TEST_TIME 6 5388
TEST_TIME 7 5908
TEST_TIME 8 5781
TEST_TIME 9 6215
TEST_TIME 10 6326
TEST_TIME 11 6638
TEST_TIME 12 6774
TEST_TIME 13 6697
TEST_TIME 14 6895
TEST_TIME 15 7056
TEST_TIME 16 7233
TEST_TIME 17 7595
TEST_TIME 18 7667
TEST_TIME 19 7687
TEST_TIME 20 8040
TEST_TIME 21 8608
TEST_TIME 22 8614
TEST_TIME 23 8561
TEST_TIME 24 8666
TEST_TIME 25 8764
TEST_TIME 26 8681
TEST_TIME 27 8723
TEST_TIME 28 8618
TEST_TIME 29 9139
TEST_TIME 30 9853
TEST_TIME 31 10771
TEST_TIME 32 10775
TEST_TIME 33 10788
TEST_TIME 34 10874
TEST_TIME 35 10691
TEST_TIME 36 10600
TEST_TIME 37 10457
TEST_TIME 38 10305
TEST_TIME 39 10210
TEST_TIME 40 10079
TEST_TIME 41 10082
TEST_TIME 42 10017
TEST_TIME 43 9927
TEST_TIME 44 10011
TEST_TIME 45 10029
TEST_TIME 46 9933
TEST_TIME 47 9817
TEST_TIME 48 9795
TEST_TIME 49 9728
TEST_TIME 50 10030
TEST_TIME 51 9907
TEST_TIME 52 10048
TEST_TIME 53 9971
TEST_TIME 54 9953
TEST_TIME 55 9947
TEST_TIME 56 10021
TEST_TIME 57 9940
TEST_TIME 58 9926
TEST_TIME 59 10005
TEST_TIME 60 9898
TEST_TIME 61 9969
TEST_TIME 62 9873
TEST_TIME 63 9787
TEST_TIME 64 9667
TEST_TIME 65 9658
TEST_TIME 66 9637
TEST_TIME 67 9583
TEST_TIME 68 9514
TEST_TIME 69 9507
TEST_TIME 70 9418
TEST_TIME 71 9393
TEST_TIME 72 9379
TEST_TIME 73 9352
TEST_TIME 74 9322
TEST_TIME 75 9304
TEST_TIME 76 9401
TEST_TIME 77 9350
TEST_TIME 78 9348
TEST_TIME 79 9377
TEST_TIME 80 9427
TEST_TIME 81 9428
TEST_TIME 82 9453
TEST_TIME 83 9477
TEST_TIME 84 9440
TEST_TIME 85 9396
TEST_TIME 86 9354
TEST_TIME 87 9294
TEST_TIME 88 9270
TEST_TIME 89 9212
TEST_TIME 90 9159
TEST_TIME 91 9194
TEST_TIME 92 9175
TEST_TIME 93 9169
TEST_TIME 94 9136
TEST_TIME 95 9177
TEST_TIME 96 9187
TEST_TIME 97 9166
TEST_TIME 98 9253
TEST_TIME 99 9217
TEST_TIME 100 9198
TEST_TIME 101 9217
TEST_TIME 102 9209
TEST_TIME 103 9265
TEST_TIME 104 9306
TEST_TIME 105 9269
TEST_TIME 106 9232
TEST_TIME 107 9221
TEST_TIME 108 9185
TEST_TIME 109 9136
TEST_TIME 110 9104
TEST_TIME 111 9111
TEST_TIME 112 9137
TEST_TIME 113 9111
TEST_TIME 114 9104
TEST_TIME 115 9092
TEST_TIME 116 9078
TEST_TIME 117 9050
TEST_TIME 118 9043
TEST_TIME 119 9180
TEST_TIME 120 9107

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-11-2021 07:38:04 to 22-11-2021 07:38:07.

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 1537955 1537955 0 0.00 0 22999 8 0 0
doc_list_biud 1537955 1537955 0 0.00 0 40816 32 0 0
sp_add_invoice_to_stock 5421 5214 207 3.81 26 229649 22095 0 207
sp_cancel_adding_invoice 5311 5311 0 0.00 5 94656 1080 0 0
sp_cancel_client_order 1819 1819 0 0.00 5 13005 402 0 0
sp_cancel_customer_reserve 522961 522961 0 0.00 1 26631 73 0 0
sp_cancel_pay_from_customer 652 652 0 0.00 2 4161 99 0 0
sp_cancel_pay_to_supplier 488 488 0 0.00 3 3733 113 0 0
sp_cancel_supplier_invoice 5829 5813 16 0.27 2 27125 1115 0 16
sp_cancel_supplier_order 406 406 0 0.00 10 1076794 119909 0 0
sp_cancel_write_off 8665 8665 0 0.00 1 7383 150 0 0
sp_client_order 21187 21187 0 0.00 4 38762 427 0 0
sp_customer_reserve 483732 483732 0 0.00 0 75912 218 0 0
sp_fill_shopping_cart 32543 32543 0 0.00 1 22777 46 0 0
sp_fill_shopping_cart_clo_res 483730 483730 0 0.00 0 31728 21 0 0
sp_get_clo_for_invoice 5209 5209 0 0.00 0 51251 360 0 0
sp_kill_cost_storno 15116 15116 0 0.00 0 7357 71 0 0
sp_kill_qstorno_ret_qs2qd 1060806 1060806 0 0.00 0 25839 40 0 0
sp_kill_qty_storno 560432 560432 0 0.00 0 40815 86 0 0
sp_lock_selected_doc 21425 21425 0 0.00 0 16130 6 0 0
sp_make_cost_storno 28808 28808 0 0.00 0 16731 63 0 0
sp_multiply_rows_for_pdistr 28808 28808 0 0.00 0 8502 35 0 0
sp_multiply_rows_for_qdistr 21187 21187 0 0.00 0 27319 209 0 0
sp_pay_from_customer 7235 7235 0 0.00 4 16948 124 0 0
sp_pay_to_supplier 6116 6116 0 0.00 5 15919 155 0 0
sp_payment_common 13351 13351 0 0.00 1 12888 23 0 0
sp_qd_handle_on_cancel_clo 1819 1819 0 0.00 0 12998 369 0 0
sp_qd_handle_on_invoice_upd_sts 10520 10520 0 0.00 0 40814 404 0 0
sp_qd_handle_on_reserve_upd_sts 10248 10248 0 0.00 0 12899 56 0 0
sp_reserve_write_off 10248 10248 0 0.00 6 16980 200 0 0
sp_supplier_invoice 5637 5637 0 0.00 8 86900 3670 0 0
sp_supplier_order 5730 5730 0 0.00 6 63600 1509 0 0
srv_aggregate_perf_data 115 115 0 0.00 2734 175019 14068 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 1365 116073 39479 0 0
srv_find_qd_qs_mism 1041954 1041954 0 0.00 0 22998 7 0 0
srv_make_invnt_saldo 490 490 0 0.00 47 99017 3644 0 0
srv_make_money_saldo 836 836 0 0.00 1 10889 593 0 0
srv_recalc_idx_stat 4 4 0 0.00 255425 356785 296868 0 0
t$perf-abend:sp_add_invoice_to_stock 207 207 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5214 5214 0 0.00 0 32775 220 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 0 2163 87 0 0
t$perf-norm:sp_cancel_client_order 1819 1819 0 0.00 0 6255 20 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 1423 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 652 652 0 0.00 0 2543 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 488 488 0 0.00 0 19116 45 0 0
t$perf-norm:sp_cancel_supplier_invoice 447 447 0 0.00 0 29191 87 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 24235 1584 0 0
t$perf-norm:sp_cancel_write_off 1037 1037 0 0.00 0 8538 11 0 0
t$perf-norm:sp_client_order 21187 21187 0 0.00 0 16564 22 0 0
t$perf-norm:sp_customer_reserve 1000 1000 0 0.00 0 4661 11 0 0
t$perf-norm:sp_pay_from_customer 7235 7235 0 0.00 0 8474 8 0 0
t$perf-norm:sp_pay_to_supplier 6116 6116 0 0.00 0 16544 13 0 0
t$perf-norm:sp_reserve_write_off 10248 10248 0 0.00 0 16555 12 0 0
t$perf-norm:sp_supplier_invoice 5637 5637 0 0.00 0 12335 26 0 0
t$perf-norm:sp_supplier_order 5730 5730 0 0.00 0 4315 21 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 1210 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 365 11 0 0
t$perf-norm:srv_make_invnt_saldo 490 490 0 0.00 0 470 8 0 0
t$perf-norm:srv_make_money_saldo 836 836 0 0.00 0 493 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 10832 10418 414 3.82 0 5707 22 0 414
v_all_customers 38602 38602 0 0.00 0 55 1 0 0
v_all_suppliers 31288 31288 0 0.00 0 40 1 0 0
v_all_wares 42374 42374 0 0.00 0 87 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 4880 14 0 0
v_cancel_client_order 3638 3638 0 0.00 0 3833 8 0 0
v_cancel_customer_prepayment 1304 1304 0 0.00 0 4085 12 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 3187 11 0 0
v_cancel_payment_to_supplier 976 976 0 0.00 0 3646 13 0 0
v_cancel_supplier_invoice 926 894 32 3.45 0 5279 38 0 32
v_cancel_supplier_order 812 812 0 0.00 0 2371 13 0 0
v_cancel_write_off 2074 2074 0 0.00 0 5307 21 0 0
v_max_id_clo_ord 5730 5730 0 0.00 0 395 2 0 0
v_max_id_clo_res 1000 1000 0 0.00 0 5835 23 0 0
v_max_id_ord_sup 5626 5626 0 0.00 0 5774 5 0 0
v_max_non_paid_invoice 1828 1828 0 0.00 0 1710 3 0 0
v_max_non_paid_realizn 3865 3865 0 0.00 0 1922 4 0 0
v_min_id_clo_ord 5730 5730 0 0.00 0 3042 8 0 0
v_min_id_clo_res 1000 1000 0 0.00 0 18505 30 0 0
v_min_id_ord_sup 5626 5626 0 0.00 0 4175 6 0 0
v_min_non_paid_invoice 6116 6116 0 0.00 0 5030 13 0 0
v_min_non_paid_realizn 7235 7235 0 0.00 0 12883 16 0 0
v_our_firm 10512 10512 0 0.00 0 14 1 0 0
v_reserve_write_off 20496 20496 0 0.00 0 5365 7 0 0
x_lock_depdocs_on_canc_invoice 10677 10677 0 0.00 0 14511 132 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 6510 400 0 0
x_make_qstorno_1000_1200 5730 5730 0 0.00 2 63543 1414 0 0
x_make_qstorno_1200_2000 5637 5637 0 0.00 3 79159 3500 0 0
x_make_qstorno_2100_3300 483732 483732 0 0.00 0 75910 194 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-11-2021 07:38:08 to 22-11-2021 07:38:12.

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
05:07:06 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:07 15908 8192000000 9733282432 10034028544 809378640 8.991 100 86 172 0 838104672 7673152 21052880
05:09:27 15603 8192000000 9778126160 10087329792 848933712 9.389 100 89 178 0 881642496 12165568 20543216
05:10:52 18092 8192000000 9806463328 10126352384 874955968 9.649 100 86 172 0 914224192 19672064 19596160
05:12:15 12590 8192000000 9865762192 10196082688 913378896 10.031 100 89 178 1 980245712 47454736 19412080
05:13:30 11272 8192000000 9924751776 10263511040 938438256 10.278 100 83 166 0 1030706160 73835088 18432816
05:14:50 6911 8192000000 9950532016 10302898176 981265536 10.697 100 82 162 0 1070539232 72578656 16695040
05:16:00 4569 8192000000 9994951328 10355089408 1012285168 10.997 100 86 172 1 1101258096 71836704 17136224
05:17:06 4809 8192000000 9999362880 10367262720 1027621056 11.146 100 77 153 1 1114787680 71994480 15172144
05:18:14 1795 8192000000 10034521776 10406047744 1061482096 11.471 100 90 181 0 1143161232 63262240 18416896
05:19:17 1699 8192000000 10062353424 10436104192 1073529328 11.586 100 91 180 1 1171384352 79357056 18497968
05:20:19 8512 8192000000 10024756816 10423713792 1072924544 11.580 100 37 74 0 1132653088 52190912 7537632
05:21:31 3531 8192000000 10009283008 10409832448 1080118640 11.649 100 42 84 1 1122229328 32848272 9262416
05:22:36 6481 8192000000 9998553072 10403078144 1089870864 11.741 100 48 95 0 1122510976 22266224 10373888
05:23:45 64862 8192000000 10018187328 10419785728 1090725792 11.750 100 41 81 2 1115754144 16443600 8584752
05:25:53 4517 8192000000 10026366464 10427080704 1103680848 11.873 100 58 115 1 1133579376 17880624 12017904
05:27:01 5391 8192000000 10032537584 10433368064 1103791920 11.874 100 42 84 0 1133043664 19703184 9548560
05:28:09 7397 8192000000 9996227136 10405281792 1053861232 11.398 97 29 58 1 1093311776 33632592 5817952
05:29:17 51236 8192000000 9937232448 10339602432 935947520 10.253 87 26 51 1 964881872 23944304 4990048
05:31:08 5323 8192000000 9917435040 10296328192 971522320 10.602 95 41 82 2 1001237984 18997168 10718496
05:32:14 6688 8192000000 9926669616 10309103616 1001268416 10.891 98 24 48 0 1028747488 21305984 6173088
05:33:24 7768 8192000000 9946322496 10332012544 1028990592 11.159 100 43 83 2 1058592528 19745872 9856064
05:35:02 6252 8192000000 9958520912 10347737088 1040159600 11.266 100 40 81 0 1071407520 21839824 9408096
05:36:09 5189 8192000000 9976606368 10359263232 1053116432 11.391 100 38 76 2 1077207664 16037504 8053728
05:37:19 37071 8192000000 9993850464 10382278656 1074180304 11.592 100 39 76 0 1100171440 17274656 8716480
05:39:00 116073 8192000000 10082773376 10487058432 1097326960 11.812 100 47 94 2 1197084048 89996704 9760384
05:42:00 11539 8192000000 10078779312 10477920256 1111499184 11.947 100 47 93 1 1188606976 67209520 9898272
05:43:24 10659 8192000000 10112692160 10514063360 1124788256 12.072 100 63 127 1 1221316768 83647904 12880608
05:44:40 5664 8192000000 10140393408 10543710208 1134870896 12.167 100 57 113 0 1245281504 99000848 11409760
05:45:50 9220 8192000000 10176515968 10575302656 1149786624 12.307 100 60 119 2 1283053920 120859664 12407632
05:47:02 4134 8192000000 10214629600 10616311808 1160419840 12.407 100 80 157 1 1309795024 133161344 16213840
05:48:09 2813 8192000000 10223084032 10631254016 1165678224 12.456 100 78 153 2 1329456736 148192592 15585920
05:49:15 12848 8192000000 10199410432 10604679168 1145744864 12.270 98 66 129 2 1301675376 142536192 13394320
05:50:40 3320 8192000000 10256047104 10655981568 1158916672 12.393 99 88 172 1 1356532368 178421616 19194080
05:51:48 2172 8192000000 10269881728 10665988096 1171203952 12.508 100 83 167 0 1367587824 178345520 18038352
05:52:58 1899 8192000000 10344682320 10742800384 1175107104 12.545 100 93 185 3 1442691664 246755424 20829136
05:54:05 1364 8192000000 10302858912 10704457728 1162093328 12.423 99 80 160 2 1402424352 221120560 19210464
05:55:12 1829 8192000000 10341676992 10739396608 1158081344 12.385 100 85 170 3 1439073440 262147472 18844624
05:56:22 2887 8192000000 10389265936 10785689600 1144351312 12.256 99 90 180 1 1488390880 322255360 21784208
05:57:35 1965 8192000000 10384225232 10783141888 1148327824 12.294 99 86 173 1 1486265440 317212336 20725280
05:58:39 10440 8192000000 10296073008 10729820160 1139486688 12.211 100 40 79 0 1395763824 246311056 9966080
05:59:52 11811 8192000000 10367860128 10779586560 1151969472 12.328 100 56 112 1 1466604000 300999664 13634864
06:01:15 5267 8192000000 10388797248 10827988992 1155772544 12.364 100 58 115 0 1513667904 344559856 13335504
06:02:27 7523 8192000000 10395487008 10813190144 1159963104 12.403 100 43 84 2 1491760704 321755568 10042032
06:03:57 19530 8192000000 10472405312 10892615680 1146039888 12.272 100 45 89 0 1572237584 414467632 11730064
06:05:31 18737 8192000000 10483799920 10939330560 1125998672 12.084 100 29 58 0 1581183424 447022688 8162064
06:06:56 24505 8192000000 10323655952 10783920128 1105758608 11.892 99 23 46 0 1455942944 343859440 6324896
06:08:23 19030 8192000000 10138074560 10636967936 1128421040 12.106 100 14 28 1 1223707696 92199648 3087008
06:09:43 33984 8192000000 10054361184 10498076672 1124635760 12.071 99 27 54 1 1176602912 46631344 5335808
06:11:29 65310 8192000000 10077861936 10524524544 1104378944 11.879 98 17 34 1 1160108592 52172992 3556656
06:13:37 97798 8192000000 10068637792 10514546688 1131067152 12.131 100 20 40 1 1165672928 30190624 4415152
06:16:20 64391 8192000000 10077258352 10517291008 1119357616 12.021 98 38 76 1 1180859648 53040416 8461616
06:18:54 27369 8192000000 10102557744 10525274112 1111257600 11.944 98 26 52 1 1176720512 59607424 5855488
06:20:28 39643 8192000000 10161759088 10607304704 1128698896 12.109 99 36 70 2 1271530896 134644512 8187488
06:22:10 59980 8192000000 10121930352 10578264064 1125086912 12.075 98 35 71 0 1192795376 60045200 7663264
06:24:21 29052 8192000000 10172776480 10613014528 1144170944 12.255 100 22 44 0 1270836032 121796912 4868176
06:25:50 47713 8192000000 10143627472 10611535872 1132356656 12.144 100 23 46 2 1270566368 132201440 6008272
06:27:40 49660 8192000000 10083960304 10540982272 1112389824 11.955 98 12 24 0 1161886208 46613920 2882464
06:29:45 68735 8192000000 10085845504 10524798976 1118255680 12.011 98 16 32 2 1160027760 38561200 3210880
06:31:56 9579 8192000000 10109131904 10530852864 1146221184 12.274 100 56 110 0 1195433040 38058368 11153488
06:33:12 63232 8192000000 10064493088 10513883136 1107408224 11.908 97 13 26 0 1143123792 33113520 2602048
06:35:40 9384 8192000000 10086977360 10504167424 1124448288 12.069 99 60 119 1 1159671632 23606720 11616624
06:36:54 62917 8192000000 10117207056 10551074816 1094958880 11.790 96 16 32 0 1150955856 53059312 2937664
06:38:57 68989 8192000000 10056029616 10531282944 1123835984 12.063 99 19 38 2 1159310080 31549184 3924912
06:41:23 55607 8192000000 10105341504 10536505344 1131051520 12.131 99 43 86 0 1197894464 57929552 8913392
06:43:21 64655 8192000000 10062423104 10526748672 1115775312 11.987 99 17 34 0 1165016240 45797952 3442976
06:46:05 50642 8192000000 10038831760 10506620928 1089698032 11.740 96 13 26 0 1136448880 43811408 2939440
06:47:56 27490 8192000000 10045026480 10505187328 1101011008 11.847 98 12 24 0 1149234032 45629984 2593040
06:49:29 53631 8192000000 10055700576 10495221760 1068410880 11.537 95 15 30 1 1125866992 54569856 2886256
06:51:29 21783 8192000000 10085169344 10515783680 1105084544 11.886 100 58 116 0 1184563376 66687920 12790912
06:52:56 64450 8192000000 10063924384 10525876224 1109700160 11.930 99 17 34 1 1180108112 66942336 3465616
06:55:01 64232 8192000000 10107666688 10541215744 1127657664 12.099 100 32 64 0 1179060352 45168352 6234336
06:57:24 82057 8192000000 10104301408 10560819200 1092847488 11.770 97 16 32 0 1173566624 77769648 2949488
06:59:48 47580 8192000000 10094722416 10531995648 1100604672 11.843 98 42 83 1 1173785600 63652176 9528752
07:01:39 113038 8192000000 10042572832 10506121216 1108641088 11.920 99 24 48 1 1174521168 60976096 4903984
07:04:33 44827 8192000000 10045346864 10502643712 1112598432 11.957 99 18 36 0 1164589328 48195920 3794976
07:06:19 69643 8192000000 10089934816 10543300608 1123414752 12.059 100 11 22 1 1176349120 51001712 1932656
07:08:28 47865 8192000000 10049253888 10522345472 1097648080 11.815 97 12 24 0 1140743696 40972304 2123312
07:10:17 57416 8192000000 10096649264 10543325184 1138455840 12.201 100 23 46 2 1193877600 50553536 4868224
07:12:22 53251 8192000000 10068409152 10534957056 1106584400 11.900 97 32 64 1 1191266720 78043488 6638832
07:14:27 53725 8192000000 10129000848 10596614144 1128462800 12.107 99 16 32 0 1236949456 104778288 3708368
07:16:21 27667 8192000000 10073358080 10504085504 1099910608 11.837 97 39 79 0 1135310800 27692752 7707440
07:17:52 34351 8192000000 10142698320 10567921664 1106870496 11.903 97 43 86 0 1209784800 94339824 8574480
07:19:27 64724 8192000000 10174499968 10616340480 1132697664 12.147 99 28 56 0 1249943776 111478080 5768032
07:21:39 26067 8192000000 10081068704 10503454720 1121440096 12.041 99 39 75 1 1151238208 21209984 8588128
07:23:06 79793 8192000000 10101839040 10544717824 1124615104 12.071 99 24 48 0 1175110848 45433440 5062304
07:26:31 57840 8192000000 10101420448 10569302016 1131200528 12.133 100 10 20 0 1215435040 81860064 2374448
07:28:29 72181 8192000000 10065304944 10544082944 1121146816 12.038 99 20 40 1 1169234272 43677440 4410016
07:30:42 58564 8192000000 10084332832 10539274240 1131952688 12.140 99 16 32 0 1196301952 60856064 3493200
07:32:42 53935 8192000000 10136412208 10568630272 1107284992 11.907 97 22 44 0 1192342128 80321888 4735248
07:34:36 64276 8192000000 10147235632 10583109632 1105891056 11.893 97 17 34 1 1229782944 119714800 4177088
07:36:46 33103 8192000000 9040334112 9301909504 178036592 2.127 16 3 6 0 188920208 10561488 322128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-11-2021 07:38:14 to 22-11-2021 07:38:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 207 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-11-2021 07:38:30 to 22-11-2021 07:38:32.

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          239280
MON$OLDEST_ACTIVE               239281
MON$OLDEST_SNAPSHOT             239281
MON$NEXT_TRANSACTION            239281
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                       2055968
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             808
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2668 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2668 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2668 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2668 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
--- end of diff output ---

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

22.11.21 07:38:33. 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-11-22 07:38:35 No severe PSQL-related problems occured
22.11.21 07:38:36. End of report.