Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.02.2021 07:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16871804 kB
MemAvailable 31265588 kB
Buffers 0 kB
Cached 14839320 kB
SwapCached 4876 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-02 07:37:13
DTS_END                         2021-02-02 07:37:13.4730
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/57910
TRN_ID                          223567
ATT_ID                          595
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: 8476.70 28840 1017204
customer order: creation 149.54 399 17945
customer order: refuse 13.37 349 1604
order to supplier: creation 41.39 1388 4967
order to supplier: removal 3.10 131566 372
invoice (draft): creation 42.61 4156 5113
invoice (draft): removal 49.90 952 5988
invoice accept: apply 37.75 18530 4530
invoice accept: cancel 44.52 1006 5342
customer reserve: creation 3465.53 182 415864
customer reserve: removal 4360.66 74 523279
realization accept: apply 75.72 194 9086
realization accept: cancel 74.49 135 8939
payment from customer: creation 54.75 133 6570
payment from customer: removal 5.43 107 652
payment to supplier: creation 45.08 135 5409
payment to supplier: removal 3.14 89 377
service: total inventory turnovers 3.24 3811 389
service: total monetary turnovers 5.53 623 664
service: aggregate perf. data 0.91 14104 109
service: refresh index statistics 0.04 398862 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-02-2021 07:38:47 to 02-02-2021 07:38:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6469
TEST_TIME 2 6803
TEST_TIME 3 5917
TEST_TIME 4 6126
TEST_TIME 5 5704
TEST_TIME 6 5462
TEST_TIME 7 5407
TEST_TIME 8 5314
TEST_TIME 9 5312
TEST_TIME 10 5279
TEST_TIME 11 5327
TEST_TIME 12 5482
TEST_TIME 13 6101
TEST_TIME 14 6207
TEST_TIME 15 6550
TEST_TIME 16 6692
TEST_TIME 17 7502
TEST_TIME 18 7710
TEST_TIME 19 7791
TEST_TIME 20 8226
TEST_TIME 21 8224
TEST_TIME 22 8437
TEST_TIME 23 8811
TEST_TIME 24 8815
TEST_TIME 25 8776
TEST_TIME 26 9058
TEST_TIME 27 9394
TEST_TIME 28 10581
TEST_TIME 29 10440
TEST_TIME 30 10201
TEST_TIME 31 10035
TEST_TIME 32 9875
TEST_TIME 33 9736
TEST_TIME 34 9575
TEST_TIME 35 9482
TEST_TIME 36 9441
TEST_TIME 37 9381
TEST_TIME 38 9331
TEST_TIME 39 9290
TEST_TIME 40 9214
TEST_TIME 41 9068
TEST_TIME 42 8955
TEST_TIME 43 8885
TEST_TIME 44 8777
TEST_TIME 45 8842
TEST_TIME 46 8884
TEST_TIME 47 8795
TEST_TIME 48 8843
TEST_TIME 49 8780
TEST_TIME 50 8734
TEST_TIME 51 8857
TEST_TIME 52 8858
TEST_TIME 53 8943
TEST_TIME 54 8945
TEST_TIME 55 8942
TEST_TIME 56 9109
TEST_TIME 57 9068
TEST_TIME 58 9026
TEST_TIME 59 8972
TEST_TIME 60 8904
TEST_TIME 61 8851
TEST_TIME 62 8742
TEST_TIME 63 8648
TEST_TIME 64 8585
TEST_TIME 65 8594
TEST_TIME 66 8726
TEST_TIME 67 8746
TEST_TIME 68 8727
TEST_TIME 69 8733
TEST_TIME 70 8687
TEST_TIME 71 8710
TEST_TIME 72 8832
TEST_TIME 73 8818
TEST_TIME 74 8872
TEST_TIME 75 8912
TEST_TIME 76 8919
TEST_TIME 77 8838
TEST_TIME 78 8763
TEST_TIME 79 8702
TEST_TIME 80 8713
TEST_TIME 81 8678
TEST_TIME 82 8715
TEST_TIME 83 8658
TEST_TIME 84 8654
TEST_TIME 85 8627
TEST_TIME 86 8753
TEST_TIME 87 8789
TEST_TIME 88 8789
TEST_TIME 89 8763
TEST_TIME 90 8768
TEST_TIME 91 8792
TEST_TIME 92 8855
TEST_TIME 93 8904
TEST_TIME 94 8873
TEST_TIME 95 8790
TEST_TIME 96 8765
TEST_TIME 97 8712
TEST_TIME 98 8700
TEST_TIME 99 8637
TEST_TIME 100 8618
TEST_TIME 101 8581
TEST_TIME 102 8561
TEST_TIME 103 8583
TEST_TIME 104 8561
TEST_TIME 105 8568
TEST_TIME 106 8623
TEST_TIME 107 8644
TEST_TIME 108 8640
TEST_TIME 109 8667
TEST_TIME 110 8673
TEST_TIME 111 8652
TEST_TIME 112 8704
TEST_TIME 113 8680
TEST_TIME 114 8652
TEST_TIME 115 8633
TEST_TIME 116 8623
TEST_TIME 117 8598
TEST_TIME 118 8544
TEST_TIME 119 8538
TEST_TIME 120 8482

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-02-2021 07:38:51 to 02-02-2021 07:38:54.

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 1400860 1400860 0 0.00 0 16916 7 0 0
doc_list_biud 1400855 1400855 0 0.00 0 38315 35 0 0
sp_add_invoice_to_stock 4631 4530 101 2.18 21 188994 18530 0 101
sp_cancel_adding_invoice 5342 5342 0 0.00 5 113826 1006 0 0
sp_cancel_client_order 1604 1604 0 0.00 4 10269 350 0 0
sp_cancel_customer_reserve 523279 523279 0 0.00 1 29871 74 0 0
sp_cancel_pay_from_customer 652 652 0 0.00 3 8484 107 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 3 7159 90 0 0
sp_cancel_supplier_invoice 5995 5988 7 0.11 2 38932 953 0 7
sp_cancel_supplier_order 372 372 0 0.00 7 1045924 131567 0 0
sp_cancel_write_off 8939 8939 0 0.00 1 11352 135 0 0
sp_client_order 17945 17945 0 0.00 4 22162 399 0 0
sp_customer_reserve 415864 415864 0 0.00 0 43723 183 0 0
sp_fill_shopping_cart 28016 28016 0 0.00 1 46021 59 0 0
sp_fill_shopping_cart_clo_res 415856 415856 0 0.00 0 31591 19 0 0
sp_get_clo_for_invoice 4520 4520 0 0.00 0 33397 431 0 0
sp_kill_cost_storno 15310 15310 0 0.00 0 12170 69 0 0
sp_kill_qstorno_ret_qs2qd 1061857 1061857 0 0.00 0 38313 40 0 0
sp_kill_qty_storno 559128 559128 0 0.00 0 38315 86 0 0
sp_lock_selected_doc 18903 18903 0 0.00 0 8703 4 0 0
sp_make_cost_storno 25585 25585 0 0.00 0 16882 61 0 0
sp_multiply_rows_for_pdistr 25583 25583 0 0.00 0 9918 37 0 0
sp_multiply_rows_for_qdistr 17945 17945 0 0.00 0 22050 210 0 0
sp_pay_from_customer 6570 6570 0 0.00 4 12053 134 0 0
sp_pay_to_supplier 5409 5409 0 0.00 4 11518 136 0 0
sp_payment_common 11979 11979 0 0.00 1 10078 21 0 0
sp_qd_handle_on_cancel_clo 1604 1604 0 0.00 0 10262 322 0 0
sp_qd_handle_on_invoice_upd_sts 9860 9860 0 0.00 0 35896 395 0 0
sp_qd_handle_on_reserve_upd_sts 9086 9086 0 0.00 0 16780 58 0 0
sp_reserve_write_off 9086 9086 0 0.00 7 17170 194 0 0
sp_supplier_invoice 5113 5113 0 0.00 7 116714 4157 0 0
sp_supplier_order 4967 4967 0 0.00 6 43680 1388 0 0
srv_aggregate_perf_data 109 109 0 0.00 2407 151415 14105 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 1039 157659 47478 0 0
srv_find_qd_qs_mism 973496 973496 0 0.00 0 15824 6 0 0
srv_make_invnt_saldo 389 389 0 0.00 66 118466 3812 0 0
srv_make_money_saldo 664 664 0 0.00 1 18134 624 0 0
srv_recalc_idx_stat 5 5 0 0.00 236454 562098 398862 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4530 4530 0 0.00 0 13309 202 0 0
t$perf-norm:sp_cancel_adding_invoice 373 373 0 0.00 0 3801 115 0 0
t$perf-norm:sp_cancel_client_order 1604 1604 0 0.00 0 8613 27 0 0
t$perf-norm:sp_cancel_customer_reserve 591 591 0 0.00 0 1476 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 652 652 0 0.00 0 2429 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 1771 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 350 350 0 0.00 0 414 10 0 0
t$perf-norm:sp_cancel_supplier_order 372 372 0 0.00 0 20070 1695 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 1274 7 0 0
t$perf-norm:sp_client_order 17945 17945 0 0.00 0 7956 21 0 0
t$perf-norm:sp_customer_reserve 917 917 0 0.00 0 1551 8 0 0
t$perf-norm:sp_pay_from_customer 6570 6570 0 0.00 0 3202 6 0 0
t$perf-norm:sp_pay_to_supplier 5409 5409 0 0.00 0 2175 8 0 0
t$perf-norm:sp_reserve_write_off 9086 9086 0 0.00 0 8598 13 0 0
t$perf-norm:sp_supplier_invoice 5113 5113 0 0.00 0 5136 16 0 0
t$perf-norm:sp_supplier_order 4967 4967 0 0.00 0 3989 18 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 1931 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 250 9 0 0
t$perf-norm:srv_make_invnt_saldo 389 389 0 0.00 0 426 7 0 0
t$perf-norm:srv_make_money_saldo 664 664 0 0.00 0 1290 12 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 28 9 0 0
v_add_invoice_to_stock 9236 9034 202 2.18 0 7081 17 0 202
v_all_customers 33036 33036 0 0.00 0 33 1 0 0
v_all_suppliers 28228 28228 0 0.00 0 20 1 0 0
v_all_wares 35888 35888 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 746 746 0 0.00 0 778 8 0 0
v_cancel_client_order 3208 3208 0 0.00 0 2907 4 0 0
v_cancel_customer_prepayment 1304 1304 0 0.00 0 4374 12 0 0
v_cancel_customer_reserve 1182 1182 0 0.00 0 1064 5 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 3332 9 0 0
v_cancel_supplier_invoice 714 700 14 1.96 0 1921 17 0 14
v_cancel_supplier_order 744 744 0 0.00 0 823 5 0 0
v_cancel_write_off 1962 1962 0 0.00 0 1495 15 0 0
v_max_id_clo_ord 4965 4965 0 0.00 0 1039 2 0 0
v_max_id_clo_res 917 917 0 0.00 0 2085 13 0 0
v_max_id_ord_sup 5105 5105 0 0.00 0 8085 9 0 0
v_max_non_paid_invoice 1365 1365 0 0.00 0 502 2 0 0
v_max_non_paid_realizn 3458 3458 0 0.00 0 1846 3 0 0
v_min_id_clo_ord 4965 4965 0 0.00 0 3660 8 0 0
v_min_id_clo_res 917 917 0 0.00 0 7290 15 0 0
v_min_id_ord_sup 5105 5105 0 0.00 0 6146 13 0 0
v_min_non_paid_invoice 5409 5409 0 0.00 0 6951 12 0 0
v_min_non_paid_realizn 6570 6570 0 0.00 0 9730 15 0 0
v_our_firm 9076 9076 0 0.00 0 18 1 0 0
v_reserve_write_off 18172 18172 0 0.00 0 4521 7 0 0
x_lock_depdocs_on_canc_invoice 10980 10980 0 0.00 0 14817 118 0 0
x_lock_depdocs_on_canc_sup_ord 372 372 0 0.00 0 8385 417 0 0
x_make_qstorno_1000_1200 4967 4967 0 0.00 2 40946 1298 0 0
x_make_qstorno_1200_2000 5113 5113 0 0.00 4 103770 3919 0 0
x_make_qstorno_2100_3300 415864 415864 0 0.00 0 35263 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-02-2021 07:38:55 to 02-02-2021 07:38:59.

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
05:07:17 1 8192000000 8796640128 8807108608 3168304 0.038 1 1 2 0 3265920 61840 35776
05:08:18 37756 8192000000 9726172960 10024804352 803461424 8.931 100 91 182 0 833601632 8300800 21839408
05:10:06 19343 8192000000 9762543744 10070515712 840109440 9.301 100 86 172 0 874682432 14498512 20074480
05:11:27 10285 8192000000 9802226704 10118336512 869561040 9.596 100 85 170 1 910689424 22082240 19046144
05:12:42 15246 8192000000 9879929536 10209693696 914277552 10.040 100 82 165 0 994928912 62050160 18601200
05:14:03 7548 8192000000 9895413904 10241712128 957626896 10.466 100 79 155 1 1021245568 47225536 16393136
05:15:13 4711 8192000000 9956364144 10307215360 991396096 10.795 100 86 170 1 1069281232 59950528 17934608
05:16:21 3895 8192000000 10005338704 10363748352 1020928304 11.081 100 85 168 1 1123711024 86114224 16668496
05:17:28 1807 8192000000 10007886400 10370813952 1040541968 11.270 100 90 178 0 1120886272 62623600 17720704
05:18:31 1949 8192000000 10048181792 10421096448 1059504080 11.452 100 90 176 1 1159460256 81889760 18066416
05:19:35 3869 8192000000 10027702880 10412302336 1070002176 11.552 100 51 102 0 1139798912 59118704 10678032
05:20:40 11458 8192000000 10001404368 10402861056 1082210608 11.669 100 48 95 1 1129773264 36462240 11100416
05:21:52 33307 8192000000 10014792416 10412498944 1086720848 11.711 100 36 72 0 1130625216 35711536 8192832
05:23:25 5305 8192000000 10002035536 10401615872 1091803312 11.760 100 52 105 0 1118243856 14747648 11692896
05:24:35 6426 8192000000 10021330688 10420731904 1096518144 11.805 100 36 72 0 1124533584 20394448 7620992
05:25:42 6195 8192000000 10007781216 10413502464 1088330304 11.727 99 39 75 0 1116708016 20722656 7655056
05:26:50 24188 8192000000 10022854608 10437332992 1102343088 11.860 100 71 144 2 1131656672 12394288 16919296
05:28:19 7959 8192000000 10002488208 10404868096 1069355024 11.546 99 44 88 5 1116908288 37998016 9555248
05:29:30 7257 8192000000 9942475568 10316673024 996041520 10.840 95 55 110 2 1023325008 15889424 11394064
05:30:40 6665 8192000000 9922967440 10298634240 981964288 10.703 96 39 78 0 999568736 8884832 8719616
05:31:49 37893 8192000000 9904416464 10292977664 935446944 10.248 92 24 48 0 964033968 23646496 4940528
05:33:27 6601 8192000000 9954771856 10335916032 1028537488 11.154 100 39 80 0 1052728608 14983008 9208112
05:34:34 4140 8192000000 9964989424 10351325184 1048339824 11.345 100 44 88 2 1084905392 26979952 9585616
05:35:39 8308 8192000000 9971691232 10372321280 1063895920 11.494 100 30 61 2 1090410928 19514096 7000912
05:36:48 17745 8192000000 9968148208 10373287936 1067405104 11.527 100 15 29 0 1089689664 19293376 2991184
05:38:16 6484 8192000000 10017540176 10412580864 1087704272 11.721 100 48 95 0 1129988528 32575696 9708560
05:39:27 13528 8192000000 10056214912 10462486528 1096301168 11.803 100 46 92 0 1173184432 67550336 9332928
05:40:43 21698 8192000000 10076639760 10477379584 1111635744 11.948 100 51 102 2 1186750288 62893568 12220976
05:42:13 9628 8192000000 10111383760 10515308544 1134759472 12.166 100 51 101 0 1222766368 77099120 10907776
05:43:27 21137 8192000000 10145156832 10555215872 1145975792 12.272 100 48 96 1 1250922416 93896432 11050192
05:44:51 15110 8192000000 10199159888 10609659904 1153677904 12.344 100 53 106 1 1308743728 143322816 11743008
05:46:17 12818 8192000000 10248886864 10663440384 1161199504 12.414 100 54 106 0 1341843440 167807904 12836032
05:48:21 12272 8192000000 10275739488 10692304896 1179134576 12.582 100 48 95 0 1389625104 199284656 11205872
05:49:40 3630 8192000000 10306276912 10711179264 1176395792 12.557 100 65 127 0 1408373392 217846256 14131344
05:50:51 3621 8192000000 10344910512 10750537728 1193879872 12.719 100 79 158 0 1445133392 233307392 17946128
05:52:00 1033 8192000000 10397951552 10801131520 1190916848 12.692 100 89 176 1 1494164704 282527312 20720544
05:53:07 1119 8192000000 10392155104 10792796160 1167627824 12.475 98 90 178 0 1483441008 295325920 20487264
05:54:12 1097 8192000000 10394397424 10803265536 1177131328 12.563 100 89 178 2 1489744160 292144176 20468656
05:55:19 9170 8192000000 10343654432 10762010624 1143877712 12.252 99 82 164 1 1451536496 288464576 19194208
05:56:35 2442 8192000000 10371446432 10772344832 1150753392 12.317 99 81 159 0 1455808752 286654656 18400704
05:57:43 12224 8192000000 10330413872 10735239168 1143220176 12.246 98 43 87 2 1422131824 267945904 10965744
05:59:01 7082 8192000000 10361572896 10773737472 1162508320 12.427 100 63 124 0 1463654288 286278320 14867648
06:00:14 18624 8192000000 10333513968 10783010816 1159177872 12.396 100 28 54 2 1431174912 264405216 7591824
06:01:41 19634 8192000000 10359946896 10790088704 1169707072 12.494 100 35 70 1 1457271648 277959328 9605248
06:03:08 22290 8192000000 10357040880 10791694336 1134737712 12.166 100 27 54 0 1454362064 312517824 7106528
06:04:59 36294 8192000000 10169461904 10724790272 1118210640 12.010 99 16 32 1 1373150800 250361600 4578560
06:06:35 55200 8192000000 10064795216 10509561856 1126336352 12.087 100 50 98 0 1161919392 24400240 11182800
06:09:11 40832 8192000000 10090330864 10545569792 1125725872 12.081 99 13 25 0 1183290448 54592880 2971696
06:12:28 83702 8192000000 10105190352 10558021632 1097880128 11.818 97 22 48 1 1185129536 82689984 4559424
06:14:54 126722 8192000000 10058588192 10511884288 1100350592 11.841 98 34 68 2 1148973264 40910448 7712224
06:18:11 37730 8192000000 10042280976 10483994624 1117569456 12.004 100 19 36 1 1156453584 34256656 4627472
06:19:49 44514 8192000000 10094270880 10542395392 1115979408 11.989 99 31 61 2 1186484144 63635120 6869616
06:21:35 77199 8192000000 10094487344 10607190016 1130517664 12.126 100 11 22 0 1211587072 78754656 2314752
06:23:54 63703 8192000000 10082332128 10528989184 1102023072 11.857 97 14 28 1 1153933392 48807632 3102688
06:25:57 51912 8192000000 10102804672 10539270144 1125510448 12.079 99 30 60 0 1189704480 57756704 6437328
06:27:51 35611 8192000000 10092211632 10560811008 1147188288 12.283 100 16 32 0 1199513888 49001552 3324048
06:29:27 82992 8192000000 10107473152 10582917120 1120685760 12.033 97 11 22 2 1180012832 56994256 2332816
06:31:50 30919 8192000000 10140053888 10562662400 1153758592 12.345 100 36 72 0 1229715296 67902096 8054608
06:33:34 50283 8192000000 10066639776 10514993152 1131673264 12.137 99 12 24 0 1167768960 33721232 2374464
06:35:25 48938 8192000000 10121795136 10567962624 1136190384 12.180 100 39 78 3 1210480496 66230016 8060096
06:38:14 90121 8192000000 10100217840 10573012992 1133317344 12.153 99 14 28 1 1196141008 59463376 3360288
06:40:45 28733 8192000000 10144935120 10615189504 1150218816 12.312 100 8 16 0 1240782272 88805136 1758320
06:42:29 90591 8192000000 10096995008 10551721984 1142265776 12.237 100 22 45 1 1258689840 111402624 5021440
06:45:07 29018 8192000000 10042007008 10471862272 1081653776 11.663 96 19 38 2 1114330064 29385968 3290320
06:46:38 51014 8192000000 10097862480 10529300480 1106492016 11.899 98 30 59 1 1158151584 44439712 7219856
06:48:39 70809 8192000000 10060874192 10538082304 1110329664 11.936 98 15 30 0 1206504848 92764912 3410272
06:50:50 67126 8192000000 10073877648 10526998528 1106070784 11.895 98 19 38 0 1167922400 57959472 3892144
06:53:04 86425 8192000000 10068193344 10496659456 1093022880 11.771 96 8 16 1 1117842272 23473424 1345968
06:56:06 63575 8192000000 10076458304 10518773760 1079391440 11.642 96 6 12 0 1127034880 46476480 1166960
06:58:10 70974 8192000000 10047651328 10513563648 1099617616 11.834 98 7 14 0 1163868256 62713344 1537296
07:00:32 32031 8192000000 10106646928 10532433920 1126020656 12.084 100 58 115 0 1192287216 53940912 12325648
07:02:06 128154 8192000000 10071328880 10503573504 1097589328 11.815 97 14 28 1 1209834880 109217536 3028016
07:05:17 85397 8192000000 10061898528 10506674176 1081618560 11.663 96 16 31 0 1119193904 34514272 3061072
07:07:43 60882 8192000000 10083450880 10545975296 1121562032 12.042 100 41 82 1 1186308800 56847920 7898848
07:09:47 86071 8192000000 10099521632 10597441536 1100159856 11.839 97 9 18 0 1181340752 79300176 1880720
07:12:22 95750 8192000000 10050866752 10520633344 1115365616 11.983 99 14 28 0 1183934224 65279856 3288752
07:17:56 34871 8192000000 10052677136 10485096448 1116012400 11.989 100 25 48 1 1148870960 27510144 5348416
07:19:45 89625 8192000000 10098987856 10555281408 1113278608 11.963 99 19 38 0 1195104128 77614720 4210800
07:22:32 62039 8192000000 10134636336 10565980160 1093559328 11.776 97 36 72 2 1197473712 96350336 7564048
07:24:58 43882 8192000000 10106378240 10549067776 1134191328 12.161 100 20 40 1 1171952512 33170496 4590688
07:27:00 71784 8192000000 10102840336 10560536576 1117190400 12.000 98 19 38 1 1175280096 54064432 4025264
07:29:13 71891 8192000000 10076206832 10523316224 1080868160 11.656 95 10 20 0 1123010272 40403120 1738992
07:31:30 157659 8192000000 10060395952 10575306752 1121837456 12.044 99 17 34 0 1183915664 58026768 4051440
07:35:16 51502 8192000000 10073291440 10538688512 1126044928 12.084 100 6 12 0 1184913328 57093216 1775184

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 02-02-2021 07:39:01 to 02-02-2021 07:39:15.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-02-2021 07:39:16 to 02-02-2021 07:39:18.

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          223048
MON$OLDEST_ACTIVE               223842
MON$OLDEST_SNAPSHOT             223842
MON$NEXT_TRANSACTION            223842
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                       2069920
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2357 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2357 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2357 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.2357 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 ---
--- end of diff output ---

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

02.02.21 07:39:19. 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-02 07:39:22 No severe PSQL-related problems occured
02.02.21 07:39:23. End of report.