Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.11.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 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16896812 kB
MemAvailable 31567252 kB
Buffers 0 kB
Cached 14749992 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 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-11-16 22:37:52
DTS_END                         2021-11-16 22:37:52.6370
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(141:26) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/37806
TRN_ID                          238219
ATT_ID                          718
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: 9247.69 23098 1109723
customer order: creation 171.84 376 20621
customer order: refuse 14.78 294 1774
order to supplier: creation 45.38 1247 5445
order to supplier: removal 3.47 93338 416
invoice (draft): creation 46.08 3167 5529
invoice (draft): removal 51.98 733 6238
invoice accept: apply 42.18 19136 5061
invoice accept: cancel 47.59 839 5711
customer reserve: creation 3867.89 192 464147
customer reserve: removal 4657.54 55 558905
realization accept: apply 83.01 166 9961
realization accept: cancel 86.88 107 10426
payment from customer: creation 59.38 102 7126
payment from customer: removal 5.52 83 662
payment to supplier: creation 49.00 111 5880
payment to supplier: removal 3.33 79 400
service: total inventory turnovers 4.64 1001 557
service: total monetary turnovers 6.46 30 775
service: aggregate perf. data 0.71 26569 85
service: refresh index statistics 0.03 314343 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2115
TEST_TIME 2 3098
TEST_TIME 3 4916
TEST_TIME 4 4976
TEST_TIME 5 5003
TEST_TIME 6 5038
TEST_TIME 7 5121
TEST_TIME 8 5129
TEST_TIME 9 5019
TEST_TIME 10 5377
TEST_TIME 11 5521
TEST_TIME 12 5695
TEST_TIME 13 6030
TEST_TIME 14 6423
TEST_TIME 15 6661
TEST_TIME 16 6857
TEST_TIME 17 6922
TEST_TIME 18 7196
TEST_TIME 19 7971
TEST_TIME 20 8493
TEST_TIME 21 8948
TEST_TIME 22 9138
TEST_TIME 23 9807
TEST_TIME 24 9981
TEST_TIME 25 10353
TEST_TIME 26 10171
TEST_TIME 27 10380
TEST_TIME 28 10273
TEST_TIME 29 10158
TEST_TIME 30 10057
TEST_TIME 31 9939
TEST_TIME 32 9744
TEST_TIME 33 9816
TEST_TIME 34 9742
TEST_TIME 35 9797
TEST_TIME 36 9716
TEST_TIME 37 9842
TEST_TIME 38 9770
TEST_TIME 39 9641
TEST_TIME 40 9628
TEST_TIME 41 9531
TEST_TIME 42 9550
TEST_TIME 43 9470
TEST_TIME 44 9607
TEST_TIME 45 9544
TEST_TIME 46 9519
TEST_TIME 47 9485
TEST_TIME 48 9735
TEST_TIME 49 9705
TEST_TIME 50 9687
TEST_TIME 51 9680
TEST_TIME 52 9820
TEST_TIME 53 9735
TEST_TIME 54 9675
TEST_TIME 55 9788
TEST_TIME 56 9782
TEST_TIME 57 9740
TEST_TIME 58 9597
TEST_TIME 59 9625
TEST_TIME 60 9614
TEST_TIME 61 9521
TEST_TIME 62 9539
TEST_TIME 63 9494
TEST_TIME 64 9471
TEST_TIME 65 9416
TEST_TIME 66 9405
TEST_TIME 67 9367
TEST_TIME 68 9421
TEST_TIME 69 9479
TEST_TIME 70 9479
TEST_TIME 71 9500
TEST_TIME 72 9605
TEST_TIME 73 9562
TEST_TIME 74 9596
TEST_TIME 75 9647
TEST_TIME 76 9646
TEST_TIME 77 9645
TEST_TIME 78 9564
TEST_TIME 79 9491
TEST_TIME 80 9426
TEST_TIME 81 9414
TEST_TIME 82 9388
TEST_TIME 83 9355
TEST_TIME 84 9352
TEST_TIME 85 9322
TEST_TIME 86 9292
TEST_TIME 87 9350
TEST_TIME 88 9337
TEST_TIME 89 9352
TEST_TIME 90 9337
TEST_TIME 91 9326
TEST_TIME 92 9411
TEST_TIME 93 9431
TEST_TIME 94 9392
TEST_TIME 95 9373
TEST_TIME 96 9447
TEST_TIME 97 9501
TEST_TIME 98 9467
TEST_TIME 99 9465
TEST_TIME 100 9481
TEST_TIME 101 9402
TEST_TIME 102 9344
TEST_TIME 103 9355
TEST_TIME 104 9305
TEST_TIME 105 9266
TEST_TIME 106 9228
TEST_TIME 107 9236
TEST_TIME 108 9171
TEST_TIME 109 9165
TEST_TIME 110 9176
TEST_TIME 111 9152
TEST_TIME 112 9158
TEST_TIME 113 9161
TEST_TIME 114 9149
TEST_TIME 115 9239
TEST_TIME 116 9201
TEST_TIME 117 9219
TEST_TIME 118 9286
TEST_TIME 119 9253
TEST_TIME 120 9252

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-11-2021 22:38:43 to 16-11-2021 22:38:46.

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 1540833 1540833 0 0.00 0 25944 6 0 0
doc_list_biud 1540833 1540833 0 0.00 0 41146 26 0 0
sp_add_invoice_to_stock 5161 5061 100 1.93 35 210920 19137 0 100
sp_cancel_adding_invoice 5711 5711 0 0.00 4 73135 840 0 0
sp_cancel_client_order 1774 1774 0 0.00 4 9592 295 0 0
sp_cancel_customer_reserve 558905 558905 0 0.00 1 41150 55 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 3 6206 84 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 3 5688 80 0 0
sp_cancel_supplier_invoice 6239 6238 1 0.01 1 32392 734 0 1
sp_cancel_supplier_order 416 416 0 0.00 7 1073608 93339 0 0
sp_cancel_write_off 10426 10426 0 0.00 1 12828 107 0 0
sp_client_order 20621 20621 0 0.00 3 23865 377 0 0
sp_customer_reserve 464147 464147 0 0.00 0 119503 192 0 0
sp_fill_shopping_cart 31595 31595 0 0.00 1 10520 33 0 0
sp_fill_shopping_cart_clo_res 464142 464142 0 0.00 0 17380 15 0 0
sp_get_clo_for_invoice 5049 5049 0 0.00 0 24519 320 0 0
sp_kill_cost_storno 17199 17199 0 0.00 0 6759 47 0 0
sp_kill_qstorno_ret_qs2qd 1134890 1134890 0 0.00 0 41101 30 0 0
sp_kill_qty_storno 598479 598479 0 0.00 0 41145 65 0 0
sp_lock_selected_doc 20773 20773 0 0.00 0 8620 6 0 0
sp_make_cost_storno 28015 28015 0 0.00 0 10541 47 0 0
sp_multiply_rows_for_pdistr 28015 28015 0 0.00 0 6928 27 0 0
sp_multiply_rows_for_qdistr 20621 20621 0 0.00 0 22401 174 0 0
sp_pay_from_customer 7126 7126 0 0.00 4 15646 103 0 0
sp_pay_to_supplier 5880 5880 0 0.00 4 10532 112 0 0
sp_payment_common 13006 13006 0 0.00 2 10255 20 0 0
sp_qd_handle_on_cancel_clo 1774 1774 0 0.00 0 9586 272 0 0
sp_qd_handle_on_invoice_upd_sts 10759 10759 0 0.00 0 32307 318 0 0
sp_qd_handle_on_reserve_upd_sts 9961 9961 0 0.00 0 12913 55 0 0
sp_reserve_write_off 9961 9961 0 0.00 6 17724 167 0 0
sp_supplier_invoice 5529 5529 0 0.00 6 100704 3168 0 0
sp_supplier_order 5445 5445 0 0.00 4 27304 1248 0 0
srv_aggregate_perf_data 85 85 0 0.00 2857 574743 26569 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2349 158387 44605 0 0
srv_find_qd_qs_mism 1062624 1062624 0 0.00 0 25894 5 0 0
srv_make_invnt_saldo 557 557 0 0.00 42 26871 1001 0 0
srv_make_money_saldo 775 775 0 0.00 0 7227 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 218861 403735 314344 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5061 5061 0 0.00 0 8770 156 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 1 2930 80 0 0
t$perf-norm:sp_cancel_client_order 1774 1774 0 0.00 0 3031 12 0 0
t$perf-norm:sp_cancel_customer_reserve 705 705 0 0.00 0 2917 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 3690 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 733 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 1097 16 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 29931 1481 0 0
t$perf-norm:sp_cancel_write_off 970 970 0 0.00 0 3876 14 0 0
t$perf-norm:sp_client_order 20621 20621 0 0.00 0 13919 17 0 0
t$perf-norm:sp_customer_reserve 1033 1033 0 0.00 0 993 5 0 0
t$perf-norm:sp_pay_from_customer 7126 7126 0 0.00 0 20009 12 0 0
t$perf-norm:sp_pay_to_supplier 5880 5880 0 0.00 0 13867 18 0 0
t$perf-norm:sp_reserve_write_off 9961 9961 0 0.00 0 9253 10 0 0
t$perf-norm:sp_supplier_invoice 5529 5529 0 0.00 0 12304 24 0 0
t$perf-norm:sp_supplier_order 5445 5445 0 0.00 0 11043 16 0 0
t$perf-norm:srv_aggregate_perf_data 85 85 0 0.00 0 84 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 4293 68 0 0
t$perf-norm:srv_make_invnt_saldo 557 557 0 0.00 0 1342 22 0 0
t$perf-norm:srv_make_money_saldo 775 775 0 0.00 0 728 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 4 0 0
v_add_invoice_to_stock 10296 10096 200 1.94 0 8811 19 0 200
v_all_customers 37692 37692 0 0.00 0 91 1 0 0
v_all_suppliers 30308 30308 0 0.00 0 96 1 0 0
v_all_wares 41242 41242 0 0.00 0 78 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 2588 12 0 0
v_cancel_client_order 3548 3548 0 0.00 0 2551 5 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 3973 8 0 0
v_cancel_customer_reserve 1410 1410 0 0.00 0 2127 5 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 1287 10 0 0
v_cancel_supplier_invoice 838 836 2 0.23 0 872 16 0 2
v_cancel_supplier_order 832 832 0 0.00 0 1301 5 0 0
v_cancel_write_off 1940 1940 0 0.00 0 2360 14 0 0
v_max_id_clo_ord 5445 5445 0 0.00 0 467 1 0 0
v_max_id_clo_res 1033 1033 0 0.00 0 3710 21 0 0
v_max_id_ord_sup 5529 5529 0 0.00 0 1946 3 0 0
v_max_non_paid_invoice 1700 1700 0 0.00 0 5579 6 0 0
v_max_non_paid_realizn 3765 3765 0 0.00 0 2943 4 0 0
v_min_id_clo_ord 5445 5445 0 0.00 0 3745 8 0 0
v_min_id_clo_res 1033 1033 0 0.00 0 6280 18 0 0
v_min_id_ord_sup 5529 5529 0 0.00 0 6829 9 0 0
v_min_non_paid_invoice 5880 5880 0 0.00 0 5410 12 0 0
v_min_non_paid_realizn 7126 7126 0 0.00 0 5811 13 0 0
v_our_firm 10272 10272 0 0.00 0 13 1 0 0
v_reserve_write_off 19922 19922 0 0.00 0 4472 5 0 0
x_lock_depdocs_on_canc_invoice 11531 11531 0 0.00 0 27291 132 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 8228 302 0 0
x_make_qstorno_1000_1200 5445 5445 0 0.00 1 27295 1176 0 0
x_make_qstorno_1200_2000 5529 5529 0 0.00 3 100683 3056 0 0
x_make_qstorno_2100_3300 464147 464147 0 0.00 0 119502 174 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 16-11-2021 22:38:47 to 16-11-2021 22:38:52.

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:56 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:08:57 31318 8192000000 9915743424 10232201216 995916944 10.839 100 81 162 1 1022384384 6051920 20415520
20:10:35 21760 8192000000 9962070544 10287022080 1044769024 11.310 100 89 178 0 1076038848 9082080 22187744
20:12:13 26767 8192000000 10001967952 10343727104 1082443360 11.671 100 82 164 0 1113357360 10830704 20083296
20:13:44 13005 8192000000 10048492080 10400976896 1134541872 12.164 100 77 153 1 1164235232 11749280 17944080
20:15:04 5982 8192000000 10102974784 10467393536 1191947712 12.701 100 78 156 2 1222552912 13022176 17583024
20:16:13 3380 8192000000 10143145728 10516099072 1228369936 13.039 100 87 172 0 1259476672 12187232 18919504
20:17:18 2562 8192000000 10176747792 10553135104 1261874752 13.347 100 82 163 0 1293404272 13345136 18184384
20:18:21 1710 8192000000 10209897552 10592018432 1287322064 13.580 100 94 191 2 1321729136 12214352 22192720
20:19:24 13553 8192000000 10203946080 10598395904 1298680784 13.683 100 73 145 0 1326515824 10927776 16907264
20:20:38 11603 8192000000 10195524704 10612568064 1307272544 13.761 100 18 36 1 1317272800 5922528 4077728
20:21:50 13623 8192000000 10207397984 10623913984 1322388464 13.898 100 13 26 2 1330644064 5058176 3197424
20:23:07 5035 8192000000 10210215104 10630209536 1329922384 13.966 100 23 46 1 1341111408 6389808 4799216
20:24:12 51667 8192000000 10248825792 10649247744 1334141424 14.005 100 53 109 2 1352137456 4928560 13067472
20:26:04 7088 8192000000 10233813232 10654511104 1338811872 14.047 100 19 36 1 1348730288 5127888 4790528
20:27:14 6718 8192000000 10201794400 10623172608 1298039456 13.677 98 14 28 2 1306615104 5578848 2996800
20:28:24 24431 8192000000 10208856672 10628685824 1292329504 13.625 97 23 46 1 1302644704 4804672 5510528
20:29:50 5728 8192000000 10166677840 10574905344 1256117712 13.294 98 33 66 2 1270366352 6382064 7866576
20:30:59 8453 8192000000 10149394448 10550837248 1240203040 13.148 98 32 64 0 1253562672 5326560 8033072
20:32:08 69321 8192000000 10153789056 10559692800 1231538320 13.068 97 23 46 1 1242135760 5305248 5292192
20:34:18 7022 8192000000 10166646672 10573455360 1258650224 13.318 98 29 56 1 1270936768 5654144 6632400
20:35:26 6431 8192000000 10198398224 10612523008 1300910512 13.704 100 13 26 0 1309166192 5416736 2838944
20:36:33 68129 8192000000 10208932176 10623270912 1312540704 13.809 100 27 52 2 1324497376 5151472 6805200
20:38:51 4580 8192000000 10249163568 10655436800 1338327696 14.042 100 53 106 0 1359298176 8943376 12027104
20:40:00 10239 8192000000 10260262640 10672439296 1354850304 14.191 100 52 104 0 1375802352 9710400 11241648
20:41:18 8820 8192000000 10287396944 10700541952 1372316544 14.348 100 52 102 0 1395813376 11705824 11791008
20:42:29 5647 8192000000 10289408592 10708647936 1378983376 14.407 100 50 98 1 1405247152 15101744 11162032
20:43:40 16702 8192000000 10316212688 10735509504 1393224336 14.535 100 45 89 1 1425826048 21760720 10840992
20:45:04 18825 8192000000 10321559520 10751082496 1403109968 14.623 100 30 60 1 1435922672 25369872 7442832
20:46:31 8991 8192000000 10357490752 10773655552 1419877040 14.772 100 67 133 1 1464851248 29416880 15557328
20:47:46 2777 8192000000 10357183040 10773516288 1420951360 14.781 100 77 153 1 1469236512 30617888 17667264
20:48:51 3264 8192000000 10374245392 10796113920 1429576864 14.858 100 75 150 1 1482076704 34665104 17834736
20:50:01 3350 8192000000 10382759760 10804097024 1434143680 14.898 100 80 160 0 1491384016 36925200 20315136
20:51:07 2346 8192000000 10386404032 10804957184 1435842096 14.913 100 92 181 4 1494532640 36376272 22314272
20:52:13 2916 8192000000 10372083312 10792824832 1412930448 14.710 99 69 137 2 1470077296 40177808 16969040
20:53:20 4483 8192000000 10367714464 10786541568 1412791792 14.709 99 66 132 3 1469869216 40695200 16382224
20:54:34 4836 8192000000 10357596480 10771865600 1391559552 14.520 99 69 138 2 1454391568 44559008 18273008
20:55:43 3785 8192000000 10353823968 10764767232 1377580560 14.395 99 69 137 1 1442103696 46742768 17780368
20:56:50 5961 8192000000 10332945136 10756562944 1380571696 14.422 100 49 98 1 1440020368 46410016 13038656
20:58:01 13730 8192000000 10300549392 10732851200 1361264848 14.249 100 39 78 2 1410114336 38208000 10641488
20:59:22 20903 8192000000 10291315808 10723852288 1352887456 14.173 100 36 72 4 1399790464 37190576 9712432
21:00:46 26253 8192000000 10250030624 10687373312 1329671024 13.964 100 18 36 0 1357178912 22658976 4848912
21:02:14 14860 8192000000 10269254480 10713305088 1348726608 14.136 99 17 35 0 1375660176 22461440 4472128
21:03:32 74534 8192000000 10261391888 10707173376 1322775616 13.902 97 25 50 2 1345294816 16733424 5785776
21:05:53 81439 8192000000 10279000128 10711842816 1347491648 14.125 99 23 46 2 1373466736 20573792 5401296
21:08:22 35240 8192000000 10301852416 10734161920 1378195248 14.400 100 41 80 1 1397376416 8767904 10413264
21:10:06 98491 8192000000 10266129296 10711175168 1326015936 13.931 96 14 28 0 1347053248 17926560 3110752
21:12:45 50071 8192000000 10286545584 10732941312 1367159248 14.302 99 26 54 1 1387025600 13924544 5941808
21:14:46 15939 8192000000 10281049744 10742824960 1372701872 14.351 100 19 38 2 1394932048 18087216 4142960
21:16:12 158387 8192000000 10275705344 10730057728 1326133536 13.932 96 13 26 0 1338469056 9079840 3255680
21:19:54 45366 8192000000 10280030608 10739773440 1374114384 14.364 100 7 14 0 1392377104 16495696 1767024
21:21:42 45306 8192000000 10288702800 10738585600 1382766288 14.441 100 26 53 0 1399400784 10762496 5872000
21:23:36 42384 8192000000 10299738784 10745851904 1381510880 14.430 99 31 62 0 1400612992 11892080 7210032
21:25:27 91325 8192000000 10285790592 10744266752 1364226224 14.275 99 19 37 1 1382118576 14017408 3874944
21:28:00 37936 8192000000 10313232432 10765443072 1395386848 14.554 100 17 34 0 1418291520 18868848 4035824
21:29:38 64697 8192000000 10308357088 10759606272 1384285504 14.455 99 21 42 0 1403358112 14471520 4601088
21:31:50 28378 8192000000 10323532864 10771820544 1391612800 14.520 99 36 72 1 1414357872 14934704 7810368
21:33:19 68886 8192000000 10307816032 10745991168 1327791104 13.947 95 19 38 2 1340987264 8741888 4454272
21:35:39 68857 8192000000 10298204912 10756579328 1375661248 14.378 99 28 56 2 1402146864 20138688 6346928
21:38:04 8671 8192000000 10297114912 10757033984 1382423152 14.438 100 9 16 1 1398446992 14478048 1545792
21:39:15 63459 8192000000 10296116000 10764226560 1398694448 14.583 100 14 28 1 1412456432 10855504 2906480
21:41:19 22984 8192000000 10299265392 10772336640 1398726912 14.584 100 5 10 1 1414165424 14668560 769952
21:42:42 33438 8192000000 10293723232 10740662272 1376193552 14.383 99 36 72 0 1393911472 9348400 8369520
21:44:23 86818 8192000000 10288622944 10753167360 1369963376 14.327 99 17 34 1 1387786960 13766256 4057328
21:46:50 89783 8192000000 10285675936 10733436928 1340923424 14.066 97 15 30 0 1365043232 20615104 3504704
21:49:37 82049 8192000000 10267364976 10705367040 1283674672 13.547 94 8 16 0 1297761376 12393520 1693184
21:52:02 92943 8192000000 10269630128 10712662016 1319593088 13.873 97 10 21 0 1342393072 20056400 2743584
21:54:43 144072 8192000000 10233066112 10675097600 1270276144 13.424 94 15 29 1 1287456000 13932896 3246960
21:58:09 81174 8192000000 10244622736 10702970880 1324883760 13.921 98 18 36 0 1341088992 11688512 4516720
22:00:36 26041 8192000000 10245515872 10698096640 1331989728 13.985 99 15 30 0 1346982144 11314352 3678064
22:02:05 46978 8192000000 10246791888 10689896448 1326952512 13.940 98 10 20 2 1336248176 7176464 2119200
22:05:54 40936 8192000000 10262089264 10704830464 1348084752 14.130 100 21 42 0 1362292160 9588848 4618560
22:07:39 58514 8192000000 10281277504 10727968768 1335402320 14.016 97 22 44 1 1350185696 10410240 4373136
22:09:42 126810 8192000000 10288846480 10728583168 1322971424 13.904 96 16 32 1 1338187904 11841760 3374720
22:12:51 62302 8192000000 10296785360 10768838656 1389476656 14.501 100 15 30 0 1410831408 17844368 3510384
22:14:57 49257 8192000000 10299846096 10746515456 1388887152 14.496 100 17 35 0 1401357760 8661584 3809024
22:16:51 21301 8192000000 10309414832 10753826816 1373320864 14.357 98 36 72 0 1394619264 13645216 7653184
22:18:15 92446 8192000000 10297905536 10757869568 1379552704 14.413 99 20 40 0 1400114624 16082384 4479536
22:21:03 59719 8192000000 10273855072 10744811520 1372528448 14.350 99 8 16 1 1384707504 10020224 2158832
22:23:23 40399 8192000000 10290251808 10739421184 1363934480 14.273 99 28 55 1 1383097616 13238192 5924944
22:25:04 58234 8192000000 10284054752 10743676928 1381977216 14.434 100 17 33 1 1392714464 6846448 3890800
22:28:22 46829 8192000000 10280597472 10727223296 1345259440 14.105 98 17 34 0 1359100304 10040944 3799920
22:30:10 58340 8192000000 10263830768 10714517504 1320843168 13.884 97 17 34 1 1341061760 16186016 4032576
22:32:09 53307 8192000000 10257473600 10721329152 1316027392 13.841 97 13 26 0 1337138912 17682208 3429312
22:34:03 29567 8192000000 10273848736 10710773760 1345007024 14.103 99 35 70 0 1366732032 13737808 7987200
22:35:32 59023 8192000000 10274738976 10703929344 1334449904 14.007 99 18 36 1 1350525216 11838752 4236560
22:37:34 28726 8192000000 9400821664 9700646912 544257472 6.229 40 9 17 1 555723904 9523712 1942720

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-11-2021 22:38:53 to 16-11-2021 22:39:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 v_add_invoice_to_stock 335544517
except 1 v_cancel_supplier_invoice 335544517

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

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          238480
MON$OLDEST_ACTIVE               238481
MON$OLDEST_SNAPSHOT             238481
MON$NEXT_TRANSACTION            238481
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                       2017600
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.33533 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33533 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33533 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 ---
:452: 
:453: mscs-test-161.budzdorov.ru    Tue Nov 16 22:38:07 2021
:454:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:455: 
:456: 
:457: mscs-test-161.budzdorov.ru    Tue Nov 16 22:38:11 2021
:458:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:459: 
--- end of diff output ---

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

16.11.21 22:39:12. 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-11-16 22:39:14 No severe PSQL-related problems occured
16.11.21 22:39:16. End of report.