Skip to content

Instantly share code, notes, and snippets.

@leejo

leejo/dnytp5.21.8

Created January 21, 2015 11:21
Show Gist options
  • Save leejo/ed3497e10821b832e47d to your computer and use it in GitHub Desktop.
Save leejo/ed3497e10821b832e47d to your computer and use it in GitHub Desktop.
Download ZIP
cpanm log output with Devel::NYTProf (perl 5.21.8)
Raw
dnytp5.21.8
cpanm (App::cpanminus) 1.7001 on perl 5.021008 built for darwin-2level
Work directory is /Users/leejohnson/.cpanm/work/1421839145.42553
You have make /usr/bin/make
You have /opt/local/bin/wget
You have /usr/bin/tar: bsdtar 2.8.3 - libarchive 2.8.3
You have /usr/bin/unzip
Searching Devel::NYTProf on cpanmetadb ...
--> Working on Devel::NYTProf
Fetching http://www.cpan.org/authors/id/T/TI/TIMB/Devel-NYTProf-5.06.tar.gz
-> OK
Unpacking Devel-NYTProf-5.06.tar.gz
Entering Devel-NYTProf-5.06
Checking configure dependencies from META.json
Configuring Devel-NYTProf-5.06
Running Makefile.PL
Looking for header files and functions...
In file included from /usr/include/zlib.h:34:
In file included from /usr/include/zconf.h:380:
In file included from /usr/include/unistd.h:72:
In file included from /usr/include/sys/unistd.h:181:
In file included from /usr/include/Availability.h:151:
/usr/include/AvailabilityInternal.h:5614:10: error: unterminated conditional directive
#if __MAC_OS_X_VERSION_MIN_REQUIRED >= __MAC_10_7
^
/usr/include/AvailabilityInternal.h:5583:10: error: unterminated conditional directive
#if __MAC_OS_X_VERSION_MIN_REQUIRED >= __MAC_10_6
^
/usr/include/AvailabilityInternal.h:5556:10: error: unterminated conditional directive
#if __MAC_OS_X_VERSION_MIN_REQUIRED >= __MAC_10_5
^
/usr/include/AvailabilityInternal.h:5533:10: error: unterminated conditional directive
#if __MAC_OS_X_VERSION_MIN_REQUIRED >= __MAC_10_4
^
/usr/include/AvailabilityInternal.h:5514:10: error: unterminated conditional directive
#if __MAC_OS_X_VERSION_MIN_REQUIRED >= __MAC_10_3
^
/usr/include/AvailabilityInternal.h:5499:10: error: unterminated conditional directive
#if __MAC_OS_X_VERSION_MIN_REQUIRED >= __MAC_10_2
^
/usr/include/AvailabilityInternal.h:5488:10: error: unterminated conditional directive
#if __MAC_OS_X_VERSION_MIN_REQUIRED >= __MAC_10_1
^
/usr/include/AvailabilityInternal.h:58:2: error: unterminated conditional directive
#ifdef __IPHONE_OS_VERSION_MIN_REQUIRED
^
/usr/include/AvailabilityInternal.h:30:2: error: unterminated conditional directive
#ifndef __AVAILABILITY_INTERNAL__
^
In file included from /usr/include/zlib.h:34:
In file included from /usr/include/zconf.h:380:
In file included from /usr/include/unistd.h:72:
In file included from /usr/include/sys/unistd.h:181:
/usr/include/Availability.h:197:2: error: #else without #if
#else
^
/usr/include/Availability.h:200:2: error: #endif without #if
#endif
^
/usr/include/Availability.h:206:2: error: #endif without #if
#endif /* __AVAILABILITY__ */
^
In file included from /usr/include/zlib.h:34:
In file included from /usr/include/zconf.h:380:
In file included from /usr/include/unistd.h:72:
In file included from /usr/include/sys/unistd.h:199:
/usr/include/Availability.h:197:2: error: #else without #if
#else
^
/usr/include/Availability.h:200:2: error: #endif without #if
#endif
^
/usr/include/Availability.h:206:2: error: #endif without #if
#endif /* __AVAILABILITY__ */
^
In file included from /usr/include/zlib.h:34:
In file included from /usr/include/zconf.h:380:
In file included from /usr/include/unistd.h:73:
/usr/include/Availability.h:197:2: error: #else without #if
#else
^
/usr/include/Availability.h:200:2: error: #endif without #if
#endif
^
/usr/include/Availability.h:206:2: error: #endif without #if
#endif /* __AVAILABILITY__ */
^
In file included from /usr/include/zlib.h:34:
In file included from /usr/include/zconf.h:380:
In file included from /usr/include/unistd.h:653:
In file included from /usr/include/gethostuuid.h:34:
/usr/include/Availability.h:197:2: error: #else without #if
#else
^
fatal error: too many errors emitted, stopping now [-ferror-limit=]
20 errors generated.
Found deflateInit2 in zlib.h
In file included from /usr/include/mach/mach_time.h:32:
In file included from /usr/include/mach/mach_types.h:91:
In file included from /usr/include/mach/task_info.h:73:
In file included from /usr/include/Availability.h:151:
/usr/include/AvailabilityInternal.h:5614:10: error: unterminated conditional directive
#if __MAC_OS_X_VERSION_MIN_REQUIRED >= __MAC_10_7
^
/usr/include/AvailabilityInternal.h:5583:10: error: unterminated conditional directive
#if __MAC_OS_X_VERSION_MIN_REQUIRED >= __MAC_10_6
^
/usr/include/AvailabilityInternal.h:5556:10: error: unterminated conditional directive
#if __MAC_OS_X_VERSION_MIN_REQUIRED >= __MAC_10_5
^
/usr/include/AvailabilityInternal.h:5533:10: error: unterminated conditional directive
#if __MAC_OS_X_VERSION_MIN_REQUIRED >= __MAC_10_4
^
/usr/include/AvailabilityInternal.h:5514:10: error: unterminated conditional directive
#if __MAC_OS_X_VERSION_MIN_REQUIRED >= __MAC_10_3
^
/usr/include/AvailabilityInternal.h:5499:10: error: unterminated conditional directive
#if __MAC_OS_X_VERSION_MIN_REQUIRED >= __MAC_10_2
^
/usr/include/AvailabilityInternal.h:5488:10: error: unterminated conditional directive
#if __MAC_OS_X_VERSION_MIN_REQUIRED >= __MAC_10_1
^
/usr/include/AvailabilityInternal.h:58:2: error: unterminated conditional directive
#ifdef __IPHONE_OS_VERSION_MIN_REQUIRED
^
/usr/include/AvailabilityInternal.h:30:2: error: unterminated conditional directive
#ifndef __AVAILABILITY_INTERNAL__
^
In file included from /usr/include/mach/mach_time.h:32:
In file included from /usr/include/mach/mach_types.h:91:
In file included from /usr/include/mach/task_info.h:73:
/usr/include/Availability.h:197:2: error: #else without #if
#else
^
/usr/include/Availability.h:200:2: error: #endif without #if
#endif
^
/usr/include/Availability.h:206:2: error: #endif without #if
#endif /* __AVAILABILITY__ */
^
12 errors generated.
Found mach_absolute_time in mach_time.h
Your perl was compiled with gcc (version 4.2.1 Compatible Apple LLVM 6.0 (clang-600.0.56)), okay.
Checking if your kit is complete...
Looks good
Generating a Unix-style Makefile
Writing Makefile for Devel::NYTProf
Writing MYMETA.yml and MYMETA.json
-> OK
Checking dependencies from MYMETA.json ...
Checking if you have List::Util 0 ... Yes (1.41)
Checking if you have ExtUtils::MakeMaker 0 ... Yes (7.04)
Checking if you have Test::Differences 0.60 ... Yes (0.63)
Checking if you have JSON::Any 0 ... Yes (1.38)
Checking if you have XSLoader 0 ... Yes (0.20)
Checking if you have Getopt::Long 0 ... Yes (2.43)
Checking if you have Test::More 0.84 ... Yes (1.301001_097)
Building and testing Devel-NYTProf-5.06
cp lib/Devel/NYTProf/js/bg.png blib/lib/Devel/NYTProf/js/bg.png
cp lib/Devel/NYTProf/SubCallInfo.pm blib/lib/Devel/NYTProf/SubCallInfo.pm
cp lib/Devel/NYTProf.pm blib/lib/Devel/NYTProf.pm
cp lib/Devel/NYTProf/js/jit/gradient20.png blib/lib/Devel/NYTProf/js/jit/gradient20.png
cp lib/Devel/NYTProf/js/jquery-tablesorter-min.js blib/lib/Devel/NYTProf/js/jquery-tablesorter-min.js
cp lib/Devel/NYTProf/js/jit/jit.js blib/lib/Devel/NYTProf/js/jit/jit.js
cp lib/Devel/NYTProf/js/jit/gradient30.png blib/lib/Devel/NYTProf/js/jit/gradient30.png
cp lib/Devel/NYTProf/js/jit/gradient-cushion1.png blib/lib/Devel/NYTProf/js/jit/gradient-cushion1.png
cp lib/Devel/NYTProf/SubInfo.pm blib/lib/Devel/NYTProf/SubInfo.pm
cp lib/Devel/NYTProf/ReadStream.pm blib/lib/Devel/NYTProf/ReadStream.pm
cp lib/Devel/NYTProf/Data.pm blib/lib/Devel/NYTProf/Data.pm
cp lib/Devel/NYTProf/js/jit/Treemap.css blib/lib/Devel/NYTProf/js/jit/Treemap.css
cp lib/Devel/NYTProf/FileInfo.pm blib/lib/Devel/NYTProf/FileInfo.pm
cp lib/Devel/NYTProf/js/asc.png blib/lib/Devel/NYTProf/js/asc.png
cp lib/Devel/NYTProf/js/jit/gradient50.png blib/lib/Devel/NYTProf/js/jit/gradient50.png
cp lib/Devel/NYTProf/Util.pm blib/lib/Devel/NYTProf/Util.pm
cp lib/Devel/NYTProf/js/desc.png blib/lib/Devel/NYTProf/js/desc.png
cp lib/Devel/NYTProf/Test.pm blib/lib/Devel/NYTProf/Test.pm
cp lib/Devel/NYTProf/js/jquery-min.js blib/lib/Devel/NYTProf/js/jquery-min.js
cp lib/Devel/NYTProf/Core.pm blib/lib/Devel/NYTProf/Core.pm
cp lib/Devel/NYTProf/Constants.pm blib/lib/Devel/NYTProf/Constants.pm
cp lib/Devel/NYTProf/js/style-tablesorter.css blib/lib/Devel/NYTProf/js/style-tablesorter.css
cp MemoryProfiling.pod blib/lib/Devel/MemoryProfiling.pod
cp lib/Devel/NYTProf/js/jit/jit-yc.js blib/lib/Devel/NYTProf/js/jit/jit-yc.js
cp lib/Devel/NYTProf/Reader.pm blib/lib/Devel/NYTProf/Reader.pm
cp lib/Devel/NYTProf/Apache.pm blib/lib/Devel/NYTProf/Apache.pm
cp lib/Devel/NYTProf/js/jit/gradient40.png blib/lib/Devel/NYTProf/js/jit/gradient40.png
cp lib/Devel/NYTProf/FileHandle.pm blib/lib/Devel/NYTProf/FileHandle.pm
cp lib/Devel/NYTProf/js/jit/gradient.png blib/lib/Devel/NYTProf/js/jit/gradient.png
cp lib/Devel/NYTProf/Run.pm blib/lib/Devel/NYTProf/Run.pm
Running Mkbootstrap for Devel::NYTProf ()
chmod 644 "NYTProf.bs"
"/Users/leejohnson/perl5/perlbrew/perls/perl-5.21.8/bin/perl5.21.8" "/Users/leejohnson/perl5/perlbrew/perls/perl-5.21.8/lib/5.21.8/ExtUtils/xsubpp" -typemap "/Users/leejohnson/perl5/perlbrew/perls/perl-5.21.8/lib/5.21.8/ExtUtils/typemap" -typemap "typemap" FileHandle.xs > FileHandle.xsc && mv FileHandle.xsc FileHandle.c
cc -c -fno-common -DPERL_DARWIN -fno-strict-aliasing -pipe -fstack-protector -I/usr/local/include -I/opt/local/include -O3 -DVERSION=\"5.06\" -DXS_VERSION=\"5.06\" "-I/Users/leejohnson/perl5/perlbrew/perls/perl-5.21.8/lib/5.21.8/darwin-2level/CORE" -DHAS_ZLIB -DHAS_MACH_TIME -W -Wall -Wpointer-arith -Wbad-function-cast -Wno-comment -Wno-sign-compare -Wno-cast-qual -Wmissing-noreturn -Wno-unused-parameter FileHandle.c
"/Users/leejohnson/perl5/perlbrew/perls/perl-5.21.8/bin/perl5.21.8" "/Users/leejohnson/perl5/perlbrew/perls/perl-5.21.8/lib/5.21.8/ExtUtils/xsubpp" -typemap "/Users/leejohnson/perl5/perlbrew/perls/perl-5.21.8/lib/5.21.8/ExtUtils/typemap" -typemap "typemap" NYTProf.xs > NYTProf.xsc && mv NYTProf.xsc NYTProf.c
cc -c -fno-common -DPERL_DARWIN -fno-strict-aliasing -pipe -fstack-protector -I/usr/local/include -I/opt/local/include -O3 -DVERSION=\"5.06\" -DXS_VERSION=\"5.06\" "-I/Users/leejohnson/perl5/perlbrew/perls/perl-5.21.8/lib/5.21.8/darwin-2level/CORE" -DHAS_ZLIB -DHAS_MACH_TIME -W -Wall -Wpointer-arith -Wbad-function-cast -Wno-comment -Wno-sign-compare -Wno-cast-qual -Wmissing-noreturn -Wno-unused-parameter NYTProf.c
NYTProf.xs:1137:1: warning: unused function 'get_str_id' [-Wunused-function]
get_str_id(pTHX_ char* str, STRLEN len)
^
1 warning generated.
rm -f blib/arch/auto/Devel/NYTProf/NYTProf.bundle
LD_RUN_PATH="/usr/lib" env MACOSX_DEPLOYMENT_TARGET=10.3 cc -bundle -undefined dynamic_lookup -L/usr/local/lib -L/opt/local/lib -fstack-protector FileHandle.o NYTProf.o -o blib/arch/auto/Devel/NYTProf/NYTProf.bundle \
-lz \
chmod 755 blib/arch/auto/Devel/NYTProf/NYTProf.bundle
"/Users/leejohnson/perl5/perlbrew/perls/perl-5.21.8/bin/perl5.21.8" -MExtUtils::Command::MM -e 'cp_nonempty' -- NYTProf.bs blib/arch/auto/Devel/NYTProf/NYTProf.bs 644
cp blib/arch/auto/Devel/NYTProf/NYTProf.bundle blib/lib/Devel/auto/Devel/NYTProf/NYTProf.bundle
cp bin/nytprofcsv blib/script/nytprofcsv
"/Users/leejohnson/perl5/perlbrew/perls/perl-5.21.8/bin/perl5.21.8" -MExtUtils::MY -e 'MY->fixin(shift)' -- blib/script/nytprofcsv
cp bin/nytprofhtml blib/script/nytprofhtml
"/Users/leejohnson/perl5/perlbrew/perls/perl-5.21.8/bin/perl5.21.8" -MExtUtils::MY -e 'MY->fixin(shift)' -- blib/script/nytprofhtml
cp bin/nytprofmerge blib/script/nytprofmerge
"/Users/leejohnson/perl5/perlbrew/perls/perl-5.21.8/bin/perl5.21.8" -MExtUtils::MY -e 'MY->fixin(shift)' -- blib/script/nytprofmerge
cp bin/flamegraph.pl blib/script/flamegraph.pl
"/Users/leejohnson/perl5/perlbrew/perls/perl-5.21.8/bin/perl5.21.8" -MExtUtils::MY -e 'MY->fixin(shift)' -- blib/script/flamegraph.pl
cp bin/nytprofcalls blib/script/nytprofcalls
"/Users/leejohnson/perl5/perlbrew/perls/perl-5.21.8/bin/perl5.21.8" -MExtUtils::MY -e 'MY->fixin(shift)' -- blib/script/nytprofcalls
cp bin/nytprofcg blib/script/nytprofcg
"/Users/leejohnson/perl5/perlbrew/perls/perl-5.21.8/bin/perl5.21.8" -MExtUtils::MY -e 'MY->fixin(shift)' -- blib/script/nytprofcg
Manifying 5 pod documents
Manifying 9 pod documents
Running Mkbootstrap for Devel::NYTProf ()
chmod 644 "NYTProf.bs"
cp blib/arch/auto/Devel/NYTProf/NYTProf.bundle blib/lib/Devel/auto/Devel/NYTProf/NYTProf.bundle
PERL_DL_NONLAZY=1 "/Users/leejohnson/perl5/perlbrew/perls/perl-5.21.8/bin/perl5.21.8" "-MExtUtils::Command::MM" "-MTest::Harness" "-e" "undef *Test::Harness::Switches; test_harness(0, 'blib/lib', 'blib/arch')" t/*.t
# Testing Devel::NYTProf 5.06 started at Wed Jan 21 12:19:09 2015
# Compression: default level is 6, zlib version 1.2.8
# --- Perl 5.021008 Config on darwin-2level:
# d_gettimeod: define
# d_sysconf: define
t/00-load.t ............ ok
t/10-run.t ............. ok
t/22-readstream.t ...... ok
t/30-util.t ............ ok
t/31-env.t ............. ok
t/40-savesrc.t ......... ok
t/42-global.t .......... ok
t/44-model.t ........... ok
t/50-errno.t ........... ok
t/60-forkdepth.t ....... ok
t/68-hashline.t ........ skipped: Currently a developer-only test
t/70-subname.t ......... skipped: Sub::Name 0.04 required (0.06+ preferred)
t/71-moose.t ........... skipped: Moose 2.0 required
t/72-autodie.t ......... skipped: Currently a developer-only test
t/80-version.t ......... ok
t/90-pod.t ............. skipped: Test::Pod 1.00 required for testing POD
t/91-pod_coverage.t .... skipped: Test::Pod::Coverage 1.04 required for testing POD coverage
t/92-file_port.t ....... skipped: Test::Portability::Files required for testing filename portability. Can't locate Test/Portability/Files.pm
t/test01.t ............. ok
t/test02.t ............. ok
t/test03.t ............. ok
t/test05.t ............. ok
t/test06.t ............. ok
t/test07.t ............. ok
t/test08.t ............. ok
t/test09.t ............. ok
t/test10.t ............. ok
t/test11.t ............. ok
t/test12.t ............. ok
t/test13.t ............. ok
t/test14.t ............. ok
given is experimental at test16.p line 10.
when is experimental at test16.p line 11.
when is experimental at test16.p line 14.
given is experimental at test16.p line 10.
when is experimental at test16.p line 11.
when is experimental at test16.p line 14.
given is experimental at test16.p line 10.
when is experimental at test16.p line 11.
when is experimental at test16.p line 14.
given is experimental at test16.p line 10.
when is experimental at test16.p line 11.
when is experimental at test16.p line 14.
given is experimental at test16.p line 10.
when is experimental at test16.p line 11.
when is experimental at test16.p line 14.
given is experimental at test16.p line 10.
when is experimental at test16.p line 11.
when is experimental at test16.p line 14.
given is experimental at test16.p line 10.
when is experimental at test16.p line 11.
when is experimental at test16.p line 14.
given is experimental at test16.p line 10.
when is experimental at test16.p line 11.
when is experimental at test16.p line 14.
given is experimental at test16.p line 10.
when is experimental at test16.p line 11.
when is experimental at test16.p line 14.
given is experimental at test16.p line 10.
when is experimental at test16.p line 11.
when is experimental at test16.p line 14.
given is experimental at test16.p line 10.
when is experimental at test16.p line 11.
when is experimental at test16.p line 14.
given is experimental at test16.p line 10.
when is experimental at test16.p line 11.
when is experimental at test16.p line 14.
given is experimental at test16.p line 10.
when is experimental at test16.p line 11.
when is experimental at test16.p line 14.
given is experimental at test16.p line 10.
when is experimental at test16.p line 11.
when is experimental at test16.p line 14.
given is experimental at test16.p line 10.
when is experimental at test16.p line 11.
when is experimental at test16.p line 14.
given is experimental at test16.p line 10.
when is experimental at test16.p line 11.
when is experimental at test16.p line 14.
t/test16.t ............. ok
t/test17-goto.t ........ ok
t/test18-goto2.t ....... ok
t/test20-streval.t ..... ok
t/test21-streval3.t .... ok
t/test22-strevala.t .... ok
t/test23-strevall.t .... ok
t/test24-strevalc.t .... ok
t/test25-strevalb.t .... ok
t/test30-fork-0.t ...... ok
t/test40pmc.t .......... ok
t/test50-disable.t ..... ok
t/test51-enable.t ...... ok
t/test60-subname.t ..... ok
t/test61-submerge.t .... ok
# Failed test 'test62-subcaller1.rdt match generated profile data for blocks=1 calls=1 compress=0 leave=0 savesrc=1 slowops=2 start=init use_db_sub=1'
# at t/lib/NYTProfTest.pm line 443.
# Structures begin differing at:
# $got->[16] = 'fid_block_time 1 4 [ 0 1 ]'
# $expected->[16] = 'fid_block_time 1 4 [ 0 2 ]'
--- test62-subcaller1.rdt 2013-03-02 23:17:35.000000000 +0100
+++ test62-subcaller1.rdt_new 2015-01-21 12:20:25.000000000 +0100
@@ -14,11 +14,11 @@
attribute total_stmts_measured 0
attribute total_sub_calls 0
attribute xs_version 0
-fid_block_time 1 4 [ 0 2 ]
+fid_block_time 1 4 [ 0 1 ]
fid_block_time 1 6 [ 0 1 ]
fid_block_time 1 7 [ 0 1 ]
fid_block_time 1 8 [ 0 1 ]
-fid_block_time 1 11 [ 0 1 ]
+fid_block_time 1 11 [ 0 2 ]
fid_block_time 1 12 [ 0 1 ]
fid_block_time 1 13 [ 0 1 ]
fid_block_time 1 16 [ 0 1 ]
@@ -26,8 +26,7 @@
fid_block_time 1 21 [ 0 1 ]
fid_block_time 1 22 [ 0 2 ]
fid_block_time 1 25 [ 0 6 ]
-fid_block_time 1 26 [ 0 3 ]
-fid_block_time 1 27 [ 0 1 ]
+fid_block_time 1 27 [ 0 4 ]
fid_block_time 1 28 [ 0 3 ]
fid_block_time 1 33 [ 0 1 ]
fid_block_time 1 36 [ 0 2 ]
@@ -59,7 +58,7 @@
fid_fileinfo 1 call 17 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 main::sub1 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-fid_fileinfo 1 call 26 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
+fid_fileinfo 1 call 27 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 27 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 28 main::sub2 [ 6 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 33 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
@@ -69,11 +68,11 @@
fid_fileinfo 2 [ Devel/NYTProf/Test.pm 2 2 0 0 ]
fid_fileinfo 2 sub Devel::NYTProf::Test::example_sub 13-13
fid_fileinfo 3 [ Exporter.pm 3 2 0 0 ]
-fid_line_time 1 4 [ 0 2 ]
+fid_line_time 1 4 [ 0 1 ]
fid_line_time 1 6 [ 0 1 ]
fid_line_time 1 7 [ 0 1 ]
fid_line_time 1 8 [ 0 1 ]
-fid_line_time 1 11 [ 0 1 ]
+fid_line_time 1 11 [ 0 2 ]
fid_line_time 1 12 [ 0 1 ]
fid_line_time 1 13 [ 0 1 ]
fid_line_time 1 16 [ 0 1 ]
@@ -81,8 +80,7 @@
fid_line_time 1 21 [ 0 1 ]
fid_line_time 1 22 [ 0 2 ]
fid_line_time 1 25 [ 0 6 ]
-fid_line_time 1 26 [ 0 3 ]
-fid_line_time 1 27 [ 0 1 ]
+fid_line_time 1 27 [ 0 4 ]
fid_line_time 1 28 [ 0 3 ]
fid_line_time 1 33 [ 0 1 ]
fid_line_time 1 36 [ 0 2 ]
@@ -94,11 +92,11 @@
fid_line_time 2 9 [ 0 1 ]
fid_line_time 2 11 [ 0 1 ]
fid_line_time 2 15 [ 0 1 ]
-fid_sub_time 1 4 [ 0 2 ]
+fid_sub_time 1 4 [ 0 1 ]
fid_sub_time 1 6 [ 0 1 ]
fid_sub_time 1 7 [ 0 1 ]
fid_sub_time 1 8 [ 0 1 ]
-fid_sub_time 1 11 [ 0 1 ]
+fid_sub_time 1 11 [ 0 2 ]
fid_sub_time 1 12 [ 0 1 ]
fid_sub_time 1 13 [ 0 1 ]
fid_sub_time 1 16 [ 0 1 ]
@@ -106,8 +104,7 @@
fid_sub_time 1 21 [ 0 1 ]
fid_sub_time 1 22 [ 0 2 ]
fid_sub_time 1 25 [ 0 6 ]
-fid_sub_time 1 26 [ 0 3 ]
-fid_sub_time 1 27 [ 0 1 ]
+fid_sub_time 1 27 [ 0 4 ]
fid_sub_time 1 28 [ 0 3 ]
fid_sub_time 1 33 [ 0 1 ]
fid_sub_time 1 36 [ 0 2 ]
@@ -126,7 +123,7 @@
sub_subinfo Devel::NYTProf::Test::example_xsub [ 1:undef-undef calls 5 times 0 0 0 0 ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:17 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:22 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:26 [ 3 0 0 0 0 0 0 main::CORE:sort ]
+sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:27 [ 3 0 0 0 0 0 0 main::CORE:sort ]
sub_subinfo MyTie::FETCH [ 1:7-7 calls 1 times 0 0 0 0 ]
sub_subinfo MyTie::FETCH called_by 1:13 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo MyTie::STORE [ 1:8-8 calls 1 times 0 0 0 0 ]
# Failed test 'test62-subcaller1.rdt match generated profile data for blocks=1 calls=2 compress=1 leave=0 savesrc=1 slowops=2 start=init use_db_sub=1'
# at t/lib/NYTProfTest.pm line 443.
# Structures begin differing at:
# $got->[16] = 'fid_block_time 1 4 [ 0 1 ]'
# $expected->[16] = 'fid_block_time 1 4 [ 0 2 ]'
--- test62-subcaller1.rdt 2013-03-02 23:17:35.000000000 +0100
+++ test62-subcaller1.rdt_new 2015-01-21 12:20:25.000000000 +0100
@@ -14,11 +14,11 @@
attribute total_stmts_measured 0
attribute total_sub_calls 0
attribute xs_version 0
-fid_block_time 1 4 [ 0 2 ]
+fid_block_time 1 4 [ 0 1 ]
fid_block_time 1 6 [ 0 1 ]
fid_block_time 1 7 [ 0 1 ]
fid_block_time 1 8 [ 0 1 ]
-fid_block_time 1 11 [ 0 1 ]
+fid_block_time 1 11 [ 0 2 ]
fid_block_time 1 12 [ 0 1 ]
fid_block_time 1 13 [ 0 1 ]
fid_block_time 1 16 [ 0 1 ]
@@ -26,8 +26,7 @@
fid_block_time 1 21 [ 0 1 ]
fid_block_time 1 22 [ 0 2 ]
fid_block_time 1 25 [ 0 6 ]
-fid_block_time 1 26 [ 0 3 ]
-fid_block_time 1 27 [ 0 1 ]
+fid_block_time 1 27 [ 0 4 ]
fid_block_time 1 28 [ 0 3 ]
fid_block_time 1 33 [ 0 1 ]
fid_block_time 1 36 [ 0 2 ]
@@ -59,7 +58,7 @@
fid_fileinfo 1 call 17 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 main::sub1 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-fid_fileinfo 1 call 26 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
+fid_fileinfo 1 call 27 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 27 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 28 main::sub2 [ 6 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 33 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
@@ -69,11 +68,11 @@
fid_fileinfo 2 [ Devel/NYTProf/Test.pm 2 2 0 0 ]
fid_fileinfo 2 sub Devel::NYTProf::Test::example_sub 13-13
fid_fileinfo 3 [ Exporter.pm 3 2 0 0 ]
-fid_line_time 1 4 [ 0 2 ]
+fid_line_time 1 4 [ 0 1 ]
fid_line_time 1 6 [ 0 1 ]
fid_line_time 1 7 [ 0 1 ]
fid_line_time 1 8 [ 0 1 ]
-fid_line_time 1 11 [ 0 1 ]
+fid_line_time 1 11 [ 0 2 ]
fid_line_time 1 12 [ 0 1 ]
fid_line_time 1 13 [ 0 1 ]
fid_line_time 1 16 [ 0 1 ]
@@ -81,8 +80,7 @@
fid_line_time 1 21 [ 0 1 ]
fid_line_time 1 22 [ 0 2 ]
fid_line_time 1 25 [ 0 6 ]
-fid_line_time 1 26 [ 0 3 ]
-fid_line_time 1 27 [ 0 1 ]
+fid_line_time 1 27 [ 0 4 ]
fid_line_time 1 28 [ 0 3 ]
fid_line_time 1 33 [ 0 1 ]
fid_line_time 1 36 [ 0 2 ]
@@ -94,11 +92,11 @@
fid_line_time 2 9 [ 0 1 ]
fid_line_time 2 11 [ 0 1 ]
fid_line_time 2 15 [ 0 1 ]
-fid_sub_time 1 4 [ 0 2 ]
+fid_sub_time 1 4 [ 0 1 ]
fid_sub_time 1 6 [ 0 1 ]
fid_sub_time 1 7 [ 0 1 ]
fid_sub_time 1 8 [ 0 1 ]
-fid_sub_time 1 11 [ 0 1 ]
+fid_sub_time 1 11 [ 0 2 ]
fid_sub_time 1 12 [ 0 1 ]
fid_sub_time 1 13 [ 0 1 ]
fid_sub_time 1 16 [ 0 1 ]
@@ -106,8 +104,7 @@
fid_sub_time 1 21 [ 0 1 ]
fid_sub_time 1 22 [ 0 2 ]
fid_sub_time 1 25 [ 0 6 ]
-fid_sub_time 1 26 [ 0 3 ]
-fid_sub_time 1 27 [ 0 1 ]
+fid_sub_time 1 27 [ 0 4 ]
fid_sub_time 1 28 [ 0 3 ]
fid_sub_time 1 33 [ 0 1 ]
fid_sub_time 1 36 [ 0 2 ]
@@ -126,7 +123,7 @@
sub_subinfo Devel::NYTProf::Test::example_xsub [ 1:undef-undef calls 5 times 0 0 0 0 ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:17 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:22 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:26 [ 3 0 0 0 0 0 0 main::CORE:sort ]
+sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:27 [ 3 0 0 0 0 0 0 main::CORE:sort ]
sub_subinfo MyTie::FETCH [ 1:7-7 calls 1 times 0 0 0 0 ]
sub_subinfo MyTie::FETCH called_by 1:13 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo MyTie::STORE [ 1:8-8 calls 1 times 0 0 0 0 ]
# Failed test 'test62-subcaller1.rdt match generated profile data for blocks=1 calls=2 compress=1 leave=1 savesrc=1 slowops=2 start=init use_db_sub=0'
# at t/lib/NYTProfTest.pm line 443.
# Structures begin differing at:
# $got->[16] = 'fid_block_time 1 4 [ 0 1 ]'
# $expected->[16] = 'fid_block_time 1 4 [ 0 2 ]'
--- test62-subcaller1.rdt 2013-03-02 23:17:35.000000000 +0100
+++ test62-subcaller1.rdt_new 2015-01-21 12:20:25.000000000 +0100
@@ -14,11 +14,11 @@
attribute total_stmts_measured 0
attribute total_sub_calls 0
attribute xs_version 0
-fid_block_time 1 4 [ 0 2 ]
+fid_block_time 1 4 [ 0 1 ]
fid_block_time 1 6 [ 0 1 ]
fid_block_time 1 7 [ 0 1 ]
fid_block_time 1 8 [ 0 1 ]
-fid_block_time 1 11 [ 0 1 ]
+fid_block_time 1 11 [ 0 2 ]
fid_block_time 1 12 [ 0 1 ]
fid_block_time 1 13 [ 0 1 ]
fid_block_time 1 16 [ 0 1 ]
@@ -26,8 +26,7 @@
fid_block_time 1 21 [ 0 1 ]
fid_block_time 1 22 [ 0 2 ]
fid_block_time 1 25 [ 0 6 ]
-fid_block_time 1 26 [ 0 3 ]
-fid_block_time 1 27 [ 0 1 ]
+fid_block_time 1 27 [ 0 4 ]
fid_block_time 1 28 [ 0 3 ]
fid_block_time 1 33 [ 0 1 ]
fid_block_time 1 36 [ 0 2 ]
@@ -59,7 +58,7 @@
fid_fileinfo 1 call 17 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 main::sub1 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-fid_fileinfo 1 call 26 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
+fid_fileinfo 1 call 27 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 27 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 28 main::sub2 [ 6 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 33 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
@@ -69,11 +68,11 @@
fid_fileinfo 2 [ Devel/NYTProf/Test.pm 2 2 0 0 ]
fid_fileinfo 2 sub Devel::NYTProf::Test::example_sub 13-13
fid_fileinfo 3 [ Exporter.pm 3 2 0 0 ]
-fid_line_time 1 4 [ 0 2 ]
+fid_line_time 1 4 [ 0 1 ]
fid_line_time 1 6 [ 0 1 ]
fid_line_time 1 7 [ 0 1 ]
fid_line_time 1 8 [ 0 1 ]
-fid_line_time 1 11 [ 0 1 ]
+fid_line_time 1 11 [ 0 2 ]
fid_line_time 1 12 [ 0 1 ]
fid_line_time 1 13 [ 0 1 ]
fid_line_time 1 16 [ 0 1 ]
@@ -81,8 +80,7 @@
fid_line_time 1 21 [ 0 1 ]
fid_line_time 1 22 [ 0 2 ]
fid_line_time 1 25 [ 0 6 ]
-fid_line_time 1 26 [ 0 3 ]
-fid_line_time 1 27 [ 0 1 ]
+fid_line_time 1 27 [ 0 4 ]
fid_line_time 1 28 [ 0 3 ]
fid_line_time 1 33 [ 0 1 ]
fid_line_time 1 36 [ 0 2 ]
@@ -94,11 +92,11 @@
fid_line_time 2 9 [ 0 1 ]
fid_line_time 2 11 [ 0 1 ]
fid_line_time 2 15 [ 0 1 ]
-fid_sub_time 1 4 [ 0 2 ]
+fid_sub_time 1 4 [ 0 1 ]
fid_sub_time 1 6 [ 0 1 ]
fid_sub_time 1 7 [ 0 1 ]
fid_sub_time 1 8 [ 0 1 ]
-fid_sub_time 1 11 [ 0 1 ]
+fid_sub_time 1 11 [ 0 2 ]
fid_sub_time 1 12 [ 0 1 ]
fid_sub_time 1 13 [ 0 1 ]
fid_sub_time 1 16 [ 0 1 ]
@@ -106,8 +104,7 @@
fid_sub_time 1 21 [ 0 1 ]
fid_sub_time 1 22 [ 0 2 ]
fid_sub_time 1 25 [ 0 6 ]
-fid_sub_time 1 26 [ 0 3 ]
-fid_sub_time 1 27 [ 0 1 ]
+fid_sub_time 1 27 [ 0 4 ]
fid_sub_time 1 28 [ 0 3 ]
fid_sub_time 1 33 [ 0 1 ]
fid_sub_time 1 36 [ 0 2 ]
@@ -126,7 +123,7 @@
sub_subinfo Devel::NYTProf::Test::example_xsub [ 1:undef-undef calls 5 times 0 0 0 0 ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:17 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:22 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:26 [ 3 0 0 0 0 0 0 main::CORE:sort ]
+sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:27 [ 3 0 0 0 0 0 0 main::CORE:sort ]
sub_subinfo MyTie::FETCH [ 1:7-7 calls 1 times 0 0 0 0 ]
sub_subinfo MyTie::FETCH called_by 1:13 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo MyTie::STORE [ 1:8-8 calls 1 times 0 0 0 0 ]
# Failed test 'test62-subcaller1.rdt match generated profile data for blocks=1 calls=1 compress=0 leave=1 savesrc=1 slowops=2 start=init use_db_sub=0'
# at t/lib/NYTProfTest.pm line 443.
# Structures begin differing at:
# $got->[16] = 'fid_block_time 1 4 [ 0 1 ]'
# $expected->[16] = 'fid_block_time 1 4 [ 0 2 ]'
--- test62-subcaller1.rdt 2013-03-02 23:17:35.000000000 +0100
+++ test62-subcaller1.rdt_new 2015-01-21 12:20:25.000000000 +0100
@@ -14,11 +14,11 @@
attribute total_stmts_measured 0
attribute total_sub_calls 0
attribute xs_version 0
-fid_block_time 1 4 [ 0 2 ]
+fid_block_time 1 4 [ 0 1 ]
fid_block_time 1 6 [ 0 1 ]
fid_block_time 1 7 [ 0 1 ]
fid_block_time 1 8 [ 0 1 ]
-fid_block_time 1 11 [ 0 1 ]
+fid_block_time 1 11 [ 0 2 ]
fid_block_time 1 12 [ 0 1 ]
fid_block_time 1 13 [ 0 1 ]
fid_block_time 1 16 [ 0 1 ]
@@ -26,8 +26,7 @@
fid_block_time 1 21 [ 0 1 ]
fid_block_time 1 22 [ 0 2 ]
fid_block_time 1 25 [ 0 6 ]
-fid_block_time 1 26 [ 0 3 ]
-fid_block_time 1 27 [ 0 1 ]
+fid_block_time 1 27 [ 0 4 ]
fid_block_time 1 28 [ 0 3 ]
fid_block_time 1 33 [ 0 1 ]
fid_block_time 1 36 [ 0 2 ]
@@ -59,7 +58,7 @@
fid_fileinfo 1 call 17 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 main::sub1 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-fid_fileinfo 1 call 26 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
+fid_fileinfo 1 call 27 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 27 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 28 main::sub2 [ 6 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 33 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
@@ -69,11 +68,11 @@
fid_fileinfo 2 [ Devel/NYTProf/Test.pm 2 2 0 0 ]
fid_fileinfo 2 sub Devel::NYTProf::Test::example_sub 13-13
fid_fileinfo 3 [ Exporter.pm 3 2 0 0 ]
-fid_line_time 1 4 [ 0 2 ]
+fid_line_time 1 4 [ 0 1 ]
fid_line_time 1 6 [ 0 1 ]
fid_line_time 1 7 [ 0 1 ]
fid_line_time 1 8 [ 0 1 ]
-fid_line_time 1 11 [ 0 1 ]
+fid_line_time 1 11 [ 0 2 ]
fid_line_time 1 12 [ 0 1 ]
fid_line_time 1 13 [ 0 1 ]
fid_line_time 1 16 [ 0 1 ]
@@ -81,8 +80,7 @@
fid_line_time 1 21 [ 0 1 ]
fid_line_time 1 22 [ 0 2 ]
fid_line_time 1 25 [ 0 6 ]
-fid_line_time 1 26 [ 0 3 ]
-fid_line_time 1 27 [ 0 1 ]
+fid_line_time 1 27 [ 0 4 ]
fid_line_time 1 28 [ 0 3 ]
fid_line_time 1 33 [ 0 1 ]
fid_line_time 1 36 [ 0 2 ]
@@ -94,11 +92,11 @@
fid_line_time 2 9 [ 0 1 ]
fid_line_time 2 11 [ 0 1 ]
fid_line_time 2 15 [ 0 1 ]
-fid_sub_time 1 4 [ 0 2 ]
+fid_sub_time 1 4 [ 0 1 ]
fid_sub_time 1 6 [ 0 1 ]
fid_sub_time 1 7 [ 0 1 ]
fid_sub_time 1 8 [ 0 1 ]
-fid_sub_time 1 11 [ 0 1 ]
+fid_sub_time 1 11 [ 0 2 ]
fid_sub_time 1 12 [ 0 1 ]
fid_sub_time 1 13 [ 0 1 ]
fid_sub_time 1 16 [ 0 1 ]
@@ -106,8 +104,7 @@
fid_sub_time 1 21 [ 0 1 ]
fid_sub_time 1 22 [ 0 2 ]
fid_sub_time 1 25 [ 0 6 ]
-fid_sub_time 1 26 [ 0 3 ]
-fid_sub_time 1 27 [ 0 1 ]
+fid_sub_time 1 27 [ 0 4 ]
fid_sub_time 1 28 [ 0 3 ]
fid_sub_time 1 33 [ 0 1 ]
fid_sub_time 1 36 [ 0 2 ]
@@ -126,7 +123,7 @@
sub_subinfo Devel::NYTProf::Test::example_xsub [ 1:undef-undef calls 5 times 0 0 0 0 ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:17 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:22 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:26 [ 3 0 0 0 0 0 0 main::CORE:sort ]
+sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:27 [ 3 0 0 0 0 0 0 main::CORE:sort ]
sub_subinfo MyTie::FETCH [ 1:7-7 calls 1 times 0 0 0 0 ]
sub_subinfo MyTie::FETCH called_by 1:13 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo MyTie::STORE [ 1:8-8 calls 1 times 0 0 0 0 ]
# Failed test 'test62-subcaller1.rdt match generated profile data for blocks=1 calls=1 compress=0 leave=0 savesrc=1 slowops=2 start=init use_db_sub=0'
# at t/lib/NYTProfTest.pm line 443.
# Structures begin differing at:
# $got->[16] = 'fid_block_time 1 4 [ 0 1 ]'
# $expected->[16] = 'fid_block_time 1 4 [ 0 2 ]'
--- test62-subcaller1.rdt 2013-03-02 23:17:35.000000000 +0100
+++ test62-subcaller1.rdt_new 2015-01-21 12:20:25.000000000 +0100
@@ -14,11 +14,11 @@
attribute total_stmts_measured 0
attribute total_sub_calls 0
attribute xs_version 0
-fid_block_time 1 4 [ 0 2 ]
+fid_block_time 1 4 [ 0 1 ]
fid_block_time 1 6 [ 0 1 ]
fid_block_time 1 7 [ 0 1 ]
fid_block_time 1 8 [ 0 1 ]
-fid_block_time 1 11 [ 0 1 ]
+fid_block_time 1 11 [ 0 2 ]
fid_block_time 1 12 [ 0 1 ]
fid_block_time 1 13 [ 0 1 ]
fid_block_time 1 16 [ 0 1 ]
@@ -26,8 +26,7 @@
fid_block_time 1 21 [ 0 1 ]
fid_block_time 1 22 [ 0 2 ]
fid_block_time 1 25 [ 0 6 ]
-fid_block_time 1 26 [ 0 3 ]
-fid_block_time 1 27 [ 0 1 ]
+fid_block_time 1 27 [ 0 4 ]
fid_block_time 1 28 [ 0 3 ]
fid_block_time 1 33 [ 0 1 ]
fid_block_time 1 36 [ 0 2 ]
@@ -59,7 +58,7 @@
fid_fileinfo 1 call 17 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 main::sub1 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-fid_fileinfo 1 call 26 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
+fid_fileinfo 1 call 27 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 27 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 28 main::sub2 [ 6 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 33 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
@@ -69,11 +68,11 @@
fid_fileinfo 2 [ Devel/NYTProf/Test.pm 2 2 0 0 ]
fid_fileinfo 2 sub Devel::NYTProf::Test::example_sub 13-13
fid_fileinfo 3 [ Exporter.pm 3 2 0 0 ]
-fid_line_time 1 4 [ 0 2 ]
+fid_line_time 1 4 [ 0 1 ]
fid_line_time 1 6 [ 0 1 ]
fid_line_time 1 7 [ 0 1 ]
fid_line_time 1 8 [ 0 1 ]
-fid_line_time 1 11 [ 0 1 ]
+fid_line_time 1 11 [ 0 2 ]
fid_line_time 1 12 [ 0 1 ]
fid_line_time 1 13 [ 0 1 ]
fid_line_time 1 16 [ 0 1 ]
@@ -81,8 +80,7 @@
fid_line_time 1 21 [ 0 1 ]
fid_line_time 1 22 [ 0 2 ]
fid_line_time 1 25 [ 0 6 ]
-fid_line_time 1 26 [ 0 3 ]
-fid_line_time 1 27 [ 0 1 ]
+fid_line_time 1 27 [ 0 4 ]
fid_line_time 1 28 [ 0 3 ]
fid_line_time 1 33 [ 0 1 ]
fid_line_time 1 36 [ 0 2 ]
@@ -94,11 +92,11 @@
fid_line_time 2 9 [ 0 1 ]
fid_line_time 2 11 [ 0 1 ]
fid_line_time 2 15 [ 0 1 ]
-fid_sub_time 1 4 [ 0 2 ]
+fid_sub_time 1 4 [ 0 1 ]
fid_sub_time 1 6 [ 0 1 ]
fid_sub_time 1 7 [ 0 1 ]
fid_sub_time 1 8 [ 0 1 ]
-fid_sub_time 1 11 [ 0 1 ]
+fid_sub_time 1 11 [ 0 2 ]
fid_sub_time 1 12 [ 0 1 ]
fid_sub_time 1 13 [ 0 1 ]
fid_sub_time 1 16 [ 0 1 ]
@@ -106,8 +104,7 @@
fid_sub_time 1 21 [ 0 1 ]
fid_sub_time 1 22 [ 0 2 ]
fid_sub_time 1 25 [ 0 6 ]
-fid_sub_time 1 26 [ 0 3 ]
-fid_sub_time 1 27 [ 0 1 ]
+fid_sub_time 1 27 [ 0 4 ]
fid_sub_time 1 28 [ 0 3 ]
fid_sub_time 1 33 [ 0 1 ]
fid_sub_time 1 36 [ 0 2 ]
@@ -126,7 +123,7 @@
sub_subinfo Devel::NYTProf::Test::example_xsub [ 1:undef-undef calls 5 times 0 0 0 0 ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:17 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:22 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:26 [ 3 0 0 0 0 0 0 main::CORE:sort ]
+sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:27 [ 3 0 0 0 0 0 0 main::CORE:sort ]
sub_subinfo MyTie::FETCH [ 1:7-7 calls 1 times 0 0 0 0 ]
sub_subinfo MyTie::FETCH called_by 1:13 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo MyTie::STORE [ 1:8-8 calls 1 times 0 0 0 0 ]
# Failed test 'test62-subcaller1.rdt match generated profile data for blocks=1 calls=0 compress=0 leave=1 savesrc=0 slowops=2 start=init use_db_sub=0'
# at t/lib/NYTProfTest.pm line 443.
# Structures begin differing at:
# $got->[16] = 'fid_block_time 1 4 [ 0 1 ]'
# $expected->[16] = 'fid_block_time 1 4 [ 0 2 ]'
--- test62-subcaller1.rdt 2013-03-02 23:17:35.000000000 +0100
+++ test62-subcaller1.rdt_new 2015-01-21 12:20:25.000000000 +0100
@@ -14,11 +14,11 @@
attribute total_stmts_measured 0
attribute total_sub_calls 0
attribute xs_version 0
-fid_block_time 1 4 [ 0 2 ]
+fid_block_time 1 4 [ 0 1 ]
fid_block_time 1 6 [ 0 1 ]
fid_block_time 1 7 [ 0 1 ]
fid_block_time 1 8 [ 0 1 ]
-fid_block_time 1 11 [ 0 1 ]
+fid_block_time 1 11 [ 0 2 ]
fid_block_time 1 12 [ 0 1 ]
fid_block_time 1 13 [ 0 1 ]
fid_block_time 1 16 [ 0 1 ]
@@ -26,8 +26,7 @@
fid_block_time 1 21 [ 0 1 ]
fid_block_time 1 22 [ 0 2 ]
fid_block_time 1 25 [ 0 6 ]
-fid_block_time 1 26 [ 0 3 ]
-fid_block_time 1 27 [ 0 1 ]
+fid_block_time 1 27 [ 0 4 ]
fid_block_time 1 28 [ 0 3 ]
fid_block_time 1 33 [ 0 1 ]
fid_block_time 1 36 [ 0 2 ]
@@ -59,7 +58,7 @@
fid_fileinfo 1 call 17 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 main::sub1 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-fid_fileinfo 1 call 26 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
+fid_fileinfo 1 call 27 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 27 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 28 main::sub2 [ 6 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 33 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
@@ -69,11 +68,11 @@
fid_fileinfo 2 [ Devel/NYTProf/Test.pm 2 2 0 0 ]
fid_fileinfo 2 sub Devel::NYTProf::Test::example_sub 13-13
fid_fileinfo 3 [ Exporter.pm 3 2 0 0 ]
-fid_line_time 1 4 [ 0 2 ]
+fid_line_time 1 4 [ 0 1 ]
fid_line_time 1 6 [ 0 1 ]
fid_line_time 1 7 [ 0 1 ]
fid_line_time 1 8 [ 0 1 ]
-fid_line_time 1 11 [ 0 1 ]
+fid_line_time 1 11 [ 0 2 ]
fid_line_time 1 12 [ 0 1 ]
fid_line_time 1 13 [ 0 1 ]
fid_line_time 1 16 [ 0 1 ]
@@ -81,8 +80,7 @@
fid_line_time 1 21 [ 0 1 ]
fid_line_time 1 22 [ 0 2 ]
fid_line_time 1 25 [ 0 6 ]
-fid_line_time 1 26 [ 0 3 ]
-fid_line_time 1 27 [ 0 1 ]
+fid_line_time 1 27 [ 0 4 ]
fid_line_time 1 28 [ 0 3 ]
fid_line_time 1 33 [ 0 1 ]
fid_line_time 1 36 [ 0 2 ]
@@ -94,11 +92,11 @@
fid_line_time 2 9 [ 0 1 ]
fid_line_time 2 11 [ 0 1 ]
fid_line_time 2 15 [ 0 1 ]
-fid_sub_time 1 4 [ 0 2 ]
+fid_sub_time 1 4 [ 0 1 ]
fid_sub_time 1 6 [ 0 1 ]
fid_sub_time 1 7 [ 0 1 ]
fid_sub_time 1 8 [ 0 1 ]
-fid_sub_time 1 11 [ 0 1 ]
+fid_sub_time 1 11 [ 0 2 ]
fid_sub_time 1 12 [ 0 1 ]
fid_sub_time 1 13 [ 0 1 ]
fid_sub_time 1 16 [ 0 1 ]
@@ -106,8 +104,7 @@
fid_sub_time 1 21 [ 0 1 ]
fid_sub_time 1 22 [ 0 2 ]
fid_sub_time 1 25 [ 0 6 ]
-fid_sub_time 1 26 [ 0 3 ]
-fid_sub_time 1 27 [ 0 1 ]
+fid_sub_time 1 27 [ 0 4 ]
fid_sub_time 1 28 [ 0 3 ]
fid_sub_time 1 33 [ 0 1 ]
fid_sub_time 1 36 [ 0 2 ]
@@ -126,7 +123,7 @@
sub_subinfo Devel::NYTProf::Test::example_xsub [ 1:undef-undef calls 5 times 0 0 0 0 ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:17 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:22 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:26 [ 3 0 0 0 0 0 0 main::CORE:sort ]
+sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:27 [ 3 0 0 0 0 0 0 main::CORE:sort ]
sub_subinfo MyTie::FETCH [ 1:7-7 calls 1 times 0 0 0 0 ]
sub_subinfo MyTie::FETCH called_by 1:13 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo MyTie::STORE [ 1:8-8 calls 1 times 0 0 0 0 ]
# Failed test 'test62-subcaller1.rdt match generated profile data for blocks=1 calls=2 compress=1 leave=0 savesrc=1 slowops=2 start=init use_db_sub=0'
# at t/lib/NYTProfTest.pm line 443.
# Structures begin differing at:
# $got->[16] = 'fid_block_time 1 4 [ 0 1 ]'
# $expected->[16] = 'fid_block_time 1 4 [ 0 2 ]'
--- test62-subcaller1.rdt 2013-03-02 23:17:35.000000000 +0100
+++ test62-subcaller1.rdt_new 2015-01-21 12:20:25.000000000 +0100
@@ -14,11 +14,11 @@
attribute total_stmts_measured 0
attribute total_sub_calls 0
attribute xs_version 0
-fid_block_time 1 4 [ 0 2 ]
+fid_block_time 1 4 [ 0 1 ]
fid_block_time 1 6 [ 0 1 ]
fid_block_time 1 7 [ 0 1 ]
fid_block_time 1 8 [ 0 1 ]
-fid_block_time 1 11 [ 0 1 ]
+fid_block_time 1 11 [ 0 2 ]
fid_block_time 1 12 [ 0 1 ]
fid_block_time 1 13 [ 0 1 ]
fid_block_time 1 16 [ 0 1 ]
@@ -26,8 +26,7 @@
fid_block_time 1 21 [ 0 1 ]
fid_block_time 1 22 [ 0 2 ]
fid_block_time 1 25 [ 0 6 ]
-fid_block_time 1 26 [ 0 3 ]
-fid_block_time 1 27 [ 0 1 ]
+fid_block_time 1 27 [ 0 4 ]
fid_block_time 1 28 [ 0 3 ]
fid_block_time 1 33 [ 0 1 ]
fid_block_time 1 36 [ 0 2 ]
@@ -59,7 +58,7 @@
fid_fileinfo 1 call 17 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 main::sub1 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-fid_fileinfo 1 call 26 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
+fid_fileinfo 1 call 27 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 27 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 28 main::sub2 [ 6 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 33 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
@@ -69,11 +68,11 @@
fid_fileinfo 2 [ Devel/NYTProf/Test.pm 2 2 0 0 ]
fid_fileinfo 2 sub Devel::NYTProf::Test::example_sub 13-13
fid_fileinfo 3 [ Exporter.pm 3 2 0 0 ]
-fid_line_time 1 4 [ 0 2 ]
+fid_line_time 1 4 [ 0 1 ]
fid_line_time 1 6 [ 0 1 ]
fid_line_time 1 7 [ 0 1 ]
fid_line_time 1 8 [ 0 1 ]
-fid_line_time 1 11 [ 0 1 ]
+fid_line_time 1 11 [ 0 2 ]
fid_line_time 1 12 [ 0 1 ]
fid_line_time 1 13 [ 0 1 ]
fid_line_time 1 16 [ 0 1 ]
@@ -81,8 +80,7 @@
fid_line_time 1 21 [ 0 1 ]
fid_line_time 1 22 [ 0 2 ]
fid_line_time 1 25 [ 0 6 ]
-fid_line_time 1 26 [ 0 3 ]
-fid_line_time 1 27 [ 0 1 ]
+fid_line_time 1 27 [ 0 4 ]
fid_line_time 1 28 [ 0 3 ]
fid_line_time 1 33 [ 0 1 ]
fid_line_time 1 36 [ 0 2 ]
@@ -94,11 +92,11 @@
fid_line_time 2 9 [ 0 1 ]
fid_line_time 2 11 [ 0 1 ]
fid_line_time 2 15 [ 0 1 ]
-fid_sub_time 1 4 [ 0 2 ]
+fid_sub_time 1 4 [ 0 1 ]
fid_sub_time 1 6 [ 0 1 ]
fid_sub_time 1 7 [ 0 1 ]
fid_sub_time 1 8 [ 0 1 ]
-fid_sub_time 1 11 [ 0 1 ]
+fid_sub_time 1 11 [ 0 2 ]
fid_sub_time 1 12 [ 0 1 ]
fid_sub_time 1 13 [ 0 1 ]
fid_sub_time 1 16 [ 0 1 ]
@@ -106,8 +104,7 @@
fid_sub_time 1 21 [ 0 1 ]
fid_sub_time 1 22 [ 0 2 ]
fid_sub_time 1 25 [ 0 6 ]
-fid_sub_time 1 26 [ 0 3 ]
-fid_sub_time 1 27 [ 0 1 ]
+fid_sub_time 1 27 [ 0 4 ]
fid_sub_time 1 28 [ 0 3 ]
fid_sub_time 1 33 [ 0 1 ]
fid_sub_time 1 36 [ 0 2 ]
@@ -126,7 +123,7 @@
sub_subinfo Devel::NYTProf::Test::example_xsub [ 1:undef-undef calls 5 times 0 0 0 0 ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:17 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:22 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:26 [ 3 0 0 0 0 0 0 main::CORE:sort ]
+sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:27 [ 3 0 0 0 0 0 0 main::CORE:sort ]
sub_subinfo MyTie::FETCH [ 1:7-7 calls 1 times 0 0 0 0 ]
sub_subinfo MyTie::FETCH called_by 1:13 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo MyTie::STORE [ 1:8-8 calls 1 times 0 0 0 0 ]
# Failed test 'test62-subcaller1.rdt match generated profile data for blocks=1 calls=1 compress=0 leave=1 savesrc=1 slowops=2 start=init use_db_sub=1'
# at t/lib/NYTProfTest.pm line 443.
# Structures begin differing at:
# $got->[16] = 'fid_block_time 1 4 [ 0 1 ]'
# $expected->[16] = 'fid_block_time 1 4 [ 0 2 ]'
--- test62-subcaller1.rdt 2013-03-02 23:17:35.000000000 +0100
+++ test62-subcaller1.rdt_new 2015-01-21 12:20:25.000000000 +0100
@@ -14,11 +14,11 @@
attribute total_stmts_measured 0
attribute total_sub_calls 0
attribute xs_version 0
-fid_block_time 1 4 [ 0 2 ]
+fid_block_time 1 4 [ 0 1 ]
fid_block_time 1 6 [ 0 1 ]
fid_block_time 1 7 [ 0 1 ]
fid_block_time 1 8 [ 0 1 ]
-fid_block_time 1 11 [ 0 1 ]
+fid_block_time 1 11 [ 0 2 ]
fid_block_time 1 12 [ 0 1 ]
fid_block_time 1 13 [ 0 1 ]
fid_block_time 1 16 [ 0 1 ]
@@ -26,8 +26,7 @@
fid_block_time 1 21 [ 0 1 ]
fid_block_time 1 22 [ 0 2 ]
fid_block_time 1 25 [ 0 6 ]
-fid_block_time 1 26 [ 0 3 ]
-fid_block_time 1 27 [ 0 1 ]
+fid_block_time 1 27 [ 0 4 ]
fid_block_time 1 28 [ 0 3 ]
fid_block_time 1 33 [ 0 1 ]
fid_block_time 1 36 [ 0 2 ]
@@ -59,7 +58,7 @@
fid_fileinfo 1 call 17 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 main::sub1 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-fid_fileinfo 1 call 26 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
+fid_fileinfo 1 call 27 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 27 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 28 main::sub2 [ 6 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 33 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
@@ -69,11 +68,11 @@
fid_fileinfo 2 [ Devel/NYTProf/Test.pm 2 2 0 0 ]
fid_fileinfo 2 sub Devel::NYTProf::Test::example_sub 13-13
fid_fileinfo 3 [ Exporter.pm 3 2 0 0 ]
-fid_line_time 1 4 [ 0 2 ]
+fid_line_time 1 4 [ 0 1 ]
fid_line_time 1 6 [ 0 1 ]
fid_line_time 1 7 [ 0 1 ]
fid_line_time 1 8 [ 0 1 ]
-fid_line_time 1 11 [ 0 1 ]
+fid_line_time 1 11 [ 0 2 ]
fid_line_time 1 12 [ 0 1 ]
fid_line_time 1 13 [ 0 1 ]
fid_line_time 1 16 [ 0 1 ]
@@ -81,8 +80,7 @@
fid_line_time 1 21 [ 0 1 ]
fid_line_time 1 22 [ 0 2 ]
fid_line_time 1 25 [ 0 6 ]
-fid_line_time 1 26 [ 0 3 ]
-fid_line_time 1 27 [ 0 1 ]
+fid_line_time 1 27 [ 0 4 ]
fid_line_time 1 28 [ 0 3 ]
fid_line_time 1 33 [ 0 1 ]
fid_line_time 1 36 [ 0 2 ]
@@ -94,11 +92,11 @@
fid_line_time 2 9 [ 0 1 ]
fid_line_time 2 11 [ 0 1 ]
fid_line_time 2 15 [ 0 1 ]
-fid_sub_time 1 4 [ 0 2 ]
+fid_sub_time 1 4 [ 0 1 ]
fid_sub_time 1 6 [ 0 1 ]
fid_sub_time 1 7 [ 0 1 ]
fid_sub_time 1 8 [ 0 1 ]
-fid_sub_time 1 11 [ 0 1 ]
+fid_sub_time 1 11 [ 0 2 ]
fid_sub_time 1 12 [ 0 1 ]
fid_sub_time 1 13 [ 0 1 ]
fid_sub_time 1 16 [ 0 1 ]
@@ -106,8 +104,7 @@
fid_sub_time 1 21 [ 0 1 ]
fid_sub_time 1 22 [ 0 2 ]
fid_sub_time 1 25 [ 0 6 ]
-fid_sub_time 1 26 [ 0 3 ]
-fid_sub_time 1 27 [ 0 1 ]
+fid_sub_time 1 27 [ 0 4 ]
fid_sub_time 1 28 [ 0 3 ]
fid_sub_time 1 33 [ 0 1 ]
fid_sub_time 1 36 [ 0 2 ]
@@ -126,7 +123,7 @@
sub_subinfo Devel::NYTProf::Test::example_xsub [ 1:undef-undef calls 5 times 0 0 0 0 ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:17 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:22 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:26 [ 3 0 0 0 0 0 0 main::CORE:sort ]
+sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:27 [ 3 0 0 0 0 0 0 main::CORE:sort ]
sub_subinfo MyTie::FETCH [ 1:7-7 calls 1 times 0 0 0 0 ]
sub_subinfo MyTie::FETCH called_by 1:13 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo MyTie::STORE [ 1:8-8 calls 1 times 0 0 0 0 ]
# Failed test 'test62-subcaller1.rdt match generated profile data for blocks=1 calls=0 compress=0 leave=1 savesrc=0 slowops=2 start=init use_db_sub=1'
# at t/lib/NYTProfTest.pm line 443.
# Structures begin differing at:
# $got->[16] = 'fid_block_time 1 4 [ 0 1 ]'
# $expected->[16] = 'fid_block_time 1 4 [ 0 2 ]'
--- test62-subcaller1.rdt 2013-03-02 23:17:35.000000000 +0100
+++ test62-subcaller1.rdt_new 2015-01-21 12:20:25.000000000 +0100
@@ -14,11 +14,11 @@
attribute total_stmts_measured 0
attribute total_sub_calls 0
attribute xs_version 0
-fid_block_time 1 4 [ 0 2 ]
+fid_block_time 1 4 [ 0 1 ]
fid_block_time 1 6 [ 0 1 ]
fid_block_time 1 7 [ 0 1 ]
fid_block_time 1 8 [ 0 1 ]
-fid_block_time 1 11 [ 0 1 ]
+fid_block_time 1 11 [ 0 2 ]
fid_block_time 1 12 [ 0 1 ]
fid_block_time 1 13 [ 0 1 ]
fid_block_time 1 16 [ 0 1 ]
@@ -26,8 +26,7 @@
fid_block_time 1 21 [ 0 1 ]
fid_block_time 1 22 [ 0 2 ]
fid_block_time 1 25 [ 0 6 ]
-fid_block_time 1 26 [ 0 3 ]
-fid_block_time 1 27 [ 0 1 ]
+fid_block_time 1 27 [ 0 4 ]
fid_block_time 1 28 [ 0 3 ]
fid_block_time 1 33 [ 0 1 ]
fid_block_time 1 36 [ 0 2 ]
@@ -59,7 +58,7 @@
fid_fileinfo 1 call 17 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 main::sub1 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-fid_fileinfo 1 call 26 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
+fid_fileinfo 1 call 27 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 27 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 28 main::sub2 [ 6 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 33 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
@@ -69,11 +68,11 @@
fid_fileinfo 2 [ Devel/NYTProf/Test.pm 2 2 0 0 ]
fid_fileinfo 2 sub Devel::NYTProf::Test::example_sub 13-13
fid_fileinfo 3 [ Exporter.pm 3 2 0 0 ]
-fid_line_time 1 4 [ 0 2 ]
+fid_line_time 1 4 [ 0 1 ]
fid_line_time 1 6 [ 0 1 ]
fid_line_time 1 7 [ 0 1 ]
fid_line_time 1 8 [ 0 1 ]
-fid_line_time 1 11 [ 0 1 ]
+fid_line_time 1 11 [ 0 2 ]
fid_line_time 1 12 [ 0 1 ]
fid_line_time 1 13 [ 0 1 ]
fid_line_time 1 16 [ 0 1 ]
@@ -81,8 +80,7 @@
fid_line_time 1 21 [ 0 1 ]
fid_line_time 1 22 [ 0 2 ]
fid_line_time 1 25 [ 0 6 ]
-fid_line_time 1 26 [ 0 3 ]
-fid_line_time 1 27 [ 0 1 ]
+fid_line_time 1 27 [ 0 4 ]
fid_line_time 1 28 [ 0 3 ]
fid_line_time 1 33 [ 0 1 ]
fid_line_time 1 36 [ 0 2 ]
@@ -94,11 +92,11 @@
fid_line_time 2 9 [ 0 1 ]
fid_line_time 2 11 [ 0 1 ]
fid_line_time 2 15 [ 0 1 ]
-fid_sub_time 1 4 [ 0 2 ]
+fid_sub_time 1 4 [ 0 1 ]
fid_sub_time 1 6 [ 0 1 ]
fid_sub_time 1 7 [ 0 1 ]
fid_sub_time 1 8 [ 0 1 ]
-fid_sub_time 1 11 [ 0 1 ]
+fid_sub_time 1 11 [ 0 2 ]
fid_sub_time 1 12 [ 0 1 ]
fid_sub_time 1 13 [ 0 1 ]
fid_sub_time 1 16 [ 0 1 ]
@@ -106,8 +104,7 @@
fid_sub_time 1 21 [ 0 1 ]
fid_sub_time 1 22 [ 0 2 ]
fid_sub_time 1 25 [ 0 6 ]
-fid_sub_time 1 26 [ 0 3 ]
-fid_sub_time 1 27 [ 0 1 ]
+fid_sub_time 1 27 [ 0 4 ]
fid_sub_time 1 28 [ 0 3 ]
fid_sub_time 1 33 [ 0 1 ]
fid_sub_time 1 36 [ 0 2 ]
@@ -126,7 +123,7 @@
sub_subinfo Devel::NYTProf::Test::example_xsub [ 1:undef-undef calls 5 times 0 0 0 0 ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:17 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:22 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:26 [ 3 0 0 0 0 0 0 main::CORE:sort ]
+sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:27 [ 3 0 0 0 0 0 0 main::CORE:sort ]
sub_subinfo MyTie::FETCH [ 1:7-7 calls 1 times 0 0 0 0 ]
sub_subinfo MyTie::FETCH called_by 1:13 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo MyTie::STORE [ 1:8-8 calls 1 times 0 0 0 0 ]
# Failed test 'test62-subcaller1.rdt match generated profile data for blocks=1 calls=1 compress=1 leave=0 savesrc=0 slowops=2 start=init use_db_sub=1'
# at t/lib/NYTProfTest.pm line 443.
# Structures begin differing at:
# $got->[16] = 'fid_block_time 1 4 [ 0 1 ]'
# $expected->[16] = 'fid_block_time 1 4 [ 0 2 ]'
--- test62-subcaller1.rdt 2013-03-02 23:17:35.000000000 +0100
+++ test62-subcaller1.rdt_new 2015-01-21 12:20:25.000000000 +0100
@@ -14,11 +14,11 @@
attribute total_stmts_measured 0
attribute total_sub_calls 0
attribute xs_version 0
-fid_block_time 1 4 [ 0 2 ]
+fid_block_time 1 4 [ 0 1 ]
fid_block_time 1 6 [ 0 1 ]
fid_block_time 1 7 [ 0 1 ]
fid_block_time 1 8 [ 0 1 ]
-fid_block_time 1 11 [ 0 1 ]
+fid_block_time 1 11 [ 0 2 ]
fid_block_time 1 12 [ 0 1 ]
fid_block_time 1 13 [ 0 1 ]
fid_block_time 1 16 [ 0 1 ]
@@ -26,8 +26,7 @@
fid_block_time 1 21 [ 0 1 ]
fid_block_time 1 22 [ 0 2 ]
fid_block_time 1 25 [ 0 6 ]
-fid_block_time 1 26 [ 0 3 ]
-fid_block_time 1 27 [ 0 1 ]
+fid_block_time 1 27 [ 0 4 ]
fid_block_time 1 28 [ 0 3 ]
fid_block_time 1 33 [ 0 1 ]
fid_block_time 1 36 [ 0 2 ]
@@ -59,7 +58,7 @@
fid_fileinfo 1 call 17 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 main::sub1 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-fid_fileinfo 1 call 26 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
+fid_fileinfo 1 call 27 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 27 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 28 main::sub2 [ 6 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 33 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
@@ -69,11 +68,11 @@
fid_fileinfo 2 [ Devel/NYTProf/Test.pm 2 2 0 0 ]
fid_fileinfo 2 sub Devel::NYTProf::Test::example_sub 13-13
fid_fileinfo 3 [ Exporter.pm 3 2 0 0 ]
-fid_line_time 1 4 [ 0 2 ]
+fid_line_time 1 4 [ 0 1 ]
fid_line_time 1 6 [ 0 1 ]
fid_line_time 1 7 [ 0 1 ]
fid_line_time 1 8 [ 0 1 ]
-fid_line_time 1 11 [ 0 1 ]
+fid_line_time 1 11 [ 0 2 ]
fid_line_time 1 12 [ 0 1 ]
fid_line_time 1 13 [ 0 1 ]
fid_line_time 1 16 [ 0 1 ]
@@ -81,8 +80,7 @@
fid_line_time 1 21 [ 0 1 ]
fid_line_time 1 22 [ 0 2 ]
fid_line_time 1 25 [ 0 6 ]
-fid_line_time 1 26 [ 0 3 ]
-fid_line_time 1 27 [ 0 1 ]
+fid_line_time 1 27 [ 0 4 ]
fid_line_time 1 28 [ 0 3 ]
fid_line_time 1 33 [ 0 1 ]
fid_line_time 1 36 [ 0 2 ]
@@ -94,11 +92,11 @@
fid_line_time 2 9 [ 0 1 ]
fid_line_time 2 11 [ 0 1 ]
fid_line_time 2 15 [ 0 1 ]
-fid_sub_time 1 4 [ 0 2 ]
+fid_sub_time 1 4 [ 0 1 ]
fid_sub_time 1 6 [ 0 1 ]
fid_sub_time 1 7 [ 0 1 ]
fid_sub_time 1 8 [ 0 1 ]
-fid_sub_time 1 11 [ 0 1 ]
+fid_sub_time 1 11 [ 0 2 ]
fid_sub_time 1 12 [ 0 1 ]
fid_sub_time 1 13 [ 0 1 ]
fid_sub_time 1 16 [ 0 1 ]
@@ -106,8 +104,7 @@
fid_sub_time 1 21 [ 0 1 ]
fid_sub_time 1 22 [ 0 2 ]
fid_sub_time 1 25 [ 0 6 ]
-fid_sub_time 1 26 [ 0 3 ]
-fid_sub_time 1 27 [ 0 1 ]
+fid_sub_time 1 27 [ 0 4 ]
fid_sub_time 1 28 [ 0 3 ]
fid_sub_time 1 33 [ 0 1 ]
fid_sub_time 1 36 [ 0 2 ]
@@ -126,7 +123,7 @@
sub_subinfo Devel::NYTProf::Test::example_xsub [ 1:undef-undef calls 5 times 0 0 0 0 ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:17 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:22 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:26 [ 3 0 0 0 0 0 0 main::CORE:sort ]
+sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:27 [ 3 0 0 0 0 0 0 main::CORE:sort ]
sub_subinfo MyTie::FETCH [ 1:7-7 calls 1 times 0 0 0 0 ]
sub_subinfo MyTie::FETCH called_by 1:13 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo MyTie::STORE [ 1:8-8 calls 1 times 0 0 0 0 ]
# Failed test 'test62-subcaller1.rdt match generated profile data for blocks=1 calls=1 compress=1 leave=1 savesrc=0 slowops=2 start=init use_db_sub=0'
# at t/lib/NYTProfTest.pm line 443.
# Structures begin differing at:
# $got->[16] = 'fid_block_time 1 4 [ 0 1 ]'
# $expected->[16] = 'fid_block_time 1 4 [ 0 2 ]'
--- test62-subcaller1.rdt 2013-03-02 23:17:35.000000000 +0100
+++ test62-subcaller1.rdt_new 2015-01-21 12:20:25.000000000 +0100
@@ -14,11 +14,11 @@
attribute total_stmts_measured 0
attribute total_sub_calls 0
attribute xs_version 0
-fid_block_time 1 4 [ 0 2 ]
+fid_block_time 1 4 [ 0 1 ]
fid_block_time 1 6 [ 0 1 ]
fid_block_time 1 7 [ 0 1 ]
fid_block_time 1 8 [ 0 1 ]
-fid_block_time 1 11 [ 0 1 ]
+fid_block_time 1 11 [ 0 2 ]
fid_block_time 1 12 [ 0 1 ]
fid_block_time 1 13 [ 0 1 ]
fid_block_time 1 16 [ 0 1 ]
@@ -26,8 +26,7 @@
fid_block_time 1 21 [ 0 1 ]
fid_block_time 1 22 [ 0 2 ]
fid_block_time 1 25 [ 0 6 ]
-fid_block_time 1 26 [ 0 3 ]
-fid_block_time 1 27 [ 0 1 ]
+fid_block_time 1 27 [ 0 4 ]
fid_block_time 1 28 [ 0 3 ]
fid_block_time 1 33 [ 0 1 ]
fid_block_time 1 36 [ 0 2 ]
@@ -59,7 +58,7 @@
fid_fileinfo 1 call 17 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 main::sub1 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-fid_fileinfo 1 call 26 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
+fid_fileinfo 1 call 27 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 27 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 28 main::sub2 [ 6 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 33 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
@@ -69,11 +68,11 @@
fid_fileinfo 2 [ Devel/NYTProf/Test.pm 2 2 0 0 ]
fid_fileinfo 2 sub Devel::NYTProf::Test::example_sub 13-13
fid_fileinfo 3 [ Exporter.pm 3 2 0 0 ]
-fid_line_time 1 4 [ 0 2 ]
+fid_line_time 1 4 [ 0 1 ]
fid_line_time 1 6 [ 0 1 ]
fid_line_time 1 7 [ 0 1 ]
fid_line_time 1 8 [ 0 1 ]
-fid_line_time 1 11 [ 0 1 ]
+fid_line_time 1 11 [ 0 2 ]
fid_line_time 1 12 [ 0 1 ]
fid_line_time 1 13 [ 0 1 ]
fid_line_time 1 16 [ 0 1 ]
@@ -81,8 +80,7 @@
fid_line_time 1 21 [ 0 1 ]
fid_line_time 1 22 [ 0 2 ]
fid_line_time 1 25 [ 0 6 ]
-fid_line_time 1 26 [ 0 3 ]
-fid_line_time 1 27 [ 0 1 ]
+fid_line_time 1 27 [ 0 4 ]
fid_line_time 1 28 [ 0 3 ]
fid_line_time 1 33 [ 0 1 ]
fid_line_time 1 36 [ 0 2 ]
@@ -94,11 +92,11 @@
fid_line_time 2 9 [ 0 1 ]
fid_line_time 2 11 [ 0 1 ]
fid_line_time 2 15 [ 0 1 ]
-fid_sub_time 1 4 [ 0 2 ]
+fid_sub_time 1 4 [ 0 1 ]
fid_sub_time 1 6 [ 0 1 ]
fid_sub_time 1 7 [ 0 1 ]
fid_sub_time 1 8 [ 0 1 ]
-fid_sub_time 1 11 [ 0 1 ]
+fid_sub_time 1 11 [ 0 2 ]
fid_sub_time 1 12 [ 0 1 ]
fid_sub_time 1 13 [ 0 1 ]
fid_sub_time 1 16 [ 0 1 ]
@@ -106,8 +104,7 @@
fid_sub_time 1 21 [ 0 1 ]
fid_sub_time 1 22 [ 0 2 ]
fid_sub_time 1 25 [ 0 6 ]
-fid_sub_time 1 26 [ 0 3 ]
-fid_sub_time 1 27 [ 0 1 ]
+fid_sub_time 1 27 [ 0 4 ]
fid_sub_time 1 28 [ 0 3 ]
fid_sub_time 1 33 [ 0 1 ]
fid_sub_time 1 36 [ 0 2 ]
@@ -126,7 +123,7 @@
sub_subinfo Devel::NYTProf::Test::example_xsub [ 1:undef-undef calls 5 times 0 0 0 0 ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:17 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:22 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:26 [ 3 0 0 0 0 0 0 main::CORE:sort ]
+sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:27 [ 3 0 0 0 0 0 0 main::CORE:sort ]
sub_subinfo MyTie::FETCH [ 1:7-7 calls 1 times 0 0 0 0 ]
sub_subinfo MyTie::FETCH called_by 1:13 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo MyTie::STORE [ 1:8-8 calls 1 times 0 0 0 0 ]
# Failed test 'test62-subcaller1.rdt match generated profile data for blocks=1 calls=2 compress=1 leave=1 savesrc=1 slowops=2 start=init use_db_sub=1'
# at t/lib/NYTProfTest.pm line 443.
# Structures begin differing at:
# $got->[16] = 'fid_block_time 1 4 [ 0 1 ]'
# $expected->[16] = 'fid_block_time 1 4 [ 0 2 ]'
--- test62-subcaller1.rdt 2013-03-02 23:17:35.000000000 +0100
+++ test62-subcaller1.rdt_new 2015-01-21 12:20:25.000000000 +0100
@@ -14,11 +14,11 @@
attribute total_stmts_measured 0
attribute total_sub_calls 0
attribute xs_version 0
-fid_block_time 1 4 [ 0 2 ]
+fid_block_time 1 4 [ 0 1 ]
fid_block_time 1 6 [ 0 1 ]
fid_block_time 1 7 [ 0 1 ]
fid_block_time 1 8 [ 0 1 ]
-fid_block_time 1 11 [ 0 1 ]
+fid_block_time 1 11 [ 0 2 ]
fid_block_time 1 12 [ 0 1 ]
fid_block_time 1 13 [ 0 1 ]
fid_block_time 1 16 [ 0 1 ]
@@ -26,8 +26,7 @@
fid_block_time 1 21 [ 0 1 ]
fid_block_time 1 22 [ 0 2 ]
fid_block_time 1 25 [ 0 6 ]
-fid_block_time 1 26 [ 0 3 ]
-fid_block_time 1 27 [ 0 1 ]
+fid_block_time 1 27 [ 0 4 ]
fid_block_time 1 28 [ 0 3 ]
fid_block_time 1 33 [ 0 1 ]
fid_block_time 1 36 [ 0 2 ]
@@ -59,7 +58,7 @@
fid_fileinfo 1 call 17 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 main::sub1 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-fid_fileinfo 1 call 26 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
+fid_fileinfo 1 call 27 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 27 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 28 main::sub2 [ 6 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 33 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
@@ -69,11 +68,11 @@
fid_fileinfo 2 [ Devel/NYTProf/Test.pm 2 2 0 0 ]
fid_fileinfo 2 sub Devel::NYTProf::Test::example_sub 13-13
fid_fileinfo 3 [ Exporter.pm 3 2 0 0 ]
-fid_line_time 1 4 [ 0 2 ]
+fid_line_time 1 4 [ 0 1 ]
fid_line_time 1 6 [ 0 1 ]
fid_line_time 1 7 [ 0 1 ]
fid_line_time 1 8 [ 0 1 ]
-fid_line_time 1 11 [ 0 1 ]
+fid_line_time 1 11 [ 0 2 ]
fid_line_time 1 12 [ 0 1 ]
fid_line_time 1 13 [ 0 1 ]
fid_line_time 1 16 [ 0 1 ]
@@ -81,8 +80,7 @@
fid_line_time 1 21 [ 0 1 ]
fid_line_time 1 22 [ 0 2 ]
fid_line_time 1 25 [ 0 6 ]
-fid_line_time 1 26 [ 0 3 ]
-fid_line_time 1 27 [ 0 1 ]
+fid_line_time 1 27 [ 0 4 ]
fid_line_time 1 28 [ 0 3 ]
fid_line_time 1 33 [ 0 1 ]
fid_line_time 1 36 [ 0 2 ]
@@ -94,11 +92,11 @@
fid_line_time 2 9 [ 0 1 ]
fid_line_time 2 11 [ 0 1 ]
fid_line_time 2 15 [ 0 1 ]
-fid_sub_time 1 4 [ 0 2 ]
+fid_sub_time 1 4 [ 0 1 ]
fid_sub_time 1 6 [ 0 1 ]
fid_sub_time 1 7 [ 0 1 ]
fid_sub_time 1 8 [ 0 1 ]
-fid_sub_time 1 11 [ 0 1 ]
+fid_sub_time 1 11 [ 0 2 ]
fid_sub_time 1 12 [ 0 1 ]
fid_sub_time 1 13 [ 0 1 ]
fid_sub_time 1 16 [ 0 1 ]
@@ -106,8 +104,7 @@
fid_sub_time 1 21 [ 0 1 ]
fid_sub_time 1 22 [ 0 2 ]
fid_sub_time 1 25 [ 0 6 ]
-fid_sub_time 1 26 [ 0 3 ]
-fid_sub_time 1 27 [ 0 1 ]
+fid_sub_time 1 27 [ 0 4 ]
fid_sub_time 1 28 [ 0 3 ]
fid_sub_time 1 33 [ 0 1 ]
fid_sub_time 1 36 [ 0 2 ]
@@ -126,7 +123,7 @@
sub_subinfo Devel::NYTProf::Test::example_xsub [ 1:undef-undef calls 5 times 0 0 0 0 ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:17 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:22 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:26 [ 3 0 0 0 0 0 0 main::CORE:sort ]
+sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:27 [ 3 0 0 0 0 0 0 main::CORE:sort ]
sub_subinfo MyTie::FETCH [ 1:7-7 calls 1 times 0 0 0 0 ]
sub_subinfo MyTie::FETCH called_by 1:13 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo MyTie::STORE [ 1:8-8 calls 1 times 0 0 0 0 ]
# Failed test 'test62-subcaller1.rdt match generated profile data for blocks=1 calls=0 compress=0 leave=0 savesrc=0 slowops=2 start=init use_db_sub=1'
# at t/lib/NYTProfTest.pm line 443.
# Structures begin differing at:
# $got->[16] = 'fid_block_time 1 4 [ 0 1 ]'
# $expected->[16] = 'fid_block_time 1 4 [ 0 2 ]'
--- test62-subcaller1.rdt 2013-03-02 23:17:35.000000000 +0100
+++ test62-subcaller1.rdt_new 2015-01-21 12:20:25.000000000 +0100
@@ -14,11 +14,11 @@
attribute total_stmts_measured 0
attribute total_sub_calls 0
attribute xs_version 0
-fid_block_time 1 4 [ 0 2 ]
+fid_block_time 1 4 [ 0 1 ]
fid_block_time 1 6 [ 0 1 ]
fid_block_time 1 7 [ 0 1 ]
fid_block_time 1 8 [ 0 1 ]
-fid_block_time 1 11 [ 0 1 ]
+fid_block_time 1 11 [ 0 2 ]
fid_block_time 1 12 [ 0 1 ]
fid_block_time 1 13 [ 0 1 ]
fid_block_time 1 16 [ 0 1 ]
@@ -26,8 +26,7 @@
fid_block_time 1 21 [ 0 1 ]
fid_block_time 1 22 [ 0 2 ]
fid_block_time 1 25 [ 0 6 ]
-fid_block_time 1 26 [ 0 3 ]
-fid_block_time 1 27 [ 0 1 ]
+fid_block_time 1 27 [ 0 4 ]
fid_block_time 1 28 [ 0 3 ]
fid_block_time 1 33 [ 0 1 ]
fid_block_time 1 36 [ 0 2 ]
@@ -59,7 +58,7 @@
fid_fileinfo 1 call 17 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 main::sub1 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-fid_fileinfo 1 call 26 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
+fid_fileinfo 1 call 27 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 27 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 28 main::sub2 [ 6 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 33 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
@@ -69,11 +68,11 @@
fid_fileinfo 2 [ Devel/NYTProf/Test.pm 2 2 0 0 ]
fid_fileinfo 2 sub Devel::NYTProf::Test::example_sub 13-13
fid_fileinfo 3 [ Exporter.pm 3 2 0 0 ]
-fid_line_time 1 4 [ 0 2 ]
+fid_line_time 1 4 [ 0 1 ]
fid_line_time 1 6 [ 0 1 ]
fid_line_time 1 7 [ 0 1 ]
fid_line_time 1 8 [ 0 1 ]
-fid_line_time 1 11 [ 0 1 ]
+fid_line_time 1 11 [ 0 2 ]
fid_line_time 1 12 [ 0 1 ]
fid_line_time 1 13 [ 0 1 ]
fid_line_time 1 16 [ 0 1 ]
@@ -81,8 +80,7 @@
fid_line_time 1 21 [ 0 1 ]
fid_line_time 1 22 [ 0 2 ]
fid_line_time 1 25 [ 0 6 ]
-fid_line_time 1 26 [ 0 3 ]
-fid_line_time 1 27 [ 0 1 ]
+fid_line_time 1 27 [ 0 4 ]
fid_line_time 1 28 [ 0 3 ]
fid_line_time 1 33 [ 0 1 ]
fid_line_time 1 36 [ 0 2 ]
@@ -94,11 +92,11 @@
fid_line_time 2 9 [ 0 1 ]
fid_line_time 2 11 [ 0 1 ]
fid_line_time 2 15 [ 0 1 ]
-fid_sub_time 1 4 [ 0 2 ]
+fid_sub_time 1 4 [ 0 1 ]
fid_sub_time 1 6 [ 0 1 ]
fid_sub_time 1 7 [ 0 1 ]
fid_sub_time 1 8 [ 0 1 ]
-fid_sub_time 1 11 [ 0 1 ]
+fid_sub_time 1 11 [ 0 2 ]
fid_sub_time 1 12 [ 0 1 ]
fid_sub_time 1 13 [ 0 1 ]
fid_sub_time 1 16 [ 0 1 ]
@@ -106,8 +104,7 @@
fid_sub_time 1 21 [ 0 1 ]
fid_sub_time 1 22 [ 0 2 ]
fid_sub_time 1 25 [ 0 6 ]
-fid_sub_time 1 26 [ 0 3 ]
-fid_sub_time 1 27 [ 0 1 ]
+fid_sub_time 1 27 [ 0 4 ]
fid_sub_time 1 28 [ 0 3 ]
fid_sub_time 1 33 [ 0 1 ]
fid_sub_time 1 36 [ 0 2 ]
@@ -126,7 +123,7 @@
sub_subinfo Devel::NYTProf::Test::example_xsub [ 1:undef-undef calls 5 times 0 0 0 0 ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:17 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:22 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:26 [ 3 0 0 0 0 0 0 main::CORE:sort ]
+sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:27 [ 3 0 0 0 0 0 0 main::CORE:sort ]
sub_subinfo MyTie::FETCH [ 1:7-7 calls 1 times 0 0 0 0 ]
sub_subinfo MyTie::FETCH called_by 1:13 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo MyTie::STORE [ 1:8-8 calls 1 times 0 0 0 0 ]
# Failed test 'test62-subcaller1.rdt match generated profile data for blocks=1 calls=1 compress=1 leave=1 savesrc=0 slowops=2 start=init use_db_sub=1'
# at t/lib/NYTProfTest.pm line 443.
# Structures begin differing at:
# $got->[16] = 'fid_block_time 1 4 [ 0 1 ]'
# $expected->[16] = 'fid_block_time 1 4 [ 0 2 ]'
--- test62-subcaller1.rdt 2013-03-02 23:17:35.000000000 +0100
+++ test62-subcaller1.rdt_new 2015-01-21 12:20:25.000000000 +0100
@@ -14,11 +14,11 @@
attribute total_stmts_measured 0
attribute total_sub_calls 0
attribute xs_version 0
-fid_block_time 1 4 [ 0 2 ]
+fid_block_time 1 4 [ 0 1 ]
fid_block_time 1 6 [ 0 1 ]
fid_block_time 1 7 [ 0 1 ]
fid_block_time 1 8 [ 0 1 ]
-fid_block_time 1 11 [ 0 1 ]
+fid_block_time 1 11 [ 0 2 ]
fid_block_time 1 12 [ 0 1 ]
fid_block_time 1 13 [ 0 1 ]
fid_block_time 1 16 [ 0 1 ]
@@ -26,8 +26,7 @@
fid_block_time 1 21 [ 0 1 ]
fid_block_time 1 22 [ 0 2 ]
fid_block_time 1 25 [ 0 6 ]
-fid_block_time 1 26 [ 0 3 ]
-fid_block_time 1 27 [ 0 1 ]
+fid_block_time 1 27 [ 0 4 ]
fid_block_time 1 28 [ 0 3 ]
fid_block_time 1 33 [ 0 1 ]
fid_block_time 1 36 [ 0 2 ]
@@ -59,7 +58,7 @@
fid_fileinfo 1 call 17 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 main::sub1 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-fid_fileinfo 1 call 26 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
+fid_fileinfo 1 call 27 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 27 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 28 main::sub2 [ 6 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 33 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
@@ -69,11 +68,11 @@
fid_fileinfo 2 [ Devel/NYTProf/Test.pm 2 2 0 0 ]
fid_fileinfo 2 sub Devel::NYTProf::Test::example_sub 13-13
fid_fileinfo 3 [ Exporter.pm 3 2 0 0 ]
-fid_line_time 1 4 [ 0 2 ]
+fid_line_time 1 4 [ 0 1 ]
fid_line_time 1 6 [ 0 1 ]
fid_line_time 1 7 [ 0 1 ]
fid_line_time 1 8 [ 0 1 ]
-fid_line_time 1 11 [ 0 1 ]
+fid_line_time 1 11 [ 0 2 ]
fid_line_time 1 12 [ 0 1 ]
fid_line_time 1 13 [ 0 1 ]
fid_line_time 1 16 [ 0 1 ]
@@ -81,8 +80,7 @@
fid_line_time 1 21 [ 0 1 ]
fid_line_time 1 22 [ 0 2 ]
fid_line_time 1 25 [ 0 6 ]
-fid_line_time 1 26 [ 0 3 ]
-fid_line_time 1 27 [ 0 1 ]
+fid_line_time 1 27 [ 0 4 ]
fid_line_time 1 28 [ 0 3 ]
fid_line_time 1 33 [ 0 1 ]
fid_line_time 1 36 [ 0 2 ]
@@ -94,11 +92,11 @@
fid_line_time 2 9 [ 0 1 ]
fid_line_time 2 11 [ 0 1 ]
fid_line_time 2 15 [ 0 1 ]
-fid_sub_time 1 4 [ 0 2 ]
+fid_sub_time 1 4 [ 0 1 ]
fid_sub_time 1 6 [ 0 1 ]
fid_sub_time 1 7 [ 0 1 ]
fid_sub_time 1 8 [ 0 1 ]
-fid_sub_time 1 11 [ 0 1 ]
+fid_sub_time 1 11 [ 0 2 ]
fid_sub_time 1 12 [ 0 1 ]
fid_sub_time 1 13 [ 0 1 ]
fid_sub_time 1 16 [ 0 1 ]
@@ -106,8 +104,7 @@
fid_sub_time 1 21 [ 0 1 ]
fid_sub_time 1 22 [ 0 2 ]
fid_sub_time 1 25 [ 0 6 ]
-fid_sub_time 1 26 [ 0 3 ]
-fid_sub_time 1 27 [ 0 1 ]
+fid_sub_time 1 27 [ 0 4 ]
fid_sub_time 1 28 [ 0 3 ]
fid_sub_time 1 33 [ 0 1 ]
fid_sub_time 1 36 [ 0 2 ]
@@ -126,7 +123,7 @@
sub_subinfo Devel::NYTProf::Test::example_xsub [ 1:undef-undef calls 5 times 0 0 0 0 ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:17 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:22 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:26 [ 3 0 0 0 0 0 0 main::CORE:sort ]
+sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:27 [ 3 0 0 0 0 0 0 main::CORE:sort ]
sub_subinfo MyTie::FETCH [ 1:7-7 calls 1 times 0 0 0 0 ]
sub_subinfo MyTie::FETCH called_by 1:13 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo MyTie::STORE [ 1:8-8 calls 1 times 0 0 0 0 ]
# Failed test 'test62-subcaller1.rdt match generated profile data for blocks=1 calls=1 compress=1 leave=0 savesrc=0 slowops=2 start=init use_db_sub=0'
# at t/lib/NYTProfTest.pm line 443.
# Structures begin differing at:
# $got->[16] = 'fid_block_time 1 4 [ 0 1 ]'
# $expected->[16] = 'fid_block_time 1 4 [ 0 2 ]'
--- test62-subcaller1.rdt 2013-03-02 23:17:35.000000000 +0100
+++ test62-subcaller1.rdt_new 2015-01-21 12:20:26.000000000 +0100
@@ -14,11 +14,11 @@
attribute total_stmts_measured 0
attribute total_sub_calls 0
attribute xs_version 0
-fid_block_time 1 4 [ 0 2 ]
+fid_block_time 1 4 [ 0 1 ]
fid_block_time 1 6 [ 0 1 ]
fid_block_time 1 7 [ 0 1 ]
fid_block_time 1 8 [ 0 1 ]
-fid_block_time 1 11 [ 0 1 ]
+fid_block_time 1 11 [ 0 2 ]
fid_block_time 1 12 [ 0 1 ]
fid_block_time 1 13 [ 0 1 ]
fid_block_time 1 16 [ 0 1 ]
@@ -26,8 +26,7 @@
fid_block_time 1 21 [ 0 1 ]
fid_block_time 1 22 [ 0 2 ]
fid_block_time 1 25 [ 0 6 ]
-fid_block_time 1 26 [ 0 3 ]
-fid_block_time 1 27 [ 0 1 ]
+fid_block_time 1 27 [ 0 4 ]
fid_block_time 1 28 [ 0 3 ]
fid_block_time 1 33 [ 0 1 ]
fid_block_time 1 36 [ 0 2 ]
@@ -59,7 +58,7 @@
fid_fileinfo 1 call 17 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 main::sub1 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-fid_fileinfo 1 call 26 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
+fid_fileinfo 1 call 27 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 27 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 28 main::sub2 [ 6 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 33 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
@@ -69,11 +68,11 @@
fid_fileinfo 2 [ Devel/NYTProf/Test.pm 2 2 0 0 ]
fid_fileinfo 2 sub Devel::NYTProf::Test::example_sub 13-13
fid_fileinfo 3 [ Exporter.pm 3 2 0 0 ]
-fid_line_time 1 4 [ 0 2 ]
+fid_line_time 1 4 [ 0 1 ]
fid_line_time 1 6 [ 0 1 ]
fid_line_time 1 7 [ 0 1 ]
fid_line_time 1 8 [ 0 1 ]
-fid_line_time 1 11 [ 0 1 ]
+fid_line_time 1 11 [ 0 2 ]
fid_line_time 1 12 [ 0 1 ]
fid_line_time 1 13 [ 0 1 ]
fid_line_time 1 16 [ 0 1 ]
@@ -81,8 +80,7 @@
fid_line_time 1 21 [ 0 1 ]
fid_line_time 1 22 [ 0 2 ]
fid_line_time 1 25 [ 0 6 ]
-fid_line_time 1 26 [ 0 3 ]
-fid_line_time 1 27 [ 0 1 ]
+fid_line_time 1 27 [ 0 4 ]
fid_line_time 1 28 [ 0 3 ]
fid_line_time 1 33 [ 0 1 ]
fid_line_time 1 36 [ 0 2 ]
@@ -94,11 +92,11 @@
fid_line_time 2 9 [ 0 1 ]
fid_line_time 2 11 [ 0 1 ]
fid_line_time 2 15 [ 0 1 ]
-fid_sub_time 1 4 [ 0 2 ]
+fid_sub_time 1 4 [ 0 1 ]
fid_sub_time 1 6 [ 0 1 ]
fid_sub_time 1 7 [ 0 1 ]
fid_sub_time 1 8 [ 0 1 ]
-fid_sub_time 1 11 [ 0 1 ]
+fid_sub_time 1 11 [ 0 2 ]
fid_sub_time 1 12 [ 0 1 ]
fid_sub_time 1 13 [ 0 1 ]
fid_sub_time 1 16 [ 0 1 ]
@@ -106,8 +104,7 @@
fid_sub_time 1 21 [ 0 1 ]
fid_sub_time 1 22 [ 0 2 ]
fid_sub_time 1 25 [ 0 6 ]
-fid_sub_time 1 26 [ 0 3 ]
-fid_sub_time 1 27 [ 0 1 ]
+fid_sub_time 1 27 [ 0 4 ]
fid_sub_time 1 28 [ 0 3 ]
fid_sub_time 1 33 [ 0 1 ]
fid_sub_time 1 36 [ 0 2 ]
@@ -126,7 +123,7 @@
sub_subinfo Devel::NYTProf::Test::example_xsub [ 1:undef-undef calls 5 times 0 0 0 0 ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:17 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:22 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:26 [ 3 0 0 0 0 0 0 main::CORE:sort ]
+sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:27 [ 3 0 0 0 0 0 0 main::CORE:sort ]
sub_subinfo MyTie::FETCH [ 1:7-7 calls 1 times 0 0 0 0 ]
sub_subinfo MyTie::FETCH called_by 1:13 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo MyTie::STORE [ 1:8-8 calls 1 times 0 0 0 0 ]
# Failed test 'test62-subcaller1.rdt match generated profile data for blocks=1 calls=0 compress=0 leave=0 savesrc=0 slowops=2 start=init use_db_sub=0'
# at t/lib/NYTProfTest.pm line 443.
# Structures begin differing at:
# $got->[16] = 'fid_block_time 1 4 [ 0 1 ]'
# $expected->[16] = 'fid_block_time 1 4 [ 0 2 ]'
--- test62-subcaller1.rdt 2013-03-02 23:17:35.000000000 +0100
+++ test62-subcaller1.rdt_new 2015-01-21 12:20:26.000000000 +0100
@@ -14,11 +14,11 @@
attribute total_stmts_measured 0
attribute total_sub_calls 0
attribute xs_version 0
-fid_block_time 1 4 [ 0 2 ]
+fid_block_time 1 4 [ 0 1 ]
fid_block_time 1 6 [ 0 1 ]
fid_block_time 1 7 [ 0 1 ]
fid_block_time 1 8 [ 0 1 ]
-fid_block_time 1 11 [ 0 1 ]
+fid_block_time 1 11 [ 0 2 ]
fid_block_time 1 12 [ 0 1 ]
fid_block_time 1 13 [ 0 1 ]
fid_block_time 1 16 [ 0 1 ]
@@ -26,8 +26,7 @@
fid_block_time 1 21 [ 0 1 ]
fid_block_time 1 22 [ 0 2 ]
fid_block_time 1 25 [ 0 6 ]
-fid_block_time 1 26 [ 0 3 ]
-fid_block_time 1 27 [ 0 1 ]
+fid_block_time 1 27 [ 0 4 ]
fid_block_time 1 28 [ 0 3 ]
fid_block_time 1 33 [ 0 1 ]
fid_block_time 1 36 [ 0 2 ]
@@ -59,7 +58,7 @@
fid_fileinfo 1 call 17 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 Devel::NYTProf::Test::example_xsub [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 22 main::sub1 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-fid_fileinfo 1 call 26 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
+fid_fileinfo 1 call 27 Devel::NYTProf::Test::example_xsub [ 3 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 27 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
fid_fileinfo 1 call 28 main::sub2 [ 6 0 0 0 0 0 0 main::CORE:sort ]
fid_fileinfo 1 call 33 main::CORE:sort [ 1 0 0 0 0 0 0 main::RUNTIME ]
@@ -69,11 +68,11 @@
fid_fileinfo 2 [ Devel/NYTProf/Test.pm 2 2 0 0 ]
fid_fileinfo 2 sub Devel::NYTProf::Test::example_sub 13-13
fid_fileinfo 3 [ Exporter.pm 3 2 0 0 ]
-fid_line_time 1 4 [ 0 2 ]
+fid_line_time 1 4 [ 0 1 ]
fid_line_time 1 6 [ 0 1 ]
fid_line_time 1 7 [ 0 1 ]
fid_line_time 1 8 [ 0 1 ]
-fid_line_time 1 11 [ 0 1 ]
+fid_line_time 1 11 [ 0 2 ]
fid_line_time 1 12 [ 0 1 ]
fid_line_time 1 13 [ 0 1 ]
fid_line_time 1 16 [ 0 1 ]
@@ -81,8 +80,7 @@
fid_line_time 1 21 [ 0 1 ]
fid_line_time 1 22 [ 0 2 ]
fid_line_time 1 25 [ 0 6 ]
-fid_line_time 1 26 [ 0 3 ]
-fid_line_time 1 27 [ 0 1 ]
+fid_line_time 1 27 [ 0 4 ]
fid_line_time 1 28 [ 0 3 ]
fid_line_time 1 33 [ 0 1 ]
fid_line_time 1 36 [ 0 2 ]
@@ -94,11 +92,11 @@
fid_line_time 2 9 [ 0 1 ]
fid_line_time 2 11 [ 0 1 ]
fid_line_time 2 15 [ 0 1 ]
-fid_sub_time 1 4 [ 0 2 ]
+fid_sub_time 1 4 [ 0 1 ]
fid_sub_time 1 6 [ 0 1 ]
fid_sub_time 1 7 [ 0 1 ]
fid_sub_time 1 8 [ 0 1 ]
-fid_sub_time 1 11 [ 0 1 ]
+fid_sub_time 1 11 [ 0 2 ]
fid_sub_time 1 12 [ 0 1 ]
fid_sub_time 1 13 [ 0 1 ]
fid_sub_time 1 16 [ 0 1 ]
@@ -106,8 +104,7 @@
fid_sub_time 1 21 [ 0 1 ]
fid_sub_time 1 22 [ 0 2 ]
fid_sub_time 1 25 [ 0 6 ]
-fid_sub_time 1 26 [ 0 3 ]
-fid_sub_time 1 27 [ 0 1 ]
+fid_sub_time 1 27 [ 0 4 ]
fid_sub_time 1 28 [ 0 3 ]
fid_sub_time 1 33 [ 0 1 ]
fid_sub_time 1 36 [ 0 2 ]
@@ -126,7 +123,7 @@
sub_subinfo Devel::NYTProf::Test::example_xsub [ 1:undef-undef calls 5 times 0 0 0 0 ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:17 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:22 [ 1 0 0 0 0 0 0 main::RUNTIME ]
-sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:26 [ 3 0 0 0 0 0 0 main::CORE:sort ]
+sub_subinfo Devel::NYTProf::Test::example_xsub called_by 1:27 [ 3 0 0 0 0 0 0 main::CORE:sort ]
sub_subinfo MyTie::FETCH [ 1:7-7 calls 1 times 0 0 0 0 ]
sub_subinfo MyTie::FETCH called_by 1:13 [ 1 0 0 0 0 0 0 main::RUNTIME ]
sub_subinfo MyTie::STORE [ 1:8-8 calls 1 times 0 0 0 0 ]
# SUMMARY: Breakdown of test62-subcaller1 test failures by option settings:
# calls: 0 => {FAIL 4}, 1 => {FAIL 8}, 2 => {FAIL 4}
# FAILED with blocks=1:calls=0:compress=0:file=nytprof_test62-subcaller1.out:leave=0:savesrc=0:slowops=2:start=init:use_db_sub=0
# FAILED with blocks=1:calls=0:compress=0:file=nytprof_test62-subcaller1.out:leave=0:savesrc=0:slowops=2:start=init:use_db_sub=1
# FAILED with blocks=1:calls=0:compress=0:file=nytprof_test62-subcaller1.out:leave=1:savesrc=0:slowops=2:start=init:use_db_sub=0
# FAILED with blocks=1:calls=0:compress=0:file=nytprof_test62-subcaller1.out:leave=1:savesrc=0:slowops=2:start=init:use_db_sub=1
# FAILED with blocks=1:calls=1:compress=0:file=nytprof_test62-subcaller1.out:leave=0:savesrc=1:slowops=2:start=init:use_db_sub=0
# FAILED with blocks=1:calls=1:compress=0:file=nytprof_test62-subcaller1.out:leave=0:savesrc=1:slowops=2:start=init:use_db_sub=1
# FAILED with blocks=1:calls=1:compress=0:file=nytprof_test62-subcaller1.out:leave=1:savesrc=1:slowops=2:start=init:use_db_sub=0
# FAILED with blocks=1:calls=1:compress=0:file=nytprof_test62-subcaller1.out:leave=1:savesrc=1:slowops=2:start=init:use_db_sub=1
# FAILED with blocks=1:calls=1:compress=1:file=nytprof_test62-subcaller1.out:leave=0:savesrc=0:slowops=2:start=init:use_db_sub=0
# FAILED with blocks=1:calls=1:compress=1:file=nytprof_test62-subcaller1.out:leave=0:savesrc=0:slowops=2:start=init:use_db_sub=1
# FAILED with blocks=1:calls=1:compress=1:file=nytprof_test62-subcaller1.out:leave=1:savesrc=0:slowops=2:start=init:use_db_sub=0
# FAILED with blocks=1:calls=1:compress=1:file=nytprof_test62-subcaller1.out:leave=1:savesrc=0:slowops=2:start=init:use_db_sub=1
# FAILED with blocks=1:calls=2:compress=1:file=nytprof_test62-subcaller1.out:leave=0:savesrc=1:slowops=2:start=init:use_db_sub=0
# FAILED with blocks=1:calls=2:compress=1:file=nytprof_test62-subcaller1.out:leave=0:savesrc=1:slowops=2:start=init:use_db_sub=1
# FAILED with blocks=1:calls=2:compress=1:file=nytprof_test62-subcaller1.out:leave=1:savesrc=1:slowops=2:start=init:use_db_sub=0
# FAILED with blocks=1:calls=2:compress=1:file=nytprof_test62-subcaller1.out:leave=1:savesrc=1:slowops=2:start=init:use_db_sub=1
# Looks like you failed 16 tests of 65.
t/test62-subcaller1.t ..
Dubious, test returned 16 (wstat 4096, 0x1000)
Failed 16/65 subtests
t/test70-subexcl.t ..... ok
t/test80-recurs.t ...... ok
t/test81-swash.t ....... ok
t/test82-version.t ..... ok
t/test90-strsubref.t ... ok
# Tests ended at Wed Jan 21 12:20:56 2015
t/zzz.t ................ ok
Test Summary Report
-------------------
t/test62-subcaller1.t (Wstat: 4096 Tests: 65 Failed: 16)
Failed tests: 3, 7, 11, 15, 19, 23, 27, 31, 35, 39, 43
47, 51, 55, 59, 63
Non-zero exit status: 16
Files=53, Tests=4584, 107 wallclock secs ( 1.01 usr 0.17 sys + 41.17 cusr 9.51 csys = 51.86 CPU)
Result: FAIL
Failed 1/53 test programs. 16/4584 subtests failed.
make: *** [test_dynamic] Error 255
-> FAIL Installing Devel::NYTProf failed. See /Users/leejohnson/.cpanm/work/1421839145.42553/build.log for details. Retry with --force to force install it.
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment