Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.12.2020 10:28
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 16868376 kB
MemAvailable 31528608 kB
Buffers 0 kB
Cached 14756328 kB
SwapCached 1984 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-12-06 10:26:54
DTS_END                         2020-12-06 10:26:54.1700
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_MONEY_SALDO(33:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35392
TRN_ID                          231487
ATT_ID                          648
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: 8807.16 27616 1056859
customer order: creation 154.12 437 18494
customer order: refuse 13.98 351 1678
order to supplier: creation 40.94 1367 4913
order to supplier: removal 3.33 112671 399
invoice (draft): creation 43.67 4005 5240
invoice (draft): removal 50.99 832 6119
invoice accept: apply 38.91 18037 4669
invoice accept: cancel 46.09 889 5531
customer reserve: creation 3598.39 176 431807
customer reserve: removal 4542.63 66 545115
realization accept: apply 76.88 180 9225
realization accept: cancel 78.88 134 9466
payment from customer: creation 54.10 108 6492
payment from customer: removal 5.62 76 674
payment to supplier: creation 45.41 116 5449
payment to supplier: removal 3.10 100 372
service: total inventory turnovers 3.73 3310 448
service: total monetary turnovers 5.49 553 659
service: aggregate perf. data 0.88 15146 105
service: refresh index statistics 0.03 393774 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 06-12-2020 10:28:49 to 06-12-2020 10:28:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4839
TEST_TIME 2 3563
TEST_TIME 3 3249
TEST_TIME 4 3951
TEST_TIME 5 3931
TEST_TIME 6 4538
TEST_TIME 7 5047
TEST_TIME 8 5335
TEST_TIME 9 5747
TEST_TIME 10 6333
TEST_TIME 11 6641
TEST_TIME 12 7000
TEST_TIME 13 6919
TEST_TIME 14 7471
TEST_TIME 15 7589
TEST_TIME 16 7850
TEST_TIME 17 8467
TEST_TIME 18 8689
TEST_TIME 19 8876
TEST_TIME 20 8720
TEST_TIME 21 9070
TEST_TIME 22 9103
TEST_TIME 23 9245
TEST_TIME 24 9114
TEST_TIME 25 9245
TEST_TIME 26 9680
TEST_TIME 27 9682
TEST_TIME 28 10232
TEST_TIME 29 10852
TEST_TIME 30 10604
TEST_TIME 31 10289
TEST_TIME 32 10170
TEST_TIME 33 10005
TEST_TIME 34 10108
TEST_TIME 35 10147
TEST_TIME 36 9966
TEST_TIME 37 9887
TEST_TIME 38 9761
TEST_TIME 39 9689
TEST_TIME 40 9601
TEST_TIME 41 9510
TEST_TIME 42 9531
TEST_TIME 43 9556
TEST_TIME 44 9502
TEST_TIME 45 9433
TEST_TIME 46 9409
TEST_TIME 47 9365
TEST_TIME 48 9318
TEST_TIME 49 9278
TEST_TIME 50 9285
TEST_TIME 51 9319
TEST_TIME 52 9594
TEST_TIME 53 9536
TEST_TIME 54 9429
TEST_TIME 55 9556
TEST_TIME 56 9627
TEST_TIME 57 9588
TEST_TIME 58 9560
TEST_TIME 59 9414
TEST_TIME 60 9319
TEST_TIME 61 9348
TEST_TIME 62 9292
TEST_TIME 63 9209
TEST_TIME 64 9124
TEST_TIME 65 9044
TEST_TIME 66 8965
TEST_TIME 67 9054
TEST_TIME 68 9075
TEST_TIME 69 9107
TEST_TIME 70 9079
TEST_TIME 71 9063
TEST_TIME 72 9097
TEST_TIME 73 9125
TEST_TIME 74 9195
TEST_TIME 75 9385
TEST_TIME 76 9372
TEST_TIME 77 9352
TEST_TIME 78 9324
TEST_TIME 79 9334
TEST_TIME 80 9238
TEST_TIME 81 9194
TEST_TIME 82 9111
TEST_TIME 83 9038
TEST_TIME 84 9011
TEST_TIME 85 8936
TEST_TIME 86 8927
TEST_TIME 87 8924
TEST_TIME 88 8965
TEST_TIME 89 8943
TEST_TIME 90 8947
TEST_TIME 91 8957
TEST_TIME 92 8945
TEST_TIME 93 8953
TEST_TIME 94 9019
TEST_TIME 95 9103
TEST_TIME 96 9123
TEST_TIME 97 9082
TEST_TIME 98 9011
TEST_TIME 99 8977
TEST_TIME 100 8920
TEST_TIME 101 8873
TEST_TIME 102 8854
TEST_TIME 103 8876
TEST_TIME 104 8868
TEST_TIME 105 8864
TEST_TIME 106 8834
TEST_TIME 107 8829
TEST_TIME 108 8819
TEST_TIME 109 8856
TEST_TIME 110 8897
TEST_TIME 111 8946
TEST_TIME 112 8963
TEST_TIME 113 8977
TEST_TIME 114 8992
TEST_TIME 115 8991
TEST_TIME 116 8994
TEST_TIME 117 8918
TEST_TIME 118 8890
TEST_TIME 119 8836
TEST_TIME 120 8809

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-12-2020 10:28:53 to 06-12-2020 10:28:56.

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 1456533 1456533 0 0.00 0 21100 7 0 0
doc_list_biud 1456529 1456529 0 0.00 0 39324 31 0 0
sp_add_invoice_to_stock 4789 4669 120 2.50 48 213020 18038 0 120
sp_cancel_adding_invoice 5531 5531 0 0.00 6 85940 890 0 0
sp_cancel_client_order 1678 1678 0 0.00 5 11808 351 0 0
sp_cancel_customer_reserve 545115 545115 0 0.00 1 25540 67 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 3 3775 76 0 0
sp_cancel_pay_to_supplier 372 372 0 0.00 3 6664 100 0 0
sp_cancel_supplier_invoice 6129 6119 10 0.16 3 39394 833 0 10
sp_cancel_supplier_order 399 399 0 0.00 9 835804 112671 0 0
sp_cancel_write_off 9466 9466 0 0.00 1 8825 134 0 0
sp_client_order 18494 18494 0 0.00 4 25360 438 0 0
sp_customer_reserve 431807 431807 0 0.00 0 105875 176 0 0
sp_fill_shopping_cart 28640 28640 0 0.00 1 22651 49 0 0
sp_fill_shopping_cart_clo_res 431800 431800 0 0.00 0 26779 19 0 0
sp_get_clo_for_invoice 4662 4662 0 0.00 0 50544 443 0 0
sp_kill_cost_storno 16043 16043 0 0.00 0 14678 61 0 0
sp_kill_qstorno_ret_qs2qd 1106214 1106214 0 0.00 0 39322 36 0 0
sp_kill_qty_storno 582194 582194 0 0.00 0 39323 77 0 0
sp_lock_selected_doc 19303 19303 0 0.00 0 8358 7 0 0
sp_make_cost_storno 25828 25828 0 0.00 0 13477 55 0 0
sp_multiply_rows_for_pdistr 25827 25827 0 0.00 0 10549 30 0 0
sp_multiply_rows_for_qdistr 18493 18493 0 0.00 0 25080 266 0 0
sp_pay_from_customer 6492 6492 0 0.00 4 14215 109 0 0
sp_pay_to_supplier 5449 5449 0 0.00 5 16255 117 0 0
sp_payment_common 11941 11941 0 0.00 1 7083 20 0 0
sp_qd_handle_on_cancel_clo 1678 1678 0 0.00 0 11728 325 0 0
sp_qd_handle_on_invoice_upd_sts 10193 10193 0 0.00 0 38985 383 0 0
sp_qd_handle_on_reserve_upd_sts 9224 9224 0 0.00 0 16373 53 0 0
sp_reserve_write_off 9225 9225 0 0.00 7 17050 180 0 0
sp_supplier_invoice 5240 5240 0 0.00 5 104789 4006 0 0
sp_supplier_order 4913 4913 0 0.00 6 39548 1368 0 0
srv_aggregate_perf_data 105 105 0 0.00 2516 90925 15146 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1207 113777 48202 0 0
srv_find_qd_qs_mism 1012876 1012876 0 0.00 0 21099 6 0 0
srv_make_invnt_saldo 448 448 0 0.00 61 90105 3311 0 0
srv_make_money_saldo 659 659 0 0.00 1 16152 553 0 0
srv_recalc_idx_stat 4 4 0 0.00 250258 552793 393774 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4669 4669 0 0.00 0 11040 159 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 0 4832 145 0 0
t$perf-norm:sp_cancel_client_order 1678 1678 0 0.00 0 4052 20 0 0
t$perf-norm:sp_cancel_customer_reserve 595 595 0 0.00 0 1516 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 11523 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 372 372 0 0.00 0 878 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 367 367 0 0.00 0 560 7 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 21637 1559 0 0
t$perf-norm:sp_cancel_write_off 932 932 0 0.00 0 2927 8 0 0
t$perf-norm:sp_client_order 18494 18494 0 0.00 0 6310 15 0 0
t$perf-norm:sp_customer_reserve 943 943 0 0.00 0 12263 19 0 0
t$perf-norm:sp_pay_from_customer 6492 6492 0 0.00 0 12259 12 0 0
t$perf-norm:sp_pay_to_supplier 5449 5449 0 0.00 0 7720 9 0 0
t$perf-norm:sp_reserve_write_off 9225 9225 0 0.00 0 8774 9 0 0
t$perf-norm:sp_supplier_invoice 5240 5240 0 0.00 0 9983 16 0 0
t$perf-norm:sp_supplier_order 4913 4913 0 0.00 0 2819 15 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 537 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 312 15 0 0
t$perf-norm:srv_make_invnt_saldo 448 448 0 0.00 0 530 7 0 0
t$perf-norm:srv_make_money_saldo 659 659 0 0.00 0 735 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 32 10 0 0
v_add_invoice_to_stock 9558 9318 240 2.51 0 6527 16 0 240
v_all_customers 33856 33856 0 0.00 0 93 1 0 0
v_all_suppliers 28254 28254 0 0.00 0 84 1 0 0
v_all_wares 36984 36984 0 0.00 0 100 1 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 2186 10 0 0
v_cancel_client_order 3356 3356 0 0.00 0 1711 4 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 2516 8 0 0
v_cancel_customer_reserve 1190 1190 0 0.00 0 374 3 0 0
v_cancel_payment_to_supplier 744 744 0 0.00 0 248 4 0 0
v_cancel_supplier_invoice 754 734 20 2.65 0 1169 15 0 20
v_cancel_supplier_order 798 798 0 0.00 0 1855 16 0 0
v_cancel_write_off 1864 1864 0 0.00 0 4502 14 0 0
v_max_id_clo_ord 4913 4913 0 0.00 0 8750 5 0 0
v_max_id_clo_res 943 943 0 0.00 0 1655 14 0 0
v_max_id_ord_sup 5235 5235 0 0.00 0 4407 6 0 0
v_max_non_paid_invoice 1470 1470 0 0.00 0 1879 4 0 0
v_max_non_paid_realizn 3489 3489 0 0.00 0 581 2 0 0
v_min_id_clo_ord 4913 4913 0 0.00 0 2347 7 0 0
v_min_id_clo_res 943 943 0 0.00 0 8071 16 0 0
v_min_id_ord_sup 5235 5235 0 0.00 0 3684 6 0 0
v_min_non_paid_invoice 5449 5449 0 0.00 0 5000 9 0 0
v_min_non_paid_realizn 6492 6492 0 0.00 0 6164 15 0 0
v_our_firm 9134 9134 0 0.00 0 14 1 0 0
v_reserve_write_off 18448 18448 0 0.00 0 3161 5 0 0
x_lock_depdocs_on_canc_invoice 11283 11283 0 0.00 0 30423 124 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 3398 294 0 0
x_make_qstorno_1000_1200 4913 4913 0 0.00 2 34245 1286 0 0
x_make_qstorno_1200_2000 5240 5240 0 0.00 2 104769 3819 0 0
x_make_qstorno_2100_3300 431807 431807 0 0.00 0 105873 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-12-2020 10:28:57 to 06-12-2020 10:29:01.

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
07:56:57 8 8192000000 8796641392 8807174144 3168704 0.038 1 1 2 0 3266400 61840 35856
07:57:58 31147 8192000000 9734504848 10034843648 803109744 8.928 100 82 164 1 830715888 7767120 19839024
07:59:34 19783 8192000000 9769053104 10079756288 844449712 9.344 100 83 166 0 877534624 13512320 19572592
08:00:56 18940 8192000000 9821586224 10143760384 883440096 9.734 100 85 170 0 925718864 22848464 19430304
08:02:16 7645 8192000000 9876650944 10208665600 923093952 10.127 100 82 164 0 984664256 43583024 17987280
08:03:26 9277 8192000000 9926233312 10268364800 951272256 10.404 100 86 170 3 1030676464 60977216 18426992
08:04:39 4135 8192000000 9947858912 10299957248 990038592 10.782 100 76 153 0 1057077600 51334048 15704960
08:05:45 4073 8192000000 10009023632 10368438272 1019830336 11.070 100 86 169 2 1123504672 86957952 16716384
08:06:53 2025 8192000000 10005350160 10369683456 1043491584 11.298 100 88 177 1 1114982944 53754304 17737056
08:07:55 32722 8192000000 10012093072 10392424448 1060843984 11.465 100 67 132 3 1139047024 64816080 13386960
08:09:29 5041 8192000000 10023528992 10406567936 1070365248 11.556 100 66 127 1 1135329392 50893040 14071104
08:10:36 5057 8192000000 10017069232 10407174144 1081130992 11.658 100 56 112 1 1139778112 46209328 12437792
08:11:43 6600 8192000000 10002829232 10398883840 1087901712 11.723 100 26 52 1 1119345728 26085136 5358880
08:12:52 40986 8192000000 10009392752 10411601920 1089045696 11.734 100 33 66 0 1109070032 13103360 6920976
08:14:34 3397 8192000000 10002803984 10404896768 1095125392 11.791 100 49 97 1 1118723824 12875856 10722576
08:15:39 6377 8192000000 10014927824 10412687360 1098894160 11.827 100 37 74 3 1124507440 17102416 8510864
08:16:45 8421 8192000000 10014252592 10421833728 1092491952 11.766 100 31 60 3 1131195648 31252096 7451600
08:17:55 41622 8192000000 9971120528 10357075968 1037689968 11.242 97 39 80 1 1063866560 17682720 8493872
08:19:37 6111 8192000000 9939540144 10322669568 999871744 10.877 98 40 80 2 1030306592 21783440 8651408
08:20:46 6190 8192000000 9939435456 10317365248 1016401696 11.037 99 38 74 1 1048733120 23517840 8813584
08:21:52 4428 8192000000 9953484128 10326650880 1024878400 11.119 99 47 91 4 1050949056 15419744 10650912
08:22:58 20980 8192000000 9964818272 10338930688 1037692176 11.242 100 63 123 1 1071535408 20357744 13485488
08:24:19 6147 8192000000 9970592560 10358321152 1051093232 11.371 100 47 93 4 1078858656 16691616 11073808
08:25:28 11368 8192000000 9970825232 10377564160 1058711760 11.444 100 32 65 2 1088544656 22525920 7306976
08:26:44 7134 8192000000 9984608144 10395492352 1069558672 11.548 100 34 68 5 1089697568 13002224 7136672
08:27:55 66880 8192000000 10022342352 10426159104 1086322304 11.708 100 36 71 1 1128370832 34791744 7256784
08:30:04 59121 8192000000 10076463824 10498510848 1113198080 11.963 100 37 73 3 1184608480 62949088 8461312
08:32:05 6814 8192000000 10134924368 10534256640 1127658144 12.099 100 64 126 1 1239536240 98447712 13430384
08:33:16 1765 8192000000 10163380672 10560921600 1133489040 12.154 100 71 142 1 1268286592 118989984 15807568
08:34:22 11344 8192000000 10194042272 10603892736 1144152496 12.255 100 55 109 2 1300021904 143488864 12380544
08:35:37 6496 8192000000 10208566816 10627940352 1142694448 12.241 100 61 118 0 1316982816 160600960 13687408
08:36:48 2673 8192000000 10241046528 10645143552 1150627392 12.315 100 70 140 3 1347494144 181305008 15561744
08:37:55 1991 8192000000 10211754528 10624823296 1123629552 12.061 98 85 166 3 1308132960 165813472 18689936
08:39:02 3396 8192000000 10231631712 10637725696 1157021296 12.375 100 77 153 0 1327505232 153377920 17106016
08:40:11 1206 8192000000 10274751456 10683813888 1138555616 12.202 100 91 182 2 1372588000 214286880 19745504
08:41:15 2414 8192000000 10241894320 10636476416 1131665568 12.137 100 91 179 0 1333231392 182293200 19272624
08:42:25 4853 8192000000 10260131088 10674429952 1117457984 12.003 98 81 161 0 1351597504 215654688 18484832
08:43:34 2407 8192000000 10264463040 10688278528 1143838560 12.252 100 85 169 0 1370675664 207948960 18888144
08:44:38 4139 8192000000 10219317536 10642624512 1141997616 12.234 100 54 107 1 1317507008 162862384 12647008
08:45:47 14294 8192000000 10237168384 10652303360 1100671328 11.844 96 31 62 2 1311744656 203704816 7368512
08:47:05 2548 8192000000 10303527840 10731462656 1142834832 12.242 99 64 128 4 1396829344 239243184 14751328
08:48:11 15855 8192000000 10249212896 10659311616 1124659728 12.071 98 27 52 1 1333536912 202927264 5949920
08:49:32 11761 8192000000 10311303120 10739572736 1140690160 12.222 99 31 62 1 1418517520 270254800 7572560
08:50:46 13073 8192000000 10335467552 10770604032 1143155968 12.245 100 28 55 0 1447975648 297550384 7269296
08:52:02 31810 8192000000 10320102800 10818924544 1143529552 12.249 100 24 48 3 1447585376 298167104 5888720
08:53:39 40768 8192000000 10318122448 10758295552 1094108688 11.782 97 17 34 1 1405437632 306816224 4512720
08:55:22 41578 8192000000 10056913552 10489683968 1105814464 11.893 98 27 54 0 1168602448 56819936 5968048
08:57:11 58306 8192000000 10074848752 10502479872 1108843664 11.921 99 50 100 2 1171841888 51930944 11067280
08:59:10 35000 8192000000 10126606304 10556796928 1134867456 12.167 100 22 43 1 1224065072 84444128 4753488
09:02:17 74827 8192000000 10108076640 10563289088 1133527376 12.155 100 22 45 4 1204392944 65992128 4873440
09:04:39 95431 8192000000 10130634768 10593492992 1113896464 11.969 97 25 50 0 1222899200 103346144 5656592
09:08:01 87355 8192000000 10074137344 10557267968 1143027536 12.244 99 25 51 1 1222241968 74510000 4704432
09:10:30 78667 8192000000 10065300384 10527100928 1144746368 12.260 100 12 23 1 1181899568 34903200 2250000
09:12:50 42461 8192000000 10073240544 10522734592 1125515072 12.079 98 23 48 0 1169587696 39572464 4500160
09:14:38 19719 8192000000 10133567248 10569650176 1147376656 12.285 99 57 113 0 1234173248 74416672 12379920
09:16:27 43403 8192000000 10180689040 10610180096 1134579520 12.165 98 25 50 0 1243360448 103231360 5549568
09:18:22 73390 8192000000 10081062400 10568962048 1152556192 12.333 100 8 16 0 1214467712 60162272 1749248
09:20:43 49291 8192000000 10159588512 10633043968 1130031536 12.122 98 37 72 1 1219084208 81178928 7873744
09:22:43 79853 8192000000 10078017472 10515775488 1094236496 11.783 95 10 20 0 1118761936 22986016 1539424
09:25:07 103692 8192000000 10068011264 10523254784 1103589760 11.872 97 21 42 1 1162937200 54670176 4677264
09:27:59 34377 8192000000 10036253616 10486710272 1121111648 12.037 100 9 18 1 1144755248 22039984 1603616
09:29:34 73417 8192000000 10078673824 10522099712 1096192448 11.801 97 13 26 1 1165032496 66074544 2765504
09:31:48 77127 8192000000 10150786080 10617909248 1121028784 12.037 99 20 40 1 1223740944 98450928 4261232
09:35:01 47219 8192000000 10048874288 10497830912 1093984544 11.781 98 18 36 0 1142012432 44299520 3728368
09:36:51 28660 8192000000 10108671056 10545143808 1128072304 12.103 100 21 42 2 1206993360 74471904 4449152
09:38:21 60148 8192000000 10155717936 10611150848 1117767936 12.006 98 25 50 2 1200723520 77410256 5545328
09:40:31 100335 8192000000 10097879568 10654609408 1113267808 11.963 98 11 22 1 1249987104 134170640 2548656
09:43:12 57584 8192000000 10070654544 10480603136 1076687088 11.616 95 27 54 0 1105187344 22859984 5640272
09:45:14 99154 8192000000 10076269216 10535190528 1114225296 11.972 100 43 86 1 1208090592 85388800 8476496
09:48:02 53824 8192000000 10015270784 10453426176 1078521296 11.633 97 8 16 0 1098385008 18640784 1222928
09:49:56 52945 8192000000 10064989248 10523361280 1117050288 11.999 100 16 32 2 1170455632 49954448 3450896
09:51:50 71164 8192000000 10132525088 10621378560 1127477632 12.098 100 10 20 1 1231478896 101523360 2477904
09:54:07 43596 8192000000 10054536576 10485850112 1093585472 11.777 97 19 37 0 1148075776 50105936 4384368
09:55:54 59870 8192000000 10118166368 10561699840 1109620624 11.929 98 26 52 1 1183323440 67859712 5843104
09:58:03 55437 8192000000 10145402896 10596327424 1135100448 12.169 100 24 48 1 1239234880 98631600 5502832
09:59:59 98982 8192000000 10120525632 10596663296 1107796704 11.912 97 15 30 1 1233640416 122683616 3160096
10:03:53 104090 8192000000 10063591984 10527469568 1135868176 12.177 100 21 42 1 1184284512 44222800 4193536
10:06:46 22090 8192000000 10071171360 10539741184 1140792624 12.223 100 5 10 0 1172725328 31087168 845536
10:08:08 67245 8192000000 10111218384 10561802240 1147726784 12.288 100 7 14 0 1176738560 27835728 1176048
10:10:20 93121 8192000000 10100603616 10545889280 1125577328 12.080 97 26 52 3 1171710320 40910000 5222992
10:13:05 102493 8192000000 10155596240 10618953728 1142818512 12.242 99 25 49 0 1233248992 85100384 5330096
10:16:28 113777 8192000000 10140378448 10612490240 1097473808 11.814 96 10 20 0 1152262080 52966704 1821568
10:19:38 43727 8192000000 10103226048 10575736832 1122326256 12.049 98 33 66 2 1178553296 49888048 6338992
10:21:35 34272 8192000000 10097987824 10584940544 1137247088 12.190 100 7 13 1 1178748912 40168416 1333408
10:23:10 104422 8192000000 10041303024 10499244032 1093610512 11.777 96 24 48 0 1139847360 40908112 5328736
10:25:56 52579 8192000000 10048702016 10504491008 1106787248 11.902 99 8 16 0 1144698720 36465248 1446224

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-12-2020 10:29:03 to 06-12-2020 10:29:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 120 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-12-2020 10:29:19 to 06-12-2020 10:29:21.

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          230486
MON$OLDEST_ACTIVE               231597
MON$OLDEST_SNAPSHOT             231597
MON$NEXT_TRANSACTION            231597
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                       2101568
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             788
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2290 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2290 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2290 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.2290 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 ---
:51: 
:52: mscs-test-161.budzdorov.ru    Sun Dec  6 10:26:58 2020
:53:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:54: 
:55: 
:56: mscs-test-161.budzdorov.ru    Sun Dec  6 10:27:16 2020
:57:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:58: 
--- end of diff output ---

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

06.12.20 10:29:22. There are 6 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-12-06 10:29:24 No severe PSQL-related problems occured
06.12.20 10:29:26. End of report.