Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.12.2020 10: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 17076716 kB
MemAvailable 31488264 kB
Buffers 0 kB
Cached 14480988 kB
SwapCached 1940 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-02 10:26:53
DTS_END                         2020-12-02 10:26:53.5720
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_INVNT_SALDO(40:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57592
TRN_ID                          238807
ATT_ID                          661
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: 8976.60 24295 1077192
customer order: creation 173.67 446 20840
customer order: refuse 14.62 336 1754
order to supplier: creation 46.58 1389 5590
order to supplier: removal 3.31 114876 397
invoice (draft): creation 45.76 3303 5491
invoice (draft): removal 48.76 933 5851
invoice accept: apply 41.60 16291 4992
invoice accept: cancel 44.33 855 5319
customer reserve: creation 3928.60 156 471432
customer reserve: removal 4334.49 71 520139
realization accept: apply 84.95 162 10194
realization accept: cancel 79.20 111 9504
payment from customer: creation 60.37 98 7244
payment from customer: removal 5.97 66 716
payment to supplier: creation 49.37 119 5924
payment to supplier: removal 3.80 91 456
service: total inventory turnovers 4.33 2785 520
service: total monetary turnovers 5.99 532 719
service: aggregate perf. data 0.88 13756 106
service: refresh index statistics 0.03 329514 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-12-2020 10:27:44 to 02-12-2020 10:27:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3618
TEST_TIME 2 4108
TEST_TIME 3 4378
TEST_TIME 4 5214
TEST_TIME 5 5515
TEST_TIME 6 5702
TEST_TIME 7 6002
TEST_TIME 8 5911
TEST_TIME 9 6366
TEST_TIME 10 6615
TEST_TIME 11 6828
TEST_TIME 12 7344
TEST_TIME 13 7593
TEST_TIME 14 7692
TEST_TIME 15 8123
TEST_TIME 16 8467
TEST_TIME 17 8654
TEST_TIME 18 8870
TEST_TIME 19 8991
TEST_TIME 20 8972
TEST_TIME 21 9717
TEST_TIME 22 9748
TEST_TIME 23 10315
TEST_TIME 24 10988
TEST_TIME 25 11723
TEST_TIME 26 11609
TEST_TIME 27 11397
TEST_TIME 28 11133
TEST_TIME 29 10943
TEST_TIME 30 10940
TEST_TIME 31 10877
TEST_TIME 32 10844
TEST_TIME 33 10695
TEST_TIME 34 10513
TEST_TIME 35 10397
TEST_TIME 36 10171
TEST_TIME 37 10186
TEST_TIME 38 10100
TEST_TIME 39 10105
TEST_TIME 40 9974
TEST_TIME 41 9940
TEST_TIME 42 9792
TEST_TIME 43 9811
TEST_TIME 44 9819
TEST_TIME 45 9868
TEST_TIME 46 9758
TEST_TIME 47 9771
TEST_TIME 48 9902
TEST_TIME 49 9850
TEST_TIME 50 9842
TEST_TIME 51 9799
TEST_TIME 52 9809
TEST_TIME 53 9921
TEST_TIME 54 9887
TEST_TIME 55 9747
TEST_TIME 56 9614
TEST_TIME 57 9560
TEST_TIME 58 9482
TEST_TIME 59 9393
TEST_TIME 60 9342
TEST_TIME 61 9338
TEST_TIME 62 9333
TEST_TIME 63 9288
TEST_TIME 64 9263
TEST_TIME 65 9225
TEST_TIME 66 9246
TEST_TIME 67 9262
TEST_TIME 68 9353
TEST_TIME 69 9376
TEST_TIME 70 9429
TEST_TIME 71 9527
TEST_TIME 72 9559
TEST_TIME 73 9605
TEST_TIME 74 9588
TEST_TIME 75 9473
TEST_TIME 76 9417
TEST_TIME 77 9369
TEST_TIME 78 9319
TEST_TIME 79 9229
TEST_TIME 80 9222
TEST_TIME 81 9184
TEST_TIME 82 9167
TEST_TIME 83 9214
TEST_TIME 84 9179
TEST_TIME 85 9204
TEST_TIME 86 9218
TEST_TIME 87 9267
TEST_TIME 88 9260
TEST_TIME 89 9350
TEST_TIME 90 9326
TEST_TIME 91 9329
TEST_TIME 92 9242
TEST_TIME 93 9233
TEST_TIME 94 9177
TEST_TIME 95 9138
TEST_TIME 96 9145
TEST_TIME 97 9123
TEST_TIME 98 9127
TEST_TIME 99 9128
TEST_TIME 100 9116
TEST_TIME 101 9092
TEST_TIME 102 9090
TEST_TIME 103 9097
TEST_TIME 104 9060
TEST_TIME 105 9134
TEST_TIME 106 9158
TEST_TIME 107 9212
TEST_TIME 108 9173
TEST_TIME 109 9101
TEST_TIME 110 9066
TEST_TIME 111 9019
TEST_TIME 112 8964
TEST_TIME 113 8938
TEST_TIME 114 8945
TEST_TIME 115 8927
TEST_TIME 116 8904
TEST_TIME 117 8906
TEST_TIME 118 8912
TEST_TIME 119 8963
TEST_TIME 120 8983

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-12-2020 10:27:47 to 02-12-2020 10:27:51.

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 1510704 1510704 0 0.00 0 24998 6 0 0
doc_list_biud 1510704 1510704 0 0.00 0 102094 30 0 0
sp_add_invoice_to_stock 5137 4992 145 2.82 42 149729 16291 0 145
sp_cancel_adding_invoice 5319 5319 0 0.00 4 69820 856 0 0
sp_cancel_client_order 1754 1754 0 0.00 4 8524 336 0 0
sp_cancel_customer_reserve 520139 520139 0 0.00 1 70567 71 0 0
sp_cancel_pay_from_customer 716 716 0 0.00 2 1928 66 0 0
sp_cancel_pay_to_supplier 456 456 0 0.00 3 8038 92 0 0
sp_cancel_supplier_invoice 5867 5851 16 0.27 2 102098 934 0 16
sp_cancel_supplier_order 397 397 0 0.00 8 923952 114876 0 0
sp_cancel_write_off 9504 9504 0 0.00 1 7578 112 0 0
sp_client_order 20840 20840 0 0.00 4 21576 447 0 0
sp_customer_reserve 471432 471432 0 0.00 0 93308 157 0 0
sp_fill_shopping_cart 31907 31907 0 0.00 1 37743 43 0 0
sp_fill_shopping_cart_clo_res 471418 471418 0 0.00 0 35399 19 0 0
sp_get_clo_for_invoice 4978 4978 0 0.00 0 26734 405 0 0
sp_kill_cost_storno 15995 15995 0 0.00 0 7859 54 0 0
sp_kill_qstorno_ret_qs2qd 1056030 1056030 0 0.00 0 102093 38 0 0
sp_kill_qty_storno 558136 558136 0 0.00 0 102093 81 0 0
sp_lock_selected_doc 21008 21008 0 0.00 0 8944 5 0 0
sp_make_cost_storno 28340 28340 0 0.00 0 15223 53 0 0
sp_multiply_rows_for_pdistr 28340 28340 0 0.00 0 9081 28 0 0
sp_multiply_rows_for_qdistr 20840 20840 0 0.00 0 21509 275 0 0
sp_pay_from_customer 7244 7244 0 0.00 4 15608 99 0 0
sp_pay_to_supplier 5924 5924 0 0.00 4 19701 119 0 0
sp_payment_common 13168 13168 0 0.00 1 11574 18 0 0
sp_qd_handle_on_cancel_clo 1754 1754 0 0.00 0 8517 312 0 0
sp_qd_handle_on_invoice_upd_sts 10297 10297 0 0.00 0 29462 329 0 0
sp_qd_handle_on_reserve_upd_sts 10194 10194 0 0.00 0 7742 43 0 0
sp_reserve_write_off 10194 10194 0 0.00 7 20028 163 0 0
sp_supplier_invoice 5491 5491 0 0.00 7 107978 3304 0 0
sp_supplier_order 5590 5590 0 0.00 6 93501 1390 0 0
srv_aggregate_perf_data 106 106 0 0.00 2757 134461 13757 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2709 137691 44291 0 0
srv_find_qd_qs_mism 1027264 1027264 0 0.00 0 24997 5 0 0
srv_make_invnt_saldo 520 520 0 0.00 35 132354 2785 0 0
srv_make_money_saldo 719 719 0 0.00 0 20003 533 0 0
srv_recalc_idx_stat 4 4 0 0.00 107483 500832 329514 0 0
t$perf-abend:sp_add_invoice_to_stock 145 145 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4992 4992 0 0.00 0 10028 122 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 1 3935 66 0 0
t$perf-norm:sp_cancel_client_order 1754 1754 0 0.00 0 1509 9 0 0
t$perf-norm:sp_cancel_customer_reserve 684 684 0 0.00 0 556 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 716 716 0 0.00 0 4750 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 456 456 0 0.00 0 4065 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 2062 19 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 33045 1427 0 0
t$perf-norm:sp_cancel_write_off 1028 1028 0 0.00 0 510 3 0 0
t$perf-norm:sp_client_order 20840 20840 0 0.00 0 8600 14 0 0
t$perf-norm:sp_customer_reserve 959 959 0 0.00 0 587 4 0 0
t$perf-norm:sp_pay_from_customer 7244 7244 0 0.00 0 5884 9 0 0
t$perf-norm:sp_pay_to_supplier 5924 5924 0 0.00 0 19425 12 0 0
t$perf-norm:sp_reserve_write_off 10194 10194 0 0.00 0 18148 11 0 0
t$perf-norm:sp_supplier_invoice 5491 5491 0 0.00 0 7129 13 0 0
t$perf-norm:sp_supplier_order 5590 5590 0 0.00 0 4741 14 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 878 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1794 41 0 0
t$perf-norm:srv_make_invnt_saldo 520 520 0 0.00 0 3205 16 0 0
t$perf-norm:srv_make_money_saldo 719 719 0 0.00 0 566 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 4 0 0
v_add_invoice_to_stock 10246 9956 290 2.83 0 7584 15 0 290
v_all_customers 38398 38398 0 0.00 0 19 1 0 0
v_all_suppliers 30610 30610 0 0.00 0 20 1 0 0
v_all_wares 41680 41680 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 9220 16 0 0
v_cancel_client_order 3508 3508 0 0.00 0 3376 5 0 0
v_cancel_customer_prepayment 1432 1432 0 0.00 0 1638 7 0 0
v_cancel_customer_reserve 1368 1368 0 0.00 0 266 3 0 0
v_cancel_payment_to_supplier 912 912 0 0.00 0 7128 12 0 0
v_cancel_supplier_invoice 782 750 32 4.09 0 2768 18 0 32
v_cancel_supplier_order 794 794 0 0.00 0 500 4 0 0
v_cancel_write_off 2056 2056 0 0.00 0 1609 15 0 0
v_max_id_clo_ord 5588 5588 0 0.00 0 1643 1 0 0
v_max_id_clo_res 959 959 0 0.00 0 4824 17 0 0
v_max_id_ord_sup 5479 5479 0 0.00 0 5806 8 0 0
v_max_non_paid_invoice 1686 1686 0 0.00 0 908 2 0 0
v_max_non_paid_realizn 3742 3742 0 0.00 0 703 2 0 0
v_min_id_clo_ord 5588 5588 0 0.00 0 6042 8 0 0
v_min_id_clo_res 959 959 0 0.00 0 5450 12 0 0
v_min_id_ord_sup 5479 5479 0 0.00 0 6934 10 0 0
v_min_non_paid_invoice 5924 5924 0 0.00 0 5951 11 0 0
v_min_non_paid_realizn 7244 7244 0 0.00 0 6075 12 0 0
v_our_firm 10286 10286 0 0.00 0 19 1 0 0
v_reserve_write_off 20388 20388 0 0.00 0 3759 4 0 0
x_lock_depdocs_on_canc_invoice 10795 10795 0 0.00 0 25316 109 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 8691 313 0 0
x_make_qstorno_1000_1200 5590 5590 0 0.00 2 93471 1319 0 0
x_make_qstorno_1200_2000 5491 5491 0 0.00 3 105743 3130 0 0
x_make_qstorno_2100_3300 471432 471432 0 0.00 0 93307 136 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-12-2020 10:27:52 to 02-12-2020 10:27:56.

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 5 8192000000 8796641392 8807174144 3168704 0.038 1 1 2 0 3266400 61840 35856
07:57:57 23110 8192000000 9724851152 10020814848 796692944 8.863 100 92 184 0 827114016 7840000 22581072
07:59:25 29074 8192000000 9774947488 10084671488 844387488 9.344 100 84 168 0 877388480 13125232 19875760
08:01:00 24162 8192000000 9825587376 10146238464 885610272 9.755 100 90 180 0 930715600 24148720 20956608
08:02:27 14634 8192000000 9865772688 10199621632 921867120 10.114 100 80 160 0 984244736 44640256 17737360
08:03:47 7473 8192000000 9916674528 10261737472 963197184 10.520 100 77 154 2 1028859888 49091584 16571120
08:05:00 5118 8192000000 10004899008 10359222272 1003285360 10.910 100 83 164 2 1105377136 85935328 16156448
08:06:06 1864 8192000000 9995538144 10353029120 1026509872 11.135 100 89 175 2 1101413776 56720192 18183712
08:07:10 2011 8192000000 10040434704 10406252544 1050478016 11.365 100 91 182 0 1150941744 82188464 18275264
08:08:13 1704 8192000000 10038513232 10411646976 1063410464 11.489 100 87 171 1 1152402736 71097376 17894896
08:09:15 4163 8192000000 9974104928 10368020480 1067144400 11.525 100 38 76 3 1099481280 23445456 8891424
08:10:19 6628 8192000000 9998049600 10396999680 1079864256 11.646 100 40 78 2 1120572576 31817264 8891056
08:11:28 3693 8192000000 9994214320 10388344832 1085398592 11.699 100 43 84 0 1113364368 18770704 9195072
08:12:32 23347 8192000000 9995046128 10405785600 1089802736 11.741 100 29 55 2 1121039424 25256864 5979824
08:13:59 6434 8192000000 10023904048 10422816768 1098965664 11.828 100 24 48 1 1144077584 40489504 4622416
08:15:07 9228 8192000000 10007124416 10414272512 1093846128 11.779 99 40 82 3 1122701920 19419440 9436352
08:16:20 45441 8192000000 10000018928 10418376704 1080732704 11.654 98 25 50 1 1105060016 18352448 5974864
08:18:06 6472 8192000000 9987893568 10385481728 1049537728 11.356 98 42 85 1 1080306672 22067968 8700976
08:19:14 4893 8192000000 9953108032 10335793152 1020441088 11.076 97 36 72 3 1049445696 21280240 7724368
08:20:20 8605 8192000000 9959146032 10351247360 1001942944 10.897 96 40 80 1 1025749040 15225680 8580416
08:21:34 14441 8192000000 9934058272 10335735808 963035360 10.519 94 33 65 3 992420736 22214752 7170624
08:22:49 5618 8192000000 9951976448 10343051264 1032328752 11.191 100 44 88 1 1066371824 23858160 10184912
08:23:56 6868 8192000000 9962775136 10352668672 1041245120 11.277 100 51 98 5 1067977360 14931776 11800464
08:25:05 79206 8192000000 9973841296 10381242368 1061087856 11.467 100 32 62 1 1093835440 26291136 6456448
08:27:28 19071 8192000000 10015907168 10410127360 1091905008 11.761 100 36 70 1 1119433024 19828320 7699696
08:28:53 11240 8192000000 10063780192 10466000896 1113321088 11.964 100 52 105 1 1166979376 42509728 11148560
08:30:17 13654 8192000000 10127336960 10530152448 1129334896 12.115 100 50 99 1 1238552800 97912880 11305024
08:31:38 12977 8192000000 10177980992 10585579520 1148832000 12.299 100 64 126 2 1291635520 128272448 14531072
08:32:56 11159 8192000000 10195634528 10616717312 1156047424 12.366 100 52 104 1 1303812416 136196304 11568688
08:34:12 11148 8192000000 10225887904 10642243584 1165659728 12.456 100 51 100 2 1340608656 163995472 10953456
08:35:28 7453 8192000000 10293879792 10705616896 1176193232 12.555 100 78 155 2 1381968880 188549008 17226640
08:36:41 13157 8192000000 10290766800 10707013632 1162675728 12.428 99 53 106 1 1397984352 223117392 12191232
08:37:59 2709 8192000000 10315135904 10724835328 1173017584 12.525 100 67 130 1 1430093840 241169232 15907024
08:39:10 6076 8192000000 10319006848 10728329216 1168786448 12.485 100 69 136 0 1420799296 235806800 16206048
08:40:21 10337 8192000000 10308624768 10728878080 1143581680 12.249 99 43 86 0 1400569376 245785888 11201808
08:41:43 5948 8192000000 10323618000 10737684480 1161024000 12.413 100 54 106 1 1425414992 251647840 12743152
08:42:56 3073 8192000000 10305872992 10726240256 1158169856 12.386 100 68 136 1 1407458176 233230304 16058016
08:44:03 6682 8192000000 10331166736 10757369856 1149134304 12.301 100 68 136 1 1432767104 267808240 15824560
08:45:13 5707 8192000000 10363479408 10797539328 1156285024 12.368 100 43 87 1 1456808032 289332160 11190848
08:46:26 6766 8192000000 10399406208 10821447680 1142065584 12.235 99 49 98 0 1477943120 324028304 11849232
08:47:36 12317 8192000000 10381937472 10856079360 1145752656 12.270 100 32 64 0 1505650064 351384112 8513296
08:49:10 12613 8192000000 10325483328 10785038336 1130633008 12.127 100 35 70 1 1452027696 312284752 9109936
08:50:35 12999 8192000000 10217897600 10741194752 1083897264 11.685 97 18 36 0 1317611792 228653088 5061440
08:51:50 24191 8192000000 10034800384 10450350080 1099724304 11.835 99 36 70 3 1145414992 36999792 8690896
08:53:15 61877 8192000000 10036321376 10474438656 1056254432 11.421 96 18 34 2 1110852816 50644112 3954272
08:56:36 108758 8192000000 10096280896 10541080576 1099088544 11.829 99 14 28 2 1154921760 52924992 2908224
08:59:26 83439 8192000000 10036918128 10453237760 1096069904 11.800 97 56 105 2 1134186880 26597184 11519792
09:01:54 58048 8192000000 10072477968 10499084288 1075164720 11.601 96 20 40 2 1132925200 53235968 4524512
09:04:24 59682 8192000000 10094279808 10543861760 1116014400 11.989 99 38 74 0 1194442416 70365216 8062800
09:06:30 57029 8192000000 10095792560 10538467328 1129342336 12.115 100 16 32 1 1199417216 66327360 3747520
09:08:52 56450 8192000000 10088792560 10563796992 1109128288 11.924 98 13 26 1 1190002800 77830912 3043600
09:10:59 54558 8192000000 10072530800 10512642048 1120453696 12.031 99 15 30 0 1181239280 57560656 3224928
09:12:56 59149 8192000000 10118010288 10574016512 1119332000 12.021 98 19 38 1 1195214272 71368144 4514128
09:15:08 53302 8192000000 10098300048 10521366528 1143887312 12.252 100 15 30 1 1186688208 39918640 2882256
09:17:03 60885 8192000000 10116349920 10538352640 1129634016 12.118 98 36 73 0 1200388128 63606448 7147664
09:19:10 137691 8192000000 10078934224 10545844224 1120810816 12.035 97 18 36 0 1173516752 48944736 3761200
09:22:56 25725 8192000000 10065138752 10507317248 1116783568 11.997 97 28 56 1 1149771200 26690144 6297488
09:24:30 77134 8192000000 10079041424 10537889792 1120806560 12.035 98 11 22 0 1163875168 40718336 2350272
09:26:54 54623 8192000000 10085022160 10544046080 1138486080 12.201 99 11 20 0 1189323152 49054816 1782256
09:28:56 70811 8192000000 10108492400 10549366784 1106863232 11.903 95 16 31 1 1144981040 34707184 3410624
09:31:08 27239 8192000000 10155547264 10594410496 1135703408 12.175 98 27 54 2 1246104208 104141552 6259248
09:32:44 35583 8192000000 10169679136 10604191744 1122191408 12.048 97 23 45 1 1258755248 131376720 5187120
09:34:22 98306 8192000000 10193078944 10651062272 1142443216 12.239 99 18 36 2 1265797136 119376224 3977696
09:37:03 49475 8192000000 10096338288 10547466240 1072845120 11.579 95 27 54 3 1144563696 66169488 5549088
09:39:12 76851 8192000000 10069418960 10500743168 1078555936 11.634 95 21 43 1 1122835760 40296224 3983600
09:41:31 82854 8192000000 10035972656 10486861824 1062920032 11.484 95 26 53 1 1129047168 60720432 5406704
09:43:55 47469 8192000000 10055607840 10498859008 1112462416 11.956 99 15 30 1 1156297648 40502416 3332816
09:45:48 79147 8192000000 10107286160 10588319744 1095842032 11.798 97 6 12 0 1163911056 66741920 1327104
09:48:11 55240 8192000000 10113663168 10550054912 1111575440 11.947 99 28 56 2 1214092208 96059216 6457552
09:50:06 65570 8192000000 10092011056 10545836032 1102142048 11.858 99 24 48 1 1187274320 79523360 5608912
09:52:13 40857 8192000000 10131067328 10557726720 1110633584 11.938 99 42 84 0 1200357072 80485936 9237552
09:54:00 37336 8192000000 10098624032 10525552640 1124104752 12.066 100 19 38 1 1181952112 53830528 4016832
09:55:43 77340 8192000000 10082747744 10530979840 1115265280 11.982 99 9 18 0 1150544448 33601552 1677616
09:58:04 62575 8192000000 10101756512 10584809472 1136189040 12.180 100 21 42 1 1219853328 79415856 4248432
10:02:25 59822 8192000000 10084925936 10520080384 1087586544 11.720 95 21 43 1 1119677440 27747536 4343360
10:04:32 32669 8192000000 10096071008 10562801664 1112738240 11.958 98 45 88 2 1171400656 48428432 10233984
10:06:05 68784 8192000000 10079629568 10543652864 1138792608 12.204 100 25 50 0 1179606864 35849440 4964816
10:08:29 44177 8192000000 10070698336 10500726784 1070881904 11.561 94 20 40 1 1116080288 40796352 4402032
10:10:14 27033 8192000000 10111864224 10529828864 1137607968 12.193 100 29 58 1 1177399648 33715168 6076512
10:11:41 74247 8192000000 10084304144 10558554112 1119384192 12.021 98 11 22 2 1189605504 68045984 2175328
10:14:00 62752 8192000000 10068244912 10508005376 1127993200 12.102 99 18 34 1 1155840928 24022704 3825024
10:16:29 54965 8192000000 10042783216 10493505536 1116321568 11.992 98 13 26 0 1144821584 25908144 2591872
10:18:25 42106 8192000000 10070291344 10524090368 1128300032 12.105 99 8 16 0 1166028144 36332816 1395296
10:20:07 56950 8192000000 10059690016 10538082304 1121245568 12.039 99 11 22 0 1171648368 48020976 2381824
10:22:06 43926 8192000000 10104937936 10539380736 1102369728 11.860 97 29 58 0 1164072096 55587936 6114432
10:23:51 39766 8192000000 10087951104 10546061312 1109905616 11.932 98 24 48 1 1203269216 87568304 5795296
10:25:37 58815 8192000000 10074297168 10548297728 1090479760 11.747 97 15 30 0 1155016960 61576592 2960608

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 02-12-2020 10:27:58 to 02-12-2020 10:28:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 145 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-12-2020 10:28:13 to 02-12-2020 10:28:15.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          237805
MON$OLDEST_ACTIVE               238922
MON$OLDEST_SNAPSHOT             238922
MON$NEXT_TRANSACTION            238922
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                       2071392
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             803
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2287 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2287 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2287 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.2287 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Wed Dec  2 10:26:59 2020
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
:15: 
:16: mscs-test-161.budzdorov.ru    Wed Dec  2 10:26:59 2020
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Wed Dec  2 10:27:03 2020
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
--- end of diff output ---

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

02.12.20 10:28:16. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2020-12-02 10:28:19 No severe PSQL-related problems occured
02.12.20 10:28:20. End of report.