builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-064 starttime: 1450370678.14 results: success (0) buildid: 20151217072904 builduid: 409a3ae361344370bc3b958c10dd3dee revision: 0711218a018d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-17 08:44:38.135540) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-17 08:44:38.136095) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-17 08:44:38.136426) ========= 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=6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006319 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-17 08:44:38.164726) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-17 08:44:38.165134) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-17 08:44:38.186468) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 08:44:38.186745) ========= 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=6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004579 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 08:44:38.237885) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-17 08:44:38.238197) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-17 08:44:38.238536) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 08:44:38.238815) ========= 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=6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-17 08:44: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.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 226M=0s 2015-12-17 08:44:39 (226 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.168627 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 08:44:38.437913) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 08:44:38.438230) ========= 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=6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016966 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 08:44:38.491485) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 08:44:38.491806) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0711218a018d --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 0711218a018d --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=6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-17 08:44:39,273 Setting DEBUG logging. 2015-12-17 08:44:39,274 attempt 1/10 2015-12-17 08:44:39,274 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0711218a018d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-17 08:44:39,535 unpacking tar archive at: mozilla-central-0711218a018d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.569436 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 08:44:39.077139) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-17 08:44:39.077444) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-17 08:44:39.087657) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-17 08:44:39.087928) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-17 08:44:39.088351) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2015-12-17 08:44:39.088635) ========= /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=6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:44:39 INFO - MultiFileLogger online at 20151217 08:44:39 in /builds/slave/test 08:44:39 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 08:44:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:44:39 INFO - {'append_to_log': False, 08:44:39 INFO - 'base_work_dir': '/builds/slave/test', 08:44:39 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 08:44:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:44:39 INFO - 'branch': 'Firefox-Non-PGO', 08:44:39 INFO - 'buildbot_json_path': 'buildprops.json', 08:44:39 INFO - 'config_files': ('talos/linux_config.py',), 08:44:39 INFO - 'default_actions': ('clobber', 08:44:39 INFO - 'read-buildbot-config', 08:44:39 INFO - 'download-and-extract', 08:44:39 INFO - 'populate-webroot', 08:44:39 INFO - 'create-virtualenv', 08:44:39 INFO - 'install', 08:44:39 INFO - 'run-tests'), 08:44:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:44:39 INFO - 'download_minidump_stackwalk': True, 08:44:39 INFO - 'download_symbols': 'ondemand', 08:44:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:44:39 INFO - 'tooltool.py': '/tools/tooltool.py', 08:44:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:44:39 INFO - '/tools/misc-python/virtualenv.py')}, 08:44:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:44:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:44:39 INFO - 'installer_path': 'installer.exe', 08:44:39 INFO - 'log_level': 'info', 08:44:39 INFO - 'log_name': 'talos', 08:44:39 INFO - 'log_to_console': True, 08:44:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:44:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:44:39 INFO - 'opt_config_files': (), 08:44:39 INFO - 'pip_index': False, 08:44:39 INFO - 'sps_profile': False, 08:44:39 INFO - 'sps_profile_interval': 0, 08:44:39 INFO - 'suite': 'dromaeojs-e10s', 08:44:39 INFO - 'system_bits': '64', 08:44:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:44:39 INFO - 'title': 'talos-linux64-ix-064', 08:44:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:44:39 INFO - 'use_talos_json': True, 08:44:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:44:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:44:39 INFO - 'work_dir': 'build'} 08:44:39 INFO - ##### 08:44:39 INFO - ##### Running clobber step. 08:44:39 INFO - ##### 08:44:39 INFO - Running pre-action listener: _resource_record_pre_action 08:44:39 INFO - Running main action method: clobber 08:44:39 INFO - rmtree: /builds/slave/test/build 08:44:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:44:40 INFO - Running post-action listener: _resource_record_post_action 08:44:40 INFO - ##### 08:44:40 INFO - ##### Running read-buildbot-config step. 08:44:40 INFO - ##### 08:44:40 INFO - Running pre-action listener: _resource_record_pre_action 08:44:40 INFO - Running main action method: read_buildbot_config 08:44:40 INFO - Using buildbot properties: 08:44:40 INFO - { 08:44:40 INFO - "project": "", 08:44:40 INFO - "product": "firefox", 08:44:40 INFO - "script_repo_revision": "production", 08:44:40 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 08:44:40 INFO - "repository": "", 08:44:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 08:44:40 INFO - "buildid": "20151217072904", 08:44:40 INFO - "pgo_build": "False", 08:44:40 INFO - "basedir": "/builds/slave/test", 08:44:40 INFO - "buildnumber": 178, 08:44:40 INFO - "slavename": "talos-linux64-ix-064", 08:44:40 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:44:40 INFO - "platform": "ubuntu64_hw", 08:44:40 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 08:44:40 INFO - "branch": "mozilla-central", 08:44:40 INFO - "repo_path": "mozilla-central", 08:44:40 INFO - "revision": "0711218a018d", 08:44:40 INFO - "stage_platform": "linux64", 08:44:40 INFO - "builduid": "409a3ae361344370bc3b958c10dd3dee", 08:44:40 INFO - "slavebuilddir": "test" 08:44:40 INFO - } 08:44:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/GYPWd6pbSlChOmtEtjqrEQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 08:44:40 INFO - Running post-action listener: _resource_record_post_action 08:44:40 INFO - ##### 08:44:40 INFO - ##### Running download-and-extract step. 08:44:40 INFO - ##### 08:44:40 INFO - Running pre-action listener: _resource_record_pre_action 08:44:40 INFO - Running main action method: download_and_extract 08:44:40 INFO - mkdir: /builds/slave/test/build/tests 08:44: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')]} 08:44:40 INFO - https://queue.taskcluster.net/v1/task/GYPWd6pbSlChOmtEtjqrEQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 08:44:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYPWd6pbSlChOmtEtjqrEQ/artifacts/public/build/test_packages.json 08:44:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYPWd6pbSlChOmtEtjqrEQ/artifacts/public/build/test_packages.json 08:44:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYPWd6pbSlChOmtEtjqrEQ/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 08:44:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYPWd6pbSlChOmtEtjqrEQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 08:44:41 INFO - Downloaded 1302 bytes. 08:44:41 INFO - Reading from file /builds/slave/test/build/test_packages.json 08:44:41 INFO - Using the following test package requirements: 08:44:41 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 08:44:41 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 08:44:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:44:41 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 08:44:41 INFO - u'jsshell-linux-x86_64.zip'], 08:44:41 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 08:44:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:44:41 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 08:44:41 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 08:44:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:44:41 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 08:44:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:44:41 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 08:44:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:44:41 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 08:44:41 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 08:44:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:44:41 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:44:41 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')]} 08:44:41 INFO - https://queue.taskcluster.net/v1/task/GYPWd6pbSlChOmtEtjqrEQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:44:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYPWd6pbSlChOmtEtjqrEQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 08:44:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYPWd6pbSlChOmtEtjqrEQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 08:44:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYPWd6pbSlChOmtEtjqrEQ/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 08:44:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYPWd6pbSlChOmtEtjqrEQ/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 08:44:45 INFO - Downloaded 21870791 bytes. 08:44:45 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 08:44:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 08:44:45 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 08:44:46 INFO - Return code: 0 08:44:46 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 08:44:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:44:46 INFO - https://queue.taskcluster.net/v1/task/GYPWd6pbSlChOmtEtjqrEQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:44:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYPWd6pbSlChOmtEtjqrEQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 08:44:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYPWd6pbSlChOmtEtjqrEQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 08:44:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYPWd6pbSlChOmtEtjqrEQ/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 08:44:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYPWd6pbSlChOmtEtjqrEQ/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 08:44:48 INFO - Downloaded 21870791 bytes. 08:44:48 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 08:44:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 08:44:48 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 08:44:49 INFO - Return code: 0 08:44:49 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')]} 08:44:49 INFO - https://queue.taskcluster.net/v1/task/GYPWd6pbSlChOmtEtjqrEQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:44:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYPWd6pbSlChOmtEtjqrEQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 08:44:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYPWd6pbSlChOmtEtjqrEQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 08:44:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYPWd6pbSlChOmtEtjqrEQ/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 08:44:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYPWd6pbSlChOmtEtjqrEQ/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 08:44:50 INFO - Downloaded 11132039 bytes. 08:44:50 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 08:44:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 08:44:50 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 08:44:50 INFO - Return code: 0 08:44:50 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')]} 08:44:50 INFO - https://queue.taskcluster.net/v1/task/GYPWd6pbSlChOmtEtjqrEQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:44:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYPWd6pbSlChOmtEtjqrEQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 08:44:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYPWd6pbSlChOmtEtjqrEQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 08:44:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYPWd6pbSlChOmtEtjqrEQ/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 08:44:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYPWd6pbSlChOmtEtjqrEQ/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 08:44:52 INFO - Downloaded 53017916 bytes. 08:44:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GYPWd6pbSlChOmtEtjqrEQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 08:44:52 INFO - mkdir: /builds/slave/test/properties 08:44:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:44:52 INFO - Writing to file /builds/slave/test/properties/build_url 08:44:52 INFO - Contents: 08:44:52 INFO - build_url:https://queue.taskcluster.net/v1/task/GYPWd6pbSlChOmtEtjqrEQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 08:44:52 INFO - Running post-action listener: _resource_record_post_action 08:44:52 INFO - Running post-action listener: set_extra_try_arguments 08:44:52 INFO - ##### 08:44:52 INFO - ##### Running populate-webroot step. 08:44:52 INFO - ##### 08:44:52 INFO - Running pre-action listener: _resource_record_pre_action 08:44:52 INFO - Running main action method: populate_webroot 08:44:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:44:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:44:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:44:52 INFO - '--tppagecycles', 08:44:52 INFO - '7'], 08:44:52 INFO - 'tests': ['tsvgm']}}, 08:44:52 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 08:44:52 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 08:44:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:44:52 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:44:52 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 08:44:52 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:44:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:44:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:52 INFO - 'talos_options': ['--e10s'], 08:44:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:44:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:52 INFO - 'tests': ['damp', 'tps']}, 08:44:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:52 INFO - 'talos_options': ['--e10s'], 08:44:52 INFO - 'tests': ['damp', 'tps']}, 08:44:52 INFO - 'g3': {'tests': ['dromaeo_dom']}, 08:44:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:44:52 INFO - 'other': {'tests': ['a11yr', 08:44:52 INFO - 'ts_paint', 08:44:52 INFO - 'tpaint', 08:44:52 INFO - 'sessionrestore', 08:44:52 INFO - 'sessionrestore_no_auto_restore']}, 08:44:52 INFO - 'other-e10s': {'talos_options': ['--e10s'], 08:44:52 INFO - 'tests': ['a11yr', 08:44:52 INFO - 'ts_paint', 08:44:52 INFO - 'tpaint', 08:44:52 INFO - 'sessionrestore', 08:44:52 INFO - 'sessionrestore_no_auto_restore']}, 08:44:52 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 08:44:52 INFO - 'tests': ['a11yr', 08:44:52 INFO - 'ts_paint', 08:44:52 INFO - 'tpaint', 08:44:52 INFO - 'sessionrestore', 08:44:52 INFO - 'sessionrestore_no_auto_restore']}, 08:44:52 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 08:44:52 INFO - 'tests': ['a11yr', 08:44:52 INFO - 'ts_paint', 08:44:52 INFO - 'tpaint', 08:44:52 INFO - 'sessionrestore', 08:44:52 INFO - 'sessionrestore_no_auto_restore']}, 08:44:52 INFO - 'other_l64': {'tests': ['a11yr', 08:44:52 INFO - 'ts_paint', 08:44:52 INFO - 'tpaint', 08:44:52 INFO - 'sessionrestore', 08:44:52 INFO - 'sessionrestore_no_auto_restore']}, 08:44:52 INFO - 'other_nol64': {'tests': ['a11yr', 08:44:52 INFO - 'ts_paint', 08:44:52 INFO - 'tpaint', 08:44:52 INFO - 'sessionrestore', 08:44:52 INFO - 'sessionrestore_no_auto_restore']}, 08:44:52 INFO - 'svgr': {'tests': ['tsvgx', 08:44:52 INFO - 'tsvgr_opacity', 08:44:52 INFO - 'tart', 08:44:52 INFO - 'tscrollx', 08:44:52 INFO - 'cart']}, 08:44:52 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 08:44:52 INFO - 'tests': ['tsvgx', 08:44:52 INFO - 'tsvgr_opacity', 08:44:52 INFO - 'tart', 08:44:52 INFO - 'tscrollx', 08:44:52 INFO - 'cart']}, 08:44:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:52 INFO - 'tests': ['tp5o']}, 08:44:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:52 INFO - 'talos_options': ['--e10s'], 08:44:52 INFO - 'tests': ['tp5o']}, 08:44:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:52 INFO - 'talos_options': ['--xperf_path', 08:44:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:44:52 INFO - 'tests': ['tp5n']}, 08:44:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:44:52 INFO - 'talos_options': ['--e10s', 08:44:52 INFO - '--xperf_path', 08:44:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:44:52 INFO - 'tests': ['tp5n']}}, 08:44:52 INFO - 'talos.zip': {'path': '', 08:44:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:44:52 INFO - Running post-action listener: _resource_record_post_action 08:44:52 INFO - ##### 08:44:52 INFO - ##### Running create-virtualenv step. 08:44:52 INFO - ##### 08:44:52 INFO - Running pre-action listener: _resource_record_pre_action 08:44:52 INFO - Running main action method: create_virtualenv 08:44:52 INFO - Creating virtualenv /builds/slave/test/build/venv 08:44:52 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 08:44:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:44:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:44:52 INFO - Using real prefix '/usr' 08:44:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:44:52 INFO - Installing distribute.............................................................................................................................................................................................done. 08:44:54 INFO - Installing pip.................done. 08:44:54 INFO - Return code: 0 08:44:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:44: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')]} 08:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44: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')]} 08:44:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:54 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 0x7f84d865bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260e0b0>, '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 0x260efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x260f440>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637', '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 08:44:54 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 08:44:54 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 08:44:54 INFO - Using env: {'DISPLAY': ':0', 08:44:54 INFO - 'HOME': '/home/cltbld', 08:44:54 INFO - 'LANG': 'en_US.UTF-8', 08:44:54 INFO - 'LANGUAGE': 'en_US:en', 08:44:54 INFO - 'LOGNAME': 'cltbld', 08:44:54 INFO - 'MAIL': '/var/mail/cltbld', 08:44:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:54 INFO - 'MOZ_NO_REMOTE': '1', 08:44:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:54 INFO - 'NO_EM_RESTART': '1', 08:44:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:54 INFO - 'PWD': '/builds/slave/test', 08:44:54 INFO - 'SHELL': '/bin/bash', 08:44:54 INFO - 'SHLVL': '1', 08:44:54 INFO - 'TERM': 'linux', 08:44:54 INFO - 'TMOUT': '86400', 08:44:54 INFO - 'USER': 'cltbld', 08:44:54 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637', 08:44:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:54 INFO - '_': '/tools/buildbot/bin/python'} 08:44:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:54 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 08:44:54 INFO - Cleaning up... 08:44:54 INFO - Return code: 0 08:44:54 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 08:44: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')]} 08:44:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44: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')]} 08:44:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:54 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 0x7f84d865bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260e0b0>, '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 0x260efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x260f440>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637', '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 08:44:54 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 08:44:54 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 08:44:54 INFO - Using env: {'DISPLAY': ':0', 08:44:54 INFO - 'HOME': '/home/cltbld', 08:44:54 INFO - 'LANG': 'en_US.UTF-8', 08:44:54 INFO - 'LANGUAGE': 'en_US:en', 08:44:54 INFO - 'LOGNAME': 'cltbld', 08:44:54 INFO - 'MAIL': '/var/mail/cltbld', 08:44:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:54 INFO - 'MOZ_NO_REMOTE': '1', 08:44:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:54 INFO - 'NO_EM_RESTART': '1', 08:44:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:54 INFO - 'PWD': '/builds/slave/test', 08:44:54 INFO - 'SHELL': '/bin/bash', 08:44:54 INFO - 'SHLVL': '1', 08:44:54 INFO - 'TERM': 'linux', 08:44:54 INFO - 'TMOUT': '86400', 08:44:54 INFO - 'USER': 'cltbld', 08:44:54 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637', 08:44:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:54 INFO - '_': '/tools/buildbot/bin/python'} 08:44:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:55 INFO - Downloading/unpacking psutil>=0.7.1 08:44:55 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 08:44:55 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 08:44:55 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 08:44:55 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 08:44:55 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 08:44:55 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 08:44:56 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 08:44:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 08:44:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:44:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:44:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:44:56 INFO - Installing collected packages: psutil 08:44:56 INFO - Running setup.py install for psutil 08:44:56 INFO - building 'psutil._psutil_linux' extension 08:44:56 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 08:44:57 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 08:44:57 INFO - building 'psutil._psutil_posix' extension 08:44:57 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 08:44:57 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 08:44:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:44:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:44:57 INFO - Successfully installed psutil 08:44:57 INFO - Cleaning up... 08:44:57 INFO - Return code: 0 08:44:57 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 08:44:57 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')]} 08:44:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:57 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')]} 08:44:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:57 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 0x7f84d865bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260e0b0>, '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 0x260efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x260f440>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637', '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 08:44:57 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 08:44:57 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 08:44:57 INFO - Using env: {'DISPLAY': ':0', 08:44:57 INFO - 'HOME': '/home/cltbld', 08:44:57 INFO - 'LANG': 'en_US.UTF-8', 08:44:57 INFO - 'LANGUAGE': 'en_US:en', 08:44:57 INFO - 'LOGNAME': 'cltbld', 08:44:57 INFO - 'MAIL': '/var/mail/cltbld', 08:44:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:57 INFO - 'MOZ_NO_REMOTE': '1', 08:44:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:57 INFO - 'NO_EM_RESTART': '1', 08:44:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:57 INFO - 'PWD': '/builds/slave/test', 08:44:57 INFO - 'SHELL': '/bin/bash', 08:44:57 INFO - 'SHLVL': '1', 08:44:57 INFO - 'TERM': 'linux', 08:44:57 INFO - 'TMOUT': '86400', 08:44:57 INFO - 'USER': 'cltbld', 08:44:57 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637', 08:44:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:57 INFO - '_': '/tools/buildbot/bin/python'} 08:44:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:57 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:44:57 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 08:44:57 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 08:44:57 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 08:44:57 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 08:44:57 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 08:44:57 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 08:44:59 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:44:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 08:44:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:44:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:44:59 INFO - Installing collected packages: mozsystemmonitor 08:44:59 INFO - Running setup.py install for mozsystemmonitor 08:44:59 INFO - Successfully installed mozsystemmonitor 08:44:59 INFO - Cleaning up... 08:44:59 INFO - Return code: 0 08:44:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:44:59 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')]} 08:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:59 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')]} 08:44:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:44:59 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 0x7f84d865bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260e0b0>, '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 0x260efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x260f440>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637', '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 08:44:59 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 08:44:59 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 08:44:59 INFO - Using env: {'DISPLAY': ':0', 08:44:59 INFO - 'HOME': '/home/cltbld', 08:44:59 INFO - 'LANG': 'en_US.UTF-8', 08:44:59 INFO - 'LANGUAGE': 'en_US:en', 08:44:59 INFO - 'LOGNAME': 'cltbld', 08:44:59 INFO - 'MAIL': '/var/mail/cltbld', 08:44:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:59 INFO - 'MOZ_NO_REMOTE': '1', 08:44:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:44:59 INFO - 'NO_EM_RESTART': '1', 08:44:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:44:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:59 INFO - 'PWD': '/builds/slave/test', 08:44:59 INFO - 'SHELL': '/bin/bash', 08:44:59 INFO - 'SHLVL': '1', 08:44:59 INFO - 'TERM': 'linux', 08:44:59 INFO - 'TMOUT': '86400', 08:44:59 INFO - 'USER': 'cltbld', 08:44:59 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637', 08:44:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:59 INFO - '_': '/tools/buildbot/bin/python'} 08:44:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:59 INFO - Downloading/unpacking blobuploader==1.2.4 08:44:59 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 08:44:59 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 08:44:59 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 08:44:59 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 08:44:59 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 08:44:59 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 08:45:01 INFO - Downloading blobuploader-1.2.4.tar.gz 08:45:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 08:45:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:45:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:45:01 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 08:45:01 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 08:45:01 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 08:45:01 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 08:45:01 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 08:45:01 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 08:45:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 08:45:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:45:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:45:01 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 08:45:01 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 08:45:01 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 08:45:01 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 08:45:01 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 08:45:01 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 08:45:02 INFO - Downloading docopt-0.6.1.tar.gz 08:45:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 08:45:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:45:02 INFO - Installing collected packages: blobuploader, requests, docopt 08:45:02 INFO - Running setup.py install for blobuploader 08:45:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:45:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:45:02 INFO - Running setup.py install for requests 08:45:02 INFO - Running setup.py install for docopt 08:45:02 INFO - Successfully installed blobuploader requests docopt 08:45:02 INFO - Cleaning up... 08:45:02 INFO - Return code: 0 08:45:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:45: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')]} 08:45:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45: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')]} 08:45:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:02 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 0x7f84d865bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260e0b0>, '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 0x260efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x260f440>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637', '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 08:45:02 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 08:45:02 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 08:45:02 INFO - Using env: {'DISPLAY': ':0', 08:45:02 INFO - 'HOME': '/home/cltbld', 08:45:02 INFO - 'LANG': 'en_US.UTF-8', 08:45:02 INFO - 'LANGUAGE': 'en_US:en', 08:45:02 INFO - 'LOGNAME': 'cltbld', 08:45:02 INFO - 'MAIL': '/var/mail/cltbld', 08:45:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:02 INFO - 'MOZ_NO_REMOTE': '1', 08:45:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:02 INFO - 'NO_EM_RESTART': '1', 08:45:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:02 INFO - 'PWD': '/builds/slave/test', 08:45:02 INFO - 'SHELL': '/bin/bash', 08:45:02 INFO - 'SHLVL': '1', 08:45:02 INFO - 'TERM': 'linux', 08:45:02 INFO - 'TMOUT': '86400', 08:45:02 INFO - 'USER': 'cltbld', 08:45:02 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637', 08:45:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:02 INFO - '_': '/tools/buildbot/bin/python'} 08:45:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:45:02 INFO - Running setup.py (path:/tmp/pip-U2_lN5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:45:03 INFO - Running setup.py (path:/tmp/pip-xMLckP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:45:03 INFO - Running setup.py (path:/tmp/pip-8z9gFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:45:03 INFO - Running setup.py (path:/tmp/pip-tGXtAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:45:03 INFO - Running setup.py (path:/tmp/pip-3WcEyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:45:03 INFO - Running setup.py (path:/tmp/pip-8iSa0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:45:03 INFO - Running setup.py (path:/tmp/pip-8XTeq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:45:03 INFO - Running setup.py (path:/tmp/pip-5ZQeS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:45:03 INFO - Running setup.py (path:/tmp/pip-zZti0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:45:03 INFO - Running setup.py (path:/tmp/pip-ScwNe7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:45:03 INFO - Running setup.py (path:/tmp/pip-sDnUZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:45:03 INFO - Running setup.py (path:/tmp/pip-MuNK2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:45:03 INFO - Running setup.py (path:/tmp/pip-tO0GTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:45:03 INFO - Running setup.py (path:/tmp/pip-KNg2SM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:45:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:03 INFO - Running setup.py (path:/tmp/pip-AxC82u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:45:04 INFO - Running setup.py (path:/tmp/pip-un_cKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:45:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:45:04 INFO - Running setup.py (path:/tmp/pip-P5fKse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:45:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:45:04 INFO - Running setup.py install for manifestparser 08:45:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:45:04 INFO - Running setup.py install for mozcrash 08:45:04 INFO - Running setup.py install for mozdebug 08:45:04 INFO - Running setup.py install for mozdevice 08:45:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:45:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:45:04 INFO - Running setup.py install for mozfile 08:45:04 INFO - Running setup.py install for mozhttpd 08:45:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:45:04 INFO - Running setup.py install for mozinfo 08:45:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:45:04 INFO - Running setup.py install for mozInstall 08:45:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:45:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:45:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:45:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:45:04 INFO - Running setup.py install for mozleak 08:45:05 INFO - Running setup.py install for mozlog 08:45:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:45:05 INFO - Running setup.py install for moznetwork 08:45:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:45:05 INFO - Running setup.py install for mozprocess 08:45:05 INFO - Running setup.py install for mozprofile 08:45:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:45:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:45:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:45:05 INFO - Running setup.py install for mozrunner 08:45:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:45:05 INFO - Running setup.py install for mozscreenshot 08:45:05 INFO - Running setup.py install for moztest 08:45:05 INFO - Running setup.py install for mozversion 08:45:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:45:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:45:05 INFO - Cleaning up... 08:45:05 INFO - Return code: 0 08:45:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:45:05 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')]} 08:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:05 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')]} 08:45:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:05 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 0x7f84d865bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260e0b0>, '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 0x260efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x260f440>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637', '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 08:45:05 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 08:45:05 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 08:45:05 INFO - Using env: {'DISPLAY': ':0', 08:45:05 INFO - 'HOME': '/home/cltbld', 08:45:05 INFO - 'LANG': 'en_US.UTF-8', 08:45:05 INFO - 'LANGUAGE': 'en_US:en', 08:45:05 INFO - 'LOGNAME': 'cltbld', 08:45:05 INFO - 'MAIL': '/var/mail/cltbld', 08:45:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:05 INFO - 'MOZ_NO_REMOTE': '1', 08:45:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:05 INFO - 'NO_EM_RESTART': '1', 08:45:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:05 INFO - 'PWD': '/builds/slave/test', 08:45:05 INFO - 'SHELL': '/bin/bash', 08:45:05 INFO - 'SHLVL': '1', 08:45:05 INFO - 'TERM': 'linux', 08:45:05 INFO - 'TMOUT': '86400', 08:45:05 INFO - 'USER': 'cltbld', 08:45:05 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637', 08:45:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:05 INFO - '_': '/tools/buildbot/bin/python'} 08:45:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:45:06 INFO - Running setup.py (path:/tmp/pip-CYFoNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:45:06 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)) 08:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:45:06 INFO - Running setup.py (path:/tmp/pip-wkMwIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:45:06 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)) 08:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:45:06 INFO - Running setup.py (path:/tmp/pip-jEPWx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:45:06 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)) 08:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:45:06 INFO - Running setup.py (path:/tmp/pip-fGDfBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:45:06 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)) 08:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:45:06 INFO - Running setup.py (path:/tmp/pip-yYiZ49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:45:06 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)) 08:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:45:06 INFO - Running setup.py (path:/tmp/pip-4AZdNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:45:06 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)) 08:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:45:06 INFO - Running setup.py (path:/tmp/pip-Z72Upz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:45:06 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)) 08:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:45:06 INFO - Running setup.py (path:/tmp/pip-6ZpWZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:45:06 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)) 08:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:45:06 INFO - Running setup.py (path:/tmp/pip-Jn6EUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:45:06 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)) 08:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:45:06 INFO - Running setup.py (path:/tmp/pip-x0iju8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:45:06 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)) 08:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:45:06 INFO - Running setup.py (path:/tmp/pip-o8gmV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:45:06 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)) 08:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:45:06 INFO - Running setup.py (path:/tmp/pip-jPF7X1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:45:07 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)) 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:45:07 INFO - Running setup.py (path:/tmp/pip-OnAhn8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:45:07 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)) 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:45:07 INFO - Running setup.py (path:/tmp/pip-XcLlLR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:45:07 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)) 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:07 INFO - Running setup.py (path:/tmp/pip-YChXtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:07 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)) 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:45:07 INFO - Running setup.py (path:/tmp/pip-l74gNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:45:07 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)) 08:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:45:07 INFO - Running setup.py (path:/tmp/pip-MsUPpq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:45:07 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)) 08:45:07 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)) 08:45:07 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)) 08:45:07 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)) 08:45:07 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)) 08:45:07 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)) 08:45:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:45: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 08:45: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 08:45: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 08:45: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 08:45: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 08:45: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 08:45:09 INFO - Downloading blessings-1.5.1.tar.gz 08:45:09 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 08:45:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:45:09 INFO - Installing collected packages: blessings 08:45:09 INFO - Running setup.py install for blessings 08:45:09 INFO - Successfully installed blessings 08:45:09 INFO - Cleaning up... 08:45:09 INFO - Return code: 0 08:45:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:45:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:45:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:45:09 INFO - Reading from file tmpfile_stdout 08:45:09 INFO - Current package versions: 08:45:09 INFO - argparse == 1.2.1 08:45:09 INFO - blessings == 1.5.1 08:45:09 INFO - blobuploader == 1.2.4 08:45:09 INFO - docopt == 0.6.1 08:45:09 INFO - manifestparser == 1.1 08:45:09 INFO - mozInstall == 1.12 08:45:09 INFO - mozcrash == 0.16 08:45:09 INFO - mozdebug == 0.1 08:45:09 INFO - mozdevice == 0.47 08:45:09 INFO - mozfile == 1.2 08:45:09 INFO - mozhttpd == 0.7 08:45:09 INFO - mozinfo == 0.9 08:45:09 INFO - mozleak == 0.1 08:45:09 INFO - mozlog == 3.1 08:45:09 INFO - moznetwork == 0.27 08:45:09 INFO - mozprocess == 0.22 08:45:09 INFO - mozprofile == 0.27 08:45:09 INFO - mozrunner == 6.11 08:45:09 INFO - mozscreenshot == 0.1 08:45:09 INFO - mozsystemmonitor == 0.0 08:45:09 INFO - moztest == 0.7 08:45:09 INFO - mozversion == 1.4 08:45:09 INFO - psutil == 3.1.1 08:45:09 INFO - requests == 1.2.3 08:45:09 INFO - wsgiref == 0.1.2 08:45:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:45:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:09 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 0x7f84d865bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260e0b0>, '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 0x260efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x260f440>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637', '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 08:45:09 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 08:45:09 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 08:45:09 INFO - Using env: {'DISPLAY': ':0', 08:45:09 INFO - 'HOME': '/home/cltbld', 08:45:09 INFO - 'LANG': 'en_US.UTF-8', 08:45:09 INFO - 'LANGUAGE': 'en_US:en', 08:45:09 INFO - 'LOGNAME': 'cltbld', 08:45:09 INFO - 'MAIL': '/var/mail/cltbld', 08:45:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:09 INFO - 'MOZ_NO_REMOTE': '1', 08:45:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:09 INFO - 'NO_EM_RESTART': '1', 08:45:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:09 INFO - 'PWD': '/builds/slave/test', 08:45:09 INFO - 'SHELL': '/bin/bash', 08:45:09 INFO - 'SHLVL': '1', 08:45:09 INFO - 'TERM': 'linux', 08:45:09 INFO - 'TMOUT': '86400', 08:45:09 INFO - 'USER': 'cltbld', 08:45:09 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637', 08:45:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:09 INFO - '_': '/tools/buildbot/bin/python'} 08:45:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:09 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)) 08:45:09 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)) 08:45:09 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)) 08:45:09 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)) 08:45:09 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)) 08:45:09 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)) 08:45:09 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)) 08:45:09 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)) 08:45:09 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)) 08:45:09 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)) 08:45:09 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)) 08:45:09 INFO - Cleaning up... 08:45:09 INFO - Return code: 0 08:45:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:45:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:09 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 0x7f84d865bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260e0b0>, '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 0x260efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x260f440>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637', '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 08:45:09 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 08:45:09 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 08:45:09 INFO - Using env: {'DISPLAY': ':0', 08:45:09 INFO - 'HOME': '/home/cltbld', 08:45:09 INFO - 'LANG': 'en_US.UTF-8', 08:45:09 INFO - 'LANGUAGE': 'en_US:en', 08:45:09 INFO - 'LOGNAME': 'cltbld', 08:45:09 INFO - 'MAIL': '/var/mail/cltbld', 08:45:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:09 INFO - 'MOZ_NO_REMOTE': '1', 08:45:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:09 INFO - 'NO_EM_RESTART': '1', 08:45:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:09 INFO - 'PWD': '/builds/slave/test', 08:45:09 INFO - 'SHELL': '/bin/bash', 08:45:09 INFO - 'SHLVL': '1', 08:45:09 INFO - 'TERM': 'linux', 08:45:09 INFO - 'TMOUT': '86400', 08:45:09 INFO - 'USER': 'cltbld', 08:45:09 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637', 08:45:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:09 INFO - '_': '/tools/buildbot/bin/python'} 08:45:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:09 INFO - Downloading/unpacking jsonschema 08:45: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 08:45: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 08:45: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 08:45: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 08:45: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 08:45: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 08:45:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:45:11 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 08:45:11 INFO - Downloading/unpacking functools32 (from jsonschema) 08:45:11 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 08:45:11 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 08:45:11 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 08:45:11 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 08:45:11 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 08:45:11 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 08:45:11 INFO - Downloading functools32-3.2.3-2.tar.gz 08:45:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 08:45:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:45:11 INFO - warning: no files found matching '*.txt' 08:45:11 INFO - no previously-included directories found matching 'build' 08:45:11 INFO - no previously-included directories found matching 'dist' 08:45:11 INFO - no previously-included directories found matching '.git*' 08:45:11 INFO - Installing collected packages: jsonschema, functools32 08:45:11 INFO - Running setup.py install for functools32 08:45:11 INFO - warning: no files found matching '*.txt' 08:45:12 INFO - no previously-included directories found matching 'build' 08:45:12 INFO - no previously-included directories found matching 'dist' 08:45:12 INFO - no previously-included directories found matching '.git*' 08:45:12 INFO - Successfully installed jsonschema functools32 08:45:12 INFO - Cleaning up... 08:45:12 INFO - Return code: 0 08:45:12 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:45:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:45:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:45:12 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 0x7f84d865bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257f240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260e0b0>, '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 0x260efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x260f440>, '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': '6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637', '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 08:45:12 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 08:45:12 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 08:45:12 INFO - Using env: {'DISPLAY': ':0', 08:45:12 INFO - 'HOME': '/home/cltbld', 08:45:12 INFO - 'LANG': 'en_US.UTF-8', 08:45:12 INFO - 'LANGUAGE': 'en_US:en', 08:45:12 INFO - 'LOGNAME': 'cltbld', 08:45:12 INFO - 'MAIL': '/var/mail/cltbld', 08:45:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:12 INFO - 'MOZ_NO_REMOTE': '1', 08:45:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:12 INFO - 'NO_EM_RESTART': '1', 08:45:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:12 INFO - 'PWD': '/builds/slave/test', 08:45:12 INFO - 'SHELL': '/bin/bash', 08:45:12 INFO - 'SHLVL': '1', 08:45:12 INFO - 'TERM': 'linux', 08:45:12 INFO - 'TMOUT': '86400', 08:45:12 INFO - 'USER': 'cltbld', 08:45:12 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637', 08:45:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:12 INFO - '_': '/tools/buildbot/bin/python'} 08:45:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:12 INFO - Downloading/unpacking flake8 08:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:45:13 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 08:45:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:45:13 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 08:45:13 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 08:45:13 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 08:45:13 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 08:45:13 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 08:45:13 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 08:45:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:45:14 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 08:45:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:45: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 08:45: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 08:45: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 08:45: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 08:45: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 08:45: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 08:45:14 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 08:45:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:45: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 08:45: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 08:45: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 08:45: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 08:45: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 08:45: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 08:45:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 08:45:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:45:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:45:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:45:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:45:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:45:14 INFO - no previously-included directories found matching 'docs/_build' 08:45:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:45:14 INFO - Running setup.py install for pep8 08:45:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:45:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:45:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:45:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:45:14 INFO - no previously-included directories found matching 'docs/_build' 08:45:14 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:45:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:45:14 INFO - Cleaning up... 08:45:14 INFO - Return code: 0 08:45:14 INFO - Running post-action listener: _resource_record_post_action 08:45:14 INFO - Running post-action listener: _start_resource_monitoring 08:45:14 INFO - Starting resource monitoring. 08:45:14 INFO - ##### 08:45:14 INFO - ##### Running install step. 08:45:14 INFO - ##### 08:45:14 INFO - Running pre-action listener: _resource_record_pre_action 08:45:14 INFO - Running main action method: install 08:45:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:45:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:45:15 INFO - Reading from file tmpfile_stdout 08:45:15 INFO - Detecting whether we're running mozinstall >=1.0... 08:45:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:45:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:45:15 INFO - Reading from file tmpfile_stdout 08:45:15 INFO - Output received: 08:45:15 INFO - Usage: mozinstall [options] installer 08:45:15 INFO - Options: 08:45:15 INFO - -h, --help show this help message and exit 08:45:15 INFO - -d DEST, --destination=DEST 08:45:15 INFO - Directory to install application into. [default: 08:45:15 INFO - "/builds/slave/test"] 08:45:15 INFO - --app=APP Application being installed. [default: firefox] 08:45:15 INFO - mkdir: /builds/slave/test/build/application 08:45:15 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'] 08:45:15 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 08:45:30 INFO - Reading from file tmpfile_stdout 08:45:30 INFO - Output received: 08:45:30 INFO - /builds/slave/test/build/application/firefox/firefox 08:45:30 INFO - Running post-action listener: _resource_record_post_action 08:45:30 INFO - ##### 08:45:30 INFO - ##### Running run-tests step. 08:45:30 INFO - ##### 08:45:30 INFO - Running pre-action listener: _resource_record_pre_action 08:45:30 INFO - Running main action method: run_tests 08:45:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:45:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:45:30 INFO - Python 2.7.3 08:45:30 INFO - Return code: 0 08:45:30 INFO - grabbing minidump binary from tooltool 08:45:30 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')]} 08:45:30 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 0x260e0b0>, '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 0x260efb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x260f440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:45:30 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 08:45:30 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 08:45:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:45:30 INFO - Return code: 0 08:45:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:45:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:45:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:45:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:45:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:45:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:45:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:45:31 INFO - Return code: 0 08:45:31 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/GYPWd6pbSlChOmtEtjqrEQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--webServer', 'localhost'] in /builds/slave/test/build 08:45:31 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/GYPWd6pbSlChOmtEtjqrEQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-064 --webServer localhost 08:45:31 INFO - Using env: {'DISPLAY': ':0', 08:45:31 INFO - 'HOME': '/home/cltbld', 08:45:31 INFO - 'LANG': 'en_US.UTF-8', 08:45:31 INFO - 'LANGUAGE': 'en_US:en', 08:45:31 INFO - 'LOGNAME': 'cltbld', 08:45:31 INFO - 'MAIL': '/var/mail/cltbld', 08:45:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:45:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:45:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:31 INFO - 'MOZ_NO_REMOTE': '1', 08:45:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:45:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:45:31 INFO - 'NO_EM_RESTART': '1', 08:45:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:45:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:31 INFO - 'PWD': '/builds/slave/test', 08:45:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:45:31 INFO - 'SHELL': '/bin/bash', 08:45:31 INFO - 'SHLVL': '1', 08:45:31 INFO - 'TERM': 'linux', 08:45:31 INFO - 'TMOUT': '86400', 08:45:31 INFO - 'USER': 'cltbld', 08:45:31 INFO - 'XDG_SESSION_COOKIE': '6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637', 08:45:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:31 INFO - '_': '/tools/buildbot/bin/python'} 08:45:31 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/GYPWd6pbSlChOmtEtjqrEQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--webServer', 'localhost'] with output_timeout 3600 08:45:31 INFO - mozversion INFO | application_buildid: 20151217072904 08:45:31 INFO - mozversion INFO | application_changeset: 0711218a018d912036f7d3be2ae2649e213cfb85 08:45:31 INFO - mozversion INFO | application_display_name: Nightly 08:45:31 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:45:31 INFO - mozversion INFO | application_name: Firefox 08:45:31 INFO - mozversion INFO | application_remotingname: firefox 08:45:31 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 08:45:31 INFO - mozversion INFO | application_vendor: Mozilla 08:45:31 INFO - mozversion INFO | application_version: 46.0a1 08:45:31 INFO - mozversion INFO | platform_buildid: 20151217072904 08:45:31 INFO - mozversion INFO | platform_changeset: 0711218a018d912036f7d3be2ae2649e213cfb85 08:45:31 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 08:45:31 INFO - mozversion INFO | platform_version: 46.0a1 08:45:31 INFO - 2015-12-17 08:45:31,762 DEBUG : using testdate: 1450370731 08:45:31 INFO - 2015-12-17 08:45:31,762 DEBUG : actual date: 1450370731 08:45:31 INFO - 2015-12-17 08:45:31,842 INFO : starting webserver on 'localhost:42524' 08:45:31 INFO - 2015-12-17 08:45:31,843 INFO : Starting test suite talos-linux64-ix-064.e 08:45:31 INFO - 2015-12-17 08:45:31,844 INFO : Starting test dromaeo_css 08:45:31 INFO - 2015-12-17 08:45:31,844 DEBUG : operating with platform_type : linux_ 08:45:31 INFO - 2015-12-17 08:45:31,844 INFO : Initialising browser for dromaeo_css test... 08:45:31 INFO - 2015-12-17 08:45:31,861 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNKWATP/profile http://localhost:42524/getInfo.html 08:45:36 INFO - 2015-12-17 08:45:36,535 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:45:36 INFO - 2015-12-17 08:45:36,535 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:45:36 INFO - 2015-12-17 08:45:36,535 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 08:45:36 INFO - 2015-12-17 08:45:36,536 DEBUG : BROWSER_OUTPUT: __metrics 08:45:36 INFO - 2015-12-17 08:45:36,652 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] 08:45:37 INFO - 2015-12-17 08:45:37,540 INFO : Browser initialized. 08:45:37 INFO - 2015-12-17 08:45:37,541 INFO : Running cycle 1/1 for dromaeo_css test... 08:45:37 INFO - 2015-12-17 08:45:37,541 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNKWATP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:45:49 INFO - 2015-12-17 08:45:49,513 DEBUG : BROWSER_OUTPUT: RSS: Main: 151416832 08:45:49 INFO - 2015-12-17 08:45:49,514 DEBUG : BROWSER_OUTPUT: 08:46:39 INFO - 2015-12-17 08:46:39,131 DEBUG : BROWSER_OUTPUT: 1450370799123 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] 08:48:51 INFO - 2015-12-17 08:48:51,350 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42524/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42524/tests/dromaeo/cssquery-ext.html) 08:48:52 INFO - 2015-12-17 08:48:52,320 DEBUG : BROWSER_OUTPUT: RSS: Main: 155901952 08:48:52 INFO - 2015-12-17 08:48:52,320 DEBUG : BROWSER_OUTPUT: 08:51:54 INFO - 2015-12-17 08:51:54,237 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42524/tests/dromaeo/cssquery-ext.html (next: http://localhost:42524/tests/dromaeo/cssquery-jquery.html) 08:51:54 INFO - 2015-12-17 08:51:54,831 DEBUG : BROWSER_OUTPUT: RSS: Main: 152666112 08:51:54 INFO - 2015-12-17 08:51:54,831 DEBUG : BROWSER_OUTPUT: 08:54:56 INFO - 2015-12-17 08:54:56,660 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42524/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42524/tests/dromaeo/cssquery-mootools.html) 08:54:57 INFO - 2015-12-17 08:54:57,261 DEBUG : BROWSER_OUTPUT: RSS: Main: 154615808 08:54:57 INFO - 2015-12-17 08:54:57,261 DEBUG : BROWSER_OUTPUT: 08:57:59 INFO - 2015-12-17 08:57:59,057 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42524/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42524/tests/dromaeo/cssquery-prototype.html) 08:57:59 INFO - 2015-12-17 08:57:59,659 DEBUG : BROWSER_OUTPUT: RSS: Main: 152068096 08:57:59 INFO - 2015-12-17 08:57:59,659 DEBUG : BROWSER_OUTPUT: 09:01:02 INFO - 2015-12-17 09:01:02,363 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42524/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42524/tests/dromaeo/cssquery-yui.html) 09:01:02 INFO - 2015-12-17 09:01:02,989 DEBUG : BROWSER_OUTPUT: RSS: Main: 152477696 09:01:02 INFO - 2015-12-17 09:01:02,989 DEBUG : BROWSER_OUTPUT: 09:04:05 INFO - 2015-12-17 09:04:05,348 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42524/tests/dromaeo/cssquery-yui.html (next: http://localhost:42524/tests/dromaeo/cssquery-dojo.html) 09:04:05 INFO - 2015-12-17 09:04:05,599 DEBUG : BROWSER_OUTPUT: RSS: Main: 155680768 09:04:05 INFO - 2015-12-17 09:04:05,599 DEBUG : BROWSER_OUTPUT: 09:04:05 INFO - 2015-12-17 09:04:05,600 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:04:05 INFO - 2015-12-17 09:04:05,600 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:04:05 INFO - 2015-12-17 09:04:05,600 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:04:05 INFO - 2015-12-17 09:04:05,601 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:04:05 INFO - 2015-12-17 09:04:05,604 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2656.1765852585695;2683.61087642292;2714.0636480414264;2727.809053366264;2732.0164740693353;4585.060062687149;4602.240630296002;4621.445426548815;4644.674872758904;4669.066186762644;4689.718616882989;4710.3405523226775;4715.104130215257;4784.5693887550115;4807.735574543399;4325.264705000158;4354.825806967726;4498.077894031732;4499.572540608637;4531.6601254905845;4239.448871646681;4405.581469760367;4435.933460998088;4465.955340446587;4536.432945881765;4258.5954334338185;4319.244132276839;4323.3947247385395;4343.84796532123;4349.521552629209;4335.9566404335865;4346.56534346566;4366.519682834885;4368.825246990148;4369.978150109229;4158.688098392633;4268.59448352406;4321.6326612238;4340.696151269412;4379.8467053653285;4581.473130589991;4627.791749371287;4631.884202894921;4654.976725116387;4668.719876807402;5128.307678463397;5159.942211846563;5160.48395160481;5174.508421699932;5181.922271165936;4947.604191664658;5088.567471764904;5102.183650615883;5187.247849061865;5266.078436273675;4713.1280713067845;4787.042591481648;4812.8796780080775;4820.373351464287;4820.493848145929;5280.6039547034125;5295.179247216687;5303.257543943774;5325;5387.892242155213;4821.228603423436;5091.185410334403;5099.923501147486;5111.514406131411;5118.053160165381;4977.129002424634;5024.723640199824;5059.671121377172;5070.847874563769;5113.795448182105;5052.292679024939;5058.974705126524;5060.342155519759;5065.949340506621;5101.362329708786;4734.786934587952;4857.781399837016;5027.547520723152;5036.370453693288;5042.924356134661;4172.520160181557;4227.619514243733;4232.174725928476;4257.340112282601;4274.679399045084;3711.9443208351895;3716.349638813197;3731.253749250161;3745.569259535134;3769.962300377016;4196.832126714959;4290.056187638715;4292.742435453821;4402.493713223021;4408.647308215335;5138.743062846843;5138.871528211825;5169.353830771153;5177.430482646906;5177.844664660066;4413.073254616503;4416.094700586372;4432.157890000954;4444.911101777946;4481.857126432804;4324.56754324565;4433.357163211316;4450.488193857683;4550.431196100488;4563.06457176278;4835.178019736582;4837.153498137882;4840.806367745321;4856.295837103671;4858.076965376567;5125.231215317732;5137.486251374982;5145.408278047923;5147.794088236428;5172.120739474373;4698.7415692137265;4769.117713223065;4824.975875120602;4878.707277563358;4906.8773280668265;5071.594272458268;5077.517635824591;5078.898422031465;5087.949120508895;5119.308893299357;4861.878453038561;5163.251328557413;5168.870778230575;5172.9741351293005;5174.844754657366;4833.975830120827;4858.368412106545;4902.774306423395;4915.1644220481885;4953.133201689618;4988.451270360185;5026.924596130988;5040.899182016413;5043.798248070036;5088.491150884882;4562.406887104655;4720.976395118002;4927.088488629615;4948.653594248368;4952.727599981891;4959.752012399438;4979.203327467732;5020.698758074527;5026.899462010812;5055.039542487063;4836.637252206028;4851.126797176542;4863.367762190893;4917.754112294442;4954.900901981868 09:04:05 INFO - 2015-12-17 09:04:05,604 DEBUG : BROWSER_OUTPUT: |1;ext.html;531.7660229499022;550.3835704011508;558.7625259264811;559.6753882748006;568.8549419897925;26958.19125426238;35120.47319290209;35594.2881142377;35633.46549801755;35767.28465430696;40842.183156336854;41037.58962410375;41185.7940710296;41242.7937860311;41250.58749412505;7953.204679532058;8116.999999999971;8144.6334914928675;8166.428350015502;8174.386920980921;7894.289513943744;8007.759767206964;8022.638981245858;8024.438289319721;8031.196880311981;7955.323797477221;8016.078151012621;8023.839523209533;8044.879326810103;8080.878786818203;7952.522848629072;7966.601669916539;7981.521108733495;8029;8043.879341809878;7870.803229919241;7987.520748755035;7993.72021979228;8007.758797386415;8007.879881801778;36017.819910900536;36417.63582364169;36520.817395913116;36580.81709591435;36720.81639591785;2709.5935609658513;2820.868894830212;2824.124521398373;2851.985740071307;3607.9098022549383;27511.499631613606;27974.580381294094;28485.85757071222;29001.274968125756;29182.978595749046;3391.5251864738957;3410.556627638391;3445.844936977842;3472.6700963408434;3475.2701932594186;210192;211530;211953;212778;212870;47863.521364785905;51233.23150152751;53280;60251.698741506014;61531.38468615257;53256.73371633117;53265.53774004677;53390.7330463353;53505;53659.73170134125;13368.462626798042;13506.05457617977;13541.187528748109;13596.796048059286;13625.318734063258;9919.851202231972;10014.198864090855;10093.192544596415;10166.694999150037;10292.433916134685;18543;18664.160112794958;18714.344997925007;18728.15723292455;18777.530561736065;7183.89224161638;7303.598302093435;7343.118825740945;7434.070741157356;7494.325510704063;880.5993273060742;881.3742243007537;907.2197909797569;916.3814425262921;918.8759517465056;26172.476550468884;26422.47155056888;26548.60177097345;26552.468950621267;26746.465070698476;932.6176267730198;960.0207788056143;975.3075316525346;1017.7862648843677;1040.5837664933936;13620.114692544952;13972;14057.85942140586;14068.577942661737;14143.151410915378;267600;273523;275544;276205;276749;45656.3151552727;45991.77004115025;46007.539924601;46049.53950460452;46055.53944460512;1231.5258625429274;1240.3240234072452;1263.1663102352404;1266.639007882749;1270.0411189551828;25389.61915571231;26072.73927260779;26444.338891527867;26454;26696.332591685365;40563.39154912738;40750.79624601858;41137.58862411338;41153.176936460506;41211.793941031305;249865;252459;252517;252811;253041;28655.57016644794;28831;29125.41749165047;29445;29533.26166845846;33768.3246335067;34098.829505853304;34335.656643434246;34368.82815585906;37648.24703505969;28860.855695721388;29293.414131717673;29445;29562.852185738935;29822.10533683993;28733.24975901682;28899.711002890544;29082;29168.515351981478;29339.559906600993;28739.425211496073;29325.120246392642;29571.408571828873;29605.85197074087;29638.55542166826;27682;28932.27669308284;29811.254718632208;30816.53775193419;30861.228469288446;21230;21661.675074873576;23451;23843.761562384156;24118.276451706475 09:04:05 INFO - 2015-12-17 09:04:05,605 DEBUG : BROWSER_OUTPUT: |2;jquery.html;322.26684293232904;323.29242593281265;327.0809026635154;327.12004707337263;327.86393646636645;15087.09477431355;15515.3018114185;15971;16158.111303878297;16620.58448538784;10459.529321180546;10491.68524944253;10544.894551054487;12540.93729531351;15291.464798732046;4553.385292985437;4563.771811409433;4656.97671511642;4680.227762419214;4681.110588988098;4552.157850797613;4609.907801843961;4611.700239484423;4613.1696294666945;4616.422947131608;4576.222042252825;4603.7237765734;6110.816675499742;6328.810135695937;6347.650879201642;6192.009278515438;6339.809805705836;6352.110704501373;6648.5678430901835;6686.665666716644;6367;6371.267304260023;6382.170317858649;6390.488760899117;6404.4876409887565;20607.484812879648;21815.890920545455;21970.780292197032;21981.780182198294;22047.77952220473;1882.3317722208692;1931.2564662604857;2004.4688157638238;2026.1490174126902;2096.5911647228795;13663.590092297147;13746.381412836346;13887.861121388758;14066.030755694212;14318.42726290947;1881.8494520438333;1910.0831600831539;1945.2413558712115;1948.6492431362349;1980.8811471311624;61431.69284153595;63927.680361598825;64292.35707642911;65386.3461365385;65591;14865.79697106026;15515.767263491056;15525.611859703598;15545.766813497807;15549.66754986761;22815.543689126123;22940.426489337566;22977.195798146986;23270.883645581664;23347.41631459193;8795.736127916172;8806.20744133031;8831.116888311246;8844.204021638083;8845.955770221108;7348.191698913115;7371.152317483558;7384.815379615553;7390.926090739132;7393.55638661682;7688.654010569538;7692.307692307607;7702.614869256515;7772.300492955661;7806.18035106317;1746.755454236408;1841.302491849447;3075.8469071649934;3106.98446507766;3265.4448743713624;936.3960245641523;957.167264479907;957.875476188105;967.5307475874196;991.8908920018937;16168;16640;16853.15734213309;17488.562785930455;17506.03716795563;862.9007664118707;864.4769914201943;930.916217540411;941.5621735892843;961.2262128986093;8424.031236407769;8518.23335899772;8619.87070193935;8683.696070637498;8851.778705532284;471873;473020;474884;478035;483295;72397;74118;74358.6282068597;75001;78389;1094.0809719835217;1105.3588918427292;1143.228320883417;1155.3530023187043;1155.7284038251005;13682.452701891812;13716.245606491739;13721.656958575717;13724.245166515937;13965.231912244924;31551.21121971969;31735.84132079325;31914.52128218125;32684.50973235356;32864.50703239501;45529;46360;47764.52235477601;49498.76253093557;50381.99236015332;8458.788530286793;8765.167309105625;8876.733697989072;8945.552722363986;8995.730128096167;10239.692809215736;10942.398168100832;12696.6825829355;16369.263383148025;16608.66782664316;10685.358878467317;13378.732425351633;13456.730865382833;13785.724285514434;14023.298835058411;10786.838197426889;13143.671408214872;13526.86473135256;13772.449102035805;14056.367463464374;14213.64465888361;14254.144751314954;14568.198749068903;14578.198199099152;14672.853271467575;8861.291096712377;8874.467531948103;8877.911220887709;8959.507227102571;9154.359194856295;13172.407241673956;13318.067735258439;13692.726145477234;13938.163710177421;14304.998650094394 09:04:05 INFO - 2015-12-17 09:04:05,607 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1480.0749531542785;1630.3397124164715;1678.8153303136653;1712.1952682239346;1719.1490212344884;6184.350643182469;6360.586561873475;6667.366600172982;7135.821604459898;7143.178534468531;6795.218549866767;6905.136857892763;7019.087518622585;7020.157581090263;7030.332118448752;2040.4898775306174;2082.0422605601443;2082.8437867159946;2083.2396175395947;2089.1121273458793;2070.1201989154624;2086.7495900491963;2102.4743814046487;2106.2101711858054;2113.0596884386455;2068.596623658387;2070.130545171032;2079.105984426696;2084.458040909359;2084.81236688698;2052.1688716070016;2061.9793802061936;2067.7105205271273;2070.2133189388087;2074.6161960037443;2046.8055534724178;2068.152057656369;2100.705901173837;2119.173522326294;2120.024788597247;6826;6868.141482314711;6878.071460352839;6891.345322194384;6893.241743408221;4178.892593462735;4212.726172798789;4212.85255016073;4217.578242175789;4252.808623611945;2560.7823335016546;2596;2603.8437693738438;2721.1564415031207;2721.6053672217417;859.2224037246302;863.4948555095277;880.2869675562814;881.5460038080394;890.1676932068472;9827.4103553787;9840;9840;9859.112679858847;9901.603935842557;3865.6520913117392;3889.747166434173;3929.8035098244973;4000.119973605802;4004.5594984551676;8327;8371.665133394717;8444.493330400073;8478.06741258461;8483.95758021206;4278.7860606969525;4280.315149576053;4295.463067116611;4296.37702533131;4299.462567179103;3266.1671273825023;3338.6995170434293;3707.7589956652732;3896.8441262349756;3913.2369188008065;3417.384870723244;3489.6510348965408;3501.562304711911;3508.649135086471;3527.0829584308262;1120.7198200449889;1123.556195302867;1127.115214556569;1128.9435528223555;1171.2503997441645;881.2685471059134;901.8196360727881;1164.8427462292652;1166.393475392791;1187.821826725998;6729.125213722283;6769.526133170631;6771.762988295484;6810.080639113756;6811.318868113248;1048;1051.363924825485;1053.1206442620344;1068.156156636264;1071.7534966957487;5308.840734777886;5319.148936170194;5368.651037682538;5370.731463426813;5405.594580406485;8552.786180345542;8558.57207139653;8818.29453643707;8866.86699699505;8900.510471923977;8670.349723770616;8712.564371781385;8726.520041397784;8744.475331480131;8756.956215218883;6769.323067693192;6811.693473793691;6823.795286141324;6859;6931.480138989495;6479.1901012373455;6543.869122617616;6551.755166518346;6591.802245932629;6623.536352455475;7615.619219039137;7699.191584883731;7875.369970402239;7938;7960.442769006114;7959.243871832166;7968.84062318762;8036.397270204875;8082.474639148437;8089.555074470725;5203.141481655484;5249.842504724865;5439.265699130567;5890.587658863838;5954.166416701752;6666.400023997863;6685.6322902240845;6706.128203333536;6732.6297053660555;6757.898631520629;6331.303556608677;6427.839304017437;6473.320301368289;6511.283758786624;6529.249136349191;6138.508919286535;6293.874122517615;6409.070684750778;6516.609003459807;6600.867982640416;6523.282438931618;6527;6564.245111812203;6572.178477690289;6575.210974683068;5804.332501762183;5831.562632802642;5846.503047241032;5869;6070.726817293321;5782.2483077199695;5803.65178089316;5806.4193580641595;5820.185174075717;5838 09:04:05 INFO - 2015-12-17 09:04:05,607 DEBUG : BROWSER_OUTPUT: |4;prototype.html;305.8470764617691;307.50031199301134;315.05326493885883;315.56452096107375;320.0143557841847;5588;5661.745221465034;5677.6309539879985;5704.686242256685;5717.6283541569765;5575.442455754423;5576.9163462547995;5576.972115139429;5582.162675598663;5597.160425936112;4143.212789569972;4224.493060832704;4352.651787856964;4352.651787856979;4361.193179261831;4233.576642335757;4241.321388577828;4268.765217913014;4269.53035166133;4274.273373526493;4198.8110535026;4200.3699445083175;4215.683823713219;4221.978890105561;4227.788610569474;4190.727602705814;4199.1391764688315;4216.3464662977285;4222.366645003243;4223.324268117087;3593.2633810068874;3617.764845285048;3627.582827974764;3638.727095467824;3645.9609011431617;5258.342707161605;5350.063738845686;5354.785808567653;5366.9463305366835;5372.328458942632;4867.975660121712;4871.318015477836;4883.902321953541;4884.780184891652;4892.68197567157;5894.793682221101;5896.97051514744;5901.0558310670085;5903.232579764646;5905;3860.36304009838;3971.8411263549424;4003.3594624859884;4005.8998525036814;4007.198560287954;6122.265328160604;6169.04379821124;6178.969105154445;6183.288921773964;6197.0704394340755;4069.267531844237;4071.328230841878;4079.3676980068135;4106.568810274938;4109.91780164395;4172.061286210578;4174.519930208005;4178.811953462102;4180.393842892763;4185.22573323936;4691.812327506861;4716.504766999485;4834.492378300298;4846.80612775485;4848.8302909398;5816;5855.473007429352;5860.824175274749;5864.413558644102;5872.265966754156;1446.0383844743337;1446.3997441062052;1447.160646824839;1447.5874375802846;1458.6280498472822;988.253868329421;997.0265841044927;1005.4369553050316;1008.1884083312857;1013.3767732844248;5084.44071152173;5091.949080509222;5093.694378337312;5094.2358646203365;5108.438071812097;6103.38966103395;6112.541559383064;6120.265568131853;6144.262688477412;6150.569460137837;5097.1844504879045;5106.055379754757;5113.488651134931;5125.589952803767;5130.127878260705;3194.4409728297455;3269.983650081734;3279.7048265655726;3305.058058453329;3312.1554003728893;5750.166225897222;5810.593258471867;5812.070068789058;5815.2149459822385;5847.3275573308765;6347.270063942613;6357.237131544244;6372.362763723683;6383.2978372379;6399.552031357854;5619.185218143428;5784;5832.679202643895;5837.357890632027;5837.883242335214;6130.63216207029;6143.078538219303;6159.507239420746;6160.845978850564;6173.938260617516;5977.432143946318;6001.039833626599;6011.0382338825575;6027.276726792813;6031.819045428644;5753.367129615823;5795.565332599987;5798.159266906359;5812.9709351452975;5827.8834423310445;4008.178323443629;4148.025214074751;4154.979225103855;4156.833726651021;4185.937210941899;6034;6098.359672234352;6102.359252278625;6118.357572455006;6166.321704612579;5831.70841457917;5906.497947674324;5909.172715819703;5911.083782013795;5936.851578710567;5875.118732190205;5880.411958804086;5886.58793884424;5896.852578685567;5903.498202652822;5690.971545142414;5733.0540460823495;5758.3090029196765;5761.884762304814;5784.884302314014;5924.822255332347;5929.377415371324;5954.464098231007;5967.552433567586;5972.850678733066;5783.652980821164;5793.739281732416;5799.043157879071;5807.128930660435;5822 09:04:05 INFO - 2015-12-17 09:04:05,608 DEBUG : BROWSER_OUTPUT: |5;yui.html;583.2650859916504;611.14439784302;632.3486808587157;723.5405517496389;732.6666366803104;11254.09967202624;12510.437030333635;12848.614541563767;12866.678333041667;12869.292188929629;13061.477540898377;13108.213507189552;13179.472821087145;13277.535286264983;13289.269090200036;620.5780069552702;781.4646966827713;785.2147852147853;787.8266781308103;792.9881051784228;621.7575145693183;778.3384123495041;787.9172686867882;791.4578513718093;792.1800936031209;808.337163525909;810.3638643664694;812.9024517057961;817.0930267403178;818.9181081891793;799.828036972048;806.4999700185894;806.6329819931917;807.3823525003377;809.0251247247053;607.8328459673581;608.3277977834517;608.6135304081896;609.1471939284992;609.576344440614;11134;11174.161937854613;11189.832152517718;11212.159088068427;11267.323960562311;10137.695869123938;10155.238357123246;10459.738506537322;10465.424401657905;10621.41582212978;8553.44402613828;8655;8872.822543549157;8916.152965468335;8999.730008099768;3066.218114380841;3088.459519584086;3110.471219892601;3124.859381327823;3150;37953.81023094895;38238.61761382406;38364;38514.42228366605;38619.22761544782;8116.147804480563;8137;8139.918600814034;8208.138145494757;8319.334453243726;15282.617934551725;15288.082715037133;15466.458673946358;15481.5355539334;15489.457868974532;6025.578209525379;6120.755169793248;6171.413715697002;6174.722137503733;6192.721327540272;3350.162459385154;3534.575850897909;3550.928981420357;3603.3333833240936;3642.4354225095153;2496.450780828215;2520.7857332126973;2523.7223905370392;2527.380791706044;2528.0772518031154;452.1206253836257;460.2681736039646;465.3276016156621;466.852941323482;467.2873867352315;418.6525184097192;442.79631369569717;445.6234481862821;537.6102325813754;543.6466296907042;6576.210854697371;6922.930770692329;7717.421193410517;7756.379489640815;8066.878996815052;444.1095603315397;523.8821265215296;539.4174291764884;559.7844829740579;580.077676494376;3206.615206175274;3413.6415676353627;3464.9480257796154;3505.298940211968;3684.4841722158917;47368.5263147371;48045.279320810216;48056;48226;48304.27543586849;42041;42555.36166957498;43085.35371969423;43312.3503147453;43355.132897342504;38966.61033389687;39605.60394396077;39699.80150099231;39728.60271397249;39999.400068991854;6859.2454829969665;7171.282871712787;7369.889451658122;7527.1343795462035;7588.582627955516;30808.845955770077;30918.845405772827;31257.37485250328;31442.371152577278;31593;35230.29539409146;35797.28405431825;38607;39555.80222098871;39599.20801583894;9324.860127098234;9343.065693430874;9480.099390557885;9526.90473095288;9561.139497445263;10419.791604168026;10660.733481663021;10682.679519614425;10686.946565267124;11040.28238164549;9678.661246856607;9808.16630586408;9814.362066465657;9871.950640246752;9922.900770992197;10056.766451574142;10078.848817267894;10179;10207.897921020694;10287.22845786554;9020.503872286825;9681.467519286507;10130.392176469435;10317.123044541298;10358.844617330595;8448.056946902125;8511.829763404821;8671.132886711279;8814.206721395105;8845.955770221366;7206.639668016683;7485.513441626277;7586.772396828104;7701.075870895304;7763.844723105393 09:04:05 INFO - 2015-12-17 09:04:05,608 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:04:05 INFO - 2015-12-17 09:04:05,608 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:04:05 INFO - 2015-12-17 09:04:05,608 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,330 09:04:05 INFO - 2015-12-17 09:04:05,608 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:04:05 INFO - 2015-12-17 09:04:05,608 DEBUG : BROWSER_OUTPUT: __startTimestamp1450371845599__endTimestamp 09:04:05 INFO - 2015-12-17 09:04:05,608 DEBUG : BROWSER_OUTPUT: 09:04:05 INFO - 2015-12-17 09:04:05,611 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:04:05 INFO - 2015-12-17 09:04:05,612 DEBUG : BROWSER_OUTPUT: Number of tests: 6 09:04:05 INFO - 2015-12-17 09:04:05,612 DEBUG : BROWSER_OUTPUT: 09:04:05 INFO - 2015-12-17 09:04:05,612 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4697.61 Median:4833.98 stddev:506.32 (10.5%) stddev-sans-first:481.88 09:04:05 INFO - 2015-12-17 09:04:05,612 DEBUG : BROWSER_OUTPUT: Values: 2656.2 2683.6 2714.1 2727.8 2732.0 4585.1 4602.2 4621.4 4644.7 4669.1 4689.7 4710.3 4715.1 4784.6 4807.7 4325.3 4354.8 4498.1 4499.6 4531.7 4239.4 4405.6 4435.9 4466.0 4536.4 4258.6 4319.2 4323.4 4343.8 4349.5 4336.0 4346.6 4366.5 4368.8 4370.0 4158.7 4268.6 4321.6 4340.7 4379.8 4581.5 4627.8 4631.9 4655.0 4668.7 5128.3 5159.9 5160.5 5174.5 5181.9 4947.6 5088.6 5102.2 5187.2 5266.1 4713.1 4787.0 4812.9 4820.4 4820.5 5280.6 5295.2 5303.3 5325.0 5387.9 4821.2 5091.2 5099.9 5111.5 5118.1 4977.1 5024.7 5059.7 5070.8 5113.8 5052.3 5059.0 5060.3 5065.9 5101.4 4734.8 4857.8 5027.5 5036.4 5042.9 4172.5 4227.6 4232.2 4257.3 4274.7 3711.9 3716.3 3731.3 3745.6 3770.0 4196.8 4290.1 4292.7 4402.5 4408.6 5138.7 5138.9 5169.4 5177.4 5177.8 4413.1 4416.1 4432.2 4444.9 4481.9 4324.6 4433.4 4450.5 4550.4 4563.1 4835.2 4837.2 4840.8 4856.3 4858.1 5125.2 5137.5 5145.4 5147.8 5172.1 4698.7 4769.1 4825.0 4878.7 4906.9 5071.6 5077.5 5078.9 5087.9 5119.3 4861.9 5163.3 5168.9 5173.0 5174.8 4834.0 4858.4 4902.8 4915.2 4953.1 4988.5 5026.9 5040.9 5043.8 5088.5 4562.4 4721.0 4927.1 4948.7 4952.7 4959.8 4979.2 5020.7 5026.9 5055.0 4836.6 4851.1 4863.4 4917.8 4954.9 09:04:05 INFO - 2015-12-17 09:04:05,612 DEBUG : BROWSER_OUTPUT: 09:04:05 INFO - 2015-12-17 09:04:05,612 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:40102.54 Median:32314.78 stddev:64498.30 (199.6%) stddev-sans-first:64610.80 09:04:05 INFO - 2015-12-17 09:04:05,612 DEBUG : BROWSER_OUTPUT: Values: 531.8 550.4 558.8 559.7 568.9 26958.2 35120.5 35594.3 35633.5 35767.3 40842.2 41037.6 41185.8 41242.8 41250.6 7953.2 8117.0 8144.6 8166.4 8174.4 7894.3 8007.8 8022.6 8024.4 8031.2 7955.3 8016.1 8023.8 8044.9 8080.9 7952.5 7966.6 7981.5 8029.0 8043.9 7870.8 7987.5 7993.7 8007.8 8007.9 36017.8 36417.6 36520.8 36580.8 36720.8 2709.6 2820.9 2824.1 2852.0 3607.9 27511.5 27974.6 28485.9 29001.3 29183.0 3391.5 3410.6 3445.8 3472.7 3475.3 210192.0 211530.0 211953.0 212778.0 212870.0 47863.5 51233.2 53280.0 60251.7 61531.4 53256.7 53265.5 53390.7 53505.0 53659.7 13368.5 13506.1 13541.2 13596.8 13625.3 9919.9 10014.2 10093.2 10166.7 10292.4 18543.0 18664.2 18714.3 18728.2 18777.5 7183.9 7303.6 7343.1 7434.1 7494.3 880.6 881.4 907.2 916.4 918.9 26172.5 26422.5 26548.6 26552.5 26746.5 932.6 960.0 975.3 1017.8 1040.6 13620.1 13972.0 14057.9 14068.6 14143.2 267600.0 273523.0 275544.0 276205.0 276749.0 45656.3 45991.8 46007.5 46049.5 46055.5 1231.5 1240.3 1263.2 1266.6 1270.0 25389.6 26072.7 26444.3 26454.0 26696.3 40563.4 40750.8 41137.6 41153.2 41211.8 249865.0 252459.0 252517.0 252811.0 253041.0 28655.6 28831.0 29125.4 29445.0 29533.3 33768.3 34098.8 34335.7 34368.8 37648.2 28860.9 29293.4 29445.0 29562.9 29822.1 28733.2 28899.7 29082.0 29168.5 29339.6 28739.4 29325.1 29571.4 29605.9 29638.6 27682.0 28932.3 29811.3 30816.5 30861.2 21230.0 21661.7 23451.0 23843.8 24118.3 09:04:05 INFO - 2015-12-17 09:04:05,612 DEBUG : BROWSER_OUTPUT: 09:04:05 INFO - 2015-12-17 09:04:05,612 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:27311.98 Median:16505.80 stddev:77825.87 (471.5%) stddev-sans-first:78017.81 09:04:05 INFO - 2015-12-17 09:04:05,612 DEBUG : BROWSER_OUTPUT: Values: 322.3 323.3 327.1 327.1 327.9 15087.1 15515.3 15971.0 16158.1 16620.6 10459.5 10491.7 10544.9 12540.9 15291.5 4553.4 4563.8 4657.0 4680.2 4681.1 4552.2 4609.9 4611.7 4613.2 4616.4 4576.2 4603.7 6110.8 6328.8 6347.7 6192.0 6339.8 6352.1 6648.6 6686.7 6367.0 6371.3 6382.2 6390.5 6404.5 20607.5 21815.9 21970.8 21981.8 22047.8 1882.3 1931.3 2004.5 2026.1 2096.6 13663.6 13746.4 13887.9 14066.0 14318.4 1881.8 1910.1 1945.2 1948.6 1980.9 61431.7 63927.7 64292.4 65386.3 65591.0 14865.8 15515.8 15525.6 15545.8 15549.7 22815.5 22940.4 22977.2 23270.9 23347.4 8795.7 8806.2 8831.1 8844.2 8846.0 7348.2 7371.2 7384.8 7390.9 7393.6 7688.7 7692.3 7702.6 7772.3 7806.2 1746.8 1841.3 3075.8 3107.0 3265.4 936.4 957.2 957.9 967.5 991.9 16168.0 16640.0 16853.2 17488.6 17506.0 862.9 864.5 930.9 941.6 961.2 8424.0 8518.2 8619.9 8683.7 8851.8 471873.0 473020.0 474884.0 478035.0 483295.0 72397.0 74118.0 74358.6 75001.0 78389.0 1094.1 1105.4 1143.2 1155.4 1155.7 13682.5 13716.2 13721.7 13724.2 13965.2 31551.2 31735.8 31914.5 32684.5 32864.5 45529.0 46360.0 47764.5 49498.8 50382.0 8458.8 8765.2 8876.7 8945.6 8995.7 10239.7 10942.4 12696.7 16369.3 16608.7 10685.4 13378.7 13456.7 13785.7 14023.3 10786.8 13143.7 13526.9 13772.4 14056.4 14213.6 14254.1 14568.2 14578.2 14672.9 8861.3 8874.5 8877.9 8959.5 9154.4 13172.4 13318.1 13692.7 13938.2 14305.0 09:04:05 INFO - 2015-12-17 09:04:05,612 DEBUG : BROWSER_OUTPUT: 09:04:05 INFO - 2015-12-17 09:04:05,612 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4982.22 Median:5922.38 stddev:2589.01 (43.7%) stddev-sans-first:2582.89 09:04:05 INFO - 2015-12-17 09:04:05,613 DEBUG : BROWSER_OUTPUT: Values: 1480.1 1630.3 1678.8 1712.2 1719.1 6184.4 6360.6 6667.4 7135.8 7143.2 6795.2 6905.1 7019.1 7020.2 7030.3 2040.5 2082.0 2082.8 2083.2 2089.1 2070.1 2086.7 2102.5 2106.2 2113.1 2068.6 2070.1 2079.1 2084.5 2084.8 2052.2 2062.0 2067.7 2070.2 2074.6 2046.8 2068.2 2100.7 2119.2 2120.0 6826.0 6868.1 6878.1 6891.3 6893.2 4178.9 4212.7 4212.9 4217.6 4252.8 2560.8 2596.0 2603.8 2721.2 2721.6 859.2 863.5 880.3 881.5 890.2 9827.4 9840.0 9840.0 9859.1 9901.6 3865.7 3889.7 3929.8 4000.1 4004.6 8327.0 8371.7 8444.5 8478.1 8484.0 4278.8 4280.3 4295.5 4296.4 4299.5 3266.2 3338.7 3707.8 3896.8 3913.2 3417.4 3489.7 3501.6 3508.6 3527.1 1120.7 1123.6 1127.1 1128.9 1171.3 881.3 901.8 1164.8 1166.4 1187.8 6729.1 6769.5 6771.8 6810.1 6811.3 1048.0 1051.4 1053.1 1068.2 1071.8 5308.8 5319.1 5368.7 5370.7 5405.6 8552.8 8558.6 8818.3 8866.9 8900.5 8670.3 8712.6 8726.5 8744.5 8757.0 6769.3 6811.7 6823.8 6859.0 6931.5 6479.2 6543.9 6551.8 6591.8 6623.5 7615.6 7699.2 7875.4 7938.0 7960.4 7959.2 7968.8 8036.4 8082.5 8089.6 5203.1 5249.8 5439.3 5890.6 5954.2 6666.4 6685.6 6706.1 6732.6 6757.9 6331.3 6427.8 6473.3 6511.3 6529.2 6138.5 6293.9 6409.1 6516.6 6600.9 6523.3 6527.0 6564.2 6572.2 6575.2 5804.3 5831.6 5846.5 5869.0 6070.7 5782.2 5803.7 5806.4 5820.2 5838.0 09:04:05 INFO - 2015-12-17 09:04:05,613 DEBUG : BROWSER_OUTPUT: 09:04:05 INFO - 2015-12-17 09:04:05,613 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4856.24 Median:5579.57 stddev:1462.49 (26.2%) stddev-sans-first:1426.16 09:04:05 INFO - 2015-12-17 09:04:05,613 DEBUG : BROWSER_OUTPUT: Values: 305.8 307.5 315.1 315.6 320.0 5588.0 5661.7 5677.6 5704.7 5717.6 5575.4 5576.9 5577.0 5582.2 5597.2 4143.2 4224.5 4352.7 4352.7 4361.2 4233.6 4241.3 4268.8 4269.5 4274.3 4198.8 4200.4 4215.7 4222.0 4227.8 4190.7 4199.1 4216.3 4222.4 4223.3 3593.3 3617.8 3627.6 3638.7 3646.0 5258.3 5350.1 5354.8 5366.9 5372.3 4868.0 4871.3 4883.9 4884.8 4892.7 5894.8 5897.0 5901.1 5903.2 5905.0 3860.4 3971.8 4003.4 4005.9 4007.2 6122.3 6169.0 6179.0 6183.3 6197.1 4069.3 4071.3 4079.4 4106.6 4109.9 4172.1 4174.5 4178.8 4180.4 4185.2 4691.8 4716.5 4834.5 4846.8 4848.8 5816.0 5855.5 5860.8 5864.4 5872.3 1446.0 1446.4 1447.2 1447.6 1458.6 988.3 997.0 1005.4 1008.2 1013.4 5084.4 5091.9 5093.7 5094.2 5108.4 6103.4 6112.5 6120.3 6144.3 6150.6 5097.2 5106.1 5113.5 5125.6 5130.1 3194.4 3270.0 3279.7 3305.1 3312.2 5750.2 5810.6 5812.1 5815.2 5847.3 6347.3 6357.2 6372.4 6383.3 6399.6 5619.2 5784.0 5832.7 5837.4 5837.9 6130.6 6143.1 6159.5 6160.8 6173.9 5977.4 6001.0 6011.0 6027.3 6031.8 5753.4 5795.6 5798.2 5813.0 5827.9 4008.2 4148.0 4155.0 4156.8 4185.9 6034.0 6098.4 6102.4 6118.4 6166.3 5831.7 5906.5 5909.2 5911.1 5936.9 5875.1 5880.4 5886.6 5896.9 5903.5 5691.0 5733.1 5758.3 5761.9 5784.9 5924.8 5929.4 5954.5 5967.6 5972.9 5783.7 5793.7 5799.0 5807.1 5822.0 09:04:05 INFO - 2015-12-17 09:04:05,613 DEBUG : BROWSER_OUTPUT: 09:04:05 INFO - 2015-12-17 09:04:05,613 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:11748.21 Median:467.07 stddev:13353.03 (2858.9%) stddev-sans-first:13364.16 09:04:05 INFO - 2015-12-17 09:04:05,613 DEBUG : BROWSER_OUTPUT: Values: 583.3 611.1 632.3 723.5 732.7 11254.1 12510.4 12848.6 12866.7 12869.3 13061.5 13108.2 13179.5 13277.5 13289.3 620.6 781.5 785.2 787.8 793.0 621.8 778.3 787.9 791.5 792.2 808.3 810.4 812.9 817.1 818.9 799.8 806.5 806.6 807.4 809.0 607.8 608.3 608.6 609.1 609.6 11134.0 11174.2 11189.8 11212.2 11267.3 10137.7 10155.2 10459.7 10465.4 10621.4 8553.4 8655.0 8872.8 8916.2 8999.7 3066.2 3088.5 3110.5 3124.9 3150.0 37953.8 38238.6 38364.0 38514.4 38619.2 8116.1 8137.0 8139.9 8208.1 8319.3 15282.6 15288.1 15466.5 15481.5 15489.5 6025.6 6120.8 6171.4 6174.7 6192.7 3350.2 3534.6 3550.9 3603.3 3642.4 2496.5 2520.8 2523.7 2527.4 2528.1 452.1 460.3 465.3 466.9 467.3 418.7 442.8 445.6 537.6 543.6 6576.2 6922.9 7717.4 7756.4 8066.9 444.1 523.9 539.4 559.8 580.1 3206.6 3413.6 3464.9 3505.3 3684.5 47368.5 48045.3 48056.0 48226.0 48304.3 42041.0 42555.4 43085.4 43312.4 43355.1 38966.6 39605.6 39699.8 39728.6 39999.4 6859.2 7171.3 7369.9 7527.1 7588.6 30808.8 30918.8 31257.4 31442.4 31593.0 35230.3 35797.3 38607.0 39555.8 39599.2 9324.9 9343.1 9480.1 9526.9 9561.1 10419.8 10660.7 10682.7 10686.9 11040.3 9678.7 9808.2 9814.4 9872.0 9922.9 10056.8 10078.8 10179.0 10207.9 10287.2 9020.5 9681.5 10130.4 10317.1 10358.8 8448.1 8511.8 8671.1 8814.2 8846.0 7206.6 7485.5 7586.8 7701.1 7763.8 09:04:05 INFO - 2015-12-17 09:04:05,613 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:04:05 INFO - 2015-12-17 09:04:05,613 DEBUG : BROWSER_OUTPUT: 09:04:06 INFO - 2015-12-17 09:04:06,316 INFO : Browser exited with error code: 0 09:04:06 INFO - 2015-12-17 09:04:06,332 INFO : Completed test dromaeo_css (00:18:34) 09:04:06 INFO - 2015-12-17 09:04:06,332 INFO : Starting test kraken 09:04:06 INFO - 2015-12-17 09:04:06,333 DEBUG : operating with platform_type : linux_ 09:04:06 INFO - 2015-12-17 09:04:06,333 INFO : Initialising browser for kraken test... 09:04:06 INFO - 2015-12-17 09:04:06,339 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGiphIi/profile http://localhost:42524/getInfo.html 09:04:10 INFO - 2015-12-17 09:04:10,762 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:04:10 INFO - 2015-12-17 09:04:10,762 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:04:10 INFO - 2015-12-17 09:04:10,762 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:04:10 INFO - 2015-12-17 09:04:10,762 DEBUG : BROWSER_OUTPUT: __metrics 09:04:10 INFO - 2015-12-17 09:04:10,899 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] 09:04:11 INFO - 2015-12-17 09:04:11,788 INFO : Browser initialized. 09:04:11 INFO - 2015-12-17 09:04:11,788 INFO : Running cycle 1/1 for kraken test... 09:04:11 INFO - 2015-12-17 09:04:11,789 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGiphIi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:04:23 INFO - 2015-12-17 09:04:23,822 DEBUG : BROWSER_OUTPUT: RSS: Main: 150810624 09:04:23 INFO - 2015-12-17 09:04:23,822 DEBUG : BROWSER_OUTPUT: 09:04:26 INFO - 2015-12-17 09:04:26,391 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42524/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42524/tests/kraken/driver.html?testName=audio-beat-detection) 09:04:27 INFO - 2015-12-17 09:04:27,001 DEBUG : BROWSER_OUTPUT: RSS: Main: 150601728 09:04:27 INFO - 2015-12-17 09:04:27,001 DEBUG : BROWSER_OUTPUT: 09:04:29 INFO - 2015-12-17 09:04:29,725 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42524/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42524/tests/kraken/driver.html?testName=audio-dft) 09:04:30 INFO - 2015-12-17 09:04:30,328 DEBUG : BROWSER_OUTPUT: RSS: Main: 151547904 09:04:30 INFO - 2015-12-17 09:04:30,329 DEBUG : BROWSER_OUTPUT: 09:04:33 INFO - 2015-12-17 09:04:33,220 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42524/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42524/tests/kraken/driver.html?testName=audio-fft) 09:04:33 INFO - 2015-12-17 09:04:33,806 DEBUG : BROWSER_OUTPUT: RSS: Main: 145248256 09:04:33 INFO - 2015-12-17 09:04:33,806 DEBUG : BROWSER_OUTPUT: 09:04:35 INFO - 2015-12-17 09:04:35,839 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42524/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42524/tests/kraken/driver.html?testName=audio-oscillator) 09:04:36 INFO - 2015-12-17 09:04:36,424 DEBUG : BROWSER_OUTPUT: RSS: Main: 146022400 09:04:36 INFO - 2015-12-17 09:04:36,425 DEBUG : BROWSER_OUTPUT: 09:04:38 INFO - 2015-12-17 09:04:38,679 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42524/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42524/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:04:39 INFO - 2015-12-17 09:04:39,253 DEBUG : BROWSER_OUTPUT: RSS: Main: 146513920 09:04:39 INFO - 2015-12-17 09:04:39,253 DEBUG : BROWSER_OUTPUT: 09:04:42 INFO - 2015-12-17 09:04:42,277 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42524/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42524/tests/kraken/driver.html?testName=imaging-darkroom) 09:04:42 INFO - 2015-12-17 09:04:42,878 DEBUG : BROWSER_OUTPUT: RSS: Main: 145735680 09:04:42 INFO - 2015-12-17 09:04:42,878 DEBUG : BROWSER_OUTPUT: 09:04:46 INFO - 2015-12-17 09:04:46,349 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42524/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42524/tests/kraken/driver.html?testName=imaging-desaturate) 09:04:46 INFO - 2015-12-17 09:04:46,951 DEBUG : BROWSER_OUTPUT: RSS: Main: 146366464 09:04:46 INFO - 2015-12-17 09:04:46,951 DEBUG : BROWSER_OUTPUT: 09:04:49 INFO - 2015-12-17 09:04:49,813 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42524/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42524/tests/kraken/driver.html?testName=json-parse-financial) 09:04:50 INFO - 2015-12-17 09:04:50,414 DEBUG : BROWSER_OUTPUT: RSS: Main: 144732160 09:04:50 INFO - 2015-12-17 09:04:50,414 DEBUG : BROWSER_OUTPUT: 09:04:52 INFO - 2015-12-17 09:04:52,174 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42524/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42524/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:04:52 INFO - 2015-12-17 09:04:52,775 DEBUG : BROWSER_OUTPUT: RSS: Main: 147804160 09:04:52 INFO - 2015-12-17 09:04:52,776 DEBUG : BROWSER_OUTPUT: 09:04:54 INFO - 2015-12-17 09:04:54,679 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42524/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42524/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:04:55 INFO - 2015-12-17 09:04:55,285 DEBUG : BROWSER_OUTPUT: RSS: Main: 147267584 09:04:55 INFO - 2015-12-17 09:04:55,285 DEBUG : BROWSER_OUTPUT: 09:04:57 INFO - 2015-12-17 09:04:57,482 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42524/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42524/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:04:58 INFO - 2015-12-17 09:04:58,068 DEBUG : BROWSER_OUTPUT: RSS: Main: 147730432 09:04:58 INFO - 2015-12-17 09:04:58,068 DEBUG : BROWSER_OUTPUT: 09:05:00 INFO - 2015-12-17 09:05:00,603 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42524/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42524/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:05:01 INFO - 2015-12-17 09:05:01,209 DEBUG : BROWSER_OUTPUT: RSS: Main: 148037632 09:05:01 INFO - 2015-12-17 09:05:01,209 DEBUG : BROWSER_OUTPUT: 09:05:03 INFO - 2015-12-17 09:05:03,844 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42524/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42524/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:05:04 INFO - 2015-12-17 09:05:04,446 DEBUG : BROWSER_OUTPUT: RSS: Main: 148566016 09:05:04 INFO - 2015-12-17 09:05:04,446 DEBUG : BROWSER_OUTPUT: 09:05:06 INFO - 2015-12-17 09:05:06,200 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42524/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42524/tests/kraken/driver.html?testName=ai-astar) 09:05:06 INFO - 2015-12-17 09:05:06,451 DEBUG : BROWSER_OUTPUT: RSS: Main: 147513344 09:05:06 INFO - 2015-12-17 09:05:06,451 DEBUG : BROWSER_OUTPUT: 09:05:06 INFO - 2015-12-17 09:05:06,452 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:05:06 INFO - 2015-12-17 09:05:06,453 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:05:06 INFO - 2015-12-17 09:05:06,453 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:05:06 INFO - 2015-12-17 09:05:06,453 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:05:06 INFO - 2015-12-17 09:05:06,453 DEBUG : BROWSER_OUTPUT: |0;ai-astar;114;108;113;114;113;125;111;114;114;114 09:05:06 INFO - 2015-12-17 09:05:06,453 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;155;134;155;173;131;154;151;144;164;132 09:05:06 INFO - 2015-12-17 09:05:06,454 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;164;163;163;163;165;167;162;165;164 09:05:06 INFO - 2015-12-17 09:05:06,454 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;72;72;75;75;130;72;78;73;72 09:05:06 INFO - 2015-12-17 09:05:06,454 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;94;96;130;93;95;95;132;94;95;133 09:05:06 INFO - 2015-12-17 09:05:06,454 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;111;110;111;110;110;111;111;111;110 09:05:06 INFO - 2015-12-17 09:05:06,454 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;147;147;150;148;147;148;148;147;148 09:05:06 INFO - 2015-12-17 09:05:06,455 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;97;96;97;95;96;97;97;96;96 09:05:06 INFO - 2015-12-17 09:05:06,455 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;72;72;73;72;77;72;72;72;72;72 09:05:06 INFO - 2015-12-17 09:05:06,455 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;67;68;67;69;68;67;67;68;68 09:05:06 INFO - 2015-12-17 09:05:06,456 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;83;93;83;84;83;84;83;84;88 09:05:06 INFO - 2015-12-17 09:05:06,456 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;122;130;124;136;121;129;122;122;121;126 09:05:06 INFO - 2015-12-17 09:05:06,456 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;137;146;149;150;150;150;147;155;144;134 09:05:06 INFO - 2015-12-17 09:05:06,456 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;69;70;70;68;70;68;72;70;70;70 09:05:06 INFO - 2015-12-17 09:05:06,456 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:05:06 INFO - 2015-12-17 09:05:06,457 DEBUG : BROWSER_OUTPUT: __start_cc_report 09:05:06 INFO - 2015-12-17 09:05:06,457 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,794 09:05:06 INFO - 2015-12-17 09:05:06,457 DEBUG : BROWSER_OUTPUT: __end_cc_report 09:05:06 INFO - 2015-12-17 09:05:06,457 DEBUG : BROWSER_OUTPUT: __startTimestamp1450371906452__endTimestamp 09:05:06 INFO - 2015-12-17 09:05:06,457 DEBUG : BROWSER_OUTPUT: 09:05:06 INFO - 2015-12-17 09:05:06,460 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:05:06 INFO - 2015-12-17 09:05:06,460 DEBUG : BROWSER_OUTPUT: Number of tests: 14 09:05:06 INFO - 2015-12-17 09:05:06,460 DEBUG : BROWSER_OUTPUT: 09:05:06 INFO - 2015-12-17 09:05:06,460 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:114.00 Median:114.00 stddev:4.32 (3.8%) stddev-sans-first:4.58 09:05:06 INFO - 2015-12-17 09:05:06,460 DEBUG : BROWSER_OUTPUT: Values: 114.0 108.0 113.0 114.0 113.0 125.0 111.0 114.0 114.0 114.0 09:05:06 INFO - 2015-12-17 09:05:06,460 DEBUG : BROWSER_OUTPUT: 09:05:06 INFO - 2015-12-17 09:05:06,461 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:149.30 Median:154.50 stddev:14.00 (9.1%) stddev-sans-first:14.70 09:05:06 INFO - 2015-12-17 09:05:06,461 DEBUG : BROWSER_OUTPUT: Values: 155.0 134.0 155.0 173.0 131.0 154.0 151.0 144.0 164.0 132.0 09:05:06 INFO - 2015-12-17 09:05:06,461 DEBUG : BROWSER_OUTPUT: 09:05:06 INFO - 2015-12-17 09:05:06,461 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:163.90 Median:164.00 stddev:1.45 (0.9%) stddev-sans-first:1.50 09:05:06 INFO - 2015-12-17 09:05:06,461 DEBUG : BROWSER_OUTPUT: Values: 163.0 164.0 163.0 163.0 163.0 165.0 167.0 162.0 165.0 164.0 09:05:06 INFO - 2015-12-17 09:05:06,461 DEBUG : BROWSER_OUTPUT: 09:05:06 INFO - 2015-12-17 09:05:06,461 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:79.20 Median:73.00 stddev:17.96 (24.6%) stddev-sans-first:18.90 09:05:06 INFO - 2015-12-17 09:05:06,461 DEBUG : BROWSER_OUTPUT: Values: 73.0 72.0 72.0 75.0 75.0 130.0 72.0 78.0 73.0 72.0 09:05:06 INFO - 2015-12-17 09:05:06,461 DEBUG : BROWSER_OUTPUT: 09:05:06 INFO - 2015-12-17 09:05:06,461 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.70 Median:94.50 stddev:17.95 (19.0%) stddev-sans-first:18.53 09:05:06 INFO - 2015-12-17 09:05:06,461 DEBUG : BROWSER_OUTPUT: Values: 94.0 96.0 130.0 93.0 95.0 95.0 132.0 94.0 95.0 133.0 09:05:06 INFO - 2015-12-17 09:05:06,461 DEBUG : BROWSER_OUTPUT: 09:05:06 INFO - 2015-12-17 09:05:06,461 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.50 Median:111.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 09:05:06 INFO - 2015-12-17 09:05:06,461 DEBUG : BROWSER_OUTPUT: Values: 110.0 111.0 110.0 111.0 110.0 110.0 111.0 111.0 111.0 110.0 09:05:06 INFO - 2015-12-17 09:05:06,461 DEBUG : BROWSER_OUTPUT: 09:05:06 INFO - 2015-12-17 09:05:06,462 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:147.70 Median:148.00 stddev:0.95 (0.6%) stddev-sans-first:0.97 09:05:06 INFO - 2015-12-17 09:05:06,462 DEBUG : BROWSER_OUTPUT: Values: 147.0 147.0 147.0 150.0 148.0 147.0 148.0 148.0 147.0 148.0 09:05:06 INFO - 2015-12-17 09:05:06,462 DEBUG : BROWSER_OUTPUT: 09:05:06 INFO - 2015-12-17 09:05:06,462 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.30 Median:96.50 stddev:0.67 (0.7%) stddev-sans-first:0.71 09:05:06 INFO - 2015-12-17 09:05:06,462 DEBUG : BROWSER_OUTPUT: Values: 96.0 97.0 96.0 97.0 95.0 96.0 97.0 97.0 96.0 96.0 09:05:06 INFO - 2015-12-17 09:05:06,462 DEBUG : BROWSER_OUTPUT: 09:05:06 INFO - 2015-12-17 09:05:06,462 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:72.60 Median:72.00 stddev:1.58 (2.2%) stddev-sans-first:1.66 09:05:06 INFO - 2015-12-17 09:05:06,462 DEBUG : BROWSER_OUTPUT: Values: 72.0 72.0 73.0 72.0 77.0 72.0 72.0 72.0 72.0 72.0 09:05:06 INFO - 2015-12-17 09:05:06,462 DEBUG : BROWSER_OUTPUT: 09:05:06 INFO - 2015-12-17 09:05:06,462 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:67.70 Median:68.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 09:05:06 INFO - 2015-12-17 09:05:06,462 DEBUG : BROWSER_OUTPUT: Values: 68.0 67.0 68.0 67.0 69.0 68.0 67.0 67.0 68.0 68.0 09:05:06 INFO - 2015-12-17 09:05:06,463 DEBUG : BROWSER_OUTPUT: 09:05:06 INFO - 2015-12-17 09:05:06,463 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:84.00 stddev:3.20 (3.8%) stddev-sans-first:3.39 09:05:06 INFO - 2015-12-17 09:05:06,463 DEBUG : BROWSER_OUTPUT: Values: 85.0 83.0 93.0 83.0 84.0 83.0 84.0 83.0 84.0 88.0 09:05:06 INFO - 2015-12-17 09:05:06,463 DEBUG : BROWSER_OUTPUT: 09:05:06 INFO - 2015-12-17 09:05:06,463 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:125.30 Median:125.00 stddev:4.97 (4.0%) stddev-sans-first:5.12 09:05:06 INFO - 2015-12-17 09:05:06,464 DEBUG : BROWSER_OUTPUT: Values: 122.0 130.0 124.0 136.0 121.0 129.0 122.0 122.0 121.0 126.0 09:05:06 INFO - 2015-12-17 09:05:06,464 DEBUG : BROWSER_OUTPUT: 09:05:06 INFO - 2015-12-17 09:05:06,464 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.20 Median:149.50 stddev:6.39 (4.3%) stddev-sans-first:5.85 09:05:06 INFO - 2015-12-17 09:05:06,464 DEBUG : BROWSER_OUTPUT: Values: 137.0 146.0 149.0 150.0 150.0 150.0 147.0 155.0 144.0 134.0 09:05:06 INFO - 2015-12-17 09:05:06,464 DEBUG : BROWSER_OUTPUT: 09:05:06 INFO - 2015-12-17 09:05:06,464 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:70.00 stddev:1.16 (1.7%) stddev-sans-first:1.20 09:05:06 INFO - 2015-12-17 09:05:06,464 DEBUG : BROWSER_OUTPUT: Values: 69.0 70.0 70.0 68.0 70.0 68.0 72.0 70.0 70.0 70.0 09:05:06 INFO - 2015-12-17 09:05:06,464 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:05:06 INFO - 2015-12-17 09:05:06,464 DEBUG : BROWSER_OUTPUT: 09:05:07 INFO - 2015-12-17 09:05:07,348 INFO : Browser exited with error code: 0 09:05:07 INFO - 2015-12-17 09:05:07,397 INFO : Completed test kraken (00:01:01) 09:05:07 INFO - 2015-12-17 09:05:07,397 INFO : Starting test v8_7 09:05:07 INFO - 2015-12-17 09:05:07,397 DEBUG : operating with platform_type : linux_ 09:05:07 INFO - 2015-12-17 09:05:07,397 INFO : Initialising browser for v8_7 test... 09:05:07 INFO - 2015-12-17 09:05:07,403 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJUVfmH/profile http://localhost:42524/getInfo.html 09:05:11 INFO - 2015-12-17 09:05:11,846 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 09:05:11 INFO - 2015-12-17 09:05:11,847 DEBUG : BROWSER_OUTPUT: colorDepth:24 09:05:11 INFO - 2015-12-17 09:05:11,847 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 09:05:11 INFO - 2015-12-17 09:05:11,847 DEBUG : BROWSER_OUTPUT: __metrics 09:05:11 INFO - 2015-12-17 09:05:11,968 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] 09:05:12 INFO - 2015-12-17 09:05:12,828 INFO : Browser initialized. 09:05:12 INFO - 2015-12-17 09:05:12,828 INFO : Running cycle 1/1 for v8_7 test... 09:05:12 INFO - 2015-12-17 09:05:12,829 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJUVfmH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 09:05:24 INFO - 2015-12-17 09:05:24,824 DEBUG : BROWSER_OUTPUT: RSS: Main: 151064576 09:05:24 INFO - 2015-12-17 09:05:24,825 DEBUG : BROWSER_OUTPUT: 09:05:46 INFO - 2015-12-17 09:05:46,262 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42524/tests/v8_7/run.html (next: http://localhost:42524/tests/v8_7/run.html) 09:05:46 INFO - 2015-12-17 09:05:46,508 DEBUG : BROWSER_OUTPUT: RSS: Main: 145608704 09:05:46 INFO - 2015-12-17 09:05:46,508 DEBUG : BROWSER_OUTPUT: 09:05:46 INFO - 2015-12-17 09:05:46,508 DEBUG : BROWSER_OUTPUT: __start_tp_report 09:05:46 INFO - 2015-12-17 09:05:46,508 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 09:05:46 INFO - 2015-12-17 09:05:46,509 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 09:05:46 INFO - 2015-12-17 09:05:46,509 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 09:05:46 INFO - 2015-12-17 09:05:46,509 DEBUG : BROWSER_OUTPUT: |0;Richards;165.0709805216243 09:05:46 INFO - 2015-12-17 09:05:46,509 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;184.53589223103893 09:05:46 INFO - 2015-12-17 09:05:46,509 DEBUG : BROWSER_OUTPUT: |2;Encrypt;277.623542476402 09:05:46 INFO - 2015-12-17 09:05:46,509 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5191.709844559586 09:05:46 INFO - 2015-12-17 09:05:46,509 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1089.3246187363834 09:05:46 INFO - 2015-12-17 09:05:46,509 DEBUG : BROWSER_OUTPUT: |5;Earley;707.7140835102618 09:05:46 INFO - 2015-12-17 09:05:46,510 DEBUG : BROWSER_OUTPUT: |6;Boyer;12011.904761904761 09:05:46 INFO - 2015-12-17 09:05:46,510 DEBUG : BROWSER_OUTPUT: |7;RegExp;25897.4358974359 09:05:46 INFO - 2015-12-17 09:05:46,510 DEBUG : BROWSER_OUTPUT: |8;Splay;602.4096385542168 09:05:46 INFO - 2015-12-17 09:05:46,510 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4850.241545893719 09:05:46 INFO - 2015-12-17 09:05:46,510 DEBUG : BROWSER_OUTPUT: __end_tp_report 09:05:46 INFO - 2015-12-17 09:05:46,510 DEBUG : BROWSER_OUTPUT: __startTimestamp1450371946507__endTimestamp 09:05:46 INFO - 2015-12-17 09:05:46,510 DEBUG : BROWSER_OUTPUT: 09:05:46 INFO - 2015-12-17 09:05:46,512 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 09:05:46 INFO - 2015-12-17 09:05:46,512 DEBUG : BROWSER_OUTPUT: Number of tests: 10 09:05:46 INFO - 2015-12-17 09:05:46,512 DEBUG : BROWSER_OUTPUT: 09:05:46 INFO - 2015-12-17 09:05:46,512 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.07 Median:165.07 stddev:0.00 (0.0%) 09:05:46 INFO - 2015-12-17 09:05:46,512 DEBUG : BROWSER_OUTPUT: Values: 165.1 09:05:46 INFO - 2015-12-17 09:05:46,512 DEBUG : BROWSER_OUTPUT: 09:05:46 INFO - 2015-12-17 09:05:46,512 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:184.54 Median:184.54 stddev:0.00 (0.0%) 09:05:46 INFO - 2015-12-17 09:05:46,512 DEBUG : BROWSER_OUTPUT: Values: 184.5 09:05:46 INFO - 2015-12-17 09:05:46,512 DEBUG : BROWSER_OUTPUT: 09:05:46 INFO - 2015-12-17 09:05:46,512 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:277.62 Median:277.62 stddev:0.00 (0.0%) 09:05:46 INFO - 2015-12-17 09:05:46,512 DEBUG : BROWSER_OUTPUT: Values: 277.6 09:05:46 INFO - 2015-12-17 09:05:46,512 DEBUG : BROWSER_OUTPUT: 09:05:46 INFO - 2015-12-17 09:05:46,512 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5191.71 Median:5191.71 stddev:0.00 (0.0%) 09:05:46 INFO - 2015-12-17 09:05:46,513 DEBUG : BROWSER_OUTPUT: Values: 5191.7 09:05:46 INFO - 2015-12-17 09:05:46,513 DEBUG : BROWSER_OUTPUT: 09:05:46 INFO - 2015-12-17 09:05:46,513 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1089.32 Median:1089.32 stddev:0.00 (0.0%) 09:05:46 INFO - 2015-12-17 09:05:46,513 DEBUG : BROWSER_OUTPUT: Values: 1089.3 09:05:46 INFO - 2015-12-17 09:05:46,513 DEBUG : BROWSER_OUTPUT: 09:05:46 INFO - 2015-12-17 09:05:46,513 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:707.71 Median:707.71 stddev:0.00 (0.0%) 09:05:46 INFO - 2015-12-17 09:05:46,513 DEBUG : BROWSER_OUTPUT: Values: 707.7 09:05:46 INFO - 2015-12-17 09:05:46,513 DEBUG : BROWSER_OUTPUT: 09:05:46 INFO - 2015-12-17 09:05:46,513 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12011.90 Median:12011.90 stddev:0.00 (0.0%) 09:05:46 INFO - 2015-12-17 09:05:46,513 DEBUG : BROWSER_OUTPUT: Values: 12011.9 09:05:46 INFO - 2015-12-17 09:05:46,513 DEBUG : BROWSER_OUTPUT: 09:05:46 INFO - 2015-12-17 09:05:46,513 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25897.44 Median:25897.44 stddev:0.00 (0.0%) 09:05:46 INFO - 2015-12-17 09:05:46,513 DEBUG : BROWSER_OUTPUT: Values: 25897.4 09:05:46 INFO - 2015-12-17 09:05:46,513 DEBUG : BROWSER_OUTPUT: 09:05:46 INFO - 2015-12-17 09:05:46,513 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:602.41 Median:602.41 stddev:0.00 (0.0%) 09:05:46 INFO - 2015-12-17 09:05:46,513 DEBUG : BROWSER_OUTPUT: Values: 602.4 09:05:46 INFO - 2015-12-17 09:05:46,514 DEBUG : BROWSER_OUTPUT: 09:05:46 INFO - 2015-12-17 09:05:46,514 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4850.24 Median:4850.24 stddev:0.00 (0.0%) 09:05:46 INFO - 2015-12-17 09:05:46,514 DEBUG : BROWSER_OUTPUT: Values: 4850.2 09:05:46 INFO - 2015-12-17 09:05:46,514 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 09:05:46 INFO - 2015-12-17 09:05:46,514 DEBUG : BROWSER_OUTPUT: 09:05:47 INFO - 2015-12-17 09:05:47,384 INFO : Browser exited with error code: 0 09:05:47 INFO - 2015-12-17 09:05:47,401 INFO : Completed test v8_7 (00:00:40) 09:05:47 INFO - 2015-12-17 09:05:47,489 INFO : Completed test suite (00:20:15) 09:05:47 INFO - 2015-12-17 09:05:47,489 DEBUG : Working with test: dromaeo_css 09:05:47 INFO - 2015-12-17 09:05:47,489 DEBUG : Generating results file: dromaeo_css 09:05:47 INFO - 2015-12-17 09:05:47,491 DEBUG : Working with test: kraken 09:05:47 INFO - 2015-12-17 09:05:47,491 DEBUG : Generating results file: kraken 09:05:47 INFO - 2015-12-17 09:05:47,491 INFO : javascript benchmark 09:05:47 INFO - 2015-12-17 09:05:47,491 DEBUG : Working with test: v8_7 09:05:47 INFO - 2015-12-17 09:05:47,492 DEBUG : Generating results file: v8_7 09:05:47 INFO - 2015-12-17 09:05:47,492 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:05:47 INFO - 2015-12-17 09:05:47,641 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,94,43]] 09:05:47 INFO - 2015-12-17 09:05:47,641 DEBUG : process_Request line: dromaeo_css 6405.61 graph.html#tests=[[72,94,43]] 09:05:47 INFO - 2015-12-17 09:05:47,641 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:05:47 INFO - 2015-12-17 09:05:47,671 DEBUG : process_Request line: kraken 1533.10 graph.html#tests=[[232,94,43]] 09:05:47 INFO - 2015-12-17 09:05:47,671 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 09:05:47 INFO - 2015-12-17 09:05:47,702 DEBUG : process_Request line: v8_7 21412.82 graph.html#tests=[[230,94,43]] 09:05:47 INFO - 2015-12-17 09:05:47,703 INFO : javascript benchmark 09:05:47 INFO - 2015-12-17 09:05:47,707 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2656.1765852585695, 2683.61087642292, 2714.0636480414264, 2727.809053366264, 2732.0164740693353, 4585.060062687149, 4602.240630296002, 4621.445426548815, 4644.674872758904, 4669.066186762644, 4689.718616882989, 4710.3405523226775, 4715.104130215257, 4784.5693887550115, 4807.735574543399, 4325.264705000158, 4354.825806967726, 4498.077894031732, 4499.572540608637, 4531.6601254905845, 4239.448871646681, 4405.581469760367, 4435.933460998088, 4465.955340446587, 4536.432945881765, 4258.5954334338185, 4319.244132276839, 4323.3947247385395, 4343.84796532123, 4349.521552629209, 4335.9566404335865, 4346.56534346566, 4366.519682834885, 4368.825246990148, 4369.978150109229, 4158.688098392633, 4268.59448352406, 4321.6326612238, 4340.696151269412, 4379.8467053653285, 4581.473130589991, 4627.791749371287, 4631.884202894921, 4654.976725116387, 4668.719876807402, 5128.307678463397, 5159.942211846563, 5160.48395160481, 5174.508421699932, 5181.922271165936, 4947.604191664658, 5088.567471764904, 5102.183650615883, 5187.247849061865, 5266.078436273675, 4713.1280713067845, 4787.042591481648, 4812.8796780080775, 4820.373351464287, 4820.493848145929, 5280.6039547034125, 5295.179247216687, 5303.257543943774, 5325.0, 5387.892242155213, 4821.228603423436, 5091.185410334403, 5099.923501147486, 5111.514406131411, 5118.053160165381, 4977.129002424634, 5024.723640199824, 5059.671121377172, 5070.847874563769, 5113.795448182105, 5052.292679024939, 5058.974705126524, 5060.342155519759, 5065.949340506621, 5101.362329708786, 4734.786934587952, 4857.781399837016, 5027.547520723152, 5036.370453693288, 5042.924356134661, 4172.520160181557, 4227.619514243733, 4232.174725928476, 4257.340112282601, 4274.679399045084, 3711.9443208351895, 3716.349638813197, 3731.253749250161, 3745.569259535134, 3769.962300377016, 4196.832126714959, 4290.056187638715, 4292.742435453821, 4402.493713223021, 4408.647308215335, 5138.743062846843, 5138.871528211825, 5169.353830771153, 5177.430482646906, 5177.844664660066, 4413.073254616503, 4416.094700586372, 4432.157890000954, 4444.911101777946, 4481.857126432804, 4324.56754324565, 4433.357163211316, 4450.488193857683, 4550.431196100488, 4563.06457176278, 4835.178019736582, 4837.153498137882, 4840.806367745321, 4856.295837103671, 4858.076965376567, 5125.231215317732, 5137.486251374982, 5145.408278047923, 5147.794088236428, 5172.120739474373, 4698.7415692137265, 4769.117713223065, 4824.975875120602, 4878.707277563358, 4906.8773280668265, 5071.594272458268, 5077.517635824591, 5078.898422031465, 5087.949120508895, 5119.308893299357, 4861.878453038561, 5163.251328557413, 5168.870778230575, 5172.9741351293005, 5174.844754657366, 4833.975830120827, 4858.368412106545, 4902.774306423395, 4915.1644220481885, 4953.133201689618, 4988.451270360185, 5026.924596130988, 5040.899182016413, 5043.798248070036, 5088.491150884882, 4562.406887104655, 4720.976395118002, 4927.088488629615, 4948.653594248368, 4952.727599981891, 4959.752012399438, 4979.203327467732, 5020.698758074527, 5026.899462010812, 5055.039542487063, 4836.637252206028, 4851.126797176542, 4863.367762190893, 4917.754112294442, 4954.900901981868], "unit": "score", "name": "dojo.html", "value": 4665.404601413861}, {"lowerIsBetter": false, "replicates": [531.7660229499022, 550.3835704011508, 558.7625259264811, 559.6753882748006, 568.8549419897925, 26958.19125426238, 35120.47319290209, 35594.2881142377, 35633.46549801755, 35767.28465430696, 40842.183156336854, 41037.58962410375, 41185.7940710296, 41242.7937860311, 41250.58749412505, 7953.204679532058, 8116.999999999971, 8144.6334914928675, 8166.428350015502, 8174.386920980921, 7894.289513943744, 8007.759767206964, 8022.638981245858, 8024.438289319721, 8031.196880311981, 7955.323797477221, 8016.078151012621, 8023.839523209533, 8044.879326810103, 8080.878786818203, 7952.522848629072, 7966.601669916539, 7981.521108733495, 8029.0, 8043.879341809878, 7870.803229919241, 7987.520748755035, 7993.72021979228, 8007.758797386415, 8007.879881801778, 36017.819910900536, 36417.63582364169, 36520.817395913116, 36580.81709591435, 36720.81639591785, 2709.5935609658513, 2820.868894830212, 2824.124521398373, 2851.985740071307, 3607.9098022549383, 27511.499631613606, 27974.580381294094, 28485.85757071222, 29001.274968125756, 29182.978595749046, 3391.5251864738957, 3410.556627638391, 3445.844936977842, 3472.6700963408434, 3475.2701932594186, 210192.0, 211530.0, 211953.0, 212778.0, 212870.0, 47863.521364785905, 51233.23150152751, 53280.0, 60251.698741506014, 61531.38468615257, 53256.73371633117, 53265.53774004677, 53390.7330463353, 53505.0, 53659.73170134125, 13368.462626798042, 13506.05457617977, 13541.187528748109, 13596.796048059286, 13625.318734063258, 9919.851202231972, 10014.198864090855, 10093.192544596415, 10166.694999150037, 10292.433916134685, 18543.0, 18664.160112794958, 18714.344997925007, 18728.15723292455, 18777.530561736065, 7183.89224161638, 7303.598302093435, 7343.118825740945, 7434.070741157356, 7494.325510704063, 880.5993273060742, 881.3742243007537, 907.2197909797569, 916.3814425262921, 918.8759517465056, 26172.476550468884, 26422.47155056888, 26548.60177097345, 26552.468950621267, 26746.465070698476, 932.6176267730198, 960.0207788056143, 975.3075316525346, 1017.7862648843677, 1040.5837664933936, 13620.114692544952, 13972.0, 14057.85942140586, 14068.577942661737, 14143.151410915378, 267600.0, 273523.0, 275544.0, 276205.0, 276749.0, 45656.3151552727, 45991.77004115025, 46007.539924601, 46049.53950460452, 46055.53944460512, 1231.5258625429274, 1240.3240234072452, 1263.1663102352404, 1266.639007882749, 1270.0411189551828, 25389.61915571231, 26072.73927260779, 26444.338891527867, 26454.0, 26696.332591685365, 40563.39154912738, 40750.79624601858, 41137.58862411338, 41153.176936460506, 41211.793941031305, 249865.0, 252459.0, 252517.0, 252811.0, 253041.0, 28655.57016644794, 28831.0, 29125.41749165047, 29445.0, 29533.26166845846, 33768.3246335067, 34098.829505853304, 34335.656643434246, 34368.82815585906, 37648.24703505969, 28860.855695721388, 29293.414131717673, 29445.0, 29562.852185738935, 29822.10533683993, 28733.24975901682, 28899.711002890544, 29082.0, 29168.515351981478, 29339.559906600993, 28739.425211496073, 29325.120246392642, 29571.408571828873, 29605.85197074087, 29638.55542166826, 27682.0, 28932.27669308284, 29811.254718632208, 30816.53775193419, 30861.228469288446, 21230.0, 21661.675074873576, 23451.0, 23843.761562384156, 24118.276451706475], "unit": "score", "name": "ext.html", "value": 16556.542080750092}, {"lowerIsBetter": false, "replicates": [322.26684293232904, 323.29242593281265, 327.0809026635154, 327.12004707337263, 327.86393646636645, 15087.09477431355, 15515.3018114185, 15971.0, 16158.111303878297, 16620.58448538784, 10459.529321180546, 10491.68524944253, 10544.894551054487, 12540.93729531351, 15291.464798732046, 4553.385292985437, 4563.771811409433, 4656.97671511642, 4680.227762419214, 4681.110588988098, 4552.157850797613, 4609.907801843961, 4611.700239484423, 4613.1696294666945, 4616.422947131608, 4576.222042252825, 4603.7237765734, 6110.816675499742, 6328.810135695937, 6347.650879201642, 6192.009278515438, 6339.809805705836, 6352.110704501373, 6648.5678430901835, 6686.665666716644, 6367.0, 6371.267304260023, 6382.170317858649, 6390.488760899117, 6404.4876409887565, 20607.484812879648, 21815.890920545455, 21970.780292197032, 21981.780182198294, 22047.77952220473, 1882.3317722208692, 1931.2564662604857, 2004.4688157638238, 2026.1490174126902, 2096.5911647228795, 13663.590092297147, 13746.381412836346, 13887.861121388758, 14066.030755694212, 14318.42726290947, 1881.8494520438333, 1910.0831600831539, 1945.2413558712115, 1948.6492431362349, 1980.8811471311624, 61431.69284153595, 63927.680361598825, 64292.35707642911, 65386.3461365385, 65591.0, 14865.79697106026, 15515.767263491056, 15525.611859703598, 15545.766813497807, 15549.66754986761, 22815.543689126123, 22940.426489337566, 22977.195798146986, 23270.883645581664, 23347.41631459193, 8795.736127916172, 8806.20744133031, 8831.116888311246, 8844.204021638083, 8845.955770221108, 7348.191698913115, 7371.152317483558, 7384.815379615553, 7390.926090739132, 7393.55638661682, 7688.654010569538, 7692.307692307607, 7702.614869256515, 7772.300492955661, 7806.18035106317, 1746.755454236408, 1841.302491849447, 3075.8469071649934, 3106.98446507766, 3265.4448743713624, 936.3960245641523, 957.167264479907, 957.875476188105, 967.5307475874196, 991.8908920018937, 16168.0, 16640.0, 16853.15734213309, 17488.562785930455, 17506.03716795563, 862.9007664118707, 864.4769914201943, 930.916217540411, 941.5621735892843, 961.2262128986093, 8424.031236407769, 8518.23335899772, 8619.87070193935, 8683.696070637498, 8851.778705532284, 471873.0, 473020.0, 474884.0, 478035.0, 483295.0, 72397.0, 74118.0, 74358.6282068597, 75001.0, 78389.0, 1094.0809719835217, 1105.3588918427292, 1143.228320883417, 1155.3530023187043, 1155.7284038251005, 13682.452701891812, 13716.245606491739, 13721.656958575717, 13724.245166515937, 13965.231912244924, 31551.21121971969, 31735.84132079325, 31914.52128218125, 32684.50973235356, 32864.50703239501, 45529.0, 46360.0, 47764.52235477601, 49498.76253093557, 50381.99236015332, 8458.788530286793, 8765.167309105625, 8876.733697989072, 8945.552722363986, 8995.730128096167, 10239.692809215736, 10942.398168100832, 12696.6825829355, 16369.263383148025, 16608.66782664316, 10685.358878467317, 13378.732425351633, 13456.730865382833, 13785.724285514434, 14023.298835058411, 10786.838197426889, 13143.671408214872, 13526.86473135256, 13772.449102035805, 14056.367463464374, 14213.64465888361, 14254.144751314954, 14568.198749068903, 14578.198199099152, 14672.853271467575, 8861.291096712377, 8874.467531948103, 8877.911220887709, 8959.507227102571, 9154.359194856295, 13172.407241673956, 13318.067735258439, 13692.726145477234, 13938.163710177421, 14304.998650094394], "unit": "score", "name": "jquery.html", "value": 9149.970643051925}, {"lowerIsBetter": false, "replicates": [1480.0749531542785, 1630.3397124164715, 1678.8153303136653, 1712.1952682239346, 1719.1490212344884, 6184.350643182469, 6360.586561873475, 6667.366600172982, 7135.821604459898, 7143.178534468531, 6795.218549866767, 6905.136857892763, 7019.087518622585, 7020.157581090263, 7030.332118448752, 2040.4898775306174, 2082.0422605601443, 2082.8437867159946, 2083.2396175395947, 2089.1121273458793, 2070.1201989154624, 2086.7495900491963, 2102.4743814046487, 2106.2101711858054, 2113.0596884386455, 2068.596623658387, 2070.130545171032, 2079.105984426696, 2084.458040909359, 2084.81236688698, 2052.1688716070016, 2061.9793802061936, 2067.7105205271273, 2070.2133189388087, 2074.6161960037443, 2046.8055534724178, 2068.152057656369, 2100.705901173837, 2119.173522326294, 2120.024788597247, 6826.0, 6868.141482314711, 6878.071460352839, 6891.345322194384, 6893.241743408221, 4178.892593462735, 4212.726172798789, 4212.85255016073, 4217.578242175789, 4252.808623611945, 2560.7823335016546, 2596.0, 2603.8437693738438, 2721.1564415031207, 2721.6053672217417, 859.2224037246302, 863.4948555095277, 880.2869675562814, 881.5460038080394, 890.1676932068472, 9827.4103553787, 9840.0, 9840.0, 9859.112679858847, 9901.603935842557, 3865.6520913117392, 3889.747166434173, 3929.8035098244973, 4000.119973605802, 4004.5594984551676, 8327.0, 8371.665133394717, 8444.493330400073, 8478.06741258461, 8483.95758021206, 4278.7860606969525, 4280.315149576053, 4295.463067116611, 4296.37702533131, 4299.462567179103, 3266.1671273825023, 3338.6995170434293, 3707.7589956652732, 3896.8441262349756, 3913.2369188008065, 3417.384870723244, 3489.6510348965408, 3501.562304711911, 3508.649135086471, 3527.0829584308262, 1120.7198200449889, 1123.556195302867, 1127.115214556569, 1128.9435528223555, 1171.2503997441645, 881.2685471059134, 901.8196360727881, 1164.8427462292652, 1166.393475392791, 1187.821826725998, 6729.125213722283, 6769.526133170631, 6771.762988295484, 6810.080639113756, 6811.318868113248, 1048.0, 1051.363924825485, 1053.1206442620344, 1068.156156636264, 1071.7534966957487, 5308.840734777886, 5319.148936170194, 5368.651037682538, 5370.731463426813, 5405.594580406485, 8552.786180345542, 8558.57207139653, 8818.29453643707, 8866.86699699505, 8900.510471923977, 8670.349723770616, 8712.564371781385, 8726.520041397784, 8744.475331480131, 8756.956215218883, 6769.323067693192, 6811.693473793691, 6823.795286141324, 6859.0, 6931.480138989495, 6479.1901012373455, 6543.869122617616, 6551.755166518346, 6591.802245932629, 6623.536352455475, 7615.619219039137, 7699.191584883731, 7875.369970402239, 7938.0, 7960.442769006114, 7959.243871832166, 7968.84062318762, 8036.397270204875, 8082.474639148437, 8089.555074470725, 5203.141481655484, 5249.842504724865, 5439.265699130567, 5890.587658863838, 5954.166416701752, 6666.400023997863, 6685.6322902240845, 6706.128203333536, 6732.6297053660555, 6757.898631520629, 6331.303556608677, 6427.839304017437, 6473.320301368289, 6511.283758786624, 6529.249136349191, 6138.508919286535, 6293.874122517615, 6409.070684750778, 6516.609003459807, 6600.867982640416, 6523.282438931618, 6527.0, 6564.245111812203, 6572.178477690289, 6575.210974683068, 5804.332501762183, 5831.562632802642, 5846.503047241032, 5869.0, 6070.726817293321, 5782.2483077199695, 5803.65178089316, 5806.4193580641595, 5820.185174075717, 5838.0], "unit": "score", "name": "mootools.html", "value": 4106.152509141543}, {"lowerIsBetter": false, "replicates": [305.8470764617691, 307.50031199301134, 315.05326493885883, 315.56452096107375, 320.0143557841847, 5588.0, 5661.745221465034, 5677.6309539879985, 5704.686242256685, 5717.6283541569765, 5575.442455754423, 5576.9163462547995, 5576.972115139429, 5582.162675598663, 5597.160425936112, 4143.212789569972, 4224.493060832704, 4352.651787856964, 4352.651787856979, 4361.193179261831, 4233.576642335757, 4241.321388577828, 4268.765217913014, 4269.53035166133, 4274.273373526493, 4198.8110535026, 4200.3699445083175, 4215.683823713219, 4221.978890105561, 4227.788610569474, 4190.727602705814, 4199.1391764688315, 4216.3464662977285, 4222.366645003243, 4223.324268117087, 3593.2633810068874, 3617.764845285048, 3627.582827974764, 3638.727095467824, 3645.9609011431617, 5258.342707161605, 5350.063738845686, 5354.785808567653, 5366.9463305366835, 5372.328458942632, 4867.975660121712, 4871.318015477836, 4883.902321953541, 4884.780184891652, 4892.68197567157, 5894.793682221101, 5896.97051514744, 5901.0558310670085, 5903.232579764646, 5905.0, 3860.36304009838, 3971.8411263549424, 4003.3594624859884, 4005.8998525036814, 4007.198560287954, 6122.265328160604, 6169.04379821124, 6178.969105154445, 6183.288921773964, 6197.0704394340755, 4069.267531844237, 4071.328230841878, 4079.3676980068135, 4106.568810274938, 4109.91780164395, 4172.061286210578, 4174.519930208005, 4178.811953462102, 4180.393842892763, 4185.22573323936, 4691.812327506861, 4716.504766999485, 4834.492378300298, 4846.80612775485, 4848.8302909398, 5816.0, 5855.473007429352, 5860.824175274749, 5864.413558644102, 5872.265966754156, 1446.0383844743337, 1446.3997441062052, 1447.160646824839, 1447.5874375802846, 1458.6280498472822, 988.253868329421, 997.0265841044927, 1005.4369553050316, 1008.1884083312857, 1013.3767732844248, 5084.44071152173, 5091.949080509222, 5093.694378337312, 5094.2358646203365, 5108.438071812097, 6103.38966103395, 6112.541559383064, 6120.265568131853, 6144.262688477412, 6150.569460137837, 5097.1844504879045, 5106.055379754757, 5113.488651134931, 5125.589952803767, 5130.127878260705, 3194.4409728297455, 3269.983650081734, 3279.7048265655726, 3305.058058453329, 3312.1554003728893, 5750.166225897222, 5810.593258471867, 5812.070068789058, 5815.2149459822385, 5847.3275573308765, 6347.270063942613, 6357.237131544244, 6372.362763723683, 6383.2978372379, 6399.552031357854, 5619.185218143428, 5784.0, 5832.679202643895, 5837.357890632027, 5837.883242335214, 6130.63216207029, 6143.078538219303, 6159.507239420746, 6160.845978850564, 6173.938260617516, 5977.432143946318, 6001.039833626599, 6011.0382338825575, 6027.276726792813, 6031.819045428644, 5753.367129615823, 5795.565332599987, 5798.159266906359, 5812.9709351452975, 5827.8834423310445, 4008.178323443629, 4148.025214074751, 4154.979225103855, 4156.833726651021, 4185.937210941899, 6034.0, 6098.359672234352, 6102.359252278625, 6118.357572455006, 6166.321704612579, 5831.70841457917, 5906.497947674324, 5909.172715819703, 5911.083782013795, 5936.851578710567, 5875.118732190205, 5880.411958804086, 5886.58793884424, 5896.852578685567, 5903.498202652822, 5690.971545142414, 5733.0540460823495, 5758.3090029196765, 5761.884762304814, 5784.884302314014, 5924.822255332347, 5929.377415371324, 5954.464098231007, 5967.552433567586, 5972.850678733066, 5783.652980821164, 5793.739281732416, 5799.043157879071, 5807.128930660435, 5822.0], "unit": "score", "name": "prototype.html", "value": 4383.026410209656}, {"lowerIsBetter": false, "replicates": [583.2650859916504, 611.14439784302, 632.3486808587157, 723.5405517496389, 732.6666366803104, 11254.09967202624, 12510.437030333635, 12848.614541563767, 12866.678333041667, 12869.292188929629, 13061.477540898377, 13108.213507189552, 13179.472821087145, 13277.535286264983, 13289.269090200036, 620.5780069552702, 781.4646966827713, 785.2147852147853, 787.8266781308103, 792.9881051784228, 621.7575145693183, 778.3384123495041, 787.9172686867882, 791.4578513718093, 792.1800936031209, 808.337163525909, 810.3638643664694, 812.9024517057961, 817.0930267403178, 818.9181081891793, 799.828036972048, 806.4999700185894, 806.6329819931917, 807.3823525003377, 809.0251247247053, 607.8328459673581, 608.3277977834517, 608.6135304081896, 609.1471939284992, 609.576344440614, 11134.0, 11174.161937854613, 11189.832152517718, 11212.159088068427, 11267.323960562311, 10137.695869123938, 10155.238357123246, 10459.738506537322, 10465.424401657905, 10621.41582212978, 8553.44402613828, 8655.0, 8872.822543549157, 8916.152965468335, 8999.730008099768, 3066.218114380841, 3088.459519584086, 3110.471219892601, 3124.859381327823, 3150.0, 37953.81023094895, 38238.61761382406, 38364.0, 38514.42228366605, 38619.22761544782, 8116.147804480563, 8137.0, 8139.918600814034, 8208.138145494757, 8319.334453243726, 15282.617934551725, 15288.082715037133, 15466.458673946358, 15481.5355539334, 15489.457868974532, 6025.578209525379, 6120.755169793248, 6171.413715697002, 6174.722137503733, 6192.721327540272, 3350.162459385154, 3534.575850897909, 3550.928981420357, 3603.3333833240936, 3642.4354225095153, 2496.450780828215, 2520.7857332126973, 2523.7223905370392, 2527.380791706044, 2528.0772518031154, 452.1206253836257, 460.2681736039646, 465.3276016156621, 466.852941323482, 467.2873867352315, 418.6525184097192, 442.79631369569717, 445.6234481862821, 537.6102325813754, 543.6466296907042, 6576.210854697371, 6922.930770692329, 7717.421193410517, 7756.379489640815, 8066.878996815052, 444.1095603315397, 523.8821265215296, 539.4174291764884, 559.7844829740579, 580.077676494376, 3206.615206175274, 3413.6415676353627, 3464.9480257796154, 3505.298940211968, 3684.4841722158917, 47368.5263147371, 48045.279320810216, 48056.0, 48226.0, 48304.27543586849, 42041.0, 42555.36166957498, 43085.35371969423, 43312.3503147453, 43355.132897342504, 38966.61033389687, 39605.60394396077, 39699.80150099231, 39728.60271397249, 39999.400068991854, 6859.2454829969665, 7171.282871712787, 7369.889451658122, 7527.1343795462035, 7588.582627955516, 30808.845955770077, 30918.845405772827, 31257.37485250328, 31442.371152577278, 31593.0, 35230.29539409146, 35797.28405431825, 38607.0, 39555.80222098871, 39599.20801583894, 9324.860127098234, 9343.065693430874, 9480.099390557885, 9526.90473095288, 9561.139497445263, 10419.791604168026, 10660.733481663021, 10682.679519614425, 10686.946565267124, 11040.28238164549, 9678.661246856607, 9808.16630586408, 9814.362066465657, 9871.950640246752, 9922.900770992197, 10056.766451574142, 10078.848817267894, 10179.0, 10207.897921020694, 10287.22845786554, 9020.503872286825, 9681.467519286507, 10130.392176469435, 10317.123044541298, 10358.844617330595, 8448.056946902125, 8511.829763404821, 8671.132886711279, 8814.206721395105, 8845.955770221366, 7206.639668016683, 7485.513441626277, 7586.772396828104, 7701.075870895304, 7763.844723105393], "unit": "score", "name": "yui.html", "value": 5430.399354292158}], "name": "dromaeo_css", "value": 6405.613504185486}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [114.0, 108.0, 113.0, 114.0, 113.0, 125.0, 111.0, 114.0, 114.0, 114.0], "unit": "score", "name": "ai-astar", "value": 114.0}, {"lowerIsBetter": true, "replicates": [155.0, 134.0, 155.0, 173.0, 131.0, 154.0, 151.0, 144.0, 164.0, 132.0], "unit": "score", "name": "audio-beat-detection", "value": 149.3}, {"lowerIsBetter": true, "replicates": [163.0, 164.0, 163.0, 163.0, 163.0, 165.0, 167.0, 162.0, 165.0, 164.0], "unit": "score", "name": "audio-dft", "value": 163.9}, {"lowerIsBetter": true, "replicates": [73.0, 72.0, 72.0, 75.0, 75.0, 130.0, 72.0, 78.0, 73.0, 72.0], "unit": "score", "name": "audio-fft", "value": 79.2}, {"lowerIsBetter": true, "replicates": [94.0, 96.0, 130.0, 93.0, 95.0, 95.0, 132.0, 94.0, 95.0, 133.0], "unit": "score", "name": "audio-oscillator", "value": 105.7}, {"lowerIsBetter": true, "replicates": [110.0, 111.0, 110.0, 111.0, 110.0, 110.0, 111.0, 111.0, 111.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.5}, {"lowerIsBetter": true, "replicates": [147.0, 147.0, 147.0, 150.0, 148.0, 147.0, 148.0, 148.0, 147.0, 148.0], "unit": "score", "name": "imaging-darkroom", "value": 147.7}, {"lowerIsBetter": true, "replicates": [96.0, 97.0, 96.0, 97.0, 95.0, 96.0, 97.0, 97.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 96.3}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 73.0, 72.0, 77.0, 72.0, 72.0, 72.0, 72.0, 72.0], "unit": "score", "name": "json-parse-financial", "value": 72.6}, {"lowerIsBetter": true, "replicates": [68.0, 67.0, 68.0, 67.0, 69.0, 68.0, 67.0, 67.0, 68.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.7}, {"lowerIsBetter": true, "replicates": [85.0, 83.0, 93.0, 83.0, 84.0, 83.0, 84.0, 83.0, 84.0, 88.0], "unit": "score", "name": "stanford-crypto-aes", "value": 85.0}, {"lowerIsBetter": true, "replicates": [122.0, 130.0, 124.0, 136.0, 121.0, 129.0, 122.0, 122.0, 121.0, 126.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 125.3}, {"lowerIsBetter": true, "replicates": [137.0, 146.0, 149.0, 150.0, 150.0, 150.0, 147.0, 155.0, 144.0, 134.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 146.2}, {"lowerIsBetter": true, "replicates": [69.0, 70.0, 70.0, 68.0, 70.0, 68.0, 72.0, 70.0, 70.0, 70.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 69.7}], "name": "kraken", "value": 1533.1}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.0709805216243], "unit": "score", "name": "Richards", "value": 213.85951599999999}, {"lowerIsBetter": false, "replicates": [184.53589223103893], "unit": "score", "name": "DeltaBlue", "value": 358.2934420000001}, {"lowerIsBetter": false, "replicates": [277.623542476402], "unit": "score", "name": "Encrypt", "value": 958.7839620000002}, {"lowerIsBetter": false, "replicates": [5191.709844559586], "unit": "score", "name": "Decrypt", "value": 51.270392215568904}, {"lowerIsBetter": false, "replicates": [1089.3246187363834], "unit": "score", "name": "RayTrace", "value": 679.3099019999999}, {"lowerIsBetter": false, "replicates": [707.7140835102618], "unit": "score", "name": "Earley", "value": 941.7122190000003}, {"lowerIsBetter": false, "replicates": [12011.904761904761], "unit": "score", "name": "Boyer", "value": 55.483540138751295}, {"lowerIsBetter": false, "replicates": [25897.4358974359], "unit": "score", "name": "RegExp", "value": 35.17664851485149}, {"lowerIsBetter": false, "replicates": [602.4096385542168], "unit": "score", "name": "Splay", "value": 135.27506}, {"lowerIsBetter": false, "replicates": [4850.241545893719], "unit": "score", "name": "NavierStokes", "value": 305.9641434262948}], "name": "v8_7", "value": 21412.819475269232}]} 09:05:47 INFO - RETURN: dromaeo_css: 6405.61 09:05:47 INFO - RETURN: kraken: 1533.10 09:05:47 INFO - RETURN: v8_7: 21412.82 09:05:47 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,94,43]]", "result": "21412.82"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,94,43]]", "result": "1533.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,94,43]]", "result": "6405.61"}}} 09:05:47 INFO - Return code: 0 09:05:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:05:47 INFO - # TBPL SUCCESS # 09:05:47 INFO - Running post-action listener: _resource_record_post_action 09:05:47 INFO - Running post-run listener: _resource_record_post_run 09:05:48 INFO - Total resource usage - Wall time: 1232s; CPU: 13.0%; Read bytes: 4096; Write bytes: 557428736; Read time: 8; Write time: 459348 09:05:48 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 238551040; Read time: 0; Write time: 228532 09:05:48 INFO - run-tests - Wall time: 1217s; CPU: 13.0%; Read bytes: 4096; Write bytes: 318013440; Read time: 8; Write time: 230784 09:05:48 INFO - Running post-run listener: _upload_blobber_files 09:05:48 INFO - Blob upload gear active. 09:05:48 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 09:05:48 INFO - Copying logs to upload dir... 09:05:48 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1269.102318 ========= master_lag: 12.63 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2015-12-17 09:06:00.818817) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-17 09:06:00.822602) ========= 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=6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/GYPWd6pbSlChOmtEtjqrEQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009959 build_url: 'https://queue.taskcluster.net/v1/task/GYPWd6pbSlChOmtEtjqrEQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.24 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-17 09:06:01.077289) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:06:01.077782) ========= 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=6d10e77667a3a93f6a3fb0d0000001b0-1450370216.52634-1256515637 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004278 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-17 09:06:01.100782) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-17 09:06:01.106247) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-17 09:06:01.106754) ========= ========= Total master_lag: 13.04 =========