Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.04.2021 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 df9f488d08424df294c459d6c283e9c3
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 2021-03-04 16:07
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.26
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 16464540 kB
MemAvailable 31399784 kB
Buffers 0 kB
Cached 15153912 kB
SwapCached 96 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-04-08 13:27:00
DTS_END                         2021-04-08 13:27:00.0020
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/35214
TRN_ID                          233972
ATT_ID                          677
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: 9402.51 23301 1128301
customer order: creation 170.86 369 20503
customer order: refuse 14.58 321 1749
order to supplier: creation 46.34 1244 5561
order to supplier: removal 3.43 116525 412
invoice (draft): creation 44.63 3495 5355
invoice (draft): removal 55.03 986 6604
invoice accept: apply 40.70 16103 4884
invoice accept: cancel 50.28 822 6033
customer reserve: creation 3734.42 157 448130
customer reserve: removal 4939.58 62 592750
realization accept: apply 81.21 182 9745
realization accept: cancel 95.83 105 11499
payment from customer: creation 58.84 127 7061
payment from customer: removal 5.62 95 674
payment to supplier: creation 47.68 145 5722
payment to supplier: removal 3.17 123 380
service: total inventory turnovers 3.78 3151 453
service: total monetary turnovers 5.69 595 683
service: aggregate perf. data 0.83 18769 99
service: refresh index statistics 0.03 302638 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-04-2021 13:27:43 to 08-04-2021 13:27:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8066
TEST_TIME 2 4764
TEST_TIME 3 5320
TEST_TIME 4 4712
TEST_TIME 5 5703
TEST_TIME 6 5819
TEST_TIME 7 6048
TEST_TIME 8 6154
TEST_TIME 9 6881
TEST_TIME 10 7134
TEST_TIME 11 6707
TEST_TIME 12 6742
TEST_TIME 13 6906
TEST_TIME 14 6989
TEST_TIME 15 7389
TEST_TIME 16 7339
TEST_TIME 17 7815
TEST_TIME 18 8155
TEST_TIME 19 8239
TEST_TIME 20 8717
TEST_TIME 21 8621
TEST_TIME 22 8671
TEST_TIME 23 8829
TEST_TIME 24 8942
TEST_TIME 25 9324
TEST_TIME 26 10705
TEST_TIME 27 11624
TEST_TIME 28 11553
TEST_TIME 29 11656
TEST_TIME 30 11435
TEST_TIME 31 11383
TEST_TIME 32 11343
TEST_TIME 33 11262
TEST_TIME 34 11109
TEST_TIME 35 11278
TEST_TIME 36 11147
TEST_TIME 37 11156
TEST_TIME 38 11115
TEST_TIME 39 11115
TEST_TIME 40 10987
TEST_TIME 41 11045
TEST_TIME 42 11014
TEST_TIME 43 11012
TEST_TIME 44 10762
TEST_TIME 45 10710
TEST_TIME 46 10659
TEST_TIME 47 10606
TEST_TIME 48 10516
TEST_TIME 49 10538
TEST_TIME 50 10524
TEST_TIME 51 10423
TEST_TIME 52 10349
TEST_TIME 53 10298
TEST_TIME 54 10124
TEST_TIME 55 10170
TEST_TIME 56 10243
TEST_TIME 57 10216
TEST_TIME 58 10169
TEST_TIME 59 10176
TEST_TIME 60 10171
TEST_TIME 61 10108
TEST_TIME 62 10174
TEST_TIME 63 10264
TEST_TIME 64 10259
TEST_TIME 65 10254
TEST_TIME 66 10370
TEST_TIME 67 10369
TEST_TIME 68 10338
TEST_TIME 69 10333
TEST_TIME 70 10375
TEST_TIME 71 10280
TEST_TIME 72 10171
TEST_TIME 73 10107
TEST_TIME 74 10000
TEST_TIME 75 9967
TEST_TIME 76 9927
TEST_TIME 77 9896
TEST_TIME 78 9888
TEST_TIME 79 9895
TEST_TIME 80 9864
TEST_TIME 81 9824
TEST_TIME 82 9919
TEST_TIME 83 9869
TEST_TIME 84 9837
TEST_TIME 85 9960
TEST_TIME 86 9946
TEST_TIME 87 9927
TEST_TIME 88 9953
TEST_TIME 89 9984
TEST_TIME 90 9990
TEST_TIME 91 9898
TEST_TIME 92 9816
TEST_TIME 93 9756
TEST_TIME 94 9731
TEST_TIME 95 9700
TEST_TIME 96 9676
TEST_TIME 97 9651
TEST_TIME 98 9678
TEST_TIME 99 9662
TEST_TIME 100 9617
TEST_TIME 101 9662
TEST_TIME 102 9624
TEST_TIME 103 9626
TEST_TIME 104 9611
TEST_TIME 105 9637
TEST_TIME 106 9673
TEST_TIME 107 9654
TEST_TIME 108 9649
TEST_TIME 109 9576
TEST_TIME 110 9554
TEST_TIME 111 9479
TEST_TIME 112 9433
TEST_TIME 113 9408
TEST_TIME 114 9413
TEST_TIME 115 9404
TEST_TIME 116 9433
TEST_TIME 117 9429
TEST_TIME 118 9450
TEST_TIME 119 9483
TEST_TIME 120 9404

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-04-2021 13:27:47 to 08-04-2021 13:27:50.

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 1544255 1544255 0 0.00 0 18173 6 0 0
doc_list_biud 1544255 1544255 0 0.00 0 77694 31 0 0
sp_add_invoice_to_stock 4998 4884 114 2.28 24 155363 16104 0 114
sp_cancel_adding_invoice 6033 6033 0 0.00 5 78170 823 0 0
sp_cancel_client_order 1749 1749 0 0.00 4 13694 321 0 0
sp_cancel_customer_reserve 592750 592750 0 0.00 1 53962 62 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 3 6362 96 0 0
sp_cancel_pay_to_supplier 380 380 0 0.00 3 6023 124 0 0
sp_cancel_supplier_invoice 6613 6604 9 0.13 2 19515 987 0 9
sp_cancel_supplier_order 412 412 0 0.00 6 1359095 116525 0 0
sp_cancel_write_off 11499 11499 0 0.00 1 14321 105 0 0
sp_client_order 20503 20503 0 0.00 4 54571 369 0 0
sp_customer_reserve 448130 448130 0 0.00 0 38479 158 0 0
sp_fill_shopping_cart 31419 31419 0 0.00 1 23877 43 0 0
sp_fill_shopping_cart_clo_res 448128 448128 0 0.00 0 32372 15 0 0
sp_get_clo_for_invoice 4882 4882 0 0.00 0 34888 381 0 0
sp_kill_cost_storno 18586 18586 0 0.00 0 7392 55 0 0
sp_kill_qstorno_ret_qs2qd 1204015 1204015 0 0.00 0 53957 34 0 0
sp_kill_qty_storno 633674 633674 0 0.00 0 77694 74 0 0
sp_lock_selected_doc 20251 20251 0 0.00 0 11274 8 0 0
sp_make_cost_storno 27410 27410 0 0.00 0 14744 62 0 0
sp_multiply_rows_for_pdistr 27410 27410 0 0.00 0 8651 33 0 0
sp_multiply_rows_for_qdistr 20503 20503 0 0.00 0 54553 197 0 0
sp_pay_from_customer 7061 7061 0 0.00 4 14533 127 0 0
sp_pay_to_supplier 5722 5722 0 0.00 5 15951 145 0 0
sp_payment_common 12783 12783 0 0.00 1 11955 25 0 0
sp_qd_handle_on_cancel_clo 1749 1749 0 0.00 0 13688 295 0 0
sp_qd_handle_on_invoice_upd_sts 10915 10915 0 0.00 0 77694 388 0 0
sp_qd_handle_on_reserve_upd_sts 9745 9745 0 0.00 0 9131 51 0 0
sp_reserve_write_off 9745 9745 0 0.00 6 24843 182 0 0
sp_supplier_invoice 5355 5355 0 0.00 6 138412 3496 0 0
sp_supplier_order 5561 5561 0 0.00 6 103630 1245 0 0
srv_aggregate_perf_data 99 99 0 0.00 3043 261390 18770 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1598 129499 47898 0 0
srv_find_qd_qs_mism 1082047 1082047 0 0.00 0 18173 5 0 0
srv_make_invnt_saldo 453 453 0 0.00 42 100201 3152 0 0
srv_make_money_saldo 683 683 0 0.00 1 17085 596 0 0
srv_recalc_idx_stat 4 4 0 0.00 244152 422658 302639 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4884 4884 0 0.00 0 34401 182 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 0 1571 66 0 0
t$perf-norm:sp_cancel_client_order 1749 1749 0 0.00 0 4555 17 0 0
t$perf-norm:sp_cancel_customer_reserve 629 629 0 0.00 0 3184 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 10919 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 380 380 0 0.00 0 625 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 1364 17 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 25895 1684 0 0
t$perf-norm:sp_cancel_write_off 950 950 0 0.00 0 8764 15 0 0
t$perf-norm:sp_client_order 20503 20503 0 0.00 0 27816 21 0 0
t$perf-norm:sp_customer_reserve 961 961 0 0.00 0 322 4 0 0
t$perf-norm:sp_pay_from_customer 7061 7061 0 0.00 0 11013 7 0 0
t$perf-norm:sp_pay_to_supplier 5722 5722 0 0.00 0 33719 15 0 0
t$perf-norm:sp_reserve_write_off 9745 9745 0 0.00 0 15151 14 0 0
t$perf-norm:sp_supplier_invoice 5355 5355 0 0.00 0 5970 20 0 0
t$perf-norm:sp_supplier_order 5561 5561 0 0.00 0 11337 20 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 378 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 4235 78 0 0
t$perf-norm:srv_make_invnt_saldo 453 453 0 0.00 0 3785 20 0 0
t$perf-norm:srv_make_money_saldo 683 683 0 0.00 0 492 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 9990 9762 228 2.28 0 4360 16 0 228
v_all_customers 37692 37692 0 0.00 0 15 1 0 0
v_all_suppliers 30118 30118 0 0.00 0 22 1 0 0
v_all_wares 41006 41006 0 0.00 0 54 1 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 1845 10 0 0
v_cancel_client_order 3498 3498 0 0.00 0 4472 6 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 4122 13 0 0
v_cancel_customer_reserve 1258 1258 0 0.00 0 703 4 0 0
v_cancel_payment_to_supplier 760 760 0 0.00 0 3734 12 0 0
v_cancel_supplier_invoice 816 798 18 2.20 0 2484 15 0 18
v_cancel_supplier_order 824 824 0 0.00 0 2797 8 0 0
v_cancel_write_off 1900 1900 0 0.00 0 3016 15 0 0
v_max_id_clo_ord 5561 5561 0 0.00 0 694 2 0 0
v_max_id_clo_res 961 961 0 0.00 0 6053 20 0 0
v_max_id_ord_sup 5355 5355 0 0.00 0 20209 9 0 0
v_max_non_paid_invoice 1579 1579 0 0.00 0 463 2 0 0
v_max_non_paid_realizn 3601 3601 0 0.00 0 1403 3 0 0
v_min_id_clo_ord 5561 5561 0 0.00 0 4335 11 0 0
v_min_id_clo_res 961 961 0 0.00 0 3943 12 0 0
v_min_id_ord_sup 5355 5355 0 0.00 0 7959 7 0 0
v_min_non_paid_invoice 5722 5722 0 0.00 0 11948 17 0 0
v_min_non_paid_realizn 7061 7061 0 0.00 0 9491 17 0 0
v_our_firm 10234 10234 0 0.00 0 22 1 0 0
v_reserve_write_off 19490 19490 0 0.00 0 4327 5 0 0
x_lock_depdocs_on_canc_invoice 12238 12238 0 0.00 0 11394 98 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 9092 312 0 0
x_make_qstorno_1000_1200 5561 5561 0 0.00 1 103597 1162 0 0
x_make_qstorno_1200_2000 5355 5355 0 0.00 3 138349 3333 0 0
x_make_qstorno_2100_3300 448130 448130 0 0.00 0 35606 140 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-04-2021 13:27:51 to 08-04-2021 13:27:55.

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:57:03 3 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:04 25647 8192000000 9720147168 10015232000 795117408 8.847 100 89 179 0 824312544 7872720 21322416
10:59:35 26984 8192000000 9774179040 10082856960 846179120 9.362 100 84 168 0 878949808 12799664 19971024
11:01:05 9846 8192000000 9816117280 10138443776 890560688 9.805 100 88 176 1 929954480 19473392 19920400
11:02:19 17169 8192000000 9870177936 10204995584 921657312 10.112 100 76 152 0 977668944 39374048 16637584
11:03:41 9031 8192000000 9926315808 10271653888 960964368 10.498 100 79 158 0 1037473616 59367984 17141264
11:04:54 4850 8192000000 9966834432 10323894272 1000163536 10.880 100 78 155 1 1084024816 68307728 15553552
11:06:01 4638 8192000000 9989807520 10356654080 1026277584 11.133 100 71 141 0 1096155248 55822880 14054784
11:07:06 2551 8192000000 10040527456 10407407616 1047852064 11.340 100 90 180 2 1155471184 89695456 17923664
11:08:11 1219 8192000000 10042819296 10411376640 1068294304 11.536 100 92 186 1 1152020976 64362368 19364304
11:09:13 4724 8192000000 9987272112 10380394496 1068122160 11.534 100 40 78 3 1106270784 29590624 8558000
11:10:21 7744 8192000000 9976697248 10375303168 1073303248 11.584 100 34 68 0 1095946160 15339088 7303824
11:11:33 11753 8192000000 10003448848 10408058880 1083846304 11.684 100 28 53 1 1111475424 21813024 5816096
11:12:48 48694 8192000000 10008754880 10411831296 1088627040 11.730 100 23 46 0 1105296544 11931040 4738464
11:14:39 8898 8192000000 10024427680 10426126336 1095092720 11.791 100 34 68 3 1139528832 36675168 7760944
11:15:51 7061 8192000000 10007676000 10409857024 1089514528 11.738 99 32 65 2 1119720080 23309744 6895808
11:16:59 5512 8192000000 10009067184 10410098688 1091139200 11.753 100 46 89 2 1124296352 23467040 9690112
11:18:06 55056 8192000000 9959837200 10362748928 988199088 10.764 93 27 54 0 1011842704 18107376 5536240
11:20:02 7460 8192000000 9955769104 10338791424 965482112 10.543 93 38 77 0 999449632 25506864 8460656
11:21:11 10567 8192000000 9937611920 10315915264 1010948544 10.985 98 22 44 1 1042079616 26136928 4994144
11:22:23 8459 8192000000 9975165936 10356080640 1040078464 11.265 99 25 49 0 1071976400 26643968 5253968
11:23:34 31620 8192000000 9947313904 10345132032 1006880592 10.945 96 23 46 0 1041936320 30191360 4864368
11:25:09 12500 8192000000 9985008400 10392850432 1067551808 11.529 100 37 74 3 1094316160 18408192 8356160
11:26:24 6743 8192000000 10012308800 10402975744 1076025120 11.610 100 33 65 1 1108540768 25442192 7073456
11:27:41 18835 8192000000 10047196528 10438582272 1102263568 11.859 100 58 116 1 1147621728 33903760 11454400
11:29:21 59022 8192000000 10077850448 10496770048 1121245312 12.039 100 47 92 3 1203952336 73417088 9289936
11:31:45 33832 8192000000 10141359472 10554871808 1142109360 12.235 100 52 104 2 1254170048 101392592 10668096
11:33:26 2416 8192000000 10178886544 10583330816 1153352160 12.341 100 73 145 2 1284092896 115709024 15031712
11:34:33 11626 8192000000 10211321712 10620788736 1155041344 12.357 99 54 107 1 1301920880 135014400 11865136
11:35:50 2102 8192000000 10221796128 10645823488 1164969056 12.450 99 72 143 0 1315800704 135188608 15643040
11:36:59 8847 8192000000 10262799264 10678276096 1191522032 12.698 100 58 116 1 1362840768 157672656 13646080
11:38:12 7611 8192000000 10257681632 10681192448 1192019152 12.702 100 49 95 3 1364914112 161616048 11278912
11:39:33 2642 8192000000 10364971872 10781904896 1194685712 12.727 100 64 127 0 1465835680 255914608 15235360
11:40:41 1596 8192000000 10374097920 10781810688 1184623728 12.633 100 82 162 1 1473790528 269998784 19168016
11:41:45 4565 8192000000 10369001088 10784575488 1163195840 12.433 98 64 128 0 1474271328 295425584 15649904
11:42:55 4400 8192000000 10436358912 10842398720 1193596992 12.717 100 83 166 0 1531638112 319363168 18677952
11:44:06 3044 8192000000 10435807776 10842742784 1162793776 12.429 99 65 130 1 1533709888 355229200 15686912
11:45:14 2515 8192000000 10419649152 10819788800 1135496400 12.173 97 87 173 1 1503017216 348177248 19343568
11:46:21 2554 8192000000 10439166320 10847629312 1145185568 12.264 100 82 162 2 1537843008 373462416 19195024
11:47:28 12868 8192000000 10414512784 10835939328 1157323248 12.378 100 46 91 0 1527632416 358478784 11830384
11:48:44 19040 8192000000 10486208224 10930499584 1142916544 12.243 99 40 79 1 1578051520 424783280 10351696
11:50:11 11359 8192000000 10472604384 10916810752 1131256688 12.133 99 44 87 2 1573043888 430448272 11338928
11:51:30 20280 8192000000 10559354640 11024842752 1138196624 12.199 100 36 72 0 1677230368 527883792 11149952
11:52:53 31617 8192000000 10412650512 10908311552 1128776160 12.110 100 26 52 0 1579226464 442870800 7579504
11:54:24 36359 8192000000 10106511040 10550431744 1112125712 11.953 98 10 20 0 1196784752 82311456 2347584
11:56:35 58856 8192000000 10072889024 10545561600 1120933424 12.036 99 4 8 0 1146245392 24862128 449840
11:58:40 83261 8192000000 10064314768 10546528256 1105478000 11.890 97 14 28 1 1161710368 53832960 2399408
12:01:09 86464 8192000000 10132942400 10596458496 1120910464 12.036 98 15 28 1 1175022320 51335792 2776064
12:03:50 78527 8192000000 10070351408 10538942464 1141124640 12.226 100 18 36 0 1183306736 38519760 3662336
12:06:13 111988 8192000000 10111490736 10571276288 1110347056 11.936 97 11 22 1 1161877424 49581440 1948928
12:09:11 54986 8192000000 10137244352 10580660224 1121956416 12.045 98 14 27 1 1211433904 85776176 3701312
12:11:09 85677 8192000000 10056439712 10526228480 1109185760 11.925 98 23 46 0 1163505312 49373024 4946528
12:13:38 68965 8192000000 10093286640 10569334784 1142832096 12.242 100 25 49 1 1210141712 61017104 6292512
12:15:57 58251 8192000000 10111366352 10609442816 1148751664 12.298 100 15 30 0 1213353216 60874224 3727328
12:18:51 49205 8192000000 10089208960 10566533120 1129978432 12.121 99 8 16 0 1178077904 46519456 1580016
12:20:42 78568 8192000000 10127085040 10598866944 1140733664 12.222 100 24 48 1 1231215312 85094000 5387648
12:23:16 48827 8192000000 10096569264 10655817728 1145904432 12.271 100 7 14 0 1240795936 93405264 1486240
12:25:06 42975 8192000000 10083924912 10540253184 1145826368 12.270 100 14 28 0 1177828816 29272496 2729952
12:26:55 74104 8192000000 10104333632 10545111040 1073764672 11.588 93 17 34 0 1128160576 51253856 3142048
12:29:19 53743 8192000000 10083445376 10595155968 1111289120 11.945 97 23 46 0 1222171472 106265296 4617056
12:31:14 67903 8192000000 10119700576 10582708224 1109552304 11.928 98 14 28 0 1174012768 61708208 2752256
12:33:40 39471 8192000000 10072452720 10522763264 1127241424 12.095 100 25 49 1 1214905152 82065632 5598096
12:35:21 19385 8192000000 10096488416 10534776832 1114235920 11.973 100 31 62 0 1180716256 60450672 6029664
12:36:50 25147 8192000000 10078535776 10519994368 1102479552 11.861 100 42 84 0 1168950240 58144512 8326176
12:38:17 129499 8192000000 10062765328 10527760384 1092670144 11.768 98 35 70 2 1164249168 64060528 7518496
12:41:37 56320 8192000000 10066178128 10534653952 1096094704 11.801 99 14 26 0 1183093120 84234768 2763648
12:43:35 79084 8192000000 10093787600 10559852544 1109040880 11.923 100 6 12 0 1184830464 74523136 1266448
12:46:12 47737 8192000000 10047265056 10525241344 1113901168 11.969 100 20 40 0 1167004640 48660896 4442576
12:48:04 47726 8192000000 10098570352 10537025536 1101676208 11.854 98 37 75 0 1176100240 66808256 7615776
12:50:02 93224 8192000000 10103871776 10545782784 1102585360 11.862 99 19 38 1 1149935872 42965376 4385136
12:52:36 94838 8192000000 10071560832 10539397120 1101920576 11.856 98 17 34 3 1163549936 58239408 3389952
12:55:11 89122 8192000000 10075964944 10561839104 1110978992 11.942 98 20 40 0 1170179488 54574144 4626352
12:57:40 77858 8192000000 10069469312 10559078400 1121965248 12.046 99 18 36 1 1169481360 43972240 3543872
13:00:30 45400 8192000000 10045383568 10515865600 1126521040 12.089 100 6 12 1 1151076656 23473680 1081936
13:02:20 77550 8192000000 10105727168 10575728640 1140239120 12.218 100 9 18 0 1177458096 35755936 1463040
13:04:41 82343 8192000000 10073848080 10567548928 1134289936 12.162 99 11 22 1 1187786944 51075424 2421584
13:07:05 33225 8192000000 10096959840 10539614208 1137320976 12.190 99 36 70 0 1189449664 44450240 7678448
13:08:50 36209 8192000000 10067044032 10524315648 1110194416 11.934 98 20 39 2 1152952576 39008096 3750064
13:10:27 36521 8192000000 10089973616 10527637504 1115097504 11.981 98 38 76 0 1165613056 42445936 8069616
13:12:04 66821 8192000000 10094262160 10572931072 1090156432 11.744 97 27 54 3 1160987168 65290880 5539856
13:14:11 83446 8192000000 10042325232 10468524032 1094956640 11.790 97 14 28 0 1119514928 21864864 2693424
13:17:24 66154 8192000000 10047981840 10532347904 1094073200 11.781 97 6 12 1 1125115344 29899296 1142848
13:19:36 51173 8192000000 10092674416 10556444672 1132750880 12.147 100 12 24 0 1197657936 61805280 3101776
13:21:45 67767 8192000000 10123317760 10605432832 1089678320 11.740 96 28 56 0 1199865792 103851984 6335488
13:24:02 74264 8192000000 10094386496 10536505344 1089440560 11.737 98 16 32 0 1222727392 129625072 3661760
13:26:16 49801 8192000000 8951625488 9155440640 112376848 1.353 11 1 2 0 119902784 7490528 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-04-2021 13:27:57 to 08-04-2021 13:28:12.

Exceptions occured during test was in run

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

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

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          234273
MON$OLDEST_ACTIVE               234274
MON$OLDEST_SNAPSHOT             234274
MON$NEXT_TRANSACTION            234274
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                       2129088
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.2416 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2416 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2416 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.2416 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 ---
:28: 
:29: mscs-test-161.budzdorov.ru    Thu Apr  8 13:27:08 2021
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Thu Apr  8 13:27:08 2021
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Thu Apr  8 13:27:08 2021
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Thu Apr  8 13:27:08 2021
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
--- end of diff output ---

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

08.04.21 13:28:16. 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-04-08 13:28:19 No severe PSQL-related problems occured
08.04.21 13:28:20. End of report.