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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16723716 kB
MemAvailable 31386792 kB
Buffers 0 kB
Cached 14916128 kB
SwapCached 3484 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-16 07:26:53
DTS_END                         2020-11-16 07:26:53.2760
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/51360
TRN_ID                          230141
ATT_ID                          682
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: 8974.30 23326 1076916
customer order: creation 162.31 408 19477
customer order: refuse 13.63 299 1636
order to supplier: creation 43.33 1342 5199
order to supplier: removal 3.42 110924 410
invoice (draft): creation 44.36 3212 5323
invoice (draft): removal 52.28 841 6274
invoice accept: apply 39.57 17293 4748
invoice accept: cancel 47.86 762 5743
customer reserve: creation 3666.28 169 439953
customer reserve: removal 4621.59 65 554591
realization accept: apply 78.58 172 9430
realization accept: cancel 79.59 119 9551
payment from customer: creation 57.48 112 6898
payment from customer: removal 5.28 62 633
payment to supplier: creation 45.49 122 5459
payment to supplier: removal 3.18 64 382
service: total inventory turnovers 3.65 3387 438
service: total monetary turnovers 5.66 505 679
service: aggregate perf. data 0.73 22198 88
service: refresh index statistics 0.03 304466 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-11-2020 07:28:09 to 16-11-2020 07:28:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3053
TEST_TIME 2 2649
TEST_TIME 3 3863
TEST_TIME 4 4061
TEST_TIME 5 4521
TEST_TIME 6 4513
TEST_TIME 7 4721
TEST_TIME 8 4588
TEST_TIME 9 4580
TEST_TIME 10 4884
TEST_TIME 11 5084
TEST_TIME 12 5108
TEST_TIME 13 5547
TEST_TIME 14 5529
TEST_TIME 15 5640
TEST_TIME 16 5762
TEST_TIME 17 6146
TEST_TIME 18 6581
TEST_TIME 19 6670
TEST_TIME 20 7210
TEST_TIME 21 7478
TEST_TIME 22 8046
TEST_TIME 23 8257
TEST_TIME 24 9237
TEST_TIME 25 9742
TEST_TIME 26 10084
TEST_TIME 27 10026
TEST_TIME 28 10517
TEST_TIME 29 10341
TEST_TIME 30 10147
TEST_TIME 31 10322
TEST_TIME 32 10523
TEST_TIME 33 10376
TEST_TIME 34 10366
TEST_TIME 35 10095
TEST_TIME 36 9857
TEST_TIME 37 9786
TEST_TIME 38 9858
TEST_TIME 39 9757
TEST_TIME 40 9764
TEST_TIME 41 9612
TEST_TIME 42 9485
TEST_TIME 43 9557
TEST_TIME 44 9491
TEST_TIME 45 9393
TEST_TIME 46 9424
TEST_TIME 47 9521
TEST_TIME 48 9454
TEST_TIME 49 9379
TEST_TIME 50 9450
TEST_TIME 51 9563
TEST_TIME 52 9572
TEST_TIME 53 9559
TEST_TIME 54 9619
TEST_TIME 55 9661
TEST_TIME 56 9819
TEST_TIME 57 9760
TEST_TIME 58 9807
TEST_TIME 59 9791
TEST_TIME 60 9795
TEST_TIME 61 9641
TEST_TIME 62 9561
TEST_TIME 63 9482
TEST_TIME 64 9415
TEST_TIME 65 9366
TEST_TIME 66 9312
TEST_TIME 67 9333
TEST_TIME 68 9306
TEST_TIME 69 9244
TEST_TIME 70 9318
TEST_TIME 71 9300
TEST_TIME 72 9329
TEST_TIME 73 9378
TEST_TIME 74 9426
TEST_TIME 75 9406
TEST_TIME 76 9449
TEST_TIME 77 9419
TEST_TIME 78 9461
TEST_TIME 79 9531
TEST_TIME 80 9414
TEST_TIME 81 9343
TEST_TIME 82 9265
TEST_TIME 83 9237
TEST_TIME 84 9191
TEST_TIME 85 9163
TEST_TIME 86 9187
TEST_TIME 87 9158
TEST_TIME 88 9162
TEST_TIME 89 9149
TEST_TIME 90 9177
TEST_TIME 91 9161
TEST_TIME 92 9170
TEST_TIME 93 9218
TEST_TIME 94 9242
TEST_TIME 95 9246
TEST_TIME 96 9242
TEST_TIME 97 9185
TEST_TIME 98 9131
TEST_TIME 99 9076
TEST_TIME 100 9037
TEST_TIME 101 9026
TEST_TIME 102 8969
TEST_TIME 103 8939
TEST_TIME 104 8970
TEST_TIME 105 8948
TEST_TIME 106 8982
TEST_TIME 107 9039
TEST_TIME 108 9060
TEST_TIME 109 9041
TEST_TIME 110 9056
TEST_TIME 111 9062
TEST_TIME 112 9041
TEST_TIME 113 9076
TEST_TIME 114 9011
TEST_TIME 115 9001
TEST_TIME 116 9010
TEST_TIME 117 8981
TEST_TIME 118 8975
TEST_TIME 119 8955
TEST_TIME 120 8978

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-11-2020 07:28:13 to 16-11-2020 07:28:16.

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 1484824 1484824 0 0.00 0 21360 6 0 0
doc_list_biud 1484822 1484822 0 0.00 0 54489 31 0 0
sp_add_invoice_to_stock 4840 4748 92 1.90 53 232264 17294 0 92
sp_cancel_adding_invoice 5743 5743 0 0.00 5 88922 762 0 0
sp_cancel_client_order 1636 1636 0 0.00 4 8958 300 0 0
sp_cancel_customer_reserve 554591 554591 0 0.00 1 24185 66 0 0
sp_cancel_pay_from_customer 633 633 0 0.00 3 8518 63 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 3 2046 65 0 0
sp_cancel_supplier_invoice 6283 6274 9 0.14 2 54546 842 0 9
sp_cancel_supplier_order 410 410 0 0.00 7 1003164 110924 0 0
sp_cancel_write_off 9551 9551 0 0.00 1 7940 119 0 0
sp_client_order 19477 19477 0 0.00 4 35172 409 0 0
sp_customer_reserve 439953 439953 0 0.00 0 95587 170 0 0
sp_fill_shopping_cart 29985 29985 0 0.00 1 26361 41 0 0
sp_fill_shopping_cart_clo_res 439949 439949 0 0.00 0 24412 19 0 0
sp_get_clo_for_invoice 4742 4742 0 0.00 0 35007 455 0 0
sp_kill_cost_storno 16309 16309 0 0.00 0 7203 51 0 0
sp_kill_qstorno_ret_qs2qd 1125417 1125417 0 0.00 0 54488 36 0 0
sp_kill_qty_storno 592376 592376 0 0.00 0 54489 76 0 0
sp_lock_selected_doc 19661 19661 0 0.00 0 6463 5 0 0
sp_make_cost_storno 26529 26529 0 0.00 0 13066 58 0 0
sp_multiply_rows_for_pdistr 26528 26528 0 0.00 0 7434 32 0 0
sp_multiply_rows_for_qdistr 19477 19477 0 0.00 0 35162 244 0 0
sp_pay_from_customer 6898 6898 0 0.00 4 12418 113 0 0
sp_pay_to_supplier 5459 5459 0 0.00 5 15374 123 0 0
sp_payment_common 12357 12357 0 0.00 1 9192 26 0 0
sp_qd_handle_on_cancel_clo 1636 1636 0 0.00 0 8952 278 0 0
sp_qd_handle_on_invoice_upd_sts 10484 10484 0 0.00 0 32716 366 0 0
sp_qd_handle_on_reserve_upd_sts 9430 9430 0 0.00 0 9239 49 0 0
sp_reserve_write_off 9430 9430 0 0.00 6 18108 172 0 0
sp_supplier_invoice 5323 5323 0 0.00 5 72259 3213 0 0
sp_supplier_order 5199 5199 0 0.00 6 58358 1342 0 0
srv_aggregate_perf_data 88 88 0 0.00 2710 362493 22199 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 917 150690 50575 0 0
srv_find_qd_qs_mism 1031922 1031922 0 0.00 0 21358 5 0 0
srv_make_invnt_saldo 438 438 0 0.00 55 122617 3387 0 0
srv_make_money_saldo 679 679 0 0.00 1 12365 505 0 0
srv_recalc_idx_stat 4 4 0 0.00 57614 519310 304466 0 0
t$perf-abend:sp_add_invoice_to_stock 92 92 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 4748 4748 0 0.00 0 16162 173 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 0 2156 55 0 0
t$perf-norm:sp_cancel_client_order 1636 1636 0 0.00 0 8214 16 0 0
t$perf-norm:sp_cancel_customer_reserve 665 665 0 0.00 0 5563 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 633 633 0 0.00 0 1105 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 324 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 438 8 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 25893 1372 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 5801 17 0 0
t$perf-norm:sp_client_order 19477 19477 0 0.00 0 12195 16 0 0
t$perf-norm:sp_customer_reserve 979 979 0 0.00 0 5489 10 0 0
t$perf-norm:sp_pay_from_customer 6898 6898 0 0.00 0 14275 13 0 0
t$perf-norm:sp_pay_to_supplier 5459 5459 0 0.00 0 5681 10 0 0
t$perf-norm:sp_reserve_write_off 9430 9430 0 0.00 0 15000 14 0 0
t$perf-norm:sp_supplier_invoice 5323 5323 0 0.00 0 12649 17 0 0
t$perf-norm:sp_supplier_order 5199 5199 0 0.00 0 2117 15 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 1057 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 126 3 0 0
t$perf-norm:srv_make_invnt_saldo 438 438 0 0.00 0 656 4 0 0
t$perf-norm:srv_make_money_saldo 679 679 0 0.00 0 1000 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 9664 9480 184 1.90 0 4638 15 0 184
v_all_customers 36240 36240 0 0.00 0 13 1 0 0
v_all_suppliers 28884 28884 0 0.00 0 17 1 0 0
v_all_wares 38954 38954 0 0.00 0 112 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 144 5 0 0
v_cancel_client_order 3272 3272 0 0.00 0 827 3 0 0
v_cancel_customer_prepayment 1266 1266 0 0.00 0 250 3 0 0
v_cancel_customer_reserve 1330 1330 0 0.00 0 1584 4 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 403 5 0 0
v_cancel_supplier_invoice 774 756 18 2.32 0 1041 14 0 18
v_cancel_supplier_order 820 820 0 0.00 0 930 5 0 0
v_cancel_write_off 1962 1962 0 0.00 0 3873 13 0 0
v_max_id_clo_ord 5194 5194 0 0.00 0 2342 2 0 0
v_max_id_clo_res 979 979 0 0.00 0 2875 20 0 0
v_max_id_ord_sup 5313 5313 0 0.00 0 3945 4 0 0
v_max_non_paid_invoice 1524 1524 0 0.00 0 3299 6 0 0
v_max_non_paid_realizn 3304 3304 0 0.00 0 6483 7 0 0
v_min_id_clo_ord 5194 5194 0 0.00 0 2588 6 0 0
v_min_id_clo_res 979 979 0 0.00 0 6678 20 0 0
v_min_id_ord_sup 5313 5313 0 0.00 0 12954 12 0 0
v_min_non_paid_invoice 5459 5459 0 0.00 0 8254 14 0 0
v_min_non_paid_realizn 6898 6898 0 0.00 0 8279 20 0 0
v_our_firm 9902 9902 0 0.00 0 14 1 0 0
v_reserve_write_off 18860 18860 0 0.00 0 4772 4 0 0
x_lock_depdocs_on_canc_invoice 11639 11639 0 0.00 0 19761 99 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 9529 322 0 0
x_make_qstorno_1000_1200 5199 5199 0 0.00 1 58342 1274 0 0
x_make_qstorno_1200_2000 5323 5323 0 0.00 2 65796 3056 0 0
x_make_qstorno_2100_3300 439953 439953 0 0.00 0 95545 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-11-2020 07:28:17 to 16-11-2020 07:28:21.

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
04:56:56 2 8192000000 8796641312 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
04:57:57 40984 8192000000 9726443344 10022064128 799934752 8.896 100 95 190 1 832263840 9357680 22971408
04:59:40 14847 8192000000 9770396144 10076209152 841141552 9.311 100 90 180 1 876005168 13569680 21293936
05:01:01 12325 8192000000 9802075200 10118074368 869092672 9.591 100 90 180 1 916172736 26402384 20677680
05:02:16 13299 8192000000 9864117344 10193285120 909898800 9.996 100 87 173 0 971596352 42986832 18710720
05:03:33 9396 8192000000 9905890400 10250240000 949959520 10.391 100 78 156 1 1024012464 57550400 16502544
05:04:45 4703 8192000000 9952326368 10308091904 989126080 10.773 100 69 139 2 1066926960 62682176 15118704
05:05:51 2650 8192000000 9992559168 10354061312 1023670896 11.107 100 85 168 2 1109568368 67920272 17977200
05:06:56 1752 8192000000 10019864544 10387173376 1043571392 11.299 100 93 186 0 1136597344 73726864 19299088
05:07:59 20922 8192000000 9996887648 10376548352 1055561552 11.414 100 60 118 1 1117054176 49618976 11873648
05:09:20 6722 8192000000 9986320960 10381709312 1058149632 11.439 100 34 68 1 1113541840 48518784 6873424
05:10:28 7199 8192000000 9977709088 10374656000 1068556384 11.538 100 39 78 2 1097283952 19826128 8901440
05:11:36 4048 8192000000 9998001008 10383286272 1077980688 11.628 100 44 90 3 1112123728 24786464 9356576
05:12:44 27195 8192000000 9999621888 10387828736 1077142912 11.620 100 53 106 0 1101641312 12232848 12265552
05:14:12 4905 8192000000 10008353824 10402934784 1082889744 11.675 100 42 85 1 1115860704 23797056 9173904
05:15:18 4084 8192000000 10014922240 10407550976 1093548992 11.776 100 42 83 2 1116938768 14262336 9127440
05:16:24 8263 8192000000 10000271168 10396217344 1080239280 11.650 100 25 50 0 1113617056 28222592 5155184
05:17:34 34782 8192000000 10007582816 10407292928 1033945344 11.206 96 35 69 0 1065935216 24686448 7303424
05:19:10 8048 8192000000 9954650320 10351288320 1010615504 10.981 96 30 59 1 1032577184 15627920 6333760
05:20:20 9870 8192000000 9926791760 10320838656 1017233152 11.045 100 32 63 3 1052219904 27814144 7172608
05:21:30 7618 8192000000 9927204464 10307641344 987380672 10.756 97 29 57 1 1018539360 24610992 6547696
05:22:39 42780 8192000000 9944438208 10346295296 1010238336 10.978 98 36 72 1 1042816320 24555440 8022544
05:24:27 5495 8192000000 9974174768 10366992384 1051084416 11.371 100 35 69 3 1081369504 22095408 8189680
05:25:34 7746 8192000000 9981075840 10387365888 1064596240 11.500 100 34 67 2 1086301984 14021408 7684336
05:26:42 10805 8192000000 9994734208 10403987456 1083620960 11.682 100 15 30 0 1102655456 16038480 2996016
05:27:57 37927 8192000000 10031860768 10433699840 1098512736 11.824 100 48 96 2 1135831264 26443440 10875088
05:29:41 8900 8192000000 10059779488 10466775040 1113135040 11.962 100 48 96 1 1169847904 46280016 10432848
05:31:01 7099 8192000000 10106779328 10509848576 1129576784 12.117 100 55 110 0 1205458960 63294064 12588112
05:32:11 11518 8192000000 10139742064 10547343360 1143534592 12.249 100 49 95 4 1253031104 98787552 10708960
05:33:24 10428 8192000000 10172599488 10583465984 1152495424 12.333 100 47 92 1 1276029824 113434384 10100016
05:34:42 10905 8192000000 10198216272 10608852992 1164566336 12.446 100 50 99 2 1301533984 125284672 11682976
05:35:54 27660 8192000000 10230589872 10665136128 1174606032 12.540 100 38 74 0 1339932208 156390144 8936032
05:37:27 4744 8192000000 10250692208 10672807936 1172677296 12.522 99 62 125 0 1350915552 163428608 14809648
05:38:36 5143 8192000000 10297950336 10713092096 1177968944 12.571 99 63 126 3 1410406016 218202224 14234848
05:39:45 4874 8192000000 10322638720 10736431104 1189402736 12.678 100 58 116 1 1420353504 217491776 13458992
05:40:55 6620 8192000000 10367845184 10788143104 1194455344 12.725 100 59 118 1 1476260048 268001392 13803312
05:42:10 3895 8192000000 10385024800 10797867008 1172460960 12.520 99 84 165 1 1480991360 289445488 19084912
05:43:21 915 8192000000 10469899760 10878296064 1185774304 12.644 100 80 160 0 1566295776 362243024 18278448
05:44:25 16393 8192000000 10419172768 10857922560 1175932416 12.552 100 48 96 1 1508897952 320818928 12146608
05:45:48 2961 8192000000 10422792320 10829340672 1150579648 12.315 99 82 159 1 1513798496 344792432 18426416
05:46:55 15164 8192000000 10395560752 10825711616 1142053984 12.235 100 34 68 2 1498407728 347372128 8981616
05:48:13 15911 8192000000 10366217904 10790207488 1128656656 12.109 100 35 71 0 1473923840 335906944 9360240
05:49:30 13096 8192000000 10326734992 10759524352 1102561472 11.862 99 29 58 0 1424171600 314199344 7410784
05:50:52 42850 8192000000 10159507952 10614722560 1073322240 11.584 97 18 36 1 1260674288 182547888 4804160
05:52:55 54074 8192000000 10150408576 10607575040 1103028448 11.866 99 12 24 2 1214309120 108195520 3085152
05:55:13 19801 8192000000 10036001984 10447167488 1101441872 11.851 100 35 67 1 1125709072 17035728 7231472
05:57:06 126226 8192000000 10056146384 10520719360 1103678880 11.873 98 10 20 1 1148023040 42876656 1467504
06:00:32 150690 8192000000 10092004512 10525433856 1123605760 12.061 100 34 68 2 1191597312 61066608 6924944
06:04:03 36624 8192000000 10079420752 10559344640 1118072400 12.009 99 31 61 3 1176217696 51749072 6396224
06:05:41 62896 8192000000 10096294480 10565844992 1129377792 12.115 99 10 21 0 1187892672 56249440 2265440
06:08:06 80118 8192000000 10142947184 10622791680 1146662608 12.278 100 23 46 2 1260752768 108408016 5682144
06:10:27 44991 8192000000 10096272368 10537979904 1107270320 11.907 97 6 11 2 1182217648 73546720 1400608
06:12:17 61579 8192000000 10107326352 10634539008 1129940640 12.121 99 8 16 0 1226779216 95256912 1581664
06:14:20 33650 8192000000 10063921376 10512551936 1138780400 12.204 100 14 28 1 1161783328 19844624 3158304
06:15:59 84026 8192000000 10171120304 10666999808 1137382720 12.191 99 26 52 0 1246200288 103641824 5175744
06:18:24 101572 8192000000 10133132832 10588409856 1155011888 12.357 100 31 62 3 1213680576 52166656 6502032
06:21:11 50752 8192000000 10162467968 10659438592 1154309824 12.350 99 17 34 1 1237494880 79388992 3796064
06:23:16 37984 8192000000 10095672080 10519932928 1113140496 11.962 96 36 72 2 1151031936 30757664 7133776
06:25:09 99267 8192000000 10074015856 10556895232 1114917952 11.979 96 15 30 1 1168335088 50221728 3195408
06:27:49 56697 8192000000 10083724944 10550898688 1152041568 12.329 100 20 40 1 1191044048 35144208 3858272
06:29:48 44597 8192000000 10082981200 10551992320 1142442352 12.238 99 7 14 0 1179358368 35735360 1180656
06:31:35 49828 8192000000 10061674384 10537156608 1111804656 11.949 97 10 20 1 1139801904 26435328 1561920
06:33:30 29106 8192000000 10067872080 10512416768 1122148224 12.047 98 23 46 0 1152669440 25870144 4651072
06:35:04 42025 8192000000 10116067040 10537644032 1099020304 11.828 97 32 63 0 1151710848 45812064 6878480
06:36:47 100770 8192000000 10050495904 10524012544 1041674224 11.281 92 25 49 1 1129721904 82698960 5348720
06:39:31 105209 8192000000 10075926384 10541563904 1071318576 11.565 96 20 41 0 1128022800 51660112 5044112
06:42:36 53994 8192000000 10070867024 10513039360 1119172720 12.019 100 22 44 2 1154504064 31134096 4197248
06:44:30 75367 8192000000 10057191680 10547396608 1079758976 11.645 97 21 42 1 1161802640 78060400 3983264
06:46:46 90875 8192000000 10032381024 10472538112 1090643072 11.749 98 16 32 1 1123888944 29789312 3456560
06:50:02 75312 8192000000 10061127328 10567933952 1124991664 12.074 100 10 20 1 1177196608 49899536 2305408
06:52:17 92914 8192000000 10079655536 10522169344 1077859232 11.627 96 15 30 2 1134331552 53403952 3068368
06:54:58 56721 8192000000 10100643856 10536218624 1117775024 12.006 99 23 46 0 1200212992 77304752 5133216
06:56:58 80444 8192000000 10119022352 10562203648 1094726400 11.788 97 12 25 0 1168848480 71785504 2336576
06:59:18 39160 8192000000 10086999200 10538336256 1130365888 12.125 100 16 32 2 1173200336 39563680 3270768
07:01:10 51290 8192000000 10073227072 10543321088 1100573744 11.843 98 23 47 1 1145676592 39951936 5150912
07:03:08 118532 8192000000 10050565152 10501214208 1129724272 12.119 100 21 42 0 1169123728 35144480 4254976
07:06:30 38622 8192000000 10078925968 10558218240 1133122352 12.151 100 8 16 0 1185764768 50914832 1727584
07:08:09 92116 8192000000 10128492928 10575343616 1119047408 12.018 98 19 38 1 1196962688 74035024 3880256
07:10:43 85793 8192000000 10164276880 10618277888 1139402480 12.210 99 21 42 0 1237390656 93411728 4576448
07:13:13 56057 8192000000 10098248544 10563014656 1135404496 12.172 99 13 26 0 1243647072 104878672 3363904
07:15:09 70483 8192000000 10096655120 10546397184 1110572240 11.938 97 20 39 0 1160186736 45766080 3848416
07:17:22 34315 8192000000 10065073664 10499772416 1085730400 11.702 95 16 32 3 1110207504 21297696 3179408
07:18:59 22547 8192000000 10114395072 10585542656 1096178064 11.801 97 47 94 2 1171647712 65559488 9910160
07:20:28 135256 8192000000 10099603040 10578067456 1124744080 12.072 100 18 36 1 1220983760 92495664 3744016
07:23:44 77060 8192000000 10084262288 10534682624 1108487440 11.918 98 18 36 0 1149308992 36943360 3878192
07:26:03 58429 8192000000 9060493872 9313472512 209650384 2.495 19 3 6 0 227632624 17463456 518784

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-11-2020 07:28:23 to 16-11-2020 07:28:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 92 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:01, from 16-11-2020 07:28:39 to 16-11-2020 07:28:40.

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          230412
MON$OLDEST_ACTIVE               230413
MON$OLDEST_SNAPSHOT             230413
MON$NEXT_TRANSACTION            230413
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                       2103616
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.2265 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2265 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2265 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.2265 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 ---
:138: 
:139: mscs-test-161.budzdorov.ru    Mon Nov 16 07:27:06 2020
:140:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:141: 
--- end of diff output ---

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

16.11.20 07:28:41. 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-16 07:28:44 No severe PSQL-related problems occured
16.11.20 07:28:45. End of report.