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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15880664 kB
MemAvailable 30609580 kB
Buffers 0 kB
Cached 15791144 kB
SwapCached 3716 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-19 16:37:54
DTS_END                         2021-10-19 16:37:54.8650
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33248
TRN_ID                          240341
ATT_ID                          693
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: 9407.33 19932 1128880
customer order: creation 176.01 334 21121
customer order: refuse 15.08 343 1810
order to supplier: creation 47.36 1270 5683
order to supplier: removal 3.37 93902 404
invoice (draft): creation 46.83 3126 5620
invoice (draft): removal 53.28 729 6393
invoice accept: apply 42.67 18148 5120
invoice accept: cancel 48.01 835 5761
customer reserve: creation 3962.93 180 475552
customer reserve: removal 4704.32 53 564518
realization accept: apply 86.17 156 10340
realization accept: cancel 90.81 104 10897
payment from customer: creation 59.91 89 7189
payment from customer: removal 5.94 44 713
payment to supplier: creation 49.22 127 5906
payment to supplier: removal 3.48 65 417
service: total inventory turnovers 4.72 1115 566
service: total monetary turnovers 6.40 33 768
service: aggregate perf. data 0.82 19341 98
service: refresh index statistics 0.03 258642 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 19-10-2021 16:39:13 to 19-10-2021 16:39:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4729
TEST_TIME 2 4025
TEST_TIME 3 4252
TEST_TIME 4 4546
TEST_TIME 5 4614
TEST_TIME 6 4974
TEST_TIME 7 5007
TEST_TIME 8 4934
TEST_TIME 9 4968
TEST_TIME 10 5252
TEST_TIME 11 5437
TEST_TIME 12 5873
TEST_TIME 13 6339
TEST_TIME 14 6630
TEST_TIME 15 7200
TEST_TIME 16 7478
TEST_TIME 17 8125
TEST_TIME 18 8292
TEST_TIME 19 8365
TEST_TIME 20 9047
TEST_TIME 21 9144
TEST_TIME 22 9189
TEST_TIME 23 9532
TEST_TIME 24 10054
TEST_TIME 25 10947
TEST_TIME 26 10796
TEST_TIME 27 10613
TEST_TIME 28 10611
TEST_TIME 29 10360
TEST_TIME 30 10344
TEST_TIME 31 10230
TEST_TIME 32 10088
TEST_TIME 33 9971
TEST_TIME 34 9945
TEST_TIME 35 9968
TEST_TIME 36 10087
TEST_TIME 37 9968
TEST_TIME 38 10024
TEST_TIME 39 10021
TEST_TIME 40 9903
TEST_TIME 41 9915
TEST_TIME 42 9928
TEST_TIME 43 9880
TEST_TIME 44 9810
TEST_TIME 45 9929
TEST_TIME 46 9913
TEST_TIME 47 9992
TEST_TIME 48 9994
TEST_TIME 49 10021
TEST_TIME 50 9953
TEST_TIME 51 9991
TEST_TIME 52 10087
TEST_TIME 53 10014
TEST_TIME 54 10129
TEST_TIME 55 10078
TEST_TIME 56 9928
TEST_TIME 57 9885
TEST_TIME 58 9794
TEST_TIME 59 9733
TEST_TIME 60 9707
TEST_TIME 61 9782
TEST_TIME 62 9656
TEST_TIME 63 9613
TEST_TIME 64 9583
TEST_TIME 65 9693
TEST_TIME 66 9647
TEST_TIME 67 9643
TEST_TIME 68 9715
TEST_TIME 69 9720
TEST_TIME 70 9681
TEST_TIME 71 9724
TEST_TIME 72 9749
TEST_TIME 73 9751
TEST_TIME 74 9837
TEST_TIME 75 9900
TEST_TIME 76 9812
TEST_TIME 77 9872
TEST_TIME 78 9870
TEST_TIME 79 9773
TEST_TIME 80 9736
TEST_TIME 81 9666
TEST_TIME 82 9654
TEST_TIME 83 9666
TEST_TIME 84 9602
TEST_TIME 85 9559
TEST_TIME 86 9552
TEST_TIME 87 9483
TEST_TIME 88 9511
TEST_TIME 89 9450
TEST_TIME 90 9522
TEST_TIME 91 9487
TEST_TIME 92 9569
TEST_TIME 93 9556
TEST_TIME 94 9576
TEST_TIME 95 9593
TEST_TIME 96 9574
TEST_TIME 97 9610
TEST_TIME 98 9631
TEST_TIME 99 9632
TEST_TIME 100 9655
TEST_TIME 101 9767
TEST_TIME 102 9810
TEST_TIME 103 9739
TEST_TIME 104 9689
TEST_TIME 105 9648
TEST_TIME 106 9635
TEST_TIME 107 9636
TEST_TIME 108 9654
TEST_TIME 109 9620
TEST_TIME 110 9615
TEST_TIME 111 9572
TEST_TIME 112 9568
TEST_TIME 113 9516
TEST_TIME 114 9493
TEST_TIME 115 9473
TEST_TIME 116 9443
TEST_TIME 117 9436
TEST_TIME 118 9438
TEST_TIME 119 9435
TEST_TIME 120 9417

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-10-2021 16:39:17 to 19-10-2021 16:39:21.

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 1568182 1568182 0 0.00 0 18183 6 0 0
doc_list_biud 1568182 1568182 0 0.00 0 27251 25 0 0
sp_add_invoice_to_stock 5239 5120 119 2.27 25 195370 18149 0 119
sp_cancel_adding_invoice 5761 5761 0 0.00 5 55689 835 0 0
sp_cancel_client_order 1810 1810 0 0.00 5 11370 343 0 0
sp_cancel_customer_reserve 564518 564518 0 0.00 1 27354 53 0 0
sp_cancel_pay_from_customer 713 713 0 0.00 2 1016 44 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 3 5619 65 0 0
sp_cancel_supplier_invoice 6401 6393 8 0.12 2 10950 729 0 8
sp_cancel_supplier_order 404 404 0 0.00 7 1235238 93903 0 0
sp_cancel_write_off 10897 10897 0 0.00 1 17876 104 0 0
sp_client_order 21121 21121 0 0.00 3 19106 334 0 0
sp_customer_reserve 475552 475552 0 0.00 0 120485 180 0 0
sp_fill_shopping_cart 32419 32419 0 0.00 1 14916 37 0 0
sp_fill_shopping_cart_clo_res 475523 475523 0 0.00 0 17795 13 0 0
sp_get_clo_for_invoice 5091 5091 0 0.00 0 12206 261 0 0
sp_kill_cost_storno 17788 17788 0 0.00 0 8535 50 0 0
sp_kill_qstorno_ret_qs2qd 1146730 1146730 0 0.00 0 27244 29 0 0
sp_kill_qty_storno 605214 605214 0 0.00 0 27251 63 0 0
sp_lock_selected_doc 21218 21218 0 0.00 0 8030 5 0 0
sp_make_cost_storno 28526 28526 0 0.00 0 18180 46 0 0
sp_multiply_rows_for_pdistr 28526 28526 0 0.00 0 14466 26 0 0
sp_multiply_rows_for_qdistr 21121 21121 0 0.00 0 14341 149 0 0
sp_pay_from_customer 7189 7189 0 0.00 4 10584 90 0 0
sp_pay_to_supplier 5906 5906 0 0.00 5 24392 127 0 0
sp_payment_common 13095 13095 0 0.00 1 24367 28 0 0
sp_qd_handle_on_cancel_clo 1810 1810 0 0.00 0 8013 306 0 0
sp_qd_handle_on_invoice_upd_sts 10852 10852 0 0.00 0 17072 325 0 0
sp_qd_handle_on_reserve_upd_sts 10340 10340 0 0.00 0 16011 59 0 0
sp_reserve_write_off 10340 10340 0 0.00 6 18193 157 0 0
sp_supplier_invoice 5620 5620 0 0.00 7 82918 3127 0 0
sp_supplier_order 5683 5683 0 0.00 5 23844 1271 0 0
srv_aggregate_perf_data 99 98 1 1.01 2824 137075 19341 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1965 156309 47068 0 0
srv_find_qd_qs_mism 1079589 1079589 0 0.00 0 17056 6 0 0
srv_make_invnt_saldo 566 566 0 0.00 66 82104 1116 0 0
srv_make_money_saldo 768 768 0 0.00 1 2640 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 81918 416289 258643 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5120 5120 0 0.00 0 14676 183 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 1 3509 93 0 0
t$perf-norm:sp_cancel_client_order 1810 1810 0 0.00 0 9073 20 0 0
t$perf-norm:sp_cancel_customer_reserve 648 648 0 0.00 0 3645 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 713 713 0 0.00 0 1433 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 4258 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 3615 25 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 18196 1306 0 0
t$perf-norm:sp_cancel_write_off 962 962 0 0.00 0 852 3 0 0
t$perf-norm:sp_client_order 21121 21121 0 0.00 0 6816 15 0 0
t$perf-norm:sp_customer_reserve 1056 1056 0 0.00 0 11102 18 0 0
t$perf-norm:sp_pay_from_customer 7189 7189 0 0.00 0 4853 8 0 0
t$perf-norm:sp_pay_to_supplier 5906 5906 0 0.00 0 8889 15 0 0
t$perf-norm:sp_reserve_write_off 10340 10340 0 0.00 0 4684 8 0 0
t$perf-norm:sp_supplier_invoice 5620 5620 0 0.00 0 10111 20 0 0
t$perf-norm:sp_supplier_order 5683 5683 0 0.00 0 5744 17 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 887 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 810 16 0 0
t$perf-norm:srv_make_invnt_saldo 566 566 0 0.00 0 1029 14 0 0
t$perf-norm:srv_make_money_saldo 768 768 0 0.00 0 963 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 860 217 0 0
v_add_invoice_to_stock 10418 10180 238 2.28 0 6127 17 0 238
v_all_customers 38316 38316 0 0.00 0 22 1 0 0
v_all_suppliers 31150 31150 0 0.00 0 18 1 0 0
v_all_wares 42242 42242 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 3355 14 0 0
v_cancel_client_order 3620 3620 0 0.00 0 1566 5 0 0
v_cancel_customer_prepayment 1426 1426 0 0.00 0 845 3 0 0
v_cancel_customer_reserve 1296 1296 0 0.00 0 1146 4 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 4234 8 0 0
v_cancel_supplier_invoice 826 810 16 1.93 0 1359 18 0 16
v_cancel_supplier_order 808 808 0 0.00 0 823 4 0 0
v_cancel_write_off 1924 1924 0 0.00 0 3075 15 0 0
v_max_id_clo_ord 5679 5679 0 0.00 0 430 1 0 0
v_max_id_clo_res 1056 1056 0 0.00 0 2254 12 0 0
v_max_id_ord_sup 5619 5619 0 0.00 0 3800 4 0 0
v_max_non_paid_invoice 1629 1629 0 0.00 0 24363 19 0 0
v_max_non_paid_realizn 3865 3865 0 0.00 0 1642 5 0 0
v_min_id_clo_ord 5679 5679 0 0.00 0 5264 8 0 0
v_min_id_clo_res 1056 1056 0 0.00 0 2691 8 0 0
v_min_id_ord_sup 5619 5619 0 0.00 0 9448 13 0 0
v_min_non_paid_invoice 5906 5906 0 0.00 0 16434 23 0 0
v_min_non_paid_realizn 7189 7189 0 0.00 0 10566 17 0 0
v_our_firm 10574 10574 0 0.00 0 14 1 0 0
v_reserve_write_off 20680 20680 0 0.00 0 2729 5 0 0
x_lock_depdocs_on_canc_invoice 11749 11749 0 0.00 0 29897 112 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 5839 303 0 0
x_make_qstorno_1000_1200 5683 5683 0 0.00 1 23837 1192 0 0
x_make_qstorno_1200_2000 5620 5620 0 0.00 2 82737 2993 0 0
x_make_qstorno_2100_3300 475552 475552 0 0.00 0 120485 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-10-2021 16:39:22 to 19-10-2021 16:39:26.

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:07:58 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:08:59 17424 8192000000 9889758448 10201059328 973980112 10.626 100 86 172 1 1003062640 6174192 22908336
14:10:23 11129 8192000000 9945829008 10270105600 1031261920 11.181 100 88 176 0 1062818160 8430896 23125344
14:11:40 18450 8192000000 9977317056 10312392704 1060306784 11.459 100 86 171 1 1091350336 9855712 21187840
14:13:02 16404 8192000000 10030464784 10377818112 1112425760 11.955 100 82 164 0 1143559920 11350288 19783872
14:14:22 11228 8192000000 10073633456 10427318272 1152892224 12.337 100 90 180 0 1187622464 14157568 20572672
14:15:38 7700 8192000000 10094923856 10461986816 1190347440 12.687 100 72 144 0 1218531376 11657040 16526896
14:16:48 5545 8192000000 10136045840 10514706432 1231151888 13.065 100 64 125 2 1256599392 11999904 13447600
14:17:56 2900 8192000000 10185589984 10564452352 1265743968 13.383 100 91 182 1 1299571104 14376128 19451008
14:19:01 8944 8192000000 10199477312 10589372416 1290644560 13.610 100 77 153 1 1318978736 11329344 17004832
14:20:12 20901 8192000000 10217625856 10609790976 1304989168 13.741 100 72 143 2 1328564304 6709712 16865424
14:21:33 3667 8192000000 10201610320 10612363264 1311190896 13.797 100 36 72 1 1327230656 7451008 8588752
14:22:40 47739 8192000000 10207909744 10625294336 1317459200 13.854 100 13 26 0 1326191744 5270576 3461968
14:24:28 3191 8192000000 10225829808 10638163968 1325659568 13.928 100 45 90 2 1343298256 7471984 10166704
14:25:33 8239 8192000000 10233239552 10649071616 1338441968 14.043 100 34 68 0 1353391664 6928640 8021056
14:26:44 48264 8192000000 10229978960 10652225536 1338378624 14.043 100 30 58 1 1350459648 4936192 7144832
14:28:34 5479 8192000000 10210128880 10625646592 1319159808 13.869 100 34 68 1 1334425584 6868160 8397616
14:29:40 4602 8192000000 10185574000 10587774976 1266453312 13.389 98 31 61 2 1279456832 6096560 6906960
14:30:46 16694 8192000000 10135992672 10525917184 1174050032 12.535 92 26 53 1 1185373376 5106768 6216576
14:32:04 13650 8192000000 10138781984 10544066560 1201093008 12.786 95 16 31 2 1209717664 4675152 3949504
14:33:18 5484 8192000000 10151949856 10555469824 1256665712 13.299 100 40 78 1 1277498800 10664768 10168320
14:34:26 6939 8192000000 10163673648 10574946304 1266431392 13.389 99 21 42 1 1277001840 5601744 4968704
14:35:33 11723 8192000000 10190882912 10602913792 1296191616 13.661 100 17 34 0 1305392320 5345184 3855520
14:36:48 12309 8192000000 10194736464 10602680320 1296250032 13.661 100 40 81 1 1311163104 5776336 9136736
14:38:04 18941 8192000000 10231133168 10641629184 1329275376 13.961 100 51 102 2 1349172784 8230784 11666624
14:39:29 20355 8192000000 10261023568 10672304128 1348466096 14.134 100 54 107 0 1372447056 11981072 11999888
14:40:55 35810 8192000000 10279168912 10701066240 1365600832 14.288 100 47 94 1 1393615360 17031008 10983520
14:42:38 4215 8192000000 10299463504 10720370688 1383729392 14.450 100 45 90 1 1417262816 23146640 10386784
14:43:54 6289 8192000000 10320762768 10740555776 1395778704 14.557 100 55 110 0 1432751600 24150160 12822736
14:45:04 7148 8192000000 10334874384 10751758336 1402375424 14.616 100 61 122 0 1443960032 27239136 14345472
14:46:15 4785 8192000000 10343111712 10766639104 1414039104 14.720 100 58 113 0 1455496432 27788624 13668704
14:47:24 3448 8192000000 10361085152 10781655040 1418945632 14.763 100 69 138 1 1467644128 32286192 16412304
14:48:32 15815 8192000000 10334397184 10754740224 1390901680 14.514 99 59 118 2 1443167552 36201104 16064768
14:49:53 5546 8192000000 10364062848 10785615872 1422657216 14.796 100 70 140 1 1477431648 36787184 17987248
14:51:03 2846 8192000000 10385660944 10802999296 1425390512 14.820 100 83 167 1 1490047760 44801648 19855600
14:52:10 2937 8192000000 10374492864 10790637568 1406051360 14.649 99 79 157 1 1473035872 46349232 20635280
14:53:18 3380 8192000000 10360217184 10780758016 1401554176 14.609 100 69 138 1 1467481232 48471632 17455424
14:54:27 1964 8192000000 10358930240 10770591744 1388488944 14.492 100 82 163 1 1461705456 53333328 19883184
14:55:34 35858 8192000000 10338322656 10776109056 1367034928 14.300 98 33 66 5 1420088176 44553792 8499456
14:57:15 14437 8192000000 10316174144 10751225856 1364015040 14.273 98 33 66 0 1413972640 41315488 8642112
14:58:32 52797 8192000000 10322545088 10762989568 1349036400 14.139 97 21 42 0 1397906144 43427568 5442176
15:00:27 20722 8192000000 10286420512 10732060672 1298393600 13.681 95 21 42 0 1347927584 43291696 6242288
15:01:50 34168 8192000000 10253956752 10703798272 1297766816 13.675 96 15 30 1 1335030752 32837712 4426224
15:03:25 7220 8192000000 10268122288 10687541248 1345899120 14.111 100 56 107 2 1364315760 5837760 12578880
15:04:37 64249 8192000000 10259724544 10699747328 1325555968 13.927 98 29 58 1 1345124624 12398352 7170304
15:06:55 13239 8192000000 10268882704 10700382208 1349456752 14.143 100 40 80 0 1367002816 7816224 9729840
15:08:15 138991 8192000000 10256259024 10696790016 1325044304 13.922 97 24 48 0 1342467664 12362816 5060544
15:11:38 29003 8192000000 10285933408 10719277056 1328028160 13.949 97 24 48 1 1350315568 16325440 5961968
15:13:10 10730 8192000000 10297657040 10732118016 1376466496 14.385 100 45 91 3 1403836432 16887680 10482256
15:14:24 52338 8192000000 10281151328 10739466240 1377770368 14.397 100 18 36 3 1402217888 19793088 4654432
15:16:22 156309 8192000000 10276495808 10736922624 1356664400 14.207 98 12 24 0 1370673856 11065824 2943632
15:19:58 93430 8192000000 10319314848 10768015360 1366162976 14.293 98 20 40 0 1386682944 16107312 4412656
15:22:33 33146 8192000000 10311306624 10747109376 1381375056 14.429 100 42 85 0 1401572704 9863408 10334240
15:24:07 63196 8192000000 10300768960 10746732544 1373176240 14.355 99 11 21 1 1387655120 11973488 2505392
15:26:11 69445 8192000000 10304769456 10760273920 1384058592 14.453 99 10 20 3 1402213696 15875424 2279680
15:28:26 48357 8192000000 10270107472 10724835328 1341823136 14.074 97 26 52 0 1360573088 12656976 6092976
15:30:15 31294 8192000000 10300977728 10731339776 1339642688 14.054 97 43 84 2 1356108928 6501872 9964368
15:32:09 86390 8192000000 10275636272 10723160064 1329800144 13.965 96 16 32 0 1344895712 11282000 3813568
15:35:02 51780 8192000000 10272484784 10718265344 1359272864 14.231 99 28 56 0 1373642240 8586608 5782768
15:36:58 39526 8192000000 10290319424 10732380160 1373828448 14.361 99 44 89 0 1394152368 10201552 10122368
15:38:38 41660 8192000000 10248889856 10715652096 1341502784 14.071 98 6 12 0 1351027904 8330992 1194128
15:40:22 52195 8192000000 10255734064 10713944064 1348173872 14.131 99 8 16 0 1359807216 10305040 1328304
15:42:14 71640 8192000000 10261587472 10703347712 1296115504 13.660 94 12 23 0 1305842752 7431840 2295408
15:44:31 33283 8192000000 10281034736 10721423360 1349031392 14.139 98 21 42 0 1368635264 14776624 4827248
15:46:12 54362 8192000000 10256654928 10708029440 1316789296 13.848 97 15 30 2 1332859152 12650720 3419136
15:48:07 46882 8192000000 10252338880 10689298432 1285260256 13.561 95 14 28 0 1305040944 16442464 3338224
15:49:54 75139 8192000000 10270481920 10722848768 1327905216 13.948 98 15 29 0 1350521488 19311984 3304288
15:52:12 78123 8192000000 10281140112 10716200960 1341699712 14.073 98 10 20 0 1357821968 13842320 2279936
15:54:31 46647 8192000000 10284324752 10730590208 1350764400 14.154 99 33 66 1 1373417200 15018944 7633856
15:56:20 96985 8192000000 10261393312 10704744448 1326627520 13.937 97 23 46 1 1339333904 7162240 5544144
15:58:57 32129 8192000000 10263463120 10703622144 1330977424 13.976 97 23 46 0 1344993088 8870560 5145104
16:00:35 44220 8192000000 10261397360 10721681408 1369355744 14.321 100 5 10 0 1378043232 7718656 968832
16:02:52 37264 8192000000 10288377440 10739412992 1383028768 14.444 100 15 30 0 1397895072 11239520 3626784
16:04:30 61508 8192000000 10280525536 10737217536 1360174400 14.239 99 19 37 1 1376119488 11787408 4157680
16:06:39 82095 8192000000 10294876256 10749800448 1370295664 14.330 99 17 34 0 1391739952 17583520 3860768
16:09:01 51690 8192000000 10293889232 10749607936 1378027984 14.399 99 16 32 1 1394637248 12840592 3768672
16:10:58 9080 8192000000 10303311648 10739744768 1385571712 14.466 100 53 106 1 1408179312 9784208 12823392
16:12:11 18642 8192000000 10311305136 10750029824 1391045824 14.515 100 28 56 1 1407142304 9779168 6317312
16:13:31 34760 8192000000 10305752096 10746847232 1388499072 14.492 100 29 58 2 1408127584 13316608 6311904
16:15:07 147870 8192000000 10318818480 10776190976 1365445168 14.286 98 11 22 0 1384172080 16515776 2211136
16:18:37 122910 8192000000 10323441488 10773848064 1381758912 14.432 99 4 8 0 1408514544 26200192 555440
16:22:40 99368 8192000000 10315154688 10768441344 1382934048 14.443 99 21 42 1 1404144640 16737792 4472800
16:25:20 68204 8192000000 10277154944 10735022080 1347402544 14.124 97 25 48 0 1363493312 10713760 5377008
16:27:42 65941 8192000000 10263128448 10714460160 1316628368 13.846 95 7 14 0 1325244528 7406112 1210048
16:29:49 43711 8192000000 10269168416 10728398848 1374832464 14.370 100 6 12 0 1385781152 9557792 1390896
16:31:41 85351 8192000000 10268955088 10713833472 1294970896 13.649 93 18 36 0 1306484592 7828304 3685392
16:34:19 65541 8192000000 10272910384 10736074752 1361704032 14.253 99 15 30 1 1380731424 15523408 3503984
16:36:24 64940 8192000000 10276789872 10714427392 1314512656 13.827 95 23 46 1 1328408496 9087952 4807888

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-10-2021 16:39:27 to 19-10-2021 16:39:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 119 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-10-2021 16:39:43 to 19-10-2021 16:39:45.

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          238814
MON$OLDEST_ACTIVE               241182
MON$OLDEST_SNAPSHOT             241182
MON$NEXT_TRANSACTION            241182
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                       2026720
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.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 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 ---
:618: 
:619: mscs-test-161.budzdorov.ru    Tue Oct 19 16:38:00 2021
:620:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:621: 
:622: 
:623: mscs-test-161.budzdorov.ru    Tue Oct 19 16:38:02 2021
:624:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:625: 
--- end of diff output ---

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

19.10.21 16:39:46. 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-19 16:39:49 No severe PSQL-related problems occured
19.10.21 16:39:50. End of report.