Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.11.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 16215136 kB
MemAvailable 30804060 kB
Buffers 0 kB
Cached 15469808 kB
SwapCached 2492 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-11-04 01:37:56
DTS_END                         2021-11-04 01:37:56.2220
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/48712
TRN_ID                          242705
ATT_ID                          722
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: 9387.53 22532 1126503
customer order: creation 174.28 364 20914
customer order: refuse 15.32 379 1838
order to supplier: creation 47.26 1228 5671
order to supplier: removal 3.24 115637 389
invoice (draft): creation 45.92 3191 5510
invoice (draft): removal 53.38 785 6406
invoice accept: apply 42.36 17781 5083
invoice accept: cancel 48.38 821 5806
customer reserve: creation 3901.24 177 468149
customer reserve: removal 4756.44 62 570773
realization accept: apply 85.31 165 10237
realization accept: cancel 82.94 119 9953
payment from customer: creation 60.77 111 7292
payment from customer: removal 5.83 98 700
payment to supplier: creation 49.44 114 5933
payment to supplier: removal 3.34 64 401
service: total inventory turnovers 4.58 989 550
service: total monetary turnovers 6.56 24 787
service: aggregate perf. data 0.89 18461 107
service: refresh index statistics 0.03 290079 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-11-2021 01:38:51 to 04-11-2021 01:38:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2663
TEST_TIME 2 4162
TEST_TIME 3 4666
TEST_TIME 4 4512
TEST_TIME 5 4280
TEST_TIME 6 4750
TEST_TIME 7 4495
TEST_TIME 8 4374
TEST_TIME 9 4546
TEST_TIME 10 4617
TEST_TIME 11 4760
TEST_TIME 12 4933
TEST_TIME 13 5158
TEST_TIME 14 5278
TEST_TIME 15 5918
TEST_TIME 16 6907
TEST_TIME 17 7175
TEST_TIME 18 7079
TEST_TIME 19 7156
TEST_TIME 20 8224
TEST_TIME 21 8526
TEST_TIME 22 8878
TEST_TIME 23 8957
TEST_TIME 24 9492
TEST_TIME 25 10552
TEST_TIME 26 10737
TEST_TIME 27 10930
TEST_TIME 28 11005
TEST_TIME 29 10957
TEST_TIME 30 10643
TEST_TIME 31 10541
TEST_TIME 32 10632
TEST_TIME 33 10399
TEST_TIME 34 10314
TEST_TIME 35 10196
TEST_TIME 36 10197
TEST_TIME 37 10015
TEST_TIME 38 9784
TEST_TIME 39 9657
TEST_TIME 40 9697
TEST_TIME 41 9858
TEST_TIME 42 9763
TEST_TIME 43 9825
TEST_TIME 44 9773
TEST_TIME 45 9741
TEST_TIME 46 9813
TEST_TIME 47 9885
TEST_TIME 48 9829
TEST_TIME 49 9874
TEST_TIME 50 9867
TEST_TIME 51 9975
TEST_TIME 52 9922
TEST_TIME 53 10016
TEST_TIME 54 9888
TEST_TIME 55 9763
TEST_TIME 56 9719
TEST_TIME 57 9683
TEST_TIME 58 9625
TEST_TIME 59 9660
TEST_TIME 60 9591
TEST_TIME 61 9612
TEST_TIME 62 9562
TEST_TIME 63 9479
TEST_TIME 64 9492
TEST_TIME 65 9502
TEST_TIME 66 9543
TEST_TIME 67 9511
TEST_TIME 68 9539
TEST_TIME 69 9652
TEST_TIME 70 9667
TEST_TIME 71 9601
TEST_TIME 72 9668
TEST_TIME 73 9740
TEST_TIME 74 9712
TEST_TIME 75 9827
TEST_TIME 76 9765
TEST_TIME 77 9750
TEST_TIME 78 9759
TEST_TIME 79 9657
TEST_TIME 80 9634
TEST_TIME 81 9560
TEST_TIME 82 9519
TEST_TIME 83 9580
TEST_TIME 84 9520
TEST_TIME 85 9474
TEST_TIME 86 9390
TEST_TIME 87 9366
TEST_TIME 88 9382
TEST_TIME 89 9344
TEST_TIME 90 9424
TEST_TIME 91 9459
TEST_TIME 92 9438
TEST_TIME 93 9465
TEST_TIME 94 9440
TEST_TIME 95 9499
TEST_TIME 96 9549
TEST_TIME 97 9550
TEST_TIME 98 9570
TEST_TIME 99 9589
TEST_TIME 100 9576
TEST_TIME 101 9615
TEST_TIME 102 9605
TEST_TIME 103 9564
TEST_TIME 104 9495
TEST_TIME 105 9454
TEST_TIME 106 9470
TEST_TIME 107 9471
TEST_TIME 108 9421
TEST_TIME 109 9371
TEST_TIME 110 9388
TEST_TIME 111 9368
TEST_TIME 112 9306
TEST_TIME 113 9321
TEST_TIME 114 9307
TEST_TIME 115 9315
TEST_TIME 116 9331
TEST_TIME 117 9317
TEST_TIME 118 9338
TEST_TIME 119 9331
TEST_TIME 120 9398

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-11-2021 01:38:55 to 04-11-2021 01:38:58.

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 1559829 1559829 0 0.00 0 15969 6 0 0
doc_list_biud 1559827 1559827 0 0.00 0 40240 29 0 0
sp_add_invoice_to_stock 5228 5083 145 2.77 57 169598 17781 0 145
sp_cancel_adding_invoice 5806 5806 0 0.00 4 54406 821 0 0
sp_cancel_client_order 1838 1838 0 0.00 4 17094 379 0 0
sp_cancel_customer_reserve 570773 570773 0 0.00 1 40242 63 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 2 5823 99 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 3 1986 65 0 0
sp_cancel_supplier_invoice 6420 6406 14 0.21 1 12548 786 0 14
sp_cancel_supplier_order 389 389 0 0.00 8 1111674 115637 0 0
sp_cancel_write_off 9953 9953 0 0.00 1 7923 120 0 0
sp_client_order 20914 20914 0 0.00 3 22500 365 0 0
sp_customer_reserve 468149 468149 0 0.00 0 80985 177 0 0
sp_fill_shopping_cart 32090 32090 0 0.00 1 19676 37 0 0
sp_fill_shopping_cart_clo_res 468132 468132 0 0.00 0 15543 14 0 0
sp_get_clo_for_invoice 5064 5064 0 0.00 0 21489 286 0 0
sp_kill_cost_storno 16860 16860 0 0.00 0 7542 58 0 0
sp_kill_qstorno_ret_qs2qd 1158294 1158294 0 0.00 0 40200 34 0 0
sp_kill_qty_storno 610464 610464 0 0.00 0 40239 72 0 0
sp_lock_selected_doc 21279 21279 0 0.00 0 8134 6 0 0
sp_make_cost_storno 28524 28524 0 0.00 0 15112 50 0 0
sp_multiply_rows_for_pdistr 28524 28524 0 0.00 0 13581 29 0 0
sp_multiply_rows_for_qdistr 20914 20914 0 0.00 0 10537 159 0 0
sp_pay_from_customer 7292 7292 0 0.00 4 18142 111 0 0
sp_pay_to_supplier 5933 5933 0 0.00 5 17827 114 0 0
sp_payment_common 13225 13225 0 0.00 1 18138 25 0 0
sp_qd_handle_on_cancel_clo 1838 1838 0 0.00 0 15187 345 0 0
sp_qd_handle_on_invoice_upd_sts 10868 10868 0 0.00 0 26105 321 0 0
sp_qd_handle_on_reserve_upd_sts 10237 10237 0 0.00 0 18687 55 0 0
sp_reserve_write_off 10237 10237 0 0.00 6 18717 166 0 0
sp_supplier_invoice 5510 5510 0 0.00 6 80113 3192 0 0
sp_supplier_order 5671 5671 0 0.00 6 30844 1229 0 0
srv_aggregate_perf_data 107 107 0 0.00 3021 249841 18462 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2020 115910 44367 0 0
srv_find_qd_qs_mism 1077987 1077987 0 0.00 0 15968 6 0 0
srv_make_invnt_saldo 550 550 0 0.00 37 38926 990 0 0
srv_make_money_saldo 787 787 0 0.00 1 1708 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 94702 473490 290079 0 0
t$perf-abend:sp_add_invoice_to_stock 145 145 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5084 5084 0 0.00 0 16874 158 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 1 3669 89 0 0
t$perf-norm:sp_cancel_client_order 1838 1838 0 0.00 0 2633 10 0 0
t$perf-norm:sp_cancel_customer_reserve 739 739 0 0.00 0 6545 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 2218 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 480 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 453 453 0 0.00 0 7269 48 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 16549 1673 0 0
t$perf-norm:sp_cancel_write_off 1039 1039 0 0.00 0 986 5 0 0
t$perf-norm:sp_client_order 20914 20914 0 0.00 0 11120 19 0 0
t$perf-norm:sp_customer_reserve 1045 1045 0 0.00 0 1569 5 0 0
t$perf-norm:sp_pay_from_customer 7292 7292 0 0.00 0 6847 9 0 0
t$perf-norm:sp_pay_to_supplier 5933 5933 0 0.00 0 4670 11 0 0
t$perf-norm:sp_reserve_write_off 10237 10237 0 0.00 0 8044 11 0 0
t$perf-norm:sp_supplier_invoice 5510 5510 0 0.00 0 3648 15 0 0
t$perf-norm:sp_supplier_order 5671 5671 0 0.00 0 8143 15 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 219 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 194 3 0 0
t$perf-norm:srv_make_invnt_saldo 550 550 0 0.00 0 5171 39 0 0
t$perf-norm:srv_make_money_saldo 787 787 0 0.00 0 2402 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 10414 10124 290 2.78 0 16903 23 0 290
v_all_customers 38622 38622 0 0.00 0 70 1 0 0
v_all_suppliers 30990 30990 0 0.00 0 46 1 0 0
v_all_wares 41828 41828 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 1249 8 0 0
v_cancel_client_order 3676 3676 0 0.00 0 16679 11 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 2981 5 0 0
v_cancel_customer_reserve 1478 1478 0 0.00 0 1476 4 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 1791 7 0 0
v_cancel_supplier_invoice 934 906 28 2.99 0 2117 21 0 28
v_cancel_supplier_order 778 778 0 0.00 0 1778 7 0 0
v_cancel_write_off 2078 2078 0 0.00 0 4674 15 0 0
v_max_id_clo_ord 5671 5671 0 0.00 0 2524 2 0 0
v_max_id_clo_res 1044 1044 0 0.00 0 1333 13 0 0
v_max_id_ord_sup 5505 5505 0 0.00 0 5556 6 0 0
v_max_non_paid_invoice 1614 1614 0 0.00 0 1224 3 0 0
v_max_non_paid_realizn 3670 3670 0 0.00 0 8044 6 0 0
v_min_id_clo_ord 5671 5671 0 0.00 0 4230 6 0 0
v_min_id_clo_res 1044 1044 0 0.00 0 2978 11 0 0
v_min_id_ord_sup 5505 5505 0 0.00 0 8109 9 0 0
v_min_non_paid_invoice 5933 5933 0 0.00 0 5099 12 0 0
v_min_non_paid_realizn 7292 7292 0 0.00 0 18135 21 0 0
v_our_firm 10450 10450 0 0.00 0 14 1 0 0
v_reserve_write_off 20474 20474 0 0.00 0 16512 7 0 0
x_lock_depdocs_on_canc_invoice 11759 11759 0 0.00 0 41942 133 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 4267 331 0 0
x_make_qstorno_1000_1200 5671 5671 0 0.00 1 30768 1147 0 0
x_make_qstorno_1200_2000 5510 5510 0 0.00 3 77219 3067 0 0
x_make_qstorno_2100_3300 468149 468149 0 0.00 0 80978 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-11-2021 01:38:59 to 04-11-2021 01:39:03.

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:59 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:00 13706 8192000000 9891934320 10200305664 979092160 10.675 100 91 182 0 1008702672 5756752 23853760
23:10:24 24355 8192000000 9936628704 10257149952 1021791408 11.089 100 88 176 0 1052617840 8376352 22450080
23:11:54 17629 8192000000 9967014768 10298281984 1054450208 11.403 100 85 170 0 1086125056 10198160 21476688
23:13:17 15320 8192000000 10024008224 10372087808 1110072704 11.933 100 75 150 0 1138914416 10994960 17846752
23:14:34 9690 8192000000 10078808480 10434973696 1157463456 12.379 100 78 156 3 1191101792 15574176 18064160
23:15:47 3667 8192000000 10125043792 10491322368 1212652528 12.894 100 82 163 1 1243659200 12859072 18147600
23:16:53 2687 8192000000 10154319536 10532487168 1247227984 13.213 100 72 144 0 1279112384 15921712 15962688
23:17:56 2892 8192000000 10188654048 10567864320 1271954256 13.439 100 88 176 0 1306222992 14828992 19439744
23:19:01 3385 8192000000 10197961616 10594557952 1296219136 13.661 100 65 130 0 1323974912 13940944 13814832
23:20:05 4602 8192000000 10204615920 10603237376 1307111232 13.760 100 45 90 1 1323760496 6233488 10415776
23:21:12 8162 8192000000 10193810176 10609938432 1314157904 13.824 100 21 42 1 1324471936 5493584 4820448
23:22:27 11321 8192000000 10196478656 10617065472 1318686208 13.865 100 37 73 0 1334106096 5897856 9522032
23:23:40 24546 8192000000 10211342464 10631503872 1324329280 13.916 100 19 38 1 1333883216 5672048 3881888
23:25:08 8601 8192000000 10217004480 10638352384 1329463200 13.962 100 27 54 2 1342119248 5844736 6811312
23:26:18 5846 8192000000 10208280400 10626199552 1316628800 13.846 100 19 37 2 1326596704 5703984 4263920
23:27:29 47394 8192000000 10196844256 10614534144 1300453600 13.699 99 27 53 2 1312372368 5972304 5946464
23:29:17 4694 8192000000 10171601568 10576674816 1267111040 13.395 99 28 56 0 1283510624 10396080 6003504
23:30:23 8278 8192000000 10130912272 10528436224 1214035456 12.906 96 21 40 2 1224119584 5052048 5032080
23:31:32 60514 8192000000 10135006048 10545643520 1230833408 13.062 98 25 50 0 1242710800 5294880 6582512
23:33:33 4010 8192000000 10180691792 10580029440 1279196512 13.506 100 40 80 1 1295556208 7288448 9071248
23:34:37 9821 8192000000 10178604464 10588692480 1285328432 13.562 100 29 58 1 1297649200 5678816 6641952
23:35:48 9105 8192000000 10196618288 10613563392 1308672320 13.774 100 26 50 2 1320490976 5596288 6222368
23:37:00 13592 8192000000 10200215104 10615988224 1313097136 13.814 100 59 118 0 1331217744 4714800 13405808
23:38:16 6014 8192000000 10231248528 10635350016 1325383120 13.925 100 51 100 1 1344176000 7527376 11265504
23:39:39 17638 8192000000 10258636112 10666233856 1346079344 14.112 100 56 112 1 1371413104 12819328 12514432
23:41:01 16009 8192000000 10269156304 10687262720 1350340464 14.151 99 40 78 2 1372397024 13013088 9043472
23:42:23 17828 8192000000 10310795792 10728312832 1389104384 14.498 100 34 68 1 1421800352 23932928 8763040
23:43:53 29672 8192000000 10325328592 10748805120 1404063872 14.631 100 35 70 0 1438149840 25300784 8785184
23:45:33 12722 8192000000 10351487664 10774970368 1417919200 14.754 100 42 84 1 1459656240 30812224 10924816
23:46:54 7481 8192000000 10368878720 10789543936 1428068320 14.844 100 59 118 2 1479337664 36241936 15027408
23:48:08 10771 8192000000 10386675344 10811449344 1439400960 14.944 100 60 119 1 1495372544 40579600 15391984
23:49:24 3254 8192000000 10393478560 10809294848 1427173200 14.836 99 73 145 0 1489409952 44950016 17286736
23:50:32 2019 8192000000 10407764960 10823426048 1447302512 15.014 100 83 164 2 1515036640 46851568 20882560
23:51:37 3577 8192000000 10387405904 10796761088 1414529744 14.724 99 87 175 2 1491595888 55263856 21802288
23:52:44 2441 8192000000 10384170688 10796322816 1397935808 14.577 98 89 179 0 1477685984 57639952 22110224
23:53:52 6620 8192000000 10368842912 10793590784 1407603664 14.663 100 54 106 2 1479727312 57770944 14352704
23:55:04 4914 8192000000 10382080288 10797871104 1388623008 14.494 98 65 129 1 1464496128 58760592 17112528
23:56:15 3414 8192000000 10383238272 10795261952 1398914112 14.585 100 80 158 0 1488600464 67906384 21779968
23:57:25 12023 8192000000 10354903856 10782851072 1389027568 14.497 100 38 76 2 1465224240 65266112 10930560
23:58:43 14014 8192000000 10345315920 10777063424 1376868416 14.389 99 34 68 3 1440136080 53834592 9433072
00:00:07 29899 8192000000 10331018368 10772815872 1388004928 14.488 100 24 48 1 1442309952 47382256 6922768
00:01:43 24152 8192000000 10317463616 10769137664 1352056320 14.166 99 17 34 0 1413089360 54880656 6152384
00:03:09 62154 8192000000 10261756256 10715439104 1247016896 13.211 92 12 24 0 1287766720 37292560 3457264
00:05:12 86585 8192000000 10268238752 10718613504 1355905776 14.201 100 23 46 0 1376422176 15059488 5456912
00:07:39 16167 8192000000 10273587648 10727116800 1359106816 14.229 100 32 64 1 1382970288 15890256 7973216
00:08:57 43448 8192000000 10242831296 10693038080 1302125920 13.715 96 27 54 4 1317198048 9317072 5755056
00:11:18 27838 8192000000 10260300848 10708996096 1363689664 14.270 100 19 38 0 1376169632 8689632 3790336
00:12:47 107007 8192000000 10267074592 10710417408 1337244544 14.033 98 16 32 2 1348110384 6982656 3883184
00:15:39 103674 8192000000 10280811440 10734194688 1360907184 14.246 99 16 33 3 1379050464 14384688 3758592
00:18:25 59932 8192000000 10281769296 10731659264 1370534784 14.332 100 19 38 1 1383206896 8381344 4290768
00:20:32 38374 8192000000 10270922880 10724864000 1361120752 14.247 99 34 68 1 1382943952 13159072 8664128
00:22:18 49643 8192000000 10301019424 10755272704 1386497856 14.475 100 15 30 0 1406721584 16779296 3444432
00:24:16 40315 8192000000 10284870768 10745925632 1377740080 14.396 99 14 28 0 1391468720 10451840 3276800
00:25:57 56484 8192000000 10290599456 10751664128 1374586240 14.368 99 16 32 0 1396810304 18636416 3587648
00:27:53 82771 8192000000 10315032320 10762756096 1383377376 14.447 99 29 58 1 1403214912 13621792 6215744
00:30:17 53844 8192000000 10298592896 10760724480 1374179168 14.364 98 19 38 1 1388924160 10729856 4015136
00:32:22 40454 8192000000 10324704704 10766626816 1393046128 14.533 99 46 90 1 1411861344 8107488 10707728
00:34:03 57877 8192000000 10278776592 10729754624 1364977056 14.282 98 44 86 1 1386418352 11111440 10329856
00:36:04 59633 8192000000 10269128352 10733400064 1339484080 14.053 97 19 38 1 1354124736 10035232 4605424
00:38:24 37250 8192000000 10278936864 10733060096 1367639312 14.306 99 23 46 0 1381173120 8442800 5091008
00:40:08 41079 8192000000 10245551072 10698887168 1346042784 14.112 99 10 20 0 1358829664 10206000 2580880
00:41:49 70381 8192000000 10264734736 10724012032 1340508992 14.062 98 10 20 0 1353934896 11448400 1977504
00:44:01 66668 8192000000 10280166848 10735595520 1345508992 14.107 97 13 26 0 1357417968 8966336 2942640
00:46:08 56962 8192000000 10285872304 10743074816 1339248096 14.051 97 15 28 1 1357949184 15583200 3117888
00:48:08 11885 8192000000 10286823184 10719748096 1357914880 14.219 99 49 96 2 1376971136 8540544 10515712
00:49:22 63040 8192000000 10265294848 10725683200 1317883216 13.858 96 18 36 1 1339860160 17953216 4023728
00:51:25 73098 8192000000 10278649696 10738008064 1333361056 13.998 97 29 58 1 1350625712 11236336 6028320
00:53:39 40605 8192000000 10306070384 10745192448 1376916736 14.389 100 32 63 1 1399531568 15783168 6831664
00:55:24 92669 8192000000 10280003696 10729799680 1349668672 14.144 98 25 50 0 1368014176 12166080 6179424
00:58:00 49328 8192000000 10274902432 10728984576 1353320528 14.177 99 13 26 1 1373001968 16917840 2763600
00:59:51 58558 8192000000 10265760064 10742267904 1370103856 14.328 100 3 6 0 1383669552 13003200 562496
01:01:50 41896 8192000000 10265182432 10717978624 1355993344 14.201 99 16 29 3 1368635648 9207808 3434496
01:03:56 75021 8192000000 10277774160 10740031488 1370488192 14.331 100 20 40 0 1389500224 14410288 4601744
01:06:11 75390 8192000000 10276368112 10744033280 1348775328 14.136 98 9 18 0 1370319168 19044864 2498976
01:08:40 18175 8192000000 10283768752 10728804352 1356530736 14.206 99 26 52 0 1377492864 15558240 5403888
01:10:02 14368 8192000000 10289449888 10730663936 1370730192 14.334 100 47 94 2 1392111184 10841616 10539376
01:11:22 59001 8192000000 10297448240 10762481664 1388669648 14.494 100 12 24 0 1404535504 13166448 2699408
01:13:31 50914 8192000000 10297741712 10757005312 1356184112 14.203 98 18 34 0 1376028432 16211712 3632608
01:15:29 68858 8192000000 10301120576 10761637888 1363737904 14.271 98 24 48 0 1385655616 16716080 5201632
01:17:44 73402 8192000000 10293853280 10758709248 1360697504 14.244 98 13 26 0 1380062336 16508224 2856608
01:19:58 115910 8192000000 10295508720 10748313600 1364391728 14.277 98 29 58 1 1383634848 11935712 7307408
01:23:03 69909 8192000000 10291354048 10759131136 1368400608 14.313 98 12 24 1 1389815824 18649792 2765424
01:25:25 60467 8192000000 10278175216 10740015104 1376174496 14.382 99 9 18 0 1385065280 6691376 2199408
01:28:16 93653 8192000000 10283595968 10746757120 1374013552 14.363 99 39 77 2 1391524560 9098624 8412384
01:31:02 45463 8192000000 10261990720 10716741632 1326905328 13.939 96 19 38 0 1340151584 9306784 3939472
01:32:48 44156 8192000000 10270391216 10724519936 1342818336 14.083 98 21 42 0 1357367488 10107552 4441600
01:34:35 42756 8192000000 10267644032 10711879680 1323337872 13.907 96 31 62 1 1339472064 9127648 7006544
01:36:18 62166 8192000000 10248108656 10688573440 1243386992 13.177 92 21 42 1 1263949216 15765504 4796720

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 145 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-11-2021 01:39:21 to 04-11-2021 01:39:23.

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          242704
MON$OLDEST_ACTIVE               242847
MON$OLDEST_SNAPSHOT             242847
MON$NEXT_TRANSACTION            242847
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                       2007104
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.33527 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33527 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33527 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33527 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 ---
:57: 
:58: mscs-test-161.budzdorov.ru    Thu Nov  4 01:38:06 2021
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Thu Nov  4 01:38:09 2021
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:64: 
:65: 
:66: mscs-test-161.budzdorov.ru    Thu Nov  4 01:38:09 2021
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:68: 
--- end of diff output ---

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

04.11.21 01:39:24. 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-11-04 01:39:26 No severe PSQL-related problems occured
04.11.21 01:39:27. End of report.