Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.12.2020 07:39
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 16685696 kB
MemAvailable 31470460 kB
Buffers 0 kB
Cached 14866204 kB
SwapCached 1972 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-05 07:38:57
DTS_END                         2020-12-05 07:38:03.1770
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44464
TRN_ID                          236772
ATT_ID                          259
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: 9105.55 23947 1092666
customer order: creation 168.03 429 20164
customer order: refuse 14.03 319 1684
order to supplier: creation 45.76 1312 5491
order to supplier: removal 3.39 125328 407
invoice (draft): creation 43.78 3508 5253
invoice (draft): removal 51.23 1022 6147
invoice accept: apply 41.53 16648 4983
invoice accept: cancel 47.12 930 5654
customer reserve: creation 3811.81 164 457417
customer reserve: removal 4580.82 75 549698
realization accept: apply 81.58 173 9790
realization accept: cancel 89.10 123 10692
payment from customer: creation 58.70 104 7044
payment from customer: removal 5.73 71 687
payment to supplier: creation 48.67 135 5840
payment to supplier: removal 3.44 74 413
service: total inventory turnovers 3.92 3216 470
service: total monetary turnovers 6.03 508 724
service: aggregate perf. data 0.87 15635 104
service: refresh index statistics 0.03 309162 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-12-2020 07:39:35 to 05-12-2020 07:39:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6663
TEST_TIME 2 5716
TEST_TIME 3 6504
TEST_TIME 4 6785
TEST_TIME 5 7007
TEST_TIME 6 6670
TEST_TIME 7 6497
TEST_TIME 8 6286
TEST_TIME 9 6106
TEST_TIME 10 6105
TEST_TIME 11 6011
TEST_TIME 12 6052
TEST_TIME 13 6743
TEST_TIME 14 6922
TEST_TIME 15 7157
TEST_TIME 16 7604
TEST_TIME 17 8275
TEST_TIME 18 8557
TEST_TIME 19 8514
TEST_TIME 20 8938
TEST_TIME 21 8824
TEST_TIME 22 9174
TEST_TIME 23 10665
TEST_TIME 24 12046
TEST_TIME 25 11740
TEST_TIME 26 11740
TEST_TIME 27 11878
TEST_TIME 28 11814
TEST_TIME 29 11623
TEST_TIME 30 11337
TEST_TIME 31 11070
TEST_TIME 32 11004
TEST_TIME 33 10996
TEST_TIME 34 10806
TEST_TIME 35 10691
TEST_TIME 36 10610
TEST_TIME 37 10557
TEST_TIME 38 10507
TEST_TIME 39 10434
TEST_TIME 40 10411
TEST_TIME 41 10410
TEST_TIME 42 10307
TEST_TIME 43 10310
TEST_TIME 44 10369
TEST_TIME 45 10367
TEST_TIME 46 10484
TEST_TIME 47 10484
TEST_TIME 48 10518
TEST_TIME 49 10386
TEST_TIME 50 10480
TEST_TIME 51 10463
TEST_TIME 52 10371
TEST_TIME 53 10190
TEST_TIME 54 10168
TEST_TIME 55 10047
TEST_TIME 56 9912
TEST_TIME 57 9872
TEST_TIME 58 9819
TEST_TIME 59 9787
TEST_TIME 60 9719
TEST_TIME 61 9681
TEST_TIME 62 9788
TEST_TIME 63 9741
TEST_TIME 64 9848
TEST_TIME 65 9820
TEST_TIME 66 9838
TEST_TIME 67 9899
TEST_TIME 68 9936
TEST_TIME 69 9885
TEST_TIME 70 9903
TEST_TIME 71 10019
TEST_TIME 72 9966
TEST_TIME 73 9846
TEST_TIME 74 9770
TEST_TIME 75 9695
TEST_TIME 76 9687
TEST_TIME 77 9697
TEST_TIME 78 9640
TEST_TIME 79 9616
TEST_TIME 80 9636
TEST_TIME 81 9579
TEST_TIME 82 9670
TEST_TIME 83 9654
TEST_TIME 84 9672
TEST_TIME 85 9641
TEST_TIME 86 9715
TEST_TIME 87 9690
TEST_TIME 88 9631
TEST_TIME 89 9560
TEST_TIME 90 9511
TEST_TIME 91 9454
TEST_TIME 92 9428
TEST_TIME 93 9431
TEST_TIME 94 9403
TEST_TIME 95 9384
TEST_TIME 96 9346
TEST_TIME 97 9322
TEST_TIME 98 9361
TEST_TIME 99 9439
TEST_TIME 100 9435
TEST_TIME 101 9489
TEST_TIME 102 9446
TEST_TIME 103 9450
TEST_TIME 104 9489
TEST_TIME 105 9547
TEST_TIME 106 9548
TEST_TIME 107 9539
TEST_TIME 108 9474
TEST_TIME 109 9417
TEST_TIME 110 9367
TEST_TIME 111 9320
TEST_TIME 112 9276
TEST_TIME 113 9286
TEST_TIME 114 9252
TEST_TIME 115 9240
TEST_TIME 116 9235
TEST_TIME 117 9208
TEST_TIME 118 9202
TEST_TIME 119 9182
TEST_TIME 120 9109

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-12-2020 07:39:38 to 05-12-2020 07:39:42.

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 1517042 1517042 0 0.00 0 18519 6 0 0
doc_list_biud 1517042 1517042 0 0.00 0 87735 34 0 0
sp_add_invoice_to_stock 5170 4983 187 3.61 30 174813 16649 0 187
sp_cancel_adding_invoice 5654 5654 0 0.00 6 121749 930 0 0
sp_cancel_client_order 1684 1684 0 0.00 4 10929 319 0 0
sp_cancel_customer_reserve 549698 549698 0 0.00 1 87739 75 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 3 4417 71 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 3 7383 74 0 0
sp_cancel_supplier_invoice 6159 6147 12 0.19 2 39068 1022 0 12
sp_cancel_supplier_order 407 407 0 0.00 5 1302124 125328 0 0
sp_cancel_write_off 10692 10692 0 0.00 1 45022 123 0 0
sp_client_order 20164 20164 0 0.00 4 53058 429 0 0
sp_customer_reserve 457417 457417 0 0.00 0 69451 164 0 0
sp_fill_shopping_cart 30897 30897 0 0.00 1 17520 41 0 0
sp_fill_shopping_cart_clo_res 457408 457408 0 0.00 0 24827 19 0 0
sp_get_clo_for_invoice 4974 4974 0 0.00 0 49778 404 0 0
sp_kill_cost_storno 17446 17446 0 0.00 0 7617 54 0 0
sp_kill_qstorno_ret_qs2qd 1116642 1116642 0 0.00 0 87726 41 0 0
sp_kill_qty_storno 589046 589046 0 0.00 0 87734 86 0 0
sp_lock_selected_doc 20487 20487 0 0.00 0 9758 6 0 0
sp_make_cost_storno 27648 27648 0 0.00 0 18504 58 0 0
sp_multiply_rows_for_pdistr 27648 27648 0 0.00 0 9080 28 0 0
sp_multiply_rows_for_qdistr 20164 20164 0 0.00 0 19238 232 0 0
sp_pay_from_customer 7044 7044 0 0.00 4 22314 105 0 0
sp_pay_to_supplier 5840 5840 0 0.00 5 17123 136 0 0
sp_payment_common 12884 12884 0 0.00 1 11791 19 0 0
sp_qd_handle_on_cancel_clo 1684 1684 0 0.00 0 10920 283 0 0
sp_qd_handle_on_invoice_upd_sts 10628 10628 0 0.00 0 34704 355 0 0
sp_qd_handle_on_reserve_upd_sts 9790 9790 0 0.00 0 47352 56 0 0
sp_reserve_write_off 9790 9790 0 0.00 6 47386 174 0 0
sp_supplier_invoice 5253 5253 0 0.00 6 94035 3509 0 0
sp_supplier_order 5491 5491 0 0.00 5 52386 1312 0 0
srv_aggregate_perf_data 104 104 0 0.00 2315 149373 15636 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1451 152157 48345 0 0
srv_find_qd_qs_mism 1046059 1046059 0 0.00 0 17002 5 0 0
srv_make_invnt_saldo 470 470 0 0.00 42 107128 3216 0 0
srv_make_money_saldo 724 724 0 0.00 1 15730 509 0 0
srv_recalc_idx_stat 4 4 0 0.00 186531 443601 309163 0 0
t$perf-abend:sp_add_invoice_to_stock 187 187 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4983 4983 0 0.00 0 25483 160 0 0
t$perf-norm:sp_cancel_adding_invoice 458 458 0 0.00 1 2608 78 0 0
t$perf-norm:sp_cancel_client_order 1684 1684 0 0.00 0 2949 12 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 5176 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 1170 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 739 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 731 14 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 16789 1330 0 0
t$perf-norm:sp_cancel_write_off 996 996 0 0.00 0 11155 15 0 0
t$perf-norm:sp_client_order 20164 20164 0 0.00 0 16056 14 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 185 2 0 0
t$perf-norm:sp_pay_from_customer 7044 7044 0 0.00 0 9056 6 0 0
t$perf-norm:sp_pay_to_supplier 5840 5840 0 0.00 0 4256 7 0 0
t$perf-norm:sp_reserve_write_off 9790 9790 0 0.00 0 12993 9 0 0
t$perf-norm:sp_supplier_invoice 5253 5253 0 0.00 0 10546 16 0 0
t$perf-norm:sp_supplier_order 5491 5491 0 0.00 0 5826 13 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 217 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 284 8 0 0
t$perf-norm:srv_make_invnt_saldo 470 470 0 0.00 0 1635 12 0 0
t$perf-norm:srv_make_money_saldo 724 724 0 0.00 0 243 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 19 6 0 0
v_add_invoice_to_stock 10322 9948 374 3.62 0 4676 20 0 374
v_all_customers 37208 37208 0 0.00 0 17 1 0 0
v_all_suppliers 29922 29922 0 0.00 0 15 1 0 0
v_all_wares 40328 40328 0 0.00 0 135 1 0 0
v_cancel_adding_invoice 916 916 0 0.00 0 815 6 0 0
v_cancel_client_order 3368 3368 0 0.00 0 1280 4 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 4117 11 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 3424 6 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 171 3 0 0
v_cancel_supplier_invoice 814 790 24 2.94 0 2290 18 0 24
v_cancel_supplier_order 814 814 0 0.00 0 691 4 0 0
v_cancel_write_off 1992 1992 0 0.00 0 4842 16 0 0
v_max_id_clo_ord 5491 5491 0 0.00 0 2196 1 0 0
v_max_id_clo_res 989 989 0 0.00 0 2168 16 0 0
v_max_id_ord_sup 5242 5242 0 0.00 0 7054 7 0 0
v_max_non_paid_invoice 1612 1612 0 0.00 0 514 2 0 0
v_max_non_paid_realizn 3500 3500 0 0.00 0 7028 5 0 0
v_min_id_clo_ord 5491 5491 0 0.00 0 1582 4 0 0
v_min_id_clo_res 989 989 0 0.00 0 2178 6 0 0
v_min_id_ord_sup 5242 5242 0 0.00 0 7879 10 0 0
v_min_non_paid_invoice 5840 5840 0 0.00 0 6742 15 0 0
v_min_non_paid_realizn 7044 7044 0 0.00 0 8455 10 0 0
v_our_firm 10208 10208 0 0.00 0 14 1 0 0
v_reserve_write_off 19580 19580 0 0.00 0 4536 7 0 0
x_lock_depdocs_on_canc_invoice 11406 11406 0 0.00 0 16451 98 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 7001 310 0 0
x_make_qstorno_1000_1200 5491 5491 0 0.00 1 52350 1244 0 0
x_make_qstorno_1200_2000 5253 5253 0 0.00 3 94017 3336 0 0
x_make_qstorno_2100_3300 457417 457417 0 0.00 0 69449 143 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-12-2020 07:39:43 to 05-12-2020 07:39:47.

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:08:06 4 8192000000 8796641392 8807174144 3168704 0.038 1 1 2 0 3266400 61840 35856
05:09:06 20698 8192000000 9722812080 10020999168 801759056 8.914 100 88 175 1 830641808 7644080 21238672
05:10:32 16490 8192000000 9764323056 10070663168 839471088 9.294 100 89 178 0 873282208 12903440 20907680
05:11:54 26025 8192000000 9811389408 10131865600 874942048 9.649 100 86 172 0 916885872 22506032 19437792
05:13:23 13824 8192000000 9865976448 10198409216 912014512 10.017 100 82 164 0 975269312 45389536 17865264
05:14:41 10368 8192000000 9915349456 10258567168 955327120 10.443 100 75 150 5 1028821344 57961328 15532896
05:15:52 4264 8192000000 9979197808 10333179904 992301632 10.804 100 82 165 2 1086206352 77054720 16850000
05:16:58 6093 8192000000 9973038608 10336141312 1025520112 11.125 100 82 164 1 1088483568 46909136 16054320
05:18:06 1504 8192000000 10030732448 10403958784 1054688640 11.406 100 87 172 0 1147316528 74920720 17707168
05:19:08 13776 8192000000 9973682576 10359668736 1061414464 11.470 100 30 57 5 1116645648 48827664 6403520
05:20:24 6822 8192000000 9980886608 10371239936 1070128256 11.553 100 46 88 1 1104288176 24977408 9182512
05:21:32 11026 8192000000 9987384320 10386731008 1078555312 11.634 100 25 49 3 1111050592 26966464 5528816
05:22:43 47904 8192000000 10001838224 10410373120 1085175376 11.697 100 46 90 4 1112230928 17194896 9860656
05:24:34 6702 8192000000 10031016512 10436276224 1095406448 11.794 100 45 90 1 1158107952 53774064 8927440
05:25:46 6267 8192000000 10019094048 10423689216 1098123040 11.820 100 41 80 0 1136209360 29668784 8417536
05:26:54 5262 8192000000 10010750528 10414080000 1100029872 11.838 100 44 87 3 1131476720 21036512 10410336
05:28:03 46231 8192000000 9992302432 10378539008 1053706816 11.396 97 53 106 2 1085351856 20764240 10880800
05:29:53 6922 8192000000 9971912288 10354130944 1039899744 11.264 100 45 87 1 1076055856 26129840 10026272
05:31:01 5361 8192000000 9938142368 10309173248 997643728 10.856 98 51 99 4 1024888096 16132672 11111696
05:32:07 7424 8192000000 9942294192 10322284544 996929632 10.849 98 41 82 3 1034202880 28475168 8798080
05:33:15 45851 8192000000 9953198560 10358054912 1034447728 11.211 100 24 48 2 1061897488 22080256 5369504
05:35:07 6247 8192000000 9965681296 10362449920 1052710576 11.387 100 43 87 5 1072508416 9768080 10029760
05:36:14 4490 8192000000 9970606096 10361651200 1062745184 11.483 100 26 51 1 1080366864 11158672 6463008
05:37:20 11033 8192000000 9978811168 10384084992 1069187344 11.544 100 24 46 5 1095115728 20319808 5608576
05:38:31 30778 8192000000 10008920880 10416001024 1084217040 11.688 100 35 70 2 1125422224 33640672 7564512
05:40:06 17117 8192000000 10050091296 10451091456 1104706560 11.882 100 47 94 3 1162052592 47822800 9523232
05:41:28 5135 8192000000 10107847584 10510848000 1123859952 12.063 100 64 128 2 1210830416 74257872 12712592
05:42:37 13643 8192000000 10141733072 10546749440 1138744288 12.204 100 56 112 3 1247279296 96360224 12174784
05:43:58 14303 8192000000 10172184048 10584956928 1141194896 12.227 99 42 84 5 1272774672 122087200 9492576
05:45:16 2268 8192000000 10221120832 10622304256 1159923088 12.403 100 61 119 1 1325267632 152347520 12997024
05:46:23 6323 8192000000 10250554512 10660085760 1161616320 12.418 100 46 90 3 1356193888 183981952 10595616
05:47:43 13541 8192000000 10301246416 10714079232 1169112960 12.489 100 47 93 0 1409597344 229515888 10968496
05:49:03 16647 8192000000 10361415712 10771869696 1169586640 12.493 99 55 110 0 1446810976 263649040 13575296
05:50:25 4730 8192000000 10387140800 10797879296 1185280336 12.639 100 80 158 1 1490004848 285488800 19235712
05:51:35 2030 8192000000 10425682960 10872000512 1185374480 12.640 100 69 138 1 1531568944 329592592 16601872
05:52:41 1450 8192000000 10452804624 10858246144 1188543392 12.670 100 93 182 1 1550854528 340794880 21516256
05:53:48 1672 8192000000 10517500096 10919698432 1177824240 12.570 100 90 178 2 1612802400 413771376 21206784
05:54:53 2038 8192000000 10496114096 10916573184 1170448544 12.501 100 85 166 1 1590896096 399946448 20501104
05:56:01 9803 8192000000 10482984912 10928996352 1173301152 12.528 100 41 82 1 1597962848 413812160 10849536
05:57:29 9398 8192000000 10512790336 10934792192 1144058576 12.254 99 51 103 1 1602424576 445351712 13014288
05:58:45 13845 8192000000 10476228960 10898694144 1125076096 12.075 99 43 85 2 1568490736 432197648 11216992
06:00:06 16009 8192000000 10484289136 10963378176 1120634496 12.033 100 34 68 2 1599827024 469362352 9830176
06:01:37 37569 8192000000 10235278928 10696511488 1054312816 11.402 95 24 48 1 1378784144 317853504 6617824
06:03:18 27044 8192000000 10059190192 10474053632 1115210784 11.982 100 38 71 3 1154362624 31193104 7958736
06:04:47 48051 8192000000 10136747040 10592788480 1122572656 12.051 100 23 44 0 1207095712 79866560 4656496
06:06:35 61247 8192000000 10053101328 10487783424 1080459568 11.652 96 24 48 2 1109870208 24208448 5202192
06:08:50 118728 8192000000 10082281232 10527735808 1104539536 11.881 98 35 66 0 1175099504 63022832 7537136
06:11:53 38909 8192000000 10065453456 10506465280 1110680416 11.939 98 34 68 5 1143355488 25921552 6753520
06:13:33 36931 8192000000 10110762512 10556514304 1134048704 12.160 100 29 58 2 1211646944 71536176 6062064
06:15:19 54249 8192000000 10095527360 10543177728 1126315744 12.087 99 30 61 1 1204693424 71665504 6712176
06:17:18 23098 8192000000 10088077568 10536218624 1123111536 12.056 99 18 34 0 1201008176 74363904 3532736
06:18:42 39382 8192000000 10093976832 10546577408 1140017184 12.216 100 14 27 1 1194125760 50924352 3184224
06:20:26 39922 8192000000 10120367280 10564182016 1110061712 11.933 97 32 65 0 1185875728 68590352 7223664
06:22:09 53474 8192000000 10103774880 10530316288 1125887424 12.083 99 27 55 0 1195681568 63959872 5834272
06:24:28 40059 8192000000 10077270352 10552086528 1117752896 12.006 98 14 29 2 1142344608 21856192 2735520
06:26:18 71212 8192000000 10064133472 10488258560 1084790944 11.693 95 19 38 0 1105398944 16810560 3797440
06:28:34 83323 8192000000 10066397792 10529669120 1062592512 11.481 94 8 16 1 1131883056 67455520 1835024
06:30:57 120926 8192000000 10062470240 10550988800 1117396784 12.002 99 21 42 0 1178993728 56513216 5083728
06:34:09 56490 8192000000 10068122720 10516922368 1116006048 11.989 99 4 8 0 1155960272 39083568 870656
06:36:06 63478 8192000000 10058418224 10507452416 1121730912 12.043 99 10 21 1 1159054912 34863184 2460816
06:38:20 30972 8192000000 10089686272 10516365312 1075164400 11.601 95 43 84 3 1129559360 45707200 8687760
06:40:01 65483 8192000000 10115801920 10596835328 1121639312 12.042 99 16 32 0 1210510592 85449968 3421312
06:42:15 61851 8192000000 10071817024 10504847360 1064085088 11.496 94 18 36 2 1121543248 53608048 3850112
06:44:17 59416 8192000000 10135161104 10602725376 1108531824 11.919 98 30 60 0 1186575760 72270976 5772960
06:46:41 64747 8192000000 10093972592 10525679616 1083281680 11.679 96 25 50 0 1147635344 59373408 4980256
06:48:48 63350 8192000000 10091084720 10608939008 1106423824 11.899 99 19 38 1 1195011040 83895968 4691248
06:50:56 127078 8192000000 10085452896 10543157248 1118779488 12.015 100 25 50 0 1161169120 37025264 5364368
06:55:16 58391 8192000000 10066888352 10519171072 1122047392 12.046 100 10 20 0 1173438016 49313552 2077072
06:57:22 83928 8192000000 10104899280 10567225344 1089763280 11.740 96 25 50 1 1156634704 61745792 5125632
07:00:12 49264 8192000000 10113747392 10619793408 1115754768 11.987 99 13 26 1 1243801984 124987744 3059472
07:02:16 139680 8192000000 10105126960 10582843392 1094672224 11.787 96 24 48 1 1157501744 57839504 4990016
07:05:41 25894 8192000000 10096827040 10519498752 1136186944 12.180 100 39 79 0 1181503328 36500368 8816016
07:07:10 152157 8192000000 10090132000 10575327232 1132059472 12.141 99 15 30 0 1192755248 57436880 3258896
07:10:42 20606 8192000000 10079455936 10572275712 1144504016 12.258 100 3 6 0 1178539248 33861712 173520
07:12:03 71374 8192000000 10079721200 10525683712 1088655008 11.730 95 16 32 0 1121842976 30172576 3015392
07:14:15 37187 8192000000 10141000800 10587435008 1143780576 12.251 99 32 64 1 1222717216 71516144 7420496
07:16:11 86667 8192000000 10142400416 10601197568 1120297296 12.030 97 24 48 1 1231378480 105682352 5398832
07:18:39 54902 8192000000 10103322224 10553446400 1116815408 11.997 98 23 45 2 1187865808 65829008 5221392
07:20:35 37112 8192000000 10086290544 10518913024 1128482032 12.107 99 49 97 4 1170869472 31862064 10525376
07:22:26 77987 8192000000 10127137504 10661797888 1104437344 11.880 97 24 48 2 1190259024 81248480 4573200
07:24:47 126491 8192000000 10041430960 10500579328 1051675312 11.377 93 16 32 0 1096248816 41826336 2747168
07:27:55 51203 8192000000 10061127568 10513465344 1130862160 12.129 100 23 46 1 1160272320 24561136 4849024
07:29:47 67154 8192000000 10097044960 10560835584 1134939296 12.168 100 10 20 1 1203171776 66020208 2212272
07:32:07 54492 8192000000 10071708352 10515820544 1097823840 11.817 96 17 34 0 1130360816 29040656 3496320
07:34:11 51024 8192000000 10085437440 10529595392 1080092800 11.648 95 21 42 2 1130129824 45295104 4741920
07:36:10 84076 8192000000 10140605968 10591748096 1089267408 11.736 96 24 48 1 1195247488 100654400 5325680

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 05-12-2020 07:39:49 to 05-12-2020 07:40:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 187 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-12-2020 07:40:04 to 05-12-2020 07:40:06.

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          244773
MON$OLDEST_ACTIVE               244774
MON$OLDEST_SNAPSHOT             244774
MON$NEXT_TRANSACTION            244774
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                       2111680
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             802
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 ---
--- end of diff output ---

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

05.12.20 07:40:07. 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-12-05 07:40:10 No severe PSQL-related problems occured
05.12.20 07:40:11. End of report.