builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-008 starttime: 1483667623.26 results: success (0) revision: 53193729a50b40c00889b1fa07df8dffab7781f9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 17:53:43.260084) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 17:53:43.260496) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 17:53:43.260768) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 17:53:43.275408) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 17:53:43.275757) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-05 17:53:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 112M=0s 2017-01-05 17:53:43 (112 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.080809 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 17:53:43.372117) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 17:53:43.372508) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019993 ========= master_lag: 0.24 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 17:53:43.634217) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-05 17:53:43.634555) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 53193729a50b40c00889b1fa07df8dffab7781f9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 53193729a50b40c00889b1fa07df8dffab7781f9 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-05 17:53:43,907 truncating revision to first 12 chars 2017-01-05 17:53:43,907 Setting DEBUG logging. 2017-01-05 17:53:43,907 attempt 1/10 2017-01-05 17:53:43,907 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/53193729a50b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-05 17:53:44,652 unpacking tar archive at: mozilla-central-53193729a50b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.051267 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-05 17:53:44.703033) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 17:53:44.703351) ========= script_repo_revision: 53193729a50b40c00889b1fa07df8dffab7781f9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 17:53:44.703735) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 17:53:44.704009) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 17:53:44.712481) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-01-05 17:53:44.712840) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:53:45 INFO - MultiFileLogger online at 20170105 17:53:45 in /builds/slave/test 17:53:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 17:53:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:53:45 INFO - {'append_to_log': False, 17:53:45 INFO - 'base_work_dir': '/builds/slave/test', 17:53:45 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 17:53:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:53:45 INFO - 'branch': 'Firefox-Non-PGO', 17:53:45 INFO - 'buildbot_json_path': 'buildprops.json', 17:53:45 INFO - 'config_files': ('talos/linux_config.py',), 17:53:45 INFO - 'default_actions': ('clobber', 17:53:45 INFO - 'read-buildbot-config', 17:53:45 INFO - 'download-and-extract', 17:53:45 INFO - 'populate-webroot', 17:53:45 INFO - 'create-virtualenv', 17:53:45 INFO - 'install', 17:53:45 INFO - 'run-tests'), 17:53:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:53:45 INFO - 'download_minidump_stackwalk': True, 17:53:45 INFO - 'download_symbols': 'ondemand', 17:53:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:53:45 INFO - 'tooltool.py': '/tools/tooltool.py', 17:53:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:53:45 INFO - '/tools/misc-python/virtualenv.py')}, 17:53:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:53:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:53:45 INFO - 'installer_path': 'installer.exe', 17:53:45 INFO - 'log_level': 'info', 17:53:45 INFO - 'log_name': 'talos', 17:53:45 INFO - 'log_to_console': True, 17:53:45 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:53:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:53:45 INFO - 'opt_config_files': (), 17:53:45 INFO - 'pip_index': False, 17:53:45 INFO - 'sps_profile': False, 17:53:45 INFO - 'sps_profile_interval': 0, 17:53:45 INFO - 'suite': 'dromaeojs', 17:53:45 INFO - 'system_bits': '32', 17:53:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:53:45 INFO - 'title': 'talos-linux64-ix-008', 17:53:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:53:45 INFO - 'use_talos_json': True, 17:53:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:53:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:53:45 INFO - 'work_dir': 'build'} 17:53:45 INFO - [mozharness: 2017-01-06 01:53:45.084487Z] Running clobber step. 17:53:45 INFO - Running pre-action listener: _resource_record_pre_action 17:53:45 INFO - Running main action method: clobber 17:53:45 INFO - rmtree: /builds/slave/test/build 17:53:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:53:45 INFO - Running post-action listener: _resource_record_post_action 17:53:45 INFO - [mozharness: 2017-01-06 01:53:45.539361Z] Finished clobber step (success) 17:53:45 INFO - [mozharness: 2017-01-06 01:53:45.539461Z] Running read-buildbot-config step. 17:53:45 INFO - Running pre-action listener: _resource_record_pre_action 17:53:45 INFO - Running main action method: read_buildbot_config 17:53:45 INFO - Using buildbot properties: 17:53:45 INFO - { 17:53:45 INFO - "project": "", 17:53:45 INFO - "product": "firefox", 17:53:45 INFO - "who": "kwierso@gmail.com", 17:53:45 INFO - "installer_path": "public/build/target.tar.bz2", 17:53:45 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 17:53:45 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 17:53:45 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 17:53:45 INFO - "stage_platform": "linux64", 17:53:45 INFO - "basedir": "/builds/slave/test", 17:53:45 INFO - "buildnumber": 765, 17:53:45 INFO - "platform": "ubuntu64_hw", 17:53:45 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:53:45 INFO - "slavebuilddir": "test", 17:53:45 INFO - "taskId": "AeQimyzjSIS51188HJ4aIg", 17:53:45 INFO - "branch": "mozilla-central", 17:53:45 INFO - "script_repo_revision": "production", 17:53:45 INFO - "revision": "53193729a50b40c00889b1fa07df8dffab7781f9", 17:53:45 INFO - "slavename": "talos-linux64-ix-008", 17:53:45 INFO - "repo_path": "mozilla-central" 17:53:45 INFO - } 17:53:45 INFO - Finding installer, test and symbols from parent task. 17:53:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AeQimyzjSIS51188HJ4aIg'}, attempt #1 17:53:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AeQimyzjSIS51188HJ4aIg'}, attempt #1 17:53:46 INFO - Task dependencies: evJgljTzQC-5Qt9CU749Qw 17:53:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw'}, attempt #1 17:53:46 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw/artifacts/public/build/target.tar.bz2 17:53:46 INFO - Set test_url: https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw/artifacts/public/build/test_packages.json 17:53:46 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw/artifacts/public/build/target.crashreporter-symbols.zip 17:53:46 INFO - Running post-action listener: _resource_record_post_action 17:53:46 INFO - [mozharness: 2017-01-06 01:53:46.933942Z] Finished read-buildbot-config step (success) 17:53:46 INFO - [mozharness: 2017-01-06 01:53:46.934159Z] Running download-and-extract step. 17:53:46 INFO - Running pre-action listener: _resource_record_pre_action 17:53:46 INFO - Running main action method: download_and_extract 17:53:46 INFO - mkdir: /builds/slave/test/build/tests 17:53:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:53:46 INFO - trying https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw/artifacts/public/build/target.test_packages.json 17:53:46 INFO - Downloading https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:53:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:53:47 INFO - Downloaded 896 bytes. 17:53:47 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:53:47 INFO - Using the following test package requirements: 17:53:47 INFO - {u'common': [u'target.common.tests.zip'], 17:53:47 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:53:47 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:53:47 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:53:47 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:53:47 INFO - u'mozbase': [u'target.common.tests.zip'], 17:53:47 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:53:47 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:53:47 INFO - u'web-platform': [u'target.common.tests.zip', 17:53:47 INFO - u'target.web-platform.tests.zip'], 17:53:47 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:53:47 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 17:53:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw/artifacts/public/build/target.common.tests.zip 17:53:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:53:47 INFO - Fetch https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw/artifacts/public/build/target.common.tests.zip into memory 17:53:50 INFO - Expected file size: 20245540 17:53:50 INFO - Obtained file size: 20245540 17:53:54 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 17:53:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw/artifacts/public/build/target.common.tests.zip 17:53:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:53:54 INFO - Fetch https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw/artifacts/public/build/target.common.tests.zip into memory 17:53:55 INFO - Expected file size: 20245540 17:53:55 INFO - Obtained file size: 20245540 17:53:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw/artifacts/public/build/target.talos.tests.zip 17:53:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:53:59 INFO - Fetch https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw/artifacts/public/build/target.talos.tests.zip into memory 17:54:01 INFO - Expected file size: 14403730 17:54:01 INFO - Obtained file size: 14403730 17:54:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:01 INFO - trying https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw/artifacts/public/build/target.tar.bz2 17:54:01 INFO - Downloading https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:54:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:54:04 INFO - Downloaded 55861260 bytes. 17:54:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw/artifacts/public/build/target.tar.bz2 17:54:04 INFO - mkdir: /builds/slave/test/properties 17:54:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:54:04 INFO - Writing to file /builds/slave/test/properties/build_url 17:54:04 INFO - Contents: 17:54:04 INFO - build_url:https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw/artifacts/public/build/target.tar.bz2 17:54:04 INFO - Running post-action listener: _resource_record_post_action 17:54:04 INFO - Running post-action listener: set_extra_try_arguments 17:54:04 INFO - [mozharness: 2017-01-06 01:54:04.014346Z] Finished download-and-extract step (success) 17:54:04 INFO - [mozharness: 2017-01-06 01:54:04.014574Z] Running populate-webroot step. 17:54:04 INFO - Running pre-action listener: _resource_record_pre_action 17:54:04 INFO - Running main action method: populate_webroot 17:54:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:54:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:54:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:54:04 INFO - '--tppagecycles', 17:54:04 INFO - '7'], 17:54:04 INFO - 'tests': ['tsvgm']}}, 17:54:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:54:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:54:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:54:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:54:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:54:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:54:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:54:04 INFO - 'talos_options': ['--disable-e10s'], 17:54:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:54:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:54:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:54:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:54:04 INFO - 'talos_options': ['--disable-e10s'], 17:54:04 INFO - 'tests': ['damp', 'tps']}, 17:54:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:54:04 INFO - 'tests': ['damp', 'tps']}, 17:54:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:54:04 INFO - 'tests': ['dromaeo_dom']}, 17:54:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:54:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:54:04 INFO - 'tests': ['basic_compositor_video']}, 17:54:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:54:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:54:04 INFO - 'tests': ['a11yr', 17:54:04 INFO - 'ts_paint', 17:54:04 INFO - 'tpaint', 17:54:04 INFO - 'sessionrestore', 17:54:04 INFO - 'sessionrestore_no_auto_restore', 17:54:04 INFO - 'tabpaint']}, 17:54:04 INFO - 'other-e10s': {'tests': ['a11yr', 17:54:04 INFO - 'ts_paint', 17:54:04 INFO - 'tpaint', 17:54:04 INFO - 'sessionrestore', 17:54:04 INFO - 'sessionrestore_no_auto_restore', 17:54:04 INFO - 'tabpaint']}, 17:54:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:54:04 INFO - 'ts_paint', 17:54:04 INFO - 'tpaint', 17:54:04 INFO - 'sessionrestore', 17:54:04 INFO - 'sessionrestore_no_auto_restore', 17:54:04 INFO - 'tabpaint']}, 17:54:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:54:04 INFO - 'ts_paint', 17:54:04 INFO - 'tpaint', 17:54:04 INFO - 'sessionrestore', 17:54:04 INFO - 'sessionrestore_no_auto_restore', 17:54:04 INFO - 'tabpaint']}, 17:54:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:54:04 INFO - 'tests': ['a11yr', 17:54:04 INFO - 'ts_paint', 17:54:04 INFO - 'tpaint', 17:54:04 INFO - 'sessionrestore', 17:54:04 INFO - 'sessionrestore_no_auto_restore', 17:54:04 INFO - 'tabpaint']}, 17:54:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:54:04 INFO - 'tests': ['a11yr', 17:54:04 INFO - 'ts_paint', 17:54:04 INFO - 'tpaint', 17:54:04 INFO - 'sessionrestore', 17:54:04 INFO - 'sessionrestore_no_auto_restore', 17:54:04 INFO - 'tabpaint']}, 17:54:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:54:04 INFO - 'tests': ['tsvgx', 17:54:04 INFO - 'tsvgr_opacity', 17:54:04 INFO - 'tart', 17:54:04 INFO - 'tscrollx', 17:54:04 INFO - 'cart', 17:54:04 INFO - 'tsvg_static']}, 17:54:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:54:04 INFO - 'tsvgr_opacity', 17:54:04 INFO - 'tart', 17:54:04 INFO - 'tscrollx', 17:54:04 INFO - 'cart', 17:54:04 INFO - 'tsvg_static']}, 17:54:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:54:04 INFO - 'talos_options': ['--disable-e10s'], 17:54:04 INFO - 'tests': ['tp5o']}, 17:54:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:54:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:54:04 INFO - 'talos_options': ['--disable-e10s', 17:54:04 INFO - '--xperf_path', 17:54:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:54:04 INFO - 'tests': ['tp5n']}, 17:54:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:54:04 INFO - 'talos_options': ['--xperf_path', 17:54:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:54:04 INFO - 'tests': ['tp5n']}}, 17:54:04 INFO - 'talos.zip': {'path': '', 17:54:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:54:04 INFO - Running post-action listener: _resource_record_post_action 17:54:04 INFO - [mozharness: 2017-01-06 01:54:04.031641Z] Finished populate-webroot step (success) 17:54:04 INFO - [mozharness: 2017-01-06 01:54:04.031735Z] Running create-virtualenv step. 17:54:04 INFO - Running pre-action listener: _resource_record_pre_action 17:54:04 INFO - Running main action method: create_virtualenv 17:54:04 INFO - Creating virtualenv /builds/slave/test/build/venv 17:54:04 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:54:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:54:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:54:04 INFO - Using real prefix '/usr' 17:54:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:54:04 INFO - Installing distribute.............................................................................................................................................................................................done. 17:54:06 INFO - Installing pip.................done. 17:54:06 INFO - Return code: 0 17:54:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:54:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:54:06 INFO - Reading from file tmpfile_stdout 17:54:06 INFO - Output received: 17:54:06 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 17:54:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:54:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1244630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cd850>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12426d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1242b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:54:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:54:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:54:06 INFO - Using env: {'DISPLAY': ':0', 17:54:06 INFO - 'HOME': '/home/cltbld', 17:54:06 INFO - 'LANG': 'en_US.UTF-8', 17:54:06 INFO - 'LANGUAGE': 'en_US:en', 17:54:06 INFO - 'LOGNAME': 'cltbld', 17:54:06 INFO - 'MAIL': '/var/mail/cltbld', 17:54:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:06 INFO - 'MOZ_NO_REMOTE': '1', 17:54:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:54:06 INFO - 'NO_EM_RESTART': '1', 17:54:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:54:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:06 INFO - 'PWD': '/builds/slave/test', 17:54:06 INFO - 'SHELL': '/bin/bash', 17:54:06 INFO - 'SHLVL': '1', 17:54:06 INFO - 'TERM': 'linux', 17:54:06 INFO - 'TMOUT': '86400', 17:54:06 INFO - 'USER': 'cltbld', 17:54:06 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429', 17:54:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:06 INFO - '_': '/tools/buildbot/bin/python'} 17:54:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:07 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:54:07 INFO - Cleaning up... 17:54:07 INFO - Return code: 0 17:54:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:54:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1244630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cd850>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12426d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1242b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:54:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:54:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:54:07 INFO - Using env: {'DISPLAY': ':0', 17:54:07 INFO - 'HOME': '/home/cltbld', 17:54:07 INFO - 'LANG': 'en_US.UTF-8', 17:54:07 INFO - 'LANGUAGE': 'en_US:en', 17:54:07 INFO - 'LOGNAME': 'cltbld', 17:54:07 INFO - 'MAIL': '/var/mail/cltbld', 17:54:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:07 INFO - 'MOZ_NO_REMOTE': '1', 17:54:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:54:07 INFO - 'NO_EM_RESTART': '1', 17:54:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:54:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:07 INFO - 'PWD': '/builds/slave/test', 17:54:07 INFO - 'SHELL': '/bin/bash', 17:54:07 INFO - 'SHLVL': '1', 17:54:07 INFO - 'TERM': 'linux', 17:54:07 INFO - 'TMOUT': '86400', 17:54:07 INFO - 'USER': 'cltbld', 17:54:07 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429', 17:54:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:07 INFO - '_': '/tools/buildbot/bin/python'} 17:54:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:07 INFO - Downloading/unpacking psutil>=3.1.1 17:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:54:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:54:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:54:09 INFO - Installing collected packages: psutil 17:54:09 INFO - Running setup.py install for psutil 17:54:09 INFO - building 'psutil._psutil_linux' extension 17:54:09 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 17:54:09 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 17:54:09 INFO - building 'psutil._psutil_posix' extension 17:54:09 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 17:54:09 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 17:54:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:54:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:54:09 INFO - Successfully installed psutil 17:54:09 INFO - Cleaning up... 17:54:09 INFO - Return code: 0 17:54:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:54:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1244630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cd850>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12426d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1242b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:54:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:54:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:54:09 INFO - Using env: {'DISPLAY': ':0', 17:54:09 INFO - 'HOME': '/home/cltbld', 17:54:09 INFO - 'LANG': 'en_US.UTF-8', 17:54:09 INFO - 'LANGUAGE': 'en_US:en', 17:54:09 INFO - 'LOGNAME': 'cltbld', 17:54:09 INFO - 'MAIL': '/var/mail/cltbld', 17:54:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:09 INFO - 'MOZ_NO_REMOTE': '1', 17:54:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:54:09 INFO - 'NO_EM_RESTART': '1', 17:54:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:54:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:09 INFO - 'PWD': '/builds/slave/test', 17:54:09 INFO - 'SHELL': '/bin/bash', 17:54:09 INFO - 'SHLVL': '1', 17:54:09 INFO - 'TERM': 'linux', 17:54:09 INFO - 'TMOUT': '86400', 17:54:09 INFO - 'USER': 'cltbld', 17:54:09 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429', 17:54:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:09 INFO - '_': '/tools/buildbot/bin/python'} 17:54:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:54:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:54:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:54:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:54:12 INFO - Installing collected packages: mozsystemmonitor 17:54:12 INFO - Running setup.py install for mozsystemmonitor 17:54:12 INFO - Successfully installed mozsystemmonitor 17:54:12 INFO - Cleaning up... 17:54:12 INFO - Return code: 0 17:54:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:54:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1244630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cd850>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12426d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1242b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:54:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:54:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:54:12 INFO - Using env: {'DISPLAY': ':0', 17:54:12 INFO - 'HOME': '/home/cltbld', 17:54:12 INFO - 'LANG': 'en_US.UTF-8', 17:54:12 INFO - 'LANGUAGE': 'en_US:en', 17:54:12 INFO - 'LOGNAME': 'cltbld', 17:54:12 INFO - 'MAIL': '/var/mail/cltbld', 17:54:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:12 INFO - 'MOZ_NO_REMOTE': '1', 17:54:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:54:12 INFO - 'NO_EM_RESTART': '1', 17:54:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:54:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:12 INFO - 'PWD': '/builds/slave/test', 17:54:12 INFO - 'SHELL': '/bin/bash', 17:54:12 INFO - 'SHLVL': '1', 17:54:12 INFO - 'TERM': 'linux', 17:54:12 INFO - 'TMOUT': '86400', 17:54:12 INFO - 'USER': 'cltbld', 17:54:12 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429', 17:54:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:12 INFO - '_': '/tools/buildbot/bin/python'} 17:54:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:12 INFO - Downloading/unpacking jsonschema==2.5.1 17:54:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:54:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:14 INFO - Downloading functools32-3.2.3-2.tar.gz 17:54:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:54:14 INFO - warning: no files found matching '*.txt' 17:54:14 INFO - no previously-included directories found matching 'build' 17:54:14 INFO - no previously-included directories found matching 'dist' 17:54:14 INFO - no previously-included directories found matching '.git*' 17:54:14 INFO - Installing collected packages: jsonschema, functools32 17:54:14 INFO - Running setup.py install for functools32 17:54:14 INFO - warning: no files found matching '*.txt' 17:54:14 INFO - no previously-included directories found matching 'build' 17:54:14 INFO - no previously-included directories found matching 'dist' 17:54:14 INFO - no previously-included directories found matching '.git*' 17:54:15 INFO - Successfully installed jsonschema functools32 17:54:15 INFO - Cleaning up... 17:54:15 INFO - Return code: 0 17:54:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:54:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1244630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cd850>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12426d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1242b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:54:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:54:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:54:15 INFO - Using env: {'DISPLAY': ':0', 17:54:15 INFO - 'HOME': '/home/cltbld', 17:54:15 INFO - 'LANG': 'en_US.UTF-8', 17:54:15 INFO - 'LANGUAGE': 'en_US:en', 17:54:15 INFO - 'LOGNAME': 'cltbld', 17:54:15 INFO - 'MAIL': '/var/mail/cltbld', 17:54:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:15 INFO - 'MOZ_NO_REMOTE': '1', 17:54:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:54:15 INFO - 'NO_EM_RESTART': '1', 17:54:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:54:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:15 INFO - 'PWD': '/builds/slave/test', 17:54:15 INFO - 'SHELL': '/bin/bash', 17:54:15 INFO - 'SHLVL': '1', 17:54:15 INFO - 'TERM': 'linux', 17:54:15 INFO - 'TMOUT': '86400', 17:54:15 INFO - 'USER': 'cltbld', 17:54:15 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429', 17:54:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:15 INFO - '_': '/tools/buildbot/bin/python'} 17:54:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:54:15 INFO - Cleaning up... 17:54:15 INFO - Return code: 0 17:54:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:54:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1244630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cd850>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12426d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1242b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:54:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:54:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:54:15 INFO - Using env: {'DISPLAY': ':0', 17:54:15 INFO - 'HOME': '/home/cltbld', 17:54:15 INFO - 'LANG': 'en_US.UTF-8', 17:54:15 INFO - 'LANGUAGE': 'en_US:en', 17:54:15 INFO - 'LOGNAME': 'cltbld', 17:54:15 INFO - 'MAIL': '/var/mail/cltbld', 17:54:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:15 INFO - 'MOZ_NO_REMOTE': '1', 17:54:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:54:15 INFO - 'NO_EM_RESTART': '1', 17:54:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:54:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:15 INFO - 'PWD': '/builds/slave/test', 17:54:15 INFO - 'SHELL': '/bin/bash', 17:54:15 INFO - 'SHLVL': '1', 17:54:15 INFO - 'TERM': 'linux', 17:54:15 INFO - 'TMOUT': '86400', 17:54:15 INFO - 'USER': 'cltbld', 17:54:15 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429', 17:54:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:15 INFO - '_': '/tools/buildbot/bin/python'} 17:54:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:15 INFO - Downloading/unpacking blobuploader==1.2.4 17:54:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:17 INFO - Downloading blobuploader-1.2.4.tar.gz 17:54:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:54:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:54:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:54:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:18 INFO - Downloading docopt-0.6.1.tar.gz 17:54:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:54:18 INFO - Installing collected packages: blobuploader, requests, docopt 17:54:18 INFO - Running setup.py install for blobuploader 17:54:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:54:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:54:18 INFO - Running setup.py install for requests 17:54:18 INFO - Running setup.py install for docopt 17:54:18 INFO - Successfully installed blobuploader requests docopt 17:54:18 INFO - Cleaning up... 17:54:18 INFO - Return code: 0 17:54:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:54:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1244630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cd850>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12426d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1242b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:54:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:54:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:54:18 INFO - Using env: {'DISPLAY': ':0', 17:54:18 INFO - 'HOME': '/home/cltbld', 17:54:18 INFO - 'LANG': 'en_US.UTF-8', 17:54:18 INFO - 'LANGUAGE': 'en_US:en', 17:54:18 INFO - 'LOGNAME': 'cltbld', 17:54:18 INFO - 'MAIL': '/var/mail/cltbld', 17:54:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:18 INFO - 'MOZ_NO_REMOTE': '1', 17:54:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:54:18 INFO - 'NO_EM_RESTART': '1', 17:54:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:54:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:18 INFO - 'PWD': '/builds/slave/test', 17:54:18 INFO - 'SHELL': '/bin/bash', 17:54:18 INFO - 'SHLVL': '1', 17:54:18 INFO - 'TERM': 'linux', 17:54:18 INFO - 'TMOUT': '86400', 17:54:18 INFO - 'USER': 'cltbld', 17:54:18 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429', 17:54:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:18 INFO - '_': '/tools/buildbot/bin/python'} 17:54:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:54:19 INFO - Running setup.py (path:/tmp/pip-gkHKz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:54:19 INFO - Running setup.py (path:/tmp/pip-0di4Cs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:54:19 INFO - Running setup.py (path:/tmp/pip-CpEXhF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:54:19 INFO - Running setup.py (path:/tmp/pip-Bc94ll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:54:19 INFO - Running setup.py (path:/tmp/pip-XmcNy6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:54:19 INFO - Running setup.py (path:/tmp/pip-pCvsWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:54:19 INFO - Running setup.py (path:/tmp/pip-jyR86U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:54:19 INFO - Running setup.py (path:/tmp/pip-oGNJFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:54:19 INFO - Running setup.py (path:/tmp/pip-oUBzo8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:54:19 INFO - Running setup.py (path:/tmp/pip-iCAWVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:54:19 INFO - Running setup.py (path:/tmp/pip-MEAPEl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:54:19 INFO - Running setup.py (path:/tmp/pip-iUMcbq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:54:20 INFO - Running setup.py (path:/tmp/pip-3D0U7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:54:20 INFO - Running setup.py (path:/tmp/pip-jmDLja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:54:20 INFO - Running setup.py (path:/tmp/pip-LzfW1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:54:20 INFO - Running setup.py (path:/tmp/pip-RHwGi2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:54:20 INFO - Running setup.py (path:/tmp/pip-fVN4Iq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:54:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:54:20 INFO - Running setup.py install for manifestparser 17:54:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:54:20 INFO - Running setup.py install for mozcrash 17:54:20 INFO - Running setup.py install for mozdebug 17:54:20 INFO - Running setup.py install for mozdevice 17:54:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:54:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:54:20 INFO - Running setup.py install for mozfile 17:54:20 INFO - Running setup.py install for mozhttpd 17:54:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:54:21 INFO - Running setup.py install for mozinfo 17:54:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:54:21 INFO - Running setup.py install for mozInstall 17:54:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:54:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:54:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:54:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:54:21 INFO - Running setup.py install for mozleak 17:54:21 INFO - Running setup.py install for mozlog 17:54:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:54:21 INFO - Running setup.py install for moznetwork 17:54:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:54:21 INFO - Running setup.py install for mozprocess 17:54:21 INFO - Running setup.py install for mozprofile 17:54:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:54:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:54:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:54:21 INFO - Running setup.py install for mozrunner 17:54:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:54:21 INFO - Running setup.py install for mozscreenshot 17:54:21 INFO - Running setup.py install for moztest 17:54:22 INFO - Running setup.py install for mozversion 17:54:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:54:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:54:22 INFO - Cleaning up... 17:54:22 INFO - Return code: 0 17:54:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:54:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1244630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cd850>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12426d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1242b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:54:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:54:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:54:22 INFO - Using env: {'DISPLAY': ':0', 17:54:22 INFO - 'HOME': '/home/cltbld', 17:54:22 INFO - 'LANG': 'en_US.UTF-8', 17:54:22 INFO - 'LANGUAGE': 'en_US:en', 17:54:22 INFO - 'LOGNAME': 'cltbld', 17:54:22 INFO - 'MAIL': '/var/mail/cltbld', 17:54:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:22 INFO - 'MOZ_NO_REMOTE': '1', 17:54:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:54:22 INFO - 'NO_EM_RESTART': '1', 17:54:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:54:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:22 INFO - 'PWD': '/builds/slave/test', 17:54:22 INFO - 'SHELL': '/bin/bash', 17:54:22 INFO - 'SHLVL': '1', 17:54:22 INFO - 'TERM': 'linux', 17:54:22 INFO - 'TMOUT': '86400', 17:54:22 INFO - 'USER': 'cltbld', 17:54:22 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429', 17:54:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:22 INFO - '_': '/tools/buildbot/bin/python'} 17:54:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:54:22 INFO - Running setup.py (path:/tmp/pip-aoKoch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:54:22 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:54:22 INFO - Running setup.py (path:/tmp/pip-VhNn19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:54:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:54:22 INFO - Running setup.py (path:/tmp/pip-yyN5_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:54:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:54:22 INFO - Running setup.py (path:/tmp/pip-g77E46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:54:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:54:22 INFO - Running setup.py (path:/tmp/pip-wsULkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:54:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 17:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:54:22 INFO - Running setup.py (path:/tmp/pip-4m02Zc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:54:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 17:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:54:22 INFO - Running setup.py (path:/tmp/pip-3CFFWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:54:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:54:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:54:22 INFO - Running setup.py (path:/tmp/pip-IkM4rR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:54:23 INFO - Running setup.py (path:/tmp/pip-VodzDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:54:23 INFO - Running setup.py (path:/tmp/pip-xMfchi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:54:23 INFO - Running setup.py (path:/tmp/pip-e9Ighc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:54:23 INFO - Running setup.py (path:/tmp/pip-tEHLd7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:54:23 INFO - Running setup.py (path:/tmp/pip-wTjHyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:54:23 INFO - Running setup.py (path:/tmp/pip-hc0avO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:54:23 INFO - Running setup.py (path:/tmp/pip-OSA9Qe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:54:23 INFO - Running setup.py (path:/tmp/pip-166Kx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:54:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:54:23 INFO - Running setup.py (path:/tmp/pip-DBOrCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:54:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:54:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:54:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:25 INFO - Downloading blessings-1.6.tar.gz 17:54:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:54:25 INFO - Installing collected packages: blessings 17:54:25 INFO - Running setup.py install for blessings 17:54:25 INFO - Successfully installed blessings 17:54:25 INFO - Cleaning up... 17:54:25 INFO - Return code: 0 17:54:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:54:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:54:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:54:26 INFO - Reading from file tmpfile_stdout 17:54:26 INFO - Current package versions: 17:54:26 INFO - argparse == 1.2.1 17:54:26 INFO - blessings == 1.6 17:54:26 INFO - blobuploader == 1.2.4 17:54:26 INFO - docopt == 0.6.1 17:54:26 INFO - functools32 == 3.2.3-2 17:54:26 INFO - jsonschema == 2.5.1 17:54:26 INFO - manifestparser == 1.1 17:54:26 INFO - mozInstall == 1.12 17:54:26 INFO - mozcrash == 1.0 17:54:26 INFO - mozdebug == 0.1 17:54:26 INFO - mozdevice == 0.49 17:54:26 INFO - mozfile == 1.2 17:54:26 INFO - mozhttpd == 0.7 17:54:26 INFO - mozinfo == 0.9 17:54:26 INFO - mozleak == 0.1 17:54:26 INFO - mozlog == 3.4 17:54:26 INFO - moznetwork == 0.27 17:54:26 INFO - mozprocess == 0.23 17:54:26 INFO - mozprofile == 0.28 17:54:26 INFO - mozrunner == 6.13 17:54:26 INFO - mozscreenshot == 0.1 17:54:26 INFO - mozsystemmonitor == 0.3 17:54:26 INFO - moztest == 0.8 17:54:26 INFO - mozversion == 1.4 17:54:26 INFO - psutil == 3.1.1 17:54:26 INFO - requests == 1.2.3 17:54:26 INFO - wsgiref == 0.1.2 17:54:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:54:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1244630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cd850>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12426d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1242b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:54:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:54:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:54:26 INFO - Using env: {'DISPLAY': ':0', 17:54:26 INFO - 'HOME': '/home/cltbld', 17:54:26 INFO - 'LANG': 'en_US.UTF-8', 17:54:26 INFO - 'LANGUAGE': 'en_US:en', 17:54:26 INFO - 'LOGNAME': 'cltbld', 17:54:26 INFO - 'MAIL': '/var/mail/cltbld', 17:54:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:26 INFO - 'MOZ_NO_REMOTE': '1', 17:54:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:54:26 INFO - 'NO_EM_RESTART': '1', 17:54:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:54:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:26 INFO - 'PWD': '/builds/slave/test', 17:54:26 INFO - 'SHELL': '/bin/bash', 17:54:26 INFO - 'SHLVL': '1', 17:54:26 INFO - 'TERM': 'linux', 17:54:26 INFO - 'TMOUT': '86400', 17:54:26 INFO - 'USER': 'cltbld', 17:54:26 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429', 17:54:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:26 INFO - '_': '/tools/buildbot/bin/python'} 17:54:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:54:26 INFO - Cleaning up... 17:54:26 INFO - Return code: 0 17:54:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:54:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1244630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cd850>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12426d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1242b60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 17:54:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:54:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:54:26 INFO - Using env: {'DISPLAY': ':0', 17:54:26 INFO - 'HOME': '/home/cltbld', 17:54:26 INFO - 'LANG': 'en_US.UTF-8', 17:54:26 INFO - 'LANGUAGE': 'en_US:en', 17:54:26 INFO - 'LOGNAME': 'cltbld', 17:54:26 INFO - 'MAIL': '/var/mail/cltbld', 17:54:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:26 INFO - 'MOZ_NO_REMOTE': '1', 17:54:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:54:26 INFO - 'NO_EM_RESTART': '1', 17:54:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:54:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:26 INFO - 'PWD': '/builds/slave/test', 17:54:26 INFO - 'SHELL': '/bin/bash', 17:54:26 INFO - 'SHLVL': '1', 17:54:26 INFO - 'TERM': 'linux', 17:54:26 INFO - 'TMOUT': '86400', 17:54:26 INFO - 'USER': 'cltbld', 17:54:26 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429', 17:54:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:26 INFO - '_': '/tools/buildbot/bin/python'} 17:54:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:54:26 INFO - Cleaning up... 17:54:26 INFO - Return code: 0 17:54:26 INFO - Running post-action listener: _resource_record_post_action 17:54:26 INFO - Running post-action listener: _start_resource_monitoring 17:54:26 INFO - Starting resource monitoring. 17:54:26 INFO - [mozharness: 2017-01-06 01:54:26.657731Z] Finished create-virtualenv step (success) 17:54:26 INFO - [mozharness: 2017-01-06 01:54:26.658237Z] Running install step. 17:54:26 INFO - Running pre-action listener: _resource_record_pre_action 17:54:26 INFO - Running main action method: install 17:54:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:54:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:54:26 INFO - Reading from file tmpfile_stdout 17:54:26 INFO - Detecting whether we're running mozinstall >=1.0... 17:54:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:54:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:54:26 INFO - Reading from file tmpfile_stdout 17:54:26 INFO - Output received: 17:54:26 INFO - Usage: mozinstall [options] installer 17:54:26 INFO - Options: 17:54:26 INFO - -h, --help show this help message and exit 17:54:26 INFO - -d DEST, --destination=DEST 17:54:26 INFO - Directory to install application into. [default: 17:54:26 INFO - "/builds/slave/test"] 17:54:26 INFO - --app=APP Application being installed. [default: firefox] 17:54:26 INFO - mkdir: /builds/slave/test/build/application 17:54:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:54:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:54:43 INFO - Reading from file tmpfile_stdout 17:54:43 INFO - Output received: 17:54:43 INFO - /builds/slave/test/build/application/firefox/firefox 17:54:43 INFO - Running post-action listener: _resource_record_post_action 17:54:43 INFO - [mozharness: 2017-01-06 01:54:43.708918Z] Finished install step (success) 17:54:43 INFO - [mozharness: 2017-01-06 01:54:43.709198Z] Running run-tests step. 17:54:43 INFO - Running pre-action listener: _resource_record_pre_action 17:54:43 INFO - Running main action method: run_tests 17:54:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:54:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:54:43 INFO - Python 2.7.3 17:54:43 INFO - Return code: 0 17:54:43 INFO - grabbing minidump binary from tooltool 17:54:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:54:43 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12cd850>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12426d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1242b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:54:43 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:54:43 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 17:54:43 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:54:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:54:43 INFO - Return code: 0 17:54:43 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:54:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:54:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:54:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:54:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:54:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 17:54:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-008 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 17:54:43 INFO - Using env: {'DISPLAY': ':0', 17:54:43 INFO - 'HOME': '/home/cltbld', 17:54:43 INFO - 'LANG': 'en_US.UTF-8', 17:54:43 INFO - 'LANGUAGE': 'en_US:en', 17:54:43 INFO - 'LOGNAME': 'cltbld', 17:54:43 INFO - 'MAIL': '/var/mail/cltbld', 17:54:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:54:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:54:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:43 INFO - 'MOZ_NO_REMOTE': '1', 17:54:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:54:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:54:43 INFO - 'NO_EM_RESTART': '1', 17:54:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:54:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:43 INFO - 'PWD': '/builds/slave/test', 17:54:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:54:43 INFO - 'SHELL': '/bin/bash', 17:54:43 INFO - 'SHLVL': '1', 17:54:43 INFO - 'TERM': 'linux', 17:54:43 INFO - 'TMOUT': '86400', 17:54:43 INFO - 'USER': 'cltbld', 17:54:43 INFO - 'XDG_SESSION_COOKIE': '32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429', 17:54:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:43 INFO - '_': '/tools/buildbot/bin/python'} 17:54:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-008', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 17:54:43 INFO - mozversion application_buildid: 20170106012421 17:54:43 INFO - mozversion application_changeset: 53193729a50b40c00889b1fa07df8dffab7781f9 17:54:43 INFO - mozversion application_display_name: Nightly 17:54:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:54:43 INFO - mozversion application_name: Firefox 17:54:43 INFO - mozversion application_remotingname: firefox 17:54:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:54:43 INFO - mozversion application_vendor: Mozilla 17:54:43 INFO - mozversion application_version: 53.0a1 17:54:43 INFO - mozversion platform_buildid: 20170106012421 17:54:43 INFO - mozversion platform_changeset: 53193729a50b40c00889b1fa07df8dffab7781f9 17:54:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:54:43 INFO - mozversion platform_version: 53.0a1 17:54:43 INFO - using testdate: 1483667683 17:54:43 INFO - actual date: 1483667683 17:54:43 INFO - starting webserver on 'localhost:44790' 17:54:43 INFO - SUITE-START | Running 2 tests 17:54:43 INFO - TEST-START | dromaeo_css 17:54:43 INFO - operating with platform_type : linux_ 17:54:43 INFO - Initialising browser for dromaeo_css test... 17:54:44 INFO - TEST-INFO | started process 18503 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYBndw2/profile http://localhost:44790/getInfo.html) 17:54:44 INFO - PROCESS | 18503 | 1483667684487 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 17:54:48 INFO - PROCESS | 18503 | __metrics Screen width/height:1600/1200 17:54:48 INFO - PROCESS | 18503 | colorDepth:24 17:54:48 INFO - PROCESS | 18503 | Browser inner width/height: 1024/697 17:54:48 INFO - PROCESS | 18503 | __metrics 17:54:48 INFO - PROCESS | 18503 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:54:48 INFO - PROCESS | 18503 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:54:49 INFO - PROCESS | 18503 | console.error: PushService: 17:54:49 INFO - PROCESS | 18503 | stateChangeProcessEnqueue: Error transitioning state 17:54:49 INFO - PROCESS | 18503 | UnknownError 17:54:49 INFO - TEST-INFO | 18503: exit 0 17:54:49 INFO - Browser initialized. 17:54:49 INFO - Running cycle 1/1 for dromaeo_css test... 17:54:49 INFO - TEST-INFO | started process 18587 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYBndw2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:55:01 INFO - PROCESS | 18587 | RSS: Main: 150941696 17:55:01 INFO - PROCESS | 18587 | 17:58:07 INFO - PROCESS | 18587 | Cycle 1(1): loaded http://localhost:44790/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44790/tests/dromaeo/cssquery-ext.html) 17:58:09 INFO - PROCESS | 18587 | RSS: Main: 270311424 17:58:09 INFO - PROCESS | 18587 | 17:59:50 INFO - PROCESS | 18587 | ************************* 17:59:50 INFO - PROCESS | 18587 | A coding exception was thrown and uncaught in a Task. 17:59:50 INFO - PROCESS | 18587 | 17:59:50 INFO - PROCESS | 18587 | Full message: ReferenceError: fetch is not defined 17:59:50 INFO - PROCESS | 18587 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 17:59:50 INFO - PROCESS | 18587 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 17:59:50 INFO - PROCESS | 18587 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 17:59:50 INFO - PROCESS | 18587 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 17:59:50 INFO - PROCESS | 18587 | TaskImpl@resource://gre/modules/Task.jsm:277:3 17:59:50 INFO - PROCESS | 18587 | asyncFunction@resource://gre/modules/Task.jsm:252:14 17:59:50 INFO - PROCESS | 18587 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 17:59:50 INFO - PROCESS | 18587 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 17:59:50 INFO - PROCESS | 18587 | TaskImpl@resource://gre/modules/Task.jsm:277:3 17:59:50 INFO - PROCESS | 18587 | asyncFunction@resource://gre/modules/Task.jsm:252:14 17:59:50 INFO - PROCESS | 18587 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 17:59:50 INFO - PROCESS | 18587 | 17:59:50 INFO - PROCESS | 18587 | ************************* 18:01:11 INFO - PROCESS | 18587 | Cycle 1(1): loaded http://localhost:44790/tests/dromaeo/cssquery-ext.html (next: http://localhost:44790/tests/dromaeo/cssquery-jquery.html) 18:01:12 INFO - PROCESS | 18587 | RSS: Main: 237977600 18:01:12 INFO - PROCESS | 18587 | 18:04:14 INFO - PROCESS | 18587 | Cycle 1(1): loaded http://localhost:44790/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44790/tests/dromaeo/cssquery-mootools.html) 18:04:15 INFO - PROCESS | 18587 | RSS: Main: 186363904 18:04:15 INFO - PROCESS | 18587 | 18:07:17 INFO - PROCESS | 18587 | Cycle 1(1): loaded http://localhost:44790/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44790/tests/dromaeo/cssquery-prototype.html) 18:07:18 INFO - PROCESS | 18587 | RSS: Main: 185425920 18:07:18 INFO - PROCESS | 18587 | 18:10:21 INFO - PROCESS | 18587 | Cycle 1(1): loaded http://localhost:44790/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44790/tests/dromaeo/cssquery-yui.html) 18:10:22 INFO - PROCESS | 18587 | RSS: Main: 182870016 18:10:22 INFO - PROCESS | 18587 | 18:13:25 INFO - PROCESS | 18587 | Cycle 1(1): loaded http://localhost:44790/tests/dromaeo/cssquery-yui.html (next: http://localhost:44790/tests/dromaeo/cssquery-dojo.html) 18:13:25 INFO - PROCESS | 18587 | RSS: Main: 268738560 18:13:25 INFO - PROCESS | 18587 | 18:13:25 INFO - PROCESS | 18587 | __start_tp_report 18:13:25 INFO - PROCESS | 18587 | _x_x_mozilla_page_load 18:13:25 INFO - PROCESS | 18587 | _x_x_mozilla_page_load_details 18:13:25 INFO - PROCESS | 18587 | |i|pagename|runs| 18:13:25 INFO - PROCESS | 18587 | |0;dojo.html;2573.2023072847414;2583.237944806282;2587.1333103410357;2609.2302770682645;2616.6205900144478;4692.085043416527;4740.217864052432;4747.525247475251;4758.52414758524;4798.01640663664;4788.257820037891;4802.663813533054;4804.879878003052;4805.759712014403;4816.91619385638;4374.671899607534;4394.187075391047;4413.117376524692;4451.977740111295;4456.442944631921;4398.208322501949;4416.447944006999;4428.003699167693;4431.26884064129;4435.866923992286;4418.955810441887;4422.270325396305;4430.579094985971;4430.933535996963;4431.800568974388;4384.495782984965;4419.889502762425;4425.203463376591;4431.578999994996;4434.046679963791;4361.738295702236;4429.247028005247;4434.224010798098;4438.933415998763;4465.084657645208;4804.711717296974;4828.275758636232;4846.297286893416;4876.902461950776;4928.6303527235605;5037.49625037497;5137.409197942247;5152.974235128838;5153.7070524411965;5209.114450543417;5013.147764879979;5080.364954380702;5084.3644544431945;5132.255822905695;5137.434882162922;4812.205986012339;4827.637927155477;4830.009847981156;4873.02539492103;4877.829275975324;5294.100002999499;5296.947030529645;5298.629095963245;5300.655457395257;5301.3373328333955;4938.185199442052;4987.251912213124;5015.648904576645;5057.898842023139;5073.568746656501;5179.922301165486;5185.948140518546;5186.455422180692;5214.32213812202;5236.842894713165;5134.101532231844;5145.305383773218;5147.2279158126585;5147.897042059138;5184.766685499161;4936.6297527685565;4975.601951843843;5025.447200807908;5124.795008199705;5140.820071297487;4368.7815609219415;4376.256036473807;4381.058072514425;4417.403650507141;4425.911481770347;3764.0401697566945;3765.962340376616;3771.26460340231;3797.8290976906105;3804.1250512382308;4320.567943205717;4350.412694286231;4420.160169567772;4429.424174857248;4432.867013989586;5093.770780314895;5102.795888164506;5104.642675012714;5128.8461346159675;5143.27994080829;4386.627136693417;4406.096750166208;4408.889777755582;4412.514623391488;4459.241928872099;4543.54564543543;4557.840475583339;4594.793234304398;4605.792739326671;4622.468416132053;4795.304680821331;4804.615630749601;4825.131476334293;4875.439324477588;4886.511348865085;5168;5175.042617115846;5177.404598471224;5178.352705911761;5232.1628539433505;4709.646776491708;4761.119192949315;4835;4845.5881250094135;4884.48712885142;5028.924566131438;5053.722045287397;5060.949390506195;5061.4179369373;5110.029094472038;5147.459516750687;5149.124648809786;5156.716380599103;5160.767765450639;5198.74006299676;4875;4985.077760614298;4988.351514303117;4988.850334489971;5003.974980125076;4898.167311557115;5023.246513023076;5054;5068.366454193226;5075.086484432837;4951.653384263067;4964.379452568429;4974.104661161025;4979.178435558238;4981.128302547112;5072.134698012331;5074.416442109057;5077.517635824591;5079.187330027178;5080.593552515864;4789.568938795525;4887.731174785421;4897.2654101885;4941.75291235444;4949.529794669501 18:13:25 INFO - PROCESS | 18587 | |1;ext.html;528.6484487815603;560.4563573333864;561.9887602247954;562.1455387810528;571.9942800571998;30037.849810750944;39500.60499395005;41700.37449438257;41786.373204401956;46239;42343;42863.14273714524;42942.35586466198;42955;42980.785096074476;7425.702971881118;7739.496932699385;7929.96035019827;7940.761777146695;7945.642446089911;7403.9629801850915;9972.850407243897;10205.285630005903;10238.897611023905;10566.20753443491;10149.441780702058;10186.949065254663;10204.948975255113;10214.846777298346;10235.283530152892;10137.391756494597;10232;10233.079022887901;10238.89761102387;10245.79508409835;10001.549930253155;10076.647317343857;10077.44574048427;10083.44541050242;10090.142337901287;38610.42084368737;38709.61290387088;38713.80643096795;38722.41916371257;38932.22135557301;3418.1041793457193;3683.37382644951;3695.131644063643;3719.3863012602887;3724.6647801697704;29121.56317655237;29205.41589168205;29333.26666833325;29698;30789.538156927665;3191.4414977379097;3191.808491490495;3203.775735698502;3211.486162214058;3216.115568218735;251837;252612;252675;252764.73617631977;253103;49453.50546494489;49504.75247623811;49530.75234623804;49534.50465495371;49640.75179624079;57745.13382299269;57770.778652668414;57782.42217577854;57795.55511112256;57811.42188578145;12821.1025228235;13065.542706005244;13119.868801312055;13123.146995445457;13126.868731312756;9022.954885225532;9377.249820014382;10305;11513.136514761427;11550.653480395602;15747;16212.189390530426;16263.268152933146;16290.674186516204;16319.265633046542;6509.381608747161;6605.273419923805;6615.4046135848;6631.734730610721;6636.767713130017;805.7421625079918;812.3501199040744;813.7876898465339;824.2581676491205;844.1178967978477;27709.307267318076;27804.86097569499;27828.582571261446;28082.945069887206;28291.434171316458;920.5351297594672;954.1269738189479;958.393170576032;959.9184069354183;965.5027660754716;13696.315184240748;14579.781303280459;14822.851771482363;14901.70196596062;14983;365940;366393;367216;367712;367815;52558.474415256125;52560;52675;52686;52776;1227.3311045480234;1251.6870782304425;1261.9810702839466;1271.2436100520176;1275.590472527862;25941.35146621274;26736.465270694087;26770.724488418702;27412.45175096527;27729.445411092067;42690;43251.78374108109;43284.567154328055;43288.99999999874;43331.566684332756;304789;307650;307711;307746;308252;31741.5238771414;36818.263634726616;37637.435438468994;37929;40799.1840163201;42218;42607.57392426036;42682.35976460294;42944.57055429531;42957;36083.81958090193;36609;36973;37039.8148009269;37488.81255593814;35590.46614300736;36220.456693150154;36440.06797960548;36463.27073458569;36806.26387472289;34685.65314346824;34988.857688195065;34994.82502587471;35141;35251.82374088113;30848.69151308548;32365;32432.83783581067;32454.651770384742;32611;24575.385615359042;24706;24764.752352476964;24981.375465613506;25182.74817251804 18:13:25 INFO - PROCESS | 18587 | |2;jquery.html;335.72135127843893;358.5500197252448;360.6123417326375;361.10986418478444;381.57835591671204;13287.800682989751;17905;19373.418797436065;20266;20417.489562760937;17336.393226237084;18420.815791842077;18902.93586878821;19017.714734278998;19513.707294390595;5876.764929402819;6081.3006504252135;6120.693965301717;6122.724477398528;6128.60164089335;5925.407459254083;6007.4593286604195;6858.725650973955;7140.357367836894;7180.28197180283;6964.895526567106;7308.1595616504255;7309;7314.049173607424;7316.451266155033;7332.633368331562;7433.628318584049;7438.925610743904;7440.404767618577;7451.031365922449;7074.575525468488;7152.284771522806;7230.276972302728;7241.420686345133;7245.130584329914;24352.878235608885;24563.877180614163;24844.130455434155;24940.750592494023;25062.37344066413;2393.4495066134887;2451.0685939343116;2452.8773561321864;2473.5918573435542;2557.194483737617;14874.435932939594;15151.848481515264;15706;15908.76136857948;16179.595510112224;1818.0910044453403;1878.234459607978;1910.1022519415853;1916.1377380178976;1917.4151883675613;92834;95231.52384238104;95908;97742.022579774;99179;18063.63872722538;18106.366277180234;18193.81806181948;18249.087545622664;18644.440666780018;32803.34393312121;33452.49821252683;33631.318434078195;33634.32731345409;33674.49488257678;9028.548572571477;9060.776795132704;9069.31980101495;9330.720078397524;9391.389559678599;7324.597147156957;7473.85052298951;7650.846983060308;7653.6938522459595;7667.578283194478;9033.683821066232;9127.68053118151;9340.34617576763;9471.147596716328;9495.891583594166;3209.6790320967716;3580.7851528907827;3617.8914632561064;3633.981830090833;3709.922443431687;803.3613277444446;831;840.6259214649544;843.7932706486949;858.2104463893234;17372;18317.450476485727;18328.35850745244;18755.156017979225;18823.247070117315;807.9232472915064;817.8650522663813;818.337146911004;823.5882058970515;836.3100442135262;9793.90206097944;9868.30921835479;10146.543405546769;10149.797004060025;10234.232432567587;483555;488188;488846;498891;500800;75246;80561;97526.51236743771;97609;99450.50274748579;910.8087301666592;919.4391421233042;959.3476436023572;959.3811991265734;961.6345788600287;16333.591660208589;16701.5824604381;17135.22891469862;17164.485065448058;17289.009645697824;39252.803735981135;39568.40647390234;40039.1992160161;40382.596174039056;40454.393184101675;52668.473315266354;57046.7147664259;57263.42736572581;57937.4206257932;58674;7762.844743105219;7838.764837054898;7879.803004924694;7964.044314682275;8049.637766300648;10066.547005384922;11796.29222246668;11940.04479641644;12090.818637720266;12614.180078294881;9848.310618256939;10196.235282353704;10428.113610343205;10595.205359598214;10830.18773591968;9884.604615815288;10023.799524009624;10248.743781405525;10314.484275786332;10664.946675266574;9880.901190987997;10263.332883362558;10296.17630589566;10459;10481.528331225265;7164.247753985965;7200;7238.0952380952385;7302.598357090411;7601.429892758176;8270.545120018456;10012.499375031364;10023.097921186836;10080.69757907264;10559.419231942009 18:13:25 INFO - PROCESS | 18587 | |3;mootools.html;1193.397334346155;1320.7556602028626;1331.1879753350454;1345.925974071426;1347.615929460104;6312.77905273315;6737.427318677912;6964.477664175181;6972.755953541627;7145.821354466128;7004.038869116655;7080.893786593192;7087.114110736158;7089.574625522459;7092.255313192118;1804.7563578916875;1878.4834170603058;2061.711360409544;2105.473631592102;2106.399676092314;1999.3701983875105;2073.2536286936693;2082.5834833033377;2096.287262330807;2100.4853810816294;2057.742782152231;2060.484878780305;2067.4934641012974;2068.803463670949;2069.2757534862753;2005.3081686818025;2043.867148635334;2045.8874761888017;2047.703083052949;2048.5902819436023;1979.0599465254033;1983.2959299448632;2011.155314767801;2043.9284625038201;2082.9270975515783;7115;7161.928380716178;7184.137903451567;7188.101487314086;7192.460565457612;4252.702310838243;4354.804033818486;4362.738235705836;4373.978130109361;4378.6934914555995;2634.841909485437;2752.4907892039837;2814.7326004029583;2833.957490637642;2844.445333160035;1009.8182327181105;1027.4965267019181;1059.9947000265026;1094.8084085285043;1096.6052221200362;9178.311626627963;9219.400738951947;9228.630854765868;9233.676821311223;9256.166944974984;3689.9631003690156;3877.418387241936;3906.7069969752383;3943.921121577552;3958.5645578986287;8037;8044;8046.839063218703;8047.034355877332;8048.356131509465;4416.24923762961;4421.049474363027;4442.933355999663;4456.9331460028125;4463.308187230984;3620.402633565485;3661.2494438640015;4052.0477687743355;4053.7365071270274;4054.533728621246;3223.629282632527;3277.1807048237943;3338.432466480704;3353.111425472252;3376.2572234108457;1099.5381939585393;1105.9391733454577;1118.2395970739974;1120.1263014848432;1121.2823792772633;686.7012849410522;696.1785093589511;706.427793487277;720.3049057659384;736.1093077376328;4800.591949684316;4839;4886.902261954741;4955.281484184773;4970.204767237225;851.5571902610608;873.3842640938124;886.1979908183105;898.8876390451194;904.439247666451;3284.392387408337;3359.983200083984;3414.146463384154;3445.8621655134016;3454.0674018014997;8126.39052071097;8286.171382861665;8375.45559538628;8376.706815261437;8390.077091519928;7792;8276;8287.419880608299;8311.376646751518;8339.95830020846;6476.6075293812055;6627.602343859384;6629.403353698191;6636.30318816527;6648.03603477493;4805.55015624768;4931.3589233399425;4952.851414457572;5090.7200103993155;5189.532942035235;7662.310392064741;7679.116901556169;7773.844523109393;7895.447318687637;7905.367570594223;7824.765257042298;7874.291313781787;7920.801979950547;7925.920740792519;7927;6067.848303792441;6201.534884883562;6203.627782333075;6210.254769427697;6210.937890621217;6102.603330783485;6341.365863413622;6350.523710721806;6353.7140828661895;6606.900896486461;5960.374160713062;5993.220881285405;6019.4883434908525;6030.125631783455;6031.909521357095;5957.463828255478;6005.279366476051;6038.124471951454;6157.599756015845;6159.81520554384;5392.865178370572;5483.369412517452;5531.170324451365;5531.585131115102;5551.250581171652;4107.507099148122;4109.527404348419;4207.4319966805315;4236.301010333384;4307.418498502662;4007.218592374459;4023;4061.5329237136925;4083;4150.211459822624 18:13:25 INFO - PROCESS | 18587 | |4;prototype.html;319.7809500492163;324.30922135850636;325.14649046253584;328.5203602739999;335.64757005144594;4734.73958932259;4806.9519304806945;4820.758962051901;4827.300041493986;4842.467328593856;4779.928301075478;4799.664023518355;4800.1599720048935;4804.639652026094;4808.879778005552;3661.359262129123;3671.3575124353265;3748.5314335708035;3762.774233545996;3842.5773164951975;3830.7893065881367;3848.5766565677754;3849.1531862990096;3861.691064714816;3876.6898648108086;3822.57951625321;3828.7894165820867;3831.655151036406;3833.6933045356445;3837;3674.3018826423026;3749.456328832331;3788.8065259443324;3809.066778639251;3839.2705385976574;3382;3410.556627638416;3416.9487457688156;3424.4520876659863;3426.468897320919;4673.906521869543;4693.88265293367;4699.412573428322;4699.812007519695;4719.339292499053;4001.8399264029404;4223.556526564697;4392;4394.846180383671;4398.3842262083335;5007.949920500785;5012.924806127911;5020.548150666421;5030.723310217936;5040.546350828443;3554.5201397811484;3556.1643013891717;3561;3561.8397172127316;3569.161247106928;5069.771860266297;5218.817341393071;5224.294720212761;5243.397009343898;5254.21186821976;3562.5012498250267;3590.389633762267;3597.730170237243;3603.1352475405715;3607.9693057831546;3623.3840247158046;3643.8178091095338;3644;3658.432942893856;3661.4690869823726;4198.5591512891315;4210.263203939298;4211.957880421157;4221.535631080578;4228.492580890252;4913.484084171182;5035.1048147314705;5064.012517559041;5077.289179514871;5087.542121209109;1233.4387853526623;1235.6540168752763;1237.7091383524864;1239.4174737873186;1243.8693937136652;802.4984384759525;806.846699127164;838.9706360277361;845.1928408369992;847.3898792869124;4191.015111448807;4352.325389564622;4412.382266482695;4433.431087974147;4444.266695995125;5157.091876005902;5164.1995490699;5169.25045868347;5173;5178.239398604493;4421.0547359161865;4429.585875697752;4439.267520859022;4444.088961762831;4455.331700244989;2532.5568025595817;2652.9388244701886;2686.2478506018347;2697.9460410792067;2699.986500067487;4700.626183777239;4706.505816889177;4733.9763301183275;4740.834070807505;4745.264484004984;4919.483454237254;5178;5183.81856635016;5188.584913206934;5195.870103247449;4662.906741865144;4687.742174180385;4726.747411935771;4731.195696731564;4732.692374995683;5063.974680126576;5065.56942659878;5075.467075956972;5085.321843791614;5087.8963832063155;4785.928211076767;4942.640773787237;4958.892470699866;5001.899962000667;5008.974955125201;4831.396075490564;4851.151048566416;4865.829695960624;4874.4150701916;4890.437599676083;3630.582483014487;3642.162302670348;3661.9816900915325;3676.852925882935;3696.1498855262903;5073.518015788494;5229.424763275961;5237.895242095213;5241.895162096813;5250.763715632882;5008.348914641073;5018.573421259234;5044.369453818273;5044.974775126101;5057.468965758542;5013.674111182761;5026.73796791438;5039.899202016012;5051.898962020812;5053.620978426707;4800.591949684316;4990.15167421532;4994.152163699455;4996.6252531061045;5021.44764075944;4893.632977526629;4905.264210368473;5002.699838009731;5066.518680725325;5092.796288148581;4871.123197824426;4897.233063319213;4908.312836203006;4916.385451818523;4916.606671466346 18:13:25 INFO - PROCESS | 18587 | |5;yui.html;618.1284389011495;636.9840753981152;642.3640595810148;652.941235288824;656.6510794945777;11504.942475287622;11796.997255233315;11962.880371196296;11974.221675591081;12206.328651924141;11788.823167652492;12038.699032524191;12068.24178460063;12121.33332666703;12128.757424851497;786.6342150899831;803.6664784114586;803.7789607857827;803.8111043904678;804.0873608454417;656.4551422319487;684.5618803965466;784.654751909161;808.5876203136384;812.0580127052621;867.0505796153202;869.7955980344614;870.1342164546286;870.7387783664907;871.7646235516406;863.6459051788736;863.8617821148586;863.952482613456;864.5849992003873;864.844328020956;501.69145975225337;518.1295423688202;603.499095750526;615.5106690181317;631.5926227583229;12034.33811140387;12113.636590902199;12120.212186207867;12120.878791212062;12122.090843186796;11304.604338848101;11636.185467017312;11695.824562631567;12022.819657705142;12267.69330766729;11131.826882442188;11612.535498580046;11735.295882247092;11885.22746021506;11885.821712674317;4082.1631565528996;4083.530394004698;4095.7337773044655;4164.583541645841;4165.75005499671;60804.69597652028;61240.69379653073;63644.68177659082;64106.35893641051;64754;8989.46063236208;9208.815823683488;9225.169734723908;9372.953135234417;9509.096635819586;17149.228284727218;17357.392491263;17430.477085687195;17468.126593670266;17552.561185970197;6211.720472578745;6282.623042617366;6425.614463132227;6428.839279018062;6539.215294164731;3546.414841551167;3723.0506220913494;3724.217914237986;3725.906852328659;3728.4034554471154;2917.0957003329036;2973.7918345716025;2983.075246673538;3012.578239046535;3019.3357461358464;511.0851575679492;519.4805194805194;522.3601088666429;522.5767128625749;524.6668365587802;572.5562688916124;593.7298529169218;597.7549204826;598.4972622996704;605.1104875832519;7661.693532258647;7798.688052477951;7825.217478252243;7914.683412663544;7932;561.6377436553482;577.6880484538302;582.3171035706811;588.3910152991718;590.7755053079802;3572.8392222350058;3599.3521166190335;3689.317476266899;3720.720945929066;3732.42147467143;52882.471175287756;53063;54236.45763542393;61524.38475615276;68506;42892.92767680833;47582;53341.93316133733;55813.72093139508;56880.71559642258;48278.27582586264;50003;50546.49453505418;50645.74677126665;51218.48781512137;7631.503952243086;7790.571518566533;7956.403269754907;7956.578671171452;8233.79415514617;33690.98927032095;34466.48300275548;34654;35097.64902351046;35694;42758.78620606877;42965.355519666606;43103.568964309954;45535.77232113818;45835;10700.946495267473;10817.56729730802;10965.780684386427;10995.285306455055;11304.378259195822;11873.287602743518;11877.109216808602;12491.063170262449;12541.498340066295;12595.496180153057;11278.718032049264;11533.88466115328;11535.884641153816;12096.697582560506;12141.575044873387;9673.9032609673;9681.177099946583;9698.321117521988;10033.749156271151;10101.898981010096;10436.739081523023;10589.629362972259;10657.573697052341;10660.680179594625;10754.13966882632;8271.503709777433;8359.582020899052;8441.240288374076;8588.914110858812;8677.479351238946;8865.955670221607;8907.554622268732;8935.106489351272;8986.640534378814;9033.407483714318 18:13:25 INFO - PROCESS | 18587 | __end_tp_report 18:13:25 INFO - PROCESS | 18587 | __start_cc_report 18:13:25 INFO - PROCESS | 18587 | _x_x_mozilla_cycle_collect,1225 18:13:25 INFO - PROCESS | 18587 | __end_cc_report 18:13:25 INFO - PROCESS | 18587 | __startTimestamp1483668805267__endTimestamp 18:13:25 INFO - PROCESS | 18587 | 18:13:25 INFO - PROCESS | 18587 | ------- Summary: start ------- 18:13:25 INFO - PROCESS | 18587 | Number of tests: 6 18:13:25 INFO - PROCESS | 18587 | 18:13:25 INFO - PROCESS | 18587 | [#0] dojo.html Cycles:165 Average:4744.22 Median:4876.90 stddev:511.23 (10.5%) stddev-sans-first:483.60 18:13:25 INFO - PROCESS | 18587 | Values: 2573.2 2583.2 2587.1 2609.2 2616.6 4692.1 4740.2 4747.5 4758.5 4798.0 4788.3 4802.7 4804.9 4805.8 4816.9 4374.7 4394.2 4413.1 4452.0 4456.4 4398.2 4416.4 4428.0 4431.3 4435.9 4419.0 4422.3 4430.6 4430.9 4431.8 4384.5 4419.9 4425.2 4431.6 4434.0 4361.7 4429.2 4434.2 4438.9 4465.1 4804.7 4828.3 4846.3 4876.9 4928.6 5037.5 5137.4 5153.0 5153.7 5209.1 5013.1 5080.4 5084.4 5132.3 5137.4 4812.2 4827.6 4830.0 4873.0 4877.8 5294.1 5296.9 5298.6 5300.7 5301.3 4938.2 4987.3 5015.6 5057.9 5073.6 5179.9 5185.9 5186.5 5214.3 5236.8 5134.1 5145.3 5147.2 5147.9 5184.8 4936.6 4975.6 5025.4 5124.8 5140.8 4368.8 4376.3 4381.1 4417.4 4425.9 3764.0 3766.0 3771.3 3797.8 3804.1 4320.6 4350.4 4420.2 4429.4 4432.9 5093.8 5102.8 5104.6 5128.8 5143.3 4386.6 4406.1 4408.9 4412.5 4459.2 4543.5 4557.8 4594.8 4605.8 4622.5 4795.3 4804.6 4825.1 4875.4 4886.5 5168.0 5175.0 5177.4 5178.4 5232.2 4709.6 4761.1 4835.0 4845.6 4884.5 5028.9 5053.7 5060.9 5061.4 5110.0 5147.5 5149.1 5156.7 5160.8 5198.7 4875.0 4985.1 4988.4 4988.9 5004.0 4898.2 5023.2 5054.0 5068.4 5075.1 4951.7 4964.4 4974.1 4979.2 4981.1 5072.1 5074.4 5077.5 5079.2 5080.6 4789.6 4887.7 4897.3 4941.8 4949.5 18:13:25 INFO - PROCESS | 18587 | 18:13:25 INFO - PROCESS | 18587 | [#1] ext.html Cycles:180 Average:47217.88 Median:36152.14 stddev:81952.18 (226.7%) stddev-sans-first:82107.10 18:13:25 INFO - PROCESS | 18587 | Values: 528.6 560.5 562.0 562.1 572.0 30037.8 39500.6 41700.4 41786.4 46239.0 42343.0 42863.1 42942.4 42955.0 42980.8 7425.7 7739.5 7930.0 7940.8 7945.6 7404.0 9972.9 10205.3 10238.9 10566.2 10149.4 10186.9 10204.9 10214.8 10235.3 10137.4 10232.0 10233.1 10238.9 10245.8 10001.5 10076.6 10077.4 10083.4 10090.1 38610.4 38709.6 38713.8 38722.4 38932.2 3418.1 3683.4 3695.1 3719.4 3724.7 29121.6 29205.4 29333.3 29698.0 30789.5 3191.4 3191.8 3203.8 3211.5 3216.1 251837.0 252612.0 252675.0 252764.7 253103.0 49453.5 49504.8 49530.8 49534.5 49640.8 57745.1 57770.8 57782.4 57795.6 57811.4 12821.1 13065.5 13119.9 13123.1 13126.9 9023.0 9377.2 10305.0 11513.1 11550.7 15747.0 16212.2 16263.3 16290.7 16319.3 6509.4 6605.3 6615.4 6631.7 6636.8 805.7 812.4 813.8 824.3 844.1 27709.3 27804.9 27828.6 28082.9 28291.4 920.5 954.1 958.4 959.9 965.5 13696.3 14579.8 14822.9 14901.7 14983.0 365940.0 366393.0 367216.0 367712.0 367815.0 52558.5 52560.0 52675.0 52686.0 52776.0 1227.3 1251.7 1262.0 1271.2 1275.6 25941.4 26736.5 26770.7 27412.5 27729.4 42690.0 43251.8 43284.6 43289.0 43331.6 304789.0 307650.0 307711.0 307746.0 308252.0 31741.5 36818.3 37637.4 37929.0 40799.2 42218.0 42607.6 42682.4 42944.6 42957.0 36083.8 36609.0 36973.0 37039.8 37488.8 35590.5 36220.5 36440.1 36463.3 36806.3 34685.7 34988.9 34994.8 35141.0 35251.8 30848.7 32365.0 32432.8 32454.7 32611.0 24575.4 24706.0 24764.8 24981.4 25182.7 18:13:25 INFO - PROCESS | 18587 | 18:13:25 INFO - PROCESS | 18587 | [#2] jquery.html Cycles:180 Average:30226.35 Median:488517.00 stddev:81267.72 (16.6%) stddev-sans-first:81464.71 18:13:25 INFO - PROCESS | 18587 | Values: 335.7 358.6 360.6 361.1 381.6 13287.8 17905.0 19373.4 20266.0 20417.5 17336.4 18420.8 18902.9 19017.7 19513.7 5876.8 6081.3 6120.7 6122.7 6128.6 5925.4 6007.5 6858.7 7140.4 7180.3 6964.9 7308.2 7309.0 7314.0 7316.5 7332.6 7433.6 7438.9 7440.4 7451.0 7074.6 7152.3 7230.3 7241.4 7245.1 24352.9 24563.9 24844.1 24940.8 25062.4 2393.4 2451.1 2452.9 2473.6 2557.2 14874.4 15151.8 15706.0 15908.8 16179.6 1818.1 1878.2 1910.1 1916.1 1917.4 92834.0 95231.5 95908.0 97742.0 99179.0 18063.6 18106.4 18193.8 18249.1 18644.4 32803.3 33452.5 33631.3 33634.3 33674.5 9028.5 9060.8 9069.3 9330.7 9391.4 7324.6 7473.9 7650.8 7653.7 7667.6 9033.7 9127.7 9340.3 9471.1 9495.9 3209.7 3580.8 3617.9 3634.0 3709.9 803.4 831.0 840.6 843.8 858.2 17372.0 18317.5 18328.4 18755.2 18823.2 807.9 817.9 818.3 823.6 836.3 9793.9 9868.3 10146.5 10149.8 10234.2 483555.0 488188.0 488846.0 498891.0 500800.0 75246.0 80561.0 97526.5 97609.0 99450.5 910.8 919.4 959.3 959.4 961.6 16333.6 16701.6 17135.2 17164.5 17289.0 39252.8 39568.4 40039.2 40382.6 40454.4 52668.5 57046.7 57263.4 57937.4 58674.0 7762.8 7838.8 7879.8 7964.0 8049.6 10066.5 11796.3 11940.0 12090.8 12614.2 9848.3 10196.2 10428.1 10595.2 10830.2 9884.6 10023.8 10248.7 10314.5 10664.9 9880.9 10263.3 10296.2 10459.0 10481.5 7164.2 7200.0 7238.1 7302.6 7601.4 8270.5 10012.5 10023.1 10080.7 10559.4 18:13:25 INFO - PROCESS | 18587 | 18:13:25 INFO - PROCESS | 18587 | [#3] mootools.html Cycles:180 Average:4629.29 Median:4954.07 stddev:2473.42 (49.9%) stddev-sans-first:2466.87 18:13:25 INFO - PROCESS | 18587 | Values: 1193.4 1320.8 1331.2 1345.9 1347.6 6312.8 6737.4 6964.5 6972.8 7145.8 7004.0 7080.9 7087.1 7089.6 7092.3 1804.8 1878.5 2061.7 2105.5 2106.4 1999.4 2073.3 2082.6 2096.3 2100.5 2057.7 2060.5 2067.5 2068.8 2069.3 2005.3 2043.9 2045.9 2047.7 2048.6 1979.1 1983.3 2011.2 2043.9 2082.9 7115.0 7161.9 7184.1 7188.1 7192.5 4252.7 4354.8 4362.7 4374.0 4378.7 2634.8 2752.5 2814.7 2834.0 2844.4 1009.8 1027.5 1060.0 1094.8 1096.6 9178.3 9219.4 9228.6 9233.7 9256.2 3690.0 3877.4 3906.7 3943.9 3958.6 8037.0 8044.0 8046.8 8047.0 8048.4 4416.2 4421.0 4442.9 4456.9 4463.3 3620.4 3661.2 4052.0 4053.7 4054.5 3223.6 3277.2 3338.4 3353.1 3376.3 1099.5 1105.9 1118.2 1120.1 1121.3 686.7 696.2 706.4 720.3 736.1 4800.6 4839.0 4886.9 4955.3 4970.2 851.6 873.4 886.2 898.9 904.4 3284.4 3360.0 3414.1 3445.9 3454.1 8126.4 8286.2 8375.5 8376.7 8390.1 7792.0 8276.0 8287.4 8311.4 8340.0 6476.6 6627.6 6629.4 6636.3 6648.0 4805.6 4931.4 4952.9 5090.7 5189.5 7662.3 7679.1 7773.8 7895.4 7905.4 7824.8 7874.3 7920.8 7925.9 7927.0 6067.8 6201.5 6203.6 6210.3 6210.9 6102.6 6341.4 6350.5 6353.7 6606.9 5960.4 5993.2 6019.5 6030.1 6031.9 5957.5 6005.3 6038.1 6157.6 6159.8 5392.9 5483.4 5531.2 5531.6 5551.3 4107.5 4109.5 4207.4 4236.3 4307.4 4007.2 4023.0 4061.5 4083.0 4150.2 18:13:25 INFO - PROCESS | 18587 | 18:13:25 INFO - PROCESS | 18587 | [#4] prototype.html Cycles:180 Average:4158.97 Median:4731.94 stddev:1203.68 (25.4%) stddev-sans-first:1172.05 18:13:25 INFO - PROCESS | 18587 | Values: 319.8 324.3 325.1 328.5 335.6 4734.7 4807.0 4820.8 4827.3 4842.5 4779.9 4799.7 4800.2 4804.6 4808.9 3661.4 3671.4 3748.5 3762.8 3842.6 3830.8 3848.6 3849.2 3861.7 3876.7 3822.6 3828.8 3831.7 3833.7 3837.0 3674.3 3749.5 3788.8 3809.1 3839.3 3382.0 3410.6 3416.9 3424.5 3426.5 4673.9 4693.9 4699.4 4699.8 4719.3 4001.8 4223.6 4392.0 4394.8 4398.4 5007.9 5012.9 5020.5 5030.7 5040.5 3554.5 3556.2 3561.0 3561.8 3569.2 5069.8 5218.8 5224.3 5243.4 5254.2 3562.5 3590.4 3597.7 3603.1 3608.0 3623.4 3643.8 3644.0 3658.4 3661.5 4198.6 4210.3 4212.0 4221.5 4228.5 4913.5 5035.1 5064.0 5077.3 5087.5 1233.4 1235.7 1237.7 1239.4 1243.9 802.5 806.8 839.0 845.2 847.4 4191.0 4352.3 4412.4 4433.4 4444.3 5157.1 5164.2 5169.3 5173.0 5178.2 4421.1 4429.6 4439.3 4444.1 4455.3 2532.6 2652.9 2686.2 2697.9 2700.0 4700.6 4706.5 4734.0 4740.8 4745.3 4919.5 5178.0 5183.8 5188.6 5195.9 4662.9 4687.7 4726.7 4731.2 4732.7 5064.0 5065.6 5075.5 5085.3 5087.9 4785.9 4942.6 4958.9 5001.9 5009.0 4831.4 4851.2 4865.8 4874.4 4890.4 3630.6 3642.2 3662.0 3676.9 3696.1 5073.5 5229.4 5237.9 5241.9 5250.8 5008.3 5018.6 5044.4 5045.0 5057.5 5013.7 5026.7 5039.9 5051.9 5053.6 4800.6 4990.2 4994.2 4996.6 5021.4 4893.6 4905.3 5002.7 5066.5 5092.8 4871.1 4897.2 4908.3 4916.4 4916.6 18:13:25 INFO - PROCESS | 18587 | 18:13:25 INFO - PROCESS | 18587 | [#5] yui.html Cycles:180 Average:13967.94 Median:25868.31 stddev:17330.42 (67.0%) stddev-sans-first:17350.04 18:13:25 INFO - PROCESS | 18587 | Values: 618.1 637.0 642.4 652.9 656.7 11504.9 11797.0 11962.9 11974.2 12206.3 11788.8 12038.7 12068.2 12121.3 12128.8 786.6 803.7 803.8 803.8 804.1 656.5 684.6 784.7 808.6 812.1 867.1 869.8 870.1 870.7 871.8 863.6 863.9 864.0 864.6 864.8 501.7 518.1 603.5 615.5 631.6 12034.3 12113.6 12120.2 12120.9 12122.1 11304.6 11636.2 11695.8 12022.8 12267.7 11131.8 11612.5 11735.3 11885.2 11885.8 4082.2 4083.5 4095.7 4164.6 4165.8 60804.7 61240.7 63644.7 64106.4 64754.0 8989.5 9208.8 9225.2 9373.0 9509.1 17149.2 17357.4 17430.5 17468.1 17552.6 6211.7 6282.6 6425.6 6428.8 6539.2 3546.4 3723.1 3724.2 3725.9 3728.4 2917.1 2973.8 2983.1 3012.6 3019.3 511.1 519.5 522.4 522.6 524.7 572.6 593.7 597.8 598.5 605.1 7661.7 7798.7 7825.2 7914.7 7932.0 561.6 577.7 582.3 588.4 590.8 3572.8 3599.4 3689.3 3720.7 3732.4 52882.5 53063.0 54236.5 61524.4 68506.0 42892.9 47582.0 53341.9 55813.7 56880.7 48278.3 50003.0 50546.5 50645.7 51218.5 7631.5 7790.6 7956.4 7956.6 8233.8 33691.0 34466.5 34654.0 35097.6 35694.0 42758.8 42965.4 43103.6 45535.8 45835.0 10700.9 10817.6 10965.8 10995.3 11304.4 11873.3 11877.1 12491.1 12541.5 12595.5 11278.7 11533.9 11535.9 12096.7 12141.6 9673.9 9681.2 9698.3 10033.7 10101.9 10436.7 10589.6 10657.6 10660.7 10754.1 8271.5 8359.6 8441.2 8588.9 8677.5 8866.0 8907.6 8935.1 8986.6 9033.4 18:13:25 INFO - PROCESS | 18587 | -------- Summary: end -------- 18:13:25 INFO - PROCESS | 18587 | 18:13:26 INFO - TEST-INFO | 18587: exit 0 18:13:26 INFO - TEST-OK | dromaeo_css | took 1122079ms 18:13:26 INFO - TEST-START | kraken 18:13:26 INFO - operating with platform_type : linux_ 18:13:26 INFO - Initialising browser for kraken test... 18:13:26 INFO - TEST-INFO | started process 18882 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9JjAuK/profile http://localhost:44790/getInfo.html) 18:13:26 INFO - PROCESS | 18882 | 1483668806543 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 18:13:29 INFO - PROCESS | 18882 | __metrics Screen width/height:1600/1200 18:13:29 INFO - PROCESS | 18882 | colorDepth:24 18:13:29 INFO - PROCESS | 18882 | Browser inner width/height: 1024/697 18:13:29 INFO - PROCESS | 18882 | __metrics 18:13:30 INFO - PROCESS | 18882 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:13:30 INFO - PROCESS | 18882 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:13:31 INFO - PROCESS | 18882 | console.error: PushService: 18:13:31 INFO - PROCESS | 18882 | stateChangeProcessEnqueue: Error transitioning state 18:13:31 INFO - PROCESS | 18882 | UnknownError 18:13:31 INFO - TEST-INFO | 18882: exit 0 18:13:31 INFO - Browser initialized. 18:13:31 INFO - Running cycle 1/1 for kraken test... 18:13:31 INFO - TEST-INFO | started process 18962 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9JjAuK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:13:43 INFO - PROCESS | 18962 | RSS: Main: 151875584 18:13:43 INFO - PROCESS | 18962 | 18:13:46 INFO - PROCESS | 18962 | Cycle 1(1): loaded http://localhost:44790/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44790/tests/kraken/driver.html?testName=audio-beat-detection) 18:13:47 INFO - PROCESS | 18962 | RSS: Main: 205627392 18:13:47 INFO - PROCESS | 18962 | 18:13:50 INFO - PROCESS | 18962 | Cycle 1(1): loaded http://localhost:44790/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44790/tests/kraken/driver.html?testName=audio-dft) 18:13:50 INFO - PROCESS | 18962 | RSS: Main: 185466880 18:13:50 INFO - PROCESS | 18962 | 18:13:53 INFO - PROCESS | 18962 | Cycle 1(1): loaded http://localhost:44790/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44790/tests/kraken/driver.html?testName=audio-fft) 18:13:54 INFO - PROCESS | 18962 | RSS: Main: 268423168 18:13:54 INFO - PROCESS | 18962 | 18:13:56 INFO - PROCESS | 18962 | Cycle 1(1): loaded http://localhost:44790/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44790/tests/kraken/driver.html?testName=audio-oscillator) 18:13:57 INFO - PROCESS | 18962 | RSS: Main: 177975296 18:13:57 INFO - PROCESS | 18962 | 18:13:59 INFO - PROCESS | 18962 | Cycle 1(1): loaded http://localhost:44790/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44790/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:13:59 INFO - PROCESS | 18962 | RSS: Main: 182829056 18:13:59 INFO - PROCESS | 18962 | 18:14:03 INFO - PROCESS | 18962 | Cycle 1(1): loaded http://localhost:44790/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44790/tests/kraken/driver.html?testName=imaging-darkroom) 18:14:03 INFO - PROCESS | 18962 | RSS: Main: 221691904 18:14:03 INFO - PROCESS | 18962 | 18:14:07 INFO - PROCESS | 18962 | Cycle 1(1): loaded http://localhost:44790/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44790/tests/kraken/driver.html?testName=imaging-desaturate) 18:14:07 INFO - PROCESS | 18962 | RSS: Main: 221016064 18:14:07 INFO - PROCESS | 18962 | 18:14:10 INFO - PROCESS | 18962 | Cycle 1(1): loaded http://localhost:44790/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44790/tests/kraken/driver.html?testName=json-parse-financial) 18:14:11 INFO - PROCESS | 18962 | RSS: Main: 220069888 18:14:11 INFO - PROCESS | 18962 | 18:14:12 INFO - PROCESS | 18962 | Cycle 1(1): loaded http://localhost:44790/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44790/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:14:13 INFO - PROCESS | 18962 | RSS: Main: 182743040 18:14:13 INFO - PROCESS | 18962 | 18:14:15 INFO - PROCESS | 18962 | Cycle 1(1): loaded http://localhost:44790/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44790/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:14:16 INFO - PROCESS | 18962 | RSS: Main: 189566976 18:14:16 INFO - PROCESS | 18962 | 18:14:18 INFO - PROCESS | 18962 | Cycle 1(1): loaded http://localhost:44790/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44790/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:14:18 INFO - PROCESS | 18962 | RSS: Main: 198246400 18:14:18 INFO - PROCESS | 18962 | 18:14:21 INFO - PROCESS | 18962 | Cycle 1(1): loaded http://localhost:44790/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44790/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:14:22 INFO - PROCESS | 18962 | RSS: Main: 207712256 18:14:22 INFO - PROCESS | 18962 | 18:14:25 INFO - PROCESS | 18962 | Cycle 1(1): loaded http://localhost:44790/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44790/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:14:26 INFO - PROCESS | 18962 | RSS: Main: 203358208 18:14:26 INFO - PROCESS | 18962 | 18:14:27 INFO - PROCESS | 18962 | Cycle 1(1): loaded http://localhost:44790/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44790/tests/kraken/driver.html?testName=ai-astar) 18:14:28 INFO - PROCESS | 18962 | RSS: Main: 254144512 18:14:28 INFO - PROCESS | 18962 | 18:14:28 INFO - PROCESS | 18962 | __start_tp_report 18:14:28 INFO - PROCESS | 18962 | _x_x_mozilla_page_load 18:14:28 INFO - PROCESS | 18962 | _x_x_mozilla_page_load_details 18:14:28 INFO - PROCESS | 18962 | |i|pagename|runs| 18:14:28 INFO - PROCESS | 18962 | |0;ai-astar;111;113;114;114;116;115;116;114;114;114 18:14:28 INFO - PROCESS | 18962 | |1;audio-beat-detection;154;124;126;157;128;124;124;124;148;124 18:14:28 INFO - PROCESS | 18962 | |2;audio-dft;161;160;161;162;161;160;160;161;161;159 18:14:28 INFO - PROCESS | 18962 | |3;audio-fft;75;76;75;103;76;77;77;77;95;75 18:14:28 INFO - PROCESS | 18962 | |4;audio-oscillator;84;84;110;85;86;84;99;84;84;107 18:14:28 INFO - PROCESS | 18962 | |5;imaging-gaussian-blur;118;117;117;117;117;117;118;117;117;118 18:14:28 INFO - PROCESS | 18962 | |6;imaging-darkroom;149;149;148;149;148;148;148;148;148;149 18:14:28 INFO - PROCESS | 18962 | |7;imaging-desaturate;95;95;96;95;95;95;96;95;95;95 18:14:28 INFO - PROCESS | 18962 | |8;json-parse-financial;62;62;62;69;63;61;64;64;63;72 18:14:28 INFO - PROCESS | 18962 | |9;json-stringify-tinderbox;66;67;66;67;67;67;67;67;67;67 18:14:28 INFO - PROCESS | 18962 | |10;stanford-crypto-aes;85;84;86;94;85;96;88;86;95;85 18:14:28 INFO - PROCESS | 18962 | |11;stanford-crypto-ccm;143;133;133;140;132;130;141;137;175;134 18:14:28 INFO - PROCESS | 18962 | |12;stanford-crypto-pbkdf2;178;179;175;175;174;175;175;176;184;176 18:14:28 INFO - PROCESS | 18962 | |13;stanford-crypto-sha256-iterative;68;71;67;68;67;68;68;69;68;68 18:14:28 INFO - PROCESS | 18962 | __end_tp_report 18:14:28 INFO - PROCESS | 18962 | __start_cc_report 18:14:28 INFO - PROCESS | 18962 | _x_x_mozilla_cycle_collect,1832 18:14:28 INFO - PROCESS | 18962 | __end_cc_report 18:14:28 INFO - PROCESS | 18962 | __startTimestamp1483668868103__endTimestamp 18:14:28 INFO - PROCESS | 18962 | 18:14:28 INFO - PROCESS | 18962 | ------- Summary: start ------- 18:14:28 INFO - PROCESS | 18962 | Number of tests: 14 18:14:28 INFO - PROCESS | 18962 | 18:14:28 INFO - PROCESS | 18962 | [#0] ai-astar Cycles:10 Average:114.10 Median:114.00 stddev:1.45 (1.3%) stddev-sans-first:1.01 18:14:28 INFO - PROCESS | 18962 | Values: 111.0 113.0 114.0 114.0 116.0 115.0 116.0 114.0 114.0 114.0 18:14:28 INFO - PROCESS | 18962 | 18:14:28 INFO - PROCESS | 18962 | [#1] audio-beat-detection Cycles:10 Average:133.30 Median:127.00 stddev:13.82 (10.9%) stddev-sans-first:12.47 18:14:28 INFO - PROCESS | 18962 | Values: 154.0 124.0 126.0 157.0 128.0 124.0 124.0 124.0 148.0 124.0 18:14:28 INFO - PROCESS | 18962 | 18:14:28 INFO - PROCESS | 18962 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.84 (0.5%) stddev-sans-first:0.88 18:14:28 INFO - PROCESS | 18962 | Values: 161.0 160.0 161.0 162.0 161.0 160.0 160.0 161.0 161.0 159.0 18:14:28 INFO - PROCESS | 18962 | 18:14:28 INFO - PROCESS | 18962 | [#3] audio-fft Cycles:10 Average:80.60 Median:76.50 stddev:9.91 (13.0%) stddev-sans-first:10.31 18:14:28 INFO - PROCESS | 18962 | Values: 75.0 76.0 75.0 103.0 76.0 77.0 77.0 77.0 95.0 75.0 18:14:28 INFO - PROCESS | 18962 | 18:14:28 INFO - PROCESS | 18962 | [#4] audio-oscillator Cycles:10 Average:90.70 Median:84.00 stddev:10.47 (12.5%) stddev-sans-first:10.82 18:14:28 INFO - PROCESS | 18962 | Values: 84.0 84.0 110.0 85.0 86.0 84.0 99.0 84.0 84.0 107.0 18:14:28 INFO - PROCESS | 18962 | 18:14:28 INFO - PROCESS | 18962 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 18:14:28 INFO - PROCESS | 18962 | Values: 118.0 117.0 117.0 117.0 117.0 117.0 118.0 117.0 117.0 118.0 18:14:28 INFO - PROCESS | 18962 | 18:14:28 INFO - PROCESS | 18962 | [#6] imaging-darkroom Cycles:10 Average:148.40 Median:148.50 stddev:0.52 (0.3%) stddev-sans-first:0.50 18:14:28 INFO - PROCESS | 18962 | Values: 149.0 149.0 148.0 149.0 148.0 148.0 148.0 148.0 148.0 149.0 18:14:28 INFO - PROCESS | 18962 | 18:14:28 INFO - PROCESS | 18962 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:14:28 INFO - PROCESS | 18962 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 18:14:28 INFO - PROCESS | 18962 | 18:14:28 INFO - PROCESS | 18962 | [#8] json-parse-financial Cycles:10 Average:64.20 Median:63.50 stddev:3.52 (5.5%) stddev-sans-first:3.64 18:14:28 INFO - PROCESS | 18962 | Values: 62.0 62.0 62.0 69.0 63.0 61.0 64.0 64.0 63.0 72.0 18:14:28 INFO - PROCESS | 18962 | 18:14:28 INFO - PROCESS | 18962 | [#9] json-stringify-tinderbox Cycles:10 Average:66.80 Median:67.00 stddev:0.42 (0.6%) stddev-sans-first:0.33 18:14:28 INFO - PROCESS | 18962 | Values: 66.0 67.0 66.0 67.0 67.0 67.0 67.0 67.0 67.0 67.0 18:14:28 INFO - PROCESS | 18962 | 18:14:28 INFO - PROCESS | 18962 | [#10] stanford-crypto-aes Cycles:10 Average:88.40 Median:87.00 stddev:4.70 (5.4%) stddev-sans-first:4.82 18:14:28 INFO - PROCESS | 18962 | Values: 85.0 84.0 86.0 94.0 85.0 96.0 88.0 86.0 95.0 85.0 18:14:28 INFO - PROCESS | 18962 | 18:14:28 INFO - PROCESS | 18962 | [#11] stanford-crypto-ccm Cycles:10 Average:139.80 Median:138.50 stddev:13.09 (9.4%) stddev-sans-first:13.83 18:14:28 INFO - PROCESS | 18962 | Values: 143.0 133.0 133.0 140.0 132.0 130.0 141.0 137.0 175.0 134.0 18:14:28 INFO - PROCESS | 18962 | 18:14:28 INFO - PROCESS | 18962 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.70 Median:176.00 stddev:2.98 (1.7%) stddev-sans-first:3.13 18:14:28 INFO - PROCESS | 18962 | Values: 178.0 179.0 175.0 175.0 174.0 175.0 175.0 176.0 184.0 176.0 18:14:28 INFO - PROCESS | 18962 | 18:14:28 INFO - PROCESS | 18962 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.00 stddev:1.14 (1.7%) stddev-sans-first:1.20 18:14:28 INFO - PROCESS | 18962 | Values: 68.0 71.0 67.0 68.0 67.0 68.0 68.0 69.0 68.0 68.0 18:14:28 INFO - PROCESS | 18962 | -------- Summary: end -------- 18:14:28 INFO - PROCESS | 18962 | 18:14:29 INFO - TEST-INFO | 18962: exit 0 18:14:29 INFO - TEST-OK | kraken | took 63210ms 18:14:29 INFO - SUITE-END | took 1185s 18:14:29 INFO - Completed test suite (00:19:45) 18:14:29 INFO - javascript benchmark 18:14:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2573.2023072847414, 2583.237944806282, 2587.1333103410357, 2609.2302770682645, 2616.6205900144478, 4692.085043416527, 4740.217864052432, 4747.525247475251, 4758.52414758524, 4798.01640663664, 4788.257820037891, 4802.663813533054, 4804.879878003052, 4805.759712014403, 4816.91619385638, 4374.671899607534, 4394.187075391047, 4413.117376524692, 4451.977740111295, 4456.442944631921, 4398.208322501949, 4416.447944006999, 4428.003699167693, 4431.26884064129, 4435.866923992286, 4418.955810441887, 4422.270325396305, 4430.579094985971, 4430.933535996963, 4431.800568974388, 4384.495782984965, 4419.889502762425, 4425.203463376591, 4431.578999994996, 4434.046679963791, 4361.738295702236, 4429.247028005247, 4434.224010798098, 4438.933415998763, 4465.084657645208, 4804.711717296974, 4828.275758636232, 4846.297286893416, 4876.902461950776, 4928.6303527235605, 5037.49625037497, 5137.409197942247, 5152.974235128838, 5153.7070524411965, 5209.114450543417, 5013.147764879979, 5080.364954380702, 5084.3644544431945, 5132.255822905695, 5137.434882162922, 4812.205986012339, 4827.637927155477, 4830.009847981156, 4873.02539492103, 4877.829275975324, 5294.100002999499, 5296.947030529645, 5298.629095963245, 5300.655457395257, 5301.3373328333955, 4938.185199442052, 4987.251912213124, 5015.648904576645, 5057.898842023139, 5073.568746656501, 5179.922301165486, 5185.948140518546, 5186.455422180692, 5214.32213812202, 5236.842894713165, 5134.101532231844, 5145.305383773218, 5147.2279158126585, 5147.897042059138, 5184.766685499161, 4936.6297527685565, 4975.601951843843, 5025.447200807908, 5124.795008199705, 5140.820071297487, 4368.7815609219415, 4376.256036473807, 4381.058072514425, 4417.403650507141, 4425.911481770347, 3764.0401697566945, 3765.962340376616, 3771.26460340231, 3797.8290976906105, 3804.1250512382308, 4320.567943205717, 4350.412694286231, 4420.160169567772, 4429.424174857248, 4432.867013989586, 5093.770780314895, 5102.795888164506, 5104.642675012714, 5128.8461346159675, 5143.27994080829, 4386.627136693417, 4406.096750166208, 4408.889777755582, 4412.514623391488, 4459.241928872099, 4543.54564543543, 4557.840475583339, 4594.793234304398, 4605.792739326671, 4622.468416132053, 4795.304680821331, 4804.615630749601, 4825.131476334293, 4875.439324477588, 4886.511348865085, 5168.0, 5175.042617115846, 5177.404598471224, 5178.352705911761, 5232.1628539433505, 4709.646776491708, 4761.119192949315, 4835.0, 4845.5881250094135, 4884.48712885142, 5028.924566131438, 5053.722045287397, 5060.949390506195, 5061.4179369373, 5110.029094472038, 5147.459516750687, 5149.124648809786, 5156.716380599103, 5160.767765450639, 5198.74006299676, 4875.0, 4985.077760614298, 4988.351514303117, 4988.850334489971, 5003.974980125076, 4898.167311557115, 5023.246513023076, 5054.0, 5068.366454193226, 5075.086484432837, 4951.653384263067, 4964.379452568429, 4974.104661161025, 4979.178435558238, 4981.128302547112, 5072.134698012331, 5074.416442109057, 5077.517635824591, 5079.187330027178, 5080.593552515864, 4789.568938795525, 4887.731174785421, 4897.2654101885, 4941.75291235444, 4949.529794669501], "value": 4709.9730973216065, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [528.6484487815603, 560.4563573333864, 561.9887602247954, 562.1455387810528, 571.9942800571998, 30037.849810750944, 39500.60499395005, 41700.37449438257, 41786.373204401956, 46239.0, 42343.0, 42863.14273714524, 42942.35586466198, 42955.0, 42980.785096074476, 7425.702971881118, 7739.496932699385, 7929.96035019827, 7940.761777146695, 7945.642446089911, 7403.9629801850915, 9972.850407243897, 10205.285630005903, 10238.897611023905, 10566.20753443491, 10149.441780702058, 10186.949065254663, 10204.948975255113, 10214.846777298346, 10235.283530152892, 10137.391756494597, 10232.0, 10233.079022887901, 10238.89761102387, 10245.79508409835, 10001.549930253155, 10076.647317343857, 10077.44574048427, 10083.44541050242, 10090.142337901287, 38610.42084368737, 38709.61290387088, 38713.80643096795, 38722.41916371257, 38932.22135557301, 3418.1041793457193, 3683.37382644951, 3695.131644063643, 3719.3863012602887, 3724.6647801697704, 29121.56317655237, 29205.41589168205, 29333.26666833325, 29698.0, 30789.538156927665, 3191.4414977379097, 3191.808491490495, 3203.775735698502, 3211.486162214058, 3216.115568218735, 251837.0, 252612.0, 252675.0, 252764.73617631977, 253103.0, 49453.50546494489, 49504.75247623811, 49530.75234623804, 49534.50465495371, 49640.75179624079, 57745.13382299269, 57770.778652668414, 57782.42217577854, 57795.55511112256, 57811.42188578145, 12821.1025228235, 13065.542706005244, 13119.868801312055, 13123.146995445457, 13126.868731312756, 9022.954885225532, 9377.249820014382, 10305.0, 11513.136514761427, 11550.653480395602, 15747.0, 16212.189390530426, 16263.268152933146, 16290.674186516204, 16319.265633046542, 6509.381608747161, 6605.273419923805, 6615.4046135848, 6631.734730610721, 6636.767713130017, 805.7421625079918, 812.3501199040744, 813.7876898465339, 824.2581676491205, 844.1178967978477, 27709.307267318076, 27804.86097569499, 27828.582571261446, 28082.945069887206, 28291.434171316458, 920.5351297594672, 954.1269738189479, 958.393170576032, 959.9184069354183, 965.5027660754716, 13696.315184240748, 14579.781303280459, 14822.851771482363, 14901.70196596062, 14983.0, 365940.0, 366393.0, 367216.0, 367712.0, 367815.0, 52558.474415256125, 52560.0, 52675.0, 52686.0, 52776.0, 1227.3311045480234, 1251.6870782304425, 1261.9810702839466, 1271.2436100520176, 1275.590472527862, 25941.35146621274, 26736.465270694087, 26770.724488418702, 27412.45175096527, 27729.445411092067, 42690.0, 43251.78374108109, 43284.567154328055, 43288.99999999874, 43331.566684332756, 304789.0, 307650.0, 307711.0, 307746.0, 308252.0, 31741.5238771414, 36818.263634726616, 37637.435438468994, 37929.0, 40799.1840163201, 42218.0, 42607.57392426036, 42682.35976460294, 42944.57055429531, 42957.0, 36083.81958090193, 36609.0, 36973.0, 37039.8148009269, 37488.81255593814, 35590.46614300736, 36220.456693150154, 36440.06797960548, 36463.27073458569, 36806.26387472289, 34685.65314346824, 34988.857688195065, 34994.82502587471, 35141.0, 35251.82374088113, 30848.69151308548, 32365.0, 32432.83783581067, 32454.651770384742, 32611.0, 24575.385615359042, 24706.0, 24764.752352476964, 24981.375465613506, 25182.74817251804], "value": 18053.426216972817, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.72135127843893, 358.5500197252448, 360.6123417326375, 361.10986418478444, 381.57835591671204, 13287.800682989751, 17905.0, 19373.418797436065, 20266.0, 20417.489562760937, 17336.393226237084, 18420.815791842077, 18902.93586878821, 19017.714734278998, 19513.707294390595, 5876.764929402819, 6081.3006504252135, 6120.693965301717, 6122.724477398528, 6128.60164089335, 5925.407459254083, 6007.4593286604195, 6858.725650973955, 7140.357367836894, 7180.28197180283, 6964.895526567106, 7308.1595616504255, 7309.0, 7314.049173607424, 7316.451266155033, 7332.633368331562, 7433.628318584049, 7438.925610743904, 7440.404767618577, 7451.031365922449, 7074.575525468488, 7152.284771522806, 7230.276972302728, 7241.420686345133, 7245.130584329914, 24352.878235608885, 24563.877180614163, 24844.130455434155, 24940.750592494023, 25062.37344066413, 2393.4495066134887, 2451.0685939343116, 2452.8773561321864, 2473.5918573435542, 2557.194483737617, 14874.435932939594, 15151.848481515264, 15706.0, 15908.76136857948, 16179.595510112224, 1818.0910044453403, 1878.234459607978, 1910.1022519415853, 1916.1377380178976, 1917.4151883675613, 92834.0, 95231.52384238104, 95908.0, 97742.022579774, 99179.0, 18063.63872722538, 18106.366277180234, 18193.81806181948, 18249.087545622664, 18644.440666780018, 32803.34393312121, 33452.49821252683, 33631.318434078195, 33634.32731345409, 33674.49488257678, 9028.548572571477, 9060.776795132704, 9069.31980101495, 9330.720078397524, 9391.389559678599, 7324.597147156957, 7473.85052298951, 7650.846983060308, 7653.6938522459595, 7667.578283194478, 9033.683821066232, 9127.68053118151, 9340.34617576763, 9471.147596716328, 9495.891583594166, 3209.6790320967716, 3580.7851528907827, 3617.8914632561064, 3633.981830090833, 3709.922443431687, 803.3613277444446, 831.0, 840.6259214649544, 843.7932706486949, 858.2104463893234, 17372.0, 18317.450476485727, 18328.35850745244, 18755.156017979225, 18823.247070117315, 807.9232472915064, 817.8650522663813, 818.337146911004, 823.5882058970515, 836.3100442135262, 9793.90206097944, 9868.30921835479, 10146.543405546769, 10149.797004060025, 10234.232432567587, 483555.0, 488188.0, 488846.0, 498891.0, 500800.0, 75246.0, 80561.0, 97526.51236743771, 97609.0, 99450.50274748579, 910.8087301666592, 919.4391421233042, 959.3476436023572, 959.3811991265734, 961.6345788600287, 16333.591660208589, 16701.5824604381, 17135.22891469862, 17164.485065448058, 17289.009645697824, 39252.803735981135, 39568.40647390234, 40039.1992160161, 40382.596174039056, 40454.393184101675, 52668.473315266354, 57046.7147664259, 57263.42736572581, 57937.4206257932, 58674.0, 7762.844743105219, 7838.764837054898, 7879.803004924694, 7964.044314682275, 8049.637766300648, 10066.547005384922, 11796.29222246668, 11940.04479641644, 12090.818637720266, 12614.180078294881, 9848.310618256939, 10196.235282353704, 10428.113610343205, 10595.205359598214, 10830.18773591968, 9884.604615815288, 10023.799524009624, 10248.743781405525, 10314.484275786332, 10664.946675266574, 9880.901190987997, 10263.332883362558, 10296.17630589566, 10459.0, 10481.528331225265, 7164.247753985965, 7200.0, 7238.0952380952385, 7302.598357090411, 7601.429892758176, 8270.545120018456, 10012.499375031364, 10023.097921186836, 10080.69757907264, 10559.419231942009], "value": 9888.939694664441, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1193.397334346155, 1320.7556602028626, 1331.1879753350454, 1345.925974071426, 1347.615929460104, 6312.77905273315, 6737.427318677912, 6964.477664175181, 6972.755953541627, 7145.821354466128, 7004.038869116655, 7080.893786593192, 7087.114110736158, 7089.574625522459, 7092.255313192118, 1804.7563578916875, 1878.4834170603058, 2061.711360409544, 2105.473631592102, 2106.399676092314, 1999.3701983875105, 2073.2536286936693, 2082.5834833033377, 2096.287262330807, 2100.4853810816294, 2057.742782152231, 2060.484878780305, 2067.4934641012974, 2068.803463670949, 2069.2757534862753, 2005.3081686818025, 2043.867148635334, 2045.8874761888017, 2047.703083052949, 2048.5902819436023, 1979.0599465254033, 1983.2959299448632, 2011.155314767801, 2043.9284625038201, 2082.9270975515783, 7115.0, 7161.928380716178, 7184.137903451567, 7188.101487314086, 7192.460565457612, 4252.702310838243, 4354.804033818486, 4362.738235705836, 4373.978130109361, 4378.6934914555995, 2634.841909485437, 2752.4907892039837, 2814.7326004029583, 2833.957490637642, 2844.445333160035, 1009.8182327181105, 1027.4965267019181, 1059.9947000265026, 1094.8084085285043, 1096.6052221200362, 9178.311626627963, 9219.400738951947, 9228.630854765868, 9233.676821311223, 9256.166944974984, 3689.9631003690156, 3877.418387241936, 3906.7069969752383, 3943.921121577552, 3958.5645578986287, 8037.0, 8044.0, 8046.839063218703, 8047.034355877332, 8048.356131509465, 4416.24923762961, 4421.049474363027, 4442.933355999663, 4456.9331460028125, 4463.308187230984, 3620.402633565485, 3661.2494438640015, 4052.0477687743355, 4053.7365071270274, 4054.533728621246, 3223.629282632527, 3277.1807048237943, 3338.432466480704, 3353.111425472252, 3376.2572234108457, 1099.5381939585393, 1105.9391733454577, 1118.2395970739974, 1120.1263014848432, 1121.2823792772633, 686.7012849410522, 696.1785093589511, 706.427793487277, 720.3049057659384, 736.1093077376328, 4800.591949684316, 4839.0, 4886.902261954741, 4955.281484184773, 4970.204767237225, 851.5571902610608, 873.3842640938124, 886.1979908183105, 898.8876390451194, 904.439247666451, 3284.392387408337, 3359.983200083984, 3414.146463384154, 3445.8621655134016, 3454.0674018014997, 8126.39052071097, 8286.171382861665, 8375.45559538628, 8376.706815261437, 8390.077091519928, 7792.0, 8276.0, 8287.419880608299, 8311.376646751518, 8339.95830020846, 6476.6075293812055, 6627.602343859384, 6629.403353698191, 6636.30318816527, 6648.03603477493, 4805.55015624768, 4931.3589233399425, 4952.851414457572, 5090.7200103993155, 5189.532942035235, 7662.310392064741, 7679.116901556169, 7773.844523109393, 7895.447318687637, 7905.367570594223, 7824.765257042298, 7874.291313781787, 7920.801979950547, 7925.920740792519, 7927.0, 6067.848303792441, 6201.534884883562, 6203.627782333075, 6210.254769427697, 6210.937890621217, 6102.603330783485, 6341.365863413622, 6350.523710721806, 6353.7140828661895, 6606.900896486461, 5960.374160713062, 5993.220881285405, 6019.4883434908525, 6030.125631783455, 6031.909521357095, 5957.463828255478, 6005.279366476051, 6038.124471951454, 6157.599756015845, 6159.81520554384, 5392.865178370572, 5483.369412517452, 5531.170324451365, 5531.585131115102, 5551.250581171652, 4107.507099148122, 4109.527404348419, 4207.4319966805315, 4236.301010333384, 4307.418498502662, 4007.218592374459, 4023.0, 4061.5329237136925, 4083.0, 4150.211459822624], "value": 3795.520551143044, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.7809500492163, 324.30922135850636, 325.14649046253584, 328.5203602739999, 335.64757005144594, 4734.73958932259, 4806.9519304806945, 4820.758962051901, 4827.300041493986, 4842.467328593856, 4779.928301075478, 4799.664023518355, 4800.1599720048935, 4804.639652026094, 4808.879778005552, 3661.359262129123, 3671.3575124353265, 3748.5314335708035, 3762.774233545996, 3842.5773164951975, 3830.7893065881367, 3848.5766565677754, 3849.1531862990096, 3861.691064714816, 3876.6898648108086, 3822.57951625321, 3828.7894165820867, 3831.655151036406, 3833.6933045356445, 3837.0, 3674.3018826423026, 3749.456328832331, 3788.8065259443324, 3809.066778639251, 3839.2705385976574, 3382.0, 3410.556627638416, 3416.9487457688156, 3424.4520876659863, 3426.468897320919, 4673.906521869543, 4693.88265293367, 4699.412573428322, 4699.812007519695, 4719.339292499053, 4001.8399264029404, 4223.556526564697, 4392.0, 4394.846180383671, 4398.3842262083335, 5007.949920500785, 5012.924806127911, 5020.548150666421, 5030.723310217936, 5040.546350828443, 3554.5201397811484, 3556.1643013891717, 3561.0, 3561.8397172127316, 3569.161247106928, 5069.771860266297, 5218.817341393071, 5224.294720212761, 5243.397009343898, 5254.21186821976, 3562.5012498250267, 3590.389633762267, 3597.730170237243, 3603.1352475405715, 3607.9693057831546, 3623.3840247158046, 3643.8178091095338, 3644.0, 3658.432942893856, 3661.4690869823726, 4198.5591512891315, 4210.263203939298, 4211.957880421157, 4221.535631080578, 4228.492580890252, 4913.484084171182, 5035.1048147314705, 5064.012517559041, 5077.289179514871, 5087.542121209109, 1233.4387853526623, 1235.6540168752763, 1237.7091383524864, 1239.4174737873186, 1243.8693937136652, 802.4984384759525, 806.846699127164, 838.9706360277361, 845.1928408369992, 847.3898792869124, 4191.015111448807, 4352.325389564622, 4412.382266482695, 4433.431087974147, 4444.266695995125, 5157.091876005902, 5164.1995490699, 5169.25045868347, 5173.0, 5178.239398604493, 4421.0547359161865, 4429.585875697752, 4439.267520859022, 4444.088961762831, 4455.331700244989, 2532.5568025595817, 2652.9388244701886, 2686.2478506018347, 2697.9460410792067, 2699.986500067487, 4700.626183777239, 4706.505816889177, 4733.9763301183275, 4740.834070807505, 4745.264484004984, 4919.483454237254, 5178.0, 5183.81856635016, 5188.584913206934, 5195.870103247449, 4662.906741865144, 4687.742174180385, 4726.747411935771, 4731.195696731564, 4732.692374995683, 5063.974680126576, 5065.56942659878, 5075.467075956972, 5085.321843791614, 5087.8963832063155, 4785.928211076767, 4942.640773787237, 4958.892470699866, 5001.899962000667, 5008.974955125201, 4831.396075490564, 4851.151048566416, 4865.829695960624, 4874.4150701916, 4890.437599676083, 3630.582483014487, 3642.162302670348, 3661.9816900915325, 3676.852925882935, 3696.1498855262903, 5073.518015788494, 5229.424763275961, 5237.895242095213, 5241.895162096813, 5250.763715632882, 5008.348914641073, 5018.573421259234, 5044.369453818273, 5044.974775126101, 5057.468965758542, 5013.674111182761, 5026.73796791438, 5039.899202016012, 5051.898962020812, 5053.620978426707, 4800.591949684316, 4990.15167421532, 4994.152163699455, 4996.6252531061045, 5021.44764075944, 4893.632977526629, 4905.264210368473, 5002.699838009731, 5066.518680725325, 5092.796288148581, 4871.123197824426, 4897.233063319213, 4908.312836203006, 4916.385451818523, 4916.606671466346], "value": 3780.9354162030854, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [618.1284389011495, 636.9840753981152, 642.3640595810148, 652.941235288824, 656.6510794945777, 11504.942475287622, 11796.997255233315, 11962.880371196296, 11974.221675591081, 12206.328651924141, 11788.823167652492, 12038.699032524191, 12068.24178460063, 12121.33332666703, 12128.757424851497, 786.6342150899831, 803.6664784114586, 803.7789607857827, 803.8111043904678, 804.0873608454417, 656.4551422319487, 684.5618803965466, 784.654751909161, 808.5876203136384, 812.0580127052621, 867.0505796153202, 869.7955980344614, 870.1342164546286, 870.7387783664907, 871.7646235516406, 863.6459051788736, 863.8617821148586, 863.952482613456, 864.5849992003873, 864.844328020956, 501.69145975225337, 518.1295423688202, 603.499095750526, 615.5106690181317, 631.5926227583229, 12034.33811140387, 12113.636590902199, 12120.212186207867, 12120.878791212062, 12122.090843186796, 11304.604338848101, 11636.185467017312, 11695.824562631567, 12022.819657705142, 12267.69330766729, 11131.826882442188, 11612.535498580046, 11735.295882247092, 11885.22746021506, 11885.821712674317, 4082.1631565528996, 4083.530394004698, 4095.7337773044655, 4164.583541645841, 4165.75005499671, 60804.69597652028, 61240.69379653073, 63644.68177659082, 64106.35893641051, 64754.0, 8989.46063236208, 9208.815823683488, 9225.169734723908, 9372.953135234417, 9509.096635819586, 17149.228284727218, 17357.392491263, 17430.477085687195, 17468.126593670266, 17552.561185970197, 6211.720472578745, 6282.623042617366, 6425.614463132227, 6428.839279018062, 6539.215294164731, 3546.414841551167, 3723.0506220913494, 3724.217914237986, 3725.906852328659, 3728.4034554471154, 2917.0957003329036, 2973.7918345716025, 2983.075246673538, 3012.578239046535, 3019.3357461358464, 511.0851575679492, 519.4805194805194, 522.3601088666429, 522.5767128625749, 524.6668365587802, 572.5562688916124, 593.7298529169218, 597.7549204826, 598.4972622996704, 605.1104875832519, 7661.693532258647, 7798.688052477951, 7825.217478252243, 7914.683412663544, 7932.0, 561.6377436553482, 577.6880484538302, 582.3171035706811, 588.3910152991718, 590.7755053079802, 3572.8392222350058, 3599.3521166190335, 3689.317476266899, 3720.720945929066, 3732.42147467143, 52882.471175287756, 53063.0, 54236.45763542393, 61524.38475615276, 68506.0, 42892.92767680833, 47582.0, 53341.93316133733, 55813.72093139508, 56880.71559642258, 48278.27582586264, 50003.0, 50546.49453505418, 50645.74677126665, 51218.48781512137, 7631.503952243086, 7790.571518566533, 7956.403269754907, 7956.578671171452, 8233.79415514617, 33690.98927032095, 34466.48300275548, 34654.0, 35097.64902351046, 35694.0, 42758.78620606877, 42965.355519666606, 43103.568964309954, 45535.77232113818, 45835.0, 10700.946495267473, 10817.56729730802, 10965.780684386427, 10995.285306455055, 11304.378259195822, 11873.287602743518, 11877.109216808602, 12491.063170262449, 12541.498340066295, 12595.496180153057, 11278.718032049264, 11533.88466115328, 11535.884641153816, 12096.697582560506, 12141.575044873387, 9673.9032609673, 9681.177099946583, 9698.321117521988, 10033.749156271151, 10101.898981010096, 10436.739081523023, 10589.629362972259, 10657.573697052341, 10660.680179594625, 10754.13966882632, 8271.503709777433, 8359.582020899052, 8441.240288374076, 8588.914110858812, 8677.479351238946, 8865.955670221607, 8907.554622268732, 8935.106489351272, 8986.640534378814, 9033.407483714318], "value": 6045.0177377850305, "unit": "score"}], "name": "dromaeo_css", "value": 6464.114285739368, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 114.0, 114.0, 116.0, 115.0, 116.0, 114.0, 114.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 124.0, 126.0, 157.0, 128.0, 124.0, 124.0, 124.0, 148.0, 124.0], "value": 133.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 162.0, 161.0, 160.0, 160.0, 161.0, 161.0, 159.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 103.0, 76.0, 77.0, 77.0, 77.0, 95.0, 75.0], "value": 80.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 110.0, 85.0, 86.0, 84.0, 99.0, 84.0, 84.0, 107.0], "value": 90.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 118.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 148.0, 149.0, 148.0, 148.0, 148.0, 148.0, 148.0, 149.0], "value": 148.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 69.0, 63.0, 61.0, 64.0, 64.0, 63.0, 72.0], "value": 64.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 67.0, 67.0, 67.0, 67.0, 67.0, 67.0, 67.0], "value": 66.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 86.0, 94.0, 85.0, 96.0, 88.0, 86.0, 95.0, 85.0], "value": 88.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 133.0, 133.0, 140.0, 132.0, 130.0, 141.0, 137.0, 175.0, 134.0], "value": 139.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 179.0, 175.0, 175.0, 174.0, 175.0, 175.0, 176.0, 184.0, 176.0], "value": 176.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 67.0, 68.0, 67.0, 68.0, 68.0, 69.0, 68.0, 68.0], "value": 68.2, "unit": "score"}], "name": "kraken", "value": 1544.3000000000002, "alertThreshold": 2.0}]} 18:14:29 INFO - Return code: 0 18:14:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:14:29 INFO - # TBPL SUCCESS # 18:14:29 INFO - Running post-action listener: _resource_record_post_action 18:14:29 INFO - [mozharness: 2017-01-06 02:14:29.897344Z] Finished run-tests step (success) 18:14:29 INFO - Running post-run listener: _resource_record_post_run 18:14:29 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 4096; Write bytes: 383500288; Read time: 12; Write time: 323368 18:14:29 INFO - TinderboxPrint: CPU usage
13.1% 18:14:29 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 18:14:29 INFO - TinderboxPrint: I/O write bytes / time
383,500,288 / 323,368 18:14:29 INFO - TinderboxPrint: CPU idle
8,344.3 (86.9%) 18:14:29 INFO - TinderboxPrint: CPU user
1,211.8 (12.6%) 18:14:29 INFO - TinderboxPrint: Swap in / out
0 / 0 18:14:29 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 101306368; Read time: 0; Write time: 119220 18:14:29 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 0; Write bytes: 281329664; Read time: 0; Write time: 204040 18:14:30 INFO - Running post-run listener: _upload_blobber_files 18:14:30 INFO - Blob upload gear active. 18:14:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:14:30 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:14:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:14:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:14:30 INFO - (blobuploader) - INFO - Open directory for files ... 18:14:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:14:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:14:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:14:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:14:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:14:35 INFO - (blobuploader) - INFO - Done attempting. 18:14:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:14:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:14:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:14:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:14:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:14:36 INFO - (blobuploader) - INFO - Done attempting. 18:14:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:14:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:14:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:14:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:14:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:14:37 INFO - (blobuploader) - INFO - Done attempting. 18:14:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:14:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:14:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:14:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:14:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:14:38 INFO - (blobuploader) - INFO - Done attempting. 18:14:38 INFO - (blobuploader) - INFO - Iteration through files over. 18:14:38 INFO - Return code: 0 18:14:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:14:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:14:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3a3ba8f76debcd3985ea7756edb942341d287e49b76aa1cd616c7fed4047b4148b57d401f8f446bc438b864f45474ec6bacb650d09994f5e0a4e0fb610688c6f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/37b0ea22e2ea6aa4e2666c0cc50b6187652c940863eeceba254bf1047ea8bcde523c4fbf262a63910bcc3df131135b19b1b761d2812241b72e68d80bdf2e70e2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8efe915ffc7d264321e8062f0725ab13b0a1e5552315371d1c871e1694c57024ca7594abe5d659a3e18253f98c2557fe87bfe7b20fa69cf251edbd9f6437f509", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6f91719b4e1207835a498cd624641c08793487ca6e916187dcd5b28ac7b6de36c2777ce3412c3d0e21ca71a4097c0e036f36e433c14bb001a05abd4fa37b6f25"} 18:14:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:14:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:14:38 INFO - Contents: 18:14:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3a3ba8f76debcd3985ea7756edb942341d287e49b76aa1cd616c7fed4047b4148b57d401f8f446bc438b864f45474ec6bacb650d09994f5e0a4e0fb610688c6f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/37b0ea22e2ea6aa4e2666c0cc50b6187652c940863eeceba254bf1047ea8bcde523c4fbf262a63910bcc3df131135b19b1b761d2812241b72e68d80bdf2e70e2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8efe915ffc7d264321e8062f0725ab13b0a1e5552315371d1c871e1694c57024ca7594abe5d659a3e18253f98c2557fe87bfe7b20fa69cf251edbd9f6437f509", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6f91719b4e1207835a498cd624641c08793487ca6e916187dcd5b28ac7b6de36c2777ce3412c3d0e21ca71a4097c0e036f36e433c14bb001a05abd4fa37b6f25"} 18:14:38 INFO - Running post-run listener: copy_logs_to_upload_dir 18:14:38 INFO - Copying logs to upload dir... 18:14:38 INFO - mkdir: /builds/slave/test/build/upload/logs 18:14:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.162878 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-01-05 18:14:37.886315) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 18:14:37.889611) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3a3ba8f76debcd3985ea7756edb942341d287e49b76aa1cd616c7fed4047b4148b57d401f8f446bc438b864f45474ec6bacb650d09994f5e0a4e0fb610688c6f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/37b0ea22e2ea6aa4e2666c0cc50b6187652c940863eeceba254bf1047ea8bcde523c4fbf262a63910bcc3df131135b19b1b761d2812241b72e68d80bdf2e70e2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8efe915ffc7d264321e8062f0725ab13b0a1e5552315371d1c871e1694c57024ca7594abe5d659a3e18253f98c2557fe87bfe7b20fa69cf251edbd9f6437f509", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6f91719b4e1207835a498cd624641c08793487ca6e916187dcd5b28ac7b6de36c2777ce3412c3d0e21ca71a4097c0e036f36e433c14bb001a05abd4fa37b6f25"} build_url:https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011329 build_url: 'https://queue.taskcluster.net/v1/task/evJgljTzQC-5Qt9CU749Qw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3a3ba8f76debcd3985ea7756edb942341d287e49b76aa1cd616c7fed4047b4148b57d401f8f446bc438b864f45474ec6bacb650d09994f5e0a4e0fb610688c6f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/37b0ea22e2ea6aa4e2666c0cc50b6187652c940863eeceba254bf1047ea8bcde523c4fbf262a63910bcc3df131135b19b1b761d2812241b72e68d80bdf2e70e2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8efe915ffc7d264321e8062f0725ab13b0a1e5552315371d1c871e1694c57024ca7594abe5d659a3e18253f98c2557fe87bfe7b20fa69cf251edbd9f6437f509", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6f91719b4e1207835a498cd624641c08793487ca6e916187dcd5b28ac7b6de36c2777ce3412c3d0e21ca71a4097c0e036f36e433c14bb001a05abd4fa37b6f25"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 18:14:37.915085) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 18:14:37.915423) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=32c5991c409366f7fec8a718000001d2-1483667620.525826-1804358429 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004436 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 18:14:38.001120) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 18:14:38.001449) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 18:14:38.001781) ========= ========= Total master_lag: 0.38 =========