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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16019812 kB
MemAvailable 30833540 kB
Buffers 0 kB
Cached 15645476 kB
SwapCached 400 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-11 01:37:51
DTS_END                         2021-09-11 01:37:51.2100
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/52866
TRN_ID                          238829
ATT_ID                          687
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: 9460.65 17481 1135278
customer order: creation 176.41 331 21169
customer order: refuse 14.72 361 1766
order to supplier: creation 46.03 1262 5523
order to supplier: removal 3.51 103617 421
invoice (draft): creation 46.21 3251 5545
invoice (draft): removal 53.29 844 6395
invoice accept: apply 43.17 19176 5180
invoice accept: cancel 48.54 765 5825
customer reserve: creation 3955.05 191 474606
customer reserve: removal 4776.93 59 573232
realization accept: apply 83.39 187 10007
realization accept: cancel 81.54 113 9785
payment from customer: creation 59.97 127 7196
payment from customer: removal 5.93 111 712
payment to supplier: creation 49.83 138 5979
payment to supplier: removal 3.54 51 425
service: total inventory turnovers 4.79 1141 575
service: total monetary turnovers 7.03 33 843
service: aggregate perf. data 0.75 23491 90
service: refresh index statistics 0.03 194372 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 11-09-2021 01:38:36 to 11-09-2021 01:38:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2874
TEST_TIME 2 3236
TEST_TIME 3 4589
TEST_TIME 4 4124
TEST_TIME 5 4784
TEST_TIME 6 4811
TEST_TIME 7 5328
TEST_TIME 8 5452
TEST_TIME 9 5500
TEST_TIME 10 6099
TEST_TIME 11 6170
TEST_TIME 12 6313
TEST_TIME 13 6466
TEST_TIME 14 6558
TEST_TIME 15 6597
TEST_TIME 16 7024
TEST_TIME 17 7040
TEST_TIME 18 7093
TEST_TIME 19 7769
TEST_TIME 20 7790
TEST_TIME 21 7893
TEST_TIME 22 8198
TEST_TIME 23 8034
TEST_TIME 24 8121
TEST_TIME 25 8048
TEST_TIME 26 8432
TEST_TIME 27 8472
TEST_TIME 28 8726
TEST_TIME 29 8896
TEST_TIME 30 9118
TEST_TIME 31 9974
TEST_TIME 32 10661
TEST_TIME 33 10882
TEST_TIME 34 10800
TEST_TIME 35 10542
TEST_TIME 36 10539
TEST_TIME 37 10309
TEST_TIME 38 10243
TEST_TIME 39 10290
TEST_TIME 40 10229
TEST_TIME 41 10027
TEST_TIME 42 10026
TEST_TIME 43 9923
TEST_TIME 44 9787
TEST_TIME 45 10067
TEST_TIME 46 10062
TEST_TIME 47 10038
TEST_TIME 48 9941
TEST_TIME 49 9900
TEST_TIME 50 9863
TEST_TIME 51 9841
TEST_TIME 52 9841
TEST_TIME 53 9686
TEST_TIME 54 9655
TEST_TIME 55 9582
TEST_TIME 56 9516
TEST_TIME 57 9523
TEST_TIME 58 9565
TEST_TIME 59 9534
TEST_TIME 60 9518
TEST_TIME 61 9623
TEST_TIME 62 9571
TEST_TIME 63 9687
TEST_TIME 64 9654
TEST_TIME 65 9676
TEST_TIME 66 9724
TEST_TIME 67 9745
TEST_TIME 68 9817
TEST_TIME 69 9888
TEST_TIME 70 9974
TEST_TIME 71 9961
TEST_TIME 72 9856
TEST_TIME 73 9796
TEST_TIME 74 9811
TEST_TIME 75 9761
TEST_TIME 76 9755
TEST_TIME 77 9709
TEST_TIME 78 9672
TEST_TIME 79 9685
TEST_TIME 80 9589
TEST_TIME 81 9530
TEST_TIME 82 9492
TEST_TIME 83 9482
TEST_TIME 84 9464
TEST_TIME 85 9484
TEST_TIME 86 9465
TEST_TIME 87 9428
TEST_TIME 88 9516
TEST_TIME 89 9482
TEST_TIME 90 9558
TEST_TIME 91 9599
TEST_TIME 92 9661
TEST_TIME 93 9732
TEST_TIME 94 9736
TEST_TIME 95 9697
TEST_TIME 96 9839
TEST_TIME 97 9766
TEST_TIME 98 9689
TEST_TIME 99 9658
TEST_TIME 100 9640
TEST_TIME 101 9593
TEST_TIME 102 9551
TEST_TIME 103 9541
TEST_TIME 104 9524
TEST_TIME 105 9485
TEST_TIME 106 9430
TEST_TIME 107 9428
TEST_TIME 108 9406
TEST_TIME 109 9366
TEST_TIME 110 9327
TEST_TIME 111 9339
TEST_TIME 112 9375
TEST_TIME 113 9376
TEST_TIME 114 9379
TEST_TIME 115 9378
TEST_TIME 116 9429
TEST_TIME 117 9426
TEST_TIME 118 9437
TEST_TIME 119 9439
TEST_TIME 120 9463

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-09-2021 01:38:40 to 11-09-2021 01:38:43.

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 1574427 1574427 0 0.00 0 21573 7 0 0
doc_list_biud 1574427 1574427 0 0.00 0 18680 27 0 0
sp_add_invoice_to_stock 5339 5180 159 2.97 39 185241 19177 0 159
sp_cancel_adding_invoice 5825 5825 0 0.00 4 69283 765 0 0
sp_cancel_client_order 1766 1766 0 0.00 4 9902 362 0 0
sp_cancel_customer_reserve 573232 573232 0 0.00 1 18450 59 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 3 6635 112 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 2593 51 0 0
sp_cancel_supplier_invoice 6407 6395 12 0.18 2 16997 845 0 12
sp_cancel_supplier_order 421 421 0 0.00 7 1050923 103617 0 0
sp_cancel_write_off 9785 9785 0 0.00 1 11197 113 0 0
sp_client_order 21169 21169 0 0.00 3 23717 332 0 0
sp_customer_reserve 474606 474606 0 0.00 0 65876 192 0 0
sp_fill_shopping_cart 32224 32224 0 0.00 1 17251 38 0 0
sp_fill_shopping_cart_clo_res 474600 474600 0 0.00 0 11869 15 0 0
sp_get_clo_for_invoice 5174 5174 0 0.00 0 18040 294 0 0
sp_kill_cost_storno 16747 16747 0 0.00 0 6256 59 0 0
sp_kill_qstorno_ret_qs2qd 1163065 1163065 0 0.00 0 18676 32 0 0
sp_kill_qty_storno 612604 612604 0 0.00 0 18679 69 0 0
sp_lock_selected_doc 20963 20963 0 0.00 0 8439 7 0 0
sp_make_cost_storno 28355 28355 0 0.00 0 21309 58 0 0
sp_multiply_rows_for_pdistr 28355 28355 0 0.00 0 10937 29 0 0
sp_multiply_rows_for_qdistr 21169 21169 0 0.00 0 12165 145 0 0
sp_pay_from_customer 7196 7196 0 0.00 4 13964 128 0 0
sp_pay_to_supplier 5979 5979 0 0.00 5 13195 138 0 0
sp_payment_common 13175 13175 0 0.00 1 12022 33 0 0
sp_qd_handle_on_cancel_clo 1766 1766 0 0.00 0 9887 331 0 0
sp_qd_handle_on_invoice_upd_sts 10998 10998 0 0.00 0 16168 321 0 0
sp_qd_handle_on_reserve_upd_sts 10007 10007 0 0.00 0 11720 54 0 0
sp_reserve_write_off 10007 10007 0 0.00 6 19409 188 0 0
sp_supplier_invoice 5545 5545 0 0.00 7 98041 3252 0 0
sp_supplier_order 5523 5523 0 0.00 5 39440 1262 0 0
srv_aggregate_perf_data 90 90 0 0.00 2763 468231 23492 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1942 99783 42368 0 0
srv_find_qd_qs_mism 1086367 1086367 0 0.00 0 15115 6 0 0
srv_make_invnt_saldo 575 575 0 0.00 25 49238 1142 0 0
srv_make_money_saldo 843 843 0 0.00 1 1985 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 97987 318759 194372 0 0
t$perf-abend:sp_add_invoice_to_stock 159 159 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5181 5181 0 0.00 0 11673 186 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 3586 84 0 0
t$perf-norm:sp_cancel_client_order 1766 1766 0 0.00 0 8536 17 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 5004 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 8141 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 3593 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 2952 25 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 29165 1930 0 0
t$perf-norm:sp_cancel_write_off 970 970 0 0.00 0 8079 30 0 0
t$perf-norm:sp_client_order 21169 21169 0 0.00 0 16113 22 0 0
t$perf-norm:sp_customer_reserve 1005 1005 0 0.00 0 575 3 0 0
t$perf-norm:sp_pay_from_customer 7196 7196 0 0.00 0 5008 10 0 0
t$perf-norm:sp_pay_to_supplier 5979 5979 0 0.00 0 4221 11 0 0
t$perf-norm:sp_reserve_write_off 10007 10007 0 0.00 0 8235 13 0 0
t$perf-norm:sp_supplier_invoice 5545 5545 0 0.00 0 5265 21 0 0
t$perf-norm:sp_supplier_order 5523 5523 0 0.00 0 6589 21 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 1970 31 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 12 1 0 0
t$perf-norm:srv_make_invnt_saldo 575 575 0 0.00 0 1120 19 0 0
t$perf-norm:srv_make_money_saldo 843 843 0 0.00 0 1030 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 14 5 0 0
v_add_invoice_to_stock 10664 10346 318 2.98 0 9382 21 0 318
v_all_customers 38656 38656 0 0.00 0 188 1 0 0
v_all_suppliers 31024 31024 0 0.00 0 19 1 0 0
v_all_wares 42338 42338 0 0.00 0 48 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 14291 30 0 0
v_cancel_client_order 3532 3532 0 0.00 0 4145 6 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 4483 14 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 2230 6 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 2427 8 0 0
v_cancel_supplier_invoice 808 784 24 2.97 0 1866 17 0 24
v_cancel_supplier_order 842 842 0 0.00 0 9891 15 0 0
v_cancel_write_off 1940 1940 0 0.00 0 10948 25 0 0
v_max_id_clo_ord 5521 5521 0 0.00 0 2563 2 0 0
v_max_id_clo_res 1005 1005 0 0.00 0 1478 17 0 0
v_max_id_ord_sup 5534 5534 0 0.00 0 4705 5 0 0
v_max_non_paid_invoice 1522 1522 0 0.00 0 1076 3 0 0
v_max_non_paid_realizn 3746 3746 0 0.00 0 1763 4 0 0
v_min_id_clo_ord 5521 5521 0 0.00 0 4006 9 0 0
v_min_id_clo_res 1005 1005 0 0.00 0 1451 7 0 0
v_min_id_ord_sup 5534 5534 0 0.00 0 4283 7 0 0
v_min_non_paid_invoice 5979 5979 0 0.00 0 6609 21 0 0
v_min_non_paid_realizn 7196 7196 0 0.00 0 8654 22 0 0
v_our_firm 10582 10582 0 0.00 0 14 1 0 0
v_reserve_write_off 20014 20014 0 0.00 0 18024 12 0 0
x_lock_depdocs_on_canc_invoice 11828 11828 0 0.00 0 10617 120 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 14301 388 0 0
x_make_qstorno_1000_1200 5523 5523 0 0.00 2 39431 1168 0 0
x_make_qstorno_1200_2000 5545 5545 0 0.00 3 98029 3129 0 0
x_make_qstorno_2100_3300 474606 474606 0 0.00 0 65874 174 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-09-2021 01:38:44 to 11-09-2021 01:38:48.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:07:54 3 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:56 30631 8192000000 10363461824 10683805696 1420655728 14.779 100 83 166 0 1453920960 5854864 27410368
23:10:30 14708 8192000000 10427145440 10755252224 1476992896 15.275 100 87 174 0 1513435168 7855280 28586992
23:11:49 19957 8192000000 10478210736 10815860736 1529244864 15.730 100 87 174 0 1565596976 9281984 27070128
23:13:14 9046 8192000000 10554116592 10908700672 1613943216 16.458 100 78 156 0 1647688912 10525840 23219856
23:14:26 9524 8192000000 10643431888 11010666496 1698461072 17.172 100 80 158 0 1733247488 11886624 22899792
23:15:40 8060 8192000000 10704271216 11082272768 1761269744 17.695 100 77 154 0 1795035504 10992224 22773536
23:16:50 4381 8192000000 10753668112 11135418368 1808748896 18.086 100 89 176 2 1848033728 14486832 24798000
23:17:56 6944 8192000000 10767127616 11155542016 1827956320 18.243 100 79 157 3 1862913520 14081456 20875744
23:19:05 2412 8192000000 10818186272 11206324224 1868745888 18.574 100 93 187 3 1906520944 12069584 25705472
23:20:09 20442 8192000000 10814423104 11215269888 1876864624 18.640 100 70 139 1 1906847952 9527040 20456288
23:21:31 10246 8192000000 10782160608 11212529664 1877977520 18.649 100 37 73 2 1893963120 6578752 9406848
23:22:44 6261 8192000000 10795059024 11226091520 1884875104 18.704 100 25 51 0 1902803936 10468336 7460496
23:23:51 13982 8192000000 10801095232 11236974592 1895088592 18.787 100 12 24 0 1904318272 5851824 3377856
23:25:05 79034 8192000000 10813844592 11257421824 1907168016 18.884 100 29 58 1 1920848976 5260464 8420496
23:27:27 6356 8192000000 10807736240 11236585472 1882946112 18.689 99 25 51 0 1895599072 5384640 7268320
23:28:33 8114 8192000000 10802109072 11229339648 1882610560 18.686 100 19 37 1 1892941824 4909856 5421408
23:29:42 26240 8192000000 10770008752 11183484928 1780718384 17.855 96 22 45 1 1792308032 5122928 6466720
23:31:09 7597 8192000000 10742240496 11167969280 1814553760 18.133 98 36 72 0 1832291280 7413648 10323872
23:32:18 6127 8192000000 10683614432 11101478912 1747913552 17.584 98 38 76 0 1765938512 6997312 11027648
23:33:26 11318 8192000000 10720219456 11139096576 1772757216 17.790 98 16 31 0 1782228672 4934240 4537216
23:34:41 13704 8192000000 10756562288 11168964608 1832573488 18.280 100 20 39 2 1845072304 5857568 6641248
23:35:55 30143 8192000000 10753627248 11181563904 1829572416 18.256 99 22 44 1 1841558688 5427520 6558752
23:37:28 5251 8192000000 10806123632 11224363008 1883177040 18.691 100 25 48 0 1896047248 5847040 7023168
23:38:36 3786 8192000000 10831043488 11249704960 1909955776 18.906 100 52 105 3 1932265392 6411584 15898032
23:39:45 29725 8192000000 10858014752 11285835776 1929732864 19.065 100 54 108 2 1957231296 12512560 14985872
23:41:18 73929 8192000000 10867897536 11306336256 1939010560 19.139 100 50 98 2 1966756672 13618016 14128096
23:43:50 14288 8192000000 10878295568 11312365568 1934961296 19.107 99 38 76 0 1964373712 18614576 10797840
23:45:10 11223 8192000000 10903822912 11328487424 1966319440 19.356 100 66 132 0 2002383872 16998176 19066256
23:46:39 16015 8192000000 10912600320 11342139392 1956974592 19.282 99 57 112 1 1992523952 18867424 16681936
23:48:01 4815 8192000000 10933177296 11359002624 1985421376 19.508 100 66 132 2 2023217200 17770272 20025552
23:49:11 10038 8192000000 10941759920 11369082880 1991996208 19.560 100 61 121 1 2031756576 21508080 18252288
23:50:27 4122 8192000000 10953836336 11381411840 1989704336 19.541 100 62 123 1 2039284880 30662192 18918352
23:51:37 1939 8192000000 10975498640 11392745472 1998564240 19.611 100 79 153 1 2052719104 30648784 23506080
23:52:45 3492 8192000000 10968338560 11388538880 1986136736 19.513 99 78 155 1 2052317280 41612960 24567584
23:53:57 9196 8192000000 10979470576 11407867904 2001539216 19.635 100 70 137 2 2067468320 44560000 21369104
23:55:11 3206 8192000000 10991736848 11403792384 2000052464 19.623 100 90 178 1 2073272448 46404896 26815088
23:56:19 5839 8192000000 11009792448 11428360192 2011884912 19.716 100 82 164 1 2086512080 50022160 24605008
23:57:30 14152 8192000000 10961520240 11403513856 1977966880 19.449 99 41 82 0 2037162768 45742656 13453232
23:59:16 5790 8192000000 10973452352 11400667136 1986214752 19.514 100 62 123 2 2053981248 49379536 18386960
00:00:26 7187 8192000000 10923532976 11366596608 1938375280 19.134 99 37 74 0 2003460864 53233024 11852560
00:01:47 3893 8192000000 10966349184 11391574016 1952770176 19.249 99 61 122 1 2033835488 61664848 19400464
00:03:00 13422 8192000000 10966008432 11407155200 1974474048 19.421 100 43 86 0 2055254352 66396464 14383840
00:04:44 23148 8192000000 10946418944 11393744896 1945474416 19.190 99 24 48 1 2017027584 62531056 9022112
00:06:16 24281 8192000000 10936441152 11381256192 1955357888 19.269 100 25 50 3 2021514816 57390288 8766640
00:07:46 59502 8192000000 10920483760 11359895552 1888332928 18.732 97 19 38 0 1955744480 60308848 7102704
00:09:47 40551 8192000000 10911311024 11356700672 1957446704 19.286 100 10 21 0 1979169024 19013328 2708992
00:11:32 36283 8192000000 10883897456 11336118272 1933663216 19.096 99 32 63 0 1954132704 11397776 9071712
00:13:09 65471 8192000000 10895634576 11336323072 1924261520 19.021 98 40 79 4 1946833968 11324816 11247632
00:15:17 56860 8192000000 10891221664 11344068608 1938122000 19.132 99 39 75 2 1957461840 8989632 10350208
00:17:17 86500 8192000000 10891870960 11350261760 1934148816 19.100 99 21 42 0 1958395024 18157888 6088320
00:19:46 32535 8192000000 10888811936 11358068736 1960807808 19.312 100 18 36 0 1984218128 18239872 5170448
00:21:31 27134 8192000000 10911113296 11360501760 1960509008 19.310 100 41 82 1 1997493776 23504144 13480624
00:23:18 42320 8192000000 10890655632 11374051328 1964277680 19.340 100 15 30 1 1987636256 18840048 4518528
00:25:06 43616 8192000000 10893588784 11352252416 1951451280 19.238 99 19 38 0 1966773424 9704976 5617168
00:26:57 20433 8192000000 10883768528 11357212672 1930673632 19.072 98 16 32 1 1951036320 16028416 4334272
00:28:17 65756 8192000000 10889570672 11377872896 1971935696 19.401 100 11 22 1 1988299472 12978304 3385472
00:30:24 28518 8192000000 10882906432 11358191616 1952537648 19.247 99 7 14 0 1963384640 9017008 1829984
00:31:56 68909 8192000000 10881815568 11363430400 1961189552 19.315 99 24 48 1 1977441472 9709712 6542208
00:34:23 74127 8192000000 10893063968 11370987520 1966284400 19.356 99 19 37 0 1983649648 11930112 5435136
00:36:39 26722 8192000000 10915628576 11374637056 1965058736 19.346 99 29 58 0 1981717696 8798432 7860528
00:38:07 68020 8192000000 10933863680 11403485184 1997451984 19.603 100 13 24 2 2016848448 16251728 3144736
00:40:16 67930 8192000000 10907144848 11381022720 1942463680 19.166 97 9 18 2 1961838208 16570336 2804192
00:42:29 53391 8192000000 10900771008 11385344000 1954503392 19.262 98 37 75 0 1978130672 12427280 11200000
00:44:25 45375 8192000000 10902094400 11363405824 1937549952 19.127 98 26 52 0 1967632752 22884960 7197840
00:46:11 81234 8192000000 10906835632 11388301312 1926737616 19.041 98 8 16 0 1950683952 21709792 2236544
00:48:33 99783 8192000000 10849519184 11306651648 1841167136 18.350 95 30 58 1 1862577936 12184576 9226224
00:51:14 38832 8192000000 10867727184 11343200256 1942503872 19.167 100 16 32 0 1962299744 14910960 4884912
00:52:57 41976 8192000000 10867065344 11346137088 1945426208 19.190 100 9 18 0 1954904672 6751824 2726640
00:54:39 91575 8192000000 10861069408 11345149952 1915063792 18.947 98 11 22 0 1930824880 12763440 2997648
00:57:11 51682 8192000000 10837334976 11320688640 1905706368 18.872 99 8 16 0 1917150736 9449680 1994688
00:59:10 50297 8192000000 10827893488 11309686784 1911917488 18.922 100 7 14 0 1928436864 14862432 1656944
01:01:11 45671 8192000000 10833426208 11306364928 1896585088 18.799 99 24 48 1 1915532560 11950960 6996512
01:02:58 44546 8192000000 10862853920 11324092416 1902747472 18.848 99 20 40 1 1918651184 10091552 5812160
01:04:47 54697 8192000000 10873511280 11357421568 1928176848 19.052 99 18 36 1 1948374720 14741808 5456064
01:06:55 84816 8192000000 10870511328 11343208448 1856852144 18.478 95 11 22 0 1878157200 18406080 2898976
01:09:27 61571 8192000000 10868765456 11352576000 1889901792 18.745 96 29 57 0 1919290096 21338848 8049456
01:12:09 37137 8192000000 10882547040 11344343040 1945483216 19.190 100 26 52 0 1968727120 15745552 7498352
01:14:04 82161 8192000000 10875590192 11362406400 1951598576 19.239 100 8 16 0 1968843440 15177744 2067120
01:16:44 47805 8192000000 10875980528 11357917184 1963237104 19.332 100 16 32 0 1977268848 9813696 4218048
01:18:32 85634 8192000000 10898841440 11374432256 1980250544 19.467 100 10 20 0 1990561024 6870208 3440272
01:20:58 72857 8192000000 10904144160 11387748352 1988076656 19.529 100 13 26 1 2004948464 13345920 3525888
01:23:11 93809 8192000000 10891932544 11372732416 1935373808 19.110 97 31 62 0 1953835632 9272688 9189136
01:25:59 31333 8192000000 10896101520 11364048896 1943799488 19.177 98 20 40 0 1958853392 8837296 6216608
01:28:44 68282 8192000000 10901599840 11386429440 1960261888 19.308 99 12 24 1 1985449312 22346912 2840512
01:30:56 50993 8192000000 10879775936 11347283968 1845539248 18.386 93 24 48 0 1861327584 8207072 7581264
01:32:47 21566 8192000000 10891362112 11356561408 1925050416 19.027 98 33 66 1 1950204480 16011664 9142400
01:34:12 64317 8192000000 10877074384 11352305664 1905077120 18.867 97 30 60 0 1925813360 12344608 8391632
01:36:19 57820 8192000000 10877202768 11335532544 1913577824 18.935 98 16 32 0 1930205088 12269104 4358160

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-09-2021 01:38:50 to 11-09-2021 01:39:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 159 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

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

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          237208
MON$OLDEST_ACTIVE               238950
MON$OLDEST_SNAPSHOT             238950
MON$NEXT_TRANSACTION            238950
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                       2042144
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.33494 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33494 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33494 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33494 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 ---
:97: 
:98: mscs-test-161.budzdorov.ru    Sat Sep 11 01:38:02 2021
:99:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:100: 
--- end of diff output ---

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

11.09.21 01:39:09. 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-11 01:39:11 No severe PSQL-related problems occured
11.09.21 01:39:13. End of report.