Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.10.2021 16:42
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 16040648 kB
MemAvailable 30666816 kB
Buffers 0 kB
Cached 15622780 kB
SwapCached 3804 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-10-07 16:41:44
DTS_END                         2021-10-07 16:41:44.7620
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(108:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42840
TRN_ID                          239918
ATT_ID                          668
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: 9236.98 20036 1108437
customer order: creation 169.57 292 20348
customer order: refuse 14.43 368 1732
order to supplier: creation 45.60 1256 5472
order to supplier: removal 3.22 98332 386
invoice (draft): creation 45.49 3243 5459
invoice (draft): removal 52.08 705 6250
invoice accept: apply 42.17 17755 5060
invoice accept: cancel 47.23 794 5667
customer reserve: creation 3901.30 175 468156
customer reserve: removal 4612.88 54 553546
realization accept: apply 82.04 159 9845
realization accept: cancel 91.28 96 10954
payment from customer: creation 59.68 110 7162
payment from customer: removal 5.82 65 698
payment to supplier: creation 48.36 128 5803
payment to supplier: removal 3.64 67 437
service: total inventory turnovers 4.75 1312 570
service: total monetary turnovers 6.58 55 789
service: aggregate perf. data 0.83 19248 99
service: refresh index statistics 0.03 256515 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-10-2021 16:42:43 to 07-10-2021 16:42:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2730
TEST_TIME 2 3372
TEST_TIME 3 3873
TEST_TIME 4 4701
TEST_TIME 5 4967
TEST_TIME 6 4939
TEST_TIME 7 4699
TEST_TIME 8 5452
TEST_TIME 9 5802
TEST_TIME 10 6056
TEST_TIME 11 6385
TEST_TIME 12 6635
TEST_TIME 13 6590
TEST_TIME 14 6932
TEST_TIME 15 7092
TEST_TIME 16 7912
TEST_TIME 17 7906
TEST_TIME 18 8308
TEST_TIME 19 8778
TEST_TIME 20 9214
TEST_TIME 21 9365
TEST_TIME 22 9353
TEST_TIME 23 9664
TEST_TIME 24 9874
TEST_TIME 25 10369
TEST_TIME 26 10712
TEST_TIME 27 10984
TEST_TIME 28 10964
TEST_TIME 29 11011
TEST_TIME 30 11017
TEST_TIME 31 11077
TEST_TIME 32 10882
TEST_TIME 33 10573
TEST_TIME 34 10370
TEST_TIME 35 10305
TEST_TIME 36 10266
TEST_TIME 37 10214
TEST_TIME 38 10081
TEST_TIME 39 9960
TEST_TIME 40 9942
TEST_TIME 41 9918
TEST_TIME 42 9900
TEST_TIME 43 9781
TEST_TIME 44 9733
TEST_TIME 45 9653
TEST_TIME 46 9567
TEST_TIME 47 9560
TEST_TIME 48 9493
TEST_TIME 49 9411
TEST_TIME 50 9434
TEST_TIME 51 9352
TEST_TIME 52 9342
TEST_TIME 53 9328
TEST_TIME 54 9175
TEST_TIME 55 9079
TEST_TIME 56 9228
TEST_TIME 57 9241
TEST_TIME 58 9224
TEST_TIME 59 9206
TEST_TIME 60 9283
TEST_TIME 61 9242
TEST_TIME 62 9176
TEST_TIME 63 9372
TEST_TIME 64 9359
TEST_TIME 65 9339
TEST_TIME 66 9287
TEST_TIME 67 9433
TEST_TIME 68 9413
TEST_TIME 69 9440
TEST_TIME 70 9498
TEST_TIME 71 9569
TEST_TIME 72 9500
TEST_TIME 73 9453
TEST_TIME 74 9385
TEST_TIME 75 9377
TEST_TIME 76 9360
TEST_TIME 77 9344
TEST_TIME 78 9297
TEST_TIME 79 9251
TEST_TIME 80 9310
TEST_TIME 81 9255
TEST_TIME 82 9222
TEST_TIME 83 9216
TEST_TIME 84 9164
TEST_TIME 85 9178
TEST_TIME 86 9125
TEST_TIME 87 9088
TEST_TIME 88 9137
TEST_TIME 89 9116
TEST_TIME 90 9156
TEST_TIME 91 9137
TEST_TIME 92 9186
TEST_TIME 93 9191
TEST_TIME 94 9240
TEST_TIME 95 9329
TEST_TIME 96 9317
TEST_TIME 97 9319
TEST_TIME 98 9308
TEST_TIME 99 9385
TEST_TIME 100 9379
TEST_TIME 101 9361
TEST_TIME 102 9382
TEST_TIME 103 9313
TEST_TIME 104 9260
TEST_TIME 105 9233
TEST_TIME 106 9234
TEST_TIME 107 9234
TEST_TIME 108 9186
TEST_TIME 109 9201
TEST_TIME 110 9158
TEST_TIME 111 9119
TEST_TIME 112 9146
TEST_TIME 113 9224
TEST_TIME 114 9225
TEST_TIME 115 9191
TEST_TIME 116 9182
TEST_TIME 117 9243
TEST_TIME 118 9242
TEST_TIME 119 9256
TEST_TIME 120 9247

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-10-2021 16:42:47 to 07-10-2021 16:42:50.

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 1540978 1540978 0 0.00 0 18534 6 0 0
doc_list_biud 1540973 1540973 0 0.00 0 23437 25 0 0
sp_add_invoice_to_stock 5204 5060 144 2.76 63 174302 17755 0 144
sp_cancel_adding_invoice 5667 5667 0 0.00 5 82320 794 0 0
sp_cancel_client_order 1732 1732 0 0.00 4 9809 368 0 0
sp_cancel_customer_reserve 553546 553546 0 0.00 1 23439 54 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 3 6223 65 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 2 4091 68 0 0
sp_cancel_supplier_invoice 6261 6250 11 0.17 1 13553 705 0 11
sp_cancel_supplier_order 386 386 0 0.00 12 1084250 98333 0 0
sp_cancel_write_off 10954 10954 0 0.00 1 8698 97 0 0
sp_client_order 20348 20348 0 0.00 3 15509 293 0 0
sp_customer_reserve 468156 468156 0 0.00 0 49591 176 0 0
sp_fill_shopping_cart 31273 31273 0 0.00 1 22503 45 0 0
sp_fill_shopping_cart_clo_res 468132 468132 0 0.00 0 15520 13 0 0
sp_get_clo_for_invoice 5036 5036 0 0.00 0 24277 327 0 0
sp_kill_cost_storno 17756 17756 0 0.00 0 8673 53 0 0
sp_kill_qstorno_ret_qs2qd 1124682 1124682 0 0.00 0 23434 29 0 0
sp_kill_qty_storno 593416 593416 0 0.00 0 23436 63 0 0
sp_lock_selected_doc 20626 20626 0 0.00 0 5667 5 0 0
sp_make_cost_storno 27846 27846 0 0.00 0 12576 50 0 0
sp_multiply_rows_for_pdistr 27846 27846 0 0.00 0 8092 27 0 0
sp_multiply_rows_for_qdistr 20348 20348 0 0.00 0 10368 129 0 0
sp_pay_from_customer 7162 7162 0 0.00 4 13359 110 0 0
sp_pay_to_supplier 5803 5803 0 0.00 4 13413 129 0 0
sp_payment_common 12965 12965 0 0.00 1 12968 26 0 0
sp_qd_handle_on_cancel_clo 1732 1732 0 0.00 0 9801 336 0 0
sp_qd_handle_on_invoice_upd_sts 10703 10703 0 0.00 0 17012 322 0 0
sp_qd_handle_on_reserve_upd_sts 9845 9845 0 0.00 0 8381 52 0 0
sp_reserve_write_off 9845 9845 0 0.00 7 13935 159 0 0
sp_supplier_invoice 5459 5459 0 0.00 7 79466 3243 0 0
sp_supplier_order 5472 5472 0 0.00 6 34803 1257 0 0
srv_aggregate_perf_data 99 99 0 0.00 2853 219436 19248 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1114 123575 49788 0 0
srv_find_qd_qs_mism 1060153 1060153 0 0.00 0 15753 5 0 0
srv_make_invnt_saldo 570 570 0 0.00 36 140706 1312 0 0
srv_make_money_saldo 789 789 0 0.00 0 22621 56 0 0
srv_recalc_idx_stat 4 4 0 0.00 90451 604766 256515 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5062 5062 0 0.00 0 9330 152 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 0 12627 187 0 0
t$perf-norm:sp_cancel_client_order 1732 1732 0 0.00 0 3187 11 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 2093 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 169 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 4063 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 1841 18 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 30882 1860 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 1707 10 0 0
t$perf-norm:sp_client_order 20348 20348 0 0.00 0 19288 18 0 0
t$perf-norm:sp_customer_reserve 976 976 0 0.00 0 1959 7 0 0
t$perf-norm:sp_pay_from_customer 7162 7162 0 0.00 0 6793 8 0 0
t$perf-norm:sp_pay_to_supplier 5803 5803 0 0.00 0 6451 10 0 0
t$perf-norm:sp_reserve_write_off 9845 9845 0 0.00 0 8696 10 0 0
t$perf-norm:sp_supplier_invoice 5459 5459 0 0.00 0 5955 16 0 0
t$perf-norm:sp_supplier_order 5472 5472 0 0.00 0 9212 16 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 159 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 28 1 0 0
t$perf-norm:srv_make_invnt_saldo 570 570 0 0.00 0 686 21 0 0
t$perf-norm:srv_make_money_saldo 789 789 0 0.00 0 1220 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10358 10070 288 2.78 0 10265 19 0 288
v_all_customers 37840 37840 0 0.00 0 19 1 0 0
v_all_suppliers 30072 30072 0 0.00 0 22 1 0 0
v_all_wares 40696 40696 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 215 6 0 0
v_cancel_client_order 3464 3464 0 0.00 0 4207 8 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 1130 6 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 3650 8 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 1019 6 0 0
v_cancel_supplier_invoice 830 808 22 2.65 0 620 14 0 22
v_cancel_supplier_order 772 772 0 0.00 0 2446 11 0 0
v_cancel_write_off 2002 2002 0 0.00 0 4171 14 0 0
v_max_id_clo_ord 5472 5472 0 0.00 0 109 1 0 0
v_max_id_clo_res 976 976 0 0.00 0 4026 17 0 0
v_max_id_ord_sup 5453 5453 0 0.00 0 4518 4 0 0
v_max_non_paid_invoice 1692 1692 0 0.00 0 463 3 0 0
v_max_non_paid_realizn 3576 3576 0 0.00 0 4890 6 0 0
v_min_id_clo_ord 5472 5472 0 0.00 0 3510 6 0 0
v_min_id_clo_res 976 976 0 0.00 0 3404 9 0 0
v_min_id_ord_sup 5453 5453 0 0.00 0 5289 6 0 0
v_min_non_paid_invoice 5803 5803 0 0.00 0 12963 20 0 0
v_min_non_paid_realizn 7162 7162 0 0.00 0 5693 19 0 0
v_our_firm 10028 10028 0 0.00 0 17 1 0 0
v_reserve_write_off 19690 19690 0 0.00 0 4251 5 0 0
x_lock_depdocs_on_canc_invoice 11513 11513 0 0.00 0 20372 138 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 3721 330 0 0
x_make_qstorno_1000_1200 5472 5472 0 0.00 1 34785 1165 0 0
x_make_qstorno_1200_2000 5459 5459 0 0.00 4 79311 3085 0 0
x_make_qstorno_2100_3300 468156 468156 0 0.00 0 49590 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-10-2021 16:42:51 to 07-10-2021 16:42:55.

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:11:48 28 8192000000 8812668416 8828850176 13799648 0.168 5 1 2 0 13993104 72560 120896
14:12:48 21185 8192000000 9880488416 10187362304 965165424 10.540 100 89 180 0 995181216 6303792 23712000
14:14:15 15929 8192000000 9941853776 10262024192 1025124256 11.121 100 92 184 0 1057624928 8659776 23840896
14:15:33 18335 8192000000 9976311552 10308227072 1058719600 11.444 100 88 176 0 1090951040 10320832 21910608
14:16:56 11473 8192000000 10020898576 10370826240 1108522064 11.918 100 79 158 2 1139745888 11924160 19299664
14:18:10 9709 8192000000 10077395936 10435534848 1164315584 12.444 100 85 170 0 1197188864 13192464 19680816
14:19:23 6555 8192000000 10117746480 10481434624 1200360848 12.780 100 82 163 0 1232106000 14080448 17664704
14:20:31 2123 8192000000 10151950480 10524508160 1238101264 13.129 100 88 175 2 1269287328 11882048 19304016
14:21:36 3109 8192000000 10178546800 10557972480 1263675520 13.364 100 77 155 0 1292779008 12560800 16542688
14:22:40 1838 8192000000 10203040352 10589773824 1290419440 13.608 100 79 156 2 1319684416 12436624 16828352
14:23:43 4385 8192000000 10200354640 10609033216 1306874064 13.758 100 34 68 0 1331101696 16446544 7781088
14:24:49 7219 8192000000 10198544048 10612903936 1311098800 13.796 100 18 36 1 1321679200 6583632 3996768
14:26:00 60427 8192000000 10210415664 10631061504 1318804240 13.866 100 36 70 0 1332630016 4967440 8858336
14:28:01 4072 8192000000 10223058592 10640007168 1327967328 13.949 100 37 72 0 1343652032 7280912 8403792
14:29:08 8594 8192000000 10226400432 10647945216 1324668288 13.919 99 28 56 0 1341181008 10388864 6123856
14:30:17 75733 8192000000 10214509824 10632585216 1300945232 13.704 98 37 74 1 1315364336 5932064 8487040
14:32:33 8418 8192000000 10206981328 10622472192 1305531824 13.746 99 23 45 0 1316927616 6298016 5097776
14:33:42 8474 8192000000 10142509424 10526728192 1162995968 12.431 92 29 59 0 1174694896 4857120 6841808
14:34:51 69548 8192000000 10117474848 10513281024 1143501408 12.248 90 26 54 0 1154699552 4968672 6229472
14:37:01 5924 8192000000 10174981120 10573385728 1272864000 13.448 100 40 80 0 1288838400 6912608 9061792
14:38:07 4734 8192000000 10182234368 10587901952 1282933104 13.540 100 32 60 3 1296436480 6141168 7362208
14:39:13 16582 8192000000 10198046352 10602323968 1295690480 13.656 100 19 38 3 1305814576 6000320 4123776
14:40:34 19781 8192000000 10189826304 10606485504 1302315968 13.716 100 24 48 0 1313105712 5653440 5136304
14:42:01 19226 8192000000 10237502528 10644021248 1328904864 13.957 100 44 85 1 1345793280 7229248 9659168
14:43:44 10702 8192000000 10251791968 10659860480 1332354928 13.988 99 52 105 0 1357519744 12328352 12836464
14:45:02 44879 8192000000 10268815200 10688266240 1354930208 14.192 100 49 98 2 1383555104 16670736 11954160
14:47:15 23644 8192000000 10286640288 10714386432 1371112720 14.337 100 41 82 2 1408449664 27723312 9613632
14:48:42 10879 8192000000 10306750704 10723352576 1381253584 14.428 100 48 95 1 1415900528 22944832 11702112
14:49:58 2432 8192000000 10308417008 10724540416 1380473792 14.421 100 66 132 2 1418265584 22430240 15361552
14:51:05 1110 8192000000 10335960800 10749497344 1395863760 14.558 100 84 170 1 1444781360 28204336 20713264
14:52:09 3260 8192000000 10348189488 10764914688 1408025440 14.666 100 79 158 0 1456439264 28378496 20035328
14:53:18 1455 8192000000 10341186240 10747535360 1392954368 14.532 100 86 172 0 1445020912 31453008 20613536
14:54:25 2137 8192000000 10331762624 10739908608 1386894192 14.478 100 81 161 2 1440500992 33995152 19611648
14:55:34 3078 8192000000 10353854912 10765348864 1396711648 14.566 100 77 154 1 1455910944 40019760 19179536
14:56:41 2850 8192000000 10344969920 10750033920 1382267200 14.437 100 81 160 2 1446855408 44286208 20302000
14:57:49 7958 8192000000 10325909008 10752577536 1383099664 14.444 100 47 92 0 1432970000 38049328 11821008
14:59:02 1335 8192000000 10338512688 10737770496 1365598032 14.288 100 92 184 1 1435280736 45743264 23939440
15:00:09 5964 8192000000 10340431328 10750894080 1368168960 14.311 100 72 140 0 1438012544 52246896 17596688
15:01:23 16921 8192000000 10316690544 10749530112 1367069456 14.301 99 40 80 1 1419696752 41855248 10772048
15:02:43 18887 8192000000 10307631984 10737090560 1368587472 14.314 100 24 48 0 1413089392 37962416 6539504
15:04:17 41717 8192000000 10293174368 10731122688 1344028672 14.094 99 20 40 1 1388261840 38725520 5507648
15:06:03 58562 8192000000 10288129040 10730106880 1342796128 14.083 99 20 38 3 1389422464 41753392 4872944
15:08:15 57241 8192000000 10259669408 10692587520 1314323040 13.825 97 19 38 1 1340729216 21820912 4585264
15:10:27 105896 8192000000 10275122704 10723729408 1305633984 13.746 97 10 20 1 1334159568 26448064 2077520
15:13:13 113388 8192000000 10255196624 10695442432 1324237472 13.915 98 18 36 0 1339811696 11263920 4310304
15:16:10 39185 8192000000 10252473680 10697445376 1349383488 14.142 100 27 54 1 1363700192 8534720 5781984
15:18:03 68498 8192000000 10270191568 10725429248 1367192464 14.302 100 11 22 0 1378013024 8011184 2809376
15:20:18 38977 8192000000 10286363760 10742657024 1384800736 14.459 100 13 27 1 1399443280 11520784 3121760
15:22:03 65011 8192000000 10293287936 10754949120 1391289184 14.517 100 17 34 1 1408242272 12778592 4174496
15:24:56 66273 8192000000 10282328416 10738438144 1375423824 14.376 99 22 44 0 1388981312 8705776 4851712
15:27:39 104764 8192000000 10264541200 10727550976 1336534672 14.026 96 11 20 2 1347739104 8899728 2304704
15:30:27 41699 8192000000 10290907472 10741383168 1361213408 14.248 98 28 56 1 1379614192 11956480 6444304
15:32:21 63393 8192000000 10288045152 10752106496 1378582672 14.404 99 15 30 0 1394042128 11764864 3694592
15:34:27 64248 8192000000 10301280960 10763247616 1393220976 14.535 100 10 20 0 1414674480 18850096 2603408
15:36:32 50664 8192000000 10285644784 10758451200 1385949088 14.470 100 14 28 0 1403674768 14558832 3166848
15:38:23 34923 8192000000 10290747888 10751340544 1380274240 14.419 99 18 36 0 1394643872 9602080 4767552
15:40:21 88679 8192000000 10290073104 10759299072 1358149568 14.221 97 17 34 1 1373946048 12374912 3421568
15:42:50 9310 8192000000 10284915648 10729811968 1359703888 14.235 99 56 108 3 1379511312 7095248 12712176
15:44:07 89787 8192000000 10285314496 10752028672 1351874960 14.164 98 14 28 0 1376041744 20766688 3400096
15:46:37 32306 8192000000 10280920048 10718670848 1340697648 14.064 98 32 64 1 1356069232 8658064 6713520
15:48:24 62975 8192000000 10282119184 10741280768 1336061456 14.022 98 12 24 0 1354946928 16135296 2750176
15:50:27 123575 8192000000 10293614608 10756599808 1339897248 14.056 97 9 18 1 1365097856 22701376 2499232
15:53:33 71061 8192000000 10276542704 10733723648 1371589872 14.341 100 19 38 0 1387093072 11114208 4388992
15:55:50 34863 8192000000 10268343056 10730876928 1361468032 14.251 100 22 44 1 1380731904 14368544 4895328
15:57:26 70762 8192000000 10237535152 10696663040 1309682576 13.783 96 16 32 1 1322608608 9117520 3808512
15:59:37 57640 8192000000 10245750224 10700087296 1325374400 13.925 97 17 34 0 1343040800 13467632 4198768
16:01:35 45745 8192000000 10251477776 10712805376 1325378352 13.925 98 14 28 0 1350957968 22168992 3410624
16:03:23 73140 8192000000 10251220688 10709397504 1321037488 13.886 97 17 32 1 1334930880 10423200 3470192
16:05:39 73898 8192000000 10245879536 10706649088 1339370176 14.052 99 17 34 1 1357167136 13754064 4042896
16:08:05 26283 8192000000 10279939968 10719072256 1341956640 14.075 98 34 68 1 1356767888 7246032 7565216
16:09:37 50251 8192000000 10268909680 10717855744 1333188720 13.996 97 9 18 0 1346541808 10943408 2409680
16:11:31 68045 8192000000 10293968992 10748514304 1362765856 14.262 98 14 28 0 1373581376 7628448 3187072
16:13:39 25297 8192000000 10285925056 10732748800 1375672192 14.378 100 20 40 0 1387965728 7706592 4586944
16:15:05 75372 8192000000 10310407152 10767446016 1380840320 14.424 99 16 32 1 1405466688 20910992 3715376
16:17:25 39367 8192000000 10308165600 10756571136 1391592560 14.520 100 20 40 0 1406182080 10257104 4332416
16:19:11 62261 8192000000 10294861008 10738839552 1343060320 14.085 97 35 71 0 1368117744 17021280 8036144
16:21:17 87854 8192000000 10307593392 10767048704 1363980224 14.273 98 20 40 2 1386180208 18066432 4133552
16:23:47 97787 8192000000 10286552928 10738450432 1357686208 14.217 98 8 16 0 1375709680 16660352 1363120
16:26:26 46285 8192000000 10284029616 10740809728 1370268704 14.329 99 16 32 1 1387186272 12909024 4008544
16:28:28 30362 8192000000 10265592688 10738057216 1349085936 14.139 98 9 18 0 1363019488 11882832 2050720
16:30:13 61127 8192000000 10280468272 10737422336 1367434688 14.304 99 27 54 0 1393568640 19710224 6423728
16:32:15 110014 8192000000 10266436128 10736218112 1340350496 14.061 97 22 43 1 1366985216 21273568 5361152
16:35:06 92252 8192000000 10264343056 10709700608 1310443296 13.790 96 16 32 1 1325447984 10838448 4166240
16:37:39 101993 8192000000 10265371824 10714828800 1337843616 14.038 99 17 34 0 1353056528 11605488 3607424
16:40:21 87259 8192000000 9858036048 10253914112 960425040 10.493 70 22 44 0 977244784 12107152 4712592

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-10-2021 16:42:57 to 07-10-2021 16:43:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 144 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 07-10-2021 16:43:13 to 07-10-2021 16:43:14.

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

07.10.21 16:43:16. 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-10-07 16:43:18 No severe PSQL-related problems occured
07.10.21 16:43:19. End of report.