Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.01.2021 04:28
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 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 16665364 kB
MemAvailable 31258940 kB
Buffers 20 kB
Cached 14982344 kB
SwapCached 4868 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 4.0.0
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-01-28 04:26:53
DTS_END                         2021-01-28 04:26:53.6860
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38608
TRN_ID                          229162
ATT_ID                          606
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: 8438.96 23398 1012675
customer order: creation 163.68 396 19642
customer order: refuse 14.08 399 1689
order to supplier: creation 44.02 1495 5282
order to supplier: removal 3.16 124377 379
invoice (draft): creation 44.10 3779 5292
invoice (draft): removal 46.63 1010 5596
invoice accept: apply 39.54 19148 4745
invoice accept: cancel 41.75 960 5010
customer reserve: creation 3616.02 190 433922
customer reserve: removal 4150.61 74 498073
realization accept: apply 81.21 166 9745
realization accept: cancel 71.33 130 8559
payment from customer: creation 57.41 109 6889
payment from customer: removal 5.49 112 659
payment to supplier: creation 46.83 132 5620
payment to supplier: removal 3.48 84 417
service: total inventory turnovers 3.59 4031 431
service: total monetary turnovers 5.19 749 623
service: aggregate perf. data 0.82 20467 98
service: refresh index statistics 0.03 290160 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-01-2021 04:28:36 to 28-01-2021 04:28:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3107
TEST_TIME 2 2438
TEST_TIME 3 3945
TEST_TIME 4 4095
TEST_TIME 5 4595
TEST_TIME 6 5035
TEST_TIME 7 5249
TEST_TIME 8 5553
TEST_TIME 9 5604
TEST_TIME 10 5587
TEST_TIME 11 5757
TEST_TIME 12 5868
TEST_TIME 13 6149
TEST_TIME 14 6284
TEST_TIME 15 6312
TEST_TIME 16 6780
TEST_TIME 17 6823
TEST_TIME 18 7045
TEST_TIME 19 6892
TEST_TIME 20 6876
TEST_TIME 21 7075
TEST_TIME 22 7337
TEST_TIME 23 7640
TEST_TIME 24 7686
TEST_TIME 25 8130
TEST_TIME 26 8528
TEST_TIME 27 8609
TEST_TIME 28 8947
TEST_TIME 29 9795
TEST_TIME 30 10016
TEST_TIME 31 9921
TEST_TIME 32 9840
TEST_TIME 33 9860
TEST_TIME 34 9931
TEST_TIME 35 9770
TEST_TIME 36 9669
TEST_TIME 37 9605
TEST_TIME 38 9696
TEST_TIME 39 9600
TEST_TIME 40 9626
TEST_TIME 41 9595
TEST_TIME 42 9610
TEST_TIME 43 9603
TEST_TIME 44 9484
TEST_TIME 45 9534
TEST_TIME 46 9640
TEST_TIME 47 9600
TEST_TIME 48 9465
TEST_TIME 49 9277
TEST_TIME 50 9097
TEST_TIME 51 9076
TEST_TIME 52 8945
TEST_TIME 53 9001
TEST_TIME 54 8979
TEST_TIME 55 8996
TEST_TIME 56 8928
TEST_TIME 57 8851
TEST_TIME 58 8824
TEST_TIME 59 8730
TEST_TIME 60 8780
TEST_TIME 61 8822
TEST_TIME 62 8782
TEST_TIME 63 8830
TEST_TIME 64 8871
TEST_TIME 65 8871
TEST_TIME 66 8849
TEST_TIME 67 8933
TEST_TIME 68 8933
TEST_TIME 69 8891
TEST_TIME 70 8958
TEST_TIME 71 8926
TEST_TIME 72 8912
TEST_TIME 73 9030
TEST_TIME 74 9014
TEST_TIME 75 8964
TEST_TIME 76 8851
TEST_TIME 77 8769
TEST_TIME 78 8764
TEST_TIME 79 8693
TEST_TIME 80 8626
TEST_TIME 81 8652
TEST_TIME 82 8610
TEST_TIME 83 8644
TEST_TIME 84 8648
TEST_TIME 85 8624
TEST_TIME 86 8637
TEST_TIME 87 8589
TEST_TIME 88 8692
TEST_TIME 89 8684
TEST_TIME 90 8743
TEST_TIME 91 8716
TEST_TIME 92 8768
TEST_TIME 93 8744
TEST_TIME 94 8814
TEST_TIME 95 8831
TEST_TIME 96 8807
TEST_TIME 97 8749
TEST_TIME 98 8676
TEST_TIME 99 8665
TEST_TIME 100 8658
TEST_TIME 101 8597
TEST_TIME 102 8552
TEST_TIME 103 8593
TEST_TIME 104 8588
TEST_TIME 105 8588
TEST_TIME 106 8584
TEST_TIME 107 8565
TEST_TIME 108 8574
TEST_TIME 109 8610
TEST_TIME 110 8641
TEST_TIME 111 8646
TEST_TIME 112 8655
TEST_TIME 113 8638
TEST_TIME 114 8650
TEST_TIME 115 8578
TEST_TIME 116 8550
TEST_TIME 117 8522
TEST_TIME 118 8506
TEST_TIME 119 8462
TEST_TIME 120 8440

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-01-2021 04:28:40 to 28-01-2021 04:28:43.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1414600 1414600 0 0.00 0 22846 7 0 0
doc_list_biud 1414600 1414600 0 0.00 0 52482 33 0 0
sp_add_invoice_to_stock 4836 4745 91 1.88 27 194063 19148 0 91
sp_cancel_adding_invoice 5010 5010 0 0.00 5 55169 960 0 0
sp_cancel_client_order 1689 1689 0 0.00 5 12453 400 0 0
sp_cancel_customer_reserve 498073 498073 0 0.00 1 22002 75 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 3 8047 112 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 3 1820 85 0 0
sp_cancel_supplier_invoice 5605 5596 9 0.16 1 52493 1010 0 9
sp_cancel_supplier_order 379 379 0 0.00 5 1115030 124378 0 0
sp_cancel_write_off 8559 8559 0 0.00 1 7478 130 0 0
sp_client_order 19642 19642 0 0.00 4 36254 396 0 0
sp_customer_reserve 433922 433922 0 0.00 0 53868 191 0 0
sp_fill_shopping_cart 30204 30204 0 0.00 1 26570 46 0 0
sp_fill_shopping_cart_clo_res 433921 433921 0 0.00 0 18396 18 0 0
sp_get_clo_for_invoice 4739 4739 0 0.00 0 30231 367 0 0
sp_kill_cost_storno 14645 14645 0 0.00 0 9546 69 0 0
sp_kill_qstorno_ret_qs2qd 1010680 1010680 0 0.00 0 52481 40 0 0
sp_kill_qty_storno 533779 533779 0 0.00 0 52482 87 0 0
sp_lock_selected_doc 20094 20094 0 0.00 0 8034 5 0 0
sp_make_cost_storno 26982 26982 0 0.00 0 13337 52 0 0
sp_multiply_rows_for_pdistr 26982 26982 0 0.00 0 7683 29 0 0
sp_multiply_rows_for_qdistr 19642 19642 0 0.00 0 10545 199 0 0
sp_pay_from_customer 6889 6889 0 0.00 4 13966 109 0 0
sp_pay_to_supplier 5620 5620 0 0.00 5 14910 132 0 0
sp_payment_common 12509 12509 0 0.00 1 10298 21 0 0
sp_qd_handle_on_cancel_clo 1689 1689 0 0.00 0 12437 370 0 0
sp_qd_handle_on_invoice_upd_sts 9738 9738 0 0.00 0 32965 414 0 0
sp_qd_handle_on_reserve_upd_sts 9745 9745 0 0.00 0 7064 47 0 0
sp_reserve_write_off 9745 9745 0 0.00 6 9798 166 0 0
sp_supplier_invoice 5292 5292 0 0.00 6 132869 3780 0 0
sp_supplier_order 5282 5282 0 0.00 6 91353 1496 0 0
srv_aggregate_perf_data 98 98 0 0.00 2196 366301 20468 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2337 262421 50050 0 0
srv_find_qd_qs_mism 967402 967402 0 0.00 0 22845 6 0 0
srv_make_invnt_saldo 431 431 0 0.00 37 111557 4031 0 0
srv_make_money_saldo 623 623 0 0.00 1 18349 750 0 0
srv_recalc_idx_stat 4 4 0 0.00 121100 442100 290160 0 0
t$perf-abend:sp_add_invoice_to_stock 91 91 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4745 4745 0 0.00 0 15623 198 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 2873 74 0 0
t$perf-norm:sp_cancel_client_order 1689 1689 0 0.00 0 1309 10 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 1389 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 2183 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 305 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 1480 11 0 0
t$perf-norm:sp_cancel_supplier_order 379 379 0 0.00 0 25993 1635 0 0
t$perf-norm:sp_cancel_write_off 1000 1000 0 0.00 0 2780 8 0 0
t$perf-norm:sp_client_order 19642 19642 0 0.00 0 15805 21 0 0
t$perf-norm:sp_customer_reserve 948 948 0 0.00 0 1895 7 0 0
t$perf-norm:sp_pay_from_customer 6889 6889 0 0.00 0 7056 15 0 0
t$perf-norm:sp_pay_to_supplier 5620 5620 0 0.00 0 4249 9 0 0
t$perf-norm:sp_reserve_write_off 9745 9745 0 0.00 0 7741 11 0 0
t$perf-norm:sp_supplier_invoice 5292 5292 0 0.00 0 12173 25 0 0
t$perf-norm:sp_supplier_order 5282 5282 0 0.00 0 4354 16 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 357 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 74 3 0 0
t$perf-norm:srv_make_invnt_saldo 431 431 0 0.00 0 2292 19 0 0
t$perf-norm:srv_make_money_saldo 623 623 0 0.00 0 702 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9638 9456 182 1.88 0 7046 18 0 182
v_all_customers 35968 35968 0 0.00 0 37 1 0 0
v_all_suppliers 29076 29076 0 0.00 0 48 1 0 0
v_all_wares 39278 39278 0 0.00 0 141 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 1529 10 0 0
v_cancel_client_order 3378 3378 0 0.00 0 7099 8 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 1306 5 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 7074 12 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 563 6 0 0
v_cancel_supplier_invoice 812 794 18 2.21 0 422 13 0 18
v_cancel_supplier_order 758 758 0 0.00 0 3500 8 0 0
v_cancel_write_off 2000 2000 0 0.00 0 3400 16 0 0
v_max_id_clo_ord 5282 5282 0 0.00 0 2808 2 0 0
v_max_id_clo_res 948 948 0 0.00 0 6510 21 0 0
v_max_id_ord_sup 5282 5282 0 0.00 0 2321 2 0 0
v_max_non_paid_invoice 1646 1646 0 0.00 0 5041 6 0 0
v_max_non_paid_realizn 3680 3680 0 0.00 0 1741 4 0 0
v_min_id_clo_ord 5282 5282 0 0.00 0 4690 9 0 0
v_min_id_clo_res 948 948 0 0.00 0 4206 12 0 0
v_min_id_ord_sup 5282 5282 0 0.00 0 5812 9 0 0
v_min_non_paid_invoice 5620 5620 0 0.00 0 10293 14 0 0
v_min_non_paid_realizn 6889 6889 0 0.00 0 5930 14 0 0
v_our_firm 9728 9728 0 0.00 0 15 1 0 0
v_reserve_write_off 19490 19490 0 0.00 0 6770 6 0 0
x_lock_depdocs_on_canc_invoice 10209 10209 0 0.00 0 18518 133 0 0
x_lock_depdocs_on_canc_sup_ord 379 379 0 0.00 0 16351 382 0 0
x_make_qstorno_1000_1200 5282 5282 0 0.00 2 91343 1407 0 0
x_make_qstorno_1200_2000 5292 5292 0 0.00 3 132833 3611 0 0
x_make_qstorno_2100_3300 433922 433922 0 0.00 0 53866 170 0 0

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

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
01:56:57 1 8192000000 8796640128 8807108608 3168304 0.038 1 1 2 0 3265920 61840 35776
01:57:58 26493 8192000000 9726293696 10022387712 800478208 8.901 100 86 173 0 828560960 7011616 21071136
01:59:30 7809 8192000000 9759947376 10064744448 839015024 9.290 100 84 166 1 870128880 11374528 19739328
02:00:41 20211 8192000000 9798690368 10112442368 863065040 9.531 100 82 164 1 899167408 17012208 19090160
02:02:07 17511 8192000000 9864904912 10190864384 908527360 9.983 100 81 162 1 965241680 39192368 17521952
02:03:32 8060 8192000000 9904113792 10247991296 951936928 10.410 100 73 144 2 1025473424 59187264 14349232
02:04:46 5686 8192000000 9963338096 10310520832 990290016 10.784 100 84 166 1 1077373808 70400944 16682848
02:05:54 3171 8192000000 10019026368 10377789440 1023666000 11.107 100 90 179 0 1129593184 88603264 17323920
02:07:00 3758 8192000000 9998001312 10364837888 1038345968 11.249 100 69 138 0 1111667056 60101088 13220000
02:08:05 2366 8192000000 10029734000 10398076928 1064832976 11.503 100 92 182 1 1148260032 64003680 19423376
02:09:11 1602 8192000000 10038340624 10409275392 1070911952 11.561 100 94 191 2 1146694128 56330976 19451200
02:10:14 3704 8192000000 10043583552 10440310784 1072002400 11.571 100 46 90 1 1150062416 69040944 9019072
02:11:18 4886 8192000000 9999792976 10388025344 1080965856 11.657 100 48 95 3 1115226256 23351328 10909072
02:12:26 56250 8192000000 10035675888 10421149696 1091779024 11.760 100 59 123 1 1129720144 23903360 14037760
02:14:24 6397 8192000000 9995618816 10396016640 1099292976 11.831 100 44 78 6 1117118992 7939648 9886368
02:15:33 8326 8192000000 10007419680 10404106240 1089049584 11.734 99 40 77 0 1119002560 21805600 8147376
02:16:42 10533 8192000000 10003909408 10403172352 1087157744 11.716 99 28 56 0 1111586448 17682768 6745936
02:17:57 29710 8192000000 10011558160 10410246144 1081754960 11.664 99 38 76 0 1117835120 27824560 8255600
02:19:29 6561 8192000000 9965738080 10353475584 1031498992 11.183 96 44 89 1 1058833536 15878784 11455760
02:20:38 5067 8192000000 9950824496 10321854464 1031016912 11.178 99 38 76 2 1059580864 19180064 9383888
02:21:46 9970 8192000000 9955474480 10336935936 1023722928 11.108 100 29 58 0 1058838832 28652416 6463488
02:22:56 32820 8192000000 9921750864 10302464000 957114528 10.461 93 27 54 0 981953344 18615552 6223264
02:24:32 3886 8192000000 9962465984 10342199296 1049113664 11.352 100 57 112 0 1074698432 12357584 13227184
02:25:38 11343 8192000000 9976675712 10365636608 1061831760 11.474 100 38 74 2 1079256400 7625920 9798720
02:26:52 97619 8192000000 10006242064 10403540992 1091306464 11.755 100 33 67 1 1113752432 15419440 7026528
02:29:38 8139 8192000000 10077476960 10475712512 1109401472 11.927 100 53 107 0 1193121184 73382976 10336736
02:30:51 25764 8192000000 10087281872 10509615104 1124242448 12.067 100 58 114 1 1204798928 68177328 12379152
02:32:25 2644 8192000000 10082095920 10474643456 1133688672 12.156 100 61 118 2 1184412032 37453520 13269840
02:33:32 40287 8192000000 10151783984 10567766016 1150660880 12.316 100 58 115 1 1243872880 81573328 11638672
02:35:16 10146 8192000000 10181227648 10590138368 1161682992 12.419 100 64 123 1 1284324016 110058432 12582592
02:36:39 8017 8192000000 10199490304 10606944256 1148213952 12.293 99 62 124 0 1310320304 148595696 13510656
02:37:52 2334 8192000000 10212690864 10619133952 1162893472 12.430 100 68 136 0 1317591568 139009536 15688560
02:38:59 4294 8192000000 10253422496 10659803136 1176970576 12.562 100 71 138 1 1356152704 163904496 15277632
02:40:08 4651 8192000000 10271051760 10676948992 1170194192 12.499 100 77 152 3 1371196208 183215536 17786480
02:41:22 3643 8192000000 10326566576 10734772224 1182435968 12.613 100 76 150 0 1425820528 226253552 17131008
02:42:31 7149 8192000000 10306767808 10742980608 1187845968 12.663 100 59 116 2 1428065312 226308832 13910512
02:43:41 4587 8192000000 10352993920 10759491584 1194887264 12.729 100 78 156 1 1448122512 234678624 18556624
02:44:52 2824 8192000000 10348113664 10755776512 1195210640 12.732 100 77 151 2 1446143248 233026816 17905792
02:46:01 4088 8192000000 10400415200 10810920960 1197205808 12.750 100 76 150 0 1495443984 281161136 17077040
02:47:10 7122 8192000000 10440567488 10849300480 1183256416 12.621 100 75 150 2 1538446432 338375744 16814272
02:48:24 4682 8192000000 10429218768 10856620032 1173485968 12.529 99 62 120 2 1522299680 333659312 15154400
02:49:36 11256 8192000000 10400314288 10834571264 1139803024 12.214 97 47 94 1 1477884416 326746752 11334640
02:51:03 9479 8192000000 10415652960 10835464192 1138542704 12.202 100 45 92 0 1520223856 369768624 11912528
02:52:22 13610 8192000000 10351990224 10792669184 1135389744 12.172 99 28 57 0 1451538512 308868176 7280592
02:53:45 21398 8192000000 10315557680 10778988544 1120690816 12.034 99 22 43 0 1407495120 280537536 6266768
02:55:09 19083 8192000000 10248491776 10677551104 1097018624 11.809 98 21 42 3 1360122016 257574624 5528768
02:56:30 86499 8192000000 10042977584 10488889344 1098482688 11.823 98 37 71 2 1138378016 31942544 7952784
02:59:04 38309 8192000000 10122897360 10577129472 1114276112 11.973 99 12 24 0 1190195232 73313984 2605136
03:00:43 76268 8192000000 10042481440 10465103872 1064671504 11.501 96 14 27 0 1109568272 42410240 2486528
03:03:00 107523 8192000000 10084478112 10576441344 1110078080 11.933 98 14 27 1 1220647424 107530800 3038544
03:05:54 44730 8192000000 10092401136 10513371136 1121832368 12.044 99 36 72 2 1164670944 35311056 7527520
03:07:39 110882 8192000000 10088528400 10528870400 1106964048 11.904 98 22 42 1 1171074560 59320384 4790128
03:10:32 35759 8192000000 10085015008 10550231040 1123784752 12.063 100 25 51 0 1193820672 64332464 5703456
03:12:31 262417 8192000000 10048230384 10483130368 1131613360 12.137 100 15 29 1 1169970112 35368432 2988320
03:17:56 54577 8192000000 10145192704 10593435648 1148610288 12.296 100 31 62 1 1234357248 78790064 6956896
03:19:55 53164 8192000000 10111491904 10598469632 1144860464 12.261 100 13 26 0 1213305264 65693488 2751312
03:21:49 41300 8192000000 10079073344 10522271744 1130119952 12.122 99 23 45 1 1171017328 35843680 5053696
03:23:33 56301 8192000000 10121018896 10557222912 1130868848 12.130 98 19 39 0 1179365536 44479312 4017376
03:25:33 89069 8192000000 10120118704 10622484480 1153557936 12.343 100 13 26 0 1262445040 105966192 2920912
03:28:04 74224 8192000000 10120429712 10570145792 1122879616 12.054 97 16 32 0 1167820832 41908832 3032384
03:30:19 44675 8192000000 10084705424 10554105856 1145703456 12.269 99 7 14 0 1171413072 24526672 1182944
03:32:08 23605 8192000000 10093994240 10521776128 1129085264 12.113 99 36 70 2 1180820784 44333888 7401632
03:33:33 55045 8192000000 10067471760 10525650944 1119730048 12.024 99 26 52 0 1166735312 41827840 5177424
03:35:34 46877 8192000000 10094399200 10541002752 1109605744 11.929 98 32 62 1 1194809664 78100976 7102944
03:37:29 18976 8192000000 10050516928 10471792640 1096429792 11.804 100 45 90 0 1142840208 37373008 9037408
03:38:53 76961 8192000000 10037593136 10491670528 1085672288 11.701 98 20 39 0 1156140624 66447600 4020736
03:41:11 61217 8192000000 10037712112 10476716032 1100249104 11.840 99 5 10 0 1135452608 33753872 1449632
03:43:18 75203 8192000000 10057827008 10522120192 1116273152 11.992 100 26 52 0 1178257216 56160944 5823120
03:45:40 73370 8192000000 10096976816 10539204608 1062292144 11.478 95 15 30 0 1136409056 70918576 3198336
03:47:55 67665 8192000000 10089433280 10536595456 1109934176 11.932 100 18 36 0 1185062768 70723856 4404736
03:50:29 45082 8192000000 10030885072 10504318976 1070569568 11.558 97 16 32 0 1137192144 62886016 3736560
03:52:27 30870 8192000000 10044228304 10474119168 1061921440 11.475 97 18 36 0 1129873296 64095232 3856624
03:54:10 91030 8192000000 10076076688 10502782976 1072148672 11.573 97 15 30 0 1122780096 47630912 3000512
03:56:42 58736 8192000000 10059433424 10484416512 1098606928 11.824 99 21 42 0 1173555840 70706960 4241952
03:58:43 44336 8192000000 10058912768 10483347456 1105636496 11.891 99 17 34 1 1134532880 25693776 3202608
04:00:28 170929 8192000000 10098622080 10522992640 1097066992 11.810 97 15 30 0 1150315856 49910768 3338096
04:06:00 85340 8192000000 10079950752 10586591232 1135777344 12.176 100 6 12 0 1183324848 46402896 1144608
04:08:26 83272 8192000000 10095455440 10588971008 1142067936 12.235 100 15 30 1 1194080448 48388816 3623696
04:10:52 42654 8192000000 10089103792 10543915008 1128117200 12.104 99 29 57 2 1170978848 36024272 6837376
04:12:40 57973 8192000000 10111387360 10535993344 1109170544 11.925 97 28 54 0 1156240416 41495648 5574224
04:14:49 76657 8192000000 10095137456 10569457664 1105006480 11.885 97 16 32 1 1183655760 75018336 3630944
04:17:20 63694 8192000000 10104456688 10549702656 1148919824 12.299 100 17 34 2 1186640672 33903072 3817776
04:19:28 40428 8192000000 10104445472 10540441600 1115589568 11.985 97 37 75 1 1179445392 55657936 8197888
04:21:13 124246 8192000000 10086557920 10542260224 1121186672 12.038 98 20 40 0 1186384048 60968544 4228832
04:24:19 61184 8192000000 10097233200 10603765760 1139594656 12.212 100 6 12 0 1193985232 52857744 1532832
04:26:25 39188 8192000000 9117489872 9412354048 257043120 3.042 24 9 15 1 284586000 25696256 1846624

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-01-2021 04:28:50 to 28-01-2021 04:29:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 91 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-01-2021 04:29:06 to 28-01-2021 04:29:08.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          229435
MON$OLDEST_ACTIVE               229436
MON$OLDEST_SNAPSHOT             229436
MON$NEXT_TRANSACTION            229436
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-30 07:30:08.6030 GMT
MON$PAGES                       2093376
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             785
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2342 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2342 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2342 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2342 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.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 ---
:69: 
:70: mscs-test-161.budzdorov.ru    Thu Jan 28 04:27:06 2021
:71:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:72: 
:73: 
:74: mscs-test-161.budzdorov.ru    Thu Jan 28 04:27:08 2021
:75:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:76: 
--- end of diff output ---

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

28.01.21 04:29:09. There are 3 logs in /var/tmp/logs-oltp40 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-01-28 04:29:11 No severe PSQL-related problems occured
28.01.21 04:29:12. End of report.