Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.10.2021 01: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 16021320 kB
MemAvailable 30643168 kB
Buffers 0 kB
Cached 15672716 kB
SwapCached 3764 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-17 01:37:53
DTS_END                         2021-10-17 01:37:53.7180
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/48610
TRN_ID                          245755
ATT_ID                          659
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: 8989.75 23085 1078770
customer order: creation 176.18 350 21141
customer order: refuse 15.84 402 1901
order to supplier: creation 46.11 1270 5533
order to supplier: removal 3.33 88330 400
invoice (draft): creation 46.56 3430 5587
invoice (draft): removal 48.91 723 5869
invoice accept: apply 43.16 18052 5179
invoice accept: cancel 43.79 762 5255
customer reserve: creation 3943.67 181 473240
customer reserve: removal 4322.49 53 518699
realization accept: apply 85.61 175 10273
realization accept: cancel 80.03 108 9604
payment from customer: creation 60.63 116 7276
payment from customer: removal 6.13 79 735
payment to supplier: creation 51.60 136 6192
payment to supplier: removal 3.52 87 422
service: total inventory turnovers 4.77 1152 572
service: total monetary turnovers 6.49 36 779
service: aggregate perf. data 0.91 17927 109
service: refresh index statistics 0.03 328325 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-10-2021 01:39:11 to 17-10-2021 01:39:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2896
TEST_TIME 2 4205
TEST_TIME 3 3480
TEST_TIME 4 4548
TEST_TIME 5 4793
TEST_TIME 6 4865
TEST_TIME 7 5218
TEST_TIME 8 4970
TEST_TIME 9 4819
TEST_TIME 10 5254
TEST_TIME 11 5344
TEST_TIME 12 6093
TEST_TIME 13 6224
TEST_TIME 14 6612
TEST_TIME 15 6945
TEST_TIME 16 7062
TEST_TIME 17 7063
TEST_TIME 18 7501
TEST_TIME 19 8176
TEST_TIME 20 8174
TEST_TIME 21 8380
TEST_TIME 22 8349
TEST_TIME 23 8621
TEST_TIME 24 8848
TEST_TIME 25 9579
TEST_TIME 26 9402
TEST_TIME 27 10225
TEST_TIME 28 10104
TEST_TIME 29 10098
TEST_TIME 30 10025
TEST_TIME 31 9909
TEST_TIME 32 9833
TEST_TIME 33 9815
TEST_TIME 34 9577
TEST_TIME 35 9481
TEST_TIME 36 9517
TEST_TIME 37 9693
TEST_TIME 38 9518
TEST_TIME 39 9501
TEST_TIME 40 9390
TEST_TIME 41 9374
TEST_TIME 42 9348
TEST_TIME 43 9339
TEST_TIME 44 9325
TEST_TIME 45 9256
TEST_TIME 46 9143
TEST_TIME 47 9136
TEST_TIME 48 9115
TEST_TIME 49 9008
TEST_TIME 50 8949
TEST_TIME 51 8867
TEST_TIME 52 8805
TEST_TIME 53 8755
TEST_TIME 54 8846
TEST_TIME 55 8812
TEST_TIME 56 8839
TEST_TIME 57 8840
TEST_TIME 58 8871
TEST_TIME 59 8835
TEST_TIME 60 9042
TEST_TIME 61 9052
TEST_TIME 62 9102
TEST_TIME 63 9105
TEST_TIME 64 9292
TEST_TIME 65 9205
TEST_TIME 66 9230
TEST_TIME 67 9178
TEST_TIME 68 9102
TEST_TIME 69 9106
TEST_TIME 70 9050
TEST_TIME 71 9005
TEST_TIME 72 8962
TEST_TIME 73 8939
TEST_TIME 74 8898
TEST_TIME 75 8873
TEST_TIME 76 8989
TEST_TIME 77 8945
TEST_TIME 78 8947
TEST_TIME 79 8995
TEST_TIME 80 9042
TEST_TIME 81 9173
TEST_TIME 82 9136
TEST_TIME 83 9141
TEST_TIME 84 9148
TEST_TIME 85 9132
TEST_TIME 86 9193
TEST_TIME 87 9236
TEST_TIME 88 9206
TEST_TIME 89 9187
TEST_TIME 90 9125
TEST_TIME 91 9080
TEST_TIME 92 9106
TEST_TIME 93 9055
TEST_TIME 94 8993
TEST_TIME 95 9040
TEST_TIME 96 9038
TEST_TIME 97 9085
TEST_TIME 98 9082
TEST_TIME 99 9075
TEST_TIME 100 9089
TEST_TIME 101 9087
TEST_TIME 102 9141
TEST_TIME 103 9143
TEST_TIME 104 9170
TEST_TIME 105 9176
TEST_TIME 106 9189
TEST_TIME 107 9209
TEST_TIME 108 9242
TEST_TIME 109 9257
TEST_TIME 110 9191
TEST_TIME 111 9155
TEST_TIME 112 9109
TEST_TIME 113 9099
TEST_TIME 114 9049
TEST_TIME 115 9043
TEST_TIME 116 9037
TEST_TIME 117 9022
TEST_TIME 118 9029
TEST_TIME 119 9017
TEST_TIME 120 8993

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-10-2021 01:39:15 to 17-10-2021 01:39:18.

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 1516700 1516700 0 0.00 0 21136 6 0 0
doc_list_biud 1516699 1516699 0 0.00 0 29822 24 0 0
sp_add_invoice_to_stock 5342 5179 163 3.05 34 194029 18053 0 163
sp_cancel_adding_invoice 5255 5255 0 0.00 5 53151 763 0 0
sp_cancel_client_order 1901 1901 0 0.00 4 15286 402 0 0
sp_cancel_customer_reserve 518699 518699 0 0.00 1 24946 54 0 0
sp_cancel_pay_from_customer 735 735 0 0.00 3 3672 80 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 3 9564 87 0 0
sp_cancel_supplier_invoice 5878 5869 9 0.15 2 29835 724 0 9
sp_cancel_supplier_order 400 400 0 0.00 7 987745 88330 0 0
sp_cancel_write_off 9604 9604 0 0.00 1 17691 108 0 0
sp_client_order 21141 21141 0 0.00 3 18209 350 0 0
sp_customer_reserve 473240 473240 0 0.00 0 90220 181 0 0
sp_fill_shopping_cart 32253 32253 0 0.00 1 14992 40 0 0
sp_fill_shopping_cart_clo_res 473240 473240 0 0.00 0 17775 14 0 0
sp_get_clo_for_invoice 5171 5171 0 0.00 0 26328 290 0 0
sp_kill_cost_storno 16016 16016 0 0.00 0 6986 50 0 0
sp_kill_qstorno_ret_qs2qd 1053271 1053271 0 0.00 0 29816 29 0 0
sp_kill_qty_storno 557170 557170 0 0.00 0 29820 64 0 0
sp_lock_selected_doc 21501 21501 0 0.00 0 9558 6 0 0
sp_make_cost_storno 28911 28911 0 0.00 0 21107 54 0 0
sp_multiply_rows_for_pdistr 28911 28911 0 0.00 0 10165 28 0 0
sp_multiply_rows_for_qdistr 21141 21141 0 0.00 0 16730 150 0 0
sp_pay_from_customer 7276 7276 0 0.00 4 14695 117 0 0
sp_pay_to_supplier 6192 6192 0 0.00 5 21144 136 0 0
sp_payment_common 13468 13468 0 0.00 1 12656 25 0 0
sp_qd_handle_on_cancel_clo 1901 1901 0 0.00 0 15282 360 0 0
sp_qd_handle_on_invoice_upd_sts 10424 10424 0 0.00 0 24580 307 0 0
sp_qd_handle_on_reserve_upd_sts 10273 10273 0 0.00 0 18525 60 0 0
sp_reserve_write_off 10273 10273 0 0.00 6 18583 175 0 0
sp_supplier_invoice 5587 5587 0 0.00 4 83125 3430 0 0
sp_supplier_order 5533 5533 0 0.00 6 27813 1270 0 0
srv_aggregate_perf_data 109 109 0 0.00 2713 138452 17927 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 3377 191262 47912 0 0
srv_find_qd_qs_mism 1029625 1029625 0 0.00 0 16334 6 0 0
srv_make_invnt_saldo 572 572 0 0.00 27 35977 1153 0 0
srv_make_money_saldo 779 779 0 0.00 1 9113 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 54178 484763 328326 0 0
t$perf-abend:sp_add_invoice_to_stock 163 163 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5179 5179 0 0.00 0 9997 165 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 6454 91 0 0
t$perf-norm:sp_cancel_client_order 1901 1901 0 0.00 0 4763 16 0 0
t$perf-norm:sp_cancel_customer_reserve 744 744 0 0.00 0 892 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 735 735 0 0.00 0 2711 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 1457 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 3676 39 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 25429 1682 0 0
t$perf-norm:sp_cancel_write_off 1039 1039 0 0.00 0 7918 23 0 0
t$perf-norm:sp_client_order 21141 21141 0 0.00 0 20797 18 0 0
t$perf-norm:sp_customer_reserve 1018 1018 0 0.00 0 4655 10 0 0
t$perf-norm:sp_pay_from_customer 7276 7276 0 0.00 0 14806 15 0 0
t$perf-norm:sp_pay_to_supplier 6192 6192 0 0.00 0 9114 14 0 0
t$perf-norm:sp_reserve_write_off 10273 10273 0 0.00 0 15745 13 0 0
t$perf-norm:sp_supplier_invoice 5587 5587 0 0.00 0 6860 18 0 0
t$perf-norm:sp_supplier_order 5533 5533 0 0.00 0 12186 18 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 733 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 11 1 0 0
t$perf-norm:srv_make_invnt_saldo 572 572 0 0.00 0 2794 25 0 0
t$perf-norm:srv_make_money_saldo 779 779 0 0.00 0 1735 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10662 10336 326 3.05 0 4899 19 0 326
v_all_customers 38144 38144 0 0.00 0 94 1 0 0
v_all_suppliers 30964 30964 0 0.00 0 20 1 0 0
v_all_wares 42282 42282 0 0.00 0 70 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 618 6 0 0
v_cancel_client_order 3802 3802 0 0.00 0 3927 8 0 0
v_cancel_customer_prepayment 1470 1470 0 0.00 0 1516 7 0 0
v_cancel_customer_reserve 1488 1488 0 0.00 0 4555 12 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 3931 9 0 0
v_cancel_supplier_invoice 826 808 18 2.17 0 2012 16 0 18
v_cancel_supplier_order 800 800 0 0.00 0 390 4 0 0
v_cancel_write_off 2078 2078 0 0.00 0 4284 18 0 0
v_max_id_clo_ord 5529 5529 0 0.00 0 4660 2 0 0
v_max_id_clo_res 1018 1018 0 0.00 0 4297 13 0 0
v_max_id_ord_sup 5582 5582 0 0.00 0 6959 6 0 0
v_max_non_paid_invoice 1821 1821 0 0.00 0 673 3 0 0
v_max_non_paid_realizn 4108 4108 0 0.00 0 4614 5 0 0
v_min_id_clo_ord 5529 5529 0 0.00 0 11299 9 0 0
v_min_id_clo_res 1018 1018 0 0.00 0 3135 12 0 0
v_min_id_ord_sup 5582 5582 0 0.00 0 7664 7 0 0
v_min_non_paid_invoice 6192 6192 0 0.00 0 10565 15 0 0
v_min_non_paid_realizn 7276 7276 0 0.00 0 12650 20 0 0
v_our_firm 10474 10474 0 0.00 0 14 1 0 0
v_reserve_write_off 20544 20544 0 0.00 0 4652 6 0 0
x_lock_depdocs_on_canc_invoice 10720 10720 0 0.00 0 14884 118 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 11273 353 0 0
x_make_qstorno_1000_1200 5533 5533 0 0.00 2 27805 1182 0 0
x_make_qstorno_1200_2000 5587 5587 0 0.00 2 83097 3292 0 0
x_make_qstorno_2100_3300 473240 473240 0 0.00 0 90219 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-10-2021 01:39:19 to 17-10-2021 01:39:23.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:07:57 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:08:58 24187 8192000000 9896215680 10204147712 979441584 10.679 100 88 176 0 1009400656 6519104 23439968
23:10:29 27688 8192000000 9951062016 10271420416 1022872672 11.100 100 88 176 0 1054256960 8694752 22689536
23:12:07 18523 8192000000 9990774256 10325602304 1069941920 11.552 100 82 164 1 1100044160 9963184 20139056
23:13:29 16162 8192000000 10042956048 10394206208 1126711744 12.090 100 78 157 0 1156614624 11276736 18626144
23:14:50 7317 8192000000 10096049904 10457362432 1181361600 12.603 100 77 154 0 1211919360 12894672 17663088
23:16:06 3113 8192000000 10140979280 10512850944 1233879392 13.090 100 83 166 0 1265493680 13216512 18397776
23:17:10 2618 8192000000 10184730960 10563543040 1262861936 13.356 100 89 178 1 1297299792 13804048 20633808
23:18:14 2507 8192000000 10203318672 10585817088 1288805984 13.593 100 80 158 1 1319876688 12975696 18095008
23:19:18 3168 8192000000 10209890256 10601910272 1296671120 13.665 100 56 112 2 1319402528 10213696 12517712
23:20:22 16393 8192000000 10209002368 10611273728 1307836064 13.766 100 54 110 2 1327509888 6765584 12908240
23:21:41 8052 8192000000 10204240096 10619314176 1317068992 13.850 100 12 24 1 1324331408 4770256 2492160
23:22:49 5163 8192000000 10205069408 10624319488 1321132976 13.887 100 19 38 0 1333170912 7086064 4951872
23:23:57 19935 8192000000 10212717872 10632028160 1330149312 13.969 100 32 63 2 1343784384 6602448 7032624
23:25:23 18502 8192000000 10232444208 10648498176 1331412672 13.980 100 35 70 2 1350158864 11664320 7081872
23:26:44 8282 8192000000 10226144832 10650177536 1337961888 14.039 100 27 54 0 1350160816 6008768 6190160
23:27:52 9361 8192000000 10194424176 10610102272 1259402048 13.325 95 19 36 1 1272856256 9947792 3506416
23:29:04 19486 8192000000 10160474880 10570145792 1235518592 13.105 96 28 55 3 1247397296 5383824 6494880
23:31:02 4182 8192000000 10151525584 10552795136 1238503200 13.132 98 30 58 0 1250888832 5628448 6757184
23:32:07 6565 8192000000 10156148992 10557575168 1261613312 13.345 100 27 54 2 1273550320 5420224 6516784
23:33:14 7734 8192000000 10182529696 10590928896 1285144592 13.560 100 27 54 1 1297306336 5106896 7054848
23:34:23 62524 8192000000 10184678560 10603319296 1297291872 13.671 100 22 44 0 1308010096 5253728 5464496
23:36:29 4638 8192000000 10196127168 10610360320 1303913200 13.731 100 24 48 1 1316099888 6820000 5366688
23:37:37 9049 8192000000 10216846384 10637172736 1323801248 13.911 100 25 50 0 1339082672 9749760 5531664
23:38:52 4164 8192000000 10268244736 10669944832 1348086400 14.130 100 68 134 3 1371778400 8533984 15158016
23:39:58 102668 8192000000 10265119968 10691719168 1362054512 14.256 100 47 94 2 1383379104 10597872 10726720
23:42:50 34676 8192000000 10280773904 10711826432 1376554512 14.386 100 35 70 0 1396454576 11811344 8088720
23:44:27 18377 8192000000 10306587264 10732138496 1394428816 14.545 100 56 111 3 1423308544 15137440 13742288
23:45:56 19748 8192000000 10316994944 10741305344 1400008000 14.595 100 45 91 0 1427260128 15866720 11385408
23:47:30 10393 8192000000 10335146736 10758504448 1399752384 14.593 99 65 129 2 1438626784 22758240 16116160
23:48:48 10932 8192000000 10359109168 10785554432 1422238448 14.793 100 61 120 2 1461629984 25337872 14053664
23:50:05 10859 8192000000 10340763056 10758135808 1394891344 14.549 99 79 157 3 1438290208 25343856 18055008
23:51:22 5159 8192000000 10340688336 10756005888 1398738544 14.584 100 76 152 1 1446672512 28974304 18959664
23:52:33 3377 8192000000 10313933120 10723508224 1346441296 14.115 98 76 152 0 1397952608 32550416 18960896
23:53:45 6031 8192000000 10320435200 10733432832 1371102848 14.337 100 67 133 1 1422704896 35130512 16471536
23:54:58 8475 8192000000 10309522496 10718605312 1350656832 14.153 99 69 137 1 1411027568 42570432 17800304
23:56:10 14322 8192000000 10293897472 10718707712 1355694208 14.199 99 33 64 1 1399915744 35215856 9005680
23:57:30 14181 8192000000 10292064848 10719420416 1351049696 14.157 99 40 80 1 1392305888 31423120 9833072
23:58:49 20060 8192000000 10302939024 10730401792 1346078784 14.112 98 27 53 1 1388501200 35054336 7368080
00:00:17 11650 8192000000 10302190272 10732019712 1364717136 14.280 100 35 70 1 1410304416 36225904 9361376
00:01:42 31237 8192000000 10295678384 10732154880 1354166080 14.185 99 26 52 0 1398912112 37993456 6752576
00:03:18 28767 8192000000 10273138832 10698100736 1323131008 13.905 98 18 36 1 1361573552 33937024 4505520
00:05:04 8858 8192000000 10272834928 10704379904 1359801456 14.236 100 28 53 1 1373648384 8014496 5832432
00:06:16 17540 8192000000 10270579584 10708758528 1356525344 14.206 100 15 30 1 1371138416 10922816 3690256
00:07:41 63910 8192000000 10265143984 10699849728 1354507680 14.188 99 37 73 2 1370328832 7936880 7884272
00:09:47 28949 8192000000 10293180624 10727518208 1373141040 14.355 99 35 70 1 1394665552 13398944 8125568
00:11:41 80888 8192000000 10298447872 10736939008 1375372352 14.375 100 32 62 3 1402015088 18542752 8099984
00:14:14 109124 8192000000 10306892128 10739863552 1374891728 14.371 99 26 48 1 1394819600 14229392 5698480
00:17:05 37410 8192000000 10292344784 10735177728 1377734080 14.396 99 35 71 0 1393570304 7203184 8633040
00:18:51 56947 8192000000 10298168784 10757877760 1402360160 14.616 100 12 24 0 1417296576 12023440 2912976
00:20:50 43309 8192000000 10317338960 10767388672 1412213424 14.704 100 13 26 0 1428832352 12969664 3649264
00:22:53 30949 8192000000 10309395152 10780065792 1412407744 14.705 100 6 12 0 1426797344 12883344 1506256
00:24:24 37316 8192000000 10316291024 10766102528 1386997840 14.479 98 18 36 1 1398838688 7947424 3893424
00:26:03 58083 8192000000 10308214272 10769186816 1400846048 14.603 99 19 38 0 1416502096 11521792 4134256
00:28:02 73169 8192000000 10295887088 10767818752 1395890848 14.558 99 5 10 0 1407831056 11022896 917312
00:30:16 47425 8192000000 10301275808 10757230592 1397055248 14.569 99 19 37 1 1410369680 9109328 4205104
00:32:04 26578 8192000000 10298069248 10749571072 1387680096 14.485 99 33 67 1 1401874400 6584640 7609664
00:33:31 22091 8192000000 10299012160 10744827904 1390690864 14.512 100 34 68 1 1408288256 10356736 7240656
00:34:54 17039 8192000000 10312377264 10761863168 1403204752 14.624 100 36 72 1 1426924272 15752016 7967504
00:36:13 66423 8192000000 10269488496 10712408064 1305448368 13.745 94 20 40 0 1325694016 15788768 4456880
00:38:19 74409 8192000000 10269781104 10725638144 1335230688 14.014 97 20 40 0 1349895200 10093392 4571120
00:40:34 86965 8192000000 10272791920 10741362688 1323263584 13.906 96 13 26 0 1344502112 18530016 2708512
00:43:04 18309 8192000000 10274474544 10708967424 1329374368 13.962 97 46 92 1 1355421680 15849552 10197760
00:44:24 86706 8192000000 10254904048 10697433088 1335600016 14.018 98 17 34 0 1347112656 7686064 3826576
00:47:24 21697 8192000000 10238672800 10687926272 1333159472 13.996 99 8 16 0 1346455120 11918688 1376960
00:48:47 62984 8192000000 10268361216 10723536896 1361837728 14.254 99 18 36 0 1374015888 8390480 3787680
00:51:08 55488 8192000000 10254882960 10711617536 1324078336 13.914 96 15 30 1 1344965408 17225408 3661664
00:53:41 39469 8192000000 10258259552 10711392256 1311635648 13.801 96 8 16 0 1323649168 10160368 1853152
00:55:37 60149 8192000000 10276533168 10731196416 1357432112 14.214 99 16 32 2 1377385104 15833968 4119024
00:57:38 47332 8192000000 10252063872 10713989120 1328333600 13.952 97 14 28 1 1347357840 15405568 3618672
00:59:46 32075 8192000000 10280728480 10717802496 1360313952 14.240 100 41 82 1 1379413712 9365648 9734112
01:01:20 39528 8192000000 10294097888 10731802624 1362262368 14.258 99 32 64 1 1379058592 10153360 6642864
01:03:02 18604 8192000000 10308962368 10751201280 1386290208 14.473 100 46 92 0 1408873120 12316288 10266624
01:04:24 64023 8192000000 10308507200 10751377408 1342182016 14.077 96 32 64 0 1360500944 11023968 7294960
01:06:31 62141 8192000000 10294184064 10742599680 1363523488 14.269 98 42 84 1 1381329184 8281776 9523920
01:08:34 60773 8192000000 10294263584 10747404288 1367751472 14.307 98 18 36 0 1380722608 9268512 3702624
01:10:49 58784 8192000000 10291605600 10751033344 1370330976 14.330 98 8 16 1 1389662400 17338880 1992544
01:14:28 117049 8192000000 10303009296 10770038784 1352716336 14.172 96 13 26 0 1363937488 8625264 2595888
01:17:26 55504 8192000000 10316879216 10758721536 1373714912 14.360 98 28 55 0 1393388768 12554272 7119584
01:19:26 164275 8192000000 10273949424 10732490752 1231638032 13.069 88 10 20 0 1243338288 9728960 1971296
01:23:16 191236 8192000000 10288802320 10747084800 1253180720 13.267 91 6 12 0 1272186992 17949952 1056320
01:27:34 130216 8192000000 10265481184 10715766784 1337212864 14.032 98 24 48 1 1353023232 9790032 6020336
01:30:46 58347 8192000000 10276923280 10732326912 1351377904 14.160 98 15 30 0 1370152464 15241216 3533344
01:32:45 44426 8192000000 10287137264 10753593344 1376956496 14.389 100 11 22 0 1399005760 19081152 2968112
01:34:30 63720 8192000000 10273340176 10731950080 1376101392 14.382 100 19 38 1 1392884032 11834688 4947952
01:36:36 75114 8192000000 10265478592 10721665024 1319306704 13.870 95 14 28 0 1331594432 9204592 3083136

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-10-2021 01:39:25 to 17-10-2021 01:39:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 163 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 17-10-2021 01:39:41 to 17-10-2021 01:39:42.

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          244097
MON$OLDEST_ACTIVE               245955
MON$OLDEST_SNAPSHOT             245955
MON$NEXT_TRANSACTION            245955
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                       2015040
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 ---
:477: 
:478: mscs-test-161.budzdorov.ru    Sun Oct 17 01:37:56 2021
:479:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:480: 
:481: 
:482: mscs-test-161.budzdorov.ru    Sun Oct 17 01:37:57 2021
:483:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:484: 
--- end of diff output ---

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

17.10.21 01:39:43. 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-17 01:39:46 No severe PSQL-related problems occured
17.10.21 01:39:47. End of report.