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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-08-08 01:37:45
DTS_END                         2021-08-08 01:37:45.9000
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/52458
TRN_ID                          248261
ATT_ID                          713
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: 9412.35 25602 1129482
customer order: creation 181.18 343 21741
customer order: refuse 15.44 343 1853
order to supplier: creation 49.21 1241 5905
order to supplier: removal 3.50 98773 420
invoice (draft): creation 48.21 3493 5785
invoice (draft): removal 53.21 847 6385
invoice accept: apply 43.62 17594 5234
invoice accept: cancel 47.85 741 5742
customer reserve: creation 3995.98 175 479517
customer reserve: removal 4652.42 57 558290
realization accept: apply 88.63 164 10636
realization accept: cancel 95.75 99 11490
payment from customer: creation 63.22 117 7586
payment from customer: removal 6.20 75 744
payment to supplier: creation 51.50 113 6180
payment to supplier: removal 3.60 90 432
service: total inventory turnovers 5.02 1131 602
service: total monetary turnovers 6.98 41 837
service: aggregate perf. data 0.83 23398 99
service: refresh index statistics 0.03 363210 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-08-2021 01:38:39 to 08-08-2021 01:38:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 603
TEST_TIME 2 2082
TEST_TIME 3 2794
TEST_TIME 4 4442
TEST_TIME 5 4592
TEST_TIME 6 4944
TEST_TIME 7 5239
TEST_TIME 8 5020
TEST_TIME 9 5175
TEST_TIME 10 5336
TEST_TIME 11 5786
TEST_TIME 12 5635
TEST_TIME 13 5800
TEST_TIME 14 6096
TEST_TIME 15 6419
TEST_TIME 16 6591
TEST_TIME 17 7027
TEST_TIME 18 7513
TEST_TIME 19 7775
TEST_TIME 20 7912
TEST_TIME 21 8184
TEST_TIME 22 8933
TEST_TIME 23 8967
TEST_TIME 24 9040
TEST_TIME 25 9776
TEST_TIME 26 9878
TEST_TIME 27 10915
TEST_TIME 28 10934
TEST_TIME 29 10790
TEST_TIME 30 10517
TEST_TIME 31 10478
TEST_TIME 32 10423
TEST_TIME 33 10564
TEST_TIME 34 10454
TEST_TIME 35 10240
TEST_TIME 36 10102
TEST_TIME 37 10023
TEST_TIME 38 9901
TEST_TIME 39 9846
TEST_TIME 40 10020
TEST_TIME 41 9893
TEST_TIME 42 9992
TEST_TIME 43 9972
TEST_TIME 44 9814
TEST_TIME 45 9701
TEST_TIME 46 9811
TEST_TIME 47 9832
TEST_TIME 48 9723
TEST_TIME 49 9683
TEST_TIME 50 9763
TEST_TIME 51 9717
TEST_TIME 52 9668
TEST_TIME 53 9655
TEST_TIME 54 9849
TEST_TIME 55 9820
TEST_TIME 56 9767
TEST_TIME 57 9908
TEST_TIME 58 9919
TEST_TIME 59 9849
TEST_TIME 60 9855
TEST_TIME 61 9956
TEST_TIME 62 9875
TEST_TIME 63 9816
TEST_TIME 64 9768
TEST_TIME 65 9684
TEST_TIME 66 9758
TEST_TIME 67 9706
TEST_TIME 68 9705
TEST_TIME 69 9667
TEST_TIME 70 9718
TEST_TIME 71 9657
TEST_TIME 72 9629
TEST_TIME 73 9702
TEST_TIME 74 9711
TEST_TIME 75 9739
TEST_TIME 76 9756
TEST_TIME 77 9784
TEST_TIME 78 9821
TEST_TIME 79 9793
TEST_TIME 80 9842
TEST_TIME 81 9798
TEST_TIME 82 9745
TEST_TIME 83 9706
TEST_TIME 84 9680
TEST_TIME 85 9653
TEST_TIME 86 9659
TEST_TIME 87 9638
TEST_TIME 88 9579
TEST_TIME 89 9546
TEST_TIME 90 9535
TEST_TIME 91 9471
TEST_TIME 92 9526
TEST_TIME 93 9491
TEST_TIME 94 9482
TEST_TIME 95 9449
TEST_TIME 96 9514
TEST_TIME 97 9516
TEST_TIME 98 9554
TEST_TIME 99 9577
TEST_TIME 100 9606
TEST_TIME 101 9564
TEST_TIME 102 9533
TEST_TIME 103 9609
TEST_TIME 104 9639
TEST_TIME 105 9642
TEST_TIME 106 9652
TEST_TIME 107 9628
TEST_TIME 108 9560
TEST_TIME 109 9538
TEST_TIME 110 9498
TEST_TIME 111 9514
TEST_TIME 112 9473
TEST_TIME 113 9434
TEST_TIME 114 9420
TEST_TIME 115 9460
TEST_TIME 116 9434
TEST_TIME 117 9429
TEST_TIME 118 9414
TEST_TIME 119 9449
TEST_TIME 120 9414

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-08-2021 01:38:42 to 08-08-2021 01:38:46.

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 1573605 1573605 0 0.00 0 15783 6 0 0
doc_list_biud 1573605 1573605 0 0.00 0 76781 26 0 0
sp_add_invoice_to_stock 5361 5234 127 2.36 31 177231 17594 0 127
sp_cancel_adding_invoice 5742 5742 0 0.00 5 63168 742 0 0
sp_cancel_client_order 1853 1853 0 0.00 4 11141 344 0 0
sp_cancel_customer_reserve 558290 558290 0 0.00 1 76783 58 0 0
sp_cancel_pay_from_customer 744 744 0 0.00 2 4617 75 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 2 9956 90 0 0
sp_cancel_supplier_invoice 6392 6385 7 0.10 1 14562 848 0 7
sp_cancel_supplier_order 420 420 0 0.00 11 1108669 98774 0 0
sp_cancel_write_off 11490 11490 0 0.00 1 10405 100 0 0
sp_client_order 21741 21741 0 0.00 3 25145 343 0 0
sp_customer_reserve 479517 479517 0 0.00 0 35536 176 0 0
sp_fill_shopping_cart 33417 33417 0 0.00 1 22603 37 0 0
sp_fill_shopping_cart_clo_res 479515 479515 0 0.00 0 22622 14 0 0
sp_get_clo_for_invoice 5232 5232 0 0.00 0 35530 277 0 0
sp_kill_cost_storno 18408 18408 0 0.00 0 6983 52 0 0
sp_kill_qstorno_ret_qs2qd 1134875 1134875 0 0.00 0 76778 31 0 0
sp_kill_qty_storno 600048 600048 0 0.00 0 76781 68 0 0
sp_lock_selected_doc 22097 22097 0 0.00 0 10377 7 0 0
sp_make_cost_storno 29634 29634 0 0.00 0 15777 50 0 0
sp_multiply_rows_for_pdistr 29634 29634 0 0.00 0 6070 26 0 0
sp_multiply_rows_for_qdistr 21741 21741 0 0.00 0 25128 154 0 0
sp_pay_from_customer 7586 7586 0 0.00 4 28033 118 0 0
sp_pay_to_supplier 6180 6180 0 0.00 4 11379 114 0 0
sp_payment_common 13766 13766 0 0.00 1 10705 30 0 0
sp_qd_handle_on_cancel_clo 1853 1853 0 0.00 0 11135 321 0 0
sp_qd_handle_on_invoice_upd_sts 10974 10974 0 0.00 0 42529 338 0 0
sp_qd_handle_on_reserve_upd_sts 10636 10636 0 0.00 0 14373 55 0 0
sp_reserve_write_off 10636 10636 0 0.00 6 15835 165 0 0
sp_supplier_invoice 5785 5785 0 0.00 7 98374 3494 0 0
sp_supplier_order 5905 5905 0 0.00 5 51615 1241 0 0
srv_aggregate_perf_data 99 99 0 0.00 2856 591795 23399 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1037 133875 45852 0 0
srv_find_qd_qs_mism 1079362 1079362 0 0.00 0 12718 5 0 0
srv_make_invnt_saldo 602 602 0 0.00 36 60583 1132 0 0
srv_make_money_saldo 837 837 0 0.00 1 15934 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 243479 543155 363210 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5235 5235 0 0.00 0 13276 170 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 4776 109 0 0
t$perf-norm:sp_cancel_client_order 1853 1853 0 0.00 0 6576 19 0 0
t$perf-norm:sp_cancel_customer_reserve 755 755 0 0.00 0 2965 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 744 744 0 0.00 0 1940 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 2503 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 469 469 0 0.00 0 2567 22 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 19764 1449 0 0
t$perf-norm:sp_cancel_write_off 1135 1135 0 0.00 0 4317 11 0 0
t$perf-norm:sp_client_order 21741 21741 0 0.00 0 7992 17 0 0
t$perf-norm:sp_customer_reserve 1130 1130 0 0.00 0 1256 4 0 0
t$perf-norm:sp_pay_from_customer 7586 7586 0 0.00 0 8942 12 0 0
t$perf-norm:sp_pay_to_supplier 6180 6180 0 0.00 0 7229 12 0 0
t$perf-norm:sp_reserve_write_off 10636 10636 0 0.00 0 7987 12 0 0
t$perf-norm:sp_supplier_invoice 5785 5785 0 0.00 0 7117 24 0 0
t$perf-norm:sp_supplier_order 5905 5905 0 0.00 0 9568 18 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 40 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1047 23 0 0
t$perf-norm:srv_make_invnt_saldo 602 602 0 0.00 0 5193 39 0 0
t$perf-norm:srv_make_money_saldo 837 837 0 0.00 0 1846 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 17 7 0 0
v_add_invoice_to_stock 10718 10464 254 2.36 0 3217 17 0 254
v_all_customers 40318 40318 0 0.00 0 24 1 0 0
v_all_suppliers 32390 32390 0 0.00 0 17 1 0 0
v_all_wares 43482 43482 0 0.00 0 56 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 1080 7 0 0
v_cancel_client_order 3706 3706 0 0.00 0 1661 4 0 0
v_cancel_customer_prepayment 1488 1488 0 0.00 0 4580 7 0 0
v_cancel_customer_reserve 1510 1510 0 0.00 0 1779 6 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 1173 5 0 0
v_cancel_supplier_invoice 952 938 14 1.47 0 4775 18 0 14
v_cancel_supplier_order 840 840 0 0.00 0 2319 6 0 0
v_cancel_write_off 2270 2270 0 0.00 0 2399 13 0 0
v_max_id_clo_ord 5905 5905 0 0.00 0 22594 5 0 0
v_max_id_clo_res 1130 1130 0 0.00 0 2224 22 0 0
v_max_id_ord_sup 5771 5771 0 0.00 0 3652 5 0 0
v_max_non_paid_invoice 1661 1661 0 0.00 0 513 3 0 0
v_max_non_paid_realizn 3800 3800 0 0.00 0 4883 8 0 0
v_min_id_clo_ord 5905 5905 0 0.00 0 2882 5 0 0
v_min_id_clo_res 1130 1130 0 0.00 0 1900 5 0 0
v_min_id_ord_sup 5771 5771 0 0.00 0 3733 5 0 0
v_min_non_paid_invoice 6180 6180 0 0.00 0 6889 19 0 0
v_min_non_paid_realizn 7586 7586 0 0.00 0 8095 19 0 0
v_our_firm 10736 10736 0 0.00 0 12 1 0 0
v_reserve_write_off 21272 21272 0 0.00 0 5074 6 0 0
x_lock_depdocs_on_canc_invoice 11658 11658 0 0.00 0 25481 118 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 12067 348 0 0
x_make_qstorno_1000_1200 5905 5905 0 0.00 1 51581 1168 0 0
x_make_qstorno_1200_2000 5785 5785 0 0.00 3 94871 3359 0 0
x_make_qstorno_2100_3300 479517 479517 0 0.00 0 35535 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-08-2021 01:38:47 to 08-08-2021 01:38:51.

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:49 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:50 31500 8192000000 10378610272 10702176256 1433220816 14.890 100 82 162 1 1466577472 6378672 26977984
23:10:29 14792 8192000000 10446826432 10777427968 1499780848 15.474 100 88 176 1 1536201520 8098848 28321824
23:11:53 20744 8192000000 10487416624 10828664832 1536385680 15.792 100 83 166 3 1572772896 10015408 26371808
23:13:20 16051 8192000000 10579177552 10936922112 1629965408 16.595 100 83 164 1 1665072800 9940784 25166608
23:14:42 13404 8192000000 10645128544 11018145792 1709701600 17.266 100 75 150 2 1742710800 12166016 20843184
23:15:58 7319 8192000000 10707902352 11090571264 1772521440 17.788 100 80 160 0 1807262880 12165600 22575840
23:17:07 3659 8192000000 10771585104 11151945728 1826463536 18.230 100 90 180 1 1866730992 15102352 25165104
23:18:12 1781 8192000000 10807378464 11192610816 1869454832 18.580 100 91 180 1 1909133776 14999376 24679568
23:19:16 19449 8192000000 10819468848 11206922240 1875983040 18.633 100 88 177 1 1912138592 10502288 25653264
23:20:36 4980 8192000000 10799037200 11217264640 1882121616 18.682 100 28 54 0 1896820960 7275344 7424000
23:21:45 6095 8192000000 10817917056 11240787968 1901173328 18.836 100 31 62 0 1917058784 7246320 8639136
23:22:53 10020 8192000000 10825701312 11240951808 1908332448 18.893 100 26 52 5 1921552992 6213552 7006992
23:24:04 21533 8192000000 10853780112 11267002368 1920158864 18.988 100 58 116 1 1944142624 5923296 18060464
23:25:27 4970 8192000000 10833955296 11268722688 1915933840 18.954 100 37 74 0 1933936848 7543376 10459632
23:26:32 7674 8192000000 10815484544 11254829056 1906112112 18.875 99 26 52 3 1918899904 5027264 7760528
23:27:41 8855 8192000000 10778203856 11217170432 1838360272 18.327 97 12 24 0 1847097280 5528608 3208400
23:28:50 24823 8192000000 10780604688 11215273984 1818514768 18.166 96 41 79 2 1836795184 6100016 12180400
23:30:17 7533 8192000000 10731292688 11157254144 1810614688 18.101 99 20 40 0 1822321040 6079504 5626848
23:31:28 9773 8192000000 10712820912 11135193088 1748531424 17.589 96 21 42 1 1760375056 5786336 6057296
23:32:40 53219 8192000000 10744926912 11159621632 1790790336 17.938 98 38 76 1 1807775856 6063984 10921536
23:34:37 7117 8192000000 10780179456 11201687552 1860237264 18.505 100 43 86 3 1880169760 6962304 12970192
23:35:45 7846 8192000000 10790731840 11223531520 1879280048 18.659 100 23 46 4 1891181088 6396912 5504128
23:36:57 11727 8192000000 10822913152 11245785088 1904183312 18.860 100 37 75 1 1920729296 5488848 11057136
23:38:14 94373 8192000000 10849430432 11281141760 1918092928 18.972 100 33 63 3 1933680832 6634016 8953888
23:40:52 16944 8192000000 10885270272 11313885184 1951764384 19.241 100 40 80 1 1977499056 13991040 11743632
23:42:27 3015 8192000000 10904898128 11338522624 1970164960 19.387 100 53 108 0 2002103184 15788064 16150160
23:43:33 6822 8192000000 10912768544 11348197376 1960225952 19.308 99 36 71 1 1988350096 17795152 10328992
23:44:48 8619 8192000000 10935810736 11372531712 1990736704 19.550 100 55 107 3 2029268608 23298544 15233360
23:46:00 4438 8192000000 10979561680 11406929920 2016810464 19.755 100 74 147 1 2066757216 28643120 21303632
23:47:07 9300 8192000000 10974160352 11404333056 2015004960 19.741 100 66 128 1 2067107088 32673600 19428528
23:48:24 2905 8192000000 10981782176 11413331968 2018316048 19.767 100 61 122 3 2072783856 35287376 19180432
23:49:31 4319 8192000000 11007301168 11433009152 2033469296 19.886 100 72 143 1 2089908688 34426336 22013056
23:50:40 3688 8192000000 10985475840 11412111360 2009593936 19.698 100 77 155 1 2073385680 40144784 23646960
23:51:48 3241 8192000000 10970102304 11394629632 1988430816 19.531 99 77 151 0 2055628368 44281936 22915616
23:52:59 7534 8192000000 10960645744 11384422400 1973766864 19.415 100 63 125 0 2042812720 49402000 19643856
23:54:15 1035 8192000000 10996174240 11407310848 1989894512 19.543 100 70 135 3 2065039616 53880160 21264944
23:55:26 10418 8192000000 10928137136 11365580800 1943718384 19.176 99 47 94 0 2017127808 57921536 15487888
23:56:42 2500 8192000000 10996518720 11407622144 1988833712 19.535 100 94 188 2 2075737520 57984288 28919520
23:57:47 6753 8192000000 10944374048 11372888064 1953368160 19.253 100 56 112 3 2027494960 55605728 18521072
23:59:01 19405 8192000000 10931254352 11364646912 1943822272 19.177 100 38 76 1 2014260224 57139232 13298720
00:00:22 29688 8192000000 10915441088 11368468480 1938511984 19.135 99 31 62 4 1998237728 49084384 10641360
00:01:55 14076 8192000000 10920747984 11363586048 1923361776 19.014 99 33 66 2 1983728176 49286640 11079760
00:03:17 48262 8192000000 10867977776 11324006400 1881607648 18.678 98 18 36 0 1926570800 37961664 7001488
00:05:08 29564 8192000000 10840508848 11289182208 1882300160 18.684 98 33 64 2 1904035328 11725216 10009952
00:06:38 133875 8192000000 10863660640 11318505472 1902251664 18.844 98 23 46 0 1919498544 11260288 5986592
00:09:53 76294 8192000000 10898214960 11369672704 1936811040 19.121 99 12 24 0 1951688640 11811104 3066496
00:12:12 37707 8192000000 10907557856 11360944128 1967334624 19.364 100 18 36 0 1980499872 7575568 5589680
00:13:54 47492 8192000000 10903882224 11366940672 1961983152 19.322 99 44 88 1 1989499008 14821968 12693888
00:15:43 81926 8192000000 10915377072 11390738432 1964405136 19.341 99 23 46 0 1997915088 26270272 7239680
00:18:10 50469 8192000000 10906448176 11370799104 1963041392 19.330 99 18 36 1 1983848880 14864592 5942896
00:20:05 20801 8192000000 10900370032 11370676224 1976934272 19.440 100 16 32 0 1990315344 8263872 5117200
00:21:27 113181 8192000000 10925539072 11404410880 1999656784 19.620 100 29 56 1 2025239616 16888768 8694064
00:24:43 55399 8192000000 10913352160 11395211264 1991712784 19.557 100 13 26 3 2007994832 12020128 4261920
00:26:45 61177 8192000000 10911964656 11395260416 1977402704 19.444 99 24 48 1 1999227808 13901760 7923344
00:28:52 25205 8192000000 10946908240 11399344128 1999284176 19.617 100 45 89 0 2027298352 15225616 12788560
00:30:24 39540 8192000000 10950235680 11413618688 1983999296 19.496 99 25 50 4 2004927664 13116144 7812224
00:32:04 57744 8192000000 10926607792 11391086592 1949969360 19.226 97 17 33 1 1965418480 11043648 4405472
00:34:09 100483 8192000000 10880966720 11357466624 1849686272 18.420 93 11 22 0 1865274576 12239472 3348832
00:36:50 91268 8192000000 10904905248 11387965440 1954138224 19.259 99 15 30 0 1972875072 14631504 4105344
00:39:21 93924 8192000000 10899639136 11342188544 1891417456 18.757 96 24 48 0 1909090560 11352768 6320336
00:41:59 28652 8192000000 10892080976 11349159936 1947779152 19.209 100 34 68 5 1976640400 19791008 9070240
00:43:34 53841 8192000000 10849091664 11319894016 1847758704 18.404 95 16 32 0 1868597872 16245872 4593296
00:45:29 76355 8192000000 10854782720 11332567040 1860575648 18.508 96 7 15 0 1874294608 11878960 1840000
00:47:48 41793 8192000000 10871297536 11335319552 1943323824 19.173 100 19 38 1 1960267664 11364096 5579744
00:49:34 24642 8192000000 10873428208 11337039872 1897253200 18.804 97 40 81 2 1922356288 13931824 11171264
00:51:19 51079 8192000000 10873263072 11327488000 1870746384 18.590 97 15 30 0 1892686592 17633456 4306752
00:53:14 44532 8192000000 10869142912 11327709184 1914799392 18.945 99 36 72 1 1936802736 12273536 9729808
00:55:02 77293 8192000000 10863332592 11338997760 1891297424 18.756 97 18 36 0 1908781904 12693600 4790880
00:57:19 115968 8192000000 10865886080 11333771264 1889002096 18.738 97 13 26 1 1904870256 11994624 3873536
01:01:07 58137 8192000000 10880762208 11350753280 1946695472 19.200 100 24 48 0 1966259008 12537264 7026272
01:03:07 51139 8192000000 10864968480 11354021888 1941865664 19.162 100 13 26 1 1965366368 20133536 3367168
01:04:58 67181 8192000000 10862972336 11341869056 1912993328 18.931 98 11 22 1 1928351904 12057328 3301248
01:07:06 94483 8192000000 10894884800 11365437440 1960621232 19.311 100 7 14 2 1973010640 10683328 1706080
01:09:42 71818 8192000000 10879567600 11362430976 1950629216 19.231 99 19 37 1 1962598064 6929680 5039168
01:11:54 42441 8192000000 10884593280 11352805376 1922724992 19.009 98 18 36 0 1941303984 13710368 4868624
01:13:38 45238 8192000000 10889005952 11363393536 1943448272 19.174 99 24 48 2 1972102160 21760736 6893152
01:15:38 64555 8192000000 10888216848 11358445568 1913489952 18.935 97 20 40 1 1933229904 13962480 5777472
01:17:47 44526 8192000000 10927119936 11394793472 1967216800 19.363 99 15 30 1 1984640544 13281440 4142304
01:19:36 36108 8192000000 10934845024 11393847296 1963465328 19.334 99 34 68 1 1990577744 17392544 9719872
01:21:15 54978 8192000000 10894110400 11354726400 1884016720 18.698 95 28 53 3 1901683328 10218544 7448064
01:23:16 55332 8192000000 10907341408 11356303360 1918278960 18.973 97 37 73 2 1941235776 11781232 11175584
01:25:13 89412 8192000000 10880403712 11344269312 1914056256 18.939 98 26 52 0 1932688928 10267680 8364992
01:27:44 24665 8192000000 10853951440 11318640640 1918708304 18.976 99 28 56 0 1937245712 10764064 7773344
01:29:09 60646 8192000000 10830707808 11307311104 1880552208 18.670 97 7 14 1 1889972960 7439600 1981152
01:31:10 45791 8192000000 10833568704 11305164800 1869066608 18.577 97 12 24 0 1879434368 6805808 3561952
01:33:02 76330 8192000000 10827121680 11313545216 1847932576 18.405 96 8 16 0 1860577968 10467568 2177824
01:35:20 24972 8192000000 10882695152 11340881920 1939777696 19.145 100 45 90 0 1963908784 10648448 13482640
01:36:47 44942 8192000000 10874917056 11323551744 1846513024 18.394 95 28 56 2 1860484176 6228768 7742384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-08-2021 01:38:53 to 08-08-2021 01:39:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

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

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          246483
MON$OLDEST_ACTIVE               248403
MON$OLDEST_SNAPSHOT             248403
MON$NEXT_TRANSACTION            248403
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                       2023360
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.33487 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33487 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33487 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 ---
:381: 
:382: mscs-test-161.budzdorov.ru    Sun Aug  8 01:37:54 2021
:383:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:384: 
--- end of diff output ---

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

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