Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.11.2020 13:27
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 17184332 kB
MemAvailable 31511412 kB
Buffers 0 kB
Cached 14388984 kB
SwapCached 2016 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 2020-11-30 13:26:52
DTS_END                         2020-11-30 13:26:52.8760
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(68:17)
IP                              ::1/56424
TRN_ID                          227383
ATT_ID                          607
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: 8799.68 28094 1055962
customer order: creation 161.69 429 19403
customer order: refuse 13.97 330 1676
order to supplier: creation 43.22 1328 5186
order to supplier: removal 3.18 113596 381
invoice (draft): creation 43.91 3300 5269
invoice (draft): removal 48.43 980 5811
invoice accept: apply 40.23 18709 4827
invoice accept: cancel 43.72 847 5246
customer reserve: creation 3832.68 180 459921
customer reserve: removal 4293.02 67 515163
realization accept: apply 76.81 169 9217
realization accept: cancel 76.97 124 9236
payment from customer: creation 56.33 97 6760
payment from customer: removal 5.38 73 646
payment to supplier: creation 45.99 120 5519
payment to supplier: removal 3.51 128 421
service: total inventory turnovers 3.95 3106 474
service: total monetary turnovers 5.79 480 695
service: aggregate perf. data 0.89 17090 107
service: refresh index statistics 0.03 400724 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5948
TEST_TIME 2 5428
TEST_TIME 3 5668
TEST_TIME 4 5958
TEST_TIME 5 6468
TEST_TIME 6 6429
TEST_TIME 7 6720
TEST_TIME 8 6649
TEST_TIME 9 6877
TEST_TIME 10 6663
TEST_TIME 11 6733
TEST_TIME 12 6687
TEST_TIME 13 6791
TEST_TIME 14 6808
TEST_TIME 15 7171
TEST_TIME 16 7254
TEST_TIME 17 7574
TEST_TIME 18 7769
TEST_TIME 19 7886
TEST_TIME 20 8367
TEST_TIME 21 8851
TEST_TIME 22 8749
TEST_TIME 23 8920
TEST_TIME 24 9101
TEST_TIME 25 9216
TEST_TIME 26 9607
TEST_TIME 27 10181
TEST_TIME 28 11058
TEST_TIME 29 10821
TEST_TIME 30 10472
TEST_TIME 31 10210
TEST_TIME 32 10028
TEST_TIME 33 10317
TEST_TIME 34 10218
TEST_TIME 35 10227
TEST_TIME 36 10039
TEST_TIME 37 10016
TEST_TIME 38 9914
TEST_TIME 39 9781
TEST_TIME 40 9909
TEST_TIME 41 9796
TEST_TIME 42 9766
TEST_TIME 43 9752
TEST_TIME 44 9768
TEST_TIME 45 9841
TEST_TIME 46 9856
TEST_TIME 47 9771
TEST_TIME 48 9784
TEST_TIME 49 9788
TEST_TIME 50 9836
TEST_TIME 51 9838
TEST_TIME 52 9894
TEST_TIME 53 9832
TEST_TIME 54 9709
TEST_TIME 55 9611
TEST_TIME 56 9581
TEST_TIME 57 9496
TEST_TIME 58 9402
TEST_TIME 59 9336
TEST_TIME 60 9295
TEST_TIME 61 9247
TEST_TIME 62 9194
TEST_TIME 63 9233
TEST_TIME 64 9187
TEST_TIME 65 9175
TEST_TIME 66 9225
TEST_TIME 67 9228
TEST_TIME 68 9229
TEST_TIME 69 9360
TEST_TIME 70 9319
TEST_TIME 71 9311
TEST_TIME 72 9361
TEST_TIME 73 9367
TEST_TIME 74 9379
TEST_TIME 75 9371
TEST_TIME 76 9314
TEST_TIME 77 9290
TEST_TIME 78 9244
TEST_TIME 79 9211
TEST_TIME 80 9148
TEST_TIME 81 9095
TEST_TIME 82 9044
TEST_TIME 83 8996
TEST_TIME 84 8960
TEST_TIME 85 8962
TEST_TIME 86 8995
TEST_TIME 87 8956
TEST_TIME 88 8954
TEST_TIME 89 9027
TEST_TIME 90 9042
TEST_TIME 91 9123
TEST_TIME 92 9259
TEST_TIME 93 9249
TEST_TIME 94 9221
TEST_TIME 95 9154
TEST_TIME 96 9107
TEST_TIME 97 9056
TEST_TIME 98 9001
TEST_TIME 99 8968
TEST_TIME 100 8929
TEST_TIME 101 8964
TEST_TIME 102 8939
TEST_TIME 103 8919
TEST_TIME 104 8918
TEST_TIME 105 8904
TEST_TIME 106 8920
TEST_TIME 107 8919
TEST_TIME 108 8922
TEST_TIME 109 8957
TEST_TIME 110 8987
TEST_TIME 111 8960
TEST_TIME 112 8992
TEST_TIME 113 8978
TEST_TIME 114 8924
TEST_TIME 115 8894
TEST_TIME 116 8869
TEST_TIME 117 8832
TEST_TIME 118 8800
TEST_TIME 119 8818
TEST_TIME 120 8808

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-11-2020 13:28:12 to 30-11-2020 13:28:15.

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 1479137 1479137 0 0.00 0 39603 7 0 0
doc_list_biud 1479135 1479135 0 0.00 0 88301 30 0 0
sp_add_invoice_to_stock 4877 4827 50 1.02 27 175451 18709 0 50
sp_cancel_adding_invoice 5246 5246 0 0.00 5 49465 848 0 0
sp_cancel_client_order 1676 1676 0 0.00 5 10173 331 0 0
sp_cancel_customer_reserve 515163 515163 0 0.00 1 47908 67 0 0
sp_cancel_pay_from_customer 646 646 0 0.00 3 3050 74 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 21531 129 0 0
sp_cancel_supplier_invoice 5819 5811 8 0.13 2 88307 980 0 8
sp_cancel_supplier_order 381 381 0 0.00 8 936599 113597 0 0
sp_cancel_write_off 9236 9236 0 0.00 1 39604 124 0 0
sp_client_order 19403 19403 0 0.00 4 21063 429 0 0
sp_customer_reserve 459921 459921 0 0.00 0 90776 181 0 0
sp_fill_shopping_cart 29844 29844 0 0.00 1 37569 43 0 0
sp_fill_shopping_cart_clo_res 459905 459905 0 0.00 0 24951 19 0 0
sp_get_clo_for_invoice 4810 4810 0 0.00 0 25894 384 0 0
sp_kill_cost_storno 15549 15549 0 0.00 0 13007 53 0 0
sp_kill_qstorno_ret_qs2qd 1045754 1045754 0 0.00 0 88300 37 0 0
sp_kill_qty_storno 551539 551539 0 0.00 0 88301 79 0 0
sp_lock_selected_doc 19536 19536 0 0.00 0 11788 6 0 0
sp_make_cost_storno 26305 26305 0 0.00 0 15606 49 0 0
sp_multiply_rows_for_pdistr 26305 26305 0 0.00 0 8097 30 0 0
sp_multiply_rows_for_qdistr 19403 19403 0 0.00 0 17109 255 0 0
sp_pay_from_customer 6760 6760 0 0.00 4 11265 97 0 0
sp_pay_to_supplier 5519 5519 0 0.00 4 12429 121 0 0
sp_payment_common 12279 12279 0 0.00 1 6383 20 0 0
sp_qd_handle_on_cancel_clo 1676 1676 0 0.00 0 10167 310 0 0
sp_qd_handle_on_invoice_upd_sts 10055 10055 0 0.00 0 22588 342 0 0
sp_qd_handle_on_reserve_upd_sts 9217 9217 0 0.00 0 9639 54 0 0
sp_reserve_write_off 9217 9217 0 0.00 6 21772 170 0 0
sp_supplier_invoice 5269 5269 0 0.00 8 103254 3300 0 0
sp_supplier_order 5186 5186 0 0.00 6 104119 1329 0 0
srv_aggregate_perf_data 107 107 0 0.00 2559 331383 17091 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1816 142573 46721 0 0
srv_find_qd_qs_mism 1008672 1008672 0 0.00 0 39600 6 0 0
srv_make_invnt_saldo 474 474 0 0.00 50 108027 3106 0 0
srv_make_money_saldo 695 695 0 0.00 1 12803 481 0 0
srv_recalc_idx_stat 4 4 0 0.00 366795 467950 400724 0 0
t$perf-abend:sp_add_invoice_to_stock 50 50 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 4827 4827 0 0.00 1 5847 139 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 0 5081 71 0 0
t$perf-norm:sp_cancel_client_order 1676 1676 0 0.00 0 2000 7 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 970 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 646 646 0 0.00 0 340 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 3499 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 475 11 0 0
t$perf-norm:sp_cancel_supplier_order 381 381 0 0.00 0 17659 1324 0 0
t$perf-norm:sp_cancel_write_off 909 909 0 0.00 0 448 3 0 0
t$perf-norm:sp_client_order 19403 19403 0 0.00 0 39821 17 0 0
t$perf-norm:sp_customer_reserve 919 919 0 0.00 0 7071 15 0 0
t$perf-norm:sp_pay_from_customer 6760 6760 0 0.00 0 6494 9 0 0
t$perf-norm:sp_pay_to_supplier 5519 5519 0 0.00 0 5394 9 0 0
t$perf-norm:sp_reserve_write_off 9217 9217 0 0.00 0 4165 8 0 0
t$perf-norm:sp_supplier_invoice 5269 5269 0 0.00 0 5297 14 0 0
t$perf-norm:sp_supplier_order 5186 5186 0 0.00 0 8731 14 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 350 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 72 2 0 0
t$perf-norm:srv_make_invnt_saldo 474 474 0 0.00 0 3291 12 0 0
t$perf-norm:srv_make_money_saldo 695 695 0 0.00 0 973 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 4 3 0 0
v_add_invoice_to_stock 9716 9616 100 1.02 0 6962 15 0 100
v_all_customers 35832 35832 0 0.00 0 16 1 0 0
v_all_suppliers 29094 29094 0 0.00 0 17 1 0 0
v_all_wares 38804 38804 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 897 6 0 0
v_cancel_client_order 3352 3352 0 0.00 0 2537 3 0 0
v_cancel_customer_prepayment 1292 1292 0 0.00 0 1427 5 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 8950 15 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 8949 16 0 0
v_cancel_supplier_invoice 798 782 16 2.00 0 562 13 0 16
v_cancel_supplier_order 762 762 0 0.00 0 892 5 0 0
v_cancel_write_off 1818 1818 0 0.00 0 1433 12 0 0
v_max_id_clo_ord 5183 5183 0 0.00 0 848 1 0 0
v_max_id_clo_res 919 919 0 0.00 0 3013 25 0 0
v_max_id_ord_sup 5259 5259 0 0.00 0 7876 7 0 0
v_max_non_paid_invoice 1414 1414 0 0.00 0 1279 3 0 0
v_max_non_paid_realizn 3422 3422 0 0.00 0 5484 4 0 0
v_min_id_clo_ord 5183 5183 0 0.00 0 4335 6 0 0
v_min_id_clo_res 919 919 0 0.00 0 1743 10 0 0
v_min_id_ord_sup 5259 5259 0 0.00 0 7614 9 0 0
v_min_non_paid_invoice 5519 5519 0 0.00 0 5689 13 0 0
v_min_non_paid_realizn 6760 6760 0 0.00 0 6380 14 0 0
v_our_firm 9648 9648 0 0.00 0 14 1 0 0
v_reserve_write_off 18434 18434 0 0.00 0 2509 4 0 0
x_lock_depdocs_on_canc_invoice 10666 10666 0 0.00 0 13472 100 0 0
x_lock_depdocs_on_canc_sup_ord 381 381 0 0.00 0 13836 278 0 0
x_make_qstorno_1000_1200 5186 5186 0 0.00 2 102232 1256 0 0
x_make_qstorno_1200_2000 5269 5269 0 0.00 4 102044 3132 0 0
x_make_qstorno_2100_3300 459921 459921 0 0.00 0 90774 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-11-2020 13:28:16 to 30-11-2020 13:28:20.

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
10:56:56 1 8192000000 8796641312 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
10:57:58 31184 8192000000 9719016336 10015121408 793337152 8.829 100 87 175 0 823170944 8088816 21744976
10:59:32 19088 8192000000 9759404960 10063523840 833834688 9.238 100 91 182 0 868079488 12552560 21692240
11:00:59 25200 8192000000 9815685600 10134904832 864154976 9.542 100 82 164 0 914072320 31059408 18857936
11:02:29 15749 8192000000 9860960880 10194173952 918910672 10.085 100 79 158 1 972631392 36407328 17313392
11:03:48 7716 8192000000 9921298640 10265124864 960485840 10.494 100 81 163 0 1034200352 56430928 17283584
11:04:58 3859 8192000000 9964014672 10322137088 996112784 10.841 100 85 170 0 1079069328 65587776 17368768
11:06:04 2499 8192000000 10008170096 10369646592 1024481808 11.115 100 81 161 3 1114444240 74515472 15446960
11:07:08 2842 8192000000 10014615680 10383851520 1045900176 11.321 100 86 172 2 1130029584 65919824 18209584
11:08:12 1475 8192000000 10041131456 10412875776 1061328624 11.469 100 94 188 1 1152734832 72278624 19127584
11:09:15 2688 8192000000 10037477200 10424565760 1066381184 11.518 100 65 129 1 1154231968 74837520 13013264
11:10:19 68183 8192000000 9992927280 10400276480 1083293952 11.679 100 36 71 0 1121660208 30718736 7647520
11:12:31 3939 8192000000 10006632592 10397908992 1087269680 11.717 100 38 74 2 1122763600 27155440 8338480
11:13:37 7840 8192000000 10011486368 10419290112 1092185632 11.763 100 34 67 3 1125539616 25671120 7682864
11:14:46 15513 8192000000 10006951152 10404708352 1096359056 11.803 100 40 80 3 1127164960 21785152 9020752
11:16:04 12757 8192000000 10031919248 10442276864 1093652736 11.777 100 34 68 0 1138804272 38365088 6786448
11:17:17 7828 8192000000 10009295072 10413703168 1097104096 11.810 100 46 91 1 1136228608 28282016 10842496
11:18:27 6666 8192000000 9990381808 10381611008 1022028304 11.092 95 37 74 0 1055997680 25448384 8520992
11:19:35 13255 8192000000 9919788640 10305134592 963188416 10.520 92 24 49 1 1001591632 32804304 5598912
11:20:49 42725 8192000000 9956738496 10347347968 951399232 10.405 91 26 51 0 987002192 30420656 5182304
11:22:32 4493 8192000000 9939540896 10319286272 1005182144 10.929 98 43 88 1 1035957376 21403408 9371824
11:23:39 4646 8192000000 9968358560 10354970624 1045182512 11.314 100 55 112 1 1074652560 15451616 14018432
11:24:47 68081 8192000000 9962683856 10366013440 1052926960 11.389 100 35 70 2 1093262848 32800576 7535312
11:27:01 22555 8192000000 10006631744 10397827072 1083878880 11.684 100 37 74 2 1108082480 16159856 8043744
11:28:31 6599 8192000000 10069942752 10464366592 1102899968 11.865 100 56 112 0 1173500624 58560000 12040656
11:29:49 10879 8192000000 10111563888 10510327808 1118561392 12.013 100 58 117 2 1227388048 96330896 12495760
11:31:02 41561 8192000000 10114226880 10523701248 1124891072 12.073 100 36 72 0 1222239264 89718608 7629584
11:32:51 11939 8192000000 10113903984 10516836352 1135825216 12.176 100 63 126 0 1219308848 70424880 13058752
11:34:08 7526 8192000000 10144200416 10546315264 1133827808 12.157 99 62 124 1 1243773264 96725648 13219808
11:35:26 10558 8192000000 10176425376 10590519296 1151805744 12.326 100 48 95 1 1282125184 120035536 10283904
11:36:45 6399 8192000000 10227302208 10635829248 1164630080 12.447 100 68 136 2 1327794272 148291376 14872816
11:37:56 10812 8192000000 10258255520 10674802688 1167577152 12.474 100 59 117 0 1364645568 184059216 13009200
11:39:15 2808 8192000000 10306927200 10720538624 1174813760 12.542 100 62 122 2 1410296496 220903472 14579264
11:40:23 5526 8192000000 10314825376 10728886272 1177112928 12.563 100 62 123 2 1414941024 223417520 14410576
11:41:36 5338 8192000000 10330957456 10740719616 1188258208 12.667 100 73 142 2 1430301824 225103456 16940160
11:42:45 1815 8192000000 10368818768 10783875072 1185624912 12.643 99 74 148 0 1463197472 260074560 17498000
11:43:55 1918 8192000000 10375763456 10783428608 1186589424 12.652 99 88 176 1 1474450896 267358880 20502592
11:45:01 14251 8192000000 10392889472 10837065728 1172475728 12.520 99 41 81 0 1491363968 308306352 10581888
11:46:20 5345 8192000000 10424573744 10839564288 1167149104 12.470 100 62 125 1 1518990880 336399264 15442512
11:47:32 14183 8192000000 10314667392 10767704064 1140456960 12.220 98 35 69 0 1421053584 271669392 8927232
11:49:03 9193 8192000000 10336240032 10789965824 1139405456 12.210 100 36 72 0 1455310768 306628960 9276352
11:50:13 28160 8192000000 10338471072 10785882112 1109265264 11.925 98 25 50 1 1435345664 318793312 7287088
11:51:44 24481 8192000000 10280014960 10759962624 1089716272 11.740 98 17 32 0 1405746096 311507824 4522000
11:53:22 28424 8192000000 10220001152 10684653568 1102339616 11.860 99 20 40 0 1373334512 265988240 5006656
11:54:52 29870 8192000000 10038140032 10456543232 1071375776 11.565 97 30 56 4 1105618576 28519168 5723632
11:56:35 97256 8192000000 10125704560 10553356288 1096971520 11.809 97 35 69 2 1181240480 77276816 6992144
11:59:13 35885 8192000000 10100421120 10631471104 1107928224 11.913 99 15 28 1 1225789280 114446224 3414832
12:00:55 132524 8192000000 10098356592 10539192320 1113930000 11.970 99 17 34 0 1182916336 65597952 3388384
12:04:07 56791 8192000000 10081492480 10520190976 1126427136 12.088 100 22 45 2 1176164528 45199232 4538160
12:06:08 50189 8192000000 10056861200 10584412160 1126316656 12.087 100 11 22 0 1195622848 66653408 2652784
12:07:59 40034 8192000000 10122825920 10560995328 1116418512 11.993 98 35 70 3 1173494192 49702160 7373520
12:09:40 46031 8192000000 10120329232 10558439424 1121740752 12.043 99 23 44 1 1196589776 70050528 4798496
12:11:29 55362 8192000000 10084184480 10596806656 1122605440 12.052 99 20 40 1 1204504192 77679632 4219120
12:13:31 27681 8192000000 10096256592 10521665536 1107150464 11.905 98 34 68 1 1147824352 33859936 6813952
12:14:59 32340 8192000000 10115854288 10553626624 1133456128 12.154 100 34 68 1 1209335760 69170544 6709088
12:16:46 19303 8192000000 10078173792 10508939264 1141617824 12.231 100 52 104 0 1166550640 13896656 11036160
12:18:05 79187 8192000000 10083456112 10537648128 1133794784 12.157 99 14 28 0 1183857536 47213040 2849712
12:20:27 142572 8192000000 10105458320 10599645184 1134317504 12.162 99 25 50 0 1193913728 53953968 5642256
12:23:52 47072 8192000000 10063006848 10527580160 1138177760 12.198 99 6 12 0 1161049152 21852032 1019360
12:25:40 61153 8192000000 10069622976 10544107520 1121949168 12.045 98 7 14 0 1156746944 33783824 1013952
12:27:42 65750 8192000000 10092070256 10561282048 1124703136 12.071 98 8 16 0 1165445216 39163632 1578448
12:29:48 48824 8192000000 10085508368 10570137600 1137533856 12.192 99 15 29 1 1176956112 35816336 3605920
12:31:48 68104 8192000000 10123298544 10610929664 1143090592 12.245 99 26 52 1 1240896560 92423456 5382512
12:34:04 62105 8192000000 10095594256 10569019392 1131896416 12.139 98 21 41 1 1176682272 40159696 4626160
12:36:12 34933 8192000000 10078631264 10514849792 1131901584 12.139 99 24 48 0 1163745680 27187392 4656704
12:37:48 42772 8192000000 10087083808 10537140224 1111451664 11.946 97 28 56 2 1196508528 79885744 5171120
12:39:32 78300 8192000000 10133842080 10603405312 1131673120 12.137 99 19 38 1 1221670352 85826176 4171056
12:41:51 130673 8192000000 10053391344 10536378368 1117644304 12.005 99 22 45 1 1183291264 60611136 5035824
12:45:11 28337 8192000000 10034495248 10477109248 1103994816 11.876 99 7 14 2 1123529952 17976336 1558800
12:46:44 54529 8192000000 10025218384 10474536960 1070898912 11.561 96 12 22 1 1097756976 23853248 3004816
12:48:47 84486 8192000000 10072265472 10507718656 1053155872 11.391 94 8 16 0 1100955600 45939376 1860352
12:51:40 58604 8192000000 10114064752 10545045504 1086438352 11.709 97 26 52 1 1168614336 76789632 5386352
12:53:50 46513 8192000000 10114399568 10546135040 1100211904 11.840 98 31 62 2 1197186480 90091536 6883040
12:55:43 96312 8192000000 10126038960 10667470848 1107276464 11.907 98 14 28 0 1209595024 99023360 3295200
12:58:22 77640 8192000000 10078191200 10621947904 1090369808 11.746 97 6 12 2 1164119920 72414944 1335168
13:00:43 108217 8192000000 10050572096 10506203136 1084182944 11.687 96 22 44 0 1125124720 36546912 4394864
13:03:41 40353 8192000000 10028648000 10479919104 1099113616 11.829 98 11 22 0 1124925456 23517968 2293872
13:05:31 59446 8192000000 10058245216 10515607552 1126318992 12.087 100 6 12 1 1152673664 25472784 881888
13:07:31 87048 8192000000 10060695648 10517925888 1097865392 11.817 97 13 26 0 1139785696 39352048 2568256
13:10:02 76769 8192000000 10127869216 10578223104 1139387904 12.210 99 31 60 2 1200190528 54050848 6751776
13:12:19 56413 8192000000 10121132192 10586611712 1131741872 12.138 98 27 54 2 1183649904 46629248 5278784
13:14:16 75932 8192000000 10101839360 10620112896 1135081680 12.169 99 29 58 1 1245058912 103590480 6386752
13:16:35 59078 8192000000 10091728848 10520383488 1113093440 11.962 96 21 42 1 1151888192 33868640 4926112
13:18:35 29627 8192000000 10115304688 10541871104 1142603968 12.240 99 19 38 0 1189251824 42385296 4262560
13:20:06 93019 8192000000 10066954048 10523811840 1136235568 12.180 99 17 34 0 1191506256 51682080 3588608
13:22:42 57201 8192000000 10072693376 10554187776 1145440784 12.267 100 8 16 0 1197560160 50280704 1838672
13:25:11 45513 8192000000 10064504880 10521800704 1128417680 12.106 99 19 38 0 1167628224 35029408 4181136

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 30-11-2020 13:28:22 to 30-11-2020 13:28:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 50 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 30-11-2020 13:28:37 to 30-11-2020 13:28:39.

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          225470
MON$OLDEST_ACTIVE               227665
MON$OLDEST_SNAPSHOT             227665
MON$NEXT_TRANSACTION            227665
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                       2064096
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             782
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2281 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2281 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2281 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.2281 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 ---
:36: 
:37: mscs-test-161.budzdorov.ru    Mon Nov 30 13:26:57 2020
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Mon Nov 30 13:26:57 2020
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Mon Nov 30 13:26:59 2020
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Mon Nov 30 13:26:59 2020
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:51: 
--- end of diff output ---

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

30.11.20 13:28:40. 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
2020-11-30 13:28:43 No severe PSQL-related problems occured
30.11.20 13:28:44. End of report.