Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.10.2020 01:27
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. 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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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 4794.00
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 17414848 kB
MemAvailable 31503364 kB
Buffers 0 kB
Cached 14260864 kB
SwapCached 3060 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.7
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 2020-10-28 01:26:59
DTS_END                         2020-10-28 01:26:59.1190
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/56930
TRN_ID                          248532
ATT_ID                          643
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: 9422.87 21330 1130744
customer order: creation 179.75 345 21570
customer order: refuse 15.49 352 1859
order to supplier: creation 47.88 1146 5746
order to supplier: removal 3.28 100127 393
invoice (draft): creation 48.68 3085 5841
invoice (draft): removal 52.12 704 6254
invoice accept: apply 43.40 17754 5208
invoice accept: cancel 46.88 801 5625
customer reserve: creation 4037.03 175 484443
customer reserve: removal 4634.29 57 556115
realization accept: apply 89.52 170 10742
realization accept: cancel 88.66 102 10639
payment from customer: creation 61.28 92 7353
payment from customer: removal 5.90 62 708
payment to supplier: creation 51.90 114 6228
payment to supplier: removal 4.00 76 480
service: total inventory turnovers 4.95 945 594
service: total monetary turnovers 7.07 25 848
service: aggregate perf. data 0.78 23534 94
service: refresh index statistics 0.03 276936 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-10-2020 01:28:11 to 28-10-2020 01:28:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 979
TEST_TIME 2 4393
TEST_TIME 3 5360
TEST_TIME 4 5899
TEST_TIME 5 5776
TEST_TIME 6 5412
TEST_TIME 7 5262
TEST_TIME 8 5304
TEST_TIME 9 5678
TEST_TIME 10 5697
TEST_TIME 11 6097
TEST_TIME 12 6185
TEST_TIME 13 6609
TEST_TIME 14 6609
TEST_TIME 15 7018
TEST_TIME 16 6946
TEST_TIME 17 6939
TEST_TIME 18 7108
TEST_TIME 19 7757
TEST_TIME 20 7737
TEST_TIME 21 8090
TEST_TIME 22 8380
TEST_TIME 23 8634
TEST_TIME 24 9003
TEST_TIME 25 8923
TEST_TIME 26 10173
TEST_TIME 27 10652
TEST_TIME 28 10652
TEST_TIME 29 10463
TEST_TIME 30 10264
TEST_TIME 31 10206
TEST_TIME 32 10369
TEST_TIME 33 10253
TEST_TIME 34 10400
TEST_TIME 35 10207
TEST_TIME 36 10137
TEST_TIME 37 9949
TEST_TIME 38 9941
TEST_TIME 39 9951
TEST_TIME 40 9923
TEST_TIME 41 9768
TEST_TIME 42 9962
TEST_TIME 43 9810
TEST_TIME 44 9824
TEST_TIME 45 9768
TEST_TIME 46 9754
TEST_TIME 47 9685
TEST_TIME 48 9574
TEST_TIME 49 9468
TEST_TIME 50 9504
TEST_TIME 51 9466
TEST_TIME 52 9477
TEST_TIME 53 9458
TEST_TIME 54 9617
TEST_TIME 55 9616
TEST_TIME 56 9700
TEST_TIME 57 9630
TEST_TIME 58 9671
TEST_TIME 59 9737
TEST_TIME 60 9814
TEST_TIME 61 9782
TEST_TIME 62 9819
TEST_TIME 63 9937
TEST_TIME 64 9786
TEST_TIME 65 9679
TEST_TIME 66 9666
TEST_TIME 67 9607
TEST_TIME 68 9522
TEST_TIME 69 9497
TEST_TIME 70 9441
TEST_TIME 71 9470
TEST_TIME 72 9409
TEST_TIME 73 9510
TEST_TIME 74 9501
TEST_TIME 75 9511
TEST_TIME 76 9567
TEST_TIME 77 9504
TEST_TIME 78 9543
TEST_TIME 79 9651
TEST_TIME 80 9620
TEST_TIME 81 9590
TEST_TIME 82 9665
TEST_TIME 83 9625
TEST_TIME 84 9533
TEST_TIME 85 9513
TEST_TIME 86 9509
TEST_TIME 87 9486
TEST_TIME 88 9481
TEST_TIME 89 9438
TEST_TIME 90 9398
TEST_TIME 91 9359
TEST_TIME 92 9394
TEST_TIME 93 9393
TEST_TIME 94 9372
TEST_TIME 95 9391
TEST_TIME 96 9417
TEST_TIME 97 9499
TEST_TIME 98 9565
TEST_TIME 99 9553
TEST_TIME 100 9541
TEST_TIME 101 9543
TEST_TIME 102 9575
TEST_TIME 103 9541
TEST_TIME 104 9540
TEST_TIME 105 9563
TEST_TIME 106 9497
TEST_TIME 107 9452
TEST_TIME 108 9412
TEST_TIME 109 9402
TEST_TIME 110 9408
TEST_TIME 111 9374
TEST_TIME 112 9328
TEST_TIME 113 9306
TEST_TIME 114 9384
TEST_TIME 115 9389
TEST_TIME 116 9413
TEST_TIME 117 9396
TEST_TIME 118 9448
TEST_TIME 119 9430
TEST_TIME 120 9425

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-10-2020 01:28:14 to 28-10-2020 01:28:18.

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 1578829 1578829 0 0.00 0 12879 6 0 0
doc_list_biud 1578829 1578829 0 0.00 0 53935 25 0 0
sp_add_invoice_to_stock 5280 5208 72 1.36 32 236150 17755 0 72
sp_cancel_adding_invoice 5625 5625 0 0.00 4 74890 802 0 0
sp_cancel_client_order 1859 1859 0 0.00 4 19863 352 0 0
sp_cancel_customer_reserve 556115 556115 0 0.00 1 53936 57 0 0
sp_cancel_pay_from_customer 708 708 0 0.00 2 5710 63 0 0
sp_cancel_pay_to_supplier 480 480 0 0.00 3 6464 77 0 0
sp_cancel_supplier_invoice 6259 6254 5 0.07 2 12916 705 0 5
sp_cancel_supplier_order 393 393 0 0.00 6 1023539 100127 0 0
sp_cancel_write_off 10639 10639 0 0.00 1 8529 102 0 0
sp_client_order 21570 21570 0 0.00 3 15579 346 0 0
sp_customer_reserve 484443 484443 0 0.00 0 63573 175 0 0
sp_fill_shopping_cart 33144 33144 0 0.00 1 11647 33 0 0
sp_fill_shopping_cart_clo_res 484437 484437 0 0.00 0 15271 12 0 0
sp_get_clo_for_invoice 5198 5198 0 0.00 0 28756 293 0 0
sp_kill_cost_storno 17452 17452 0 0.00 0 5706 47 0 0
sp_kill_qstorno_ret_qs2qd 1129516 1129516 0 0.00 0 53931 31 0 0
sp_kill_qty_storno 596824 596824 0 0.00 0 53935 66 0 0
sp_lock_selected_doc 22083 22083 0 0.00 0 8460 6 0 0
sp_make_cost_storno 29520 29520 0 0.00 0 9307 44 0 0
sp_multiply_rows_for_pdistr 29520 29520 0 0.00 0 7436 26 0 0
sp_multiply_rows_for_qdistr 21570 21570 0 0.00 0 13874 165 0 0
sp_pay_from_customer 7353 7353 0 0.00 4 11196 93 0 0
sp_pay_to_supplier 6228 6228 0 0.00 4 12250 115 0 0
sp_payment_common 13581 13581 0 0.00 1 6569 24 0 0
sp_qd_handle_on_cancel_clo 1859 1859 0 0.00 0 19850 327 0 0
sp_qd_handle_on_invoice_upd_sts 10822 10822 0 0.00 0 31009 276 0 0
sp_qd_handle_on_reserve_upd_sts 10742 10742 0 0.00 0 19331 58 0 0
sp_reserve_write_off 10742 10742 0 0.00 6 19365 170 0 0
sp_supplier_invoice 5841 5841 0 0.00 4 126034 3086 0 0
sp_supplier_order 5746 5746 0 0.00 5 24598 1147 0 0
srv_aggregate_perf_data 94 94 0 0.00 2440 704350 23534 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2446 125994 47104 0 0
srv_find_qd_qs_mism 1080451 1080451 0 0.00 0 12879 6 0 0
srv_make_invnt_saldo 594 594 0 0.00 25 71157 946 0 0
srv_make_money_saldo 848 848 0 0.00 0 3672 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 119544 444464 276937 0 0
t$perf-abend:sp_add_invoice_to_stock 72 72 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5208 5208 0 0.00 0 14761 167 0 0
t$perf-norm:sp_cancel_adding_invoice 446 446 0 0.00 0 6650 97 0 0
t$perf-norm:sp_cancel_client_order 1859 1859 0 0.00 0 5566 13 0 0
t$perf-norm:sp_cancel_customer_reserve 754 754 0 0.00 0 4728 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 708 708 0 0.00 0 7725 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 480 480 0 0.00 0 2271 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 441 441 0 0.00 0 3686 25 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 20679 1563 0 0
t$perf-norm:sp_cancel_write_off 1063 1063 0 0.00 0 2346 8 0 0
t$perf-norm:sp_client_order 21570 21570 0 0.00 0 5283 15 0 0
t$perf-norm:sp_customer_reserve 1023 1023 0 0.00 0 6716 11 0 0
t$perf-norm:sp_pay_from_customer 7353 7353 0 0.00 0 5707 10 0 0
t$perf-norm:sp_pay_to_supplier 6228 6228 0 0.00 0 10586 10 0 0
t$perf-norm:sp_reserve_write_off 10742 10742 0 0.00 0 6629 10 0 0
t$perf-norm:sp_supplier_invoice 5841 5841 0 0.00 0 10627 24 0 0
t$perf-norm:sp_supplier_order 5746 5746 0 0.00 0 6609 14 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 497 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 843 15 0 0
t$perf-norm:srv_make_invnt_saldo 594 594 0 0.00 0 733 19 0 0
t$perf-norm:srv_make_money_saldo 848 848 0 0.00 0 705 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10538 10394 144 1.36 0 6449 17 0 144
v_all_customers 38742 38742 0 0.00 0 138 1 0 0
v_all_suppliers 32116 32116 0 0.00 0 25 1 0 0
v_all_wares 43140 43140 0 0.00 0 37 1 0 0
v_cancel_adding_invoice 892 892 0 0.00 0 146 5 0 0
v_cancel_client_order 3718 3718 0 0.00 0 2955 5 0 0
v_cancel_customer_prepayment 1416 1416 0 0.00 0 1208 5 0 0
v_cancel_customer_reserve 1508 1508 0 0.00 0 4482 10 0 0
v_cancel_payment_to_supplier 960 960 0 0.00 0 6455 14 0 0
v_cancel_supplier_invoice 892 882 10 1.12 0 3067 22 0 10
v_cancel_supplier_order 786 786 0 0.00 0 1980 6 0 0
v_cancel_write_off 2126 2126 0 0.00 0 5108 17 0 0
v_max_id_clo_ord 5746 5746 0 0.00 0 636 1 0 0
v_max_id_clo_res 1023 1023 0 0.00 0 3515 16 0 0
v_max_id_ord_sup 5828 5828 0 0.00 0 5629 6 0 0
v_max_non_paid_invoice 1744 1744 0 0.00 0 4724 5 0 0
v_max_non_paid_realizn 4157 4157 0 0.00 0 689 3 0 0
v_min_id_clo_ord 5746 5746 0 0.00 0 4293 6 0 0
v_min_id_clo_res 1023 1023 0 0.00 0 582 2 0 0
v_min_id_ord_sup 5828 5828 0 0.00 0 3641 6 0 0
v_min_non_paid_invoice 6228 6228 0 0.00 0 6565 17 0 0
v_min_non_paid_realizn 7353 7353 0 0.00 0 6183 16 0 0
v_our_firm 10790 10790 0 0.00 0 12 1 0 0
v_reserve_write_off 21484 21484 0 0.00 0 4630 5 0 0
x_lock_depdocs_on_canc_invoice 11438 11438 0 0.00 0 15430 125 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 13749 361 0 0
x_make_qstorno_1000_1200 5746 5746 0 0.00 2 24575 1077 0 0
x_make_qstorno_1200_2000 5841 5841 0 0.00 2 126026 2963 0 0
x_make_qstorno_2100_3300 484443 484443 0 0.00 0 63572 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-10-2020 01:28:19 to 28-10-2020 01:28:23.

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
22:57:02 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
22:58:04 27751 8192000000 10350203088 10669015040 1406260608 14.651 100 90 180 0 1440877104 5519776 29096720
22:59:35 24248 8192000000 10420318320 10746269696 1472332352 15.234 100 93 185 0 1509970288 7828240 29809696
23:01:16 15314 8192000000 10465111680 10813034496 1528978064 15.728 100 76 152 1 1562540752 9859152 23703536
23:02:33 14926 8192000000 10571454016 10933121024 1627462256 16.573 100 79 158 1 1661901088 10835440 23603392
23:03:52 13448 8192000000 10638233872 11013984256 1699687840 17.182 100 71 141 1 1732415648 11992848 20734960
23:05:11 6777 8192000000 10697158256 11073769472 1755697936 17.649 100 80 160 0 1792626960 14479552 22449472
23:06:19 2435 8192000000 10757684016 11144957952 1815643904 18.142 100 86 173 3 1853187344 13383584 24159856
23:07:24 1748 8192000000 10790420288 11177492480 1847860816 18.405 100 86 174 0 1886189456 13997792 24330848
23:08:27 30474 8192000000 10795312288 11204460544 1869793008 18.583 100 62 120 2 1897762096 10299488 17669600
23:10:05 7442 8192000000 10781841040 11214192640 1879536272 18.661 100 40 79 1 1899175088 8189424 11449392
23:11:13 8367 8192000000 10794632208 11221610496 1884466752 18.701 100 20 40 0 1896410112 6553968 5389392
23:12:22 52096 8192000000 10815816640 11239743488 1896200000 18.796 100 42 85 1 1914165136 4678272 13286864
23:14:17 8386 8192000000 10808975088 11245670400 1901441312 18.838 100 25 50 5 1914737328 6278704 7017312
23:15:25 7309 8192000000 10822020928 11260674048 1908405344 18.894 100 21 41 3 1921044816 5840016 6799456
23:16:33 12601 8192000000 10801635264 11241234432 1863529200 18.532 98 17 33 1 1872895296 5436000 3930096
23:17:48 15516 8192000000 10767814096 11207643136 1809123616 18.089 95 36 72 0 1824925792 4565264 11236912
23:19:04 5687 8192000000 10738375776 11159162880 1807377760 18.074 99 32 64 2 1824102800 7263984 9461056
23:20:10 6766 8192000000 10719728672 11142692864 1792604528 17.953 99 21 40 1 1804626752 6493920 5528304
23:21:17 14297 8192000000 10708798176 11125284864 1787122000 17.908 100 19 38 0 1798779200 5768832 5888368
23:22:33 35953 8192000000 10739371760 11164614656 1826772192 18.233 100 26 52 0 1840448976 6452352 7224432
23:24:13 6001 8192000000 10756280304 11184783360 1829019504 18.251 99 30 60 1 1844559088 6126256 9413328
23:25:24 6096 8192000000 10773515728 11201519616 1859195408 18.497 100 39 78 1 1877204608 5054656 12954544
23:26:30 79660 8192000000 10801911744 11221721088 1867121456 18.561 100 25 51 0 1879795008 5505504 7168048
23:28:52 5028 8192000000 10851949520 11268083712 1919291296 18.981 100 59 118 2 1947109312 10821376 16996640
23:30:01 11427 8192000000 10868000784 11294146560 1935427712 19.110 100 44 87 1 1957947536 9922896 12596928
23:31:21 26810 8192000000 10892063840 11325894656 1959531488 19.302 100 31 62 1 1977756432 9098672 9126272
23:32:51 12700 8192000000 10903399024 11337900032 1970931600 19.393 100 37 74 1 1992317744 10794640 10591504
23:34:10 5892 8192000000 10925796192 11354701824 1984483872 19.500 100 59 116 0 2015771984 14942464 16345648
23:35:21 13551 8192000000 10895836304 11324170240 1939676368 19.144 98 51 102 1 1973911312 18756704 15478240
23:36:47 6763 8192000000 10936480992 11363352576 1986619776 19.517 100 54 108 1 2027814208 23906480 17287952
23:37:58 6579 8192000000 10928437920 11356667904 1974388912 19.420 100 62 122 0 2017105024 24142128 18573984
23:39:08 2797 8192000000 10933462624 11345059840 1967490640 19.366 100 79 159 0 2018487728 26730224 24266864
23:40:16 4877 8192000000 10943433008 11368124416 1981455344 19.476 100 60 118 1 2030548048 30319856 18772848
23:41:24 8027 8192000000 10926755392 11352969216 1957569104 19.287 99 55 110 0 2011638160 36948688 17120368
23:42:38 5664 8192000000 10897695840 11321237504 1925004320 19.027 99 61 121 0 1975194112 31058272 19131520
23:43:55 2446 8192000000 10928720800 11332415488 1946811776 19.201 100 67 132 3 2000568176 33669360 20087040
23:45:03 3048 8192000000 10943845216 11350904832 1954920464 19.266 99 86 170 2 2019610464 38636256 26053744
23:46:10 8620 8192000000 10888801600 11317825536 1904211056 18.860 98 40 80 2 1950032304 32314624 13506624
23:47:23 3962 8192000000 10960166656 11370844160 1970529376 19.390 100 82 162 0 2038068976 40705504 26834096
23:48:30 13230 8192000000 10908394352 11341336576 1912665648 18.928 98 36 71 1 1957869984 33170512 12033824
23:49:46 13337 8192000000 10905708016 11342667776 1939019648 19.139 100 27 55 2 1983652608 35541072 9091888
23:51:01 23338 8192000000 10899591408 11344654336 1924957040 19.027 99 23 44 2 1971534848 38586928 7990880
23:52:28 50716 8192000000 10815062224 11277856768 1816438032 18.149 95 14 28 0 1854168432 33109184 4621216
23:54:20 70691 8192000000 10860713472 11291803648 1897813984 18.809 99 21 42 1 1914208080 9564832 6829264
23:56:33 110707 8192000000 10903840880 11345100800 1941700176 19.160 100 35 69 1 1970629856 19519248 9410432
23:59:25 71213 8192000000 10912505248 11368558592 1958380288 19.293 99 19 38 0 1977422688 13984192 5058208
00:01:40 26091 8192000000 10899212944 11354370048 1960737232 19.312 99 20 40 0 1972499216 6387776 5374208
00:03:09 40376 8192000000 10932869504 11384647680 1989304752 19.538 100 37 74 0 2012935616 12637792 10993072
00:05:01 86670 8192000000 10901306592 11376156672 1962709056 19.328 99 18 35 0 1983184672 14870624 5604992
00:08:12 40114 8192000000 10911850752 11378159616 1967849872 19.368 99 20 40 0 1992037520 18672480 5515168
00:10:35 26873 8192000000 10910274640 11370909696 1963872048 19.337 99 13 26 0 1981646336 13601024 4173264
00:12:03 36213 8192000000 10901485904 11377655808 1969856560 19.384 99 17 34 1 1985534432 11311296 4366576
00:13:42 68698 8192000000 10896103952 11375525888 1985018496 19.504 100 11 22 0 1995939280 7908720 3012064
00:16:05 77089 8192000000 10918896432 11391242240 1986425872 19.516 100 7 14 1 1998021472 9397408 2198192
00:18:22 42811 8192000000 10925243936 11389071360 1955822928 19.273 98 20 40 1 1972250432 11027696 5399808
00:20:13 72404 8192000000 10901147760 11368189952 1926978288 19.043 97 17 34 1 1949669568 17817680 4873600
00:22:28 52695 8192000000 10901848160 11365011456 1922105952 19.004 97 30 60 0 1946613760 15788192 8719616
00:24:26 44982 8192000000 10880026384 11336667136 1918319904 18.973 98 35 70 0 1949777872 21262704 10195264
00:26:15 44552 8192000000 10834065888 11295039488 1829266576 18.253 95 14 28 1 1846010560 12709376 4034608
00:28:01 125994 8192000000 10851006816 11332247552 1805396528 18.058 93 21 42 0 1828017024 16949680 5670816
00:31:17 61810 8192000000 10853348848 11327057920 1938706768 19.136 100 26 50 2 1953525568 7683648 7135152
00:33:26 51603 8192000000 10856189440 11325313024 1908324208 18.893 98 8 16 0 1918413712 7378928 2710576
00:37:02 63890 8192000000 10872219776 11338735616 1904342368 18.861 98 18 35 1 1925771424 15523920 5905136
00:39:07 73469 8192000000 10850978768 11325198336 1901694240 18.840 98 16 32 1 1926399680 19406144 5299296
00:41:44 60910 8192000000 10860766752 11334938624 1906969168 18.882 98 31 62 0 1930790096 14732784 9088144
00:43:46 60449 8192000000 10891091520 11356278784 1914463360 18.942 98 26 52 1 1938299264 16527008 7308896
00:46:03 81018 8192000000 10901019952 11358584832 1943380432 19.174 99 15 31 2 1962969888 15006240 4583216
00:48:25 114756 8192000000 10881016528 11344875520 1909590672 18.903 97 23 46 0 1926102400 10389296 6122432
00:51:28 64691 8192000000 10880153088 11346014208 1937367920 19.126 99 23 46 1 1956655488 12930896 6356672
00:53:35 43493 8192000000 10882264992 11356389376 1950422240 19.230 99 13 26 1 1964210672 10139808 3648624
00:55:19 73722 8192000000 10886295440 11357147136 1947315232 19.205 99 13 26 0 1959558032 8072096 4170704
00:57:52 89424 8192000000 10903104592 11371646976 1978952048 19.456 100 11 23 0 1994283216 12351232 2979936
01:00:34 36718 8192000000 10915404144 11385483264 1987644640 19.525 100 23 46 3 2003791152 9338288 6808224
01:02:21 83304 8192000000 10918570176 11413123072 1989333808 19.539 100 12 24 1 2008536352 15967584 3234960
01:05:31 31621 8192000000 10881608160 11347144704 1892258576 18.764 95 19 38 1 1904902032 7607968 5035488
01:07:03 64372 8192000000 10906424976 11366772736 1939220096 19.141 98 31 62 1 1960209968 12437104 8552768
01:09:07 30391 8192000000 10927461536 11378245632 1948412000 19.214 98 34 68 1 1967729408 10237184 9080224
01:10:42 15057 8192000000 10910672256 11356049408 1962396864 19.325 100 53 106 3 1991033968 13927440 14709664
01:11:58 92446 8192000000 10859106336 11321864192 1884693808 18.703 97 29 58 1 1905792240 13398848 7699584
01:14:42 28162 8192000000 10836597408 11300646912 1890136928 18.747 98 21 41 1 1903893696 7873840 5882928
01:16:26 63529 8192000000 10853449200 11332939776 1925798976 19.033 100 13 26 1 1946462304 16529520 4133808
01:18:33 44714 8192000000 10852795264 11320786944 1893586816 18.775 98 16 32 0 1917996336 19841600 4567920
01:20:18 89566 8192000000 10855530736 11352371200 1889348768 18.741 98 8 16 0 1916818608 24691936 2777904
01:22:48 79486 8192000000 10868490128 11348570112 1919829536 18.985 99 15 30 0 1943786784 20053840 3903408
01:25:09 122217 8192000000 9430449936 9727221760 539956224 6.183 28 6 12 0 552177888 10431984 1789680

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-10-2020 01:28:24 to 28-10-2020 01:28:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 72 v_add_invoice_to_stock 335544517
user_exc 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-10-2020 01:28:40 to 28-10-2020 01:28:42.

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          246449
MON$OLDEST_ACTIVE               248926
MON$OLDEST_SNAPSHOT             248926
MON$NEXT_TRANSACTION            248926
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                       2028768
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.7.33380 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33380 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33380 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33380 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 ---
:208: 
:209: mscs-test-161.budzdorov.ru    Wed Oct 28 01:27:22 2020
:210:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:211: 
--- end of diff output ---

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

28.10.20 01:28:43. 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
2020-10-28 01:28:45 No severe PSQL-related problems occured
28.10.20 01:28:46. End of report.