Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.09.2021 01: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 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 16465244 kB
MemAvailable 30873164 kB
Buffers 0 kB
Cached 15212088 kB
SwapCached 336 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-04 01:37:44
DTS_END                         2021-09-04 01:37:44.1900
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55300
TRN_ID                          244189
ATT_ID                          680
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: 9273.25 20333 1112790
customer order: creation 180.32 344 21638
customer order: refuse 14.39 328 1727
order to supplier: creation 47.03 1216 5644
order to supplier: removal 3.48 90913 418
invoice (draft): creation 47.94 3365 5753
invoice (draft): removal 50.68 761 6082
invoice accept: apply 43.65 17857 5238
invoice accept: cancel 46.00 658 5520
customer reserve: creation 4007.85 179 480942
customer reserve: removal 4525.48 55 543058
realization accept: apply 88.07 147 10568
realization accept: cancel 83.36 106 10003
payment from customer: creation 60.86 101 7303
payment from customer: removal 6.14 72 737
payment to supplier: creation 52.21 106 6265
payment to supplier: removal 3.30 60 396
service: total inventory turnovers 4.93 1068 591
service: total monetary turnovers 6.67 27 800
service: aggregate perf. data 0.86 20238 103
service: refresh index statistics 0.03 269060 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-09-2021 01:38:20 to 04-09-2021 01:38:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1956
TEST_TIME 2 3019
TEST_TIME 3 4382
TEST_TIME 4 4321
TEST_TIME 5 4455
TEST_TIME 6 4742
TEST_TIME 7 4812
TEST_TIME 8 4860
TEST_TIME 9 4924
TEST_TIME 10 5204
TEST_TIME 11 5564
TEST_TIME 12 6117
TEST_TIME 13 6073
TEST_TIME 14 6515
TEST_TIME 15 6905
TEST_TIME 16 7083
TEST_TIME 17 7428
TEST_TIME 18 7801
TEST_TIME 19 7912
TEST_TIME 20 8210
TEST_TIME 21 9094
TEST_TIME 22 10161
TEST_TIME 23 10823
TEST_TIME 24 10445
TEST_TIME 25 10492
TEST_TIME 26 10406
TEST_TIME 27 10304
TEST_TIME 28 10301
TEST_TIME 29 10139
TEST_TIME 30 9897
TEST_TIME 31 10091
TEST_TIME 32 9898
TEST_TIME 33 9820
TEST_TIME 34 9767
TEST_TIME 35 9606
TEST_TIME 36 9494
TEST_TIME 37 9400
TEST_TIME 38 9253
TEST_TIME 39 9237
TEST_TIME 40 9368
TEST_TIME 41 9514
TEST_TIME 42 9481
TEST_TIME 43 9414
TEST_TIME 44 9404
TEST_TIME 45 9532
TEST_TIME 46 9567
TEST_TIME 47 9544
TEST_TIME 48 9593
TEST_TIME 49 9636
TEST_TIME 50 9560
TEST_TIME 51 9605
TEST_TIME 52 9536
TEST_TIME 53 9543
TEST_TIME 54 9394
TEST_TIME 55 9370
TEST_TIME 56 9288
TEST_TIME 57 9253
TEST_TIME 58 9198
TEST_TIME 59 9129
TEST_TIME 60 9107
TEST_TIME 61 9086
TEST_TIME 62 9081
TEST_TIME 63 9120
TEST_TIME 64 9132
TEST_TIME 65 9133
TEST_TIME 66 9140
TEST_TIME 67 9151
TEST_TIME 68 9142
TEST_TIME 69 9143
TEST_TIME 70 9223
TEST_TIME 71 9236
TEST_TIME 72 9235
TEST_TIME 73 9325
TEST_TIME 74 9394
TEST_TIME 75 9406
TEST_TIME 76 9475
TEST_TIME 77 9374
TEST_TIME 78 9309
TEST_TIME 79 9247
TEST_TIME 80 9273
TEST_TIME 81 9248
TEST_TIME 82 9210
TEST_TIME 83 9170
TEST_TIME 84 9202
TEST_TIME 85 9162
TEST_TIME 86 9191
TEST_TIME 87 9190
TEST_TIME 88 9272
TEST_TIME 89 9292
TEST_TIME 90 9354
TEST_TIME 91 9307
TEST_TIME 92 9332
TEST_TIME 93 9318
TEST_TIME 94 9332
TEST_TIME 95 9398
TEST_TIME 96 9374
TEST_TIME 97 9396
TEST_TIME 98 9434
TEST_TIME 99 9367
TEST_TIME 100 9317
TEST_TIME 101 9255
TEST_TIME 102 9272
TEST_TIME 103 9252
TEST_TIME 104 9219
TEST_TIME 105 9185
TEST_TIME 106 9147
TEST_TIME 107 9117
TEST_TIME 108 9101
TEST_TIME 109 9090
TEST_TIME 110 9065
TEST_TIME 111 9076
TEST_TIME 112 9196
TEST_TIME 113 9197
TEST_TIME 114 9181
TEST_TIME 115 9193
TEST_TIME 116 9202
TEST_TIME 117 9194
TEST_TIME 118 9235
TEST_TIME 119 9258
TEST_TIME 120 9282

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-09-2021 01:38:23 to 04-09-2021 01:38:27.

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 1557079 1557079 0 0.00 0 18394 7 0 0
doc_list_biud 1557079 1557079 0 0.00 0 24606 24 0 0
sp_add_invoice_to_stock 5350 5238 112 2.09 48 186123 17857 0 112
sp_cancel_adding_invoice 5520 5520 0 0.00 5 40840 658 0 0
sp_cancel_client_order 1727 1727 0 0.00 4 10138 328 0 0
sp_cancel_customer_reserve 543058 543058 0 0.00 1 24607 55 0 0
sp_cancel_pay_from_customer 737 737 0 0.00 3 3437 72 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 3 2307 61 0 0
sp_cancel_supplier_invoice 6091 6082 9 0.14 2 14222 761 0 9
sp_cancel_supplier_order 418 418 0 0.00 8 1013835 90914 0 0
sp_cancel_write_off 10003 10003 0 0.00 1 7596 106 0 0
sp_client_order 21638 21638 0 0.00 4 22493 344 0 0
sp_customer_reserve 480942 480942 0 0.00 0 60641 180 0 0
sp_fill_shopping_cart 33024 33024 0 0.00 1 42308 39 0 0
sp_fill_shopping_cart_clo_res 480936 480936 0 0.00 0 13521 13 0 0
sp_get_clo_for_invoice 5225 5225 0 0.00 0 28684 323 0 0
sp_kill_cost_storno 16656 16656 0 0.00 0 5245 47 0 0
sp_kill_qstorno_ret_qs2qd 1102619 1102619 0 0.00 0 24605 30 0 0
sp_kill_qty_storno 582598 582598 0 0.00 0 24606 64 0 0
sp_lock_selected_doc 21593 21593 0 0.00 0 8336 5 0 0
sp_make_cost_storno 29358 29358 0 0.00 0 9777 43 0 0
sp_multiply_rows_for_pdistr 29358 29358 0 0.00 0 9688 24 0 0
sp_multiply_rows_for_qdistr 21638 21638 0 0.00 0 18354 162 0 0
sp_pay_from_customer 7303 7303 0 0.00 4 24783 102 0 0
sp_pay_to_supplier 6265 6265 0 0.00 4 24812 107 0 0
sp_payment_common 13568 13568 0 0.00 2 14569 22 0 0
sp_qd_handle_on_cancel_clo 1727 1727 0 0.00 0 10127 301 0 0
sp_qd_handle_on_invoice_upd_sts 10742 10742 0 0.00 0 17698 268 0 0
sp_qd_handle_on_reserve_upd_sts 10568 10568 0 0.00 0 16657 48 0 0
sp_reserve_write_off 10568 10568 0 0.00 6 20671 147 0 0
sp_supplier_invoice 5753 5753 0 0.00 7 101735 3366 0 0
sp_supplier_order 5644 5644 0 0.00 5 75629 1216 0 0
srv_aggregate_perf_data 103 103 0 0.00 2847 285692 20238 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 3889 138038 48811 0 0
srv_find_qd_qs_mism 1062496 1062496 0 0.00 0 18393 7 0 0
srv_make_invnt_saldo 591 591 0 0.00 34 94626 1068 0 0
srv_make_money_saldo 800 800 0 0.00 1 8336 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 188035 381040 269060 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 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 5238 5238 0 0.00 0 9938 143 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 3814 83 0 0
t$perf-norm:sp_cancel_client_order 1727 1727 0 0.00 0 4340 12 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 2557 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 737 737 0 0.00 0 752 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 156 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 6272 54 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 18854 1279 0 0
t$perf-norm:sp_cancel_write_off 1019 1019 0 0.00 0 1810 5 0 0
t$perf-norm:sp_client_order 21638 21638 0 0.00 0 8808 16 0 0
t$perf-norm:sp_customer_reserve 1017 1017 0 0.00 0 6068 13 0 0
t$perf-norm:sp_pay_from_customer 7303 7303 0 0.00 0 9091 8 0 0
t$perf-norm:sp_pay_to_supplier 6265 6265 0 0.00 0 6355 9 0 0
t$perf-norm:sp_reserve_write_off 10568 10568 0 0.00 0 9722 12 0 0
t$perf-norm:sp_supplier_invoice 5753 5753 0 0.00 0 7185 20 0 0
t$perf-norm:sp_supplier_order 5644 5644 0 0.00 0 4921 15 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 626 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 166 4 0 0
t$perf-norm:srv_make_invnt_saldo 591 591 0 0.00 0 2426 20 0 0
t$perf-norm:srv_make_money_saldo 800 800 0 0.00 0 2511 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 922 233 0 0
v_add_invoice_to_stock 10666 10442 224 2.10 0 4570 15 0 224
v_all_customers 39126 39126 0 0.00 0 21 0 0 0
v_all_suppliers 31942 31942 0 0.00 0 17 1 0 0
v_all_wares 43276 43276 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 615 7 0 0
v_cancel_client_order 3454 3454 0 0.00 0 8112 5 0 0
v_cancel_customer_prepayment 1474 1474 0 0.00 0 1143 5 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 1584 7 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 1110 5 0 0
v_cancel_supplier_invoice 838 820 18 2.14 0 687 13 0 18
v_cancel_supplier_order 836 836 0 0.00 0 2096 5 0 0
v_cancel_write_off 2038 2038 0 0.00 0 842 11 0 0
v_max_id_clo_ord 5644 5644 0 0.00 0 252 1 0 0
v_max_id_clo_res 1017 1017 0 0.00 0 978 7 0 0
v_max_id_ord_sup 5742 5742 0 0.00 0 2357 4 0 0
v_max_non_paid_invoice 1680 1680 0 0.00 0 582 2 0 0
v_max_non_paid_realizn 4017 4017 0 0.00 0 550 3 0 0
v_min_id_clo_ord 5644 5644 0 0.00 0 3466 5 0 0
v_min_id_clo_res 1017 1017 0 0.00 0 3838 17 0 0
v_min_id_ord_sup 5742 5742 0 0.00 0 3328 7 0 0
v_min_non_paid_invoice 6265 6265 0 0.00 0 7270 12 0 0
v_min_non_paid_realizn 7303 7303 0 0.00 0 3640 12 0 0
v_our_firm 10722 10722 0 0.00 0 131 1 0 0
v_reserve_write_off 21136 21136 0 0.00 0 14777 7 0 0
x_lock_depdocs_on_canc_invoice 11192 11192 0 0.00 0 15927 109 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 6289 292 0 0
x_make_qstorno_1000_1200 5644 5644 0 0.00 1 75618 1154 0 0
x_make_qstorno_1200_2000 5753 5753 0 0.00 3 95709 3201 0 0
x_make_qstorno_2100_3300 480942 480942 0 0.00 0 60638 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-09-2021 01:38:28 to 04-09-2021 01:38:32.

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
23:07:47 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:49 15649 8192000000 10350054752 10664644608 1404415312 14.634 100 85 170 0 1437850128 5632688 27802128
23:10:12 30417 8192000000 10433206976 10766016512 1481000256 15.310 100 88 176 0 1516742896 7594480 28148160
23:11:49 16877 8192000000 10510017472 10854576128 1568739424 16.071 100 86 172 0 1604815232 9390640 26685168
23:13:09 14455 8192000000 10585238864 10942881792 1633716208 16.626 100 75 150 0 1666207632 9694640 22796784
23:14:27 8168 8192000000 10651734288 11018870784 1711742576 17.283 100 83 166 1 1748403024 12932416 23728032
23:15:39 5713 8192000000 10701062496 11081564160 1764009536 17.718 100 74 148 1 1797671440 12999088 20662816
23:16:48 3963 8192000000 10748988592 11132366848 1809886672 18.095 100 88 174 1 1848198672 13454048 24857952
23:17:53 2608 8192000000 10784427104 11168956416 1842383168 18.360 100 81 162 5 1878446384 13109328 22953888
23:18:58 2001 8192000000 10815716288 11203334144 1869110976 18.577 100 96 192 3 1910373328 14069264 27193088
23:20:02 12930 8192000000 10817712000 11214217216 1879732816 18.663 100 74 145 1 1912931216 12041888 21156512
23:21:17 4824 8192000000 10793640944 11213692928 1880576368 18.670 100 38 76 2 1900814768 8316160 11922240
23:22:24 4845 8192000000 10799237360 11221065728 1883616208 18.694 100 32 62 0 1900636592 6834976 10185408
23:23:34 8731 8192000000 10797899392 11229999104 1896473584 18.798 100 18 35 1 1906763632 5844608 4445440
23:24:43 70600 8192000000 10852553696 11263823872 1911470144 18.918 100 56 114 1 1934359664 5916032 16973488
23:26:54 5976 8192000000 10813309264 11246714880 1903503536 18.854 100 33 66 0 1919369520 5854480 10011504
23:28:02 42680 8192000000 10796542496 11231592448 1860400896 18.507 98 21 43 1 1872051472 5932176 5718400
23:29:45 4905 8192000000 10766651344 11191365632 1843866064 18.372 99 21 42 3 1855596288 6174064 5556160
23:30:51 6563 8192000000 10702428320 11114119168 1752630064 17.623 97 29 58 1 1765689680 5613728 7445888
23:31:58 57798 8192000000 10729203600 11162243072 1819705552 18.175 99 56 113 2 1843440608 6128544 17606512
23:33:58 8454 8192000000 10736651360 11157843968 1799183808 18.007 98 20 40 2 1810371680 5258480 5929392
23:35:08 7846 8192000000 10763427200 11194679296 1854980000 18.463 100 18 35 2 1866044480 5718848 5345632
23:36:19 10595 8192000000 10785782848 11213746176 1868000816 18.568 100 12 24 0 1876339856 4993136 3345904
23:37:31 82424 8192000000 10853156064 11264987136 1900722848 18.832 100 47 91 2 1919854032 5997904 13133280
23:40:16 3887 8192000000 10894218528 11313008640 1951218176 19.236 100 67 134 2 1985362976 14682448 19462352
23:41:27 25489 8192000000 10901204000 11331538944 1962211344 19.324 100 50 100 2 1992155712 15687232 14257136
23:43:00 10695 8192000000 10911258384 11351982080 1977425600 19.444 100 40 78 0 2006215248 17462944 11326704
23:44:15 7226 8192000000 10938180656 11372212224 1996937312 19.599 100 44 88 0 2032065216 21797232 13330672
23:45:28 5024 8192000000 10961681472 11393925120 2007123104 19.679 100 61 120 1 2052273136 28040224 17109808
23:46:44 14711 8192000000 10982546736 11414847488 2004824608 19.661 99 67 134 1 2065746848 40248112 20674128
23:48:04 5799 8192000000 10988262000 11420622848 2013078832 19.726 100 61 123 1 2070397920 38510448 18808640
23:49:20 6822 8192000000 11004493088 11435282432 2027515936 19.839 100 71 138 0 2096048736 47364752 21168048
23:50:31 6737 8192000000 11010665280 11430486016 2023525424 19.808 100 75 150 0 2094432720 49254672 21652624
23:51:44 6442 8192000000 10997730512 11423113216 2008305296 19.688 100 74 144 1 2080409824 49124912 22979616
23:52:57 7981 8192000000 10981507312 11416256512 1984658528 19.502 99 47 94 1 2051993824 52047104 15288192
23:54:10 5305 8192000000 10992671840 11428155392 2003642288 19.651 100 49 96 1 2074971744 55264688 16064768
23:55:18 11339 8192000000 10985244688 11432333312 2008112032 19.687 100 40 78 3 2075860720 54727648 13021040
23:56:37 14115 8192000000 10968157088 11421478912 1980709616 19.470 99 37 74 0 2047454352 54235040 12509696
23:58:10 19123 8192000000 10911799584 11369172992 1929799248 19.065 99 30 60 2 2008221088 67795808 10626032
23:59:45 19668 8192000000 10879882256 11324862464 1858138448 18.488 96 27 54 0 1905551440 38692272 8720720
00:01:19 45174 8192000000 10848775792 11297951744 1888859824 18.737 98 25 50 0 1910334368 14081152 7393392
00:03:05 55127 8192000000 10834072464 11298496512 1900777664 18.833 100 9 17 1 1916815952 13165776 2872512
00:05:01 98087 8192000000 10854560336 11306213376 1904128784 18.859 99 23 47 1 1918407200 8232784 6045632
00:07:40 39556 8192000000 10877729472 11336138752 1944246256 19.181 100 14 28 0 1965106080 16419680 4440144
00:09:20 42756 8192000000 10845255264 11299831808 1897817712 18.809 98 12 24 2 1913001360 12151216 3032432
00:12:11 42689 8192000000 10823476240 11283234816 1880986896 18.673 97 13 24 1 1895989920 11119136 3883888
00:14:26 73894 8192000000 10900862544 11361533952 1969957904 19.385 100 22 44 1 1993434592 16822912 6653776
00:16:48 61325 8192000000 10887021424 11362172928 1950975808 19.234 99 16 32 1 1978947056 22347856 5623392
00:18:51 43910 8192000000 10880190928 11340152832 1919645648 18.984 98 16 32 0 1937717744 13406800 4665296
00:20:36 41323 8192000000 10906305616 11342708736 1930686576 19.072 98 41 79 0 1954951728 12340352 11924800
00:22:21 138038 8192000000 10873980336 11338002432 1928015504 19.051 99 11 22 2 1948673056 17384288 3273264
00:25:47 123059 8192000000 10889043008 11348377600 1935329312 19.109 98 19 38 2 1951664800 11329472 5006016
00:28:56 61278 8192000000 10902530432 11358507008 1923550640 19.015 97 34 68 0 1943029792 10706384 8772768
00:31:02 92975 8192000000 10876087024 11328462848 1889714704 18.743 96 31 62 1 1910536384 11742080 9079600
00:33:41 17668 8192000000 10848960016 11315777536 1891955952 18.762 98 12 24 1 1903777024 8660688 3160384
00:34:59 42825 8192000000 10865952496 11338108928 1936079264 19.115 99 14 28 0 1949664896 9649856 3935776
00:36:46 62283 8192000000 10893334512 11360870400 1971854528 19.400 100 9 18 0 1985344608 10707968 2782112
00:38:48 54368 8192000000 10886610048 11358793728 1955509872 19.270 99 9 18 1 1969224624 11195504 2519248
00:40:43 43187 8192000000 10889415344 11351109632 1903228400 18.852 96 12 24 0 1913623968 7428160 2967408
00:42:34 36377 8192000000 10870149088 11332382720 1873246656 18.611 95 24 48 1 1886482496 6904320 6331520
00:44:11 10529 8192000000 10853170256 11298430976 1891527392 18.758 98 50 100 0 1914280768 8163536 14589840
00:45:21 63326 8192000000 10856006896 11313790976 1868605584 18.573 97 26 52 0 1885253760 9628896 7019280
00:47:31 76016 8192000000 10852797040 11309293568 1840094800 18.342 95 26 53 2 1863331840 16310640 6926400
00:49:51 79882 8192000000 10870398736 11335208960 1901942640 18.842 98 16 32 1 1925631872 18986320 4702912
00:52:11 119351 8192000000 10894668832 11366825984 1954546320 19.263 100 21 42 1 1979402112 18256672 6599120
00:56:05 53606 8192000000 10856213056 11320254464 1938616176 19.136 99 22 44 1 1953770080 8835968 6317936
00:57:59 42532 8192000000 10841749200 11327205376 1925394144 19.030 100 5 10 0 1943602384 16512624 1695616
01:00:09 74223 8192000000 10855280720 11327107072 1880281520 18.667 96 9 18 0 1893570576 10506240 2782816
01:02:39 75605 8192000000 10889378608 11367038976 1950620944 19.231 99 15 30 1 1968069408 13189408 4259056
01:05:04 69102 8192000000 10902207824 11382767616 1953954368 19.258 99 10 20 0 1971841712 14934336 2953008
01:07:14 88945 8192000000 10903440800 11362684928 1941043600 19.155 98 16 31 0 1952898672 8091648 3763424
01:09:46 41744 8192000000 10923652704 11391954944 1974748448 19.423 99 18 36 0 1989060784 9610256 4702080
01:11:36 24080 8192000000 10919698032 11370921984 1962734992 19.328 99 37 73 2 1985451232 12621648 10094592
01:13:01 59687 8192000000 10918875248 11380326400 1957836608 19.289 99 12 24 1 1970251792 9505488 2909696
01:15:07 94192 8192000000 10909236624 11380092928 1913508272 18.935 96 22 43 1 1936681344 16453504 6719568
01:17:57 65386 8192000000 10897531312 11365396480 1950705600 19.232 98 26 52 1 1968652544 10701728 7245216
01:20:13 40441 8192000000 10878895520 11356962816 1941516896 19.159 98 16 32 1 1953021216 6847216 4657104
01:21:54 81443 8192000000 10893905760 11368919040 1976738480 19.439 100 12 24 1 1987420768 7118336 3563952
01:24:23 81903 8192000000 10897541664 11378991104 1949111040 19.219 98 15 30 2 1960411760 6946544 4354176
01:26:51 55898 8192000000 10907369680 11367407616 1918527856 18.975 96 22 44 2 1938447760 13265200 6654704
01:28:47 74422 8192000000 10879707680 11361824768 1871387856 18.596 94 9 18 0 1889815760 15258272 3169632
01:31:23 22567 8192000000 10821225056 11271745536 1824240112 18.212 95 36 72 0 1844359296 10072272 10046912
01:32:50 21633 8192000000 10885277616 11334774784 1936465312 19.119 100 37 74 0 1960992496 13987904 10539280
01:34:21 62461 8192000000 10893015632 11358236672 1945032592 19.187 100 24 48 0 1965429680 13625616 6771472
01:36:29 78581 8192000000 9244787488 9498791936 383254144 4.469 20 4 8 0 389285456 5256416 774896

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 04-09-2021 01:38:34 to 04-09-2021 01:38:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 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 04-09-2021 01:38:49 to 04-09-2021 01:38:51.

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          244188
MON$OLDEST_ACTIVE               244466
MON$OLDEST_SNAPSHOT             244466
MON$NEXT_TRANSACTION            244466
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                       1992576
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.33491 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33491 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33491 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33491 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'

04.09.21 01:38:52. 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-04 01:38:55 No severe PSQL-related problems occured
04.09.21 01:38:56. End of report.