Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.08.2021 16:39
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 16216744 kB
MemAvailable 30988564 kB
Buffers 0 kB
Cached 15474972 kB
SwapCached 2604 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-08-19 16:37:46
DTS_END                         2021-08-19 16:37:46.7600
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/37868
TRN_ID                          242996
ATT_ID                          719
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: 9312.35 19988 1117482
customer order: creation 180.33 341 21640
customer order: refuse 15.18 385 1821
order to supplier: creation 47.10 1272 5652
order to supplier: removal 3.66 96796 439
invoice (draft): creation 48.35 3574 5802
invoice (draft): removal 54.33 822 6519
invoice accept: apply 42.75 17966 5130
invoice accept: cancel 48.83 857 5860
customer reserve: creation 3848.82 182 461858
customer reserve: removal 4713.18 59 565582
realization accept: apply 87.23 181 10467
realization accept: cancel 87.43 122 10492
payment from customer: creation 62.41 106 7489
payment from customer: removal 6.11 112 733
payment to supplier: creation 50.49 137 6059
payment to supplier: removal 3.43 78 412
service: total inventory turnovers 4.98 1276 598
service: total monetary turnovers 6.85 35 822
service: aggregate perf. data 0.86 18206 103
service: refresh index statistics 0.03 257253 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-08-2021 16:39:49 to 19-08-2021 16:39:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1874
TEST_TIME 2 2959
TEST_TIME 3 5154
TEST_TIME 4 6148
TEST_TIME 5 6205
TEST_TIME 6 6018
TEST_TIME 7 6210
TEST_TIME 8 6114
TEST_TIME 9 6119
TEST_TIME 10 6564
TEST_TIME 11 6573
TEST_TIME 12 6714
TEST_TIME 13 6904
TEST_TIME 14 7254
TEST_TIME 15 7325
TEST_TIME 16 7441
TEST_TIME 17 8029
TEST_TIME 18 8343
TEST_TIME 19 8995
TEST_TIME 20 9013
TEST_TIME 21 8999
TEST_TIME 22 9248
TEST_TIME 23 9135
TEST_TIME 24 9313
TEST_TIME 25 9289
TEST_TIME 26 9544
TEST_TIME 27 9837
TEST_TIME 28 10174
TEST_TIME 29 10981
TEST_TIME 30 10994
TEST_TIME 31 10795
TEST_TIME 32 10838
TEST_TIME 33 10695
TEST_TIME 34 10816
TEST_TIME 35 10588
TEST_TIME 36 10378
TEST_TIME 37 10374
TEST_TIME 38 10281
TEST_TIME 39 10189
TEST_TIME 40 10174
TEST_TIME 41 10007
TEST_TIME 42 9941
TEST_TIME 43 9864
TEST_TIME 44 9909
TEST_TIME 45 9889
TEST_TIME 46 9794
TEST_TIME 47 9615
TEST_TIME 48 9591
TEST_TIME 49 9509
TEST_TIME 50 9524
TEST_TIME 51 9481
TEST_TIME 52 9475
TEST_TIME 53 9476
TEST_TIME 54 9332
TEST_TIME 55 9359
TEST_TIME 56 9284
TEST_TIME 57 9165
TEST_TIME 58 9077
TEST_TIME 59 9082
TEST_TIME 60 9046
TEST_TIME 61 9018
TEST_TIME 62 9069
TEST_TIME 63 9077
TEST_TIME 64 9100
TEST_TIME 65 9082
TEST_TIME 66 9159
TEST_TIME 67 9166
TEST_TIME 68 9299
TEST_TIME 69 9276
TEST_TIME 70 9325
TEST_TIME 71 9416
TEST_TIME 72 9370
TEST_TIME 73 9419
TEST_TIME 74 9544
TEST_TIME 75 9445
TEST_TIME 76 9358
TEST_TIME 77 9264
TEST_TIME 78 9291
TEST_TIME 79 9251
TEST_TIME 80 9208
TEST_TIME 81 9152
TEST_TIME 82 9086
TEST_TIME 83 9119
TEST_TIME 84 9099
TEST_TIME 85 9153
TEST_TIME 86 9194
TEST_TIME 87 9172
TEST_TIME 88 9146
TEST_TIME 89 9236
TEST_TIME 90 9435
TEST_TIME 91 9393
TEST_TIME 92 9440
TEST_TIME 93 9501
TEST_TIME 94 9501
TEST_TIME 95 9464
TEST_TIME 96 9454
TEST_TIME 97 9442
TEST_TIME 98 9381
TEST_TIME 99 9342
TEST_TIME 100 9341
TEST_TIME 101 9308
TEST_TIME 102 9279
TEST_TIME 103 9220
TEST_TIME 104 9201
TEST_TIME 105 9208
TEST_TIME 106 9183
TEST_TIME 107 9165
TEST_TIME 108 9197
TEST_TIME 109 9199
TEST_TIME 110 9208
TEST_TIME 111 9246
TEST_TIME 112 9209
TEST_TIME 113 9267
TEST_TIME 114 9279
TEST_TIME 115 9283
TEST_TIME 116 9283
TEST_TIME 117 9326
TEST_TIME 118 9338
TEST_TIME 119 9330
TEST_TIME 120 9317

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-08-2021 16:39:53 to 19-08-2021 16:39:56.

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 1546099 1546099 0 0.00 0 32892 7 0 0
doc_list_biud 1546098 1546098 0 0.00 0 26445 28 0 0
sp_add_invoice_to_stock 5203 5130 73 1.40 56 215107 17966 0 73
sp_cancel_adding_invoice 5860 5860 0 0.00 5 75975 857 0 0
sp_cancel_client_order 1821 1821 0 0.00 4 12059 386 0 0
sp_cancel_customer_reserve 565582 565582 0 0.00 1 20495 59 0 0
sp_cancel_pay_from_customer 733 733 0 0.00 3 9410 112 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 3 3395 78 0 0
sp_cancel_supplier_invoice 6528 6519 9 0.13 2 26464 823 0 9
sp_cancel_supplier_order 439 439 0 0.00 6 1010509 96796 0 0
sp_cancel_write_off 10492 10492 0 0.00 1 13065 122 0 0
sp_client_order 21640 21640 0 0.00 4 19234 342 0 0
sp_customer_reserve 461858 461858 0 0.00 0 46418 182 0 0
sp_fill_shopping_cart 33089 33089 0 0.00 1 30066 42 0 0
sp_fill_shopping_cart_clo_res 461856 461856 0 0.00 0 14505 13 0 0
sp_get_clo_for_invoice 5124 5124 0 0.00 0 21010 286 0 0
sp_kill_cost_storno 17497 17497 0 0.00 0 7723 58 0 0
sp_kill_qstorno_ret_qs2qd 1148614 1148614 0 0.00 0 26437 32 0 0
sp_kill_qty_storno 606302 606302 0 0.00 0 26442 70 0 0
sp_lock_selected_doc 21503 21503 0 0.00 0 11648 6 0 0
sp_make_cost_storno 29138 29138 0 0.00 0 32890 53 0 0
sp_multiply_rows_for_pdistr 29137 29137 0 0.00 0 8848 31 0 0
sp_multiply_rows_for_qdistr 21640 21640 0 0.00 0 17411 176 0 0
sp_pay_from_customer 7489 7489 0 0.00 4 19078 107 0 0
sp_pay_to_supplier 6059 6059 0 0.00 4 15145 137 0 0
sp_payment_common 13548 13548 0 0.00 1 19061 30 0 0
sp_qd_handle_on_cancel_clo 1821 1821 0 0.00 0 12053 339 0 0
sp_qd_handle_on_invoice_upd_sts 10982 10982 0 0.00 0 14152 339 0 0
sp_qd_handle_on_reserve_upd_sts 10467 10467 0 0.00 0 26258 49 0 0
sp_reserve_write_off 10467 10467 0 0.00 6 32897 182 0 0
sp_supplier_invoice 5802 5802 0 0.00 5 128836 3574 0 0
sp_supplier_order 5652 5652 0 0.00 5 27885 1272 0 0
srv_aggregate_perf_data 103 103 0 0.00 2090 112514 18206 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 3021 160860 44297 0 0
srv_find_qd_qs_mism 1068856 1068856 0 0.00 0 14730 6 0 0
srv_make_invnt_saldo 598 598 0 0.00 33 134718 1277 0 0
srv_make_money_saldo 822 822 0 0.00 1 2546 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 150426 472886 257253 0 0
t$perf-abend:sp_add_invoice_to_stock 73 73 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 5131 5131 0 0.00 0 10416 178 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 7294 102 0 0
t$perf-norm:sp_cancel_client_order 1821 1821 0 0.00 0 11214 20 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 1525 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 733 733 0 0.00 0 172 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 447 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 919 12 0 0
t$perf-norm:sp_cancel_supplier_order 439 439 0 0.00 0 23359 1514 0 0
t$perf-norm:sp_cancel_write_off 990 990 0 0.00 0 760 4 0 0
t$perf-norm:sp_client_order 21640 21640 0 0.00 0 13138 21 0 0
t$perf-norm:sp_customer_reserve 1029 1029 0 0.00 0 1073 7 0 0
t$perf-norm:sp_pay_from_customer 7489 7489 0 0.00 0 10416 12 0 0
t$perf-norm:sp_pay_to_supplier 6059 6059 0 0.00 0 8351 12 0 0
t$perf-norm:sp_reserve_write_off 10467 10467 0 0.00 0 11588 14 0 0
t$perf-norm:sp_supplier_invoice 5802 5802 0 0.00 0 7564 21 0 0
t$perf-norm:sp_supplier_order 5652 5652 0 0.00 0 3337 17 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 423 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 103 4 0 0
t$perf-norm:srv_make_invnt_saldo 598 598 0 0.00 0 1298 23 0 0
t$perf-norm:srv_make_money_saldo 822 822 0 0.00 0 545 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10390 10244 146 1.40 0 7438 21 0 146
v_all_customers 39366 39366 0 0.00 0 19 1 0 0
v_all_suppliers 31492 31492 0 0.00 0 23 1 0 0
v_all_wares 43280 43280 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 946 8 0 0
v_cancel_client_order 3642 3642 0 0.00 0 6361 8 0 0
v_cancel_customer_prepayment 1466 1466 0 0.00 0 9394 20 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 0 2784 9 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 2268 8 0 0
v_cancel_supplier_invoice 846 828 18 2.12 0 9460 25 0 18
v_cancel_supplier_order 878 878 0 0.00 0 126 3 0 0
v_cancel_write_off 1980 1980 0 0.00 0 3335 21 0 0
v_max_id_clo_ord 5650 5650 0 0.00 0 7119 3 0 0
v_max_id_clo_res 1028 1028 0 0.00 0 1192 11 0 0
v_max_id_ord_sup 5798 5798 0 0.00 0 3902 5 0 0
v_max_non_paid_invoice 1760 1760 0 0.00 0 3005 4 0 0
v_max_non_paid_realizn 3986 3986 0 0.00 0 5091 4 0 0
v_min_id_clo_ord 5650 5650 0 0.00 0 4241 8 0 0
v_min_id_clo_res 1028 1028 0 0.00 0 3227 9 0 0
v_min_id_ord_sup 5798 5798 0 0.00 0 6417 8 0 0
v_min_non_paid_invoice 6059 6059 0 0.00 0 8171 21 0 0
v_min_non_paid_realizn 7489 7489 0 0.00 0 19054 19 0 0
v_our_firm 10920 10920 0 0.00 0 14 1 0 0
v_reserve_write_off 20934 20934 0 0.00 0 6935 8 0 0
x_lock_depdocs_on_canc_invoice 11965 11965 0 0.00 0 11878 117 0 0
x_lock_depdocs_on_canc_sup_ord 439 439 0 0.00 0 2959 313 0 0
x_make_qstorno_1000_1200 5652 5652 0 0.00 2 27858 1185 0 0
x_make_qstorno_1200_2000 5802 5802 0 0.00 2 128805 3421 0 0
x_make_qstorno_2100_3300 461858 461858 0 0.00 0 39333 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-08-2021 16:39:57 to 19-08-2021 16:40:01.

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:50 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:51 38198 8192000000 10374424496 10693472256 1428042688 14.844 100 94 188 3 1465244768 6378528 30823552
14:10:39 27406 8192000000 10452033088 10784108544 1500565072 15.481 100 87 174 0 1536307328 8581520 27160736
14:12:10 19329 8192000000 10510613056 10857476096 1565260400 16.042 100 86 171 3 1602279488 10824448 26194640
14:13:35 10298 8192000000 10583941152 10944339968 1654624592 16.803 100 78 156 1 1688495808 10674384 23196832
14:14:51 8643 8192000000 10656786800 11029213184 1716151552 17.320 100 78 156 2 1750894416 11951840 22791024
14:16:04 6951 8192000000 10710211888 11095515136 1782087392 17.867 100 81 162 4 1818325104 13277488 22960224
14:17:12 2525 8192000000 10757914368 11142103040 1819292208 18.172 100 87 174 0 1858112000 14136320 24683472
14:18:17 2550 8192000000 10802155312 11190390784 1859947248 18.503 100 86 172 1 1897228240 12576960 24704032
14:19:22 20364 8192000000 10830095344 11218845696 1879195408 18.659 100 73 146 0 1911247056 11418784 20632864
14:20:43 50262 8192000000 10820361088 11235147776 1896969792 18.802 100 57 113 2 1920739840 7342448 16427600
14:22:34 8139 8192000000 10816601200 11248717824 1903798176 18.857 100 25 49 1 1916637232 5835872 7003184
14:23:43 8432 8192000000 10815499376 11249930240 1908191072 18.892 100 14 28 1 1917556272 5765856 3599344
14:24:54 7646 8192000000 10839105056 11262693376 1918492864 18.975 100 14 27 2 1928475584 5555504 4427216
14:26:02 65693 8192000000 10850265808 11283329024 1931024992 19.075 100 31 62 0 1946166896 5669088 9472816
14:28:09 6624 8192000000 10813793696 11254751232 1908405520 18.894 100 21 42 1 1920211200 5363776 6441904
14:29:16 5114 8192000000 10750245424 11176411136 1840092368 18.342 100 29 58 5 1855589280 7000464 8496448
14:30:22 10199 8192000000 10739670944 11160764416 1770426960 17.771 96 26 50 0 1783642640 5954640 7261040
14:31:35 58951 8192000000 10703594496 11114196992 1669188336 16.926 92 19 38 2 1679704704 5545072 4971296
14:33:35 5191 8192000000 10716286768 11123867648 1742083904 17.536 96 25 50 1 1755298784 5798816 7416064
14:34:42 8081 8192000000 10765506304 11179069440 1836726304 18.314 100 24 46 1 1849122224 5268928 7126992
14:35:52 81384 8192000000 10799914944 11229904896 1875586720 18.629 100 38 76 3 1892221696 5643232 10991744
14:38:22 35528 8192000000 10834694320 11259109376 1914125168 18.940 100 34 69 0 1932476016 8649856 9700992
14:39:59 25139 8192000000 10864068512 11296509952 1932962352 19.091 100 43 86 3 1958758560 13347664 12448544
14:41:29 5527 8192000000 10898437616 11325526016 1957282432 19.284 100 66 131 1 1989807280 13264080 19260768
14:42:42 6683 8192000000 10907737312 11337314304 1969828416 19.384 100 55 108 1 1999484112 13472544 16183152
14:43:53 11741 8192000000 10935978112 11367145472 1991037952 19.552 100 60 122 3 2025685136 17567248 17079936
14:45:08 4262 8192000000 10957619296 11384807424 2006685120 19.675 100 68 135 1 2043129744 16380128 20064496
14:46:18 3020 8192000000 10973447792 11399499776 2017565168 19.761 100 67 134 1 2060181840 22525984 20090688
14:47:24 6634 8192000000 10973414192 11407454208 2020440672 19.784 100 59 119 2 2061762976 23968912 17353392
14:48:36 15956 8192000000 10974094208 11406843904 2010809616 19.708 100 58 115 0 2055314368 27548928 16955824
14:50:00 11093 8192000000 10919124240 11346866176 1950829440 19.233 99 74 144 1 2004694800 31871712 21993648
14:51:17 3502 8192000000 10962871808 11392372736 1993531520 19.572 100 62 124 1 2050485872 38284160 18670192
14:52:24 3150 8192000000 10948127936 11363188736 1966234496 19.356 100 83 166 0 2031439984 39557168 25648320
14:53:30 3264 8192000000 10961785440 11372580864 1960163824 19.307 99 84 165 2 2030244704 44243872 25837008
14:54:41 3863 8192000000 10928482096 11348914176 1945401200 19.190 100 74 146 0 2013073536 44658976 23013360
14:55:52 5625 8192000000 10938036528 11359924224 1942677488 19.168 99 55 108 3 2007478736 47216864 17584384
14:57:05 3658 8192000000 10946310720 11353419776 1953723024 19.256 100 81 160 4 2020190528 41448768 25018736
14:58:14 6529 8192000000 10918467936 11347910656 1948181024 19.212 100 55 109 0 2007623136 41630288 17811824
14:59:23 21788 8192000000 10932112464 11372937216 1951235440 19.236 99 34 68 1 2003668272 41349792 11083040
15:00:56 8163 8192000000 10965405168 11403689984 1989088448 19.537 100 45 90 1 2050409376 46314640 15006288
15:02:08 17712 8192000000 10949885088 11392921600 1949413744 19.222 98 27 54 1 2007118960 48527248 9177968
15:03:34 20751 8192000000 10955757760 11420340224 1985781200 19.510 100 26 52 1 2049921584 54837872 9302512
15:05:11 20984 8192000000 10932484416 11396521984 1972394736 19.404 100 24 48 1 2024246752 43788208 8063808
15:06:34 15625 8192000000 10926112256 11384102912 1939780272 19.145 98 16 32 1 1971046592 26147504 5118816
15:07:50 54927 8192000000 10940889104 11387904000 1991413968 19.555 100 33 67 3 2017344208 15927808 10002432
15:09:47 22815 8192000000 10949484720 11386433536 1997114464 19.600 100 42 81 2 2022063808 13876176 11073168
15:11:12 38578 8192000000 10922929632 11402915840 1996468480 19.595 100 10 20 1 2018442352 19233248 2740624
15:12:55 97174 8192000000 10917852752 11388157952 1965325840 19.348 98 12 24 2 1980560464 12297088 2937536
15:15:33 113577 8192000000 10891271392 11359625216 1873475248 18.612 94 17 35 0 1892387264 13742656 5169360
15:18:32 83553 8192000000 10928586992 11384885248 1974139808 19.418 99 26 51 1 1999098192 17532720 7425664
15:21:25 160860 8192000000 10898797984 11380252672 1924408400 19.022 97 19 38 1 1949019808 18338960 6272448
15:25:16 14799 8192000000 10920978720 11371425792 1975198144 19.427 99 55 108 0 2000129136 9944496 14986496
15:26:32 43698 8192000000 10929851936 11374809088 1955670128 19.272 98 39 76 2 1979423856 12556320 11197408
15:28:23 59737 8192000000 10907091328 11369193472 1964410064 19.341 99 18 36 1 1982466944 12293344 5763536
15:30:27 57267 8192000000 10906874848 11388661760 1987131280 19.521 100 10 20 1 2006015600 15665552 3218768
15:32:25 40027 8192000000 10856360240 11330793472 1916343072 18.958 97 21 40 2 1928821488 6984512 5493904
15:34:30 62263 8192000000 10851810000 11327295488 1905111152 18.867 97 12 22 0 1914645776 6516272 3018352
15:37:04 60927 8192000000 10877258192 11348840448 1908584896 18.895 97 4 8 0 1916676128 7424624 666608
15:39:20 35651 8192000000 10875688736 11349225472 1950879760 19.233 100 13 26 0 1962645568 8431472 3334336
15:41:01 58584 8192000000 10888928000 11364204544 1946314720 19.197 99 17 34 1 1968040192 16597600 5127872
15:43:13 50783 8192000000 10890989104 11347697664 1928767136 19.057 99 23 46 0 1946464832 11610224 6087472
15:45:06 73401 8192000000 10872366512 11342516224 1912236960 18.925 98 13 26 0 1931218464 14848432 4133072
15:47:24 64354 8192000000 10858630400 11324481536 1886158432 18.715 97 25 50 0 1908094160 14584032 7351696
15:49:28 112357 8192000000 10855655136 11324567552 1848531104 18.410 95 28 56 0 1867514736 11271808 7711824
15:52:21 94829 8192000000 10874926080 11339563008 1948190320 19.212 100 20 40 2 1963757072 9806144 5760608
15:54:57 27887 8192000000 10881510160 11352186880 1959644608 19.303 100 22 44 0 1974707520 8459856 6603056
15:56:25 48269 8192000000 10878699056 11357732864 1958381232 19.293 100 13 26 1 1968684480 6957712 3345536
15:58:14 67028 8192000000 10911952192 11385352192 1960589264 19.311 99 20 40 1 1975058496 8553440 5915792
16:00:21 77333 8192000000 10908081216 11394904064 1969455680 19.381 99 10 20 0 1989498400 16935024 3107696
16:02:38 52568 8192000000 10921312096 11387351040 1963120144 19.331 99 20 40 3 1985602496 16154560 6327792
16:04:32 45345 8192000000 10904445776 11375001600 1969660304 19.383 100 25 50 1 1992457664 15304080 7493280
16:06:28 85500 8192000000 10897103648 11390328832 1944638096 19.184 99 11 22 1 1973365536 25570576 3156864
16:09:01 78574 8192000000 10911237456 11377102848 1934798640 19.105 98 16 30 2 1955183952 16072000 4313312
16:11:59 37548 8192000000 10907981664 11361161216 1955160336 19.268 99 45 90 0 1976739440 8289168 13289936
16:13:41 27882 8192000000 10918410704 11367669760 1956215936 19.276 99 55 110 1 1985396864 13813984 15366944
16:16:28 42202 8192000000 10915216800 11363708928 1966729760 19.359 100 34 66 1 1984275440 8480240 9065440
16:18:11 76012 8192000000 10876136784 11352297472 1929531296 19.063 98 13 26 0 1941541424 8487744 3522384
16:20:35 80008 8192000000 10890810688 11370909696 1953550688 19.255 99 8 16 0 1966262752 10279280 2432784
16:23:31 41700 8192000000 10879981872 11366772736 1956919088 19.282 99 24 48 1 1975360832 11233984 7207760
16:25:17 70419 8192000000 10884105760 11357786112 1909298656 18.901 97 25 49 3 1927021024 10736784 6985584
16:27:31 37462 8192000000 10859639792 11316768768 1894727328 18.784 96 20 38 2 1908908736 7948704 6232704
16:29:14 74496 8192000000 10894604032 11364462592 1928501600 19.055 98 19 39 1 1949073344 14963584 5608160
16:31:34 91178 8192000000 10885252816 11356385280 1911370928 18.918 97 10 19 1 1925712464 11764928 2576608
16:34:13 89159 8192000000 10858529264 11331346432 1838263536 18.327 94 20 41 0 1854739104 11356512 5119056
16:36:42 24080 8192000000 10852548656 11306733568 1884453728 18.701 98 36 72 0 1905350000 10922864 9973408

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-08-2021 16:40:03 to 19-08-2021 16:40:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 73 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:02, from 19-08-2021 16:40:18 to 19-08-2021 16:40:20.

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          239933
MON$OLDEST_ACTIVE               243111
MON$OLDEST_SNAPSHOT             243111
MON$NEXT_TRANSACTION            243111
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                       2035808
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.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 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 ---
:82: 
:83: mscs-test-161.budzdorov.ru    Thu Aug 19 16:37:50 2021
:84:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:85: 
:86: 
:87: mscs-test-161.budzdorov.ru    Thu Aug 19 16:37:52 2021
:88:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:89: 
:90: 
:91: mscs-test-161.budzdorov.ru    Thu Aug 19 16:38:30 2021
:92:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:93: 
:94: 
:95: mscs-test-161.budzdorov.ru    Thu Aug 19 16:38:32 2021
:96:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:97: 
:98: 
:99: mscs-test-161.budzdorov.ru    Thu Aug 19 16:38:34 2021
:100:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:101: 
--- end of diff output ---

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

19.08.21 16:40:21. 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-08-19 16:40:24 No severe PSQL-related problems occured
19.08.21 16:40:25. End of report.