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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16271052 kB
MemAvailable 30805196 kB
Buffers 0 kB
Cached 15398120 kB
SwapCached 416 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-14 22:38:04
DTS_END                         2021-09-14 22:37:51.6330
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55812
TRN_ID                          242462
ATT_ID                          274
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: 9189.04 25627 1102685
customer order: creation 172.19 338 20663
customer order: refuse 14.51 344 1741
order to supplier: creation 45.38 1290 5446
order to supplier: removal 3.43 97584 411
invoice (draft): creation 46.13 3197 5535
invoice (draft): removal 52.89 762 6347
invoice accept: apply 42.29 15706 5075
invoice accept: cancel 47.62 736 5714
customer reserve: creation 3799.26 159 455911
customer reserve: removal 4670.47 56 560456
realization accept: apply 83.83 173 10060
realization accept: cancel 79.89 106 9587
payment from customer: creation 58.70 112 7044
payment from customer: removal 6.03 82 723
payment to supplier: creation 50.16 116 6019
payment to supplier: removal 3.78 70 453
service: total inventory turnovers 4.97 902 596
service: total monetary turnovers 6.68 45 802
service: aggregate perf. data 0.82 19431 98
service: refresh index statistics 0.03 371337 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 14-09-2021 22:39:04 to 14-09-2021 22:39:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4962
TEST_TIME 2 3841
TEST_TIME 3 5665
TEST_TIME 4 5210
TEST_TIME 5 6357
TEST_TIME 6 5624
TEST_TIME 7 5411
TEST_TIME 8 5308
TEST_TIME 9 5450
TEST_TIME 10 5421
TEST_TIME 11 5834
TEST_TIME 12 6060
TEST_TIME 13 6178
TEST_TIME 14 6264
TEST_TIME 15 6572
TEST_TIME 16 7322
TEST_TIME 17 8004
TEST_TIME 18 8476
TEST_TIME 19 8898
TEST_TIME 20 8939
TEST_TIME 21 9181
TEST_TIME 22 9191
TEST_TIME 23 9442
TEST_TIME 24 9418
TEST_TIME 25 10132
TEST_TIME 26 10392
TEST_TIME 27 10728
TEST_TIME 28 10647
TEST_TIME 29 10528
TEST_TIME 30 10349
TEST_TIME 31 10327
TEST_TIME 32 10218
TEST_TIME 33 10233
TEST_TIME 34 10136
TEST_TIME 35 10113
TEST_TIME 36 9876
TEST_TIME 37 9796
TEST_TIME 38 9617
TEST_TIME 39 9558
TEST_TIME 40 9487
TEST_TIME 41 9546
TEST_TIME 42 9412
TEST_TIME 43 9411
TEST_TIME 44 9354
TEST_TIME 45 9253
TEST_TIME 46 9220
TEST_TIME 47 9274
TEST_TIME 48 9304
TEST_TIME 49 9267
TEST_TIME 50 9287
TEST_TIME 51 9199
TEST_TIME 52 9209
TEST_TIME 53 9294
TEST_TIME 54 9259
TEST_TIME 55 9235
TEST_TIME 56 9267
TEST_TIME 57 9322
TEST_TIME 58 9333
TEST_TIME 59 9420
TEST_TIME 60 9379
TEST_TIME 61 9472
TEST_TIME 62 9617
TEST_TIME 63 9680
TEST_TIME 64 9538
TEST_TIME 65 9398
TEST_TIME 66 9431
TEST_TIME 67 9382
TEST_TIME 68 9303
TEST_TIME 69 9267
TEST_TIME 70 9262
TEST_TIME 71 9295
TEST_TIME 72 9292
TEST_TIME 73 9299
TEST_TIME 74 9379
TEST_TIME 75 9345
TEST_TIME 76 9328
TEST_TIME 77 9360
TEST_TIME 78 9393
TEST_TIME 79 9348
TEST_TIME 80 9353
TEST_TIME 81 9413
TEST_TIME 82 9438
TEST_TIME 83 9441
TEST_TIME 84 9371
TEST_TIME 85 9307
TEST_TIME 86 9353
TEST_TIME 87 9268
TEST_TIME 88 9282
TEST_TIME 89 9242
TEST_TIME 90 9205
TEST_TIME 91 9192
TEST_TIME 92 9159
TEST_TIME 93 9138
TEST_TIME 94 9107
TEST_TIME 95 9105
TEST_TIME 96 9170
TEST_TIME 97 9202
TEST_TIME 98 9190
TEST_TIME 99 9261
TEST_TIME 100 9275
TEST_TIME 101 9277
TEST_TIME 102 9252
TEST_TIME 103 9253
TEST_TIME 104 9341
TEST_TIME 105 9324
TEST_TIME 106 9334
TEST_TIME 107 9391
TEST_TIME 108 9352
TEST_TIME 109 9290
TEST_TIME 110 9290
TEST_TIME 111 9235
TEST_TIME 112 9241
TEST_TIME 113 9217
TEST_TIME 114 9172
TEST_TIME 115 9147
TEST_TIME 116 9092
TEST_TIME 117 9104
TEST_TIME 118 9178
TEST_TIME 119 9184
TEST_TIME 120 9194

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-09-2021 22:39:08 to 14-09-2021 22:39:12.

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 1524751 1524751 0 0.00 0 27585 6 0 0
doc_list_biud 1524749 1524749 0 0.00 0 80020 26 0 0
sp_add_invoice_to_stock 5246 5075 171 3.25 45 141636 15707 0 171
sp_cancel_adding_invoice 5714 5714 0 0.00 4 64604 737 0 0
sp_cancel_client_order 1741 1741 0 0.00 4 11850 345 0 0
sp_cancel_customer_reserve 560456 560456 0 0.00 1 80022 56 0 0
sp_cancel_pay_from_customer 723 723 0 0.00 3 7611 82 0 0
sp_cancel_pay_to_supplier 453 453 0 0.00 3 5903 71 0 0
sp_cancel_supplier_invoice 6360 6347 13 0.20 1 27314 763 0 13
sp_cancel_supplier_order 411 411 0 0.00 5 977935 97584 0 0
sp_cancel_write_off 9587 9587 0 0.00 1 13269 107 0 0
sp_client_order 20663 20663 0 0.00 3 16058 339 0 0
sp_customer_reserve 455911 455911 0 0.00 0 66852 159 0 0
sp_fill_shopping_cart 31615 31615 0 0.00 1 15421 38 0 0
sp_fill_shopping_cart_clo_res 455909 455909 0 0.00 0 10807 13 0 0
sp_get_clo_for_invoice 5066 5066 0 0.00 0 29225 313 0 0
sp_kill_cost_storno 16477 16477 0 0.00 0 15203 55 0 0
sp_kill_qstorno_ret_qs2qd 1137257 1137257 0 0.00 0 79993 30 0 0
sp_kill_qty_storno 599374 599374 0 0.00 0 80020 66 0 0
sp_lock_selected_doc 20968 20968 0 0.00 0 9951 5 0 0
sp_make_cost_storno 28182 28182 0 0.00 0 27576 52 0 0
sp_multiply_rows_for_pdistr 28181 28181 0 0.00 0 9093 28 0 0
sp_multiply_rows_for_qdistr 20663 20663 0 0.00 0 9998 160 0 0
sp_pay_from_customer 7044 7044 0 0.00 4 8152 112 0 0
sp_pay_to_supplier 6019 6019 0 0.00 5 18474 117 0 0
sp_payment_common 13063 13063 0 0.00 1 18446 31 0 0
sp_qd_handle_on_cancel_clo 1741 1741 0 0.00 0 11829 309 0 0
sp_qd_handle_on_invoice_upd_sts 10772 10772 0 0.00 0 24468 311 0 0
sp_qd_handle_on_reserve_upd_sts 10060 10060 0 0.00 0 13390 52 0 0
sp_reserve_write_off 10060 10060 0 0.00 6 13981 174 0 0
sp_supplier_invoice 5535 5535 0 0.00 7 73851 3198 0 0
sp_supplier_order 5446 5446 0 0.00 4 32537 1291 0 0
srv_aggregate_perf_data 98 98 0 0.00 2660 215051 19432 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 2108 153408 53662 0 0
srv_find_qd_qs_mism 1054958 1054958 0 0.00 0 13368 5 0 0
srv_make_invnt_saldo 596 596 0 0.00 49 29135 903 0 0
srv_make_money_saldo 802 802 0 0.00 1 14073 46 0 0
srv_recalc_idx_stat 4 4 0 0.00 235987 506391 371338 0 0
t$perf-abend:sp_add_invoice_to_stock 171 171 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5075 5075 0 0.00 0 17603 165 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 0 10048 86 0 0
t$perf-norm:sp_cancel_client_order 1741 1741 0 0.00 0 3563 13 0 0
t$perf-norm:sp_cancel_customer_reserve 745 745 0 0.00 0 5018 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 723 723 0 0.00 0 1064 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 453 453 0 0.00 0 3903 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 4827 38 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 28470 1597 0 0
t$perf-norm:sp_cancel_write_off 1000 1000 0 0.00 0 1318 6 0 0
t$perf-norm:sp_client_order 20663 20663 0 0.00 0 11227 20 0 0
t$perf-norm:sp_customer_reserve 1023 1023 0 0.00 0 5715 8 0 0
t$perf-norm:sp_pay_from_customer 7044 7044 0 0.00 0 9636 13 0 0
t$perf-norm:sp_pay_to_supplier 6019 6019 0 0.00 0 22856 21 0 0
t$perf-norm:sp_reserve_write_off 10060 10060 0 0.00 0 9347 12 0 0
t$perf-norm:sp_supplier_invoice 5535 5535 0 0.00 0 7143 20 0 0
t$perf-norm:sp_supplier_order 5446 5446 0 0.00 0 5591 17 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 3448 47 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 773 13 0 0
t$perf-norm:srv_make_invnt_saldo 596 596 0 0.00 0 1057 18 0 0
t$perf-norm:srv_make_money_saldo 802 802 0 0.00 0 11885 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 12 5 0 0
v_add_invoice_to_stock 10458 10116 342 3.27 0 7134 17 0 342
v_all_customers 37314 37314 0 0.00 0 37 1 0 0
v_all_suppliers 30570 30570 0 0.00 0 18 1 0 0
v_all_wares 41322 41322 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 521 6 0 0
v_cancel_client_order 3482 3482 0 0.00 0 3013 4 0 0
v_cancel_customer_prepayment 1446 1446 0 0.00 0 2420 5 0 0
v_cancel_customer_reserve 1490 1490 0 0.00 0 1201 4 0 0
v_cancel_payment_to_supplier 906 906 0 0.00 0 2857 7 0 0
v_cancel_supplier_invoice 800 774 26 3.25 0 7726 33 0 26
v_cancel_supplier_order 822 822 0 0.00 0 2823 10 0 0
v_cancel_write_off 2000 2000 0 0.00 0 1485 13 0 0
v_max_id_clo_ord 5443 5443 0 0.00 0 1028 1 0 0
v_max_id_clo_res 1023 1023 0 0.00 0 1786 10 0 0
v_max_id_ord_sup 5508 5508 0 0.00 0 5675 4 0 0
v_max_non_paid_invoice 1685 1685 0 0.00 0 2190 3 0 0
v_max_non_paid_realizn 3933 3933 0 0.00 0 4862 6 0 0
v_min_id_clo_ord 5443 5443 0 0.00 0 6487 8 0 0
v_min_id_clo_res 1023 1023 0 0.00 0 4205 9 0 0
v_min_id_ord_sup 5508 5508 0 0.00 0 5012 6 0 0
v_min_non_paid_invoice 6019 6019 0 0.00 0 18443 21 0 0
v_min_non_paid_realizn 7044 7044 0 0.00 0 4894 19 0 0
v_our_firm 10230 10230 0 0.00 0 18 1 0 0
v_reserve_write_off 20120 20120 0 0.00 0 5951 5 0 0
x_lock_depdocs_on_canc_invoice 11674 11674 0 0.00 0 11849 112 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 5589 316 0 0
x_make_qstorno_1000_1200 5446 5446 0 0.00 1 32526 1217 0 0
x_make_qstorno_1200_2000 5535 5535 0 0.00 3 73839 3055 0 0
x_make_qstorno_2100_3300 455911 455911 0 0.00 0 66850 144 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-09-2021 22:39:13 to 14-09-2021 22:39:17.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:07:55 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:56 24129 8192000000 10365953552 10687614976 1420596880 14.778 100 86 172 0 1454913152 6240160 28076112
20:10:35 24096 8192000000 10454331216 10788102144 1509086752 15.555 100 87 174 0 1545821104 9312848 27421504
20:12:02 19725 8192000000 10512666000 10855649280 1565903440 16.047 100 84 168 1 1602231680 10142880 26185360
20:13:26 10064 8192000000 10586004272 10945585152 1640716880 16.686 100 82 164 0 1677110608 11866048 24527680
20:14:38 7680 8192000000 10636206864 11012894720 1706712064 17.241 100 73 146 1 1740720064 12564352 21443648
20:15:48 5968 8192000000 10698906992 11086938112 1770994960 17.775 100 75 150 0 1804150816 12293888 20861968
20:16:56 1750 8192000000 10759618704 11144183808 1820929248 18.185 100 92 182 1 1860959296 13799712 26230336
20:18:00 2310 8192000000 10808535696 11194740736 1860230352 18.505 100 91 182 2 1901509872 15152640 26126880
20:19:03 21762 8192000000 10794229008 11209236480 1876888272 18.640 100 59 118 0 1905006880 11777872 16340736
20:20:25 10640 8192000000 10787196544 11210936320 1878137712 18.650 100 18 36 1 1888995328 5994912 4862704
20:21:38 9878 8192000000 10796618400 11226173440 1888290160 18.732 100 18 36 1 1899604160 6392896 4921104
20:22:52 62076 8192000000 10820014880 11248103424 1896618192 18.799 100 20 41 0 1907169920 5132272 5419456
20:25:02 5556 8192000000 10823751520 11256713216 1904400896 18.862 100 34 68 1 1924282736 10411744 9470096
20:26:11 7578 8192000000 10818123952 11249029120 1886806432 18.720 99 17 32 2 1897308784 5783984 4718368
20:27:21 12347 8192000000 10793299280 11222700032 1862999264 18.528 99 24 46 2 1875304288 5953520 6351504
20:28:35 16665 8192000000 10763112560 11193962496 1812235936 18.114 96 21 42 0 1823995840 5995120 5764784
20:29:56 6888 8192000000 10733453856 11149389824 1757943168 17.667 96 24 49 1 1769776144 5003664 6829312
20:31:06 9297 8192000000 10725195280 11135700992 1770770560 17.773 98 28 56 0 1784315760 5222176 8323024
20:32:17 51699 8192000000 10733656160 11152670720 1800663600 18.019 99 42 84 1 1818865520 5989152 12212768
20:34:12 4272 8192000000 10777118656 11199311872 1855421760 18.466 100 25 50 1 1868700080 6720352 6557968
20:35:17 6390 8192000000 10778374336 11215515648 1872586448 18.605 100 17 34 0 1882067408 4745248 4735712
20:36:23 4329 8192000000 10801963552 11231801344 1884757072 18.704 100 38 73 1 1901597760 6139552 10701136
20:37:28 27680 8192000000 10827867056 11251625984 1898193120 18.812 100 39 78 0 1915667264 5290304 12183840
20:39:08 23197 8192000000 10872127776 11296210944 1935627296 19.112 100 57 112 1 1961612768 10499440 15486032
20:40:47 37583 8192000000 10892522928 11330699264 1957490752 19.286 100 27 54 0 1980474720 13993808 8990160
20:42:32 17327 8192000000 10908377936 11350495232 1970043632 19.386 100 41 82 0 2003430480 20769680 12617168
20:43:57 7253 8192000000 10920465440 11353329664 1951099632 19.235 98 52 104 0 1990148848 22545952 16503264
20:45:15 23106 8192000000 10943563600 11381821440 1989363056 19.539 100 55 110 0 2036600336 30815696 16421584
20:46:43 13573 8192000000 10955611168 11396616192 2008030224 19.686 100 45 88 1 2050938560 29071856 13836480
20:48:10 6976 8192000000 10980310528 11408318464 2000606560 19.628 99 71 142 2 2057310656 34760656 21943440
20:49:25 2578 8192000000 10984108464 11404529664 1998357024 19.610 99 82 160 3 2056991536 34017952 24616560
20:50:36 4621 8192000000 11007130096 11430875136 2017876400 19.763 99 72 143 1 2080639408 40518720 22244288
20:51:46 2107 8192000000 10999324016 11414827008 2008865568 19.693 100 91 180 1 2082512256 45366896 28279792
20:52:53 2504 8192000000 10971506912 11389571072 1977285408 19.443 100 81 162 0 2056465104 51751168 27428528
20:53:57 7328 8192000000 10929314384 11376349184 1949885264 19.226 99 42 84 2 2014047072 49356320 14805488
20:55:16 3552 8192000000 10943928976 11373641728 1966053872 19.354 100 58 116 0 2025597696 40923952 18619872
20:56:23 7395 8192000000 10938744704 11384315904 1968913104 19.377 100 41 79 0 2024892144 43689792 12289248
20:57:38 17465 8192000000 10914723808 11358060544 1942209376 19.164 99 32 64 3 1986058576 33240400 10608800
20:59:02 26244 8192000000 10926342768 11372920832 1951175456 19.236 99 29 58 1 1999775072 38519856 10079760
21:00:33 33141 8192000000 10884471968 11333189632 1878436240 18.652 96 25 49 1 1920240400 33936624 7867536
21:02:22 39426 8192000000 10878792064 11346046976 1908542416 18.895 99 19 38 2 1957364512 41862096 6960000
21:04:02 11711 8192000000 10886165888 11323224064 1911360912 18.918 99 42 86 1 1950743088 25664960 13717216
21:05:19 24159 8192000000 10867933472 11323617280 1934609520 19.104 100 26 52 2 1953682496 11383152 7689824
21:06:52 111138 8192000000 10863463088 11324555264 1884100256 18.698 96 22 44 0 1900697312 11119088 5477968
21:10:06 80082 8192000000 10879764960 11336269824 1928066496 19.051 99 15 30 0 1943586720 11534496 3985728
21:12:26 85898 8192000000 10891985664 11336531968 1938698960 19.136 99 18 35 2 1957495728 13493344 5303424
21:15:02 47448 8192000000 10880147680 11335032832 1933764992 19.097 98 26 52 1 1948601136 7226576 7609568
21:16:54 59937 8192000000 10893937584 11356659712 1972413280 19.405 100 28 56 0 1994531872 14296608 7821984
21:18:54 36102 8192000000 10898162288 11357982720 1968274656 19.372 100 24 48 3 1986275200 11083216 6917328
21:20:32 53859 8192000000 10895557232 11369676800 1957852432 19.289 99 14 28 2 1976129040 14556384 3720224
21:22:26 65131 8192000000 10921806192 11364851712 1972325216 19.404 100 18 35 0 1994574864 17146224 5103424
21:24:36 77998 8192000000 10902726496 11375951872 1964487744 19.342 99 12 24 0 1980537296 12635280 3414272
21:26:58 28459 8192000000 10897396192 11353378816 1919068656 18.979 97 28 56 1 1936468384 8839408 8560320
21:28:28 49935 8192000000 10892857872 11358085120 1956523904 19.278 99 33 66 0 1976855600 11092048 9239648
21:30:20 80389 8192000000 10884677040 11350994944 1894923920 18.785 96 10 20 0 1905885104 7996864 2964320
21:32:49 85964 8192000000 10886727040 11368636416 1910957488 18.914 97 17 34 1 1936168128 20564720 4645920
21:35:15 153408 8192000000 10892090272 11336318976 1772088784 17.784 90 15 30 1 1794133872 18364784 3680304
21:38:49 53068 8192000000 10889226464 11369201664 1953598160 19.255 100 13 26 0 1974596432 17410832 3587440
21:41:13 139171 8192000000 10885235200 11354411008 1902942160 18.850 96 31 62 1 1923338704 12469504 7927040
21:44:39 50135 8192000000 10885916976 11356360704 1936146192 19.116 99 14 28 1 1956773888 17081184 3546512
21:46:31 83509 8192000000 10876493952 11353325568 1887635856 18.727 96 13 26 1 1911171200 19440640 4094704
21:49:00 106058 8192000000 10922386304 11390316544 1946064144 19.195 98 19 38 1 1965546080 13896864 5585072
21:51:47 117626 8192000000 10861844992 11325550592 1808346032 18.082 92 17 34 0 1826875632 13017648 5511952
21:55:03 83093 8192000000 10876674704 11324534784 1837597456 18.321 94 28 54 0 1855333744 10115936 7620352
21:57:28 43127 8192000000 10877976096 11343228928 1939682512 19.144 100 22 44 0 1959866592 14390064 5794016
21:59:15 111832 8192000000 10884909744 11346644992 1879513920 18.661 96 15 31 0 1894940384 11496736 3929728
22:02:12 114022 8192000000 10894894960 11342598144 1953421200 19.254 100 20 40 2 1982290000 22525520 6343280
22:05:08 43782 8192000000 10891214768 11355525120 1933118496 19.092 98 30 60 0 1950019792 8968688 7932608
22:06:59 64470 8192000000 10894847296 11371704320 1960966240 19.314 99 11 22 0 1969797056 6003216 2827600
22:09:26 56767 8192000000 10887224992 11370070016 1950105600 19.227 98 6 12 0 1959702656 8229808 1367248
22:11:23 94685 8192000000 10886909408 11362217984 1906048752 18.875 96 15 30 1 1924254160 14153472 4051936
22:13:59 57493 8192000000 10895822960 11357945856 1918549200 18.975 97 21 42 1 1933401360 9062080 5790080
22:16:04 26735 8192000000 10905109520 11373654016 1978685984 19.454 100 27 55 1 2000123808 13478288 7959536
22:17:36 58473 8192000000 10906282224 11376992256 1964590880 19.343 99 27 54 2 1984194736 11936672 7667184
22:19:42 62278 8192000000 10915065776 11383980032 1984603008 19.501 100 27 54 1 2004723344 12514224 7606112
22:21:45 76617 8192000000 10914217392 11375534080 1907242672 18.885 96 17 34 1 1924048896 12850032 3956192
22:24:05 78592 8192000000 10897004736 11376472064 1954063600 19.259 99 18 36 1 1972551776 13464288 5023888
22:26:24 104487 8192000000 10851872768 11321851904 1866199168 18.554 95 14 27 0 1878224128 7260560 4764400
22:29:32 76221 8192000000 10876525840 11360161792 1912584384 18.927 97 8 16 0 1924561152 9758400 2218368
22:31:55 54876 8192000000 10879857712 11354099712 1918977216 18.979 97 14 28 0 1939014016 15894048 4142752
22:33:50 75344 8192000000 10862693584 11341676544 1917069392 18.963 98 12 24 1 1943788160 22728416 3990352
22:36:16 32512 8192000000 10886465632 11348733952 1916784592 18.961 98 21 41 0 1930404736 8060752 5559392

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-09-2021 22:39:18 to 14-09-2021 22:39:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 171 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

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

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          250028
MON$OLDEST_ACTIVE               250029
MON$OLDEST_SNAPSHOT             250029
MON$NEXT_TRANSACTION            250029
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                       2009088
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.33495 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33495 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33495 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33495 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 ---
--- end of diff output ---

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

14.09.21 22:39:37. 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-14 22:39:39 No severe PSQL-related problems occured
14.09.21 22:39:40. End of report.