Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.02.2021 04:30
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 16561012 kB
MemAvailable 31152508 kB
Buffers 20 kB
Cached 15087972 kB
SwapCached 4640 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-02-15 04:26:56
DTS_END                         2021-02-15 04:26:56.6970
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/60360
TRN_ID                          234381
ATT_ID                          669
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: 8741.63 24787 1048996
customer order: creation 169.06 423 20287
customer order: refuse 14.53 312 1743
order to supplier: creation 43.94 1349 5273
order to supplier: removal 3.23 122577 388
invoice (draft): creation 44.48 4023 5338
invoice (draft): removal 49.20 1028 5904
invoice accept: apply 39.97 19132 4796
invoice accept: cancel 44.14 1016 5297
customer reserve: creation 3665.61 188 439873
customer reserve: removal 4384.79 71 526175
realization accept: apply 80.43 180 9651
realization accept: cancel 77.72 126 9327
payment from customer: creation 56.79 113 6815
payment from customer: removal 5.42 83 650
payment to supplier: creation 47.63 126 5715
payment to supplier: removal 3.66 70 439
service: total inventory turnovers 4.12 3285 494
service: total monetary turnovers 6.02 544 722
service: aggregate perf. data 0.88 12297 105
service: refresh index statistics 0.03 328802 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-02-2021 04:30:31 to 15-02-2021 04:30:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5996
TEST_TIME 2 3432
TEST_TIME 3 3827
TEST_TIME 4 4286
TEST_TIME 5 4446
TEST_TIME 6 4254
TEST_TIME 7 4745
TEST_TIME 8 4928
TEST_TIME 9 4793
TEST_TIME 10 4938
TEST_TIME 11 5145
TEST_TIME 12 5309
TEST_TIME 13 5619
TEST_TIME 14 6288
TEST_TIME 15 6284
TEST_TIME 16 6579
TEST_TIME 17 6732
TEST_TIME 18 6799
TEST_TIME 19 7246
TEST_TIME 20 7373
TEST_TIME 21 7811
TEST_TIME 22 8168
TEST_TIME 23 8269
TEST_TIME 24 8533
TEST_TIME 25 8281
TEST_TIME 26 8661
TEST_TIME 27 9084
TEST_TIME 28 10468
TEST_TIME 29 10673
TEST_TIME 30 10506
TEST_TIME 31 10515
TEST_TIME 32 10440
TEST_TIME 33 10290
TEST_TIME 34 10148
TEST_TIME 35 10020
TEST_TIME 36 9884
TEST_TIME 37 9688
TEST_TIME 38 9627
TEST_TIME 39 9738
TEST_TIME 40 9646
TEST_TIME 41 9503
TEST_TIME 42 9391
TEST_TIME 43 9306
TEST_TIME 44 9245
TEST_TIME 45 9222
TEST_TIME 46 9129
TEST_TIME 47 9050
TEST_TIME 48 9001
TEST_TIME 49 8968
TEST_TIME 50 8932
TEST_TIME 51 8859
TEST_TIME 52 8877
TEST_TIME 53 8857
TEST_TIME 54 8974
TEST_TIME 55 8923
TEST_TIME 56 8924
TEST_TIME 57 8907
TEST_TIME 58 8987
TEST_TIME 59 8929
TEST_TIME 60 8994
TEST_TIME 61 9031
TEST_TIME 62 9081
TEST_TIME 63 9158
TEST_TIME 64 9173
TEST_TIME 65 9050
TEST_TIME 66 9066
TEST_TIME 67 8988
TEST_TIME 68 8952
TEST_TIME 69 8962
TEST_TIME 70 8921
TEST_TIME 71 8902
TEST_TIME 72 8890
TEST_TIME 73 8926
TEST_TIME 74 8910
TEST_TIME 75 8990
TEST_TIME 76 8985
TEST_TIME 77 9048
TEST_TIME 78 9067
TEST_TIME 79 9080
TEST_TIME 80 9158
TEST_TIME 81 9217
TEST_TIME 82 9177
TEST_TIME 83 9131
TEST_TIME 84 9046
TEST_TIME 85 8970
TEST_TIME 86 8934
TEST_TIME 87 8908
TEST_TIME 88 8850
TEST_TIME 89 8815
TEST_TIME 90 8795
TEST_TIME 91 8760
TEST_TIME 92 8741
TEST_TIME 93 8723
TEST_TIME 94 8763
TEST_TIME 95 8822
TEST_TIME 96 8841
TEST_TIME 97 8843
TEST_TIME 98 8869
TEST_TIME 99 8848
TEST_TIME 100 8875
TEST_TIME 101 8859
TEST_TIME 102 8904
TEST_TIME 103 8913
TEST_TIME 104 8950
TEST_TIME 105 8876
TEST_TIME 106 8843
TEST_TIME 107 8809
TEST_TIME 108 8781
TEST_TIME 109 8780
TEST_TIME 110 8767
TEST_TIME 111 8728
TEST_TIME 112 8692
TEST_TIME 113 8769
TEST_TIME 114 8747
TEST_TIME 115 8738
TEST_TIME 116 8806
TEST_TIME 117 8779
TEST_TIME 118 8757
TEST_TIME 119 8747
TEST_TIME 120 8746
TEST_TIME 121 8676

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-02-2021 04:30:34 to 15-02-2021 04:30:38.

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 1457493 1457493 0 0.00 0 29559 7 0 0
doc_list_biud 1457489 1457489 0 0.00 0 56669 33 0 0
sp_add_invoice_to_stock 4918 4796 122 2.48 28 264627 19132 0 122
sp_cancel_adding_invoice 5297 5297 0 0.00 6 82301 1016 0 0
sp_cancel_client_order 1743 1743 0 0.00 4 13849 313 0 0
sp_cancel_customer_reserve 526175 526175 0 0.00 1 52653 72 0 0
sp_cancel_pay_from_customer 650 650 0 0.00 3 7940 84 0 0
sp_cancel_pay_to_supplier 439 439 0 0.00 3 2784 71 0 0
sp_cancel_supplier_invoice 5912 5904 8 0.13 2 28685 1029 0 8
sp_cancel_supplier_order 388 388 0 0.00 9 1105593 122578 0 0
sp_cancel_write_off 9327 9327 0 0.00 1 8104 126 0 0
sp_client_order 20287 20287 0 0.00 4 51214 423 0 0
sp_customer_reserve 439873 439873 0 0.00 0 101200 188 0 0
sp_fill_shopping_cart 30891 30891 0 0.00 1 29816 52 0 0
sp_fill_shopping_cart_clo_res 439862 439862 0 0.00 0 31387 21 0 0
sp_get_clo_for_invoice 4783 4783 0 0.00 0 53549 604 0 0
sp_kill_cost_storno 15713 15713 0 0.00 0 6976 62 0 0
sp_kill_qstorno_ret_qs2qd 1067969 1067969 0 0.00 0 51879 39 0 0
sp_kill_qty_storno 563265 563265 0 0.00 0 56669 84 0 0
sp_lock_selected_doc 20058 20058 0 0.00 0 9294 4 0 0
sp_make_cost_storno 26964 26964 0 0.00 0 23286 62 0 0
sp_multiply_rows_for_pdistr 26962 26962 0 0.00 0 8953 29 0 0
sp_multiply_rows_for_qdistr 20287 20287 0 0.00 0 51167 232 0 0
sp_pay_from_customer 6815 6815 0 0.00 4 15337 114 0 0
sp_pay_to_supplier 5715 5715 0 0.00 4 14712 126 0 0
sp_payment_common 12529 12529 0 0.00 1 14396 26 0 0
sp_qd_handle_on_cancel_clo 1743 1743 0 0.00 0 13838 287 0 0
sp_qd_handle_on_invoice_upd_sts 10079 10079 0 0.00 0 56667 392 0 0
sp_qd_handle_on_reserve_upd_sts 9649 9649 0 0.00 0 27753 57 0 0
sp_reserve_write_off 9651 9651 0 0.00 7 29572 181 0 0
sp_supplier_invoice 5338 5338 0 0.00 7 139296 4024 0 0
sp_supplier_order 5273 5273 0 0.00 6 55802 1350 0 0
srv_aggregate_perf_data 105 105 0 0.00 2599 68011 12297 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1479 162645 47646 0 0
srv_find_qd_qs_mism 1003575 1003575 0 0.00 0 7654 6 0 0
srv_make_invnt_saldo 494 494 0 0.00 43 103677 3285 0 0
srv_make_money_saldo 722 722 0 0.00 1 17962 545 0 0
srv_recalc_idx_stat 4 4 0 0.00 232615 465592 328802 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4796 4796 0 0.00 0 14635 168 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 0 5003 101 0 0
t$perf-norm:sp_cancel_client_order 1743 1743 0 0.00 0 2163 10 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 4708 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 650 650 0 0.00 0 355 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 439 439 0 0.00 0 1524 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 4416 26 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 21131 1623 0 0
t$perf-norm:sp_cancel_write_off 943 943 0 0.00 0 5984 23 0 0
t$perf-norm:sp_client_order 20287 20287 0 0.00 0 10703 19 0 0
t$perf-norm:sp_customer_reserve 925 925 0 0.00 0 4926 12 0 0
t$perf-norm:sp_pay_from_customer 6815 6815 0 0.00 0 28135 15 0 0
t$perf-norm:sp_pay_to_supplier 5715 5715 0 0.00 0 11012 14 0 0
t$perf-norm:sp_reserve_write_off 9651 9651 0 0.00 0 7395 10 0 0
t$perf-norm:sp_supplier_invoice 5338 5338 0 0.00 0 10564 19 0 0
t$perf-norm:sp_supplier_order 5273 5273 0 0.00 0 11292 15 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 128 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 12 1 0 0
t$perf-norm:srv_make_invnt_saldo 494 494 0 0.00 0 636 7 0 0
t$perf-norm:srv_make_money_saldo 722 722 0 0.00 0 3031 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9806 9562 244 2.48 0 5500 17 0 244
v_all_customers 37130 37130 0 0.00 0 118 1 0 0
v_all_suppliers 29514 29514 0 0.00 0 66 1 0 0
v_all_wares 40570 40570 0 0.00 0 42 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 1166 8 0 0
v_cancel_client_order 3486 3486 0 0.00 0 3872 5 0 0
v_cancel_customer_prepayment 1300 1300 0 0.00 0 1646 7 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 6165 11 0 0
v_cancel_payment_to_supplier 878 878 0 0.00 0 422 4 0 0
v_cancel_supplier_invoice 814 798 16 1.96 0 4942 28 0 16
v_cancel_supplier_order 776 776 0 0.00 0 1731 8 0 0
v_cancel_write_off 1886 1886 0 0.00 0 897 12 0 0
v_max_id_clo_ord 5272 5272 0 0.00 0 208 1 0 0
v_max_id_clo_res 925 925 0 0.00 0 3555 21 0 0
v_max_id_ord_sup 5334 5334 0 0.00 0 6413 10 0 0
v_max_non_paid_invoice 1563 1563 0 0.00 0 904 3 0 0
v_max_non_paid_realizn 3570 3570 0 0.00 0 4223 6 0 0
v_min_id_clo_ord 5272 5272 0 0.00 0 1560 5 0 0
v_min_id_clo_res 925 925 0 0.00 0 1259 4 0 0
v_min_id_ord_sup 5334 5334 0 0.00 0 9475 16 0 0
v_min_non_paid_invoice 5714 5714 0 0.00 0 13048 22 0 0
v_min_non_paid_realizn 6815 6815 0 0.00 0 5484 15 0 0
v_our_firm 9930 9930 0 0.00 0 20 1 0 0
v_reserve_write_off 19298 19298 0 0.00 0 4516 6 0 0
x_lock_depdocs_on_canc_invoice 10802 10802 0 0.00 0 15515 120 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 13728 400 0 0
x_make_qstorno_1000_1200 5273 5273 0 0.00 2 55776 1274 0 0
x_make_qstorno_1200_2000 5338 5338 0 0.00 3 128592 3810 0 0
x_make_qstorno_2100_3300 439873 439873 0 0.00 0 101199 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-02-2021 04:30:39 to 15-02-2021 04:30:43.

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:57:00 19 8192000000 8813925360 8830906368 13354864 0.162 6 4 2 0 13467712 77072 35776
01:58:00 30350 8192000000 9733173520 10030526464 807811616 8.975 100 90 180 0 837736928 8025296 21900016
01:59:37 15140 8192000000 9770423424 10075566080 844632256 9.346 100 90 180 1 879797568 13658752 21506560
02:00:59 18028 8192000000 9815511104 10133348352 873781568 9.638 100 81 162 1 918141920 26094640 18265712
02:02:32 10594 8192000000 9874538800 10205589504 915908032 10.056 100 79 158 1 976028080 43128736 16991312
02:03:45 8510 8192000000 9927816496 10270818304 962907680 10.517 100 90 179 3 1046404352 65271728 18224944
02:04:56 5649 8192000000 9961900800 10318376960 995132800 10.831 100 77 153 2 1077794352 67426816 15234736
02:06:03 2452 8192000000 10008919216 10372440064 1023651840 11.107 100 86 171 2 1128074528 86927456 17495232
02:07:07 2731 8192000000 10020964976 10388873216 1052729296 11.387 100 93 186 1 1131527488 60383216 18414976
02:08:12 1286 8192000000 10044495136 10411040768 1062852832 11.484 100 94 187 0 1152374608 70499760 19022016
02:09:14 4517 8192000000 9999386400 10385219584 1065792832 11.512 100 62 121 2 1105688864 26182784 13713248
02:10:22 3457 8192000000 9992357680 10384052224 1074429680 11.594 100 33 65 0 1112689296 31806784 6452832
02:11:26 8373 8192000000 9997566528 10404388864 1084042176 11.686 100 24 44 3 1112432144 23872304 4517664
02:12:35 14316 8192000000 9999354560 10402390016 1086354304 11.708 100 33 65 2 1113707248 19761392 7591552
02:13:50 22310 8192000000 10025262528 10408849408 1091136032 11.753 100 74 151 0 1121829136 13897504 16795600
02:15:14 5471 8192000000 10034954080 10441895936 1095513200 11.795 100 49 97 1 1140136416 35073120 9550096
02:16:24 10894 8192000000 10006828528 10417491968 1083186000 11.678 99 34 68 1 1115734352 25255024 7293328
02:17:37 42258 8192000000 10004047280 10417618944 1056709952 11.425 97 36 72 0 1083005872 18467408 7828512
02:19:22 6660 8192000000 9951623168 10332712960 998788320 10.867 95 33 66 2 1021990464 15853760 7348384
02:20:31 5628 8192000000 9939667472 10312413184 1007352064 10.950 97 30 56 2 1033842400 20009904 6480432
02:21:37 7874 8192000000 9967391984 10362052608 1022097184 11.092 99 39 78 2 1064160400 33386976 8676240
02:22:45 32848 8192000000 9953458368 10336002048 1007950544 10.956 97 54 105 3 1042861856 23378448 11532864
02:24:22 8323 8192000000 9972641968 10374004736 1044962688 11.312 100 45 87 1 1084347840 29601280 9783872
02:25:31 7599 8192000000 9976530048 10377900032 1054279600 11.402 100 40 77 1 1076948352 14404016 8264736
02:26:39 6468 8192000000 9977557104 10374680576 1062890064 11.484 100 30 59 2 1083226256 13046912 7289280
02:27:54 99563 8192000000 10046108496 10450505728 1090455184 11.747 100 42 84 0 1135267824 35878016 8934624
02:30:41 13739 8192000000 10080298592 10489991168 1114251360 11.973 100 48 96 0 1183697264 59337248 10108656
02:32:05 32438 8192000000 10135563424 10548420608 1128497904 12.107 100 50 100 1 1235712560 95923472 11291184
02:33:43 8067 8192000000 10172286528 10579697664 1141690848 12.231 100 61 121 1 1286509152 132232064 12586240
02:34:58 1960 8192000000 10200079504 10603409408 1154219360 12.349 100 77 154 0 1310006784 138106368 17681056
02:36:13 8756 8192000000 10246667248 10661658624 1166912880 12.468 100 64 125 1 1358555376 177001264 14641232
02:37:29 3945 8192000000 10319709680 10721886208 1161694272 12.419 99 82 163 1 1414632976 234915552 18023152
02:38:43 7517 8192000000 10316672880 10733719552 1164584096 12.446 99 59 116 1 1410853280 232148288 14120896
02:39:55 6779 8192000000 10328340624 10776113152 1182092432 12.610 100 57 114 1 1431173664 235342496 13738736
02:41:07 13530 8192000000 10367872128 10781990912 1170094864 12.498 100 70 139 0 1469829504 283379264 16355376
02:42:29 6288 8192000000 10355380000 10774646784 1168606352 12.484 100 81 162 2 1462291024 274329168 19355504
02:43:43 2290 8192000000 10411506064 10817171456 1177447600 12.566 100 82 162 1 1504983248 309006464 18529184
02:44:49 1475 8192000000 10430284592 10840825856 1181169456 12.601 100 80 163 0 1527592352 326822656 19600240
02:45:57 6425 8192000000 10452095072 10887086080 1176877792 12.561 100 68 135 1 1553449792 360536512 16035488
02:47:05 8964 8192000000 10450663584 10876968960 1156488688 12.370 99 45 90 1 1541919120 374170656 11259776
02:48:15 10348 8192000000 10417925168 10843979776 1147386016 12.285 100 34 68 1 1515346848 359169360 8791472
02:49:31 17557 8192000000 10389601568 10825318400 1144922880 12.262 100 32 64 0 1490326480 336996704 8406896
02:50:55 15757 8192000000 10406034800 10845655040 1122354928 12.049 99 29 58 1 1488799232 358652640 7791664
02:52:14 38444 8192000000 10434239216 10890235904 1118366704 12.012 98 32 62 1 1511748160 386119552 7261904
02:54:10 24402 8192000000 10296179216 10782601216 1101921120 11.856 99 22 44 1 1415128928 307226432 5981376
02:55:49 80380 8192000000 10070472976 10539626496 1103760032 11.873 99 27 55 0 1157402080 48001040 5641008
02:58:25 71828 8192000000 10072816352 10516402176 1121944608 12.045 100 21 42 1 1179434560 53086992 4402960
03:01:00 72655 8192000000 10067209472 10507649024 1074201248 11.592 95 20 40 1 1126519968 48356736 3961984
03:03:21 38827 8192000000 10136879216 10571411456 1112767440 11.959 99 31 62 1 1216003744 96853408 6382896
03:05:35 105240 8192000000 10042373488 10517573632 1113591584 11.966 100 23 46 1 1141020048 22309616 5118848
03:08:23 46169 8192000000 10074601008 10518999040 1117060800 11.999 99 39 74 2 1159614784 34404336 8149648
03:10:36 155031 8192000000 10082192256 10543550464 1126337120 12.087 99 28 56 1 1175933488 43427168 6169200
03:14:12 45319 8192000000 10048639216 10534207488 1121983712 12.046 99 8 15 1 1158731264 34651824 2095728
03:15:57 91113 8192000000 10062444864 10544525312 1120823104 12.035 99 14 27 2 1153417120 29841968 2752048
03:18:33 74032 8192000000 10110908944 10561527808 1130677664 12.128 99 17 35 0 1214679296 79982224 4019408
03:21:03 38209 8192000000 10083655600 10526539776 1148028960 12.291 100 32 62 2 1190422160 35689888 6703312
03:22:41 66283 8192000000 10117326032 10575269888 1126451504 12.088 98 20 40 1 1171037872 40101440 4484928
03:24:50 28503 8192000000 10071095008 10515542016 1141104752 12.226 100 18 36 1 1164416976 19751904 3560320
03:26:28 68265 8192000000 10065389104 10526822400 1111551088 11.947 97 13 26 1 1170337744 56180544 2606112
03:28:43 88874 8192000000 10098034624 10578407424 1109862592 11.931 97 7 14 2 1187692544 76873424 956528
03:31:16 69970 8192000000 10092756560 10602504192 1102419808 11.861 97 18 36 0 1184994992 78561248 4013936
03:34:19 46955 8192000000 10084878384 10524487680 1105970368 11.894 98 16 32 0 1151775792 42668096 3137328
03:36:06 36191 8192000000 10072470528 10491027456 1091872240 11.760 97 40 80 2 1135468320 34253536 9342544
03:37:49 86929 8192000000 10116356112 10561761280 1091243216 11.754 97 15 29 0 1142324464 47959008 3122240
03:40:17 73036 8192000000 10082370800 10528088064 1097520720 11.814 97 30 60 2 1195801760 91119888 7161152
03:42:30 41518 8192000000 10091683504 10523910144 1024523552 11.116 92 39 78 1 1112752896 80432400 7796944
03:44:13 45921 8192000000 10075817136 10532507648 1059536608 11.452 96 30 60 0 1127799408 61818400 6444400
03:46:03 46210 8192000000 10077392416 10560417792 1084186592 11.687 98 15 29 2 1151153808 64017872 2949344
03:47:50 61902 8192000000 10072889664 10511650816 1075006528 11.600 97 16 32 1 1120674176 42604912 3062736
03:49:56 162645 8192000000 10072924288 10516185088 1091025904 11.752 98 23 46 0 1118703296 22911536 4765856
03:53:58 31366 8192000000 10033302064 10529132544 1101418384 11.851 100 5 10 0 1138107968 35765440 924144
03:55:29 26751 8192000000 10039787408 10505506816 1097144144 11.811 99 8 15 0 1136603104 38239824 1219136
03:57:06 78181 8192000000 10078161984 10527789056 1082366496 11.670 97 17 34 0 1139778480 54224672 3187312
03:59:24 47354 8192000000 10127696288 10591494144 1118662176 12.014 99 40 78 1 1192955168 66166736 8126256
04:01:21 36719 8192000000 10076265984 10516078592 1122253856 12.048 99 26 52 1 1183486560 55950384 5282320
04:03:11 36573 8192000000 10080048880 10515849216 1122761312 12.053 99 13 25 2 1178812064 53049088 3001664
04:04:50 23338 8192000000 10080715696 10509504512 1134391152 12.163 100 37 75 1 1167389888 25841872 7156864
04:06:14 16895 8192000000 10138432848 10570678272 1129183776 12.114 100 52 103 1 1210157856 70563552 10410528
04:07:38 15137 8192000000 10124356848 10552176640 1137622496 12.193 100 40 80 1 1200009008 53966224 8420288
04:08:53 63886 8192000000 10146582416 10601267200 1138964512 12.206 99 31 62 0 1233994144 88672848 6356784
04:10:58 90444 8192000000 10091280464 10604257280 1122718768 12.053 98 22 44 0 1176275808 49183696 4373344
04:13:31 84334 8192000000 10114134128 10572963840 1118655248 12.014 98 11 23 0 1143115856 22111408 2349200
04:15:58 57642 8192000000 10087983152 10524389376 1094374864 11.784 96 14 27 4 1136758800 39034128 3349808
04:17:59 68015 8192000000 10141768000 10619219968 1112806688 11.959 97 16 32 0 1205933808 89269200 3857920
04:20:35 77128 8192000000 10128580768 10574626816 1130833680 12.129 99 19 38 0 1204389264 69364112 4191472
04:23:00 34965 8192000000 10091046672 10517581824 1130474288 12.126 99 42 84 1 1159466848 20200432 8792128
04:24:39 45526 8192000000 10117765216 10540240896 1078481104 11.633 96 47 95 2 1140843984 53081232 9281648
04:26:24 97144 8192000000 9785878032 10214400000 842871232 9.329 76 18 34 0 906209856 59969744 3368880

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-02-2021 04:30:45 to 15-02-2021 04:31:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-02-2021 04:31:00 to 15-02-2021 04:31:02.

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          234381
MON$OLDEST_ACTIVE               234596
MON$OLDEST_SNAPSHOT             234596
MON$NEXT_TRANSACTION            234596
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                       2089056
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             802
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2365 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.2365 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 ---
:449: 
:450: mscs-test-161.budzdorov.ru    Mon Feb 15 04:28:30 2021
:451:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:452: 
:453: 
:454: mscs-test-161.budzdorov.ru    Mon Feb 15 04:28:32 2021
:455:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:456: 
--- end of diff output ---

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

15.02.21 04:31:03. 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-02-15 04:31:06 No severe PSQL-related problems occured
15.02.21 04:31:07. End of report.