Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.07.2022 16:40
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 16293360 kB
MemAvailable 31121448 kB
Buffers 0 kB
Cached 15455404 kB
SwapCached 2496 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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 2022-07-15 16:38:26
DTS_END                         2022-07-15 16:38:26.2670
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43058
TRN_ID                          242259
ATT_ID                          641
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: 8945.87 20654 1073504
customer order: creation 174.99 351 20999
customer order: refuse 14.85 355 1782
order to supplier: creation 46.04 1279 5525
order to supplier: removal 3.08 108581 369
invoice (draft): creation 46.00 3320 5520
invoice (draft): removal 49.28 778 5913
invoice accept: apply 41.87 18279 5024
invoice accept: cancel 44.59 861 5351
customer reserve: creation 3845.26 181 461431
customer reserve: removal 4385.63 60 526276
realization accept: apply 84.24 216 10109
realization accept: cancel 77.29 119 9275
payment from customer: creation 60.23 114 7227
payment from customer: removal 6.21 82 745
payment to supplier: creation 49.89 155 5987
payment to supplier: removal 3.57 76 428
service: total inventory turnovers 5.29 1045 635
service: total monetary turnovers 6.67 37 800
service: aggregate perf. data 0.87 22202 104
service: refresh index statistics 0.03 254985 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-07-2022 16:41:06 to 15-07-2022 16:41:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4146
TEST_TIME 2 2986
TEST_TIME 3 3344
TEST_TIME 4 3994
TEST_TIME 5 3919
TEST_TIME 6 4836
TEST_TIME 7 4768
TEST_TIME 8 4905
TEST_TIME 9 4920
TEST_TIME 10 5267
TEST_TIME 11 5868
TEST_TIME 12 5879
TEST_TIME 13 6375
TEST_TIME 14 6515
TEST_TIME 15 6849
TEST_TIME 16 6983
TEST_TIME 17 7305
TEST_TIME 18 7280
TEST_TIME 19 7653
TEST_TIME 20 7646
TEST_TIME 21 7790
TEST_TIME 22 8108
TEST_TIME 23 8492
TEST_TIME 24 8795
TEST_TIME 25 8669
TEST_TIME 26 9227
TEST_TIME 27 9300
TEST_TIME 28 9339
TEST_TIME 29 10117
TEST_TIME 30 9964
TEST_TIME 31 9868
TEST_TIME 32 9719
TEST_TIME 33 9645
TEST_TIME 34 9527
TEST_TIME 35 9453
TEST_TIME 36 9433
TEST_TIME 37 9235
TEST_TIME 38 9133
TEST_TIME 39 9249
TEST_TIME 40 9300
TEST_TIME 41 9230
TEST_TIME 42 9201
TEST_TIME 43 9137
TEST_TIME 44 9150
TEST_TIME 45 9042
TEST_TIME 46 8997
TEST_TIME 47 8931
TEST_TIME 48 8952
TEST_TIME 49 8995
TEST_TIME 50 9013
TEST_TIME 51 8921
TEST_TIME 52 8776
TEST_TIME 53 8809
TEST_TIME 54 8855
TEST_TIME 55 8885
TEST_TIME 56 8862
TEST_TIME 57 8944
TEST_TIME 58 8945
TEST_TIME 59 8909
TEST_TIME 60 8998
TEST_TIME 61 9054
TEST_TIME 62 8979
TEST_TIME 63 9108
TEST_TIME 64 9105
TEST_TIME 65 9205
TEST_TIME 66 9155
TEST_TIME 67 9174
TEST_TIME 68 9235
TEST_TIME 69 9211
TEST_TIME 70 9096
TEST_TIME 71 9071
TEST_TIME 72 9054
TEST_TIME 73 9040
TEST_TIME 74 8970
TEST_TIME 75 8970
TEST_TIME 76 8967
TEST_TIME 77 9020
TEST_TIME 78 8961
TEST_TIME 79 8885
TEST_TIME 80 8927
TEST_TIME 81 8868
TEST_TIME 82 8860
TEST_TIME 83 8861
TEST_TIME 84 8884
TEST_TIME 85 8912
TEST_TIME 86 8955
TEST_TIME 87 8955
TEST_TIME 88 8962
TEST_TIME 89 9003
TEST_TIME 90 9066
TEST_TIME 91 9081
TEST_TIME 92 9066
TEST_TIME 93 9055
TEST_TIME 94 9124
TEST_TIME 95 9128
TEST_TIME 96 9066
TEST_TIME 97 9046
TEST_TIME 98 9048
TEST_TIME 99 9005
TEST_TIME 100 8955
TEST_TIME 101 8929
TEST_TIME 102 8855
TEST_TIME 103 8873
TEST_TIME 104 8932
TEST_TIME 105 8907
TEST_TIME 106 8925
TEST_TIME 107 8913
TEST_TIME 108 8928
TEST_TIME 109 8979
TEST_TIME 110 8979
TEST_TIME 111 8976
TEST_TIME 112 9072
TEST_TIME 113 9074
TEST_TIME 114 9081
TEST_TIME 115 9121
TEST_TIME 116 9107
TEST_TIME 117 9048
TEST_TIME 118 9005
TEST_TIME 119 8955
TEST_TIME 120 8948

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-07-2022 16:41:10 to 15-07-2022 16:41:13.

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 1499140 1499140 0 0.00 0 36606 7 0 0
doc_list_biud 1499135 1499135 0 0.00 0 30029 27 0 0
sp_add_invoice_to_stock 5186 5024 162 3.12 28 158033 18279 0 162
sp_cancel_adding_invoice 5351 5351 0 0.00 3 67386 861 0 0
sp_cancel_client_order 1782 1782 0 0.00 4 11103 355 0 0
sp_cancel_customer_reserve 526276 526276 0 0.00 0 25897 60 0 0
sp_cancel_pay_from_customer 745 745 0 0.00 2 10725 83 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 2412 77 0 0
sp_cancel_supplier_invoice 5922 5913 9 0.15 1 24239 778 0 9
sp_cancel_supplier_order 369 369 0 0.00 5 956565 108582 0 0
sp_cancel_write_off 9275 9275 0 0.00 1 18595 119 0 0
sp_client_order 20999 20999 0 0.00 3 25525 351 0 0
sp_customer_reserve 461431 461431 0 0.00 0 38714 181 0 0
sp_fill_shopping_cart 32040 32040 0 0.00 1 13535 42 0 0
sp_fill_shopping_cart_clo_res 461430 461430 0 0.00 0 20858 16 0 0
sp_get_clo_for_invoice 5021 5021 0 0.00 0 28486 361 0 0
sp_kill_cost_storno 15799 15799 0 0.00 0 16852 61 0 0
sp_kill_qstorno_ret_qs2qd 1068109 1068109 0 0.00 0 25788 32 0 0
sp_kill_qty_storno 564091 564091 0 0.00 0 30029 70 0 0
sp_lock_selected_doc 21002 21002 0 0.00 0 9917 7 0 0
sp_make_cost_storno 28344 28344 0 0.00 0 36011 69 0 0
sp_multiply_rows_for_pdistr 28343 28343 0 0.00 0 7459 33 0 0
sp_multiply_rows_for_qdistr 20999 20999 0 0.00 0 17489 151 0 0
sp_pay_from_customer 7227 7227 0 0.00 4 12316 115 0 0
sp_pay_to_supplier 5987 5987 0 0.00 4 17550 155 0 0
sp_payment_common 13214 13214 0 0.00 1 11715 36 0 0
sp_qd_handle_on_cancel_clo 1782 1782 0 0.00 0 7149 325 0 0
sp_qd_handle_on_invoice_upd_sts 10369 10369 0 0.00 0 30029 345 0 0
sp_qd_handle_on_reserve_upd_sts 10107 10107 0 0.00 0 15002 64 0 0
sp_reserve_write_off 10109 10109 0 0.00 5 23306 217 0 0
sp_supplier_invoice 5520 5520 0 0.00 7 69015 3321 0 0
sp_supplier_order 5525 5525 0 0.00 5 26563 1280 0 0
srv_aggregate_perf_data 104 104 0 0.00 2216 312057 22202 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1545 131178 44511 0 0
srv_find_qd_qs_mism 1024422 1024422 0 0.00 0 16151 5 0 0
srv_make_invnt_saldo 635 635 0 0.00 31 36576 1046 0 0
srv_make_money_saldo 800 800 0 0.00 0 3491 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 209994 318118 254985 0 0
t$perf-abend:sp_add_invoice_to_stock 162 162 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 5024 5024 0 0.00 0 18181 181 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 3896 127 0 0
t$perf-norm:sp_cancel_client_order 1782 1782 0 0.00 0 3595 18 0 0
t$perf-norm:sp_cancel_customer_reserve 684 684 0 0.00 0 1140 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 745 745 0 0.00 0 1314 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 1203 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 433 433 0 0.00 0 1495 24 0 0
t$perf-norm:sp_cancel_supplier_order 369 369 0 0.00 0 30009 1936 0 0
t$perf-norm:sp_cancel_write_off 1032 1032 0 0.00 0 2651 9 0 0
t$perf-norm:sp_client_order 20999 20999 0 0.00 0 9013 20 0 0
t$perf-norm:sp_customer_reserve 991 991 0 0.00 0 2248 11 0 0
t$perf-norm:sp_pay_from_customer 7227 7227 0 0.00 0 3662 11 0 0
t$perf-norm:sp_pay_to_supplier 5987 5987 0 0.00 0 5753 10 0 0
t$perf-norm:sp_reserve_write_off 10109 10109 0 0.00 0 11149 15 0 0
t$perf-norm:sp_supplier_invoice 5520 5520 0 0.00 0 10181 19 0 0
t$perf-norm:sp_supplier_order 5525 5525 0 0.00 0 11161 23 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 333 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1749 27 0 0
t$perf-norm:srv_make_invnt_saldo 635 635 0 0.00 0 3613 36 0 0
t$perf-norm:srv_make_money_saldo 800 800 0 0.00 0 2080 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 5 4 0 0
v_add_invoice_to_stock 10358 10034 324 3.12 0 13496 18 0 324
v_all_customers 38090 38090 0 0.00 0 18 1 0 0
v_all_suppliers 30456 30456 0 0.00 0 29 1 0 0
v_all_wares 41998 41998 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 1922 11 0 0
v_cancel_client_order 3564 3564 0 0.00 0 10341 7 0 0
v_cancel_customer_prepayment 1490 1490 0 0.00 0 10342 10 0 0
v_cancel_customer_reserve 1368 1368 0 0.00 0 10132 18 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 2408 9 0 0
v_cancel_supplier_invoice 884 866 18 2.03 0 1696 17 0 18
v_cancel_supplier_order 738 738 0 0.00 0 1093 6 0 0
v_cancel_write_off 2064 2064 0 0.00 0 1992 17 0 0
v_max_id_clo_ord 5525 5525 0 0.00 0 701 1 0 0
v_max_id_clo_res 991 991 0 0.00 0 2147 15 0 0
v_max_id_ord_sup 5516 5516 0 0.00 0 9950 5 0 0
v_max_non_paid_invoice 1800 1800 0 0.00 0 277 2 0 0
v_max_non_paid_realizn 3891 3891 0 0.00 0 5930 8 0 0
v_min_id_clo_ord 5525 5525 0 0.00 0 7753 6 0 0
v_min_id_clo_res 991 991 0 0.00 0 1923 8 0 0
v_min_id_ord_sup 5516 5516 0 0.00 0 7079 10 0 0
v_min_non_paid_invoice 5987 5987 0 0.00 0 11709 31 0 0
v_min_non_paid_realizn 7227 7227 0 0.00 0 6836 22 0 0
v_our_firm 10580 10580 0 0.00 0 21 1 0 0
v_reserve_write_off 20214 20214 0 0.00 0 10259 7 0 0
x_lock_depdocs_on_canc_invoice 10831 10831 0 0.00 0 16830 127 0 0
x_lock_depdocs_on_canc_sup_ord 369 369 0 0.00 0 17432 390 0 0
x_make_qstorno_1000_1200 5525 5525 0 0.00 1 26553 1203 0 0
x_make_qstorno_1200_2000 5520 5520 0 0.00 2 69001 3155 0 0
x_make_qstorno_2100_3300 461431 461431 0 0.00 0 38712 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-07-2022 16:41:14 to 15-07-2022 16:41:18.

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
14:08:30 5 8192000000 8809783904 8824594432 14500192 0.176 4 4 4 0 14687296 79984 107120
14:09:31 26934 8192000000 9908559472 10218000384 986511840 10.748 100 91 182 0 1017019744 6268768 24239136
14:11:06 16191 8192000000 9939802464 10261848064 1024210208 11.113 100 84 168 0 1054340016 8100736 22029072
14:12:26 15305 8192000000 9982677232 10316169216 1067190416 11.525 100 81 162 0 1097255168 9469328 20595424
14:13:47 11486 8192000000 10028551216 10374070272 1116967216 11.998 100 76 152 1 1145703088 11065728 17670144
14:15:01 7811 8192000000 10076678368 10435665920 1163812880 12.439 100 83 164 1 1193969168 11968768 18187520
14:16:12 3720 8192000000 10114088912 10481336320 1201257648 12.788 100 84 166 0 1232890720 12537568 19095504
14:17:20 1869 8192000000 10148864464 10522849280 1238732528 13.135 100 79 156 0 1268528912 12784256 17012128
14:18:24 1636 8192000000 10187702160 10569662464 1270491392 13.426 100 81 160 1 1300624192 12873872 17258928
14:19:27 5675 8192000000 10214441136 10602975232 1302168816 13.715 100 90 183 1 1335018208 12126432 20722960
14:20:39 15950 8192000000 10194009760 10604670976 1309291104 13.780 100 38 77 1 1323268096 5738144 8238848
14:21:55 3325 8192000000 10212625216 10618609664 1315338048 13.834 100 44 88 0 1333710096 8789936 9582112
14:22:59 5225 8192000000 10209104352 10625908736 1323229312 13.906 100 26 53 1 1334873600 6010016 5634272
14:24:09 7750 8192000000 10214833520 10633867264 1328769040 13.956 100 22 45 0 1339615536 5348192 5498304
14:25:18 36241 8192000000 10222820256 10648813568 1333277424 13.997 100 28 56 2 1349461248 9378992 6804832
14:26:54 9260 8192000000 10230868880 10651258880 1339579696 14.054 100 24 49 0 1351719664 6707600 5432368
14:28:06 10244 8192000000 10216142880 10638868480 1321081136 13.886 99 16 32 0 1330534288 5796064 3657088
14:29:19 57077 8192000000 10185857872 10591678464 1256452992 13.297 96 41 82 0 1271764400 6037232 9274176
14:31:19 9364 8192000000 10147191936 10548989952 1240384448 13.150 98 23 42 0 1250986976 5777440 4825088
14:32:30 8180 8192000000 10102926848 10498244608 1193555376 12.716 97 25 50 0 1206471008 7719792 5195840
14:33:40 9952 8192000000 10162305888 10558820352 1252166832 13.258 99 24 48 2 1261891216 4692448 5031936
14:34:52 16107 8192000000 10155054944 10562920448 1255460816 13.288 99 18 36 0 1264355216 4676896 4217504
14:36:08 7319 8192000000 10181594288 10587762688 1285272224 13.561 100 21 42 0 1294678672 4848960 4557488
14:37:16 15275 8192000000 10193383392 10604564480 1300107696 13.696 100 18 36 0 1309316944 4918400 4290848
14:38:36 39611 8192000000 10231598656 10645159936 1330859952 13.975 100 39 76 0 1345976016 5552352 9563712
14:40:18 48849 8192000000 10244482608 10661507072 1345203472 14.104 100 33 66 0 1359610896 7257744 7149680
14:42:11 39576 8192000000 10265769824 10684440576 1360914064 14.246 100 42 82 1 1380154912 9686192 9554656
14:43:59 13580 8192000000 10284668224 10705702912 1370169680 14.329 100 49 97 1 1395107952 13428288 11509984
14:45:17 24797 8192000000 10291719792 10721648640 1384094576 14.453 100 29 56 1 1401662592 10890736 6677280
14:46:46 23544 8192000000 10316155888 10736152576 1388537968 14.493 100 57 106 0 1418950608 17996528 12416112
14:48:20 12376 8192000000 10317432496 10741923840 1393407088 14.536 100 50 96 1 1428401232 23131008 11863136
14:49:36 4410 8192000000 10332028560 10755383296 1401498096 14.608 100 54 108 1 1440467872 25375936 13593840
14:50:46 6245 8192000000 10343569008 10758803456 1404196880 14.632 100 69 135 1 1449614704 28948528 16469296
14:51:55 4574 8192000000 10350870128 10766913536 1405522528 14.644 100 68 134 0 1451089200 29291680 16274992
14:53:03 3715 8192000000 10339037936 10761416704 1392337344 14.527 98 66 130 0 1436008160 28192144 15478672
14:54:12 1564 8192000000 10350470624 10767196160 1402333936 14.616 99 64 128 0 1451576320 33570944 15671440
14:55:18 2995 8192000000 10347348432 10766942208 1401103856 14.605 100 72 143 0 1455391296 35325088 18962352
14:56:30 3782 8192000000 10319434832 10724843520 1364103984 14.274 98 73 142 0 1415175776 33605440 17466352
14:57:42 1700 8192000000 10335562272 10747326464 1382916048 14.443 100 70 138 0 1437604608 37460640 17227920
14:58:50 1542 8192000000 10314636256 10717184000 1357645392 14.216 99 85 169 0 1421504096 42320224 21538480
14:59:57 2247 8192000000 10334934128 10745528320 1377815040 14.397 100 67 133 1 1439232320 44447280 16970000
15:01:05 3907 8192000000 10310580272 10735792128 1370947200 14.336 100 45 90 0 1421565664 38900640 11717824
15:02:18 12463 8192000000 10319846944 10748698624 1383568944 14.448 100 30 60 1 1427538960 35709008 8261008
15:03:35 14496 8192000000 10323651552 10752659456 1383752608 14.450 100 35 66 0 1431232128 37732464 9747056
15:04:52 17871 8192000000 10303872976 10745643008 1348695072 14.136 98 28 56 0 1404686304 48317248 7673984
15:06:40 7833 8192000000 10304963296 10740731904 1367879984 14.308 100 53 106 1 1413789728 33196560 12713184
15:07:54 45938 8192000000 10278371984 10720415744 1344910800 14.102 98 18 38 1 1361984304 13286064 3787440
15:09:41 25195 8192000000 10296904352 10729517056 1368866992 14.317 99 36 73 0 1386504240 8659328 8977920
15:11:12 37949 8192000000 10278977456 10726580224 1368720752 14.316 99 15 30 0 1380959680 8747568 3491360
15:12:52 12146 8192000000 10276311632 10712735744 1368594432 14.314 99 61 121 2 1393621664 10890768 14136464
15:14:10 79132 8192000000 10292118736 10741104640 1385047424 14.462 100 11 22 0 1403706000 15867888 2790688
15:16:29 44791 8192000000 10288998000 10750713856 1385826768 14.469 100 10 20 0 1408362416 19891856 2643792
15:18:17 39167 8192000000 10309185440 10758467584 1400631488 14.601 100 17 34 0 1419606528 15140512 3834528
15:20:03 89749 8192000000 10295959968 10752352256 1390452416 14.510 100 15 30 0 1409975424 15876480 3646528
15:22:54 75404 8192000000 10276341152 10724417536 1339670000 14.054 96 10 20 0 1350927936 8445920 2812016
15:25:21 42794 8192000000 10317832656 10760347648 1401196688 14.606 100 55 110 0 1424051152 9943712 12910752
15:27:19 57136 8192000000 10298808000 10756358144 1387367904 14.482 99 11 23 0 1405447248 16018896 2060448
15:29:19 49064 8192000000 10302977120 10766712832 1391698624 14.521 99 13 26 2 1415814736 21380608 2735504
15:31:14 65912 8192000000 10281287360 10752925696 1355371952 14.196 97 15 30 1 1378903424 20329232 3202240
15:33:20 45047 8192000000 10293646112 10752229376 1355732256 14.199 97 16 32 0 1368482544 9352144 3398144
15:35:06 63079 8192000000 10291335040 10755395584 1372443152 14.349 98 11 20 0 1388513328 13551312 2518864
15:37:10 64023 8192000000 10280220176 10731945984 1321945248 13.894 95 21 42 0 1334861152 8488416 4427488
15:39:14 131177 8192000000 10273592768 10723180544 1280677232 13.519 92 13 24 0 1304144224 21198256 2268736
15:42:26 39716 8192000000 10273237472 10718134272 1315698816 13.838 96 18 36 0 1333291200 13777984 3814400
15:44:06 53795 8192000000 10277519088 10714398720 1311260080 13.798 95 26 52 0 1326242272 9618560 5363632
15:46:02 21471 8192000000 10290627280 10724790272 1362980784 14.264 99 34 68 0 1380635296 10019296 7635216
15:47:29 123441 8192000000 10266503696 10711429120 1340773472 14.064 98 21 41 0 1356205056 10596336 4835248
15:50:33 53889 8192000000 10264412864 10711326720 1348511520 14.134 99 20 40 0 1364437088 11469760 4455808
15:52:27 50146 8192000000 10239614768 10689200128 1316404512 13.844 97 16 32 1 1332110448 12080800 3625136
15:54:34 38007 8192000000 10238408384 10677284864 1321936688 13.894 98 17 33 0 1336395104 10680160 3778256
15:56:24 80040 8192000000 10234141296 10684346368 1299729088 13.693 96 10 20 1 1310645632 8610592 2305952
15:58:58 45407 8192000000 10258233280 10719379456 1354251792 14.186 100 14 25 0 1366239264 9217152 2770320
16:01:43 37059 8192000000 10259349536 10710507520 1338555984 14.044 98 17 34 0 1352672608 9995680 4120944
16:03:22 66441 8192000000 10269236448 10724364288 1345703984 14.109 98 32 64 2 1369978864 17130784 7144096
16:05:32 47394 8192000000 10283000208 10723414016 1346524032 14.116 98 22 44 0 1361323936 10184544 4615360
16:07:28 70201 8192000000 10281787104 10740027392 1366101920 14.292 99 21 42 0 1384711344 14230816 4378608
16:09:51 58891 8192000000 10291863792 10733117440 1363270544 14.267 99 14 28 0 1374614800 8882752 2461504
16:11:50 36889 8192000000 10281364048 10733117440 1355018000 14.193 98 42 84 0 1380399984 15804864 9577120
16:13:30 43626 8192000000 10297184512 10729848832 1379224256 14.410 100 33 66 1 1397312032 11020624 7067152
16:15:16 88837 8192000000 10285761904 10740310016 1379070560 14.408 100 18 34 0 1393176304 9984800 4120944
16:17:45 44146 8192000000 10266780768 10728452096 1367054848 14.301 99 6 12 0 1375558832 7121536 1382448
16:19:34 72632 8192000000 10247312944 10692231168 1326315008 13.934 96 12 24 0 1339450512 10344512 2790992
16:21:49 66377 8192000000 10269827040 10724364288 1345685360 14.109 98 11 22 0 1358270688 9903328 2682000
16:23:56 80131 8192000000 10275274192 10727964672 1316078400 13.841 95 15 30 0 1329214192 9843904 3291888
16:26:16 64091 8192000000 10257097504 10714841088 1318121136 13.860 96 29 58 0 1340765168 15899264 6744768
16:28:36 44023 8192000000 10283844976 10731208704 1373588160 14.359 100 22 44 0 1392077712 13517584 4971968
16:30:20 121831 8192000000 10288714528 10747641856 1340533824 14.062 97 14 28 0 1360195952 17170256 2491872
16:33:23 71073 8192000000 10285766656 10728689664 1363623712 14.270 99 16 32 0 1379215456 12298272 3293472
16:35:36 114240 8192000000 10251512704 10692562944 1325629920 13.928 97 21 42 0 1342560432 12183680 4746832

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-07-2022 16:41:20 to 15-07-2022 16:41:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 162 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-07-2022 16:41:36 to 15-07-2022 16:41:38.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          240727
MON$OLDEST_ACTIVE               242377
MON$OLDEST_SNAPSHOT             242377
MON$NEXT_TRANSACTION            242377
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-03 16:41:55.3470
MON$PAGES                       2012608
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:1118: 
:1119: mscs-test-161.budzdorov.ru    Fri Jul 15 16:38:54 2022
:1120:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1121: 
--- end of diff output ---

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

15.07.22 16:41:39. There are 3 logs in /var/tmp/logs-oltp30 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
2022-07-15 16:41:41 No severe PSQL-related problems occured
15.07.22 16:41:43. End of report.