builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-035 starttime: 1450699177.72 results: success (0) buildid: 20151221024928 builduid: 99e5cd6198a247ffa1a93238ad634f1a revision: fe2802097d8613deb9f0d89b6ed876e314c886d0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-21 03:59:37.716544) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-21 03:59:37.717056) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-21 03:59:37.717371) ========= bash -c pwd in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] 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=0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006276 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-21 03:59:37.744757) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-21 03:59:37.745111) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-21 03:59:37.765559) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 03:59:37.765900) ========= rm -rf properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', '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=0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004618 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 03:59:37.816444) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-21 03:59:37.816768) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-21 03:59:37.817195) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 03:59:37.817762) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-21 03:59:38-- 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: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2015-12-21 03:59:38 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123474 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 03:59:37.961662) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 03:59:37.961963) ========= rm -rf scripts in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] 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=0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013671 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 03:59:38.015658) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-21 03:59:38.015973) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fe2802097d8613deb9f0d89b6ed876e314c886d0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev fe2802097d8613deb9f0d89b6ed876e314c886d0 --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=0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-21 03:59:38,618 truncating revision to first 12 chars 2015-12-21 03:59:38,618 Setting DEBUG logging. 2015-12-21 03:59:38,618 attempt 1/10 2015-12-21 03:59:38,619 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fe2802097d86?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-21 03:59:39,658 unpacking tar archive at: mozilla-central-fe2802097d86/testing/mozharness/ program finished with exit code 0 elapsedTime=1.333760 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-21 03:59:39.362696) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-21 03:59:39.363040) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-21 03:59:39.373071) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-21 03:59:39.373340) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-21 03:59:39.373748) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2015-12-21 03:59:39.374090) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--system-bits', '64', '--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=0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:59:40 INFO - MultiFileLogger online at 20151221 03:59:40 in /builds/slave/test 03:59:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 03:59:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:59:40 INFO - {'append_to_log': False, 03:59:40 INFO - 'base_work_dir': '/builds/slave/test', 03:59:40 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 03:59:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:59:40 INFO - 'branch': 'Firefox-Non-PGO', 03:59:40 INFO - 'buildbot_json_path': 'buildprops.json', 03:59:40 INFO - 'config_files': ('talos/linux_config.py',), 03:59:40 INFO - 'default_actions': ('clobber', 03:59:40 INFO - 'read-buildbot-config', 03:59:40 INFO - 'download-and-extract', 03:59:40 INFO - 'populate-webroot', 03:59:40 INFO - 'create-virtualenv', 03:59:40 INFO - 'install', 03:59:40 INFO - 'run-tests'), 03:59:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:59:40 INFO - 'download_minidump_stackwalk': True, 03:59:40 INFO - 'download_symbols': 'ondemand', 03:59:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:59:40 INFO - 'tooltool.py': '/tools/tooltool.py', 03:59:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:59:40 INFO - '/tools/misc-python/virtualenv.py')}, 03:59:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:59:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:59:40 INFO - 'installer_path': 'installer.exe', 03:59:40 INFO - 'log_level': 'info', 03:59:40 INFO - 'log_name': 'talos', 03:59:40 INFO - 'log_to_console': True, 03:59:40 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:59:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:59:40 INFO - 'opt_config_files': (), 03:59:40 INFO - 'pip_index': False, 03:59:40 INFO - 'sps_profile': False, 03:59:40 INFO - 'sps_profile_interval': 0, 03:59:40 INFO - 'suite': 'dromaeojs-e10s', 03:59:40 INFO - 'system_bits': '64', 03:59:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:59:40 INFO - 'title': 'talos-linux64-ix-035', 03:59:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:59:40 INFO - 'use_talos_json': True, 03:59:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:59:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:59:40 INFO - 'work_dir': 'build'} 03:59:40 INFO - ##### 03:59:40 INFO - ##### Running clobber step. 03:59:40 INFO - ##### 03:59:40 INFO - Running pre-action listener: _resource_record_pre_action 03:59:40 INFO - Running main action method: clobber 03:59:40 INFO - rmtree: /builds/slave/test/build 03:59:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:59:40 INFO - Running post-action listener: _resource_record_post_action 03:59:40 INFO - ##### 03:59:40 INFO - ##### Running read-buildbot-config step. 03:59:40 INFO - ##### 03:59:40 INFO - Running pre-action listener: _resource_record_pre_action 03:59:40 INFO - Running main action method: read_buildbot_config 03:59:40 INFO - Using buildbot properties: 03:59:40 INFO - { 03:59:40 INFO - "project": "", 03:59:40 INFO - "product": "firefox", 03:59:40 INFO - "script_repo_revision": "production", 03:59:40 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 03:59:40 INFO - "repository": "", 03:59:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 03:59:40 INFO - "buildid": "20151221024928", 03:59:40 INFO - "pgo_build": "False", 03:59:40 INFO - "basedir": "/builds/slave/test", 03:59:40 INFO - "buildnumber": 182, 03:59:40 INFO - "slavename": "talos-linux64-ix-035", 03:59:40 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:59:40 INFO - "platform": "ubuntu64_hw", 03:59:40 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 03:59:40 INFO - "branch": "mozilla-central", 03:59:40 INFO - "repo_path": "mozilla-central", 03:59:40 INFO - "revision": "fe2802097d8613deb9f0d89b6ed876e314c886d0", 03:59:40 INFO - "stage_platform": "linux64", 03:59:40 INFO - "builduid": "99e5cd6198a247ffa1a93238ad634f1a", 03:59:40 INFO - "slavebuilddir": "test" 03:59:40 INFO - } 03:59:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 03:59:40 INFO - Running post-action listener: _resource_record_post_action 03:59:40 INFO - ##### 03:59:40 INFO - ##### Running download-and-extract step. 03:59:40 INFO - ##### 03:59:40 INFO - Running pre-action listener: _resource_record_pre_action 03:59:40 INFO - Running main action method: download_and_extract 03:59:40 INFO - mkdir: /builds/slave/test/build/tests 03:59:40 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:59:40 INFO - https://queue.taskcluster.net/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 03:59:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/test_packages.json 03:59:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/test_packages.json 03:59:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 03:59:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 03:59:45 INFO - Downloaded 1302 bytes. 03:59:45 INFO - Reading from file /builds/slave/test/build/test_packages.json 03:59:45 INFO - Using the following test package requirements: 03:59:45 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 03:59:45 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 03:59:45 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 03:59:45 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 03:59:45 INFO - u'jsshell-linux-x86_64.zip'], 03:59:45 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 03:59:45 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 03:59:45 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 03:59:45 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 03:59:45 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 03:59:45 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 03:59:45 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 03:59:45 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 03:59:45 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 03:59:45 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 03:59:45 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 03:59:45 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 03:59:45 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:59:45 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:59:45 INFO - https://queue.taskcluster.net/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:59:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 03:59:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 03:59:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 03:59:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:59:53 INFO - Downloaded 21871955 bytes. 03:59:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 03:59:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 03:59:53 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 03:59:54 INFO - Return code: 0 03:59:54 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:59:54 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:59:54 INFO - https://queue.taskcluster.net/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:59:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 03:59:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 03:59:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 03:59:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:59:55 INFO - Downloaded 21871955 bytes. 03:59:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 03:59:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 03:59:55 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 03:59:56 INFO - Return code: 0 03:59:56 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:59:56 INFO - https://queue.taskcluster.net/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:59:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 03:59:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 03:59:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 03:59:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:59:57 INFO - Downloaded 11132039 bytes. 03:59:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 03:59:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 03:59:57 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 03:59:58 INFO - Return code: 0 03:59:58 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:59:58 INFO - https://queue.taskcluster.net/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:59:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 03:59:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 03:59:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 03:59:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:59:59 INFO - Downloaded 53020251 bytes. 03:59:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 03:59:59 INFO - mkdir: /builds/slave/test/properties 03:59:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:59:59 INFO - Writing to file /builds/slave/test/properties/build_url 03:59:59 INFO - Contents: 03:59:59 INFO - build_url:https://queue.taskcluster.net/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 03:59:59 INFO - Running post-action listener: _resource_record_post_action 03:59:59 INFO - Running post-action listener: set_extra_try_arguments 03:59:59 INFO - ##### 03:59:59 INFO - ##### Running populate-webroot step. 03:59:59 INFO - ##### 03:59:59 INFO - Running pre-action listener: _resource_record_pre_action 03:59:59 INFO - Running main action method: populate_webroot 03:59:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:59:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:59:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:59:59 INFO - '--tppagecycles', 03:59:59 INFO - '7'], 03:59:59 INFO - 'tests': ['tsvgm']}}, 03:59:59 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 03:59:59 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 03:59:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:59:59 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 03:59:59 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 03:59:59 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 03:59:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:59:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:59:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:59:59 INFO - 'talos_options': ['--e10s'], 03:59:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:59:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:59:59 INFO - 'tests': ['damp', 'tps']}, 03:59:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:59:59 INFO - 'talos_options': ['--e10s'], 03:59:59 INFO - 'tests': ['damp', 'tps']}, 03:59:59 INFO - 'g3': {'tests': ['dromaeo_dom']}, 03:59:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:59:59 INFO - 'other': {'tests': ['a11yr', 03:59:59 INFO - 'ts_paint', 03:59:59 INFO - 'tpaint', 03:59:59 INFO - 'sessionrestore', 03:59:59 INFO - 'sessionrestore_no_auto_restore']}, 03:59:59 INFO - 'other-e10s': {'talos_options': ['--e10s'], 03:59:59 INFO - 'tests': ['a11yr', 03:59:59 INFO - 'ts_paint', 03:59:59 INFO - 'tpaint', 03:59:59 INFO - 'sessionrestore', 03:59:59 INFO - 'sessionrestore_no_auto_restore']}, 03:59:59 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 03:59:59 INFO - 'tests': ['a11yr', 03:59:59 INFO - 'ts_paint', 03:59:59 INFO - 'tpaint', 03:59:59 INFO - 'sessionrestore', 03:59:59 INFO - 'sessionrestore_no_auto_restore']}, 03:59:59 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 03:59:59 INFO - 'tests': ['a11yr', 03:59:59 INFO - 'ts_paint', 03:59:59 INFO - 'tpaint', 03:59:59 INFO - 'sessionrestore', 03:59:59 INFO - 'sessionrestore_no_auto_restore']}, 03:59:59 INFO - 'other_l64': {'tests': ['a11yr', 03:59:59 INFO - 'ts_paint', 03:59:59 INFO - 'tpaint', 03:59:59 INFO - 'sessionrestore', 03:59:59 INFO - 'sessionrestore_no_auto_restore']}, 03:59:59 INFO - 'other_nol64': {'tests': ['a11yr', 03:59:59 INFO - 'ts_paint', 03:59:59 INFO - 'tpaint', 03:59:59 INFO - 'sessionrestore', 03:59:59 INFO - 'sessionrestore_no_auto_restore']}, 03:59:59 INFO - 'svgr': {'tests': ['tsvgx', 03:59:59 INFO - 'tsvgr_opacity', 03:59:59 INFO - 'tart', 03:59:59 INFO - 'tscrollx', 03:59:59 INFO - 'cart']}, 03:59:59 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 03:59:59 INFO - 'tests': ['tsvgx', 03:59:59 INFO - 'tsvgr_opacity', 03:59:59 INFO - 'tart', 03:59:59 INFO - 'tscrollx', 03:59:59 INFO - 'cart']}, 03:59:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:59:59 INFO - 'tests': ['tp5o']}, 03:59:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:59:59 INFO - 'talos_options': ['--e10s'], 03:59:59 INFO - 'tests': ['tp5o']}, 03:59:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:59:59 INFO - 'talos_options': ['--xperf_path', 03:59:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:59:59 INFO - 'tests': ['tp5n']}, 03:59:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:59:59 INFO - 'talos_options': ['--e10s', 03:59:59 INFO - '--xperf_path', 03:59:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:59:59 INFO - 'tests': ['tp5n']}}, 03:59:59 INFO - 'talos.zip': {'path': '', 03:59:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:59:59 INFO - Running post-action listener: _resource_record_post_action 03:59:59 INFO - ##### 03:59:59 INFO - ##### Running create-virtualenv step. 03:59:59 INFO - ##### 03:59:59 INFO - Running pre-action listener: _resource_record_pre_action 03:59:59 INFO - Running main action method: create_virtualenv 03:59:59 INFO - Creating virtualenv /builds/slave/test/build/venv 03:59:59 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 03:59:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:59:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:59:59 INFO - Using real prefix '/usr' 03:59:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:00:00 INFO - Installing distribute.............................................................................................................................................................................................done. 04:00:01 INFO - Installing pip.................done. 04:00:01 INFO - Return code: 0 04:00:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:00:02 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:02 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f4098b78f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee5240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf740b0>, '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 0xf74fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf75440>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612', '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 04:00:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 04:00:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 04:00:02 INFO - Using env: {'DISPLAY': ':0', 04:00:02 INFO - 'HOME': '/home/cltbld', 04:00:02 INFO - 'LANG': 'en_US.UTF-8', 04:00:02 INFO - 'LANGUAGE': 'en_US:en', 04:00:02 INFO - 'LOGNAME': 'cltbld', 04:00:02 INFO - 'MAIL': '/var/mail/cltbld', 04:00:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:02 INFO - 'MOZ_NO_REMOTE': '1', 04:00:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:02 INFO - 'NO_EM_RESTART': '1', 04:00:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:02 INFO - 'PWD': '/builds/slave/test', 04:00:02 INFO - 'SHELL': '/bin/bash', 04:00:02 INFO - 'SHLVL': '1', 04:00:02 INFO - 'TERM': 'linux', 04:00:02 INFO - 'TMOUT': '86400', 04:00:02 INFO - 'USER': 'cltbld', 04:00:02 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612', 04:00:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:02 INFO - '_': '/tools/buildbot/bin/python'} 04:00:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:02 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 04:00:02 INFO - Cleaning up... 04:00:02 INFO - Return code: 0 04:00:02 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:00:02 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:02 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4098b78f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee5240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf740b0>, '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 0xf74fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf75440>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612', '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 04:00:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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>=0.7.1'] in /builds/slave/test/build 04:00:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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>=0.7.1 04:00:02 INFO - Using env: {'DISPLAY': ':0', 04:00:02 INFO - 'HOME': '/home/cltbld', 04:00:02 INFO - 'LANG': 'en_US.UTF-8', 04:00:02 INFO - 'LANGUAGE': 'en_US:en', 04:00:02 INFO - 'LOGNAME': 'cltbld', 04:00:02 INFO - 'MAIL': '/var/mail/cltbld', 04:00:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:02 INFO - 'MOZ_NO_REMOTE': '1', 04:00:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:02 INFO - 'NO_EM_RESTART': '1', 04:00:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:02 INFO - 'PWD': '/builds/slave/test', 04:00:02 INFO - 'SHELL': '/bin/bash', 04:00:02 INFO - 'SHLVL': '1', 04:00:02 INFO - 'TERM': 'linux', 04:00:02 INFO - 'TMOUT': '86400', 04:00:02 INFO - 'USER': 'cltbld', 04:00:02 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612', 04:00:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:02 INFO - '_': '/tools/buildbot/bin/python'} 04:00:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:02 INFO - Downloading/unpacking psutil>=0.7.1 04:00:02 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 04:00:02 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 04:00:02 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 04:00:02 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 04:00:02 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 04:00:02 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 04:00:04 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 04:00:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 04:00:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:00:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:00:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:00:04 INFO - Installing collected packages: psutil 04:00:04 INFO - Running setup.py install for psutil 04:00:04 INFO - building 'psutil._psutil_linux' extension 04:00:04 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 04:00:04 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 04:00:04 INFO - building 'psutil._psutil_posix' extension 04:00:04 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 04:00:04 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 04:00:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:00:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:00:04 INFO - Successfully installed psutil 04:00:04 INFO - Cleaning up... 04:00:04 INFO - Return code: 0 04:00:04 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:00:04 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:04 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4098b78f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee5240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf740b0>, '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 0xf74fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf75440>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612', '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 04:00:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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.0.0'] in /builds/slave/test/build 04:00:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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.0.0 04:00:04 INFO - Using env: {'DISPLAY': ':0', 04:00:04 INFO - 'HOME': '/home/cltbld', 04:00:04 INFO - 'LANG': 'en_US.UTF-8', 04:00:04 INFO - 'LANGUAGE': 'en_US:en', 04:00:04 INFO - 'LOGNAME': 'cltbld', 04:00:04 INFO - 'MAIL': '/var/mail/cltbld', 04:00:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:04 INFO - 'MOZ_NO_REMOTE': '1', 04:00:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:04 INFO - 'NO_EM_RESTART': '1', 04:00:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:04 INFO - 'PWD': '/builds/slave/test', 04:00:04 INFO - 'SHELL': '/bin/bash', 04:00:04 INFO - 'SHLVL': '1', 04:00:04 INFO - 'TERM': 'linux', 04:00:04 INFO - 'TMOUT': '86400', 04:00:04 INFO - 'USER': 'cltbld', 04:00:04 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612', 04:00:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:04 INFO - '_': '/tools/buildbot/bin/python'} 04:00:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:05 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:00:05 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 04:00:05 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 04:00:05 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 04:00:05 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 04:00:05 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 04:00:05 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 04:00:06 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:00:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 04:00:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:00:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:00:06 INFO - Installing collected packages: mozsystemmonitor 04:00:06 INFO - Running setup.py install for mozsystemmonitor 04:00:06 INFO - Successfully installed mozsystemmonitor 04:00:06 INFO - Cleaning up... 04:00:06 INFO - Return code: 0 04:00:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:00: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f4098b78f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee5240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf740b0>, '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 0xf74fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf75440>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612', '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 04:00:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 04:00:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 04:00:06 INFO - Using env: {'DISPLAY': ':0', 04:00:06 INFO - 'HOME': '/home/cltbld', 04:00:06 INFO - 'LANG': 'en_US.UTF-8', 04:00:06 INFO - 'LANGUAGE': 'en_US:en', 04:00:06 INFO - 'LOGNAME': 'cltbld', 04:00:06 INFO - 'MAIL': '/var/mail/cltbld', 04:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:06 INFO - 'MOZ_NO_REMOTE': '1', 04:00:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:06 INFO - 'NO_EM_RESTART': '1', 04:00:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:06 INFO - 'PWD': '/builds/slave/test', 04:00:06 INFO - 'SHELL': '/bin/bash', 04:00:06 INFO - 'SHLVL': '1', 04:00:06 INFO - 'TERM': 'linux', 04:00:06 INFO - 'TMOUT': '86400', 04:00:06 INFO - 'USER': 'cltbld', 04:00:06 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612', 04:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:06 INFO - '_': '/tools/buildbot/bin/python'} 04:00:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:07 INFO - Downloading/unpacking blobuploader==1.2.4 04:00: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 04:00: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 04:00: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 04:00: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 04:00: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 04:00: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 04:00:08 INFO - Downloading blobuploader-1.2.4.tar.gz 04:00:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 04:00:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:00:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:00:08 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 04:00:08 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 04:00:08 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 04:00:08 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 04:00:08 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 04:00:08 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 04:00:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 04:00:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:00:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:00:09 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 04:00:09 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 04:00:09 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 04:00:09 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 04:00:09 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 04:00:09 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 04:00:09 INFO - Downloading docopt-0.6.1.tar.gz 04:00:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 04:00:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:00:09 INFO - Installing collected packages: blobuploader, requests, docopt 04:00:09 INFO - Running setup.py install for blobuploader 04:00:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:00:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:00:09 INFO - Running setup.py install for requests 04:00:09 INFO - Running setup.py install for docopt 04:00:10 INFO - Successfully installed blobuploader requests docopt 04:00:10 INFO - Cleaning up... 04:00:10 INFO - Return code: 0 04:00:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:00:10 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:10 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f4098b78f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee5240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf740b0>, '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 0xf74fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf75440>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612', '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 04:00:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 04:00:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test/build/venv/cache --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 04:00:10 INFO - Using env: {'DISPLAY': ':0', 04:00:10 INFO - 'HOME': '/home/cltbld', 04:00:10 INFO - 'LANG': 'en_US.UTF-8', 04:00:10 INFO - 'LANGUAGE': 'en_US:en', 04:00:10 INFO - 'LOGNAME': 'cltbld', 04:00:10 INFO - 'MAIL': '/var/mail/cltbld', 04:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:10 INFO - 'MOZ_NO_REMOTE': '1', 04:00:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:10 INFO - 'NO_EM_RESTART': '1', 04:00:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:10 INFO - 'PWD': '/builds/slave/test', 04:00:10 INFO - 'SHELL': '/bin/bash', 04:00:10 INFO - 'SHLVL': '1', 04:00:10 INFO - 'TERM': 'linux', 04:00:10 INFO - 'TMOUT': '86400', 04:00:10 INFO - 'USER': 'cltbld', 04:00:10 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612', 04:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:10 INFO - '_': '/tools/buildbot/bin/python'} 04:00:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:00:10 INFO - Running setup.py (path:/tmp/pip-2yjJ8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:00:10 INFO - Running setup.py (path:/tmp/pip-JDRdhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:00:10 INFO - Running setup.py (path:/tmp/pip-LAfmun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:00:10 INFO - Running setup.py (path:/tmp/pip-1W1xvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:00:10 INFO - Running setup.py (path:/tmp/pip-OQXk0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:00:10 INFO - Running setup.py (path:/tmp/pip-1BbTTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:00:10 INFO - Running setup.py (path:/tmp/pip-QAqTvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:00:10 INFO - Running setup.py (path:/tmp/pip-c19euJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:00:10 INFO - Running setup.py (path:/tmp/pip-GgfxLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:00:10 INFO - Running setup.py (path:/tmp/pip-tJcHQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:00:10 INFO - Running setup.py (path:/tmp/pip-FfLKKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:00:11 INFO - Running setup.py (path:/tmp/pip-RdN5cz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:00:11 INFO - Running setup.py (path:/tmp/pip-rqnwRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:00:11 INFO - Running setup.py (path:/tmp/pip-0kbk3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:00:11 INFO - Running setup.py (path:/tmp/pip-3mieP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:00:11 INFO - Running setup.py (path:/tmp/pip-GLLUEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:00:11 INFO - Running setup.py (path:/tmp/pip-T4Osk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:00:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:00:11 INFO - Running setup.py install for manifestparser 04:00:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:00:11 INFO - Running setup.py install for mozcrash 04:00:11 INFO - Running setup.py install for mozdebug 04:00:11 INFO - Running setup.py install for mozdevice 04:00:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:00:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:00:11 INFO - Running setup.py install for mozfile 04:00:12 INFO - Running setup.py install for mozhttpd 04:00:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:00:12 INFO - Running setup.py install for mozinfo 04:00:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:00:12 INFO - Running setup.py install for mozInstall 04:00:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:00:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:00:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:00:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:00:12 INFO - Running setup.py install for mozleak 04:00:12 INFO - Running setup.py install for mozlog 04:00:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:00:12 INFO - Running setup.py install for moznetwork 04:00:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:00:12 INFO - Running setup.py install for mozprocess 04:00:12 INFO - Running setup.py install for mozprofile 04:00:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:00:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:00:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:00:12 INFO - Running setup.py install for mozrunner 04:00:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:00:12 INFO - Running setup.py install for mozscreenshot 04:00:13 INFO - Running setup.py install for moztest 04:00:13 INFO - Running setup.py install for mozversion 04:00:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:00:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:00:13 INFO - Cleaning up... 04:00:13 INFO - Return code: 0 04:00:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:00:13 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:13 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f4098b78f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee5240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf740b0>, '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 0xf74fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf75440>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612', '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 04:00:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 04:00:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 04:00:13 INFO - Using env: {'DISPLAY': ':0', 04:00:13 INFO - 'HOME': '/home/cltbld', 04:00:13 INFO - 'LANG': 'en_US.UTF-8', 04:00:13 INFO - 'LANGUAGE': 'en_US:en', 04:00:13 INFO - 'LOGNAME': 'cltbld', 04:00:13 INFO - 'MAIL': '/var/mail/cltbld', 04:00:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:13 INFO - 'MOZ_NO_REMOTE': '1', 04:00:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:13 INFO - 'NO_EM_RESTART': '1', 04:00:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:13 INFO - 'PWD': '/builds/slave/test', 04:00:13 INFO - 'SHELL': '/bin/bash', 04:00:13 INFO - 'SHLVL': '1', 04:00:13 INFO - 'TERM': 'linux', 04:00:13 INFO - 'TMOUT': '86400', 04:00:13 INFO - 'USER': 'cltbld', 04:00:13 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612', 04:00:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:13 INFO - '_': '/tools/buildbot/bin/python'} 04:00:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:00:13 INFO - Running setup.py (path:/tmp/pip-rKh7Rr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:00:13 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)) 04:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:00:13 INFO - Running setup.py (path:/tmp/pip-djBm6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:00:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 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)) 04:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:00:13 INFO - Running setup.py (path:/tmp/pip-pjMGPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:00:13 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)) 04:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:00:13 INFO - Running setup.py (path:/tmp/pip-L2I6eZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:00:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 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)) 04:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:00:13 INFO - Running setup.py (path:/tmp/pip-FBlHzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:00:13 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)) 04:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:00:13 INFO - Running setup.py (path:/tmp/pip-KhRpQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:00:13 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)) 04:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:00:13 INFO - Running setup.py (path:/tmp/pip-ZJFNjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:00:14 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)) 04:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:00:14 INFO - Running setup.py (path:/tmp/pip-MRgjfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:00:14 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)) 04:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:00:14 INFO - Running setup.py (path:/tmp/pip-8_TC0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:00:14 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)) 04:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:00:14 INFO - Running setup.py (path:/tmp/pip-bVDu0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 04:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:00:14 INFO - Running setup.py (path:/tmp/pip-U43skk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:00:14 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)) 04:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:00:14 INFO - Running setup.py (path:/tmp/pip-hejxJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 04:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:00:14 INFO - Running setup.py (path:/tmp/pip-iWyW4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 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)) 04:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:00:14 INFO - Running setup.py (path:/tmp/pip-SKvazJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 04:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:00:14 INFO - Running setup.py (path:/tmp/pip-fDNcsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:00:14 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)) 04:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:00:14 INFO - Running setup.py (path:/tmp/pip-ZPEdqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 04:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:00:14 INFO - Running setup.py (path:/tmp/pip-vtC81H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:00:14 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)) 04:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:00:14 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)) 04:00:14 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.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:00:14 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.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:00:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:00: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 04:00: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 04:00: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 04:00: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 04:00: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 04:00: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 04:00:16 INFO - Downloading blessings-1.5.1.tar.gz 04:00:16 INFO - Storing download in cache at /builds/slave/test/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 04:00:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:00:16 INFO - Installing collected packages: blessings 04:00:16 INFO - Running setup.py install for blessings 04:00:16 INFO - Successfully installed blessings 04:00:16 INFO - Cleaning up... 04:00:16 INFO - Return code: 0 04:00:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:00:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:00:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:00:16 INFO - Reading from file tmpfile_stdout 04:00:16 INFO - Current package versions: 04:00:16 INFO - argparse == 1.2.1 04:00:16 INFO - blessings == 1.5.1 04:00:16 INFO - blobuploader == 1.2.4 04:00:16 INFO - docopt == 0.6.1 04:00:16 INFO - manifestparser == 1.1 04:00:16 INFO - mozInstall == 1.12 04:00:16 INFO - mozcrash == 0.16 04:00:16 INFO - mozdebug == 0.1 04:00:16 INFO - mozdevice == 0.47 04:00:16 INFO - mozfile == 1.2 04:00:16 INFO - mozhttpd == 0.7 04:00:16 INFO - mozinfo == 0.9 04:00:16 INFO - mozleak == 0.1 04:00:16 INFO - mozlog == 3.1 04:00:16 INFO - moznetwork == 0.27 04:00:16 INFO - mozprocess == 0.22 04:00:16 INFO - mozprofile == 0.27 04:00:16 INFO - mozrunner == 6.11 04:00:16 INFO - mozscreenshot == 0.1 04:00:16 INFO - mozsystemmonitor == 0.0 04:00:16 INFO - moztest == 0.7 04:00:16 INFO - mozversion == 1.4 04:00:16 INFO - psutil == 3.1.1 04:00:16 INFO - requests == 1.2.3 04:00:16 INFO - wsgiref == 0.1.2 04:00:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:00:16 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:16 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f4098b78f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee5240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf740b0>, '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 0xf74fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf75440>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612', '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 04:00:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 04:00:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 04:00:16 INFO - Using env: {'DISPLAY': ':0', 04:00:16 INFO - 'HOME': '/home/cltbld', 04:00:16 INFO - 'LANG': 'en_US.UTF-8', 04:00:16 INFO - 'LANGUAGE': 'en_US:en', 04:00:16 INFO - 'LOGNAME': 'cltbld', 04:00:16 INFO - 'MAIL': '/var/mail/cltbld', 04:00:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:16 INFO - 'MOZ_NO_REMOTE': '1', 04:00:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:16 INFO - 'NO_EM_RESTART': '1', 04:00:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:16 INFO - 'PWD': '/builds/slave/test', 04:00:16 INFO - 'SHELL': '/bin/bash', 04:00:16 INFO - 'SHLVL': '1', 04:00:16 INFO - 'TERM': 'linux', 04:00:16 INFO - 'TMOUT': '86400', 04:00:16 INFO - 'USER': 'cltbld', 04:00:16 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612', 04:00:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:16 INFO - '_': '/tools/buildbot/bin/python'} 04:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:17 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 1)) 04:00:17 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 2)) 04:00:17 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 3)) 04:00:17 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 4)) 04:00:17 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 5)) 04:00:17 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 6)) 04:00:17 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 7)) 04:00:17 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 8)) 04:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:00:17 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 3)) 04:00:17 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.0->mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:00:17 INFO - Cleaning up... 04:00:17 INFO - Return code: 0 04:00:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:00:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 0x7f4098b78f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee5240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf740b0>, '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 0xf74fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf75440>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612', '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 04:00:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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 04:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 04:00:17 INFO - Using env: {'DISPLAY': ':0', 04:00:17 INFO - 'HOME': '/home/cltbld', 04:00:17 INFO - 'LANG': 'en_US.UTF-8', 04:00:17 INFO - 'LANGUAGE': 'en_US:en', 04:00:17 INFO - 'LOGNAME': 'cltbld', 04:00:17 INFO - 'MAIL': '/var/mail/cltbld', 04:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:17 INFO - 'MOZ_NO_REMOTE': '1', 04:00:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:17 INFO - 'NO_EM_RESTART': '1', 04:00:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:17 INFO - 'PWD': '/builds/slave/test', 04:00:17 INFO - 'SHELL': '/bin/bash', 04:00:17 INFO - 'SHLVL': '1', 04:00:17 INFO - 'TERM': 'linux', 04:00:17 INFO - 'TMOUT': '86400', 04:00:17 INFO - 'USER': 'cltbld', 04:00:17 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612', 04:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:17 INFO - '_': '/tools/buildbot/bin/python'} 04:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:17 INFO - Downloading/unpacking jsonschema 04:00: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 04:00: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 04:00: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 04:00: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 04:00: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 04:00: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 04:00:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:00:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 04:00:18 INFO - Downloading/unpacking functools32 (from jsonschema) 04:00: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 04:00: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 04:00: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 04:00: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 04:00: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 04:00: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 04:00:19 INFO - Downloading functools32-3.2.3-2.tar.gz 04:00:19 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 04:00:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:00:19 INFO - warning: no files found matching '*.txt' 04:00:19 INFO - no previously-included directories found matching 'build' 04:00:19 INFO - no previously-included directories found matching 'dist' 04:00:19 INFO - no previously-included directories found matching '.git*' 04:00:19 INFO - Installing collected packages: jsonschema, functools32 04:00:19 INFO - Running setup.py install for functools32 04:00:19 INFO - warning: no files found matching '*.txt' 04:00:19 INFO - no previously-included directories found matching 'build' 04:00:19 INFO - no previously-included directories found matching 'dist' 04:00:19 INFO - no previously-included directories found matching '.git*' 04:00:19 INFO - Successfully installed jsonschema functools32 04:00:19 INFO - Cleaning up... 04:00:19 INFO - Return code: 0 04:00:19 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:00:19 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:00:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:19 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:00:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:00:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4098b78f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe178a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xee5240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf740b0>, '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 0xf74fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf75440>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612', '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 04:00:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--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', 'flake8'] in /builds/slave/test/build 04:00:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --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 flake8 04:00:19 INFO - Using env: {'DISPLAY': ':0', 04:00:19 INFO - 'HOME': '/home/cltbld', 04:00:19 INFO - 'LANG': 'en_US.UTF-8', 04:00:19 INFO - 'LANGUAGE': 'en_US:en', 04:00:19 INFO - 'LOGNAME': 'cltbld', 04:00:19 INFO - 'MAIL': '/var/mail/cltbld', 04:00:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:19 INFO - 'MOZ_NO_REMOTE': '1', 04:00:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:19 INFO - 'NO_EM_RESTART': '1', 04:00:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:19 INFO - 'PWD': '/builds/slave/test', 04:00:19 INFO - 'SHELL': '/bin/bash', 04:00:19 INFO - 'SHLVL': '1', 04:00:19 INFO - 'TERM': 'linux', 04:00:19 INFO - 'TMOUT': '86400', 04:00:19 INFO - 'USER': 'cltbld', 04:00:19 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612', 04:00:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:19 INFO - '_': '/tools/buildbot/bin/python'} 04:00:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:00:19 INFO - Downloading/unpacking flake8 04:00:19 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 04:00:19 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 04:00:19 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 04:00:19 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 04:00:19 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 04:00:19 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 04:00:21 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:00:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 04:00:21 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:00:21 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 04:00:21 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 04:00:21 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 04:00:21 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 04:00:21 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 04:00:21 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 04:00:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:00:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 04:00:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:00:21 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 04:00:21 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 04:00:21 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 04:00:21 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 04:00:21 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 04:00:21 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 04:00:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 04:00:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:00:21 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 04:00:21 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 04:00:21 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 04:00:21 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 04:00:21 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 04:00:21 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 04:00:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 04:00:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:00:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:00:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:00:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:00:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:00:22 INFO - no previously-included directories found matching 'docs/_build' 04:00:22 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:00:22 INFO - Running setup.py install for pep8 04:00:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:00:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:00:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:00:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:00:22 INFO - no previously-included directories found matching 'docs/_build' 04:00:22 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:00:22 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:00:22 INFO - Cleaning up... 04:00:22 INFO - Return code: 0 04:00:22 INFO - Running post-action listener: _resource_record_post_action 04:00:22 INFO - Running post-action listener: _start_resource_monitoring 04:00:22 INFO - Starting resource monitoring. 04:00:22 INFO - ##### 04:00:22 INFO - ##### Running install step. 04:00:22 INFO - ##### 04:00:22 INFO - Running pre-action listener: _resource_record_pre_action 04:00:22 INFO - Running main action method: install 04:00:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:00:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:00:22 INFO - Reading from file tmpfile_stdout 04:00:22 INFO - Detecting whether we're running mozinstall >=1.0... 04:00:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:00:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:00:22 INFO - Reading from file tmpfile_stdout 04:00:22 INFO - Output received: 04:00:22 INFO - Usage: mozinstall [options] installer 04:00:22 INFO - Options: 04:00:22 INFO - -h, --help show this help message and exit 04:00:22 INFO - -d DEST, --destination=DEST 04:00:22 INFO - Directory to install application into. [default: 04:00:22 INFO - "/builds/slave/test"] 04:00:22 INFO - --app=APP Application being installed. [default: firefox] 04:00:22 INFO - mkdir: /builds/slave/test/build/application 04:00:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:00:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:00:38 INFO - Reading from file tmpfile_stdout 04:00:38 INFO - Output received: 04:00:38 INFO - /builds/slave/test/build/application/firefox/firefox 04:00:38 INFO - Running post-action listener: _resource_record_post_action 04:00:38 INFO - ##### 04:00:38 INFO - ##### Running run-tests step. 04:00:38 INFO - ##### 04:00:38 INFO - Running pre-action listener: _resource_record_pre_action 04:00:38 INFO - Running main action method: run_tests 04:00:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:00:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:00:38 INFO - Python 2.7.3 04:00:38 INFO - Return code: 0 04:00:38 INFO - grabbing minidump binary from tooltool 04:00:38 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:00:38 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf740b0>, '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 0xf74fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf75440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:00:38 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 04:00:38 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 04:00:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:00:38 INFO - Return code: 0 04:00:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:00:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:00:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:00:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:00:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:00:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:00:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:00:39 INFO - Return code: 0 04:00:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost'] in /builds/slave/test/build 04:00:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-035 --webServer localhost 04:00:39 INFO - Using env: {'DISPLAY': ':0', 04:00:39 INFO - 'HOME': '/home/cltbld', 04:00:39 INFO - 'LANG': 'en_US.UTF-8', 04:00:39 INFO - 'LANGUAGE': 'en_US:en', 04:00:39 INFO - 'LOGNAME': 'cltbld', 04:00:39 INFO - 'MAIL': '/var/mail/cltbld', 04:00:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:00:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:00:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:00:39 INFO - 'MOZ_NO_REMOTE': '1', 04:00:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:00:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:00:39 INFO - 'NO_EM_RESTART': '1', 04:00:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:00:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:00:39 INFO - 'PWD': '/builds/slave/test', 04:00:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:00:39 INFO - 'SHELL': '/bin/bash', 04:00:39 INFO - 'SHLVL': '1', 04:00:39 INFO - 'TERM': 'linux', 04:00:39 INFO - 'TMOUT': '86400', 04:00:39 INFO - 'USER': 'cltbld', 04:00:39 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612', 04:00:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:00:39 INFO - '_': '/tools/buildbot/bin/python'} 04:00:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost'] with output_timeout 3600 04:00:39 INFO - mozversion INFO | application_buildid: 20151221024928 04:00:39 INFO - mozversion INFO | application_changeset: fe2802097d8613deb9f0d89b6ed876e314c886d0 04:00:39 INFO - mozversion INFO | application_display_name: Nightly 04:00:39 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:00:39 INFO - mozversion INFO | application_name: Firefox 04:00:39 INFO - mozversion INFO | application_remotingname: firefox 04:00:39 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 04:00:39 INFO - mozversion INFO | application_vendor: Mozilla 04:00:39 INFO - mozversion INFO | application_version: 46.0a1 04:00:39 INFO - mozversion INFO | platform_buildid: 20151221024928 04:00:39 INFO - mozversion INFO | platform_changeset: fe2802097d8613deb9f0d89b6ed876e314c886d0 04:00:39 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 04:00:39 INFO - mozversion INFO | platform_version: 46.0a1 04:00:39 INFO - 2015-12-21 04:00:39,234 DEBUG : using testdate: 1450699239 04:00:39 INFO - 2015-12-21 04:00:39,234 DEBUG : actual date: 1450699239 04:00:39 INFO - 2015-12-21 04:00:39,314 INFO : starting webserver on 'localhost:54367' 04:00:39 INFO - 2015-12-21 04:00:39,315 INFO : Starting test suite talos-linux64-ix-035.e 04:00:39 INFO - 2015-12-21 04:00:39,315 INFO : Starting test dromaeo_css 04:00:39 INFO - 2015-12-21 04:00:39,316 DEBUG : operating with platform_type : linux_ 04:00:39 INFO - 2015-12-21 04:00:39,316 INFO : Initialising browser for dromaeo_css test... 04:00:39 INFO - 2015-12-21 04:00:39,331 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpX6gxMU/profile http://localhost:54367/getInfo.html 04:00:44 INFO - 2015-12-21 04:00:44,141 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:00:44 INFO - 2015-12-21 04:00:44,141 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:00:44 INFO - 2015-12-21 04:00:44,141 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:00:44 INFO - 2015-12-21 04:00:44,141 DEBUG : BROWSER_OUTPUT: __metrics 04:00:44 INFO - 2015-12-21 04:00:44,278 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 04:00:45 INFO - 2015-12-21 04:00:45,427 INFO : Browser initialized. 04:00:45 INFO - 2015-12-21 04:00:45,427 INFO : Running cycle 1/1 for dromaeo_css test... 04:00:45 INFO - 2015-12-21 04:00:45,427 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpX6gxMU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:00:57 INFO - 2015-12-21 04:00:57,364 DEBUG : BROWSER_OUTPUT: RSS: Main: 150814720 04:00:57 INFO - 2015-12-21 04:00:57,364 DEBUG : BROWSER_OUTPUT: 04:01:47 INFO - 2015-12-21 04:01:47,002 DEBUG : BROWSER_OUTPUT: 1450699307001 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:03:59 INFO - 2015-12-21 04:03:59,036 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54367/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54367/tests/dromaeo/cssquery-ext.html) 04:04:00 INFO - 2015-12-21 04:04:00,243 DEBUG : BROWSER_OUTPUT: RSS: Main: 153731072 04:04:00 INFO - 2015-12-21 04:04:00,243 DEBUG : BROWSER_OUTPUT: 04:07:02 INFO - 2015-12-21 04:07:02,343 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54367/tests/dromaeo/cssquery-ext.html (next: http://localhost:54367/tests/dromaeo/cssquery-jquery.html) 04:07:02 INFO - 2015-12-21 04:07:02,944 DEBUG : BROWSER_OUTPUT: RSS: Main: 154480640 04:07:02 INFO - 2015-12-21 04:07:02,944 DEBUG : BROWSER_OUTPUT: 04:10:04 INFO - 2015-12-21 04:10:04,718 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54367/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54367/tests/dromaeo/cssquery-mootools.html) 04:10:05 INFO - 2015-12-21 04:10:05,307 DEBUG : BROWSER_OUTPUT: RSS: Main: 154279936 04:10:05 INFO - 2015-12-21 04:10:05,307 DEBUG : BROWSER_OUTPUT: 04:13:07 INFO - 2015-12-21 04:13:07,065 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54367/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54367/tests/dromaeo/cssquery-prototype.html) 04:13:07 INFO - 2015-12-21 04:13:07,667 DEBUG : BROWSER_OUTPUT: RSS: Main: 152481792 04:13:07 INFO - 2015-12-21 04:13:07,667 DEBUG : BROWSER_OUTPUT: 04:16:10 INFO - 2015-12-21 04:16:10,219 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54367/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54367/tests/dromaeo/cssquery-yui.html) 04:16:10 INFO - 2015-12-21 04:16:10,812 DEBUG : BROWSER_OUTPUT: RSS: Main: 152350720 04:16:10 INFO - 2015-12-21 04:16:10,812 DEBUG : BROWSER_OUTPUT: 04:19:13 INFO - 2015-12-21 04:19:13,297 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54367/tests/dromaeo/cssquery-yui.html (next: http://localhost:54367/tests/dromaeo/cssquery-dojo.html) 04:19:13 INFO - 2015-12-21 04:19:13,556 DEBUG : BROWSER_OUTPUT: RSS: Main: 155467776 04:19:13 INFO - 2015-12-21 04:19:13,556 DEBUG : BROWSER_OUTPUT: 04:19:13 INFO - 2015-12-21 04:19:13,556 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:19:13 INFO - 2015-12-21 04:19:13,556 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:19:13 INFO - 2015-12-21 04:19:13,557 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:19:13 INFO - 2015-12-21 04:19:13,557 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:19:13 INFO - 2015-12-21 04:19:13,557 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2661.294756889424;2695.487857307113;2711.0240313487143;2714.674239091309;2736.3569561153126;4502.729836209822;4646.581807637316;4684.3676103726075;4697.67116301859;4707.388039554858;4560.20196465618;4606.907861842761;4651.7906694198755;4682.41469816273;4806.735629540374;4297.763623000742;4338.631216346615;4361.34579813029;4428.6014258716705;4455.198064348416;4421.624161946238;4457.264551349023;4467.396901418299;4469.463664360282;4474.686771925966;4107.281225785476;4208.431861698693;4225.514065882401;4334.913301733947;4398.582134697214;4247.129338485603;4254.617084462413;4278.251306021465;4309.82760689572;4321.243782338078;4241.34259189823;4261.573842615745;4265.488141423018;4285.400043993844;4305.031367942219;4287;4407.272799987998;4586.793594288265;4741.359916411275;4759.452662943771;5024.145895197825;5026.220935754964;5038.345015148007;5055.5702765265;5094.4141423736;4751.40607424072;5045.84862454127;5065.164247899129;5080.110980578421;5121.564667003309;4508.233600287959;4512.774361281956;4514.0971805639;4516.119356725473;4639.164950308977;5173.81891633791;5174.43081261061;5176.120059589879;5186.222066690027;5199.2201169824075;4952.90094198114;4954.603631709455;4972.627052970969;4975.502449754996;5000.6249531284575;4796.25658023007;4904.117258893433;4966.652334336562;5069.264956581275;5100.48995100487;4908.092002979389;4915.287283343895;4915.827946021872;4932.926006109911;4971.850844474671;4882.902341953141;4899.608031357483;4912.557869791664;4919.262110683355;4941.135301322326;3992.4210989406374;4093.017675757856;4118;4169.082801783603;4259.233337999128;3659.1583935694407;3674.4855720199193;3688.0595448161084;3707.5550933888107;3712.7401081924004;4124.773137477468;4330.891727706832;4336.132773445323;4337.544557821447;4355.978220108943;4814.181589129857;4902.896848209124;4972.801087956441;4975.203967365204;5017.422996355466;4314.913701725948;4320.4383430153875;4363.498197707241;4405.31717583775;4408.713433626868;4245.1934132514725;4339.349097635317;4478.171538265431;4490.730556166641;4497.572730590588;4702.52974702527;4786.856394308176;4829.2997515360075;4839.056384005085;4842.51574842527;5002.474740152377;5057.468965758542;5060.3168572242275;5062.088824011713;5087.186050231945;4634.119517291704;4821.493743157058;4830.661853670209;4831.299461578122;4866.440359358719;4859.31969524274;4916.655834091579;4954.256861470808;4966.205407134841;5021.698698078127;5038;5067.214581739836;5077.847664570068;5089.541941225308;5132.3327967364;4601.700889442175;4701.177293973473;4902.166631672696;4925.1381008324115;4966.701997880139;4765.475797662324;4827.61379089679;4832.057748738962;4848.466668666373;4869.293952376956;4762.3570817939135;4817.470078291455;4823.517648235148;4832.371791667201;4866.95133048665;4640.303954406866;4741.976290118528;4755.5006724294835;4939.604831613534;4982.676126051795;4778.617710583076;4789.736564488847;4828.565429111397;4853.684510506806;4891.388576427947 04:19:13 INFO - 2015-12-21 04:19:13,557 DEBUG : BROWSER_OUTPUT: |1;ext.html;553.7480446396889;561;564.9858753531162;566.2808233543402;569.5699746691249;35641;36015.27969440616;36064.45903311452;36299.637003629956;36379.63620363802;40818.7959060205;41425.58574414255;41536.58463415365;41559.79220103895;41660.37509437361;7940.404469664783;8071.434999550034;8094.271515563628;8113.7565873023905;8120.147384524628;7820.726274580363;7954.602269886512;7963.124056353797;7969;7980.561069141195;7898.5260884346835;7970.08344040437;7970.362371010334;7971.760847174565;7972.8405431891615;7786.3259399229155;7804.49270797402;7820.374370050382;7838.412119091063;7845.607719614053;7801.765947021599;7899.605019749047;7907.48601340911;7934.642941067666;7937.126916039231;36826.26347473035;37246.441303380474;37295.81352093222;37309.44035839465;37315.25369492595;2992.850357482139;3012.487877060905;3182.3317103408313;3205;3378.7297016238886;28111;28284.434311313864;28518.857405713046;28678.85660571684;28876;3375.476801095834;3434.5019972104155;3437.604675462329;3491.8603255869734;3514.5606799150105;207926;209013;209115;209796.95101524546;210278;52705.472945270056;53655.46344536583;54541.454585454434;54803.72598137063;55004.449955500735;65780;66112.33887661158;66846.33153668499;66898.3310166892;67078.66460667666;13233.404496797668;13539.59381218565;13584.030315604183;13585.932070339584;13610.319484025758;10433.32183408076;10451.529681164213;10472.895271047191;10523.315984460985;10611.893881061245;18833.717494237597;19276.554258430675;19301.4209573713;19320.903395482932;19339.323123690603;7599.544027358376;7759.340456061298;7839.137694853562;7906.486078404885;7946.404019698546;873.6057630120504;875.5840975536569;876.2245412809582;882.8852249207562;998.4608311511847;26723.732762672123;27754.444911101666;27925;29545;30268;978.6379039755194;994.547480896205;1012.5241136665755;1024.472396715692;1116.843641890136;13613.183209007491;13772.173669579857;13825.585232443043;13894.374753136133;14547.41810327575;269596;272152;273056;273356;275044;47127.528724712996;47266.52733472621;47415;47596.28605570919;47747.28379074247;1194.0745921910416;1253.0476837603487;1253.423425224407;1258.811178323259;1263.677762170659;27276.31809204786;27510.862445687642;27784.44431111326;27879.163625091278;28018.439631206853;41120.38319425271;41420.792896035324;41466.792666036476;41491.37762933619;41544.79227603963;246106;247045;248741;248978;249596;28795.856020719762;29407.55888661629;29706.405871882875;29747.553786692784;30035.69964300329;33795.32409351848;34664;34668.13329666779;34692.132696682784;34734.65265347314;29947.850260748557;32461.35077298488;32673.509897351083;33044.50433243455;34430.13924651904;31512;32730;33064.33871322512;33225.66774332226;35762;32343.676563234065;32876.67123328736;32895;32985;33284.33431331408;28487.28781780472;29752.256193595334;29908.850455747583;30214;30647.54028689527;21884.671729923746;22401.551968960204;22448;22450;22473.21343752961 04:19:13 INFO - 2015-12-21 04:19:13,558 DEBUG : BROWSER_OUTPUT: |2;jquery.html;323.08727843841154;324.93663735571545;329.3133815993653;337.3455496337106;337.87160878866035;15998.680026399465;16076.919615401921;16400.707012870615;16663.91668041658;16926.645868330535;15483.071015739035;15586.532404027897;15588;15619.843801561981;15702.37190512381;4116.7529948203055;4288.442502474674;4288.807003684826;4299.914001719963;4300.118475712471;3729.974257079298;4230.407742915994;4235.74585524868;4248.702590818644;4641.187792136379;4551;4554.681172317939;4587.816487340502;4588.197065513538;4606.60843828275;4481.372607834889;4590;4814.87962800931;4865.975670121662;4876.097921884428;4332.631726303268;4339.522652508222;4340.956590434087;4348.2607956647125;4354.804033818486;10933.781324373547;11025.669229923034;11032.889671103347;11057.06014988727;11061.723456913562;1831.7893442254044;1843.2626949220285;1871.9344822931132;1928.855335849803;1939.56359819041;8638.568071596395;8907.643694252221;12377.504899803997;12482.126251162423;12803.871961280362;1707.4792188382662;1741.0946307919937;1751.474557632705;1757.9736303955451;1774.2459454731688;54845.45154548444;57472.137917931264;57841.13238301429;58369.708151459396;58877.116843247386;9753.21974242059;9792.55933482995;9824.361416507903;9832.213422926148;9835.3115281931;11490.712732181595;11826.763464730657;11860.22908510944;11914.344711040803;12499.812502812465;6807.795766127024;6975.511714179958;7077;7093.716251349888;7127.073480447612;6709.027191057367;6782.966085169543;6903.102596662402;6903.5167538271835;6920.619365934922;4042.0703238255364;4099.713020088625;4101.118259574206;4101.200265948171;4121.113960498507;1559.3216950626513;1560.7658851172414;1690.6787710334997;1704.1649591700225;1734.1415999080536;812.224325768901;854.2994744309731;932.1238036245907;992.2211064314479;1013.4324778124127;15751.44869929547;16005.537352229438;16062.518124456285;16158.67682646364;16230.269637866324;838.8490071787137;879.5821984557411;888.440282621944;961.5913236874301;962.7593101724569;8992.955035224782;9098.636054557875;9262.861057084148;9348.345615806964;9513.381630194015;318825;366563;431317;431548;432022;71950;76022;76142.23857761463;76253;76701;1091.6015654286127;1105.209775010855;1105.3147048829617;1115.67087709126;1129.627223016419;14623.488177913721;14772.77840832367;15147.166905820286;15150.242487875783;15370.077795332316;25207.87396063008;28523.857380712965;31462;33912.83043584766;34094.31811363808;33516.83663556178;34640.65359346374;35035;36806.26387472182;37010.62989370179;8405.369597280107;8495.8725619117;8763.737087887373;8763.824723505622;8768.649254030022;10367.066963973279;10411.375317480975;11405.942970285096;14628.561143165722;14818.77771833445;13685;13768.898488120725;13787.241701706502;14050.086744361992;14158;13281.66795830112;13496.5276215332;13503;13869.583912482642;13906.096103753223;13926.58220253394;14027.01810873229;14045.227512486912;14257.42970281177;14421.92789036048;8621.568921554022;8717.825643486967;8757.91242087571;8764.1674040966;8820.25027872638;9409.858852117086;9440.197583205505;11403.600873969372;13612.795808062689;13615.659608509866 04:19:13 INFO - 2015-12-21 04:19:13,558 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1505.4354617018619;1570.9764353534695;1646.901185928844;1667.0914351678334;1695.1270095900607;5619.353774315955;6049.969750151254;6953.791386258414;7145.249748776375;7161.785146445602;6748.730050797963;7019.89470157948;7044.119485064367;7055.89416158758;7125.572465652064;2063.1334839367464;2079.9792002079935;2097.1716172112006;2098.706181134635;2103.621348157331;2081.032319971213;2087.634663933813;2106.3575609439113;2120.109553987329;2125.7980491853327;2023.5548179400507;2071.7099606055162;2085.8435617328687;2097.5490269592033;2101.9159233630635;2069.420562242567;2094.4973206430423;2098.3600001999384;2098.5593025464673;2098.95802083959;1913.4929243750416;1936.6514027475048;1948.5031317014214;1965.6756635155182;1971.802819718031;7057.788266352018;7205.2434494378385;7206.711731530732;7211.242819503929;7231.746888858917;4214.346776249687;4267.274563324242;4318.611324980767;4327.026419055719;4334.761588112652;2657.8139530232893;2680.4773069251505;2715.8913643454434;2740.4656092062055;2761.7100204478443;837.2841220756227;848.7750746052301;859.3082568532329;859.673324136824;898.3307435960252;9352.438853668802;9375.5312234388;9452.054794520562;9498.9525052375;9510.857337139949;3783.3000894834545;3796.335641262768;3837.57786643469;3837.9232415351535;3853.8265778040054;8198.303144232688;8206.384521160937;8212.753617391489;8215.34277257818;8226.588670566447;4120.443740095108;4141.565135660712;4159.085001299709;4159.438475805788;4175.478065241845;3244.724198443159;3339.1151344893624;3719.925601487955;3744.5506539215467;3785.3943369061367;3445.104272889067;3454.170998960282;3469;3502.1069627244888;3507.596626387947;1040.4693606260862;1047.9633212837514;1067.8291473364225;1075.2795626929976;1088.1458055426451;1064.4252103863828;1090.9945450272699;1103.624767579027;1117.4412793603199;1118.429600903545;6819.147606549182;6826.419754320938;6830.487713421513;6833.658317084126;6863.485238607124;1087.9891201088046;1098.6045023791428;1125.4035361258545;1130.2540323386525;1143.3140115930375;5244.895102098013;5254.97372513135;5368.5436737876935;5398.757055932492;5421.376541697755;8614.483131012159;8743.256823169975;8768.95615521931;8771.780705482413;8800.559972001374;8397.95801020991;8530.061693213742;8557.957210213908;8559.700410485602;8566.100559441293;6761.72953081881;6778.864422711518;6804.659767011729;6808.183018037867;6846.281140480178;6414.5189110815945;6483.870322593616;6530.379613936669;6538.967305163444;6541.934580654323;7558.58427786477;7682.116556596063;7803.258690424401;7816.687332506636;7831.2560306770765;7791.4935529190425;7843.56860372685;7875.960620196863;7968.243016913384;8060.7178748745855;4975.950240497548;5119.334486516878;6114.510839132948;6186.133941248138;6289.433950944437;6741.1910570731825;6853.246142924175;6886.758963436256;6894.103766510321;6903.102596662402;6371.74513019474;6381.936180638134;6494.902576461452;6530.249021362604;6654.900176497454;6234.189555357774;6292.811215663537;6295.937040629535;6470.676466176578;6550.213974323112;6441.259255185526;6441.3880681335195;6469.061986011908;6484.610923344614;6489.448396886318;5865.94134058654;5893.086571581412;5947.821565353046;5953.464188223081;6042.546808989256;6105.572609917263;6126.6017708848785;6127.724252408741;6135.53983451234;6153.953827849186 04:19:13 INFO - 2015-12-21 04:19:13,561 DEBUG : BROWSER_OUTPUT: |4;prototype.html;298.09676679660623;305.51270723196507;306.986185621647;307.8359135278989;308.3755395324468;5461.726913654321;5483.451654834519;5544.140658197981;5568.916466253009;5579.609427340088;5445.292112025431;5467.890642187154;5471.425500322459;5480.424555421683;5487.5609951203905;4076.9592304076878;4249.107687385653;4249.681273904454;4250.596193361625;4254.106637606099;4092.1201941582704;4117.053077792109;4120.876373708778;4143.399207114967;4153.563875793044;4077.55146933837;4090.8159132838946;4096.283150448675;4107.17856428714;4111.280525907969;4069.6540794032544;4080.0411903202726;4080.795960201993;4116.382542618601;4128.4632997710405;3621.5473065866768;3626.9455958160647;3629.056445324208;3629.2922880038404;3632.6367363263726;5174.068667639824;5287.86780330491;5291.920621190685;5297;5310.787568497255;4786.234202527614;4788.545088216614;4794.113089078497;4799.784009719571;4807.18277892759;5738.368779434259;5740.311162660508;5749.195112684228;5753.942460575425;5770.278715160605;3951.881443556698;3953.89290998518;3954.3079961006997;3956.248312820555;3958.5447673517624;5932.199153114318;5993.760249590011;6044.365341639153;6063.484603808682;6073.848153796102;4067;4072.6538244248977;4075.49056367954;4087.9386809197886;4088.6320231178743;4158.896027599334;4163.167366526707;4163.188178305262;4164.167166566699;4167.729097608646;4763.69036012658;4779.211430114062;4783.1390349737385;4793.784279707352;4796.208625576811;5618.213450116987;5753.935521928456;5755.453231942959;5767.567432442584;5772.307323121168;1429.1567974894863;1444.6821699226152;1445.2918070145554;1445.7903603977575;1446.0167086381362;958.4153666263574;961.4519723757392;971.5336638413601;981.474910922653;988.7478692933399;4960.082384758831;4960.553550180429;4983.25251212321;4986.925196122061;4989.975050124799;5862.003459411825;5903.085021821625;5908.675022873783;6014.308354539196;6059.303180134253;5029.899402012013;5034.874128146753;5058.645894787403;5076.162433198481;5077.289179514871;3212.4699424595146;3245.334706385185;3246.707796298297;3247.6427592964756;3264.6735326467165;5700.515456186187;5703.9429605704245;5711.628744131701;5714.2571465710025;5721.599488035798;6003.489703375175;6214.720337584819;6218.347073557302;6228.314885362606;6243.96878015607;5716.599838011382;5716.771329146871;5718.085106383042;5718.228039214653;5727.2268243787385;6069.453749162597;6070;6070.635761854126;6072.483838873569;6085.6348619083;5735.942640573708;5891.9116213255975;5918.378570250234;5933.3769954154195;5936.13925980739;5685.658860468385;5715;5721.427857214246;5725.398833122462;5726.74229659658;4079.306517891906;4126.711130220856;4134.813933372945;4135.875923722293;4141.958580414278;5927.377625349276;6005;6031.155638210566;6046.128089157615;6059.575829692056;5792.189093526826;5808.7095645218415;5812.622179558315;5828.53371730269;5835.591508594358;5801.82594522165;5803.448672376117;5804.82585522435;5808.390119037609;5810.82567522975;5580;5622.24099746529;5680.772769089355;5692.260006199168;5696.1455781633085;5837.737301821343;5849.795257165979;5852.678102704393;5856.4143585641095;5863.530917526673;5683.460071293221;5700.3444603871085;5713.143028545752;5713.8285851424525;5716.571257155647 04:19:13 INFO - 2015-12-21 04:19:13,563 DEBUG : BROWSER_OUTPUT: |5;yui.html;699.9825004374892;734.3647744700831;746.1009483572296;752.7553545097843;800.4276941986478;11146.108311335094;11377.886221137807;11391.202615816886;11418.200725949177;11434.199606027581;11600.941995290012;11709.473073711682;11718.941405292982;11727.824082638766;11746.588869389572;779.9181085985961;788.7436583110483;792.3502727763237;798.744401791427;800.2837460472873;786.7875673568133;786.8662327404355;791.6833266693311;793.9682412703485;795.932345750612;831.683960095163;838.3419016072385;838.3921656798833;838.6351936907465;840.3235395506615;821.5070957425556;823.7652269103306;826.3802148388648;828.5691440450992;828.70580943765;618.5886385553647;618.7896115320767;620.0017971066579;620.1596836286857;620.3300435529617;11592.536298548048;11595.826062609067;11599.884001159964;11603.6518904433;11681.824772628417;10795.622153224678;10798.51406686701;10805.08895736012;11022.118230541537;11548;9085.182333589943;9194.770130746718;9318.113847074084;9639.987801280904;9702.854457183148;3211.983940080308;3238.8542515586855;3255.96744032559;3265.755068369858;3361.0085024917707;40268;40384.39423408651;40469.595304046874;40671;40833.59166408327;8558.315334773204;8759.2992560595;8879;8977.865332019894;9037.864432033524;15420.537383878502;15541.689166216613;15541.922290388702;15610.45363412275;15652;6155.599886007395;6180.072989051679;6228.4705800007505;6280.748770049238;6337.746490140435;3482.651734826497;3523.700485458765;3547.875824346187;3572.285542891432;3573.249617580337;2480.751924807505;2509.209598976353;2510.9121180758584;2525.4696513731956;2528.127795910408;444.46220073711174;445.4454204515399;446.4999200895018;447.7358358568395;450.7228054746307;555.7384736647867;565.2425749495695;581.1515187825737;596.3559355895623;597.6892016151577;7326.853462930818;7368.263173682589;7420.183779784219;7508;7532.435067369969;526.7471613625481;582.347770497046;587.7560812262863;613.4908026338129;637.7002808679913;3374.342003309331;3385.6614338565946;3467.878624248139;3485.721142308609;3543.20277937462;48548;48564;48653.270200947016;48926;48976;43627.56372436235;43700.3444948326;44126.55873441289;44562;44612.10775784531;40096.39855402229;40307;40367.19265614671;40592.594074058885;40750.388744168864;6822.8635427292165;6876.724931002904;7018.438524917892;7139.32176443237;7167.534110282815;30042.24894377658;30455.238619034702;30638.84680576583;30793;31097.222569435944;36601.45097823482;38965.80517097397;40132.59867401289;40208.798956005034;40301.1939761209;9153.17621414076;9285;9296.860547091934;9315.906840931775;9418.62325506972;9993.00069992995;10105.44420056904;10188.694339169837;10406.219533534864;10508.054275115277;9506.239500840054;9740.123388895034;9798.265130115413;9976.401415915068;10388.532516036645;9323.393979391318;9460.243180545402;9507.144357007903;9874.111329980336;9915.900840991499;9364.531773411438;9484.146426821619;9600.279979001463;9879.308448408543;9972.30193886421;8504.319654427507;8534;8767.693130740394;8786.341024423065;8935.285177185939;7691.923080769121;8210;8329.083800781787;8394.58027098655;8972.102789720975 04:19:13 INFO - 2015-12-21 04:19:13,563 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:19:13 INFO - 2015-12-21 04:19:13,563 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:19:13 INFO - 2015-12-21 04:19:13,563 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,323 04:19:13 INFO - 2015-12-21 04:19:13,563 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:19:13 INFO - 2015-12-21 04:19:13,563 DEBUG : BROWSER_OUTPUT: __startTimestamp1450700353549__endTimestamp 04:19:13 INFO - 2015-12-21 04:19:13,564 DEBUG : BROWSER_OUTPUT: 04:19:13 INFO - 2015-12-21 04:19:13,564 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:19:13 INFO - 2015-12-21 04:19:13,564 DEBUG : BROWSER_OUTPUT: Number of tests: 6 04:19:13 INFO - 2015-12-21 04:19:13,564 DEBUG : BROWSER_OUTPUT: 04:19:13 INFO - 2015-12-21 04:19:13,564 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4617.49 Median:4762.36 stddev:481.39 (10.1%) stddev-sans-first:457.75 04:19:13 INFO - 2015-12-21 04:19:13,564 DEBUG : BROWSER_OUTPUT: Values: 2661.3 2695.5 2711.0 2714.7 2736.4 4502.7 4646.6 4684.4 4697.7 4707.4 4560.2 4606.9 4651.8 4682.4 4806.7 4297.8 4338.6 4361.3 4428.6 4455.2 4421.6 4457.3 4467.4 4469.5 4474.7 4107.3 4208.4 4225.5 4334.9 4398.6 4247.1 4254.6 4278.3 4309.8 4321.2 4241.3 4261.6 4265.5 4285.4 4305.0 4287.0 4407.3 4586.8 4741.4 4759.5 5024.1 5026.2 5038.3 5055.6 5094.4 4751.4 5045.8 5065.2 5080.1 5121.6 4508.2 4512.8 4514.1 4516.1 4639.2 5173.8 5174.4 5176.1 5186.2 5199.2 4952.9 4954.6 4972.6 4975.5 5000.6 4796.3 4904.1 4966.7 5069.3 5100.5 4908.1 4915.3 4915.8 4932.9 4971.9 4882.9 4899.6 4912.6 4919.3 4941.1 3992.4 4093.0 4118.0 4169.1 4259.2 3659.2 3674.5 3688.1 3707.6 3712.7 4124.8 4330.9 4336.1 4337.5 4356.0 4814.2 4902.9 4972.8 4975.2 5017.4 4314.9 4320.4 4363.5 4405.3 4408.7 4245.2 4339.3 4478.2 4490.7 4497.6 4702.5 4786.9 4829.3 4839.1 4842.5 5002.5 5057.5 5060.3 5062.1 5087.2 4634.1 4821.5 4830.7 4831.3 4866.4 4859.3 4916.7 4954.3 4966.2 5021.7 5038.0 5067.2 5077.8 5089.5 5132.3 4601.7 4701.2 4902.2 4925.1 4966.7 4765.5 4827.6 4832.1 4848.5 4869.3 4762.4 4817.5 4823.5 4832.4 4867.0 4640.3 4742.0 4755.5 4939.6 4982.7 4778.6 4789.7 4828.6 4853.7 4891.4 04:19:13 INFO - 2015-12-21 04:19:13,564 DEBUG : BROWSER_OUTPUT: 04:19:13 INFO - 2015-12-21 04:19:13,564 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:40794.60 Median:3377.10 stddev:63822.43 (1889.9%) stddev-sans-first:63929.95 04:19:13 INFO - 2015-12-21 04:19:13,566 DEBUG : BROWSER_OUTPUT: Values: 553.7 561.0 565.0 566.3 569.6 35641.0 36015.3 36064.5 36299.6 36379.6 40818.8 41425.6 41536.6 41559.8 41660.4 7940.4 8071.4 8094.3 8113.8 8120.1 7820.7 7954.6 7963.1 7969.0 7980.6 7898.5 7970.1 7970.4 7971.8 7972.8 7786.3 7804.5 7820.4 7838.4 7845.6 7801.8 7899.6 7907.5 7934.6 7937.1 36826.3 37246.4 37295.8 37309.4 37315.3 2992.9 3012.5 3182.3 3205.0 3378.7 28111.0 28284.4 28518.9 28678.9 28876.0 3375.5 3434.5 3437.6 3491.9 3514.6 207926.0 209013.0 209115.0 209797.0 210278.0 52705.5 53655.5 54541.5 54803.7 55004.4 65780.0 66112.3 66846.3 66898.3 67078.7 13233.4 13539.6 13584.0 13585.9 13610.3 10433.3 10451.5 10472.9 10523.3 10611.9 18833.7 19276.6 19301.4 19320.9 19339.3 7599.5 7759.3 7839.1 7906.5 7946.4 873.6 875.6 876.2 882.9 998.5 26723.7 27754.4 27925.0 29545.0 30268.0 978.6 994.5 1012.5 1024.5 1116.8 13613.2 13772.2 13825.6 13894.4 14547.4 269596.0 272152.0 273056.0 273356.0 275044.0 47127.5 47266.5 47415.0 47596.3 47747.3 1194.1 1253.0 1253.4 1258.8 1263.7 27276.3 27510.9 27784.4 27879.2 28018.4 41120.4 41420.8 41466.8 41491.4 41544.8 246106.0 247045.0 248741.0 248978.0 249596.0 28795.9 29407.6 29706.4 29747.6 30035.7 33795.3 34664.0 34668.1 34692.1 34734.7 29947.9 32461.4 32673.5 33044.5 34430.1 31512.0 32730.0 33064.3 33225.7 35762.0 32343.7 32876.7 32895.0 32985.0 33284.3 28487.3 29752.3 29908.9 30214.0 30647.5 21884.7 22401.6 22448.0 22450.0 22473.2 04:19:13 INFO - 2015-12-21 04:19:13,566 DEBUG : BROWSER_OUTPUT: 04:19:13 INFO - 2015-12-21 04:19:13,566 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:23404.07 Median:200799.00 stddev:65433.40 (32.6%) stddev-sans-first:65594.01 04:19:13 INFO - 2015-12-21 04:19:13,566 DEBUG : BROWSER_OUTPUT: Values: 323.1 324.9 329.3 337.3 337.9 15998.7 16076.9 16400.7 16663.9 16926.6 15483.1 15586.5 15588.0 15619.8 15702.4 4116.8 4288.4 4288.8 4299.9 4300.1 3730.0 4230.4 4235.7 4248.7 4641.2 4551.0 4554.7 4587.8 4588.2 4606.6 4481.4 4590.0 4814.9 4866.0 4876.1 4332.6 4339.5 4341.0 4348.3 4354.8 10933.8 11025.7 11032.9 11057.1 11061.7 1831.8 1843.3 1871.9 1928.9 1939.6 8638.6 8907.6 12377.5 12482.1 12803.9 1707.5 1741.1 1751.5 1758.0 1774.2 54845.5 57472.1 57841.1 58369.7 58877.1 9753.2 9792.6 9824.4 9832.2 9835.3 11490.7 11826.8 11860.2 11914.3 12499.8 6807.8 6975.5 7077.0 7093.7 7127.1 6709.0 6783.0 6903.1 6903.5 6920.6 4042.1 4099.7 4101.1 4101.2 4121.1 1559.3 1560.8 1690.7 1704.2 1734.1 812.2 854.3 932.1 992.2 1013.4 15751.4 16005.5 16062.5 16158.7 16230.3 838.8 879.6 888.4 961.6 962.8 8993.0 9098.6 9262.9 9348.3 9513.4 318825.0 366563.0 431317.0 431548.0 432022.0 71950.0 76022.0 76142.2 76253.0 76701.0 1091.6 1105.2 1105.3 1115.7 1129.6 14623.5 14772.8 15147.2 15150.2 15370.1 25207.9 28523.9 31462.0 33912.8 34094.3 33516.8 34640.7 35035.0 36806.3 37010.6 8405.4 8495.9 8763.7 8763.8 8768.6 10367.1 10411.4 11405.9 14628.6 14818.8 13685.0 13768.9 13787.2 14050.1 14158.0 13281.7 13496.5 13503.0 13869.6 13906.1 13926.6 14027.0 14045.2 14257.4 14421.9 8621.6 8717.8 8757.9 8764.2 8820.3 9409.9 9440.2 11403.6 13612.8 13615.7 04:19:13 INFO - 2015-12-21 04:19:13,566 DEBUG : BROWSER_OUTPUT: 04:19:13 INFO - 2015-12-21 04:19:13,566 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4968.58 Median:6120.56 stddev:2565.43 (41.9%) stddev-sans-first:2559.42 04:19:13 INFO - 2015-12-21 04:19:13,566 DEBUG : BROWSER_OUTPUT: Values: 1505.4 1571.0 1646.9 1667.1 1695.1 5619.4 6050.0 6953.8 7145.2 7161.8 6748.7 7019.9 7044.1 7055.9 7125.6 2063.1 2080.0 2097.2 2098.7 2103.6 2081.0 2087.6 2106.4 2120.1 2125.8 2023.6 2071.7 2085.8 2097.5 2101.9 2069.4 2094.5 2098.4 2098.6 2099.0 1913.5 1936.7 1948.5 1965.7 1971.8 7057.8 7205.2 7206.7 7211.2 7231.7 4214.3 4267.3 4318.6 4327.0 4334.8 2657.8 2680.5 2715.9 2740.5 2761.7 837.3 848.8 859.3 859.7 898.3 9352.4 9375.5 9452.1 9499.0 9510.9 3783.3 3796.3 3837.6 3837.9 3853.8 8198.3 8206.4 8212.8 8215.3 8226.6 4120.4 4141.6 4159.1 4159.4 4175.5 3244.7 3339.1 3719.9 3744.6 3785.4 3445.1 3454.2 3469.0 3502.1 3507.6 1040.5 1048.0 1067.8 1075.3 1088.1 1064.4 1091.0 1103.6 1117.4 1118.4 6819.1 6826.4 6830.5 6833.7 6863.5 1088.0 1098.6 1125.4 1130.3 1143.3 5244.9 5255.0 5368.5 5398.8 5421.4 8614.5 8743.3 8769.0 8771.8 8800.6 8398.0 8530.1 8558.0 8559.7 8566.1 6761.7 6778.9 6804.7 6808.2 6846.3 6414.5 6483.9 6530.4 6539.0 6541.9 7558.6 7682.1 7803.3 7816.7 7831.3 7791.5 7843.6 7876.0 7968.2 8060.7 4976.0 5119.3 6114.5 6186.1 6289.4 6741.2 6853.2 6886.8 6894.1 6903.1 6371.7 6381.9 6494.9 6530.2 6654.9 6234.2 6292.8 6295.9 6470.7 6550.2 6441.3 6441.4 6469.1 6484.6 6489.4 5865.9 5893.1 5947.8 5953.5 6042.5 6105.6 6126.6 6127.7 6135.5 6154.0 04:19:13 INFO - 2015-12-21 04:19:13,567 DEBUG : BROWSER_OUTPUT: 04:19:13 INFO - 2015-12-21 04:19:13,567 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4775.28 Median:5485.51 stddev:1429.01 (26.1%) stddev-sans-first:1392.95 04:19:13 INFO - 2015-12-21 04:19:13,568 DEBUG : BROWSER_OUTPUT: Values: 298.1 305.5 307.0 307.8 308.4 5461.7 5483.5 5544.1 5568.9 5579.6 5445.3 5467.9 5471.4 5480.4 5487.6 4077.0 4249.1 4249.7 4250.6 4254.1 4092.1 4117.1 4120.9 4143.4 4153.6 4077.6 4090.8 4096.3 4107.2 4111.3 4069.7 4080.0 4080.8 4116.4 4128.5 3621.5 3626.9 3629.1 3629.3 3632.6 5174.1 5287.9 5291.9 5297.0 5310.8 4786.2 4788.5 4794.1 4799.8 4807.2 5738.4 5740.3 5749.2 5753.9 5770.3 3951.9 3953.9 3954.3 3956.2 3958.5 5932.2 5993.8 6044.4 6063.5 6073.8 4067.0 4072.7 4075.5 4087.9 4088.6 4158.9 4163.2 4163.2 4164.2 4167.7 4763.7 4779.2 4783.1 4793.8 4796.2 5618.2 5753.9 5755.5 5767.6 5772.3 1429.2 1444.7 1445.3 1445.8 1446.0 958.4 961.5 971.5 981.5 988.7 4960.1 4960.6 4983.3 4986.9 4990.0 5862.0 5903.1 5908.7 6014.3 6059.3 5029.9 5034.9 5058.6 5076.2 5077.3 3212.5 3245.3 3246.7 3247.6 3264.7 5700.5 5703.9 5711.6 5714.3 5721.6 6003.5 6214.7 6218.3 6228.3 6244.0 5716.6 5716.8 5718.1 5718.2 5727.2 6069.5 6070.0 6070.6 6072.5 6085.6 5735.9 5891.9 5918.4 5933.4 5936.1 5685.7 5715.0 5721.4 5725.4 5726.7 4079.3 4126.7 4134.8 4135.9 4142.0 5927.4 6005.0 6031.2 6046.1 6059.6 5792.2 5808.7 5812.6 5828.5 5835.6 5801.8 5803.4 5804.8 5808.4 5810.8 5580.0 5622.2 5680.8 5692.3 5696.1 5837.7 5849.8 5852.7 5856.4 5863.5 5683.5 5700.3 5713.1 5713.8 5716.6 04:19:13 INFO - 2015-12-21 04:19:13,568 DEBUG : BROWSER_OUTPUT: 04:19:13 INFO - 2015-12-21 04:19:13,568 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:11902.47 Median:592.06 stddev:13697.14 (2313.5%) stddev-sans-first:13709.73 04:19:13 INFO - 2015-12-21 04:19:13,568 DEBUG : BROWSER_OUTPUT: Values: 700.0 734.4 746.1 752.8 800.4 11146.1 11377.9 11391.2 11418.2 11434.2 11600.9 11709.5 11718.9 11727.8 11746.6 779.9 788.7 792.4 798.7 800.3 786.8 786.9 791.7 794.0 795.9 831.7 838.3 838.4 838.6 840.3 821.5 823.8 826.4 828.6 828.7 618.6 618.8 620.0 620.2 620.3 11592.5 11595.8 11599.9 11603.7 11681.8 10795.6 10798.5 10805.1 11022.1 11548.0 9085.2 9194.8 9318.1 9640.0 9702.9 3212.0 3238.9 3256.0 3265.8 3361.0 40268.0 40384.4 40469.6 40671.0 40833.6 8558.3 8759.3 8879.0 8977.9 9037.9 15420.5 15541.7 15541.9 15610.5 15652.0 6155.6 6180.1 6228.5 6280.7 6337.7 3482.7 3523.7 3547.9 3572.3 3573.2 2480.8 2509.2 2510.9 2525.5 2528.1 444.5 445.4 446.5 447.7 450.7 555.7 565.2 581.2 596.4 597.7 7326.9 7368.3 7420.2 7508.0 7532.4 526.7 582.3 587.8 613.5 637.7 3374.3 3385.7 3467.9 3485.7 3543.2 48548.0 48564.0 48653.3 48926.0 48976.0 43627.6 43700.3 44126.6 44562.0 44612.1 40096.4 40307.0 40367.2 40592.6 40750.4 6822.9 6876.7 7018.4 7139.3 7167.5 30042.2 30455.2 30638.8 30793.0 31097.2 36601.5 38965.8 40132.6 40208.8 40301.2 9153.2 9285.0 9296.9 9315.9 9418.6 9993.0 10105.4 10188.7 10406.2 10508.1 9506.2 9740.1 9798.3 9976.4 10388.5 9323.4 9460.2 9507.1 9874.1 9915.9 9364.5 9484.1 9600.3 9879.3 9972.3 8504.3 8534.0 8767.7 8786.3 8935.3 7691.9 8210.0 8329.1 8394.6 8972.1 04:19:13 INFO - 2015-12-21 04:19:13,569 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:19:13 INFO - 2015-12-21 04:19:13,569 DEBUG : BROWSER_OUTPUT: 04:19:14 INFO - 2015-12-21 04:19:14,255 INFO : Browser exited with error code: 0 04:19:14 INFO - 2015-12-21 04:19:14,276 INFO : Completed test dromaeo_css (00:18:34) 04:19:14 INFO - 2015-12-21 04:19:14,276 INFO : Starting test kraken 04:19:14 INFO - 2015-12-21 04:19:14,276 DEBUG : operating with platform_type : linux_ 04:19:14 INFO - 2015-12-21 04:19:14,276 INFO : Initialising browser for kraken test... 04:19:14 INFO - 2015-12-21 04:19:14,282 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpctw4N7/profile http://localhost:54367/getInfo.html 04:19:18 INFO - 2015-12-21 04:19:18,821 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:19:18 INFO - 2015-12-21 04:19:18,821 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:19:18 INFO - 2015-12-21 04:19:18,821 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:19:18 INFO - 2015-12-21 04:19:18,821 DEBUG : BROWSER_OUTPUT: __metrics 04:19:18 INFO - 2015-12-21 04:19:18,958 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 04:19:19 INFO - 2015-12-21 04:19:19,854 INFO : Browser initialized. 04:19:19 INFO - 2015-12-21 04:19:19,855 INFO : Running cycle 1/1 for kraken test... 04:19:19 INFO - 2015-12-21 04:19:19,855 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpctw4N7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:19:31 INFO - 2015-12-21 04:19:31,899 DEBUG : BROWSER_OUTPUT: RSS: Main: 151474176 04:19:31 INFO - 2015-12-21 04:19:31,900 DEBUG : BROWSER_OUTPUT: 04:19:34 INFO - 2015-12-21 04:19:34,475 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54367/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54367/tests/kraken/driver.html?testName=audio-beat-detection) 04:19:35 INFO - 2015-12-21 04:19:35,096 DEBUG : BROWSER_OUTPUT: RSS: Main: 152834048 04:19:35 INFO - 2015-12-21 04:19:35,096 DEBUG : BROWSER_OUTPUT: 04:19:37 INFO - 2015-12-21 04:19:37,818 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54367/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54367/tests/kraken/driver.html?testName=audio-dft) 04:19:38 INFO - 2015-12-21 04:19:38,425 DEBUG : BROWSER_OUTPUT: RSS: Main: 151535616 04:19:38 INFO - 2015-12-21 04:19:38,425 DEBUG : BROWSER_OUTPUT: 04:19:41 INFO - 2015-12-21 04:19:41,323 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54367/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54367/tests/kraken/driver.html?testName=audio-fft) 04:19:41 INFO - 2015-12-21 04:19:41,912 DEBUG : BROWSER_OUTPUT: RSS: Main: 146239488 04:19:41 INFO - 2015-12-21 04:19:41,912 DEBUG : BROWSER_OUTPUT: 04:19:43 INFO - 2015-12-21 04:19:43,977 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54367/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54367/tests/kraken/driver.html?testName=audio-oscillator) 04:19:44 INFO - 2015-12-21 04:19:44,558 DEBUG : BROWSER_OUTPUT: RSS: Main: 146292736 04:19:44 INFO - 2015-12-21 04:19:44,559 DEBUG : BROWSER_OUTPUT: 04:19:46 INFO - 2015-12-21 04:19:46,834 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54367/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54367/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:19:47 INFO - 2015-12-21 04:19:47,415 DEBUG : BROWSER_OUTPUT: RSS: Main: 146690048 04:19:47 INFO - 2015-12-21 04:19:47,415 DEBUG : BROWSER_OUTPUT: 04:19:50 INFO - 2015-12-21 04:19:50,455 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54367/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54367/tests/kraken/driver.html?testName=imaging-darkroom) 04:19:51 INFO - 2015-12-21 04:19:51,056 DEBUG : BROWSER_OUTPUT: RSS: Main: 146546688 04:19:51 INFO - 2015-12-21 04:19:51,056 DEBUG : BROWSER_OUTPUT: 04:19:54 INFO - 2015-12-21 04:19:54,522 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54367/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54367/tests/kraken/driver.html?testName=imaging-desaturate) 04:19:55 INFO - 2015-12-21 04:19:55,115 DEBUG : BROWSER_OUTPUT: RSS: Main: 145625088 04:19:55 INFO - 2015-12-21 04:19:55,115 DEBUG : BROWSER_OUTPUT: 04:19:58 INFO - 2015-12-21 04:19:58,004 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54367/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54367/tests/kraken/driver.html?testName=json-parse-financial) 04:19:58 INFO - 2015-12-21 04:19:58,604 DEBUG : BROWSER_OUTPUT: RSS: Main: 146423808 04:19:58 INFO - 2015-12-21 04:19:58,604 DEBUG : BROWSER_OUTPUT: 04:20:00 INFO - 2015-12-21 04:20:00,386 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54367/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54367/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:20:00 INFO - 2015-12-21 04:20:00,974 DEBUG : BROWSER_OUTPUT: RSS: Main: 148217856 04:20:00 INFO - 2015-12-21 04:20:00,975 DEBUG : BROWSER_OUTPUT: 04:20:02 INFO - 2015-12-21 04:20:02,873 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54367/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54367/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:20:03 INFO - 2015-12-21 04:20:03,486 DEBUG : BROWSER_OUTPUT: RSS: Main: 147492864 04:20:03 INFO - 2015-12-21 04:20:03,487 DEBUG : BROWSER_OUTPUT: 04:20:05 INFO - 2015-12-21 04:20:05,700 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54367/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54367/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:20:06 INFO - 2015-12-21 04:20:06,285 DEBUG : BROWSER_OUTPUT: RSS: Main: 146886656 04:20:06 INFO - 2015-12-21 04:20:06,286 DEBUG : BROWSER_OUTPUT: 04:20:08 INFO - 2015-12-21 04:20:08,853 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54367/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54367/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:20:09 INFO - 2015-12-21 04:20:09,455 DEBUG : BROWSER_OUTPUT: RSS: Main: 148963328 04:20:09 INFO - 2015-12-21 04:20:09,455 DEBUG : BROWSER_OUTPUT: 04:20:12 INFO - 2015-12-21 04:20:12,050 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54367/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54367/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:20:12 INFO - 2015-12-21 04:20:12,635 DEBUG : BROWSER_OUTPUT: RSS: Main: 148381696 04:20:12 INFO - 2015-12-21 04:20:12,635 DEBUG : BROWSER_OUTPUT: 04:20:14 INFO - 2015-12-21 04:20:14,429 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54367/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54367/tests/kraken/driver.html?testName=ai-astar) 04:20:14 INFO - 2015-12-21 04:20:14,679 DEBUG : BROWSER_OUTPUT: RSS: Main: 148267008 04:20:14 INFO - 2015-12-21 04:20:14,679 DEBUG : BROWSER_OUTPUT: 04:20:14 INFO - 2015-12-21 04:20:14,680 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:20:14 INFO - 2015-12-21 04:20:14,681 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:20:14 INFO - 2015-12-21 04:20:14,681 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:20:14 INFO - 2015-12-21 04:20:14,681 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:20:14 INFO - 2015-12-21 04:20:14,681 DEBUG : BROWSER_OUTPUT: |0;ai-astar;113;109;113;111;113;126;113;111;112;113 04:20:14 INFO - 2015-12-21 04:20:14,681 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;164;131;163;160;129;162;152;131;169;131 04:20:14 INFO - 2015-12-21 04:20:14,682 DEBUG : BROWSER_OUTPUT: |2;audio-dft;166;162;162;162;161;164;173;162;165;165 04:20:14 INFO - 2015-12-21 04:20:14,683 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;73;73;73;73;140;73;74;74;75 04:20:14 INFO - 2015-12-21 04:20:14,683 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;93;138;94;95;94;138;95;94;130 04:20:14 INFO - 2015-12-21 04:20:14,683 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;111;110;110;110;110;110;110;111;110 04:20:14 INFO - 2015-12-21 04:20:14,683 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;149;147;148;151;149;147;148;148;148 04:20:14 INFO - 2015-12-21 04:20:14,683 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;97;96;96;96;96;96;96;96;96 04:20:14 INFO - 2015-12-21 04:20:14,683 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;73;73;72;71;72;71;73;73;74;78 04:20:14 INFO - 2015-12-21 04:20:14,684 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;67;69;67;68;67;68;67;68;69 04:20:14 INFO - 2015-12-21 04:20:14,684 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;82;84;84;93;86;82;84;84;87;85 04:20:14 INFO - 2015-12-21 04:20:14,684 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;127;127;134;123;116;124;121;128;129;121 04:20:14 INFO - 2015-12-21 04:20:14,684 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;135;147;147;162;140;134;136;136;145;145 04:20:14 INFO - 2015-12-21 04:20:14,684 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;75;69;72;72;73;69;69;69;71;69 04:20:14 INFO - 2015-12-21 04:20:14,685 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:20:14 INFO - 2015-12-21 04:20:14,686 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:20:14 INFO - 2015-12-21 04:20:14,686 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,778 04:20:14 INFO - 2015-12-21 04:20:14,686 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:20:14 INFO - 2015-12-21 04:20:14,686 DEBUG : BROWSER_OUTPUT: __startTimestamp1450700414680__endTimestamp 04:20:14 INFO - 2015-12-21 04:20:14,686 DEBUG : BROWSER_OUTPUT: 04:20:14 INFO - 2015-12-21 04:20:14,690 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:20:14 INFO - 2015-12-21 04:20:14,690 DEBUG : BROWSER_OUTPUT: Number of tests: 14 04:20:14 INFO - 2015-12-21 04:20:14,690 DEBUG : BROWSER_OUTPUT: 04:20:14 INFO - 2015-12-21 04:20:14,690 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:113.40 Median:113.00 stddev:4.62 (4.1%) stddev-sans-first:4.90 04:20:14 INFO - 2015-12-21 04:20:14,690 DEBUG : BROWSER_OUTPUT: Values: 113.0 109.0 113.0 111.0 113.0 126.0 113.0 111.0 112.0 113.0 04:20:14 INFO - 2015-12-21 04:20:14,690 DEBUG : BROWSER_OUTPUT: 04:20:14 INFO - 2015-12-21 04:20:14,690 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:149.20 Median:161.00 stddev:16.64 (10.3%) stddev-sans-first:16.76 04:20:14 INFO - 2015-12-21 04:20:14,691 DEBUG : BROWSER_OUTPUT: Values: 164.0 131.0 163.0 160.0 129.0 162.0 152.0 131.0 169.0 131.0 04:20:14 INFO - 2015-12-21 04:20:14,691 DEBUG : BROWSER_OUTPUT: 04:20:14 INFO - 2015-12-21 04:20:14,691 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:164.20 Median:164.50 stddev:3.52 (2.1%) stddev-sans-first:3.67 04:20:14 INFO - 2015-12-21 04:20:14,691 DEBUG : BROWSER_OUTPUT: Values: 166.0 162.0 162.0 162.0 161.0 164.0 173.0 162.0 165.0 165.0 04:20:14 INFO - 2015-12-21 04:20:14,691 DEBUG : BROWSER_OUTPUT: 04:20:14 INFO - 2015-12-21 04:20:14,691 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.00 Median:73.00 stddev:21.10 (28.9%) stddev-sans-first:22.18 04:20:14 INFO - 2015-12-21 04:20:14,691 DEBUG : BROWSER_OUTPUT: Values: 72.0 73.0 73.0 73.0 73.0 140.0 73.0 74.0 74.0 75.0 04:20:14 INFO - 2015-12-21 04:20:14,691 DEBUG : BROWSER_OUTPUT: 04:20:14 INFO - 2015-12-21 04:20:14,691 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.50 Median:94.00 stddev:20.02 (21.3%) stddev-sans-first:20.72 04:20:14 INFO - 2015-12-21 04:20:14,691 DEBUG : BROWSER_OUTPUT: Values: 94.0 93.0 138.0 94.0 95.0 94.0 138.0 95.0 94.0 130.0 04:20:14 INFO - 2015-12-21 04:20:14,691 DEBUG : BROWSER_OUTPUT: 04:20:14 INFO - 2015-12-21 04:20:14,691 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.20 Median:110.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 04:20:14 INFO - 2015-12-21 04:20:14,691 DEBUG : BROWSER_OUTPUT: Values: 110.0 111.0 110.0 110.0 110.0 110.0 110.0 110.0 111.0 110.0 04:20:14 INFO - 2015-12-21 04:20:14,692 DEBUG : BROWSER_OUTPUT: 04:20:14 INFO - 2015-12-21 04:20:14,692 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:148.20 Median:148.00 stddev:1.23 (0.8%) stddev-sans-first:1.22 04:20:14 INFO - 2015-12-21 04:20:14,692 DEBUG : BROWSER_OUTPUT: Values: 147.0 149.0 147.0 148.0 151.0 149.0 147.0 148.0 148.0 148.0 04:20:14 INFO - 2015-12-21 04:20:14,692 DEBUG : BROWSER_OUTPUT: 04:20:14 INFO - 2015-12-21 04:20:14,692 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 04:20:14 INFO - 2015-12-21 04:20:14,692 DEBUG : BROWSER_OUTPUT: Values: 96.0 97.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 04:20:14 INFO - 2015-12-21 04:20:14,692 DEBUG : BROWSER_OUTPUT: 04:20:14 INFO - 2015-12-21 04:20:14,692 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:73.00 Median:73.00 stddev:2.00 (2.7%) stddev-sans-first:2.12 04:20:14 INFO - 2015-12-21 04:20:14,692 DEBUG : BROWSER_OUTPUT: Values: 73.0 73.0 72.0 71.0 72.0 71.0 73.0 73.0 74.0 78.0 04:20:14 INFO - 2015-12-21 04:20:14,693 DEBUG : BROWSER_OUTPUT: 04:20:14 INFO - 2015-12-21 04:20:14,693 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:67.80 Median:68.00 stddev:0.79 (1.2%) stddev-sans-first:0.83 04:20:14 INFO - 2015-12-21 04:20:14,693 DEBUG : BROWSER_OUTPUT: Values: 68.0 67.0 69.0 67.0 68.0 67.0 68.0 67.0 68.0 69.0 04:20:14 INFO - 2015-12-21 04:20:14,693 DEBUG : BROWSER_OUTPUT: 04:20:14 INFO - 2015-12-21 04:20:14,693 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:84.50 stddev:3.18 (3.8%) stddev-sans-first:3.17 04:20:14 INFO - 2015-12-21 04:20:14,693 DEBUG : BROWSER_OUTPUT: Values: 82.0 84.0 84.0 93.0 86.0 82.0 84.0 84.0 87.0 85.0 04:20:14 INFO - 2015-12-21 04:20:14,693 DEBUG : BROWSER_OUTPUT: 04:20:14 INFO - 2015-12-21 04:20:14,693 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:125.00 Median:127.00 stddev:5.08 (4.0%) stddev-sans-first:5.33 04:20:14 INFO - 2015-12-21 04:20:14,693 DEBUG : BROWSER_OUTPUT: Values: 127.0 127.0 134.0 123.0 116.0 124.0 121.0 128.0 129.0 121.0 04:20:14 INFO - 2015-12-21 04:20:14,693 DEBUG : BROWSER_OUTPUT: 04:20:14 INFO - 2015-12-21 04:20:14,694 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:142.70 Median:145.00 stddev:8.51 (5.9%) stddev-sans-first:8.56 04:20:14 INFO - 2015-12-21 04:20:14,694 DEBUG : BROWSER_OUTPUT: Values: 135.0 147.0 147.0 162.0 140.0 134.0 136.0 136.0 145.0 145.0 04:20:14 INFO - 2015-12-21 04:20:14,694 DEBUG : BROWSER_OUTPUT: 04:20:14 INFO - 2015-12-21 04:20:14,694 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.80 Median:71.50 stddev:2.15 (3.0%) stddev-sans-first:1.66 04:20:14 INFO - 2015-12-21 04:20:14,694 DEBUG : BROWSER_OUTPUT: Values: 75.0 69.0 72.0 72.0 73.0 69.0 69.0 69.0 71.0 69.0 04:20:14 INFO - 2015-12-21 04:20:14,694 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:20:14 INFO - 2015-12-21 04:20:14,695 DEBUG : BROWSER_OUTPUT: 04:20:15 INFO - 2015-12-21 04:20:15,579 INFO : Browser exited with error code: 0 04:20:15 INFO - 2015-12-21 04:20:15,630 INFO : Completed test kraken (00:01:01) 04:20:15 INFO - 2015-12-21 04:20:15,630 INFO : Starting test v8_7 04:20:15 INFO - 2015-12-21 04:20:15,630 DEBUG : operating with platform_type : linux_ 04:20:15 INFO - 2015-12-21 04:20:15,630 INFO : Initialising browser for v8_7 test... 04:20:15 INFO - 2015-12-21 04:20:15,636 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxQjTkS/profile http://localhost:54367/getInfo.html 04:20:20 INFO - 2015-12-21 04:20:20,195 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:20:20 INFO - 2015-12-21 04:20:20,195 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:20:20 INFO - 2015-12-21 04:20:20,196 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 04:20:20 INFO - 2015-12-21 04:20:20,196 DEBUG : BROWSER_OUTPUT: __metrics 04:20:20 INFO - 2015-12-21 04:20:20,312 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 04:20:21 INFO - 2015-12-21 04:20:21,266 INFO : Browser initialized. 04:20:21 INFO - 2015-12-21 04:20:21,267 INFO : Running cycle 1/1 for v8_7 test... 04:20:21 INFO - 2015-12-21 04:20:21,267 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxQjTkS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:20:33 INFO - 2015-12-21 04:20:33,268 DEBUG : BROWSER_OUTPUT: RSS: Main: 150994944 04:20:33 INFO - 2015-12-21 04:20:33,268 DEBUG : BROWSER_OUTPUT: 04:20:54 INFO - 2015-12-21 04:20:54,680 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:54367/tests/v8_7/run.html (next: http://localhost:54367/tests/v8_7/run.html) 04:20:54 INFO - 2015-12-21 04:20:54,938 DEBUG : BROWSER_OUTPUT: RSS: Main: 145293312 04:20:54 INFO - 2015-12-21 04:20:54,938 DEBUG : BROWSER_OUTPUT: 04:20:54 INFO - 2015-12-21 04:20:54,939 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:20:54 INFO - 2015-12-21 04:20:54,939 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:20:54 INFO - 2015-12-21 04:20:54,939 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:20:54 INFO - 2015-12-21 04:20:54,939 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:20:54 INFO - 2015-12-21 04:20:54,939 DEBUG : BROWSER_OUTPUT: |0;Richards;166.14055490945339 04:20:54 INFO - 2015-12-21 04:20:54,939 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;184.5699520118125 04:20:54 INFO - 2015-12-21 04:20:54,939 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.473962684489 04:20:54 INFO - 2015-12-21 04:20:54,939 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5122.448979591837 04:20:54 INFO - 2015-12-21 04:20:54,939 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1072.961373390558 04:20:54 INFO - 2015-12-21 04:20:54,939 DEBUG : BROWSER_OUTPUT: |5;Earley;704.7216349541931 04:20:54 INFO - 2015-12-21 04:20:54,939 DEBUG : BROWSER_OUTPUT: |6;Boyer;12192.77108433735 04:20:54 INFO - 2015-12-21 04:20:54,939 DEBUG : BROWSER_OUTPUT: |7;RegExp;26473.684210526317 04:20:54 INFO - 2015-12-21 04:20:54,940 DEBUG : BROWSER_OUTPUT: |8;Splay;590.5604719764012 04:20:54 INFO - 2015-12-21 04:20:54,940 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4845.410628019324 04:20:54 INFO - 2015-12-21 04:20:54,940 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:20:54 INFO - 2015-12-21 04:20:54,940 DEBUG : BROWSER_OUTPUT: __startTimestamp1450700454932__endTimestamp 04:20:54 INFO - 2015-12-21 04:20:54,940 DEBUG : BROWSER_OUTPUT: 04:20:54 INFO - 2015-12-21 04:20:54,940 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:20:54 INFO - 2015-12-21 04:20:54,941 DEBUG : BROWSER_OUTPUT: Number of tests: 10 04:20:54 INFO - 2015-12-21 04:20:54,941 DEBUG : BROWSER_OUTPUT: 04:20:54 INFO - 2015-12-21 04:20:54,941 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.14 Median:166.14 stddev:0.00 (0.0%) 04:20:54 INFO - 2015-12-21 04:20:54,941 DEBUG : BROWSER_OUTPUT: Values: 166.1 04:20:54 INFO - 2015-12-21 04:20:54,941 DEBUG : BROWSER_OUTPUT: 04:20:54 INFO - 2015-12-21 04:20:54,941 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:184.57 Median:184.57 stddev:0.00 (0.0%) 04:20:54 INFO - 2015-12-21 04:20:54,941 DEBUG : BROWSER_OUTPUT: Values: 184.6 04:20:54 INFO - 2015-12-21 04:20:54,941 DEBUG : BROWSER_OUTPUT: 04:20:54 INFO - 2015-12-21 04:20:54,942 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.47 Median:278.47 stddev:0.00 (0.0%) 04:20:54 INFO - 2015-12-21 04:20:54,942 DEBUG : BROWSER_OUTPUT: Values: 278.5 04:20:54 INFO - 2015-12-21 04:20:54,942 DEBUG : BROWSER_OUTPUT: 04:20:54 INFO - 2015-12-21 04:20:54,942 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5122.45 Median:5122.45 stddev:0.00 (0.0%) 04:20:54 INFO - 2015-12-21 04:20:54,942 DEBUG : BROWSER_OUTPUT: Values: 5122.4 04:20:54 INFO - 2015-12-21 04:20:54,942 DEBUG : BROWSER_OUTPUT: 04:20:54 INFO - 2015-12-21 04:20:54,942 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1072.96 Median:1072.96 stddev:0.00 (0.0%) 04:20:54 INFO - 2015-12-21 04:20:54,943 DEBUG : BROWSER_OUTPUT: Values: 1073.0 04:20:54 INFO - 2015-12-21 04:20:54,943 DEBUG : BROWSER_OUTPUT: 04:20:54 INFO - 2015-12-21 04:20:54,943 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:704.72 Median:704.72 stddev:0.00 (0.0%) 04:20:54 INFO - 2015-12-21 04:20:54,943 DEBUG : BROWSER_OUTPUT: Values: 704.7 04:20:54 INFO - 2015-12-21 04:20:54,943 DEBUG : BROWSER_OUTPUT: 04:20:54 INFO - 2015-12-21 04:20:54,943 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12192.77 Median:12192.77 stddev:0.00 (0.0%) 04:20:54 INFO - 2015-12-21 04:20:54,943 DEBUG : BROWSER_OUTPUT: Values: 12192.8 04:20:54 INFO - 2015-12-21 04:20:54,943 DEBUG : BROWSER_OUTPUT: 04:20:54 INFO - 2015-12-21 04:20:54,944 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26473.68 Median:26473.68 stddev:0.00 (0.0%) 04:20:54 INFO - 2015-12-21 04:20:54,944 DEBUG : BROWSER_OUTPUT: Values: 26473.7 04:20:54 INFO - 2015-12-21 04:20:54,944 DEBUG : BROWSER_OUTPUT: 04:20:54 INFO - 2015-12-21 04:20:54,944 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:590.56 Median:590.56 stddev:0.00 (0.0%) 04:20:54 INFO - 2015-12-21 04:20:54,944 DEBUG : BROWSER_OUTPUT: Values: 590.6 04:20:54 INFO - 2015-12-21 04:20:54,944 DEBUG : BROWSER_OUTPUT: 04:20:54 INFO - 2015-12-21 04:20:54,944 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4845.41 Median:4845.41 stddev:0.00 (0.0%) 04:20:54 INFO - 2015-12-21 04:20:54,944 DEBUG : BROWSER_OUTPUT: Values: 4845.4 04:20:54 INFO - 2015-12-21 04:20:54,945 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:20:54 INFO - 2015-12-21 04:20:54,945 DEBUG : BROWSER_OUTPUT: 04:20:55 INFO - 2015-12-21 04:20:55,855 INFO : Browser exited with error code: 0 04:20:55 INFO - 2015-12-21 04:20:55,867 INFO : Completed test v8_7 (00:00:40) 04:20:55 INFO - 2015-12-21 04:20:55,925 INFO : Completed test suite (00:20:16) 04:20:55 INFO - 2015-12-21 04:20:55,925 DEBUG : Working with test: dromaeo_css 04:20:55 INFO - 2015-12-21 04:20:55,925 DEBUG : Generating results file: dromaeo_css 04:20:55 INFO - 2015-12-21 04:20:55,926 DEBUG : Working with test: kraken 04:20:55 INFO - 2015-12-21 04:20:55,926 DEBUG : Generating results file: kraken 04:20:55 INFO - 2015-12-21 04:20:55,927 INFO : javascript benchmark 04:20:55 INFO - 2015-12-21 04:20:55,927 DEBUG : Working with test: v8_7 04:20:55 INFO - 2015-12-21 04:20:55,927 DEBUG : Generating results file: v8_7 04:20:55 INFO - 2015-12-21 04:20:55,927 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:20:55 INFO - 2015-12-21 04:20:55,979 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,94,43]] 04:20:55 INFO - 2015-12-21 04:20:55,979 DEBUG : process_Request line: dromaeo_css 6266.91 graph.html#tests=[[72,94,43]] 04:20:55 INFO - 2015-12-21 04:20:55,979 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:20:56 INFO - 2015-12-21 04:20:56,013 DEBUG : process_Request line: kraken 1532.20 graph.html#tests=[[232,94,43]] 04:20:56 INFO - 2015-12-21 04:20:56,013 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 04:20:56 INFO - 2015-12-21 04:20:56,042 DEBUG : process_Request line: v8_7 21428.95 graph.html#tests=[[230,94,43]] 04:20:56 INFO - 2015-12-21 04:20:56,044 INFO : javascript benchmark 04:20:56 INFO - 2015-12-21 04:20:56,047 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2661.294756889424, 2695.487857307113, 2711.0240313487143, 2714.674239091309, 2736.3569561153126, 4502.729836209822, 4646.581807637316, 4684.3676103726075, 4697.67116301859, 4707.388039554858, 4560.20196465618, 4606.907861842761, 4651.7906694198755, 4682.41469816273, 4806.735629540374, 4297.763623000742, 4338.631216346615, 4361.34579813029, 4428.6014258716705, 4455.198064348416, 4421.624161946238, 4457.264551349023, 4467.396901418299, 4469.463664360282, 4474.686771925966, 4107.281225785476, 4208.431861698693, 4225.514065882401, 4334.913301733947, 4398.582134697214, 4247.129338485603, 4254.617084462413, 4278.251306021465, 4309.82760689572, 4321.243782338078, 4241.34259189823, 4261.573842615745, 4265.488141423018, 4285.400043993844, 4305.031367942219, 4287.0, 4407.272799987998, 4586.793594288265, 4741.359916411275, 4759.452662943771, 5024.145895197825, 5026.220935754964, 5038.345015148007, 5055.5702765265, 5094.4141423736, 4751.40607424072, 5045.84862454127, 5065.164247899129, 5080.110980578421, 5121.564667003309, 4508.233600287959, 4512.774361281956, 4514.0971805639, 4516.119356725473, 4639.164950308977, 5173.81891633791, 5174.43081261061, 5176.120059589879, 5186.222066690027, 5199.2201169824075, 4952.90094198114, 4954.603631709455, 4972.627052970969, 4975.502449754996, 5000.6249531284575, 4796.25658023007, 4904.117258893433, 4966.652334336562, 5069.264956581275, 5100.48995100487, 4908.092002979389, 4915.287283343895, 4915.827946021872, 4932.926006109911, 4971.850844474671, 4882.902341953141, 4899.608031357483, 4912.557869791664, 4919.262110683355, 4941.135301322326, 3992.4210989406374, 4093.017675757856, 4118.0, 4169.082801783603, 4259.233337999128, 3659.1583935694407, 3674.4855720199193, 3688.0595448161084, 3707.5550933888107, 3712.7401081924004, 4124.773137477468, 4330.891727706832, 4336.132773445323, 4337.544557821447, 4355.978220108943, 4814.181589129857, 4902.896848209124, 4972.801087956441, 4975.203967365204, 5017.422996355466, 4314.913701725948, 4320.4383430153875, 4363.498197707241, 4405.31717583775, 4408.713433626868, 4245.1934132514725, 4339.349097635317, 4478.171538265431, 4490.730556166641, 4497.572730590588, 4702.52974702527, 4786.856394308176, 4829.2997515360075, 4839.056384005085, 4842.51574842527, 5002.474740152377, 5057.468965758542, 5060.3168572242275, 5062.088824011713, 5087.186050231945, 4634.119517291704, 4821.493743157058, 4830.661853670209, 4831.299461578122, 4866.440359358719, 4859.31969524274, 4916.655834091579, 4954.256861470808, 4966.205407134841, 5021.698698078127, 5038.0, 5067.214581739836, 5077.847664570068, 5089.541941225308, 5132.3327967364, 4601.700889442175, 4701.177293973473, 4902.166631672696, 4925.1381008324115, 4966.701997880139, 4765.475797662324, 4827.61379089679, 4832.057748738962, 4848.466668666373, 4869.293952376956, 4762.3570817939135, 4817.470078291455, 4823.517648235148, 4832.371791667201, 4866.95133048665, 4640.303954406866, 4741.976290118528, 4755.5006724294835, 4939.604831613534, 4982.676126051795, 4778.617710583076, 4789.736564488847, 4828.565429111397, 4853.684510506806, 4891.388576427947], "unit": "score", "name": "dojo.html", "value": 4588.11285175221}, {"lowerIsBetter": false, "replicates": [553.7480446396889, 561.0, 564.9858753531162, 566.2808233543402, 569.5699746691249, 35641.0, 36015.27969440616, 36064.45903311452, 36299.637003629956, 36379.63620363802, 40818.7959060205, 41425.58574414255, 41536.58463415365, 41559.79220103895, 41660.37509437361, 7940.404469664783, 8071.434999550034, 8094.271515563628, 8113.7565873023905, 8120.147384524628, 7820.726274580363, 7954.602269886512, 7963.124056353797, 7969.0, 7980.561069141195, 7898.5260884346835, 7970.08344040437, 7970.362371010334, 7971.760847174565, 7972.8405431891615, 7786.3259399229155, 7804.49270797402, 7820.374370050382, 7838.412119091063, 7845.607719614053, 7801.765947021599, 7899.605019749047, 7907.48601340911, 7934.642941067666, 7937.126916039231, 36826.26347473035, 37246.441303380474, 37295.81352093222, 37309.44035839465, 37315.25369492595, 2992.850357482139, 3012.487877060905, 3182.3317103408313, 3205.0, 3378.7297016238886, 28111.0, 28284.434311313864, 28518.857405713046, 28678.85660571684, 28876.0, 3375.476801095834, 3434.5019972104155, 3437.604675462329, 3491.8603255869734, 3514.5606799150105, 207926.0, 209013.0, 209115.0, 209796.95101524546, 210278.0, 52705.472945270056, 53655.46344536583, 54541.454585454434, 54803.72598137063, 55004.449955500735, 65780.0, 66112.33887661158, 66846.33153668499, 66898.3310166892, 67078.66460667666, 13233.404496797668, 13539.59381218565, 13584.030315604183, 13585.932070339584, 13610.319484025758, 10433.32183408076, 10451.529681164213, 10472.895271047191, 10523.315984460985, 10611.893881061245, 18833.717494237597, 19276.554258430675, 19301.4209573713, 19320.903395482932, 19339.323123690603, 7599.544027358376, 7759.340456061298, 7839.137694853562, 7906.486078404885, 7946.404019698546, 873.6057630120504, 875.5840975536569, 876.2245412809582, 882.8852249207562, 998.4608311511847, 26723.732762672123, 27754.444911101666, 27925.0, 29545.0, 30268.0, 978.6379039755194, 994.547480896205, 1012.5241136665755, 1024.472396715692, 1116.843641890136, 13613.183209007491, 13772.173669579857, 13825.585232443043, 13894.374753136133, 14547.41810327575, 269596.0, 272152.0, 273056.0, 273356.0, 275044.0, 47127.528724712996, 47266.52733472621, 47415.0, 47596.28605570919, 47747.28379074247, 1194.0745921910416, 1253.0476837603487, 1253.423425224407, 1258.811178323259, 1263.677762170659, 27276.31809204786, 27510.862445687642, 27784.44431111326, 27879.163625091278, 28018.439631206853, 41120.38319425271, 41420.792896035324, 41466.792666036476, 41491.37762933619, 41544.79227603963, 246106.0, 247045.0, 248741.0, 248978.0, 249596.0, 28795.856020719762, 29407.55888661629, 29706.405871882875, 29747.553786692784, 30035.69964300329, 33795.32409351848, 34664.0, 34668.13329666779, 34692.132696682784, 34734.65265347314, 29947.850260748557, 32461.35077298488, 32673.509897351083, 33044.50433243455, 34430.13924651904, 31512.0, 32730.0, 33064.33871322512, 33225.66774332226, 35762.0, 32343.676563234065, 32876.67123328736, 32895.0, 32985.0, 33284.33431331408, 28487.28781780472, 29752.256193595334, 29908.850455747583, 30214.0, 30647.54028689527, 21884.671729923746, 22401.551968960204, 22448.0, 22450.0, 22473.21343752961], "unit": "score", "name": "ext.html", "value": 17003.05395369892}, {"lowerIsBetter": false, "replicates": [323.08727843841154, 324.93663735571545, 329.3133815993653, 337.3455496337106, 337.87160878866035, 15998.680026399465, 16076.919615401921, 16400.707012870615, 16663.91668041658, 16926.645868330535, 15483.071015739035, 15586.532404027897, 15588.0, 15619.843801561981, 15702.37190512381, 4116.7529948203055, 4288.442502474674, 4288.807003684826, 4299.914001719963, 4300.118475712471, 3729.974257079298, 4230.407742915994, 4235.74585524868, 4248.702590818644, 4641.187792136379, 4551.0, 4554.681172317939, 4587.816487340502, 4588.197065513538, 4606.60843828275, 4481.372607834889, 4590.0, 4814.87962800931, 4865.975670121662, 4876.097921884428, 4332.631726303268, 4339.522652508222, 4340.956590434087, 4348.2607956647125, 4354.804033818486, 10933.781324373547, 11025.669229923034, 11032.889671103347, 11057.06014988727, 11061.723456913562, 1831.7893442254044, 1843.2626949220285, 1871.9344822931132, 1928.855335849803, 1939.56359819041, 8638.568071596395, 8907.643694252221, 12377.504899803997, 12482.126251162423, 12803.871961280362, 1707.4792188382662, 1741.0946307919937, 1751.474557632705, 1757.9736303955451, 1774.2459454731688, 54845.45154548444, 57472.137917931264, 57841.13238301429, 58369.708151459396, 58877.116843247386, 9753.21974242059, 9792.55933482995, 9824.361416507903, 9832.213422926148, 9835.3115281931, 11490.712732181595, 11826.763464730657, 11860.22908510944, 11914.344711040803, 12499.812502812465, 6807.795766127024, 6975.511714179958, 7077.0, 7093.716251349888, 7127.073480447612, 6709.027191057367, 6782.966085169543, 6903.102596662402, 6903.5167538271835, 6920.619365934922, 4042.0703238255364, 4099.713020088625, 4101.118259574206, 4101.200265948171, 4121.113960498507, 1559.3216950626513, 1560.7658851172414, 1690.6787710334997, 1704.1649591700225, 1734.1415999080536, 812.224325768901, 854.2994744309731, 932.1238036245907, 992.2211064314479, 1013.4324778124127, 15751.44869929547, 16005.537352229438, 16062.518124456285, 16158.67682646364, 16230.269637866324, 838.8490071787137, 879.5821984557411, 888.440282621944, 961.5913236874301, 962.7593101724569, 8992.955035224782, 9098.636054557875, 9262.861057084148, 9348.345615806964, 9513.381630194015, 318825.0, 366563.0, 431317.0, 431548.0, 432022.0, 71950.0, 76022.0, 76142.23857761463, 76253.0, 76701.0, 1091.6015654286127, 1105.209775010855, 1105.3147048829617, 1115.67087709126, 1129.627223016419, 14623.488177913721, 14772.77840832367, 15147.166905820286, 15150.242487875783, 15370.077795332316, 25207.87396063008, 28523.857380712965, 31462.0, 33912.83043584766, 34094.31811363808, 33516.83663556178, 34640.65359346374, 35035.0, 36806.26387472182, 37010.62989370179, 8405.369597280107, 8495.8725619117, 8763.737087887373, 8763.824723505622, 8768.649254030022, 10367.066963973279, 10411.375317480975, 11405.942970285096, 14628.561143165722, 14818.77771833445, 13685.0, 13768.898488120725, 13787.241701706502, 14050.086744361992, 14158.0, 13281.66795830112, 13496.5276215332, 13503.0, 13869.583912482642, 13906.096103753223, 13926.58220253394, 14027.01810873229, 14045.227512486912, 14257.42970281177, 14421.92789036048, 8621.568921554022, 8717.825643486967, 8757.91242087571, 8764.1674040966, 8820.25027872638, 9409.858852117086, 9440.197583205505, 11403.600873969372, 13612.795808062689, 13615.659608509866], "unit": "score", "name": "jquery.html", "value": 7932.455973520967}, {"lowerIsBetter": false, "replicates": [1505.4354617018619, 1570.9764353534695, 1646.901185928844, 1667.0914351678334, 1695.1270095900607, 5619.353774315955, 6049.969750151254, 6953.791386258414, 7145.249748776375, 7161.785146445602, 6748.730050797963, 7019.89470157948, 7044.119485064367, 7055.89416158758, 7125.572465652064, 2063.1334839367464, 2079.9792002079935, 2097.1716172112006, 2098.706181134635, 2103.621348157331, 2081.032319971213, 2087.634663933813, 2106.3575609439113, 2120.109553987329, 2125.7980491853327, 2023.5548179400507, 2071.7099606055162, 2085.8435617328687, 2097.5490269592033, 2101.9159233630635, 2069.420562242567, 2094.4973206430423, 2098.3600001999384, 2098.5593025464673, 2098.95802083959, 1913.4929243750416, 1936.6514027475048, 1948.5031317014214, 1965.6756635155182, 1971.802819718031, 7057.788266352018, 7205.2434494378385, 7206.711731530732, 7211.242819503929, 7231.746888858917, 4214.346776249687, 4267.274563324242, 4318.611324980767, 4327.026419055719, 4334.761588112652, 2657.8139530232893, 2680.4773069251505, 2715.8913643454434, 2740.4656092062055, 2761.7100204478443, 837.2841220756227, 848.7750746052301, 859.3082568532329, 859.673324136824, 898.3307435960252, 9352.438853668802, 9375.5312234388, 9452.054794520562, 9498.9525052375, 9510.857337139949, 3783.3000894834545, 3796.335641262768, 3837.57786643469, 3837.9232415351535, 3853.8265778040054, 8198.303144232688, 8206.384521160937, 8212.753617391489, 8215.34277257818, 8226.588670566447, 4120.443740095108, 4141.565135660712, 4159.085001299709, 4159.438475805788, 4175.478065241845, 3244.724198443159, 3339.1151344893624, 3719.925601487955, 3744.5506539215467, 3785.3943369061367, 3445.104272889067, 3454.170998960282, 3469.0, 3502.1069627244888, 3507.596626387947, 1040.4693606260862, 1047.9633212837514, 1067.8291473364225, 1075.2795626929976, 1088.1458055426451, 1064.4252103863828, 1090.9945450272699, 1103.624767579027, 1117.4412793603199, 1118.429600903545, 6819.147606549182, 6826.419754320938, 6830.487713421513, 6833.658317084126, 6863.485238607124, 1087.9891201088046, 1098.6045023791428, 1125.4035361258545, 1130.2540323386525, 1143.3140115930375, 5244.895102098013, 5254.97372513135, 5368.5436737876935, 5398.757055932492, 5421.376541697755, 8614.483131012159, 8743.256823169975, 8768.95615521931, 8771.780705482413, 8800.559972001374, 8397.95801020991, 8530.061693213742, 8557.957210213908, 8559.700410485602, 8566.100559441293, 6761.72953081881, 6778.864422711518, 6804.659767011729, 6808.183018037867, 6846.281140480178, 6414.5189110815945, 6483.870322593616, 6530.379613936669, 6538.967305163444, 6541.934580654323, 7558.58427786477, 7682.116556596063, 7803.258690424401, 7816.687332506636, 7831.2560306770765, 7791.4935529190425, 7843.56860372685, 7875.960620196863, 7968.243016913384, 8060.7178748745855, 4975.950240497548, 5119.334486516878, 6114.510839132948, 6186.133941248138, 6289.433950944437, 6741.1910570731825, 6853.246142924175, 6886.758963436256, 6894.103766510321, 6903.102596662402, 6371.74513019474, 6381.936180638134, 6494.902576461452, 6530.249021362604, 6654.900176497454, 6234.189555357774, 6292.811215663537, 6295.937040629535, 6470.676466176578, 6550.213974323112, 6441.259255185526, 6441.3880681335195, 6469.061986011908, 6484.610923344614, 6489.448396886318, 5865.94134058654, 5893.086571581412, 5947.821565353046, 5953.464188223081, 6042.546808989256, 6105.572609917263, 6126.6017708848785, 6127.724252408741, 6135.53983451234, 6153.953827849186], "unit": "score", "name": "mootools.html", "value": 4097.021751797827}, {"lowerIsBetter": false, "replicates": [298.09676679660623, 305.51270723196507, 306.986185621647, 307.8359135278989, 308.3755395324468, 5461.726913654321, 5483.451654834519, 5544.140658197981, 5568.916466253009, 5579.609427340088, 5445.292112025431, 5467.890642187154, 5471.425500322459, 5480.424555421683, 5487.5609951203905, 4076.9592304076878, 4249.107687385653, 4249.681273904454, 4250.596193361625, 4254.106637606099, 4092.1201941582704, 4117.053077792109, 4120.876373708778, 4143.399207114967, 4153.563875793044, 4077.55146933837, 4090.8159132838946, 4096.283150448675, 4107.17856428714, 4111.280525907969, 4069.6540794032544, 4080.0411903202726, 4080.795960201993, 4116.382542618601, 4128.4632997710405, 3621.5473065866768, 3626.9455958160647, 3629.056445324208, 3629.2922880038404, 3632.6367363263726, 5174.068667639824, 5287.86780330491, 5291.920621190685, 5297.0, 5310.787568497255, 4786.234202527614, 4788.545088216614, 4794.113089078497, 4799.784009719571, 4807.18277892759, 5738.368779434259, 5740.311162660508, 5749.195112684228, 5753.942460575425, 5770.278715160605, 3951.881443556698, 3953.89290998518, 3954.3079961006997, 3956.248312820555, 3958.5447673517624, 5932.199153114318, 5993.760249590011, 6044.365341639153, 6063.484603808682, 6073.848153796102, 4067.0, 4072.6538244248977, 4075.49056367954, 4087.9386809197886, 4088.6320231178743, 4158.896027599334, 4163.167366526707, 4163.188178305262, 4164.167166566699, 4167.729097608646, 4763.69036012658, 4779.211430114062, 4783.1390349737385, 4793.784279707352, 4796.208625576811, 5618.213450116987, 5753.935521928456, 5755.453231942959, 5767.567432442584, 5772.307323121168, 1429.1567974894863, 1444.6821699226152, 1445.2918070145554, 1445.7903603977575, 1446.0167086381362, 958.4153666263574, 961.4519723757392, 971.5336638413601, 981.474910922653, 988.7478692933399, 4960.082384758831, 4960.553550180429, 4983.25251212321, 4986.925196122061, 4989.975050124799, 5862.003459411825, 5903.085021821625, 5908.675022873783, 6014.308354539196, 6059.303180134253, 5029.899402012013, 5034.874128146753, 5058.645894787403, 5076.162433198481, 5077.289179514871, 3212.4699424595146, 3245.334706385185, 3246.707796298297, 3247.6427592964756, 3264.6735326467165, 5700.515456186187, 5703.9429605704245, 5711.628744131701, 5714.2571465710025, 5721.599488035798, 6003.489703375175, 6214.720337584819, 6218.347073557302, 6228.314885362606, 6243.96878015607, 5716.599838011382, 5716.771329146871, 5718.085106383042, 5718.228039214653, 5727.2268243787385, 6069.453749162597, 6070.0, 6070.635761854126, 6072.483838873569, 6085.6348619083, 5735.942640573708, 5891.9116213255975, 5918.378570250234, 5933.3769954154195, 5936.13925980739, 5685.658860468385, 5715.0, 5721.427857214246, 5725.398833122462, 5726.74229659658, 4079.306517891906, 4126.711130220856, 4134.813933372945, 4135.875923722293, 4141.958580414278, 5927.377625349276, 6005.0, 6031.155638210566, 6046.128089157615, 6059.575829692056, 5792.189093526826, 5808.7095645218415, 5812.622179558315, 5828.53371730269, 5835.591508594358, 5801.82594522165, 5803.448672376117, 5804.82585522435, 5808.390119037609, 5810.82567522975, 5580.0, 5622.24099746529, 5680.772769089355, 5692.260006199168, 5696.1455781633085, 5837.737301821343, 5849.795257165979, 5852.678102704393, 5856.4143585641095, 5863.530917526673, 5683.460071293221, 5700.3444603871085, 5713.143028545752, 5713.8285851424525, 5716.571257155647], "unit": "score", "name": "prototype.html", "value": 4311.320397997642}, {"lowerIsBetter": false, "replicates": [699.9825004374892, 734.3647744700831, 746.1009483572296, 752.7553545097843, 800.4276941986478, 11146.108311335094, 11377.886221137807, 11391.202615816886, 11418.200725949177, 11434.199606027581, 11600.941995290012, 11709.473073711682, 11718.941405292982, 11727.824082638766, 11746.588869389572, 779.9181085985961, 788.7436583110483, 792.3502727763237, 798.744401791427, 800.2837460472873, 786.7875673568133, 786.8662327404355, 791.6833266693311, 793.9682412703485, 795.932345750612, 831.683960095163, 838.3419016072385, 838.3921656798833, 838.6351936907465, 840.3235395506615, 821.5070957425556, 823.7652269103306, 826.3802148388648, 828.5691440450992, 828.70580943765, 618.5886385553647, 618.7896115320767, 620.0017971066579, 620.1596836286857, 620.3300435529617, 11592.536298548048, 11595.826062609067, 11599.884001159964, 11603.6518904433, 11681.824772628417, 10795.622153224678, 10798.51406686701, 10805.08895736012, 11022.118230541537, 11548.0, 9085.182333589943, 9194.770130746718, 9318.113847074084, 9639.987801280904, 9702.854457183148, 3211.983940080308, 3238.8542515586855, 3255.96744032559, 3265.755068369858, 3361.0085024917707, 40268.0, 40384.39423408651, 40469.595304046874, 40671.0, 40833.59166408327, 8558.315334773204, 8759.2992560595, 8879.0, 8977.865332019894, 9037.864432033524, 15420.537383878502, 15541.689166216613, 15541.922290388702, 15610.45363412275, 15652.0, 6155.599886007395, 6180.072989051679, 6228.4705800007505, 6280.748770049238, 6337.746490140435, 3482.651734826497, 3523.700485458765, 3547.875824346187, 3572.285542891432, 3573.249617580337, 2480.751924807505, 2509.209598976353, 2510.9121180758584, 2525.4696513731956, 2528.127795910408, 444.46220073711174, 445.4454204515399, 446.4999200895018, 447.7358358568395, 450.7228054746307, 555.7384736647867, 565.2425749495695, 581.1515187825737, 596.3559355895623, 597.6892016151577, 7326.853462930818, 7368.263173682589, 7420.183779784219, 7508.0, 7532.435067369969, 526.7471613625481, 582.347770497046, 587.7560812262863, 613.4908026338129, 637.7002808679913, 3374.342003309331, 3385.6614338565946, 3467.878624248139, 3485.721142308609, 3543.20277937462, 48548.0, 48564.0, 48653.270200947016, 48926.0, 48976.0, 43627.56372436235, 43700.3444948326, 44126.55873441289, 44562.0, 44612.10775784531, 40096.39855402229, 40307.0, 40367.19265614671, 40592.594074058885, 40750.388744168864, 6822.8635427292165, 6876.724931002904, 7018.438524917892, 7139.32176443237, 7167.534110282815, 30042.24894377658, 30455.238619034702, 30638.84680576583, 30793.0, 31097.222569435944, 36601.45097823482, 38965.80517097397, 40132.59867401289, 40208.798956005034, 40301.1939761209, 9153.17621414076, 9285.0, 9296.860547091934, 9315.906840931775, 9418.62325506972, 9993.00069992995, 10105.44420056904, 10188.694339169837, 10406.219533534864, 10508.054275115277, 9506.239500840054, 9740.123388895034, 9798.265130115413, 9976.401415915068, 10388.532516036645, 9323.393979391318, 9460.243180545402, 9507.144357007903, 9874.111329980336, 9915.900840991499, 9364.531773411438, 9484.146426821619, 9600.279979001463, 9879.308448408543, 9972.30193886421, 8504.319654427507, 8534.0, 8767.693130740394, 8786.341024423065, 8935.285177185939, 7691.923080769121, 8210.0, 8329.083800781787, 8394.58027098655, 8972.102789720975], "unit": "score", "name": "yui.html", "value": 5541.525983459451}], "name": "dromaeo_css", "value": 6266.910323390947}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 109.0, 113.0, 111.0, 113.0, 126.0, 113.0, 111.0, 112.0, 113.0], "unit": "score", "name": "ai-astar", "value": 113.4}, {"lowerIsBetter": true, "replicates": [164.0, 131.0, 163.0, 160.0, 129.0, 162.0, 152.0, 131.0, 169.0, 131.0], "unit": "score", "name": "audio-beat-detection", "value": 149.2}, {"lowerIsBetter": true, "replicates": [166.0, 162.0, 162.0, 162.0, 161.0, 164.0, 173.0, 162.0, 165.0, 165.0], "unit": "score", "name": "audio-dft", "value": 164.2}, {"lowerIsBetter": true, "replicates": [72.0, 73.0, 73.0, 73.0, 73.0, 140.0, 73.0, 74.0, 74.0, 75.0], "unit": "score", "name": "audio-fft", "value": 80.0}, {"lowerIsBetter": true, "replicates": [94.0, 93.0, 138.0, 94.0, 95.0, 94.0, 138.0, 95.0, 94.0, 130.0], "unit": "score", "name": "audio-oscillator", "value": 106.5}, {"lowerIsBetter": true, "replicates": [110.0, 111.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 111.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.2}, {"lowerIsBetter": true, "replicates": [147.0, 149.0, 147.0, 148.0, 151.0, 149.0, 147.0, 148.0, 148.0, 148.0], "unit": "score", "name": "imaging-darkroom", "value": 148.2}, {"lowerIsBetter": true, "replicates": [96.0, 97.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 96.1}, {"lowerIsBetter": true, "replicates": [73.0, 73.0, 72.0, 71.0, 72.0, 71.0, 73.0, 73.0, 74.0, 78.0], "unit": "score", "name": "json-parse-financial", "value": 73.0}, {"lowerIsBetter": true, "replicates": [68.0, 67.0, 69.0, 67.0, 68.0, 67.0, 68.0, 67.0, 68.0, 69.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.8}, {"lowerIsBetter": true, "replicates": [82.0, 84.0, 84.0, 93.0, 86.0, 82.0, 84.0, 84.0, 87.0, 85.0], "unit": "score", "name": "stanford-crypto-aes", "value": 85.1}, {"lowerIsBetter": true, "replicates": [127.0, 127.0, 134.0, 123.0, 116.0, 124.0, 121.0, 128.0, 129.0, 121.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 125.0}, {"lowerIsBetter": true, "replicates": [135.0, 147.0, 147.0, 162.0, 140.0, 134.0, 136.0, 136.0, 145.0, 145.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 142.7}, {"lowerIsBetter": true, "replicates": [75.0, 69.0, 72.0, 72.0, 73.0, 69.0, 69.0, 69.0, 71.0, 69.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 70.8}], "name": "kraken", "value": 1532.2}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.14055490945339], "unit": "score", "name": "Richards", "value": 212.48273800000004}, {"lowerIsBetter": false, "replicates": [184.5699520118125], "unit": "score", "name": "DeltaBlue", "value": 358.227324}, {"lowerIsBetter": false, "replicates": [278.473962684489], "unit": "score", "name": "Encrypt", "value": 955.8559709999997}, {"lowerIsBetter": false, "replicates": [5122.448979591837], "unit": "score", "name": "Decrypt", "value": 51.96362151394425}, {"lowerIsBetter": false, "replicates": [1072.961373390558], "unit": "score", "name": "RayTrace", "value": 689.6697479999999}, {"lowerIsBetter": false, "replicates": [704.7216349541931], "unit": "score", "name": "Earley", "value": 945.7109970000002}, {"lowerIsBetter": false, "replicates": [12192.77108433735], "unit": "score", "name": "Boyer", "value": 54.66050296442688}, {"lowerIsBetter": false, "replicates": [26473.684210526317], "unit": "score", "name": "RegExp", "value": 34.410964214711704}, {"lowerIsBetter": false, "replicates": [590.5604719764012], "unit": "score", "name": "Splay", "value": 137.9892557442557}, {"lowerIsBetter": false, "replicates": [4845.410628019324], "unit": "score", "name": "NavierStokes", "value": 306.26919242273203}], "name": "v8_7", "value": 21428.954281571278}]} 04:20:56 INFO - RETURN: dromaeo_css: 6266.91 04:20:56 INFO - RETURN: kraken: 1532.20 04:20:56 INFO - RETURN: v8_7: 21428.95 04:20:56 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,94,43]]", "result": "21428.95"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,94,43]]", "result": "1532.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,94,43]]", "result": "6266.91"}}} 04:20:56 INFO - Return code: 0 04:20:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:20:56 INFO - # TBPL SUCCESS # 04:20:56 INFO - Running post-action listener: _resource_record_post_action 04:20:56 INFO - Running post-run listener: _resource_record_post_run 04:20:57 INFO - Total resource usage - Wall time: 1233s; CPU: 13.0%; Read bytes: 4096; Write bytes: 545247232; Read time: 12; Write time: 383816 04:20:57 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 226746368; Read time: 0; Write time: 190644 04:20:57 INFO - run-tests - Wall time: 1218s; CPU: 13.0%; Read bytes: 4096; Write bytes: 318111744; Read time: 12; Write time: 193144 04:20:57 INFO - Running post-run listener: _upload_blobber_files 04:20:57 INFO - Blob upload gear active. 04:20:57 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 04:20:57 INFO - Copying logs to upload dir... 04:20:57 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1277.366980 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2015-12-21 04:20:56.753126) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-21 04:20:56.756379) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010104 build_url: 'https://queue.taskcluster.net/v1/task/7v4n-LbdT6OBl6uaBCTUdA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-21 04:20:56.806926) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 04:20:56.807256) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1450698325.214407-544971612 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004492 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 04:20:56.857001) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-21 04:20:56.857303) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-21 04:20:56.857605) ========= ========= Total master_lag: 0.24 =========