Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.09.2021 19: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 16253152 kB
MemAvailable 30876104 kB
Buffers 0 kB
Cached 15411608 kB
SwapCached 336 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.8
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-09-02 19:37:50
DTS_END                         2021-09-02 19:37:50.0680
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35394
TRN_ID                          241895
ATT_ID                          702
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: 9136.36 23516 1096363
customer order: creation 170.54 364 20465
customer order: refuse 14.71 358 1765
order to supplier: creation 44.83 1400 5380
order to supplier: removal 3.39 110609 407
invoice (draft): creation 44.90 3480 5388
invoice (draft): removal 52.31 900 6277
invoice accept: apply 41.46 19156 4975
invoice accept: cancel 47.16 859 5659
customer reserve: creation 3805.84 190 456701
customer reserve: removal 4610.68 63 553281
realization accept: apply 84.55 203 10146
realization accept: cancel 85.89 121 10307
payment from customer: creation 59.58 120 7150
payment from customer: removal 5.80 100 696
payment to supplier: creation 48.68 133 5841
payment to supplier: removal 3.38 63 406
service: total inventory turnovers 5.02 1194 602
service: total monetary turnovers 6.82 63 818
service: aggregate perf. data 0.79 25135 95
service: refresh index statistics 0.03 305806 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-09-2021 19:38:32 to 02-09-2021 19:38:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5521
TEST_TIME 2 5715
TEST_TIME 3 5415
TEST_TIME 4 5074
TEST_TIME 5 4321
TEST_TIME 6 4632
TEST_TIME 7 4944
TEST_TIME 8 5260
TEST_TIME 9 5487
TEST_TIME 10 5904
TEST_TIME 11 6046
TEST_TIME 12 6079
TEST_TIME 13 6122
TEST_TIME 14 6265
TEST_TIME 15 6836
TEST_TIME 16 6890
TEST_TIME 17 7134
TEST_TIME 18 7174
TEST_TIME 19 7325
TEST_TIME 20 8035
TEST_TIME 21 8238
TEST_TIME 22 8282
TEST_TIME 23 8659
TEST_TIME 24 8992
TEST_TIME 25 9198
TEST_TIME 26 9489
TEST_TIME 27 9773
TEST_TIME 28 10169
TEST_TIME 29 10249
TEST_TIME 30 10562
TEST_TIME 31 10310
TEST_TIME 32 10150
TEST_TIME 33 10361
TEST_TIME 34 10265
TEST_TIME 35 10111
TEST_TIME 36 9929
TEST_TIME 37 9876
TEST_TIME 38 9789
TEST_TIME 39 9916
TEST_TIME 40 9787
TEST_TIME 41 9679
TEST_TIME 42 9656
TEST_TIME 43 9543
TEST_TIME 44 9453
TEST_TIME 45 9330
TEST_TIME 46 9215
TEST_TIME 47 9143
TEST_TIME 48 9032
TEST_TIME 49 9044
TEST_TIME 50 9002
TEST_TIME 51 8987
TEST_TIME 52 8911
TEST_TIME 53 8968
TEST_TIME 54 9009
TEST_TIME 55 9028
TEST_TIME 56 9046
TEST_TIME 57 9114
TEST_TIME 58 9167
TEST_TIME 59 9146
TEST_TIME 60 9111
TEST_TIME 61 9330
TEST_TIME 62 9338
TEST_TIME 63 9314
TEST_TIME 64 9320
TEST_TIME 65 9368
TEST_TIME 66 9451
TEST_TIME 67 9432
TEST_TIME 68 9397
TEST_TIME 69 9273
TEST_TIME 70 9220
TEST_TIME 71 9213
TEST_TIME 72 9237
TEST_TIME 73 9201
TEST_TIME 74 9164
TEST_TIME 75 9129
TEST_TIME 76 9211
TEST_TIME 77 9160
TEST_TIME 78 9124
TEST_TIME 79 9066
TEST_TIME 80 8997
TEST_TIME 81 9027
TEST_TIME 82 8984
TEST_TIME 83 8918
TEST_TIME 84 8923
TEST_TIME 85 9014
TEST_TIME 86 9033
TEST_TIME 87 9022
TEST_TIME 88 9023
TEST_TIME 89 9029
TEST_TIME 90 9075
TEST_TIME 91 9095
TEST_TIME 92 9155
TEST_TIME 93 9137
TEST_TIME 94 9126
TEST_TIME 95 9136
TEST_TIME 96 9218
TEST_TIME 97 9211
TEST_TIME 98 9248
TEST_TIME 99 9269
TEST_TIME 100 9201
TEST_TIME 101 9157
TEST_TIME 102 9099
TEST_TIME 103 9102
TEST_TIME 104 9095
TEST_TIME 105 9049
TEST_TIME 106 9028
TEST_TIME 107 8996
TEST_TIME 108 9027
TEST_TIME 109 9003
TEST_TIME 110 9060
TEST_TIME 111 9019
TEST_TIME 112 9033
TEST_TIME 113 9035
TEST_TIME 114 9000
TEST_TIME 115 9103
TEST_TIME 116 9116
TEST_TIME 117 9121
TEST_TIME 118 9117
TEST_TIME 119 9145
TEST_TIME 120 9143

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-09-2021 19:38:35 to 02-09-2021 19:38:39.

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 1518479 1518479 0 0.00 0 16668 7 0 0
doc_list_biud 1518477 1518477 0 0.00 0 25455 29 0 0
sp_add_invoice_to_stock 5116 4975 141 2.75 36 171950 19156 0 141
sp_cancel_adding_invoice 5659 5659 0 0.00 4 80403 860 0 0
sp_cancel_client_order 1765 1765 0 0.00 4 12121 358 0 0
sp_cancel_customer_reserve 553281 553281 0 0.00 1 19035 63 0 0
sp_cancel_pay_from_customer 696 696 0 0.00 3 5728 100 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 3006 63 0 0
sp_cancel_supplier_invoice 6282 6277 5 0.07 2 25463 900 0 5
sp_cancel_supplier_order 407 407 0 0.00 6 1031799 110610 0 0
sp_cancel_write_off 10307 10307 0 0.00 1 9101 122 0 0
sp_client_order 20465 20465 0 0.00 4 24053 365 0 0
sp_customer_reserve 456701 456701 0 0.00 0 53668 190 0 0
sp_fill_shopping_cart 31231 31231 0 0.00 1 15137 42 0 0
sp_fill_shopping_cart_clo_res 456691 456691 0 0.00 0 20446 15 0 0
sp_get_clo_for_invoice 4959 4959 0 0.00 0 32317 387 0 0
sp_kill_cost_storno 17068 17068 0 0.00 0 10920 66 0 0
sp_kill_qstorno_ret_qs2qd 1123553 1123553 0 0.00 0 25451 34 0 0
sp_kill_qty_storno 592801 592801 0 0.00 0 25454 74 0 0
sp_lock_selected_doc 20892 20892 0 0.00 0 7398 6 0 0
sp_make_cost_storno 28096 28096 0 0.00 0 11339 60 0 0
sp_multiply_rows_for_pdistr 28096 28096 0 0.00 0 12770 32 0 0
sp_multiply_rows_for_qdistr 20465 20465 0 0.00 0 11339 152 0 0
sp_pay_from_customer 7150 7150 0 0.00 4 14783 120 0 0
sp_pay_to_supplier 5841 5841 0 0.00 4 17193 133 0 0
sp_payment_common 12991 12991 0 0.00 1 17006 35 0 0
sp_qd_handle_on_cancel_clo 1765 1765 0 0.00 0 12114 331 0 0
sp_qd_handle_on_invoice_upd_sts 10618 10618 0 0.00 0 22269 350 0 0
sp_qd_handle_on_reserve_upd_sts 10146 10146 0 0.00 0 21016 68 0 0
sp_reserve_write_off 10146 10146 0 0.00 7 21065 204 0 0
sp_supplier_invoice 5388 5388 0 0.00 6 92923 3480 0 0
sp_supplier_order 5380 5380 0 0.00 6 43853 1400 0 0
srv_aggregate_perf_data 95 95 0 0.00 3277 228896 25135 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1558 129811 47268 0 0
srv_find_qd_qs_mism 1048598 1048598 0 0.00 0 16667 6 0 0
srv_make_invnt_saldo 602 602 0 0.00 41 62851 1195 0 0
srv_make_money_saldo 818 818 0 0.00 0 17765 63 0 0
srv_recalc_idx_stat 4 4 0 0.00 54261 679909 305806 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4975 4975 0 0.00 0 8211 189 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 1 2634 91 0 0
t$perf-norm:sp_cancel_client_order 1765 1765 0 0.00 0 6144 16 0 0
t$perf-norm:sp_cancel_customer_reserve 687 687 0 0.00 0 8593 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 696 696 0 0.00 0 482 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 364 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 1353 12 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 35343 2189 0 0
t$perf-norm:sp_cancel_write_off 995 995 0 0.00 0 8411 19 0 0
t$perf-norm:sp_client_order 20465 20465 0 0.00 0 15424 21 0 0
t$perf-norm:sp_customer_reserve 1026 1026 0 0.00 0 2767 9 0 0
t$perf-norm:sp_pay_from_customer 7150 7150 0 0.00 0 13376 15 0 0
t$perf-norm:sp_pay_to_supplier 5841 5841 0 0.00 0 13323 14 0 0
t$perf-norm:sp_reserve_write_off 10146 10146 0 0.00 0 13405 14 0 0
t$perf-norm:sp_supplier_invoice 5388 5388 0 0.00 0 7502 21 0 0
t$perf-norm:sp_supplier_order 5380 5380 0 0.00 0 12030 20 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 140 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 367 11 0 0
t$perf-norm:srv_make_invnt_saldo 602 602 0 0.00 0 2028 29 0 0
t$perf-norm:srv_make_money_saldo 818 818 0 0.00 0 1813 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10197 9915 282 2.76 0 5492 20 0 282
v_all_customers 37422 37422 0 0.00 0 121 1 0 0
v_all_suppliers 30044 30044 0 0.00 0 20 1 0 0
v_all_wares 40930 40930 0 0.00 0 29 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 1526 11 0 0
v_cancel_client_order 3530 3530 0 0.00 0 3343 5 0 0
v_cancel_customer_prepayment 1392 1392 0 0.00 0 1870 6 0 0
v_cancel_customer_reserve 1374 1374 0 0.00 0 5009 12 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 3001 8 0 0
v_cancel_supplier_invoice 840 830 10 1.19 0 358 13 0 10
v_cancel_supplier_order 814 814 0 0.00 0 2280 11 0 0
v_cancel_write_off 1990 1990 0 0.00 0 5801 18 0 0
v_max_id_clo_ord 5380 5380 0 0.00 0 2937 2 0 0
v_max_id_clo_res 1026 1026 0 0.00 0 4807 19 0 0
v_max_id_ord_sup 5386 5386 0 0.00 0 1864 3 0 0
v_max_non_paid_invoice 1585 1585 0 0.00 0 3740 5 0 0
v_max_non_paid_realizn 3754 3754 0 0.00 0 7298 5 0 0
v_min_id_clo_ord 5380 5380 0 0.00 0 8761 12 0 0
v_min_id_clo_res 1026 1026 0 0.00 0 5863 17 0 0
v_min_id_ord_sup 5386 5386 0 0.00 0 6468 4 0 0
v_min_non_paid_invoice 5841 5841 0 0.00 0 17000 26 0 0
v_min_non_paid_realizn 7150 7150 0 0.00 0 13794 21 0 0
v_our_firm 10300 10300 0 0.00 0 120 1 0 0
v_reserve_write_off 20290 20290 0 0.00 0 6369 6 0 0
x_lock_depdocs_on_canc_invoice 11521 11521 0 0.00 0 16004 127 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 10100 418 0 0
x_make_qstorno_1000_1200 5380 5380 0 0.00 2 43821 1303 0 0
x_make_qstorno_1200_2000 5388 5388 0 0.00 2 92913 3337 0 0
x_make_qstorno_2100_3300 456701 456701 0 0.00 0 53667 172 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-09-2021 19:38:40 to 02-09-2021 19:38:44.

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
17:07:53 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:55 25036 8192000000 10394118832 10716028928 1432243664 14.881 100 83 166 0 1464324224 5380240 26700320
17:10:27 19711 8192000000 10446823152 10776457216 1500587008 15.481 100 93 186 0 1538998864 8383216 30028640
17:12:03 16330 8192000000 10489179024 10836434944 1546547232 15.880 100 81 162 1 1582061936 10474992 25039712
17:13:21 12119 8192000000 10568723568 10931417088 1633410288 16.624 100 81 162 1 1670083552 12076960 24596304
17:14:36 12793 8192000000 10646858224 11017551872 1704803184 17.225 100 83 166 2 1741529680 11986480 24740016
17:15:54 5348 8192000000 10723282944 11099340800 1779414656 17.845 100 84 167 0 1814834848 11707728 23712464
17:17:03 2954 8192000000 10760668848 11146747904 1821838160 18.193 100 89 177 0 1858486320 12131056 24517104
17:18:07 1519 8192000000 10790337088 11179642880 1855349600 18.466 100 84 166 1 1891574784 12018688 24206496
17:19:10 1284 8192000000 10823361840 11212525568 1878633088 18.654 100 92 184 1 1919587728 13209680 27744960
17:20:12 22332 8192000000 10781241808 11209945088 1876205952 18.634 100 38 74 1 1893344176 6252576 10885648
17:21:35 10647 8192000000 10792011136 11225583616 1883566656 18.694 100 15 30 2 1893560464 5950736 4043072
17:22:46 20019 8192000000 10809296256 11246989312 1896001328 18.794 100 13 26 1 1910055408 10616496 3437584
17:24:06 11935 8192000000 10818908528 11258499072 1911245856 18.917 100 17 31 1 1925182304 9843616 4092832
17:25:22 22670 8192000000 10872096944 11271618560 1903770848 18.857 99 82 170 1 1934136864 5599088 24766928
17:26:45 7298 8192000000 10821241552 11254460416 1903080336 18.851 100 27 53 2 1916463296 5825056 7557904
17:27:54 15314 8192000000 10760013264 11189678080 1823374208 18.205 96 24 47 1 1836368144 5995600 6998336
17:29:11 7155 8192000000 10755365056 11174117376 1799650496 18.011 97 29 58 2 1813997440 5354640 8992304
17:30:18 8297 8192000000 10686638880 11109412864 1764851296 17.724 97 17 33 1 1774886032 4872048 5162688
17:31:26 25849 8192000000 10701713920 11125874688 1697945088 17.168 93 38 76 1 1713706768 4633440 11128240
17:32:56 5637 8192000000 10751457440 11177443328 1840690560 18.346 100 28 57 2 1854596640 6311328 7594752
17:34:02 4649 8192000000 10759559728 11177943040 1827659936 18.240 99 33 64 1 1843541520 5740224 10141360
17:35:09 59998 8192000000 10790517584 11215446016 1866277616 18.554 100 34 65 0 1882126848 6026816 9822416
17:37:11 3697 8192000000 10818209120 11239432192 1894492560 18.782 100 38 75 2 1910937888 5883360 10561968
17:38:20 26896 8192000000 10844187648 11262779392 1911073904 18.915 100 44 88 0 1932406640 9274864 12057872
17:39:54 33101 8192000000 10860240096 11295653888 1937788832 19.129 100 30 60 1 1955392640 8101696 9502112
17:41:37 19620 8192000000 10896329760 11325972480 1957714464 19.288 100 39 77 1 1983017680 14018112 11285104
17:43:01 53613 8192000000 10894974000 11339206656 1965487472 19.350 100 41 82 0 1997066640 19198624 12380544
17:45:01 21633 8192000000 10915404832 11350134784 1973892816 19.416 100 41 80 1 2002730976 17405360 11432800
17:46:28 3551 8192000000 10946679760 11360845824 1983012112 19.489 100 81 161 1 2030259856 23584064 23663680
17:47:37 10710 8192000000 10931317536 11357728768 1982343536 19.483 100 66 131 4 2022855904 21181104 19331264
17:48:50 2863 8192000000 10948242752 11361738752 1985602928 19.509 100 77 154 0 2031558176 23598016 22357232
17:49:58 2902 8192000000 10939159920 11348545536 1967600496 19.366 99 84 162 3 2018473008 24845504 26027008
17:51:05 2729 8192000000 10956319664 11367174144 1983353296 19.491 100 92 184 1 2041922192 31185824 27383072
17:52:14 5713 8192000000 10954083600 11384020992 1980728016 19.470 100 57 112 1 2033346384 33977312 18641056
17:53:34 2766 8192000000 10957885296 11365134336 1978973792 19.457 100 83 166 0 2035904752 31844736 25086224
17:54:42 5186 8192000000 10953793184 11377557504 1982621680 19.485 100 69 137 1 2038359840 34815952 20922208
17:55:53 2105 8192000000 10974383424 11386880000 1986922304 19.519 100 92 180 1 2055689872 41023120 27744448
17:57:03 2544 8192000000 10991071920 11407380480 1995308080 19.586 100 85 172 0 2069204656 47158768 26737808
17:58:14 1557 8192000000 10979520544 11393503232 1989974096 19.544 100 82 159 2 2052685408 38041968 24669344
17:59:20 5854 8192000000 10952479952 11386753024 1970093216 19.386 99 56 113 0 2027815552 38990448 18731888
18:00:31 5807 8192000000 10955611648 11393839104 1981164032 19.474 100 53 103 0 2044264976 46149040 16951904
18:01:43 20862 8192000000 10939191120 11388624896 1944470240 19.182 98 36 72 1 2000748528 44322912 11955376
18:03:06 18376 8192000000 10943420672 11395805184 1974719376 19.423 100 35 70 1 2032834576 46034688 12080512
18:04:27 35789 8192000000 10910392992 11368751104 1939700672 19.144 99 22 44 0 1983819104 35992784 8125648
18:06:10 20424 8192000000 10886537648 11339407360 1885647536 18.711 97 23 46 1 1926599424 33377072 7574816
18:07:31 52923 8192000000 10901055808 11349262336 1965716336 19.351 100 30 60 0 1984959520 10774864 8468320
18:09:34 42794 8192000000 10909354144 11377172480 1975543440 19.429 100 18 36 2 2001305536 20609440 5152656
18:11:26 59455 8192000000 10906571936 11360198656 1966288992 19.356 99 14 28 1 1986236672 15884480 4063200
18:13:29 89874 8192000000 10915913904 11387265024 1958917872 19.297 98 15 30 0 1980749296 17394720 4436704
18:16:00 37651 8192000000 10895838176 11375644672 1956124448 19.275 99 13 26 1 1979336512 19109248 4102816
18:17:38 83570 8192000000 10919593728 11386892288 1993467600 19.571 100 34 64 3 2010593600 8332400 8793600
18:20:10 67875 8192000000 10930896368 11378270208 1957089952 19.283 98 31 62 0 1973466944 8266720 8110272
18:22:21 66671 8192000000 10930626944 11388362752 1999280160 19.617 100 32 61 1 2017112528 8798496 9033872
18:24:28 71260 8192000000 10931376512 11397505024 1980288880 19.467 99 15 31 0 2002417520 16199648 5928992
18:26:40 62816 8192000000 10908448528 11383627776 1962489632 19.326 98 31 61 1 1989445904 18478304 8477968
18:28:45 44056 8192000000 10914025616 11382095872 1980253808 19.467 99 15 30 0 2002324080 17544720 4525552
18:30:30 61123 8192000000 10918185008 11392991232 1998630016 19.612 100 17 33 0 2016581152 12929232 5021904
18:32:34 129811 8192000000 10915177680 11399356416 1952250896 19.244 98 11 22 0 1981305264 25623696 3430672
18:35:44 48085 8192000000 10937099504 11388493824 1988873312 19.535 100 21 42 1 2006527984 10745344 6909328
18:37:34 63329 8192000000 10934937600 11424784384 1983197552 19.490 99 18 36 1 2015952112 27286192 5468368
18:39:37 86977 8192000000 10898193792 11366211584 1893066576 18.770 95 8 16 0 1914854864 20006208 1782080
18:42:10 60022 8192000000 10921471680 11396354048 1980747408 19.471 100 19 38 0 2002385760 16268640 5369712
18:44:11 108866 8192000000 10860889344 11333513216 1800752576 18.020 91 10 20 3 1815610240 11685792 3171872
18:47:13 67521 8192000000 10865843568 11322875904 1863098736 18.528 95 41 82 0 1882698128 8995600 10603792
18:49:21 48243 8192000000 10837924832 11310964736 1874890464 18.624 97 15 30 0 1891787232 12942352 3954416
18:51:21 65447 8192000000 10846242128 11317100544 1881890448 18.680 97 26 51 3 1901997760 12308640 7798672
18:53:31 53107 8192000000 10835586544 11304656896 1902537488 18.847 99 11 22 0 1913779568 8032096 3209984
18:55:33 61642 8192000000 10808423872 11278245888 1833099984 18.285 96 13 26 0 1844733328 8175264 3458080
18:57:38 76899 8192000000 10826719680 11301761024 1879081520 18.658 98 18 36 1 1894806368 10611600 5113248
18:59:55 89583 8192000000 10872161520 11348373504 1940752592 19.153 100 13 26 1 1961373424 16255456 4365376
19:02:34 47745 8192000000 10872507328 11346345984 1944676032 19.184 100 12 24 0 1956677648 8143152 3858464
19:04:29 29557 8192000000 10882568400 11342852096 1931152624 19.076 99 29 57 0 1947836400 8279408 8404368
19:06:01 32660 8192000000 10901363184 11351674880 1928479712 19.055 98 41 82 2 1950422576 10035024 11907840
19:07:36 51889 8192000000 10879534656 11343413248 1866043776 18.552 95 17 34 1 1886451536 15297648 5110112
19:09:35 84738 8192000000 10903171040 11370065920 1928826832 19.057 97 15 30 0 1944433360 11213296 4393232
19:12:01 25596 8192000000 10919326032 11367137280 1945428048 19.190 98 52 104 0 1974117616 13384480 15305088
19:13:31 106951 8192000000 10890102560 11375026176 1924256992 19.021 97 19 38 0 1943193696 13823808 5112896
19:16:19 101866 8192000000 10891324192 11364716544 1926082544 19.036 97 12 25 0 1938078192 8872496 3123152
19:19:07 66301 8192000000 10913190816 11374440448 1949446256 19.222 98 22 44 0 1966983008 11472432 6064320
19:21:14 73811 8192000000 10879654000 11362545664 1910994576 18.915 97 10 20 0 1922650848 9190752 2465520
19:23:28 69002 8192000000 10902344224 11365695488 1940908192 19.154 98 16 32 0 1956513376 10834000 4771184
19:25:38 42571 8192000000 10898709984 11384639488 1942023296 19.163 98 15 30 0 1962706944 15919136 4764512
19:27:26 50737 8192000000 10888247104 11361857536 1927947792 19.050 98 8 16 1 1943710112 13651712 2110608
19:29:17 38175 8192000000 10895857296 11354750976 1954011120 19.258 100 35 70 2 1978094256 13803088 10280048
19:30:59 61411 8192000000 10906965216 11361910784 1922107728 19.004 97 32 65 1 1946121248 14391008 9622512
19:33:01 100225 8192000000 10834880944 11294982144 1761163344 17.694 91 23 46 5 1779366624 12158752 6044528
19:35:43 37563 8192000000 10854150704 11309547520 1896021632 18.794 99 28 56 1 1917750128 13908096 7820400

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-09-2021 19:38:45 to 02-09-2021 19:39:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 141 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-09-2021 19:39:01 to 02-09-2021 19:39:03.

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          239409
MON$OLDEST_ACTIVE               242048
MON$OLDEST_SNAPSHOT             242048
MON$NEXT_TRANSACTION            242048
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                       2020224
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33491 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33491 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33491 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33491 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:19: 
:20: mscs-test-161.budzdorov.ru    Thu Sep  2 19:37:58 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Thu Sep  2 19:37:58 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Thu Sep  2 19:38:03 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Thu Sep  2 19:38:03 2021
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:34: 
--- end of diff output ---

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

02.09.21 19:39:04. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-09-02 19:39:06 No severe PSQL-related problems occured
02.09.21 19:39:08. End of report.