Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.11.2020 07:37
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 16968916 kB
MemAvailable 31509736 kB
Buffers 0 kB
Cached 14735312 kB
SwapCached 2872 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-04 07:35:17
DTS_END                         2020-11-04 07:35:17.5120
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/60456
TRN_ID                          230180
ATT_ID                          608
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: 8497.54 25303 1019705
customer order: creation 162.09 410 19451
customer order: refuse 13.79 313 1655
order to supplier: creation 43.38 1386 5206
order to supplier: removal 3.11 118850 373
invoice (draft): creation 43.81 4004 5257
invoice (draft): removal 49.55 934 5946
invoice accept: apply 39.53 18861 4744
invoice accept: cancel 44.11 792 5293
customer reserve: creation 3557.06 189 426847
customer reserve: removal 4265.96 69 511915
realization accept: apply 78.43 202 9412
realization accept: cancel 75.38 128 9045
payment from customer: creation 56.30 129 6756
payment from customer: removal 5.57 91 668
payment to supplier: creation 46.04 151 5525
payment to supplier: removal 3.13 97 376
service: total inventory turnovers 3.56 3479 427
service: total monetary turnovers 5.78 611 693
service: aggregate perf. data 0.93 13063 112
service: refresh index statistics 0.03 342306 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-11-2020 07:37:56 to 04-11-2020 07:37:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4854
TEST_TIME 2 4958
TEST_TIME 3 4346
TEST_TIME 4 4850
TEST_TIME 5 5133
TEST_TIME 6 5268
TEST_TIME 7 5688
TEST_TIME 8 5799
TEST_TIME 9 5906
TEST_TIME 10 5839
TEST_TIME 11 6050
TEST_TIME 12 6258
TEST_TIME 13 6685
TEST_TIME 14 6675
TEST_TIME 15 7024
TEST_TIME 16 7266
TEST_TIME 17 7750
TEST_TIME 18 7895
TEST_TIME 19 8296
TEST_TIME 20 8198
TEST_TIME 21 8518
TEST_TIME 22 8607
TEST_TIME 23 8650
TEST_TIME 24 9015
TEST_TIME 25 9074
TEST_TIME 26 9384
TEST_TIME 27 10876
TEST_TIME 28 10815
TEST_TIME 29 10602
TEST_TIME 30 10431
TEST_TIME 31 10183
TEST_TIME 32 10060
TEST_TIME 33 9846
TEST_TIME 34 9605
TEST_TIME 35 9673
TEST_TIME 36 9708
TEST_TIME 37 9498
TEST_TIME 38 9359
TEST_TIME 39 9245
TEST_TIME 40 9258
TEST_TIME 41 9151
TEST_TIME 42 9041
TEST_TIME 43 8918
TEST_TIME 44 8855
TEST_TIME 45 8794
TEST_TIME 46 9090
TEST_TIME 47 9036
TEST_TIME 48 9019
TEST_TIME 49 9011
TEST_TIME 50 8953
TEST_TIME 51 8957
TEST_TIME 52 8949
TEST_TIME 53 9061
TEST_TIME 54 9048
TEST_TIME 55 9031
TEST_TIME 56 9070
TEST_TIME 57 9078
TEST_TIME 58 9109
TEST_TIME 59 8974
TEST_TIME 60 8866
TEST_TIME 61 8804
TEST_TIME 62 8712
TEST_TIME 63 8693
TEST_TIME 64 8660
TEST_TIME 65 8642
TEST_TIME 66 8681
TEST_TIME 67 8653
TEST_TIME 68 8621
TEST_TIME 69 8736
TEST_TIME 70 8728
TEST_TIME 71 8719
TEST_TIME 72 8683
TEST_TIME 73 8841
TEST_TIME 74 8825
TEST_TIME 75 8822
TEST_TIME 76 8877
TEST_TIME 77 8842
TEST_TIME 78 8837
TEST_TIME 79 8786
TEST_TIME 80 8741
TEST_TIME 81 8756
TEST_TIME 82 8695
TEST_TIME 83 8635
TEST_TIME 84 8598
TEST_TIME 85 8596
TEST_TIME 86 8666
TEST_TIME 87 8735
TEST_TIME 88 8726
TEST_TIME 89 8744
TEST_TIME 90 8704
TEST_TIME 91 8743
TEST_TIME 92 8725
TEST_TIME 93 8690
TEST_TIME 94 8852
TEST_TIME 95 8850
TEST_TIME 96 8820
TEST_TIME 97 8749
TEST_TIME 98 8707
TEST_TIME 99 8667
TEST_TIME 100 8607
TEST_TIME 101 8641
TEST_TIME 102 8651
TEST_TIME 103 8612
TEST_TIME 104 8594
TEST_TIME 105 8569
TEST_TIME 106 8589
TEST_TIME 107 8575
TEST_TIME 108 8556
TEST_TIME 109 8680
TEST_TIME 110 8674
TEST_TIME 111 8688
TEST_TIME 112 8724
TEST_TIME 113 8704
TEST_TIME 114 8720
TEST_TIME 115 8742
TEST_TIME 116 8675
TEST_TIME 117 8636
TEST_TIME 118 8601
TEST_TIME 119 8553
TEST_TIME 120 8500

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-11-2020 07:38:00 to 04-11-2020 07:38:03.

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 1415526 1415526 0 0.00 0 19554 7 0 0
doc_list_biud 1415524 1415524 0 0.00 0 40592 32 0 0
sp_add_invoice_to_stock 4827 4744 83 1.71 17 204963 18861 0 83
sp_cancel_adding_invoice 5293 5293 0 0.00 5 67687 793 0 0
sp_cancel_client_order 1655 1655 0 0.00 5 10753 314 0 0
sp_cancel_customer_reserve 511915 511915 0 0.00 1 29756 70 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 3 8470 91 0 0
sp_cancel_pay_to_supplier 376 376 0 0.00 3 2076 97 0 0
sp_cancel_supplier_invoice 5953 5946 7 0.11 2 40657 934 0 7
sp_cancel_supplier_order 373 373 0 0.00 6 1026519 118850 0 0
sp_cancel_write_off 9045 9045 0 0.00 1 8053 129 0 0
sp_client_order 19451 19451 0 0.00 4 34585 410 0 0
sp_customer_reserve 426847 426847 0 0.00 0 61178 190 0 0
sp_fill_shopping_cart 29907 29907 0 0.00 1 53859 60 0 0
sp_fill_shopping_cart_clo_res 426841 426841 0 0.00 0 24548 21 0 0
sp_get_clo_for_invoice 4738 4738 0 0.00 0 33443 441 0 0
sp_kill_cost_storno 15382 15382 0 0.00 0 8461 54 0 0
sp_kill_qstorno_ret_qs2qd 1039194 1039194 0 0.00 0 40588 38 0 0
sp_kill_qty_storno 548377 548377 0 0.00 0 40592 80 0 0
sp_lock_selected_doc 19580 19580 0 0.00 0 8854 6 0 0
sp_make_cost_storno 26431 26431 0 0.00 0 19157 66 0 0
sp_multiply_rows_for_pdistr 26431 26431 0 0.00 0 15675 32 0 0
sp_multiply_rows_for_qdistr 19451 19451 0 0.00 0 17981 226 0 0
sp_pay_from_customer 6756 6756 0 0.00 4 15641 130 0 0
sp_pay_to_supplier 5525 5525 0 0.00 4 19832 151 0 0
sp_payment_common 12281 12281 0 0.00 1 14875 32 0 0
sp_qd_handle_on_cancel_clo 1655 1655 0 0.00 0 10748 286 0 0
sp_qd_handle_on_invoice_upd_sts 10031 10031 0 0.00 0 21943 351 0 0
sp_qd_handle_on_reserve_upd_sts 9412 9412 0 0.00 0 10380 64 0 0
sp_reserve_write_off 9412 9412 0 0.00 6 19183 202 0 0
sp_supplier_invoice 5257 5257 0 0.00 6 90972 4005 0 0
sp_supplier_order 5206 5206 0 0.00 5 51798 1386 0 0
srv_aggregate_perf_data 112 112 0 0.00 2676 106412 13063 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2276 160290 49836 0 0
srv_find_qd_qs_mism 975294 975294 0 0.00 0 19552 6 0 0
srv_make_invnt_saldo 427 427 0 0.00 49 97558 3479 0 0
srv_make_money_saldo 693 693 0 0.00 1 17369 612 0 0
srv_recalc_idx_stat 4 4 0 0.00 176627 479923 342306 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 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 4744 4744 0 0.00 0 10527 154 0 0
t$perf-norm:sp_cancel_adding_invoice 372 372 0 0.00 0 6759 118 0 0
t$perf-norm:sp_cancel_client_order 1655 1655 0 0.00 0 8655 18 0 0
t$perf-norm:sp_cancel_customer_reserve 644 644 0 0.00 0 7787 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 7365 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 376 376 0 0.00 0 2764 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 6902 34 0 0
t$perf-norm:sp_cancel_supplier_order 373 373 0 0.00 0 25361 1319 0 0
t$perf-norm:sp_cancel_write_off 948 948 0 0.00 0 1830 8 0 0
t$perf-norm:sp_client_order 19451 19451 0 0.00 0 10729 17 0 0
t$perf-norm:sp_customer_reserve 962 962 0 0.00 0 694 3 0 0
t$perf-norm:sp_pay_from_customer 6756 6756 0 0.00 0 5887 5 0 0
t$perf-norm:sp_pay_to_supplier 5525 5525 0 0.00 0 4622 10 0 0
t$perf-norm:sp_reserve_write_off 9412 9412 0 0.00 0 3894 9 0 0
t$perf-norm:sp_supplier_invoice 5257 5257 0 0.00 0 8635 15 0 0
t$perf-norm:sp_supplier_order 5206 5206 0 0.00 0 8805 20 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 570 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 75 3 0 0
t$perf-norm:srv_make_invnt_saldo 427 427 0 0.00 0 976 10 0 0
t$perf-norm:srv_make_money_saldo 693 693 0 0.00 0 5868 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9642 9476 166 1.72 0 8227 19 0 166
v_all_customers 35328 35328 0 0.00 0 59 1 0 0
v_all_suppliers 28488 28488 0 0.00 0 20 1 0 0
v_all_wares 38902 38902 0 0.00 0 31 1 0 0
v_cancel_adding_invoice 744 744 0 0.00 0 1617 9 0 0
v_cancel_client_order 3310 3310 0 0.00 0 3385 6 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 3285 12 0 0
v_cancel_customer_reserve 1288 1288 0 0.00 0 3351 9 0 0
v_cancel_payment_to_supplier 752 752 0 0.00 0 2046 15 0 0
v_cancel_supplier_invoice 802 788 14 1.74 0 2267 17 0 14
v_cancel_supplier_order 746 746 0 0.00 0 6384 15 0 0
v_cancel_write_off 1896 1896 0 0.00 0 3434 19 0 0
v_max_id_clo_ord 5205 5205 0 0.00 0 544 1 0 0
v_max_id_clo_res 962 962 0 0.00 0 5359 27 0 0
v_max_id_ord_sup 5251 5251 0 0.00 0 4517 5 0 0
v_max_non_paid_invoice 1736 1736 0 0.00 0 368 2 0 0
v_max_non_paid_realizn 3648 3648 0 0.00 0 6877 6 0 0
v_min_id_clo_ord 5205 5205 0 0.00 0 3086 9 0 0
v_min_id_clo_res 962 962 0 0.00 0 2002 5 0 0
v_min_id_ord_sup 5251 5251 0 0.00 0 5224 9 0 0
v_min_non_paid_invoice 5525 5525 0 0.00 0 13826 23 0 0
v_min_non_paid_realizn 6756 6756 0 0.00 0 14866 26 0 0
v_our_firm 9790 9790 0 0.00 0 15 1 0 0
v_reserve_write_off 18824 18824 0 0.00 0 3547 7 0 0
x_lock_depdocs_on_canc_invoice 10845 10845 0 0.00 0 12314 110 0 0
x_lock_depdocs_on_canc_sup_ord 373 373 0 0.00 0 18944 372 0 0
x_make_qstorno_1000_1200 5206 5206 0 0.00 2 46827 1296 0 0
x_make_qstorno_1200_2000 5257 5257 0 0.00 2 85258 3754 0 0
x_make_qstorno_2100_3300 426847 426847 0 0.00 0 61177 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-11-2020 07:38:04 to 04-11-2020 07:38:08.

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:05:20 10 8192000000 8798373232 8809664512 4436416 0.054 2 1 2 0 4535888 63616 35856
05:06:21 10431 8192000000 9704495904 9994366976 787017968 8.765 100 90 178 1 816300128 7376064 21906096
05:07:40 16915 8192000000 9754278688 10056232960 827264512 9.172 100 92 184 0 860769168 11284160 22220496
05:09:02 15720 8192000000 9789458688 10102517760 854486512 9.445 100 82 164 0 891840112 18393952 18959648
05:10:23 13691 8192000000 9850820048 10179862528 903209952 9.930 100 81 163 1 961663920 40360208 18093760
05:11:41 8579 8192000000 9911058000 10250874880 945723056 10.349 100 79 157 2 1021410464 59240560 16446848
05:12:53 7605 8192000000 9956000112 10307784704 988847504 10.770 100 80 156 4 1069856704 65131680 15877520
05:14:04 2936 8192000000 9979195504 10342887424 1017734992 11.050 100 85 165 0 1090157712 56534880 15887840
05:15:08 2043 8192000000 10022789024 10394050560 1042493712 11.289 100 86 171 1 1133884000 73898832 17491456
05:16:12 1839 8192000000 10058576240 10431676416 1065129056 11.506 100 92 182 0 1160811792 77193344 18489392
05:17:15 4912 8192000000 10017893424 10414170112 1068132336 11.534 100 35 71 2 1122449824 47526176 6791312
05:18:21 7139 8192000000 10006629040 10407440384 1077954720 11.628 100 51 103 2 1122387488 33798752 10634016
05:19:28 6278 8192000000 10005695760 10408529920 1083387792 11.680 100 41 81 0 1124464784 32153264 8923728
05:20:36 70865 8192000000 9995650208 10410586112 1085262656 11.698 100 20 39 1 1112845184 23583744 3998784
05:22:48 6470 8192000000 10005686624 10415149056 1095211856 11.792 100 40 80 1 1125169056 21121136 8836064
05:23:55 10360 8192000000 10024840560 10443456512 1098696288 11.825 100 31 60 1 1131412688 26213376 6503024
05:25:08 7725 8192000000 9996935840 10397093888 1076922048 11.618 99 18 34 1 1091486000 11081968 3481984
05:26:17 33125 8192000000 9999120144 10403151872 1030146064 11.170 95 31 62 4 1070514032 33901200 6466768
05:27:54 6562 8192000000 9965133488 10358542336 1036202800 11.228 100 43 87 0 1079601120 33231520 10166800
05:29:03 6008 8192000000 9933551584 10304860160 1003245792 10.910 99 28 54 3 1020279264 10493040 6540432
05:30:11 8894 8192000000 9948654592 10335219712 1025522352 11.125 100 38 76 2 1051123312 17015792 8585168
05:31:21 38666 8192000000 9943568096 10342789120 1017838160 11.051 98 16 32 2 1032997504 12094496 3064848
05:33:03 4896 8192000000 9967605072 10350862336 1049042464 11.351 100 55 109 4 1076582144 14415552 13124128
05:34:11 10858 8192000000 9981170400 10379067392 1061293104 11.469 100 28 56 0 1080196544 12327200 6576240
05:35:26 23445 8192000000 10001046160 10390822912 1084823088 11.693 100 44 86 2 1102891504 8955712 9112704
05:36:54 62480 8192000000 10047530096 10452275200 1100770560 11.845 100 47 93 3 1163925776 52950928 10204288
05:39:00 9023 8192000000 10090211680 10486398976 1115015312 11.980 100 55 109 1 1197841120 72053248 10772560
05:40:19 10304 8192000000 10084061632 10481807360 1125325328 12.077 100 53 107 2 1199594896 61889600 12379968
05:41:32 10334 8192000000 10162124336 10560172032 1137101264 12.188 100 61 122 1 1255539920 105273504 13165152
05:42:57 2275 8192000000 10153407408 10563833856 1147509584 12.286 100 55 107 1 1259092096 99915152 11667360
05:44:04 4800 8192000000 10180667696 10593886208 1155790480 12.364 100 60 120 2 1288171120 119138896 13241744
05:45:15 5646 8192000000 10205772464 10606759936 1155617472 12.362 100 67 130 1 1307478352 137515360 14345520
05:46:29 6740 8192000000 10246024480 10643972096 1162817920 12.430 100 70 136 1 1340777344 163366448 14592976
05:47:39 5773 8192000000 10286179712 10690478080 1158560960 12.390 100 67 133 0 1386545776 213396992 14587824
05:48:53 6202 8192000000 10281033152 10675933184 1155740624 12.363 100 83 161 1 1375914416 203253376 16920416
05:50:01 3353 8192000000 10324980672 10722889728 1157723008 12.382 100 84 168 0 1427666528 250941584 19001936
05:51:11 4601 8192000000 10336826208 10737942528 1150215632 12.312 99 61 122 0 1437498352 273638624 13644096
05:52:22 2639 8192000000 10349338528 10768760832 1165402480 12.454 100 69 138 0 1454131024 272466032 16262512
05:53:30 9445 8192000000 10336686464 10753064960 1143439200 12.248 100 50 99 2 1440486544 284978752 12068592
05:54:42 8456 8192000000 10337483088 10773901312 1155129648 12.358 100 39 78 2 1435986016 271169216 9687152
05:55:54 10094 8192000000 10376668864 10795257856 1160733984 12.410 100 44 88 4 1481797216 309675040 11388192
05:57:10 3880 8192000000 10368946640 10792165376 1154729520 12.354 100 41 81 0 1467565104 303160160 9675424
05:58:23 16874 8192000000 10365610416 10794897408 1127973664 12.102 99 34 68 2 1445295952 308602864 8719424
05:59:57 25380 8192000000 10346047392 10784456704 1137381472 12.191 100 31 60 3 1442664368 297914832 7368064
06:01:37 23911 8192000000 10319339344 10771841024 1094426576 11.785 98 25 50 1 1427862304 325437984 7997744
06:03:17 62611 8192000000 10058739152 10519543808 1087563840 11.719 97 9 18 1 1132146560 42783664 1799056
06:06:24 39783 8192000000 10076697296 10506203136 1120532736 12.032 99 32 64 4 1170552480 43304896 6714848
06:08:04 68449 8192000000 10129499200 10569338880 1146870400 12.280 100 16 32 1 1211082496 61016288 3195808
06:10:14 50303 8192000000 10102485104 10578538496 1125963632 12.083 99 6 12 1 1203859200 76708464 1187104
06:12:22 69624 8192000000 10060205536 10503852032 1131935760 12.140 99 27 54 0 1146450944 8645216 5869968
06:14:35 18116 8192000000 10091623792 10536050688 1140337936 12.219 99 30 60 3 1199477056 52693520 6445600
06:15:56 68443 8192000000 10111811936 10556035072 1141740816 12.232 99 19 38 0 1178860640 33170080 3949744
06:18:13 89767 8192000000 10172382608 10622849024 1145258128 12.265 99 20 39 1 1247601456 97932720 4410608
06:20:44 77250 8192000000 10089593184 10640764928 1139151232 12.208 99 10 20 0 1236561536 95045328 2364976
06:23:02 76316 8192000000 10097541024 10556870656 1124068736 12.065 97 26 49 1 1169906992 40693472 5144784
06:25:18 36429 8192000000 10068427424 10494869504 1102598768 11.862 96 32 63 4 1131172384 22409328 6164288
06:26:55 88183 8192000000 10116698640 10592923648 1117015664 11.999 97 14 28 0 1176378960 56650064 2713232
06:29:24 59223 8192000000 10070111648 10523914240 1098231376 11.821 96 19 38 0 1144129200 41872128 4025696
06:31:28 59398 8192000000 10085692032 10511675392 1079436720 11.642 95 16 32 0 1120926752 38155568 3334464
06:33:28 140594 8192000000 10026570176 10466394112 1105479952 11.890 98 17 34 1 1142109744 32595712 4034080
06:36:49 53253 8192000000 10051367712 10497257472 1112906976 11.960 98 7 14 0 1148380336 34335264 1138096
06:38:42 93433 8192000000 10059625232 10536304640 1118717824 12.015 98 13 26 0 1177520624 55986672 2816128
06:41:17 75096 8192000000 10063888752 10518417408 1085508992 11.700 96 18 36 0 1134146816 44744464 3893360
06:43:51 35677 8192000000 10092881232 10544549888 1097167568 11.811 98 27 55 3 1181737072 79114384 5455120
06:45:29 46537 8192000000 10070953696 10523770880 1112614464 11.957 99 17 34 0 1182356288 66060368 3681456
06:47:36 45995 8192000000 10170163600 10600611840 1121625072 12.042 99 46 92 0 1249996688 118167616 10204000
06:49:32 22051 8192000000 10066880144 10495660032 1110114864 11.934 98 43 86 4 1148431200 29494864 8821472
06:50:54 65957 8192000000 10031207984 10465722368 1090528864 11.748 99 25 50 2 1165560944 69839728 5192352
06:53:01 19896 8192000000 10085504784 10510635008 1091433568 11.756 98 48 96 1 1153991344 51699776 10858000
06:54:21 160290 8192000000 10037756752 10511269888 1118387952 12.012 100 33 65 3 1219466624 93856528 7222144
06:58:22 61508 8192000000 10097513376 10543116288 1102194128 11.858 99 13 26 0 1164062240 58956656 2911456
07:00:53 63169 8192000000 10082002608 10533183488 1090245536 11.745 98 21 42 0 1165036832 69635744 5155552
07:02:57 97607 8192000000 10067957088 10505883648 1102160240 11.858 98 12 24 1 1153150480 48734864 2255376
07:05:35 61444 8192000000 10097353360 10540359680 1130824656 12.129 100 38 76 0 1190830848 52414736 7591456
07:07:48 73332 8192000000 10165860416 10596872192 1131210032 12.133 99 26 52 3 1218369376 81640592 5518752
07:10:02 104518 8192000000 10096419904 10518130688 1131516304 12.136 99 20 40 2 1169541088 34360880 3663904
07:12:49 113541 8192000000 10100567776 10547339264 1135376656 12.172 99 26 52 1 1202797808 61754800 5666352
07:15:54 23982 8192000000 10095711504 10541367296 1116711424 11.996 98 10 20 0 1200820464 82074352 2034688
07:17:18 47843 8192000000 10062655264 10600001536 1092328880 11.765 97 2 4 1 1138020224 45470112 221232
07:19:51 41193 8192000000 10118985232 10544984064 1093771200 11.779 96 24 48 0 1170807536 70878944 6157392
07:21:37 71149 8192000000 10126888624 10579800064 1131261744 12.133 100 24 48 1 1245853232 109030480 5561008
07:23:52 86791 8192000000 10052323104 10536030208 1095363200 11.794 98 11 22 0 1175660560 77737456 2559904
07:26:25 43941 8192000000 10119092016 10589859840 1111463792 11.946 99 46 92 1 1192091248 71073936 9553520
07:28:12 72582 8192000000 10146061440 10660397056 1131993648 12.140 100 14 28 0 1223939744 88792912 3153184
07:30:29 132144 8192000000 10065132400 10508091392 1121572336 12.042 99 21 42 0 1144046064 18016016 4457712
07:33:42 116589 8192000000 9650466160 10070671360 734997888 8.233 65 5 10 0 772829440 36994720 836832

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-11-2020 07:38:10 to 04-11-2020 07:38:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 83 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 04-11-2020 07:38:26 to 04-11-2020 07:38:28.

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          230109
MON$OLDEST_ACTIVE               230484
MON$OLDEST_SNAPSHOT             230484
MON$NEXT_TRANSACTION            230484
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                       2091456
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             791
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2244 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2244 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2244 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.2244 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Wed Nov  4 07:35:58 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
--- end of diff output ---

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

04.11.20 07:38:29. 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-04 07:38:31 No severe PSQL-related problems occured
04.11.20 07:38:33. End of report.