Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.09.2021 22:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16220432 kB
MemAvailable 30798520 kB
Buffers 0 kB
Cached 15432424 kB
SwapCached 396 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.8
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 2021-09-13 22:37:51
DTS_END                         2021-09-13 22:37:51.3820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/41176
TRN_ID                          242738
ATT_ID                          640
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: 9010.38 22096 1081246
customer order: creation 175.58 338 21070
customer order: refuse 15.62 347 1874
order to supplier: creation 45.86 1212 5503
order to supplier: removal 3.13 113861 375
invoice (draft): creation 47.57 3483 5708
invoice (draft): removal 49.02 876 5882
invoice accept: apply 42.74 18997 5129
invoice accept: cancel 44.61 831 5353
customer reserve: creation 3938.64 188 472637
customer reserve: removal 4349.21 65 521905
realization accept: apply 84.66 193 10159
realization accept: cancel 81.47 116 9777
payment from customer: creation 60.82 135 7298
payment from customer: removal 5.53 97 663
payment to supplier: creation 49.33 135 5920
payment to supplier: removal 3.63 68 435
service: total inventory turnovers 5.00 972 600
service: total monetary turnovers 7.13 35 855
service: aggregate perf. data 0.83 22936 99
service: refresh index statistics 0.03 277037 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 13-09-2021 22:38:58 to 13-09-2021 22:39:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3312
TEST_TIME 2 4455
TEST_TIME 3 3902
TEST_TIME 4 4449
TEST_TIME 5 4394
TEST_TIME 6 4704
TEST_TIME 7 5062
TEST_TIME 8 5268
TEST_TIME 9 5336
TEST_TIME 10 6239
TEST_TIME 11 6305
TEST_TIME 12 7507
TEST_TIME 13 7572
TEST_TIME 14 7707
TEST_TIME 15 7784
TEST_TIME 16 7650
TEST_TIME 17 7642
TEST_TIME 18 7568
TEST_TIME 19 7608
TEST_TIME 20 8189
TEST_TIME 21 8335
TEST_TIME 22 8154
TEST_TIME 23 8117
TEST_TIME 24 8041
TEST_TIME 25 8752
TEST_TIME 26 9099
TEST_TIME 27 9106
TEST_TIME 28 9560
TEST_TIME 29 10873
TEST_TIME 30 10598
TEST_TIME 31 10607
TEST_TIME 32 10435
TEST_TIME 33 10401
TEST_TIME 34 10166
TEST_TIME 35 10181
TEST_TIME 36 10091
TEST_TIME 37 9904
TEST_TIME 38 9999
TEST_TIME 39 10066
TEST_TIME 40 9992
TEST_TIME 41 9957
TEST_TIME 42 9819
TEST_TIME 43 9688
TEST_TIME 44 9509
TEST_TIME 45 9627
TEST_TIME 46 9518
TEST_TIME 47 9592
TEST_TIME 48 9463
TEST_TIME 49 9322
TEST_TIME 50 9316
TEST_TIME 51 9300
TEST_TIME 52 9284
TEST_TIME 53 9305
TEST_TIME 54 9247
TEST_TIME 55 9292
TEST_TIME 56 9260
TEST_TIME 57 9357
TEST_TIME 58 9386
TEST_TIME 59 9309
TEST_TIME 60 9408
TEST_TIME 61 9496
TEST_TIME 62 9464
TEST_TIME 63 9435
TEST_TIME 64 9509
TEST_TIME 65 9450
TEST_TIME 66 9327
TEST_TIME 67 9336
TEST_TIME 68 9299
TEST_TIME 69 9237
TEST_TIME 70 9251
TEST_TIME 71 9239
TEST_TIME 72 9175
TEST_TIME 73 9155
TEST_TIME 74 9106
TEST_TIME 75 9102
TEST_TIME 76 9062
TEST_TIME 77 9113
TEST_TIME 78 9109
TEST_TIME 79 9071
TEST_TIME 80 9166
TEST_TIME 81 9187
TEST_TIME 82 9172
TEST_TIME 83 9155
TEST_TIME 84 9211
TEST_TIME 85 9317
TEST_TIME 86 9320
TEST_TIME 87 9298
TEST_TIME 88 9293
TEST_TIME 89 9303
TEST_TIME 90 9234
TEST_TIME 91 9165
TEST_TIME 92 9151
TEST_TIME 93 9188
TEST_TIME 94 9134
TEST_TIME 95 9087
TEST_TIME 96 9061
TEST_TIME 97 9103
TEST_TIME 98 9081
TEST_TIME 99 9120
TEST_TIME 100 9109
TEST_TIME 101 9123
TEST_TIME 102 9146
TEST_TIME 103 9205
TEST_TIME 104 9204
TEST_TIME 105 9222
TEST_TIME 106 9194
TEST_TIME 107 9226
TEST_TIME 108 9249
TEST_TIME 109 9189
TEST_TIME 110 9168
TEST_TIME 111 9110
TEST_TIME 112 9090
TEST_TIME 113 9098
TEST_TIME 114 9053
TEST_TIME 115 9003
TEST_TIME 116 9037
TEST_TIME 117 9015
TEST_TIME 118 9007
TEST_TIME 119 9005
TEST_TIME 120 9014

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-09-2021 22:39:02 to 13-09-2021 22:39:05.

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 1517244 1517244 0 0.00 0 17382 7 0 0
doc_list_biud 1517244 1517244 0 0.00 0 58421 28 0 0
sp_add_invoice_to_stock 5245 5129 116 2.21 43 210112 18997 0 116
sp_cancel_adding_invoice 5353 5353 0 0.00 4 106720 831 0 0
sp_cancel_client_order 1874 1874 0 0.00 4 9308 348 0 0
sp_cancel_customer_reserve 521905 521905 0 0.00 1 58423 65 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 2 11893 98 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 3 4302 69 0 0
sp_cancel_supplier_invoice 5889 5882 7 0.11 1 37615 876 0 7
sp_cancel_supplier_order 375 375 0 0.00 5 1022527 113862 0 0
sp_cancel_write_off 9777 9777 0 0.00 1 16523 116 0 0
sp_client_order 21070 21070 0 0.00 3 28176 339 0 0
sp_customer_reserve 472637 472637 0 0.00 0 80580 189 0 0
sp_fill_shopping_cart 32272 32272 0 0.00 1 16407 41 0 0
sp_fill_shopping_cart_clo_res 472630 472630 0 0.00 0 24206 17 0 0
sp_get_clo_for_invoice 5121 5121 0 0.00 0 23998 345 0 0
sp_kill_cost_storno 16228 16228 0 0.00 0 16502 62 0 0
sp_kill_qstorno_ret_qs2qd 1059844 1059844 0 0.00 0 53837 35 0 0
sp_kill_qty_storno 560446 560446 0 0.00 0 58420 75 0 0
sp_lock_selected_doc 21203 21203 0 0.00 0 8147 7 0 0
sp_make_cost_storno 28498 28498 0 0.00 0 15509 59 0 0
sp_multiply_rows_for_pdistr 28498 28498 0 0.00 0 16558 33 0 0
sp_multiply_rows_for_qdistr 21070 21070 0 0.00 0 23300 158 0 0
sp_pay_from_customer 7298 7298 0 0.00 4 17313 136 0 0
sp_pay_to_supplier 5920 5920 0 0.00 5 18813 136 0 0
sp_payment_common 13218 13218 0 0.00 1 16623 44 0 0
sp_qd_handle_on_cancel_clo 1874 1874 0 0.00 0 9301 318 0 0
sp_qd_handle_on_invoice_upd_sts 10474 10474 0 0.00 0 14841 319 0 0
sp_qd_handle_on_reserve_upd_sts 10159 10159 0 0.00 0 12211 51 0 0
sp_reserve_write_off 10159 10159 0 0.00 6 16668 194 0 0
sp_supplier_invoice 5708 5708 0 0.00 5 89091 3484 0 0
sp_supplier_order 5503 5503 0 0.00 5 30180 1212 0 0
srv_aggregate_perf_data 99 99 0 0.00 2594 394253 22936 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1990 108381 44995 0 0
srv_find_qd_qs_mism 1031691 1031691 0 0.00 0 14430 5 0 0
srv_make_invnt_saldo 600 600 0 0.00 38 23512 973 0 0
srv_make_money_saldo 855 855 0 0.00 0 4640 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 105845 347166 277037 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5129 5129 0 0.00 0 7768 168 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 7170 115 0 0
t$perf-norm:sp_cancel_client_order 1874 1874 0 0.00 0 4630 18 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 1080 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 3737 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 507 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 945 19 0 0
t$perf-norm:sp_cancel_supplier_order 375 375 0 0.00 0 23681 1719 0 0
t$perf-norm:sp_cancel_write_off 1075 1075 0 0.00 0 5534 11 0 0
t$perf-norm:sp_client_order 21070 21070 0 0.00 0 8629 18 0 0
t$perf-norm:sp_customer_reserve 1008 1008 0 0.00 0 6182 14 0 0
t$perf-norm:sp_pay_from_customer 7298 7298 0 0.00 0 16149 13 0 0
t$perf-norm:sp_pay_to_supplier 5920 5920 0 0.00 0 5993 12 0 0
t$perf-norm:sp_reserve_write_off 10159 10159 0 0.00 0 5479 13 0 0
t$perf-norm:sp_supplier_invoice 5708 5708 0 0.00 0 6701 18 0 0
t$perf-norm:sp_supplier_order 5503 5503 0 0.00 0 5110 16 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 168 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 86 2 0 0
t$perf-norm:srv_make_invnt_saldo 600 600 0 0.00 0 1850 25 0 0
t$perf-norm:srv_make_money_saldo 855 855 0 0.00 0 1481 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 4 0 0
v_add_invoice_to_stock 10474 10242 232 2.21 0 4961 18 0 232
v_all_customers 38528 38528 0 0.00 0 18 1 0 0
v_all_suppliers 31044 31044 0 0.00 0 20 1 0 0
v_all_wares 42140 42140 0 0.00 0 111 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 2403 10 0 0
v_cancel_client_order 3748 3748 0 0.00 0 1404 5 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 1641 7 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 1126 5 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 1194 4 0 0
v_cancel_supplier_invoice 762 748 14 1.83 0 2462 25 0 14
v_cancel_supplier_order 750 750 0 0.00 0 2091 10 0 0
v_cancel_write_off 2150 2150 0 0.00 0 2435 13 0 0
v_max_id_clo_ord 5502 5502 0 0.00 0 174 1 0 0
v_max_id_clo_res 1008 1008 0 0.00 0 22064 31 0 0
v_max_id_ord_sup 5700 5700 0 0.00 0 7417 8 0 0
v_max_non_paid_invoice 1600 1600 0 0.00 0 3894 5 0 0
v_max_non_paid_realizn 3840 3840 0 0.00 0 3860 5 0 0
v_min_id_clo_ord 5502 5502 0 0.00 0 6399 11 0 0
v_min_id_clo_res 1008 1008 0 0.00 0 6562 13 0 0
v_min_id_ord_sup 5700 5700 0 0.00 0 6445 9 0 0
v_min_non_paid_invoice 5920 5920 0 0.00 0 13786 28 0 0
v_min_non_paid_realizn 7298 7298 0 0.00 0 16483 36 0 0
v_our_firm 10528 10528 0 0.00 0 19 1 0 0
v_reserve_write_off 20318 20318 0 0.00 0 4566 5 0 0
x_lock_depdocs_on_canc_invoice 10861 10861 0 0.00 0 15219 116 0 0
x_lock_depdocs_on_canc_sup_ord 375 375 0 0.00 0 4395 324 0 0
x_make_qstorno_1000_1200 5503 5503 0 0.00 2 30159 1122 0 0
x_make_qstorno_1200_2000 5708 5708 0 0.00 2 82185 3338 0 0
x_make_qstorno_2100_3300 472636 472636 0 0.00 0 80579 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-09-2021 22:39:06 to 13-09-2021 22:39:10.

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
20:07:55 8 8192000000 8802500576 8814034944 8572016 0.104 2 1 2 0 8691424 56848 62560
20:08:56 42413 8192000000 10407550480 10733195264 1444636912 14.991 100 89 178 0 1480018096 6181168 29200016
20:10:42 17278 8192000000 10451865840 10787250176 1508517472 15.550 100 88 175 1 1546028720 9272528 28238720
20:12:28 18408 8192000000 10520600928 10865766400 1564487920 16.035 100 76 152 1 1598245264 10193808 23563536
20:13:51 11635 8192000000 10598772080 10963456000 1659960880 16.849 100 83 166 0 1696281888 11760112 24560896
20:15:07 8183 8192000000 10674828384 11047297024 1726967856 17.410 100 76 152 1 1760999360 12163920 21867584
20:16:19 6455 8192000000 10721309248 11107315712 1790357600 17.935 100 78 156 1 1824810032 12887184 21565248
20:17:31 2008 8192000000 10773259488 11163201536 1841332368 18.352 100 88 174 2 1879310912 13555936 24422608
20:18:35 1594 8192000000 10811090880 11202691072 1875191184 18.626 100 90 179 3 1912730800 13015616 24524000
20:19:38 41475 8192000000 10813198704 11226353664 1885044320 18.706 100 64 129 1 1912888544 9379968 18464256
20:21:22 5277 8192000000 10806411808 11233038336 1892246080 18.764 100 30 60 1 1908469056 7907024 8315952
20:22:29 6768 8192000000 10820546384 11246358528 1904274864 18.861 100 26 52 1 1918865952 7496288 7094800
20:23:37 10940 8192000000 10830152400 11264331776 1912275264 18.925 100 18 35 2 1926642576 9712528 4654784
20:24:51 37868 8192000000 10837177072 11271991296 1925547040 19.031 100 34 68 0 1941673632 5741376 10385216
20:26:44 4765 8192000000 10842575536 11275366400 1923016640 19.011 100 39 78 0 1941338704 7565968 10756096
20:27:49 10343 8192000000 10814382176 11247685632 1888992832 18.738 99 20 38 2 1900242064 5268032 5981200
20:29:02 52621 8192000000 10781451344 11212730368 1815572576 18.141 96 34 68 1 1830596320 5736272 9287472
20:30:55 2683 8192000000 10759270976 11175153664 1800874240 18.021 97 43 86 2 1820998864 8242576 11882048
20:31:59 9572 8192000000 10720503392 11131138048 1783168672 17.876 99 25 49 0 1795839776 5805408 6865696
20:33:09 9121 8192000000 10727288112 11145781248 1810181936 18.097 100 24 48 1 1823080384 5515664 7382784
20:34:19 25183 8192000000 10736715520 11171270656 1831078912 18.268 100 15 30 1 1841051904 5666720 4306272
20:35:47 7573 8192000000 10765285184 11188506624 1850838944 18.429 100 29 56 3 1864976816 6439744 7698128
20:36:55 8904 8192000000 10783355232 11206975488 1864312896 18.538 100 21 42 1 1875357824 4888128 6156800
20:38:10 10255 8192000000 10827414784 11245473792 1901361872 18.837 100 42 82 1 1919009792 5679488 11968432
20:39:22 39240 8192000000 10840729968 11274731520 1916597824 18.960 100 34 66 0 1933188400 7475024 9115552
20:41:10 19992 8192000000 10880615856 11302236160 1944059728 19.179 100 55 110 2 1971181936 11432336 15689872
20:42:35 34136 8192000000 10890132800 11324440576 1955315520 19.269 100 39 76 1 1983429360 16958576 11155264
20:44:20 18795 8192000000 10904170320 11339755520 1969314544 19.380 100 35 70 1 1993100240 13299392 10486304
20:45:42 6963 8192000000 10917020528 11355742208 1983210288 19.490 100 47 94 1 2013270016 16155200 13904528
20:47:01 8897 8192000000 10906878272 11345346560 1952636224 19.247 99 39 77 4 1984403904 19917952 11849728
20:48:14 8907 8192000000 10930578624 11346010112 1970310752 19.388 99 78 155 1 2016076240 22141168 23624320
20:49:35 1990 8192000000 10941991152 11361853440 1967578000 19.366 99 89 177 2 2015249824 22420272 25251552
20:50:43 6220 8192000000 10919296144 11339034624 1957916880 19.289 99 69 138 0 2000616336 21935920 20763536
20:51:54 7503 8192000000 10953971632 11368984576 1981006240 19.473 100 76 150 0 2031365520 28361984 21997296
20:53:06 12650 8192000000 10973108400 11396743168 2005304576 19.665 100 61 122 1 2056060560 32622688 18133296
20:54:26 4378 8192000000 10989931712 11408007168 2009357824 19.696 100 70 140 0 2072205696 40427248 22420624
20:55:38 2228 8192000000 11002102016 11408416768 2006719968 19.676 100 91 181 3 2078667712 44081584 27866160
20:56:46 5697 8192000000 10979585664 11400876032 1981196448 19.474 99 65 130 1 2053187728 50756656 21234624
20:57:56 14071 8192000000 10963953600 11393536000 1982905200 19.488 100 47 92 0 2045031872 46709904 15416768
20:59:14 2533 8192000000 10957649648 11381272576 1976100320 19.434 100 50 99 0 2036314432 44004112 16210000
21:00:22 9538 8192000000 10979210432 11414667264 1992075872 19.560 100 44 88 2 2058676960 52495808 14105280
21:01:41 15645 8192000000 10990208272 11431243776 1995645152 19.588 100 43 86 1 2075787344 64605008 15537184
21:03:19 14314 8192000000 10928171856 11385200640 1949508368 19.223 99 28 56 3 2007669376 48527936 9633072
21:04:36 70288 8192000000 10906934608 11359924224 1825288960 18.221 94 19 38 1 1897233072 64291344 7652768
21:06:51 63420 8192000000 10884148064 11314470912 1914731232 18.945 99 38 76 1 1939568144 13702384 11134528
21:09:26 47714 8192000000 10864149360 11313176576 1898867712 18.817 97 45 89 1 1922770720 11948848 11954160
21:11:16 45973 8192000000 10857025232 11314782208 1926381312 19.038 100 14 27 2 1950766912 20947312 3438288
21:13:16 21431 8192000000 10912148736 11364085760 1971492976 19.397 100 47 94 0 2007159552 20774672 14891904
21:14:52 94230 8192000000 10899535088 11389460480 1957980304 19.290 99 14 28 0 1995560656 32763136 4817216
21:17:28 51734 8192000000 10894392240 11353485312 1960162992 19.307 100 21 42 0 1976722096 10916832 5642272
21:19:24 108381 8192000000 10897775744 11374919680 1983547056 19.493 100 16 32 0 1998792880 10192384 5053440
21:22:27 79145 8192000000 10906787408 11390373888 1984540304 19.501 100 12 24 1 2005360176 17488688 3331184
21:24:46 80737 8192000000 10892439040 11363737600 1946043424 19.195 98 12 25 0 1959716112 10127968 3544720
21:27:07 57094 8192000000 10893031536 11375837184 1976882000 19.440 100 23 46 0 2001897200 17807824 7207376
21:29:07 89586 8192000000 10914506528 11385311232 1961003072 19.314 98 21 41 1 1976726576 9603824 6119680
21:31:50 8460 8192000000 10929585072 11379699712 1993221888 19.569 100 60 122 1 2019900416 9634544 17043984
21:33:04 51473 8192000000 10951324704 11413131264 2015427536 19.744 100 29 58 1 2036945536 13093520 8424480
21:34:59 47397 8192000000 10923887104 11381608448 1990354000 19.547 100 22 44 1 2005615872 9046064 6215808
21:36:52 30540 8192000000 10950773248 11402809344 1985639952 19.509 99 48 96 3 2015337712 16583504 13114256
21:38:33 87101 8192000000 10877812656 11347652608 1878583632 18.654 95 9 18 0 1891586224 10736336 2266256
21:41:00 95339 8192000000 10899710272 11356954624 1919553808 18.983 97 19 37 0 1934379424 9678336 5147280
21:44:44 95072 8192000000 10884526864 11343732736 1861752784 18.517 94 30 60 0 1882833200 12901344 8179072
21:47:27 72421 8192000000 10834483408 11318710272 1874935344 18.624 97 14 28 0 1894601712 15314272 4352096
21:49:40 95392 8192000000 10858203808 11326164992 1870972944 18.592 96 24 48 1 1890059376 12386112 6700320
21:54:43 60227 8192000000 10857307024 11325669376 1887015168 18.722 97 17 34 1 1902283664 10850800 4417696
21:56:45 35063 8192000000 10879000384 11343978496 1946214720 19.196 100 34 66 2 1971238368 15914464 9109184
21:58:32 52151 8192000000 10875305088 11339010048 1927253408 19.045 99 20 40 0 1942430000 9947680 5228912
22:00:28 74696 8192000000 10862970528 11325169664 1878053200 18.649 96 16 32 2 1895156960 12510592 4593168
22:02:43 51229 8192000000 10868222384 11325820928 1901707872 18.840 98 13 25 1 1913315408 8197488 3410048
22:04:37 31020 8192000000 10876294512 11334279168 1943477120 19.174 100 38 78 1 1965579776 11133712 10968944
22:06:08 49227 8192000000 10879720240 11327209472 1925614080 19.032 99 11 22 0 1936839792 8496560 2729152
22:07:59 69997 8192000000 10885983072 11340865536 1940968624 19.154 99 32 65 2 1956028576 6419792 8640160
22:10:11 58669 8192000000 10852102528 11341287424 1902383456 18.845 97 9 18 1 1913136656 8649904 2103296
22:12:50 56649 8192000000 10883138832 11358347264 1942580640 19.167 99 12 24 0 1954044736 7786416 3677680
22:15:15 65943 8192000000 10887008784 11365834752 1953645808 19.256 99 24 48 0 1971072848 10324608 7102432
22:17:39 32528 8192000000 10894915584 11357085696 1929792928 19.065 97 35 70 0 1952936560 12101728 11041904
22:19:12 68025 8192000000 10914986096 11401691136 1983647792 19.494 100 17 34 0 2004920384 16493696 4778896
22:21:20 31342 8192000000 10892239456 11364356096 1951655104 19.240 99 23 46 3 1967332720 8923744 6753872
22:22:53 49799 8192000000 10896537968 11354841088 1954574064 19.263 99 38 76 1 1974937280 9570656 10792560
22:24:46 39785 8192000000 10891084352 11347120128 1870550192 18.589 95 32 64 1 1894658368 15173600 8934576
22:26:37 95654 8192000000 10852801600 11330637824 1905195856 18.868 97 25 50 0 1920952608 8069344 7687408
22:29:15 73919 8192000000 10884362752 11363053568 1961450784 19.318 100 10 20 1 1973509168 9181680 2876704
22:31:29 39247 8192000000 10857406480 11341410304 1904299904 18.861 97 6 12 0 1916378192 10163728 1914560
22:33:10 70210 8192000000 10840604320 11310891008 1847813776 18.404 95 6 11 0 1862775680 13963568 998336
22:35:30 38485 8192000000 10817111440 11277557760 1819612208 18.175 95 19 38 2 1833736912 8760240 5364464
22:37:11 53822 8192000000 8936850784 9326936064 25547184 0.310 2 1 2 0 27978960 2369648 62128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-09-2021 22:39:12 to 13-09-2021 22:39:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 116 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-09-2021 22:39:28 to 13-09-2021 22:39:30.

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          243045
MON$OLDEST_ACTIVE               243046
MON$OLDEST_SNAPSHOT             243046
MON$NEXT_TRANSACTION            243046
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                       2012672
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


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

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

13.09.21 22:39:31. 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
2021-09-13 22:39:33 No severe PSQL-related problems occured
13.09.21 22:39:34. End of report.