builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-020 starttime: 1461564401.19 results: success (0) buildid: 20160424203035 builduid: b9c18d5e79154e3582c0ed4d36867f73 revision: 3cfe3ba1ed21c24a62d442f5645afb0fb6ed5e59 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:41.187908) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:41.188299) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:41.188590) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:41.213119) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:41.213449) ========= 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=af724c981e2a0f424dbabf99000001e7-1461561215.764862-835822559 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-24 23:06:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 46.2M=0s 2016-04-24 23:06:43 (46.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106878 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:41.335583) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:41.335872) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=af724c981e2a0f424dbabf99000001e7-1461561215.764862-835822559 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016912 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:41.391349) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:41.391730) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 3cfe3ba1ed21c24a62d442f5645afb0fb6ed5e59 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 3cfe3ba1ed21c24a62d442f5645afb0fb6ed5e59 --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=af724c981e2a0f424dbabf99000001e7-1461561215.764862-835822559 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-24 23:06:43,409 truncating revision to first 12 chars 2016-04-24 23:06:43,409 Setting DEBUG logging. 2016-04-24 23:06:43,409 attempt 1/10 2016-04-24 23:06:43,409 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/3cfe3ba1ed21?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-24 23:06:43,886 unpacking tar archive at: fx-team-3cfe3ba1ed21/testing/mozharness/ program finished with exit code 0 elapsedTime=0.761500 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:42.169996) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:42.170307) ========= script_repo_revision: 3cfe3ba1ed21c24a62d442f5645afb0fb6ed5e59 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:42.170763) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:42.171052) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-24 23:06:42.179857) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 25 mins, 39 secs) (at 2016-04-24 23:06:42.180294) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=af724c981e2a0f424dbabf99000001e7-1461561215.764862-835822559 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:06:44 INFO - MultiFileLogger online at 20160424 23:06:44 in /builds/slave/test 23:06:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:06:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:06:44 INFO - {'append_to_log': False, 23:06:44 INFO - 'base_work_dir': '/builds/slave/test', 23:06:44 INFO - 'blob_upload_branch': 'Fx-Team', 23:06:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:06:44 INFO - 'branch': 'Fx-Team', 23:06:44 INFO - 'buildbot_json_path': 'buildprops.json', 23:06:44 INFO - 'config_files': ('talos/linux_config.py',), 23:06:44 INFO - 'default_actions': ('clobber', 23:06:44 INFO - 'read-buildbot-config', 23:06:44 INFO - 'download-and-extract', 23:06:44 INFO - 'populate-webroot', 23:06:44 INFO - 'create-virtualenv', 23:06:44 INFO - 'install', 23:06:44 INFO - 'run-tests'), 23:06:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:06:44 INFO - 'download_minidump_stackwalk': True, 23:06:44 INFO - 'download_symbols': 'ondemand', 23:06:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:06:44 INFO - 'tooltool.py': '/tools/tooltool.py', 23:06:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:06:44 INFO - '/tools/misc-python/virtualenv.py')}, 23:06:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:06:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:06:44 INFO - 'installer_path': 'installer.exe', 23:06:44 INFO - 'log_level': 'info', 23:06:44 INFO - 'log_name': 'talos', 23:06:44 INFO - 'log_to_console': True, 23:06:44 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:06:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:06:44 INFO - 'opt_config_files': (), 23:06:44 INFO - 'pip_index': False, 23:06:44 INFO - 'sps_profile': False, 23:06:44 INFO - 'sps_profile_interval': 0, 23:06:44 INFO - 'suite': 'dromaeojs', 23:06:44 INFO - 'system_bits': '32', 23:06:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:06:44 INFO - 'title': 'talos-linux64-ix-020', 23:06:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:06:44 INFO - 'use_talos_json': True, 23:06:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:06:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:06:44 INFO - 'work_dir': 'build'} 23:06:44 INFO - ##### 23:06:44 INFO - ##### Running clobber step. 23:06:44 INFO - ##### 23:06:44 INFO - Running pre-action listener: _resource_record_pre_action 23:06:44 INFO - Running main action method: clobber 23:06:44 INFO - rmtree: /builds/slave/test/build 23:06:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:06:45 INFO - Running post-action listener: _resource_record_post_action 23:06:45 INFO - ##### 23:06:45 INFO - ##### Running read-buildbot-config step. 23:06:45 INFO - ##### 23:06:45 INFO - Running pre-action listener: _resource_record_pre_action 23:06:45 INFO - Running main action method: read_buildbot_config 23:06:45 INFO - Using buildbot properties: 23:06:45 INFO - { 23:06:45 INFO - "project": "", 23:06:45 INFO - "product": "firefox", 23:06:45 INFO - "script_repo_revision": "production", 23:06:45 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 23:06:45 INFO - "repository": "", 23:06:45 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 23:06:45 INFO - "buildid": "20160424203035", 23:06:45 INFO - "pgo_build": "True", 23:06:45 INFO - "basedir": "/builds/slave/test", 23:06:45 INFO - "buildnumber": 1112, 23:06:45 INFO - "slavename": "talos-linux64-ix-020", 23:06:45 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 23:06:45 INFO - "platform": "ubuntu64_hw", 23:06:45 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 23:06:45 INFO - "branch": "fx-team", 23:06:45 INFO - "repo_path": "integration/fx-team", 23:06:45 INFO - "revision": "3cfe3ba1ed21c24a62d442f5645afb0fb6ed5e59", 23:06:45 INFO - "stage_platform": "linux64-pgo", 23:06:45 INFO - "builduid": "b9c18d5e79154e3582c0ed4d36867f73", 23:06:45 INFO - "slavebuilddir": "test" 23:06:45 INFO - } 23:06:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2. 23:06:45 INFO - Running post-action listener: _resource_record_post_action 23:06:45 INFO - ##### 23:06:45 INFO - ##### Running download-and-extract step. 23:06:45 INFO - ##### 23:06:45 INFO - Running pre-action listener: _resource_record_pre_action 23:06:45 INFO - Running main action method: download_and_extract 23:06:45 INFO - mkdir: /builds/slave/test/build/tests 23:06:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:06:45 INFO - https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:06:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 23:06:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 23:06:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 23:06:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 23:06:56 INFO - Downloaded 1361 bytes. 23:06:56 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 23:06:56 INFO - Using the following test package requirements: 23:06:56 INFO - {u'common': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 23:06:56 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 23:06:56 INFO - u'firefox-48.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:06:56 INFO - u'gtest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 23:06:56 INFO - u'firefox-48.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:06:56 INFO - u'jittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 23:06:56 INFO - u'jsshell-linux-x86_64.zip'], 23:06:56 INFO - u'mochitest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 23:06:56 INFO - u'firefox-48.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:06:56 INFO - u'mozbase': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 23:06:56 INFO - u'reftest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 23:06:56 INFO - u'firefox-48.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:06:56 INFO - u'talos': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 23:06:56 INFO - u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:06:56 INFO - u'web-platform': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 23:06:56 INFO - u'firefox-48.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:06:56 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 23:06:56 INFO - u'firefox-48.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:06:56 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:06:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:06:56 INFO - https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:06:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 23:06:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 23:06:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 23:06:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:07:08 INFO - Downloaded 23857707 bytes. 23:07:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:07:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:07:10 INFO - Return code: 0 23:07:10 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 23:07:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:10 INFO - https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:07:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 23:07:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 23:07:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 23:07:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:07:11 INFO - Downloaded 23857707 bytes. 23:07:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:07:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:07:12 INFO - Return code: 0 23:07: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')]} 23:07:12 INFO - https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:07:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 23:07:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 23:07:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 23:07:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 23:07:14 INFO - Downloaded 10840145 bytes. 23:07:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:07:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 23:07:14 INFO - Return code: 0 23:07:14 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')]} 23:07:14 INFO - https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:07:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 23:07:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 23:07:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 23:07:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 23:07:16 INFO - Downloaded 58331334 bytes. 23:07:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 23:07:16 INFO - mkdir: /builds/slave/test/properties 23:07:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:07:16 INFO - Writing to file /builds/slave/test/properties/build_url 23:07:16 INFO - Contents: 23:07:16 INFO - build_url:https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 23:07:17 INFO - Running post-action listener: _resource_record_post_action 23:07:17 INFO - Running post-action listener: set_extra_try_arguments 23:07:17 INFO - ##### 23:07:17 INFO - ##### Running populate-webroot step. 23:07:17 INFO - ##### 23:07:17 INFO - Running pre-action listener: _resource_record_pre_action 23:07:17 INFO - Running main action method: populate_webroot 23:07:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:07:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:07:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:07:17 INFO - '--tppagecycles', 23:07:17 INFO - '7'], 23:07:17 INFO - 'tests': ['tsvgm']}}, 23:07:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:07:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:07:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:07:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:07:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:07:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:07:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:07:17 INFO - 'talos_options': ['--disable-e10s'], 23:07:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:07:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:07:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:07:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:07:17 INFO - 'talos_options': ['--disable-e10s'], 23:07:17 INFO - 'tests': ['damp', 'tps']}, 23:07:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:07:17 INFO - 'tests': ['damp', 'tps']}, 23:07:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:07:17 INFO - 'tests': ['dromaeo_dom']}, 23:07:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:07:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:07:17 INFO - 'tests': ['a11yr', 23:07:17 INFO - 'ts_paint', 23:07:17 INFO - 'tpaint', 23:07:17 INFO - 'sessionrestore', 23:07:17 INFO - 'sessionrestore_no_auto_restore', 23:07:17 INFO - 'tabpaint']}, 23:07:17 INFO - 'other-e10s': {'tests': ['a11yr', 23:07:17 INFO - 'ts_paint', 23:07:17 INFO - 'tpaint', 23:07:17 INFO - 'sessionrestore', 23:07:17 INFO - 'sessionrestore_no_auto_restore', 23:07:17 INFO - 'tabpaint']}, 23:07:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:07:17 INFO - 'ts_paint', 23:07:17 INFO - 'tpaint', 23:07:17 INFO - 'sessionrestore', 23:07:17 INFO - 'sessionrestore_no_auto_restore', 23:07:17 INFO - 'tabpaint']}, 23:07:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:07:17 INFO - 'ts_paint', 23:07:17 INFO - 'tpaint', 23:07:17 INFO - 'sessionrestore', 23:07:17 INFO - 'sessionrestore_no_auto_restore', 23:07:17 INFO - 'tabpaint']}, 23:07:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:07:17 INFO - 'tests': ['a11yr', 23:07:17 INFO - 'ts_paint', 23:07:17 INFO - 'tpaint', 23:07:17 INFO - 'sessionrestore', 23:07:17 INFO - 'sessionrestore_no_auto_restore', 23:07:17 INFO - 'tabpaint']}, 23:07:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:07:17 INFO - 'tests': ['a11yr', 23:07:17 INFO - 'ts_paint', 23:07:17 INFO - 'tpaint', 23:07:17 INFO - 'sessionrestore', 23:07:17 INFO - 'sessionrestore_no_auto_restore', 23:07:17 INFO - 'tabpaint']}, 23:07:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:07:17 INFO - 'tests': ['tsvgx', 23:07:17 INFO - 'tsvgr_opacity', 23:07:17 INFO - 'tart', 23:07:17 INFO - 'tscrollx', 23:07:17 INFO - 'cart']}, 23:07:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:07:17 INFO - 'tsvgr_opacity', 23:07:17 INFO - 'tart', 23:07:17 INFO - 'tscrollx', 23:07:17 INFO - 'cart']}, 23:07:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:07:17 INFO - 'talos_options': ['--disable-e10s'], 23:07:17 INFO - 'tests': ['tp5o']}, 23:07:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:07:17 INFO - 'tests': ['tp5o']}, 23:07:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:07:17 INFO - 'talos_options': ['--disable-e10s', 23:07:17 INFO - '--xperf_path', 23:07:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:07:17 INFO - 'tests': ['tp5n']}, 23:07:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:07:17 INFO - 'talos_options': ['--xperf_path', 23:07:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:07:17 INFO - 'tests': ['tp5n']}}, 23:07:17 INFO - 'talos.zip': {'path': '', 23:07:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:07:17 INFO - Running post-action listener: _resource_record_post_action 23:07:17 INFO - ##### 23:07:17 INFO - ##### Running create-virtualenv step. 23:07:17 INFO - ##### 23:07:17 INFO - Running pre-action listener: _resource_record_pre_action 23:07:17 INFO - Running main action method: create_virtualenv 23:07:17 INFO - Creating virtualenv /builds/slave/test/build/venv 23:07:17 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 23:07:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:07:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:07:17 INFO - Using real prefix '/usr' 23:07:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:07:17 INFO - Installing distribute.............................................................................................................................................................................................done. 23:07:19 INFO - Installing pip.................done. 23:07:19 INFO - Return code: 0 23:07:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:07:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1a29261f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1133810>, '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 0x1134710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1134ba0>, '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': 'af724c981e2a0f424dbabf99000001e7-1461561215.764862-835822559', '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 23:07:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:07:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:07:19 INFO - Using env: {'DISPLAY': ':0', 23:07:19 INFO - 'HOME': '/home/cltbld', 23:07:19 INFO - 'LANG': 'en_US.UTF-8', 23:07:19 INFO - 'LANGUAGE': 'en_US:en', 23:07:19 INFO - 'LOGNAME': 'cltbld', 23:07:19 INFO - 'MAIL': '/var/mail/cltbld', 23:07:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:19 INFO - 'MOZ_NO_REMOTE': '1', 23:07:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:07:19 INFO - 'NO_EM_RESTART': '1', 23:07:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:07:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:19 INFO - 'PWD': '/builds/slave/test', 23:07:19 INFO - 'SHELL': '/bin/bash', 23:07:19 INFO - 'SHLVL': '1', 23:07:19 INFO - 'TERM': 'linux', 23:07:19 INFO - 'TMOUT': '86400', 23:07:19 INFO - 'USER': 'cltbld', 23:07:19 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1461561215.764862-835822559', 23:07:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:19 INFO - '_': '/tools/buildbot/bin/python'} 23:07:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:20 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 23:07:20 INFO - Cleaning up... 23:07:20 INFO - Return code: 0 23:07:20 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 23:07:20 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')]} 23:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:20 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')]} 23:07:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1a29261f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1133810>, '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 0x1134710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1134ba0>, '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': 'af724c981e2a0f424dbabf99000001e7-1461561215.764862-835822559', '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 23:07:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 23:07:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 23:07:20 INFO - Using env: {'DISPLAY': ':0', 23:07:20 INFO - 'HOME': '/home/cltbld', 23:07:20 INFO - 'LANG': 'en_US.UTF-8', 23:07:20 INFO - 'LANGUAGE': 'en_US:en', 23:07:20 INFO - 'LOGNAME': 'cltbld', 23:07:20 INFO - 'MAIL': '/var/mail/cltbld', 23:07:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:20 INFO - 'MOZ_NO_REMOTE': '1', 23:07:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:07:20 INFO - 'NO_EM_RESTART': '1', 23:07:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:07:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:20 INFO - 'PWD': '/builds/slave/test', 23:07:20 INFO - 'SHELL': '/bin/bash', 23:07:20 INFO - 'SHLVL': '1', 23:07:20 INFO - 'TERM': 'linux', 23:07:20 INFO - 'TMOUT': '86400', 23:07:20 INFO - 'USER': 'cltbld', 23:07:20 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1461561215.764862-835822559', 23:07:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:20 INFO - '_': '/tools/buildbot/bin/python'} 23:07:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:20 INFO - Downloading/unpacking psutil>=0.7.1 23:07:20 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 23:07:20 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 23:07:20 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 23:07:20 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 23:07:20 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 23:07:20 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 23:07:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:07:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:07:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:07:22 INFO - Installing collected packages: psutil 23:07:22 INFO - Running setup.py install for psutil 23:07:22 INFO - building 'psutil._psutil_linux' extension 23:07:22 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 23:07:22 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 23:07:22 INFO - building 'psutil._psutil_posix' extension 23:07:22 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 23:07:22 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 23:07:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:07:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:07:22 INFO - Successfully installed psutil 23:07:22 INFO - Cleaning up... 23:07:22 INFO - Return code: 0 23:07:22 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 23:07:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:07:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:07:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1a29261f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1133810>, '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 0x1134710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1134ba0>, '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': 'af724c981e2a0f424dbabf99000001e7-1461561215.764862-835822559', '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 23:07:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 23:07:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 23:07:22 INFO - Using env: {'DISPLAY': ':0', 23:07:22 INFO - 'HOME': '/home/cltbld', 23:07:22 INFO - 'LANG': 'en_US.UTF-8', 23:07:22 INFO - 'LANGUAGE': 'en_US:en', 23:07:22 INFO - 'LOGNAME': 'cltbld', 23:07:22 INFO - 'MAIL': '/var/mail/cltbld', 23:07:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:22 INFO - 'MOZ_NO_REMOTE': '1', 23:07:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:07:22 INFO - 'NO_EM_RESTART': '1', 23:07:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:07:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:22 INFO - 'PWD': '/builds/slave/test', 23:07:22 INFO - 'SHELL': '/bin/bash', 23:07:22 INFO - 'SHLVL': '1', 23:07:22 INFO - 'TERM': 'linux', 23:07:22 INFO - 'TMOUT': '86400', 23:07:22 INFO - 'USER': 'cltbld', 23:07:22 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1461561215.764862-835822559', 23:07:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:22 INFO - '_': '/tools/buildbot/bin/python'} 23:07:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:22 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 23:07:22 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 23:07:22 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 23:07:22 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 23:07:22 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 23:07:22 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 23:07:22 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 23:07:24 INFO - Downloading mozsystemmonitor-0.0.tar.gz 23:07:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:07:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 23:07:24 INFO - Installing collected packages: mozsystemmonitor 23:07:24 INFO - Running setup.py install for mozsystemmonitor 23:07:24 INFO - Successfully installed mozsystemmonitor 23:07:24 INFO - Cleaning up... 23:07:25 INFO - Return code: 0 23:07:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:07:25 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')]} 23:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:25 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')]} 23:07:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1a29261f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1133810>, '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 0x1134710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1134ba0>, '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': 'af724c981e2a0f424dbabf99000001e7-1461561215.764862-835822559', '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 23:07:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:07:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:07:25 INFO - Using env: {'DISPLAY': ':0', 23:07:25 INFO - 'HOME': '/home/cltbld', 23:07:25 INFO - 'LANG': 'en_US.UTF-8', 23:07:25 INFO - 'LANGUAGE': 'en_US:en', 23:07:25 INFO - 'LOGNAME': 'cltbld', 23:07:25 INFO - 'MAIL': '/var/mail/cltbld', 23:07:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:25 INFO - 'MOZ_NO_REMOTE': '1', 23:07:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:07:25 INFO - 'NO_EM_RESTART': '1', 23:07:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:07:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:25 INFO - 'PWD': '/builds/slave/test', 23:07:25 INFO - 'SHELL': '/bin/bash', 23:07:25 INFO - 'SHLVL': '1', 23:07:25 INFO - 'TERM': 'linux', 23:07:25 INFO - 'TMOUT': '86400', 23:07:25 INFO - 'USER': 'cltbld', 23:07:25 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1461561215.764862-835822559', 23:07:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:25 INFO - '_': '/tools/buildbot/bin/python'} 23:07:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:25 INFO - Downloading/unpacking blobuploader==1.2.4 23:07:25 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 23:07:25 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 23:07:25 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 23:07:25 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 23:07:25 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 23:07:25 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 23:07:27 INFO - Downloading blobuploader-1.2.4.tar.gz 23:07:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:07:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:07:27 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 23:07:27 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 23:07:27 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 23:07:27 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 23:07:27 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 23:07:27 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 23:07:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:07:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:07:27 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 23:07:27 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 23:07:27 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 23:07:27 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 23:07:27 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 23:07:27 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 23:07:27 INFO - Downloading docopt-0.6.1.tar.gz 23:07:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:07:27 INFO - Installing collected packages: blobuploader, requests, docopt 23:07:27 INFO - Running setup.py install for blobuploader 23:07:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:07:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:07:27 INFO - Running setup.py install for requests 23:07:28 INFO - Running setup.py install for docopt 23:07:28 INFO - Successfully installed blobuploader requests docopt 23:07:28 INFO - Cleaning up... 23:07:28 INFO - Return code: 0 23:07:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:07:28 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')]} 23:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:28 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')]} 23:07:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1a29261f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1133810>, '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 0x1134710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1134ba0>, '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': 'af724c981e2a0f424dbabf99000001e7-1461561215.764862-835822559', '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 23:07:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:07:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:07:28 INFO - Using env: {'DISPLAY': ':0', 23:07:28 INFO - 'HOME': '/home/cltbld', 23:07:28 INFO - 'LANG': 'en_US.UTF-8', 23:07:28 INFO - 'LANGUAGE': 'en_US:en', 23:07:28 INFO - 'LOGNAME': 'cltbld', 23:07:28 INFO - 'MAIL': '/var/mail/cltbld', 23:07:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:28 INFO - 'MOZ_NO_REMOTE': '1', 23:07:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:07:28 INFO - 'NO_EM_RESTART': '1', 23:07:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:07:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:28 INFO - 'PWD': '/builds/slave/test', 23:07:28 INFO - 'SHELL': '/bin/bash', 23:07:28 INFO - 'SHLVL': '1', 23:07:28 INFO - 'TERM': 'linux', 23:07:28 INFO - 'TMOUT': '86400', 23:07:28 INFO - 'USER': 'cltbld', 23:07:28 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1461561215.764862-835822559', 23:07:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:28 INFO - '_': '/tools/buildbot/bin/python'} 23:07:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:07:28 INFO - Running setup.py (path:/tmp/pip-rJ_umM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:07:28 INFO - Running setup.py (path:/tmp/pip-tJyffc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:07:28 INFO - Running setup.py (path:/tmp/pip-dXDq0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:07:28 INFO - Running setup.py (path:/tmp/pip-zahooQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:07:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:07:28 INFO - Running setup.py (path:/tmp/pip-nzBnZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:07:29 INFO - Running setup.py (path:/tmp/pip-nkFgnU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:07:29 INFO - Running setup.py (path:/tmp/pip-tgGf5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:07:29 INFO - Running setup.py (path:/tmp/pip-d17srd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:07:29 INFO - Running setup.py (path:/tmp/pip-HTo15U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:07:29 INFO - Running setup.py (path:/tmp/pip-Ecc4wW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:07:29 INFO - Running setup.py (path:/tmp/pip-JHrR9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:07:29 INFO - Running setup.py (path:/tmp/pip-5cQ5oC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:07:29 INFO - Running setup.py (path:/tmp/pip-NRT3hc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:07:29 INFO - Running setup.py (path:/tmp/pip-eOsr04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:07:29 INFO - Running setup.py (path:/tmp/pip-XLkWht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:07:29 INFO - Running setup.py (path:/tmp/pip-pn6p8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:07:29 INFO - Running setup.py (path:/tmp/pip-vVA0Di-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:07:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:07:29 INFO - Running setup.py install for manifestparser 23:07:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:07:30 INFO - Running setup.py install for mozcrash 23:07:30 INFO - Running setup.py install for mozdebug 23:07:30 INFO - Running setup.py install for mozdevice 23:07:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:07:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:07:30 INFO - Running setup.py install for mozfile 23:07:30 INFO - Running setup.py install for mozhttpd 23:07:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:07:30 INFO - Running setup.py install for mozinfo 23:07:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:07:30 INFO - Running setup.py install for mozInstall 23:07:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:07:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:07:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:07:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:07:30 INFO - Running setup.py install for mozleak 23:07:30 INFO - Running setup.py install for mozlog 23:07:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:07:30 INFO - Running setup.py install for moznetwork 23:07:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:07:31 INFO - Running setup.py install for mozprocess 23:07:31 INFO - Running setup.py install for mozprofile 23:07:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:07:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:07:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:07:31 INFO - Running setup.py install for mozrunner 23:07:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:07:31 INFO - Running setup.py install for mozscreenshot 23:07:31 INFO - Running setup.py install for moztest 23:07:31 INFO - Running setup.py install for mozversion 23:07:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:07:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:07:31 INFO - Cleaning up... 23:07:31 INFO - Return code: 0 23:07:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:07:31 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')]} 23:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:31 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')]} 23:07:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1a29261f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1133810>, '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 0x1134710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1134ba0>, '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': 'af724c981e2a0f424dbabf99000001e7-1461561215.764862-835822559', '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 23:07:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:07:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:07:31 INFO - Using env: {'DISPLAY': ':0', 23:07:31 INFO - 'HOME': '/home/cltbld', 23:07:31 INFO - 'LANG': 'en_US.UTF-8', 23:07:31 INFO - 'LANGUAGE': 'en_US:en', 23:07:31 INFO - 'LOGNAME': 'cltbld', 23:07:31 INFO - 'MAIL': '/var/mail/cltbld', 23:07:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:31 INFO - 'MOZ_NO_REMOTE': '1', 23:07:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:07:31 INFO - 'NO_EM_RESTART': '1', 23:07:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:07:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:31 INFO - 'PWD': '/builds/slave/test', 23:07:31 INFO - 'SHELL': '/bin/bash', 23:07:31 INFO - 'SHLVL': '1', 23:07:31 INFO - 'TERM': 'linux', 23:07:31 INFO - 'TMOUT': '86400', 23:07:31 INFO - 'USER': 'cltbld', 23:07:31 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1461561215.764862-835822559', 23:07:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:31 INFO - '_': '/tools/buildbot/bin/python'} 23:07:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:07:31 INFO - Running setup.py (path:/tmp/pip-TeO9Qu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:07:32 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)) 23:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:07:32 INFO - Running setup.py (path:/tmp/pip-BPHlOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:07:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 23:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:07:32 INFO - Running setup.py (path:/tmp/pip-PG8WjL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:07:32 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)) 23:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:07:32 INFO - Running setup.py (path:/tmp/pip-VD32S2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:07:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 23:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:07:32 INFO - Running setup.py (path:/tmp/pip-7xx8x2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:07:32 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)) 23:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:07:32 INFO - Running setup.py (path:/tmp/pip-6VgbBV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:07:32 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)) 23:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:07:32 INFO - Running setup.py (path:/tmp/pip-aUeEU6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:07:32 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)) 23:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:07:32 INFO - Running setup.py (path:/tmp/pip-s7sZpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:07:32 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)) 23:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:07:32 INFO - Running setup.py (path:/tmp/pip-iaAUCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:07:32 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)) 23:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:07:32 INFO - Running setup.py (path:/tmp/pip-ZvO5n6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:07:32 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)) 23:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:07:32 INFO - Running setup.py (path:/tmp/pip-QmWlVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:07:32 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)) 23:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:07:32 INFO - Running setup.py (path:/tmp/pip-82CyxP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:07:32 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)) 23:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:07:32 INFO - Running setup.py (path:/tmp/pip-Pp5bVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:07:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 23:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:07:32 INFO - Running setup.py (path:/tmp/pip-pgIKVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:07:32 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)) 23:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:07:32 INFO - Running setup.py (path:/tmp/pip-c5TKml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:07:33 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)) 23:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:07:33 INFO - Running setup.py (path:/tmp/pip-J61tuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:07:33 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)) 23:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:07:33 INFO - Running setup.py (path:/tmp/pip-UwV7qG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:07:33 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)) 23:07:33 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.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:07:33 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.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:07:33 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)) 23:07:33 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:07:33 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:07:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:07:33 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 23:07:33 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 23:07:33 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 23:07:33 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 23:07:33 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 23:07:33 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 23:07:35 INFO - Downloading blessings-1.6.tar.gz 23:07:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:07:35 INFO - Installing collected packages: blessings 23:07:35 INFO - Running setup.py install for blessings 23:07:35 INFO - Successfully installed blessings 23:07:35 INFO - Cleaning up... 23:07:35 INFO - Return code: 0 23:07:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:07:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:07:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:07:35 INFO - Reading from file tmpfile_stdout 23:07:35 INFO - Current package versions: 23:07:35 INFO - argparse == 1.2.1 23:07:35 INFO - blessings == 1.6 23:07:35 INFO - blobuploader == 1.2.4 23:07:35 INFO - docopt == 0.6.1 23:07:35 INFO - manifestparser == 1.1 23:07:35 INFO - mozInstall == 1.12 23:07:35 INFO - mozcrash == 0.17 23:07:35 INFO - mozdebug == 0.1 23:07:35 INFO - mozdevice == 0.48 23:07:35 INFO - mozfile == 1.2 23:07:35 INFO - mozhttpd == 0.7 23:07:35 INFO - mozinfo == 0.9 23:07:35 INFO - mozleak == 0.1 23:07:35 INFO - mozlog == 3.1 23:07:35 INFO - moznetwork == 0.27 23:07:35 INFO - mozprocess == 0.22 23:07:35 INFO - mozprofile == 0.28 23:07:35 INFO - mozrunner == 6.11 23:07:35 INFO - mozscreenshot == 0.1 23:07:35 INFO - mozsystemmonitor == 0.0 23:07:35 INFO - moztest == 0.7 23:07:35 INFO - mozversion == 1.4 23:07:35 INFO - psutil == 3.1.1 23:07:35 INFO - requests == 1.2.3 23:07:35 INFO - wsgiref == 0.1.2 23:07:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:07:35 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')]} 23:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:35 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')]} 23:07:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1a29261f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1133810>, '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 0x1134710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1134ba0>, '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': 'af724c981e2a0f424dbabf99000001e7-1461561215.764862-835822559', '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 23:07:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 23:07:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:07:35 INFO - Using env: {'DISPLAY': ':0', 23:07:35 INFO - 'HOME': '/home/cltbld', 23:07:35 INFO - 'LANG': 'en_US.UTF-8', 23:07:35 INFO - 'LANGUAGE': 'en_US:en', 23:07:35 INFO - 'LOGNAME': 'cltbld', 23:07:35 INFO - 'MAIL': '/var/mail/cltbld', 23:07:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:35 INFO - 'MOZ_NO_REMOTE': '1', 23:07:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:07:35 INFO - 'NO_EM_RESTART': '1', 23:07:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:07:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:35 INFO - 'PWD': '/builds/slave/test', 23:07:35 INFO - 'SHELL': '/bin/bash', 23:07:35 INFO - 'SHLVL': '1', 23:07:35 INFO - 'TERM': 'linux', 23:07:35 INFO - 'TMOUT': '86400', 23:07:35 INFO - 'USER': 'cltbld', 23:07:35 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1461561215.764862-835822559', 23:07:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:35 INFO - '_': '/tools/buildbot/bin/python'} 23:07:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 23:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 23:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 23:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 23:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 23:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 23:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 23:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:07:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:07:35 INFO - Cleaning up... 23:07:35 INFO - Return code: 0 23:07:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:07:35 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')]} 23:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:35 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')]} 23:07:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1a29261f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1133810>, '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 0x1134710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1134ba0>, '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': 'af724c981e2a0f424dbabf99000001e7-1461561215.764862-835822559', '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 23:07:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:07:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:07:35 INFO - Using env: {'DISPLAY': ':0', 23:07:35 INFO - 'HOME': '/home/cltbld', 23:07:35 INFO - 'LANG': 'en_US.UTF-8', 23:07:35 INFO - 'LANGUAGE': 'en_US:en', 23:07:35 INFO - 'LOGNAME': 'cltbld', 23:07:35 INFO - 'MAIL': '/var/mail/cltbld', 23:07:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:35 INFO - 'MOZ_NO_REMOTE': '1', 23:07:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:07:35 INFO - 'NO_EM_RESTART': '1', 23:07:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:07:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:35 INFO - 'PWD': '/builds/slave/test', 23:07:35 INFO - 'SHELL': '/bin/bash', 23:07:35 INFO - 'SHLVL': '1', 23:07:35 INFO - 'TERM': 'linux', 23:07:35 INFO - 'TMOUT': '86400', 23:07:35 INFO - 'USER': 'cltbld', 23:07:35 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1461561215.764862-835822559', 23:07:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:35 INFO - '_': '/tools/buildbot/bin/python'} 23:07:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:35 INFO - Downloading/unpacking jsonschema 23:07:35 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 23:07:35 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 23:07:35 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 23:07:35 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 23:07:35 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 23:07:35 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 23:07:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:07:37 INFO - Downloading/unpacking functools32 (from jsonschema) 23:07:37 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 23:07:37 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 23:07:37 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 23:07:37 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 23:07:37 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 23:07:37 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 23:07:37 INFO - Downloading functools32-3.2.3-2.tar.gz 23:07:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:07:38 INFO - warning: no files found matching '*.txt' 23:07:38 INFO - no previously-included directories found matching 'build' 23:07:38 INFO - no previously-included directories found matching 'dist' 23:07:38 INFO - no previously-included directories found matching '.git*' 23:07:38 INFO - Installing collected packages: jsonschema, functools32 23:07:38 INFO - Running setup.py install for functools32 23:07:38 INFO - warning: no files found matching '*.txt' 23:07:38 INFO - no previously-included directories found matching 'build' 23:07:38 INFO - no previously-included directories found matching 'dist' 23:07:38 INFO - no previously-included directories found matching '.git*' 23:07:38 INFO - Successfully installed jsonschema functools32 23:07:38 INFO - Cleaning up... 23:07:38 INFO - Return code: 0 23:07:38 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:07:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:07:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:07:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:07:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1a29261f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfd98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1133810>, '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 0x1134710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1134ba0>, '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': 'af724c981e2a0f424dbabf99000001e7-1461561215.764862-835822559', '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 23:07:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 23:07:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 23:07:38 INFO - Using env: {'DISPLAY': ':0', 23:07:38 INFO - 'HOME': '/home/cltbld', 23:07:38 INFO - 'LANG': 'en_US.UTF-8', 23:07:38 INFO - 'LANGUAGE': 'en_US:en', 23:07:38 INFO - 'LOGNAME': 'cltbld', 23:07:38 INFO - 'MAIL': '/var/mail/cltbld', 23:07:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:38 INFO - 'MOZ_NO_REMOTE': '1', 23:07:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:07:38 INFO - 'NO_EM_RESTART': '1', 23:07:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:07:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:38 INFO - 'PWD': '/builds/slave/test', 23:07:38 INFO - 'SHELL': '/bin/bash', 23:07:38 INFO - 'SHLVL': '1', 23:07:38 INFO - 'TERM': 'linux', 23:07:38 INFO - 'TMOUT': '86400', 23:07:38 INFO - 'USER': 'cltbld', 23:07:38 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1461561215.764862-835822559', 23:07:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:38 INFO - '_': '/tools/buildbot/bin/python'} 23:07:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:07:38 INFO - Downloading/unpacking flake8 23:07:38 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 23:07:38 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 23:07:38 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 23:07:38 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 23:07:38 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 23:07:38 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 23:07:40 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:07:40 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:07:40 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 23:07:40 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 23:07:40 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 23:07:40 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 23:07:40 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 23:07:40 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 23:07:40 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:07:40 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:07:40 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 23:07:40 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 23:07:40 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 23:07:40 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 23:07:40 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 23:07:40 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 23:07:40 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:07:40 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 23:07:40 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 23:07:40 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 23:07:40 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 23:07:40 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 23:07:40 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 23:07:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:07:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:07:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:07:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:07:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:07:41 INFO - no previously-included directories found matching 'docs/_build' 23:07:41 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:07:41 INFO - Running setup.py install for pep8 23:07:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:07:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:07:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:07:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:07:41 INFO - no previously-included directories found matching 'docs/_build' 23:07:41 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:07:41 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:07:41 INFO - Cleaning up... 23:07:41 INFO - Return code: 0 23:07:41 INFO - Running post-action listener: _resource_record_post_action 23:07:41 INFO - Running post-action listener: _start_resource_monitoring 23:07:41 INFO - Starting resource monitoring. 23:07:41 INFO - ##### 23:07:41 INFO - ##### Running install step. 23:07:41 INFO - ##### 23:07:41 INFO - Running pre-action listener: _resource_record_pre_action 23:07:41 INFO - Running main action method: install 23:07:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:07:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:07:41 INFO - Reading from file tmpfile_stdout 23:07:41 INFO - Detecting whether we're running mozinstall >=1.0... 23:07:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:07:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:07:41 INFO - Reading from file tmpfile_stdout 23:07:41 INFO - Output received: 23:07:41 INFO - Usage: mozinstall [options] installer 23:07:41 INFO - Options: 23:07:41 INFO - -h, --help show this help message and exit 23:07:41 INFO - -d DEST, --destination=DEST 23:07:41 INFO - Directory to install application into. [default: 23:07:41 INFO - "/builds/slave/test"] 23:07:41 INFO - --app=APP Application being installed. [default: firefox] 23:07:41 INFO - mkdir: /builds/slave/test/build/application 23:07:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 23:07:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 23:07:58 INFO - Reading from file tmpfile_stdout 23:07:58 INFO - Output received: 23:07:58 INFO - /builds/slave/test/build/application/firefox/firefox 23:07:58 INFO - Running post-action listener: _resource_record_post_action 23:07:58 INFO - ##### 23:07:58 INFO - ##### Running run-tests step. 23:07:58 INFO - ##### 23:07:58 INFO - Running pre-action listener: _resource_record_pre_action 23:07:58 INFO - Running main action method: run_tests 23:07:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:07:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:07:58 INFO - Python 2.7.3 23:07:58 INFO - Return code: 0 23:07:58 INFO - grabbing minidump binary from tooltool 23:07:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:07:58 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 0x1133810>, '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 0x1134710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1134ba0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:07:58 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 23:07:58 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 23:07:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:07:58 INFO - Return code: 0 23:07:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:07:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:07:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:07:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:07:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:07:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:07:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:07:59 INFO - Return code: 0 23:07:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 23:07:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-020 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 23:07:59 INFO - Using env: {'DISPLAY': ':0', 23:07:59 INFO - 'HOME': '/home/cltbld', 23:07:59 INFO - 'LANG': 'en_US.UTF-8', 23:07:59 INFO - 'LANGUAGE': 'en_US:en', 23:07:59 INFO - 'LOGNAME': 'cltbld', 23:07:59 INFO - 'MAIL': '/var/mail/cltbld', 23:07:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:07:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:07:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:07:59 INFO - 'MOZ_NO_REMOTE': '1', 23:07:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:07:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:07:59 INFO - 'NO_EM_RESTART': '1', 23:07:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:07:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:07:59 INFO - 'PWD': '/builds/slave/test', 23:07:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:07:59 INFO - 'SHELL': '/bin/bash', 23:07:59 INFO - 'SHLVL': '1', 23:07:59 INFO - 'TERM': 'linux', 23:07:59 INFO - 'TMOUT': '86400', 23:07:59 INFO - 'USER': 'cltbld', 23:07:59 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1461561215.764862-835822559', 23:07:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:07:59 INFO - '_': '/tools/buildbot/bin/python'} 23:07:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 23:07:59 INFO - mozversion application_buildid: 20160424203035 23:07:59 INFO - mozversion application_changeset: 3cfe3ba1ed21c24a62d442f5645afb0fb6ed5e59 23:07:59 INFO - mozversion application_display_name: Nightly 23:07:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:07:59 INFO - mozversion application_name: Firefox 23:07:59 INFO - mozversion application_remotingname: firefox 23:07:59 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:07:59 INFO - mozversion application_vendor: Mozilla 23:07:59 INFO - mozversion application_version: 48.0a1 23:07:59 INFO - mozversion platform_buildid: 20160424203035 23:07:59 INFO - mozversion platform_changeset: 3cfe3ba1ed21c24a62d442f5645afb0fb6ed5e59 23:07:59 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:07:59 INFO - mozversion platform_version: 48.0a1 23:07:59 INFO - using testdate: 1461564479 23:07:59 INFO - actual date: 1461564479 23:07:59 INFO - starting webserver on 'localhost:43047' 23:07:59 INFO - SUITE-START | Running 2 tests 23:07:59 INFO - TEST-START | dromaeo_css 23:07:59 INFO - operating with platform_type : linux_ 23:07:59 INFO - Initialising browser for dromaeo_css test... 23:07:59 INFO - TEST-INFO | started process 25908 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJgFghZ/profile http://localhost:43047/getInfo.html) 23:08:04 INFO - PROCESS | 25908 | __metrics Screen width/height:1600/1200 23:08:04 INFO - PROCESS | 25908 | colorDepth:24 23:08:04 INFO - PROCESS | 25908 | Browser inner width/height: 1024/697 23:08:04 INFO - PROCESS | 25908 | __metrics 23:08:05 ERROR - PROCESS | 25908 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:08:06 INFO - TEST-INFO | 25908: exit 0 23:08:06 INFO - Browser initialized. 23:08:06 INFO - Running cycle 1/1 for dromaeo_css test... 23:08:06 INFO - TEST-INFO | started process 25989 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJgFghZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:08:18 INFO - PROCESS | 25989 | RSS: Main: 154320896 23:08:18 INFO - PROCESS | 25989 | 23:09:09 INFO - PROCESS | 25989 | 1461564549321 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] 23:11:16 INFO - PROCESS | 25989 | Cycle 1(1): loaded http://localhost:43047/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43047/tests/dromaeo/cssquery-ext.html) 23:11:17 INFO - PROCESS | 25989 | RSS: Main: 252878848 23:11:17 INFO - PROCESS | 25989 | 23:14:20 INFO - PROCESS | 25989 | Cycle 1(1): loaded http://localhost:43047/tests/dromaeo/cssquery-ext.html (next: http://localhost:43047/tests/dromaeo/cssquery-jquery.html) 23:14:20 INFO - PROCESS | 25989 | RSS: Main: 234287104 23:14:20 INFO - PROCESS | 25989 | 23:17:22 INFO - PROCESS | 25989 | Cycle 1(1): loaded http://localhost:43047/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43047/tests/dromaeo/cssquery-mootools.html) 23:17:23 INFO - PROCESS | 25989 | RSS: Main: 231632896 23:17:23 INFO - PROCESS | 25989 | 23:20:25 INFO - PROCESS | 25989 | Cycle 1(1): loaded http://localhost:43047/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43047/tests/dromaeo/cssquery-prototype.html) 23:20:25 INFO - PROCESS | 25989 | RSS: Main: 225521664 23:20:25 INFO - PROCESS | 25989 | 23:23:28 INFO - PROCESS | 25989 | Cycle 1(1): loaded http://localhost:43047/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43047/tests/dromaeo/cssquery-yui.html) 23:23:28 INFO - PROCESS | 25989 | RSS: Main: 223559680 23:23:28 INFO - PROCESS | 25989 | 23:26:31 INFO - PROCESS | 25989 | Cycle 1(1): loaded http://localhost:43047/tests/dromaeo/cssquery-yui.html (next: http://localhost:43047/tests/dromaeo/cssquery-dojo.html) 23:26:31 INFO - PROCESS | 25989 | RSS: Main: 264814592 23:26:31 INFO - PROCESS | 25989 | 23:26:31 INFO - PROCESS | 25989 | __start_tp_report 23:26:31 INFO - PROCESS | 25989 | _x_x_mozilla_page_load 23:26:31 INFO - PROCESS | 25989 | _x_x_mozilla_page_load_details 23:26:31 INFO - PROCESS | 25989 | |i|pagename|runs| 23:26:31 INFO - PROCESS | 25989 | |0;dojo.html;3082.306481041765;3110.3001824589455;3146.3707258548297;3149.7637677174203;3177.396294704006;5168.560672342846;5197.0645283848935;5206.114960456726;5206.427292997767;5237.816676416326;5307.52232299093;5312.229726689638;5321.866953326169;5323.733813309338;5324.973375133119;4692.835750748724;4713.740744259065;4803.61571074315;4840.830570930018;4891.192953162724;4766.594839438634;4770.618350531967;4827.25177597473;4831.033793241349;4837.806487740504;4650.930236046462;4670.76646167692;4674.345591617176;4677.181493238703;4741.834035808747;4764.666473346867;4774.236122220463;4777.856664300042;4778.784954677048;4806.038792241566;4673.485916549177;4732.929006064912;4750.311204875308;4754.9286760698615;4797.832075877328;5060.291559181717;5095.719735414551;5134.717590532519;5147.871303217412;5158.638895277295;5432.320959880015;5435.266239057755;5441.347038355383;5447.618666693333;5464.754086066137;5438.945610543883;5496.1755736639425;5527.281453411071;5552.361478429992;5572.60991730579;5147.639665223436;5165.380154381461;5215.16557350822;5243.056249874983;5247.47525247476;5645.661260324394;5650.26546548946;5653.434656534314;5653.773849045992;5663.235463212496;5385.299911011543;5429.077056900336;5479.232907392969;5479.972600137054;5489.451054894558;5498.5876059295715;5517.089680202722;5530.834074977757;5537.640053396597;5553.722313884289;5502.9174562381595;5508.20131080991;5508.20131080999;5511.917321240185;5563.221149039137;5395.973020134874;5531.170324451365;5578.721063946787;5611.579131565149;5651.773929042874;4178.415021896937;4183.414321994923;4188.476440444944;4189.895252618709;4216.241076606179;3770.981145094257;3772.773633581994;3783.6027217142355;3801.3157631626573;3804.6956243500454;5094.337736094358;5225.973870130625;5257.658252213594;5272.2882410874045;5298.97350513245;5534.889302213933;5536.2249285100115;5550.555955523549;5559.722013899289;5565.582581306418;5146.099432599281;5253.894922101536;5269.288646032736;5275.076861549213;5322.92015619766;4795.496472870368;4811.735354555533;4828.324034635154;4870.220764677634;4873.171560834667;5763.711814409263;5776.451237132465;5787.305523337226;5788.18965344852;5792.044312688359;5479.479449452296;5535.612507124623;5561.304836895388;5565.666060036411;5597.608167428241;5660.066089095418;5676.716164191691;5677.659340439422;5712.5429965601825;5725.454127385666;5414.539764120094;5416.187571864094;5418.945810541844;5419.10584753527;5422.566194704493;5486.31421072366;5496.065668836228;5511.393746687781;5551.2228288040515;5562.833115006556;5322.574194064388;5328.706921119376;5329;5330.147176451749;5390.622656414013;5314.096603577478;5318.335208098988;5322.627416080837;5340.279062326692;5365.597580181424;5250.343707036621;5279.894402112013;5296.947030529799;5376.704281264568;5387.86530336729;5409.864753381197;5410.513053825017;5413.810516631899;5434.2935418395355;5437.048516509674;5283.260343551829;5306.787728490972;5306.946930530646;5314.096603577478;5360.865978350573 23:26:31 INFO - PROCESS | 25989 | |1;ext.html;586.9970650146748;642.1170890026214;646.6120327803319;647.1586936981922;660.9074729537869;30332.696673033264;31013.379732405367;31039.844800775994;36112;37463.43804842923;39374;40158.799206004005;40162.19675606486;40240.19519609613;40249.597504024954;8653.961524617054;8878.733637990837;8880.6003729832;8881.555922203896;8882.467051976902;7481.139668938087;7703.037120359955;8689.869651955225;10229.744256393575;10599.258051936367;10260.74348141295;10372.896271037269;10372.896271037307;10382.325148865337;10383.428911409907;7568.810779730551;10120.848187277197;10231.846522302172;10246.590136394536;10256;9848.162906152986;10169.13562347194;10176;10176.694699159038;10176.79646407075;38076.096195190126;38742.22515549673;38847.22305553901;38986.80506597449;39011.219775604615;3475.94786078209;3482.233908540117;3603.819809009539;3667.853285868562;3701.555813302394;28620.14139575816;29536;29690.25774355637;32048.519272210935;32457;4229.767362795076;4266.530681625019;4275.978620106911;4315.611594956437;4367.323064924942;312747;318710;319240;319437;319781;61176;61334.693326533976;61718;61780.69109654423;61888.381116189164;64516.354836451974;64666.676666617306;66530.33469665339;66648.33351666518;66668;16024.27890744896;16177.514674559781;16194.838051619568;16203.756943645854;16205.756913646304;11874.465649045813;11887.28676279426;11905.345206013752;11910.404479775976;11914;20060.097295621734;20110.597788044157;20267.898660506602;20301.492462688257;20308.18767249323;7287.307705767944;7314.524555903849;7462.179160292363;7490.5431268396305;7716.807079823049;921.953902304882;930.3905941608258;1014.1531820929459;1023.9180865530889;1086.8804431512526;28170.85914570414;29233.269168270537;29899.252518687208;30350.241243969078;30614.84692576523;1063.813832579311;1066.440118937564;1070.9625163119408;1087.8857719939451;1119.6752941646994;15833.841661583467;16084.597885052968;16255.18724063792;16256.837431625769;16619.750703739453;463581;470617.8221980019;472187.1422214884;472636;472893;56663.71668141715;57204;57245;57648.711756441786;57661.423385766444;1215.9939200303943;1272.7709012377861;1319.64369620202;1353.4653811744306;1374.3815283122435;28133.57799633048;29371;29460.263493411978;29712.108636740933;30199.09402717922;41987.1602567953;42547.787261063495;42950.14099717926;42957.14085718331;43068;283571;286199;287710;288325;288719;24591.13931012406;25737;26309;26614;27085.729142709108;41964.790176048926;42167;42244.36633450562;42762;43392;36635.63364366322;37053.44419833651;37086.81456592808;37485.62514374821;37537.624623754506;36010.01944893056;36072.09819754422;36454.81772591226;36942.63057369392;40045.199096017335;39705;39840.80079599699;39984.2003159941;40961.59038409697;41178;38639;39399.80300098481;40216.597834022454;40318;40578.79710601546;30678.693213068476;31759.682403175673;32067.518987214742;32251.51622725614;32665 23:26:31 INFO - PROCESS | 25989 | |2;jquery.html;370.5645866107324;371.1093375897845;376.766404829006;378.7386703174811;379.3399484896281;17669.55826104348;18785.154668039937;18961.810381896175;19147;19230.807691923095;17700.380486682967;17870.28518859244;18414.539636509093;18446.446606601825;18462.907685461552;5550.639208451458;5708.714564271791;5739.139129130622;5760.193572899798;5763;3754.943675844851;3927.8821635350987;3931.9213615727667;3934.4098385242155;5257.684538927658;5130.153524668425;5411.350637923435;5434.755436005369;5436.809711660093;5448.618596698233;5387.757550910218;5419.403865574784;5446.645968012067;5573.637713548626;5591.720413979285;3642.7085833133287;5263.236830659533;5420.430854760233;5430.15832545956;5436.184572314184;20606.55754097214;20781.792182078138;21203.68194477084;21829.126834926585;22468.517379806155;2239.29462219402;2272.306946381353;2372.905083796646;2453.4234454903085;2804.326961529239;9364.812703745887;9962.302638815287;10285.9485702571;12489.937550312281;12828.294443805587;1873.49415657773;1877.8216069473378;1930.3533316339044;1931.661959157156;1966.6165097805933;73018.63490682565;77162.22837771606;77641.61179194125;77692.61153694252;78772.21227787706;10248.743781405376;10429.895701043044;10441.582336706446;10465.319754215952;10624.734381640521;14559.199244041467;14620.634484137981;14621.707565848623;14636.780448293284;14657.56027319182;7526.623668816537;7954.2841144296135;8032.678692852337;8109.067457242492;8173.427860049739;7439.51643143194;7491.288327608869;7689.615519224017;7848.254415830487;7940.563269020144;4092.959070409258;4145.191687620885;4496.662750293741;4519.81920723174;4540.523245059288;1668.6579251253706;1757.5606098475382;1798.739182818484;1897.7627796525435;1906.046976511744;917.1287277086794;934.3693007220099;964.286428043243;989.4706332112286;1049.4385503755454;17924.91037544804;18233.452996410128;18324.81675183258;18665.346712864986;19137.904310478636;930.2511278795955;938.380668758616;960.1838437328355;974.1427543761445;1038.6416686243233;10181.643642472478;10468.63359782404;10622.787544249071;10625.574977000833;10662.520186591622;507630;517554;518645;519513;522217;94004.52997734968;94634.52682736542;95020;95203;95457.522712386;978.8727465429591;984.1978787288338;993.5876611206269;1013.022433351819;1014.264658122855;16081.781207426096;16246.187690615185;16536.834631653528;16638.334466621684;16862.24119914582;34041;34206;35339.82330088436;35708;38037.80981095077;45626.31560526661;46909.2963605553;50950;53639.463605363446;58931.70534147302;7606.239376062349;8063.27430531255;8235.38234632417;8319.376046796393;8617.82764344697;8286.668533258844;13006.479740810262;13187.274699891597;13383.866161338263;13613.591592252249;11800.70498237551;11801.409929503663;11989.22070065443;12027.518899244282;12210.389480526115;10425.9478702606;10613.681589552325;11003.504842281956;11531.192816502764;11753.294802311888;10683.626073087406;11805.23265987708;11811.586594469152;11928.76142477163;12116.636500904988;7949.8410031800195;7983;8304.501729895985;8337;8428.0729119798;11713.238639488405;11912.642620721392;11940.582079627171;12057.457414416196;12180.390980450764 23:26:31 INFO - PROCESS | 25989 | |3;mootools.html;1146.1747541769932;1503.9849601503981;1506.5856889355427;1659.6348803263277;1780.9732854007193;7960.522368657877;8182.590870456475;8261.17388261175;8275.958620206906;8291.543965081919;8094.757157285276;8241.29948954338;8242.381821363391;8245.67017319308;8248.463849849755;2007.7891821358767;2069.4412508622663;2070.937871863839;2274.7270327560673;2303.5047464795066;2259.8644081355087;2329.033451117784;2330.475642980333;2331.568659797943;2331.7901388875;2229.398062523118;2254.41385239838;2271.375371772759;2275.1468199425217;2279.669447930049;1957.4910523263911;2023.8380929525604;2248.1119957616734;2260.3219034289727;2262.864228146308;1998.2406685459432;2194.3746032380695;2261.604219261623;2262.9547409051725;2264.467850055236;8129.674813007473;8193.385496087758;8193.71322003727;8195.221453961864;8200.83598328036;4901.387326584177;5071.822486213002;5081.110805609041;5083.262926875583;5093.414257360377;3029.439553682576;3083.5528848316867;3108.145260053481;3188.872445102193;3220.533022711694;990.7671697151163;1015.2588610314511;1021.2697920986456;1024.1704219582161;1044.4151275285788;11468.082553395709;11481.425928703615;11492.94253528727;11500.884991150066;11687.824682629767;4388.253996820548;4485.282354823278;4583.427071616048;4635.6986795858165;4668.229742092584;9797.06927841854;9808.16630586408;9808.9019109808;9813.411195328303;9820.901790982141;5102.158143906214;5143.691378517301;5154.433012368637;5173.896522069537;5191.480851914854;4133.9793301033305;4615.284630882219;4714.717116972993;4717.410323709536;4732;3824.770513769183;3931.4102884566973;3935.5474120475938;3963.7621742695533;3976.900577485528;1236.6784635994527;1238.8761123887539;1267.3789842976876;1276.6616846535676;1278.7378587389749;981.1709105805583;988.367444835306;991.5934466868694;997.1673652500099;1018.495844556949;5956.106584012433;5975.19334889793;5992.250968628921;5996.850078748066;6266.780662676784;393.2076865116792;424.35074336264944;425.6956276262425;430.2148578843623;433.59458905922435;3979.9204015919518;4143.606357396043;4172.435336748719;4302.720323178984;4475.686701930834;9930.900690993141;10278.743031424125;10409.167266618653;10417;10418.635347762793;9926.2059035277;10252.128569071712;10286.434246116385;10288.742781430374;10306.639267625596;7724.188960159102;7772.68909243624;7852.293293603603;7870;7880.290773830268;5837.182794408871;5898.351181370132;5951.523878089829;5960.731767070579;6067.575269731076;9302.441853488812;9541.427514348883;9541.522923853918;9547.809043818947;9610.375325603813;9487.33588648788;9543.9045609543;9761.219102471927;9912.207023438252;10042;6722.932770672231;7352.889706654511;7525.661345239368;7587.203343648837;7705.691772329044;8100.108988011196;8103.594820259082;8140.674373025013;8152.103268640326;8164.306033987177;7357.5217610855125;7394.55632662042;7544.585047822423;7566.167721550515;7692.307692307719;7394.889076663747;7404.518706284074;7452.962735186289;7520.473566850268;7571.848563028819;6801.251862295243;6866.725330986705;6899.344562266577;6909.516333856582;6923.065386173003;5013.523715247045;5041.369828771403;5156.535911767959;5177.508136727005;5186.974065129802;5074.898502030012;5219.137837999929;5314.395148420057;5355.277037599874;5357.008953343644 23:26:31 INFO - PROCESS | 25989 | |4;prototype.html;314.26462078735756;315.60234105027666;319.59411547334884;325.92666650003736;329.8169515918664;5675.914861277084;5775.3358363788175;5807.332156801969;5811;5817.272840894888;5729.28383952006;5741.645925170681;5748;5753.453421924911;5759.510441612468;4084.8774536763945;4140.9378859316985;4274;4310.59049390309;4352.7605981670995;4298.763568003758;4316.56834316569;4316.913661726763;4320.805563749639;4321.611055005049;4144.23331683638;4327.052826228194;4333.2416827055295;4337.848175313865;4349.630281426083;4233.005243767712;4337.956620433818;4348.717333373321;4360.585744354281;4389.47326320842;3776.131489757371;3786.4320351947154;3790.8673196438267;3795.2788970095594;3797.4493698413576;5428.647137936022;5507.5593952484105;5537.224788529609;5542.501174894279;5551.666899986014;4991.276264941564;5002.974985125087;5021.196608542652;5035.496450354972;5041.395032596075;5918.970405147989;5956.195913551658;5963.403659634046;5968.074948383008;5984.73068711909;4097.569755175693;4204.327307630764;4208.810603522848;4223.64099051581;4230.851920182809;6190.21440351839;6208.596441231305;6209.937900620981;6225.035119556476;6232.252129744415;4299.527052024275;4301.78491075445;4302.290122129813;4317.525072242051;4332.870013899588;4275.1022285319805;4363.323684828857;4381.78091095444;4384.167008268419;4391.6486681065435;4987.875303117451;5010.949890501049;5016.724080175552;5027.296178535008;5043.293938848564;5992.730327135289;6021.157038014681;6022.2171117754415;6034.24571928509;6041.184440100618;1486.9851301487063;1508.7284288828116;1510.5996910818421;1515.4165646226281;1532.1190315568592;944.622151139536;986.4623780039896;1020.0972139656458;1026.5585798106886;1037.5642230263309;5754.539636829044;5778.566607504454;5785.739641716133;5788.27646544182;5794.536437085023;6091.573589848668;6093.177421048191;6104.4200800923845;6114.144019837227;6148.323684394713;5650.717464126859;5684.403137670568;5771.769129234784;5782.913256301159;5786.623869448473;3232;3246.935061298761;3268.6731326867125;3273.018094571619;3276.6887145721116;6179.598326108879;6361;6362.236531616143;6377.266614339317;6394.776182833672;6147.262328520606;6161.106639537242;6166.907496387557;6186.597871138361;6202.658853762996;6149.692515374124;6357.4914006879335;6371.649559274192;6376.608771710608;6380.234371875312;6038.486728628116;6103.328633850362;6106.480949119375;6112.32764395925;6115.785947491817;6035.849103772441;6057.636541807506;6058.4850287724275;6066.605670631395;6077.4834139098675;6162.414570615784;6351.697901930001;6355.650439225702;6368.522360822864;6372.171617689856;4192.83228670862;4209.116085621931;4219.7046206765235;4227.556106608762;4230.95769042318;5924.6149000314845;6148.231471066117;6168.475679567107;6181.721822518088;6183.659898705434;5914.846604912001;5942.7920022799;5946.494547963299;5952.345242023461;5973.462388385066;5887.8233652990475;5919.733611987384;5959.821205363846;5972.193753843176;5974.014287642491;5847.1229315603;5854.180414741854;5863.73613187399;5999;6026.909596356146;6095.1466794647895;6122;6150;6160.10678451631;6168.691565421801;5840.240768700041;6008.669523176092;6013.368596297326;6019.4883434908525;6024.638521688713 23:26:31 INFO - PROCESS | 25989 | |5;yui.html;801.4950581133886;836.5984327522789;846.3271698999295;848.376443314164;857.1214505132239;13235.801462978052;13496.730065398686;13511;13550.18698878068;13550.525731599397;12786.936065319684;13133.540326088565;13341.399637016333;13343.799843002364;13355.666108347295;659.8614290998896;783.4202690009381;788.6254029336062;802.1697543042923;811.2698571285861;806.5846089264033;807.4751411582489;809.2150613904503;809.9311558517533;810.2707563193144;834.9707760228393;840.7477756672973;842.2672275120623;842.4271495383138;842.4692443760422;676.3067855448156;828.3994104274414;839.2949922065434;842.2882664148784;845.0197770586152;616.9012957926755;617.783775678509;617.8949578571609;619.9845003874894;621.4034526854225;11492.712682182928;11533.423328833609;11535.59625413115;11624.825627615508;11633.825492617618;12948.611541653765;13111.737765244738;13128.86871131286;13243.404046817916;13817.930910345383;10343.637972670997;10387.37675739458;10435.113015393701;10531.73670658232;10779.08377787889;3765.1340191755776;3776.3580191367532;3865.3428917083884;3908.6286802753693;3909.4722212501515;54182.72908635471;55240.44759552393;55255.72372138114;55346;55526;10504.474776261157;10504.632337868137;10619.203559732896;10706.89293107059;11044;17253.223604937808;17259.827401726074;17268.740968885475;17413.12934353277;19081;7764.3788496920115;7766.922330776733;7869.0950540688555;7895.842083158304;8005.599720013976;4335.111302183045;4369.213541562549;4378.146261478981;4417.469903611523;4450;3088.135322109813;3156.873725050972;3167;3170.936581268362;3170.9682903171133;574.8390450673818;575.9769609215584;577.3648986115239;585.9033259512217;585.9267591551056;652.7160685101995;667.6127845849396;717.9676914538858;724.8260417499868;738.674983007475;7929.365650747926;8122.309603683753;8694.565271736388;9137.76640153584;9156.084391560924;673.6867356679168;674.7267356720536;754.7773406844993;767.6545554500497;773.6518566645033;3825.387937929918;4191.8532851350665;4219.535851056381;4265.50946641134;4283.225944909978;58897.41102589005;59375.70312148498;59720;59788;61510.69244653748;51120.42157049045;51167;51779;51838.74080629648;52102.99999999924;46051.5394846054;47128.999999999316;47374.5262547377;47754.76122619434;47901.520984790404;7993.6802527898235;8012.799680008046;8098.51408915467;8197.877031844408;8198.180181981754;32027.67972320247;33110.337793244486;33591.83204083964;33755.32489350248;33859.661403386635;39334.40998384969;40988.59011409967;42507;42616.360754589325;42907;9811.901880981384;10376;10432.113270372103;10558.155347572023;10710.30383025132;10159.390436573829;10979.890201097887;11166.218364714718;11189.272697274651;11387.487563059516;11463.197576169589;11706.239094458833;11861.110416718953;11921.105917056082;12346.629601111981;10933.180011499011;11024.889751102386;11071;11329.660110196708;11901.464434100313;10724.89275107239;10879.510422031186;11211.55153793839;11213.383263920563;11446.94276528612;9781.315307928377;10093.545790439595;10122.949385253027;10526.578936842441;10611.469426528796;9851.261155413229;9965.651202207888;10079.193664506969;10112.696619101438;10220.233482488695 23:26:31 INFO - PROCESS | 25989 | __end_tp_report 23:26:31 INFO - PROCESS | 25989 | __start_cc_report 23:26:31 INFO - PROCESS | 25989 | _x_x_mozilla_cycle_collect,821 23:26:31 INFO - PROCESS | 25989 | __end_cc_report 23:26:31 INFO - PROCESS | 25989 | __startTimestamp1461565591710__endTimestamp 23:26:31 INFO - PROCESS | 25989 | 23:26:31 INFO - PROCESS | 25989 | ------- Summary: start ------- 23:26:31 INFO - PROCESS | 25989 | Number of tests: 6 23:26:31 INFO - PROCESS | 25989 | 23:26:31 INFO - PROCESS | 25989 | [#0] dojo.html Cycles:165 Average:5150.86 Median:5322.63 stddev:558.63 (10.5%) stddev-sans-first:536.25 23:26:31 INFO - PROCESS | 25989 | Values: 3082.3 3110.3 3146.4 3149.8 3177.4 5168.6 5197.1 5206.1 5206.4 5237.8 5307.5 5312.2 5321.9 5323.7 5325.0 4692.8 4713.7 4803.6 4840.8 4891.2 4766.6 4770.6 4827.3 4831.0 4837.8 4650.9 4670.8 4674.3 4677.2 4741.8 4764.7 4774.2 4777.9 4778.8 4806.0 4673.5 4732.9 4750.3 4754.9 4797.8 5060.3 5095.7 5134.7 5147.9 5158.6 5432.3 5435.3 5441.3 5447.6 5464.8 5438.9 5496.2 5527.3 5552.4 5572.6 5147.6 5165.4 5215.2 5243.1 5247.5 5645.7 5650.3 5653.4 5653.8 5663.2 5385.3 5429.1 5479.2 5480.0 5489.5 5498.6 5517.1 5530.8 5537.6 5553.7 5502.9 5508.2 5508.2 5511.9 5563.2 5396.0 5531.2 5578.7 5611.6 5651.8 4178.4 4183.4 4188.5 4189.9 4216.2 3771.0 3772.8 3783.6 3801.3 3804.7 5094.3 5226.0 5257.7 5272.3 5299.0 5534.9 5536.2 5550.6 5559.7 5565.6 5146.1 5253.9 5269.3 5275.1 5322.9 4795.5 4811.7 4828.3 4870.2 4873.2 5763.7 5776.5 5787.3 5788.2 5792.0 5479.5 5535.6 5561.3 5565.7 5597.6 5660.1 5676.7 5677.7 5712.5 5725.5 5414.5 5416.2 5418.9 5419.1 5422.6 5486.3 5496.1 5511.4 5551.2 5562.8 5322.6 5328.7 5329.0 5330.1 5390.6 5314.1 5318.3 5322.6 5340.3 5365.6 5250.3 5279.9 5296.9 5376.7 5387.9 5409.9 5410.5 5413.8 5434.3 5437.0 5283.3 5306.8 5306.9 5314.1 5360.9 23:26:31 INFO - PROCESS | 25989 | 23:26:31 INFO - PROCESS | 25989 | [#1] ext.html Cycles:180 Average:52520.78 Median:36092.05 stddev:96969.66 (268.7%) stddev-sans-first:97163.29 23:26:31 INFO - PROCESS | 25989 | Values: 587.0 642.1 646.6 647.2 660.9 30332.7 31013.4 31039.8 36112.0 37463.4 39374.0 40158.8 40162.2 40240.2 40249.6 8654.0 8878.7 8880.6 8881.6 8882.5 7481.1 7703.0 8689.9 10229.7 10599.3 10260.7 10372.9 10372.9 10382.3 10383.4 7568.8 10120.8 10231.8 10246.6 10256.0 9848.2 10169.1 10176.0 10176.7 10176.8 38076.1 38742.2 38847.2 38986.8 39011.2 3475.9 3482.2 3603.8 3667.9 3701.6 28620.1 29536.0 29690.3 32048.5 32457.0 4229.8 4266.5 4276.0 4315.6 4367.3 312747.0 318710.0 319240.0 319437.0 319781.0 61176.0 61334.7 61718.0 61780.7 61888.4 64516.4 64666.7 66530.3 66648.3 66668.0 16024.3 16177.5 16194.8 16203.8 16205.8 11874.5 11887.3 11905.3 11910.4 11914.0 20060.1 20110.6 20267.9 20301.5 20308.2 7287.3 7314.5 7462.2 7490.5 7716.8 922.0 930.4 1014.2 1023.9 1086.9 28170.9 29233.3 29899.3 30350.2 30614.8 1063.8 1066.4 1071.0 1087.9 1119.7 15833.8 16084.6 16255.2 16256.8 16619.8 463581.0 470617.8 472187.1 472636.0 472893.0 56663.7 57204.0 57245.0 57648.7 57661.4 1216.0 1272.8 1319.6 1353.5 1374.4 28133.6 29371.0 29460.3 29712.1 30199.1 41987.2 42547.8 42950.1 42957.1 43068.0 283571.0 286199.0 287710.0 288325.0 288719.0 24591.1 25737.0 26309.0 26614.0 27085.7 41964.8 42167.0 42244.4 42762.0 43392.0 36635.6 37053.4 37086.8 37485.6 37537.6 36010.0 36072.1 36454.8 36942.6 40045.2 39705.0 39840.8 39984.2 40961.6 41178.0 38639.0 39399.8 40216.6 40318.0 40578.8 30678.7 31759.7 32067.5 32251.5 32665.0 23:26:31 INFO - PROCESS | 25989 | 23:26:31 INFO - PROCESS | 25989 | [#2] jquery.html Cycles:180 Average:29051.80 Median:2006.64 stddev:85105.42 (4241.2%) stddev-sans-first:85316.91 23:26:31 INFO - PROCESS | 25989 | Values: 370.6 371.1 376.8 378.7 379.3 17669.6 18785.2 18961.8 19147.0 19230.8 17700.4 17870.3 18414.5 18446.4 18462.9 5550.6 5708.7 5739.1 5760.2 5763.0 3754.9 3927.9 3931.9 3934.4 5257.7 5130.2 5411.4 5434.8 5436.8 5448.6 5387.8 5419.4 5446.6 5573.6 5591.7 3642.7 5263.2 5420.4 5430.2 5436.2 20606.6 20781.8 21203.7 21829.1 22468.5 2239.3 2272.3 2372.9 2453.4 2804.3 9364.8 9962.3 10285.9 12489.9 12828.3 1873.5 1877.8 1930.4 1931.7 1966.6 73018.6 77162.2 77641.6 77692.6 78772.2 10248.7 10429.9 10441.6 10465.3 10624.7 14559.2 14620.6 14621.7 14636.8 14657.6 7526.6 7954.3 8032.7 8109.1 8173.4 7439.5 7491.3 7689.6 7848.3 7940.6 4093.0 4145.2 4496.7 4519.8 4540.5 1668.7 1757.6 1798.7 1897.8 1906.0 917.1 934.4 964.3 989.5 1049.4 17924.9 18233.5 18324.8 18665.3 19137.9 930.3 938.4 960.2 974.1 1038.6 10181.6 10468.6 10622.8 10625.6 10662.5 507630.0 517554.0 518645.0 519513.0 522217.0 94004.5 94634.5 95020.0 95203.0 95457.5 978.9 984.2 993.6 1013.0 1014.3 16081.8 16246.2 16536.8 16638.3 16862.2 34041.0 34206.0 35339.8 35708.0 38037.8 45626.3 46909.3 50950.0 53639.5 58931.7 7606.2 8063.3 8235.4 8319.4 8617.8 8286.7 13006.5 13187.3 13383.9 13613.6 11800.7 11801.4 11989.2 12027.5 12210.4 10425.9 10613.7 11003.5 11531.2 11753.3 10683.6 11805.2 11811.6 11928.8 12116.6 7949.8 7983.0 8304.5 8337.0 8428.1 11713.2 11912.6 11940.6 12057.5 12180.4 23:26:31 INFO - PROCESS | 25989 | 23:26:31 INFO - PROCESS | 25989 | [#3] mootools.html Cycles:180 Average:5559.07 Median:5088.34 stddev:3120.31 (61.3%) stddev-sans-first:3111.43 23:26:31 INFO - PROCESS | 25989 | Values: 1146.2 1504.0 1506.6 1659.6 1781.0 7960.5 8182.6 8261.2 8276.0 8291.5 8094.8 8241.3 8242.4 8245.7 8248.5 2007.8 2069.4 2070.9 2274.7 2303.5 2259.9 2329.0 2330.5 2331.6 2331.8 2229.4 2254.4 2271.4 2275.1 2279.7 1957.5 2023.8 2248.1 2260.3 2262.9 1998.2 2194.4 2261.6 2263.0 2264.5 8129.7 8193.4 8193.7 8195.2 8200.8 4901.4 5071.8 5081.1 5083.3 5093.4 3029.4 3083.6 3108.1 3188.9 3220.5 990.8 1015.3 1021.3 1024.2 1044.4 11468.1 11481.4 11492.9 11500.9 11687.8 4388.3 4485.3 4583.4 4635.7 4668.2 9797.1 9808.2 9808.9 9813.4 9820.9 5102.2 5143.7 5154.4 5173.9 5191.5 4134.0 4615.3 4714.7 4717.4 4732.0 3824.8 3931.4 3935.5 3963.8 3976.9 1236.7 1238.9 1267.4 1276.7 1278.7 981.2 988.4 991.6 997.2 1018.5 5956.1 5975.2 5992.3 5996.9 6266.8 393.2 424.4 425.7 430.2 433.6 3979.9 4143.6 4172.4 4302.7 4475.7 9930.9 10278.7 10409.2 10417.0 10418.6 9926.2 10252.1 10286.4 10288.7 10306.6 7724.2 7772.7 7852.3 7870.0 7880.3 5837.2 5898.4 5951.5 5960.7 6067.6 9302.4 9541.4 9541.5 9547.8 9610.4 9487.3 9543.9 9761.2 9912.2 10042.0 6722.9 7352.9 7525.7 7587.2 7705.7 8100.1 8103.6 8140.7 8152.1 8164.3 7357.5 7394.6 7544.6 7566.2 7692.3 7394.9 7404.5 7453.0 7520.5 7571.8 6801.3 6866.7 6899.3 6909.5 6923.1 5013.5 5041.4 5156.5 5177.5 5187.0 5074.9 5219.1 5314.4 5355.3 5357.0 23:26:31 INFO - PROCESS | 25989 | 23:26:31 INFO - PROCESS | 25989 | [#4] prototype.html Cycles:180 Average:5010.99 Median:5784.33 stddev:1505.46 (26.0%) stddev-sans-first:1467.83 23:26:31 INFO - PROCESS | 25989 | Values: 314.3 315.6 319.6 325.9 329.8 5675.9 5775.3 5807.3 5811.0 5817.3 5729.3 5741.6 5748.0 5753.5 5759.5 4084.9 4140.9 4274.0 4310.6 4352.8 4298.8 4316.6 4316.9 4320.8 4321.6 4144.2 4327.1 4333.2 4337.8 4349.6 4233.0 4338.0 4348.7 4360.6 4389.5 3776.1 3786.4 3790.9 3795.3 3797.4 5428.6 5507.6 5537.2 5542.5 5551.7 4991.3 5003.0 5021.2 5035.5 5041.4 5919.0 5956.2 5963.4 5968.1 5984.7 4097.6 4204.3 4208.8 4223.6 4230.9 6190.2 6208.6 6209.9 6225.0 6232.3 4299.5 4301.8 4302.3 4317.5 4332.9 4275.1 4363.3 4381.8 4384.2 4391.6 4987.9 5010.9 5016.7 5027.3 5043.3 5992.7 6021.2 6022.2 6034.2 6041.2 1487.0 1508.7 1510.6 1515.4 1532.1 944.6 986.5 1020.1 1026.6 1037.6 5754.5 5778.6 5785.7 5788.3 5794.5 6091.6 6093.2 6104.4 6114.1 6148.3 5650.7 5684.4 5771.8 5782.9 5786.6 3232.0 3246.9 3268.7 3273.0 3276.7 6179.6 6361.0 6362.2 6377.3 6394.8 6147.3 6161.1 6166.9 6186.6 6202.7 6149.7 6357.5 6371.6 6376.6 6380.2 6038.5 6103.3 6106.5 6112.3 6115.8 6035.8 6057.6 6058.5 6066.6 6077.5 6162.4 6351.7 6355.7 6368.5 6372.2 4192.8 4209.1 4219.7 4227.6 4231.0 5924.6 6148.2 6168.5 6181.7 6183.7 5914.8 5942.8 5946.5 5952.3 5973.5 5887.8 5919.7 5959.8 5972.2 5974.0 5847.1 5854.2 5863.7 5999.0 6026.9 6095.1 6122.0 6150.0 6160.1 6168.7 5840.2 6008.7 6013.4 6019.5 6024.6 23:26:31 INFO - PROCESS | 25989 | 23:26:31 INFO - PROCESS | 25989 | [#5] yui.html Cycles:180 Average:13935.83 Median:23621.06 stddev:16430.41 (69.6%) stddev-sans-first:16446.90 23:26:31 INFO - PROCESS | 25989 | Values: 801.5 836.6 846.3 848.4 857.1 13235.8 13496.7 13511.0 13550.2 13550.5 12786.9 13133.5 13341.4 13343.8 13355.7 659.9 783.4 788.6 802.2 811.3 806.6 807.5 809.2 809.9 810.3 835.0 840.7 842.3 842.4 842.5 676.3 828.4 839.3 842.3 845.0 616.9 617.8 617.9 620.0 621.4 11492.7 11533.4 11535.6 11624.8 11633.8 12948.6 13111.7 13128.9 13243.4 13817.9 10343.6 10387.4 10435.1 10531.7 10779.1 3765.1 3776.4 3865.3 3908.6 3909.5 54182.7 55240.4 55255.7 55346.0 55526.0 10504.5 10504.6 10619.2 10706.9 11044.0 17253.2 17259.8 17268.7 17413.1 19081.0 7764.4 7766.9 7869.1 7895.8 8005.6 4335.1 4369.2 4378.1 4417.5 4450.0 3088.1 3156.9 3167.0 3170.9 3171.0 574.8 576.0 577.4 585.9 585.9 652.7 667.6 718.0 724.8 738.7 7929.4 8122.3 8694.6 9137.8 9156.1 673.7 674.7 754.8 767.7 773.7 3825.4 4191.9 4219.5 4265.5 4283.2 58897.4 59375.7 59720.0 59788.0 61510.7 51120.4 51167.0 51779.0 51838.7 52103.0 46051.5 47129.0 47374.5 47754.8 47901.5 7993.7 8012.8 8098.5 8197.9 8198.2 32027.7 33110.3 33591.8 33755.3 33859.7 39334.4 40988.6 42507.0 42616.4 42907.0 9811.9 10376.0 10432.1 10558.2 10710.3 10159.4 10979.9 11166.2 11189.3 11387.5 11463.2 11706.2 11861.1 11921.1 12346.6 10933.2 11024.9 11071.0 11329.7 11901.5 10724.9 10879.5 11211.6 11213.4 11446.9 9781.3 10093.5 10122.9 10526.6 10611.5 9851.3 9965.7 10079.2 10112.7 10220.2 23:26:31 INFO - PROCESS | 25989 | -------- Summary: end -------- 23:26:31 INFO - PROCESS | 25989 | 23:26:32 INFO - TEST-INFO | 25989: exit 0 23:26:32 INFO - TEST-OK | dromaeo_css | took 1113136ms 23:26:32 INFO - TEST-START | kraken 23:26:32 INFO - operating with platform_type : linux_ 23:26:32 INFO - Initialising browser for kraken test... 23:26:32 INFO - TEST-INFO | started process 26171 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2pZSBV/profile http://localhost:43047/getInfo.html) 23:26:36 INFO - PROCESS | 26171 | __metrics Screen width/height:1600/1200 23:26:36 INFO - PROCESS | 26171 | colorDepth:24 23:26:36 INFO - PROCESS | 26171 | Browser inner width/height: 1024/697 23:26:36 INFO - PROCESS | 26171 | __metrics 23:26:38 ERROR - PROCESS | 26171 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:26:38 INFO - TEST-INFO | 26171: exit 0 23:26:38 INFO - Browser initialized. 23:26:38 INFO - Running cycle 1/1 for kraken test... 23:26:38 INFO - TEST-INFO | started process 26255 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2pZSBV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:26:50 INFO - PROCESS | 26255 | RSS: Main: 153587712 23:26:50 INFO - PROCESS | 26255 | 23:26:53 INFO - PROCESS | 26255 | Cycle 1(1): loaded http://localhost:43047/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43047/tests/kraken/driver.html?testName=audio-beat-detection) 23:26:54 INFO - PROCESS | 26255 | RSS: Main: 208957440 23:26:54 INFO - PROCESS | 26255 | 23:26:56 INFO - PROCESS | 26255 | Cycle 1(1): loaded http://localhost:43047/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43047/tests/kraken/driver.html?testName=audio-dft) 23:26:57 INFO - PROCESS | 26255 | RSS: Main: 194269184 23:26:57 INFO - PROCESS | 26255 | 23:27:00 INFO - PROCESS | 26255 | Cycle 1(1): loaded http://localhost:43047/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43047/tests/kraken/driver.html?testName=audio-fft) 23:27:01 INFO - PROCESS | 26255 | RSS: Main: 265383936 23:27:01 INFO - PROCESS | 26255 | 23:27:03 INFO - PROCESS | 26255 | Cycle 1(1): loaded http://localhost:43047/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43047/tests/kraken/driver.html?testName=audio-oscillator) 23:27:03 INFO - PROCESS | 26255 | RSS: Main: 172683264 23:27:03 INFO - PROCESS | 26255 | 23:27:06 INFO - PROCESS | 26255 | Cycle 1(1): loaded http://localhost:43047/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43047/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:27:06 INFO - PROCESS | 26255 | RSS: Main: 177549312 23:27:06 INFO - PROCESS | 26255 | 23:27:09 INFO - PROCESS | 26255 | Cycle 1(1): loaded http://localhost:43047/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43047/tests/kraken/driver.html?testName=imaging-darkroom) 23:27:10 INFO - PROCESS | 26255 | RSS: Main: 213987328 23:27:10 INFO - PROCESS | 26255 | 23:27:13 INFO - PROCESS | 26255 | Cycle 1(1): loaded http://localhost:43047/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43047/tests/kraken/driver.html?testName=imaging-desaturate) 23:27:14 INFO - PROCESS | 26255 | RSS: Main: 213925888 23:27:14 INFO - PROCESS | 26255 | 23:27:16 INFO - PROCESS | 26255 | Cycle 1(1): loaded http://localhost:43047/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43047/tests/kraken/driver.html?testName=json-parse-financial) 23:27:17 INFO - PROCESS | 26255 | RSS: Main: 214454272 23:27:17 INFO - PROCESS | 26255 | 23:27:19 INFO - PROCESS | 26255 | Cycle 1(1): loaded http://localhost:43047/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43047/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:27:19 INFO - PROCESS | 26255 | RSS: Main: 202858496 23:27:19 INFO - PROCESS | 26255 | 23:27:21 INFO - PROCESS | 26255 | Cycle 1(1): loaded http://localhost:43047/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43047/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:27:21 INFO - PROCESS | 26255 | RSS: Main: 202686464 23:27:21 INFO - PROCESS | 26255 | 23:27:23 INFO - PROCESS | 26255 | Cycle 1(1): loaded http://localhost:43047/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43047/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:27:24 INFO - PROCESS | 26255 | RSS: Main: 213762048 23:27:24 INFO - PROCESS | 26255 | 23:27:27 INFO - PROCESS | 26255 | Cycle 1(1): loaded http://localhost:43047/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43047/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:27:27 INFO - PROCESS | 26255 | RSS: Main: 217694208 23:27:27 INFO - PROCESS | 26255 | 23:27:30 INFO - PROCESS | 26255 | Cycle 1(1): loaded http://localhost:43047/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43047/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:27:31 INFO - PROCESS | 26255 | RSS: Main: 216047616 23:27:31 INFO - PROCESS | 26255 | 23:27:32 INFO - PROCESS | 26255 | Cycle 1(1): loaded http://localhost:43047/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43047/tests/kraken/driver.html?testName=ai-astar) 23:27:33 INFO - PROCESS | 26255 | RSS: Main: 280588288 23:27:33 INFO - PROCESS | 26255 | 23:27:33 INFO - PROCESS | 26255 | __start_tp_report 23:27:33 INFO - PROCESS | 26255 | _x_x_mozilla_page_load 23:27:33 INFO - PROCESS | 26255 | _x_x_mozilla_page_load_details 23:27:33 INFO - PROCESS | 26255 | |i|pagename|runs| 23:27:33 INFO - PROCESS | 26255 | |0;ai-astar;111;111;106;110;112;112;101;110;109;110 23:27:33 INFO - PROCESS | 26255 | |1;audio-beat-detection;159;134;163;191;128;153;157;128;206;128 23:27:33 INFO - PROCESS | 26255 | |2;audio-dft;161;161;161;161;161;162;162;164;160;160 23:27:33 INFO - PROCESS | 26255 | |3;audio-fft;73;76;100;118;75;72;76;116;105;76 23:27:33 INFO - PROCESS | 26255 | |4;audio-oscillator;92;93;147;92;92;92;151;92;92;145 23:27:33 INFO - PROCESS | 26255 | |5;imaging-gaussian-blur;117;117;117;116;117;117;117;117;117;117 23:27:33 INFO - PROCESS | 26255 | |6;imaging-darkroom;146;146;145;147;145;146;146;146;145;147 23:27:33 INFO - PROCESS | 26255 | |7;imaging-desaturate;95;95;95;95;94;95;94;95;94;94 23:27:33 INFO - PROCESS | 26255 | |8;json-parse-financial;57;56;58;64;59;60;57;81;55;54 23:27:33 INFO - PROCESS | 26255 | |9;json-stringify-tinderbox;61;62;62;61;61;64;62;61;64;60 23:27:33 INFO - PROCESS | 26255 | |10;stanford-crypto-aes;76;76;83;77;76;77;77;77;77;77 23:27:33 INFO - PROCESS | 26255 | |11;stanford-crypto-ccm;127;130;124;125;122;123;131;130;203;125 23:27:33 INFO - PROCESS | 26255 | |12;stanford-crypto-pbkdf2;167;165;164;170;171;167;172;166;172;165 23:27:33 INFO - PROCESS | 26255 | |13;stanford-crypto-sha256-iterative;69;69;68;67;66;67;68;67;69;68 23:27:33 INFO - PROCESS | 26255 | __end_tp_report 23:27:33 INFO - PROCESS | 26255 | __start_cc_report 23:27:33 INFO - PROCESS | 26255 | _x_x_mozilla_cycle_collect,1616 23:27:33 INFO - PROCESS | 26255 | __end_cc_report 23:27:33 INFO - PROCESS | 26255 | __startTimestamp1461565653099__endTimestamp 23:27:33 INFO - PROCESS | 26255 | 23:27:33 INFO - PROCESS | 26255 | ------- Summary: start ------- 23:27:33 INFO - PROCESS | 26255 | Number of tests: 14 23:27:33 INFO - PROCESS | 26255 | 23:27:33 INFO - PROCESS | 26255 | [#0] ai-astar Cycles:10 Average:109.20 Median:110.50 stddev:3.36 (3.0%) stddev-sans-first:3.50 23:27:33 INFO - PROCESS | 26255 | Values: 111.0 111.0 106.0 110.0 112.0 112.0 101.0 110.0 109.0 110.0 23:27:33 INFO - PROCESS | 26255 | 23:27:33 INFO - PROCESS | 26255 | [#1] audio-beat-detection Cycles:10 Average:154.70 Median:158.00 stddev:27.10 (17.2%) stddev-sans-first:28.70 23:27:33 INFO - PROCESS | 26255 | Values: 159.0 134.0 163.0 191.0 128.0 153.0 157.0 128.0 206.0 128.0 23:27:33 INFO - PROCESS | 26255 | 23:27:33 INFO - PROCESS | 26255 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:1.16 (0.7%) stddev-sans-first:1.22 23:27:33 INFO - PROCESS | 26255 | Values: 161.0 161.0 161.0 161.0 161.0 162.0 162.0 164.0 160.0 160.0 23:27:33 INFO - PROCESS | 26255 | 23:27:33 INFO - PROCESS | 26255 | [#3] audio-fft Cycles:10 Average:88.70 Median:74.00 stddev:18.84 (25.5%) stddev-sans-first:19.11 23:27:33 INFO - PROCESS | 26255 | Values: 73.0 76.0 100.0 118.0 75.0 72.0 76.0 116.0 105.0 76.0 23:27:33 INFO - PROCESS | 26255 | 23:27:33 INFO - PROCESS | 26255 | [#4] audio-oscillator Cycles:10 Average:108.80 Median:92.00 stddev:26.86 (29.2%) stddev-sans-first:27.79 23:27:33 INFO - PROCESS | 26255 | Values: 92.0 93.0 147.0 92.0 92.0 92.0 151.0 92.0 92.0 145.0 23:27:33 INFO - PROCESS | 26255 | 23:27:33 INFO - PROCESS | 26255 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 23:27:33 INFO - PROCESS | 26255 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 23:27:33 INFO - PROCESS | 26255 | 23:27:33 INFO - PROCESS | 26255 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 23:27:33 INFO - PROCESS | 26255 | Values: 146.0 146.0 145.0 147.0 145.0 146.0 146.0 146.0 145.0 147.0 23:27:33 INFO - PROCESS | 26255 | 23:27:33 INFO - PROCESS | 26255 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 23:27:33 INFO - PROCESS | 26255 | Values: 95.0 95.0 95.0 95.0 94.0 95.0 94.0 95.0 94.0 94.0 23:27:33 INFO - PROCESS | 26255 | 23:27:33 INFO - PROCESS | 26255 | [#8] json-parse-financial Cycles:10 Average:60.10 Median:58.50 stddev:7.87 (13.4%) stddev-sans-first:8.26 23:27:33 INFO - PROCESS | 26255 | Values: 57.0 56.0 58.0 64.0 59.0 60.0 57.0 81.0 55.0 54.0 23:27:33 INFO - PROCESS | 26255 | 23:27:33 INFO - PROCESS | 26255 | [#9] json-stringify-tinderbox Cycles:10 Average:61.80 Median:62.00 stddev:1.32 (2.1%) stddev-sans-first:1.36 23:27:33 INFO - PROCESS | 26255 | Values: 61.0 62.0 62.0 61.0 61.0 64.0 62.0 61.0 64.0 60.0 23:27:33 INFO - PROCESS | 26255 | 23:27:33 INFO - PROCESS | 26255 | [#10] stanford-crypto-aes Cycles:10 Average:77.30 Median:77.00 stddev:2.06 (2.7%) stddev-sans-first:2.13 23:27:33 INFO - PROCESS | 26255 | Values: 76.0 76.0 83.0 77.0 76.0 77.0 77.0 77.0 77.0 77.0 23:27:33 INFO - PROCESS | 26255 | 23:27:33 INFO - PROCESS | 26255 | [#11] stanford-crypto-ccm Cycles:10 Average:134.00 Median:128.50 stddev:24.44 (19.0%) stddev-sans-first:25.80 23:27:33 INFO - PROCESS | 26255 | Values: 127.0 130.0 124.0 125.0 122.0 123.0 131.0 130.0 203.0 125.0 23:27:33 INFO - PROCESS | 26255 | 23:27:33 INFO - PROCESS | 26255 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.90 Median:168.50 stddev:3.07 (1.8%) stddev-sans-first:3.24 23:27:33 INFO - PROCESS | 26255 | Values: 167.0 165.0 164.0 170.0 171.0 167.0 172.0 166.0 172.0 165.0 23:27:33 INFO - PROCESS | 26255 | 23:27:33 INFO - PROCESS | 26255 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:1.03 (1.5%) stddev-sans-first:1.00 23:27:33 INFO - PROCESS | 26255 | Values: 69.0 69.0 68.0 67.0 66.0 67.0 68.0 67.0 69.0 68.0 23:27:33 INFO - PROCESS | 26255 | -------- Summary: end -------- 23:27:33 INFO - PROCESS | 26255 | 23:27:33 INFO - TEST-INFO | 26255: exit 0 23:27:34 INFO - TEST-OK | kraken | took 61536ms 23:27:34 INFO - SUITE-END | took 1174s 23:27:34 INFO - Completed test suite (00:19:34) 23:27:34 INFO - javascript benchmark 23:27:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3082.306481041765, 3110.3001824589455, 3146.3707258548297, 3149.7637677174203, 3177.396294704006, 5168.560672342846, 5197.0645283848935, 5206.114960456726, 5206.427292997767, 5237.816676416326, 5307.52232299093, 5312.229726689638, 5321.866953326169, 5323.733813309338, 5324.973375133119, 4692.835750748724, 4713.740744259065, 4803.61571074315, 4840.830570930018, 4891.192953162724, 4766.594839438634, 4770.618350531967, 4827.25177597473, 4831.033793241349, 4837.806487740504, 4650.930236046462, 4670.76646167692, 4674.345591617176, 4677.181493238703, 4741.834035808747, 4764.666473346867, 4774.236122220463, 4777.856664300042, 4778.784954677048, 4806.038792241566, 4673.485916549177, 4732.929006064912, 4750.311204875308, 4754.9286760698615, 4797.832075877328, 5060.291559181717, 5095.719735414551, 5134.717590532519, 5147.871303217412, 5158.638895277295, 5432.320959880015, 5435.266239057755, 5441.347038355383, 5447.618666693333, 5464.754086066137, 5438.945610543883, 5496.1755736639425, 5527.281453411071, 5552.361478429992, 5572.60991730579, 5147.639665223436, 5165.380154381461, 5215.16557350822, 5243.056249874983, 5247.47525247476, 5645.661260324394, 5650.26546548946, 5653.434656534314, 5653.773849045992, 5663.235463212496, 5385.299911011543, 5429.077056900336, 5479.232907392969, 5479.972600137054, 5489.451054894558, 5498.5876059295715, 5517.089680202722, 5530.834074977757, 5537.640053396597, 5553.722313884289, 5502.9174562381595, 5508.20131080991, 5508.20131080999, 5511.917321240185, 5563.221149039137, 5395.973020134874, 5531.170324451365, 5578.721063946787, 5611.579131565149, 5651.773929042874, 4178.415021896937, 4183.414321994923, 4188.476440444944, 4189.895252618709, 4216.241076606179, 3770.981145094257, 3772.773633581994, 3783.6027217142355, 3801.3157631626573, 3804.6956243500454, 5094.337736094358, 5225.973870130625, 5257.658252213594, 5272.2882410874045, 5298.97350513245, 5534.889302213933, 5536.2249285100115, 5550.555955523549, 5559.722013899289, 5565.582581306418, 5146.099432599281, 5253.894922101536, 5269.288646032736, 5275.076861549213, 5322.92015619766, 4795.496472870368, 4811.735354555533, 4828.324034635154, 4870.220764677634, 4873.171560834667, 5763.711814409263, 5776.451237132465, 5787.305523337226, 5788.18965344852, 5792.044312688359, 5479.479449452296, 5535.612507124623, 5561.304836895388, 5565.666060036411, 5597.608167428241, 5660.066089095418, 5676.716164191691, 5677.659340439422, 5712.5429965601825, 5725.454127385666, 5414.539764120094, 5416.187571864094, 5418.945810541844, 5419.10584753527, 5422.566194704493, 5486.31421072366, 5496.065668836228, 5511.393746687781, 5551.2228288040515, 5562.833115006556, 5322.574194064388, 5328.706921119376, 5329.0, 5330.147176451749, 5390.622656414013, 5314.096603577478, 5318.335208098988, 5322.627416080837, 5340.279062326692, 5365.597580181424, 5250.343707036621, 5279.894402112013, 5296.947030529799, 5376.704281264568, 5387.86530336729, 5409.864753381197, 5410.513053825017, 5413.810516631899, 5434.2935418395355, 5437.048516509674, 5283.260343551829, 5306.787728490972, 5306.946930530646, 5314.096603577478, 5360.865978350573], "value": 5115.174863639797, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.9970650146748, 642.1170890026214, 646.6120327803319, 647.1586936981922, 660.9074729537869, 30332.696673033264, 31013.379732405367, 31039.844800775994, 36112.0, 37463.43804842923, 39374.0, 40158.799206004005, 40162.19675606486, 40240.19519609613, 40249.597504024954, 8653.961524617054, 8878.733637990837, 8880.6003729832, 8881.555922203896, 8882.467051976902, 7481.139668938087, 7703.037120359955, 8689.869651955225, 10229.744256393575, 10599.258051936367, 10260.74348141295, 10372.896271037269, 10372.896271037307, 10382.325148865337, 10383.428911409907, 7568.810779730551, 10120.848187277197, 10231.846522302172, 10246.590136394536, 10256.0, 9848.162906152986, 10169.13562347194, 10176.0, 10176.694699159038, 10176.79646407075, 38076.096195190126, 38742.22515549673, 38847.22305553901, 38986.80506597449, 39011.219775604615, 3475.94786078209, 3482.233908540117, 3603.819809009539, 3667.853285868562, 3701.555813302394, 28620.14139575816, 29536.0, 29690.25774355637, 32048.519272210935, 32457.0, 4229.767362795076, 4266.530681625019, 4275.978620106911, 4315.611594956437, 4367.323064924942, 312747.0, 318710.0, 319240.0, 319437.0, 319781.0, 61176.0, 61334.693326533976, 61718.0, 61780.69109654423, 61888.381116189164, 64516.354836451974, 64666.676666617306, 66530.33469665339, 66648.33351666518, 66668.0, 16024.27890744896, 16177.514674559781, 16194.838051619568, 16203.756943645854, 16205.756913646304, 11874.465649045813, 11887.28676279426, 11905.345206013752, 11910.404479775976, 11914.0, 20060.097295621734, 20110.597788044157, 20267.898660506602, 20301.492462688257, 20308.18767249323, 7287.307705767944, 7314.524555903849, 7462.179160292363, 7490.5431268396305, 7716.807079823049, 921.953902304882, 930.3905941608258, 1014.1531820929459, 1023.9180865530889, 1086.8804431512526, 28170.85914570414, 29233.269168270537, 29899.252518687208, 30350.241243969078, 30614.84692576523, 1063.813832579311, 1066.440118937564, 1070.9625163119408, 1087.8857719939451, 1119.6752941646994, 15833.841661583467, 16084.597885052968, 16255.18724063792, 16256.837431625769, 16619.750703739453, 463581.0, 470617.8221980019, 472187.1422214884, 472636.0, 472893.0, 56663.71668141715, 57204.0, 57245.0, 57648.711756441786, 57661.423385766444, 1215.9939200303943, 1272.7709012377861, 1319.64369620202, 1353.4653811744306, 1374.3815283122435, 28133.57799633048, 29371.0, 29460.263493411978, 29712.108636740933, 30199.09402717922, 41987.1602567953, 42547.787261063495, 42950.14099717926, 42957.14085718331, 43068.0, 283571.0, 286199.0, 287710.0, 288325.0, 288719.0, 24591.13931012406, 25737.0, 26309.0, 26614.0, 27085.729142709108, 41964.790176048926, 42167.0, 42244.36633450562, 42762.0, 43392.0, 36635.63364366322, 37053.44419833651, 37086.81456592808, 37485.62514374821, 37537.624623754506, 36010.01944893056, 36072.09819754422, 36454.81772591226, 36942.63057369392, 40045.199096017335, 39705.0, 39840.80079599699, 39984.2003159941, 40961.59038409697, 41178.0, 38639.0, 39399.80300098481, 40216.597834022454, 40318.0, 40578.79710601546, 30678.693213068476, 31759.682403175673, 32067.518987214742, 32251.51622725614, 32665.0], "value": 19417.07666326307, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.5645866107324, 371.1093375897845, 376.766404829006, 378.7386703174811, 379.3399484896281, 17669.55826104348, 18785.154668039937, 18961.810381896175, 19147.0, 19230.807691923095, 17700.380486682967, 17870.28518859244, 18414.539636509093, 18446.446606601825, 18462.907685461552, 5550.639208451458, 5708.714564271791, 5739.139129130622, 5760.193572899798, 5763.0, 3754.943675844851, 3927.8821635350987, 3931.9213615727667, 3934.4098385242155, 5257.684538927658, 5130.153524668425, 5411.350637923435, 5434.755436005369, 5436.809711660093, 5448.618596698233, 5387.757550910218, 5419.403865574784, 5446.645968012067, 5573.637713548626, 5591.720413979285, 3642.7085833133287, 5263.236830659533, 5420.430854760233, 5430.15832545956, 5436.184572314184, 20606.55754097214, 20781.792182078138, 21203.68194477084, 21829.126834926585, 22468.517379806155, 2239.29462219402, 2272.306946381353, 2372.905083796646, 2453.4234454903085, 2804.326961529239, 9364.812703745887, 9962.302638815287, 10285.9485702571, 12489.937550312281, 12828.294443805587, 1873.49415657773, 1877.8216069473378, 1930.3533316339044, 1931.661959157156, 1966.6165097805933, 73018.63490682565, 77162.22837771606, 77641.61179194125, 77692.61153694252, 78772.21227787706, 10248.743781405376, 10429.895701043044, 10441.582336706446, 10465.319754215952, 10624.734381640521, 14559.199244041467, 14620.634484137981, 14621.707565848623, 14636.780448293284, 14657.56027319182, 7526.623668816537, 7954.2841144296135, 8032.678692852337, 8109.067457242492, 8173.427860049739, 7439.51643143194, 7491.288327608869, 7689.615519224017, 7848.254415830487, 7940.563269020144, 4092.959070409258, 4145.191687620885, 4496.662750293741, 4519.81920723174, 4540.523245059288, 1668.6579251253706, 1757.5606098475382, 1798.739182818484, 1897.7627796525435, 1906.046976511744, 917.1287277086794, 934.3693007220099, 964.286428043243, 989.4706332112286, 1049.4385503755454, 17924.91037544804, 18233.452996410128, 18324.81675183258, 18665.346712864986, 19137.904310478636, 930.2511278795955, 938.380668758616, 960.1838437328355, 974.1427543761445, 1038.6416686243233, 10181.643642472478, 10468.63359782404, 10622.787544249071, 10625.574977000833, 10662.520186591622, 507630.0, 517554.0, 518645.0, 519513.0, 522217.0, 94004.52997734968, 94634.52682736542, 95020.0, 95203.0, 95457.522712386, 978.8727465429591, 984.1978787288338, 993.5876611206269, 1013.022433351819, 1014.264658122855, 16081.781207426096, 16246.187690615185, 16536.834631653528, 16638.334466621684, 16862.24119914582, 34041.0, 34206.0, 35339.82330088436, 35708.0, 38037.80981095077, 45626.31560526661, 46909.2963605553, 50950.0, 53639.463605363446, 58931.70534147302, 7606.239376062349, 8063.27430531255, 8235.38234632417, 8319.376046796393, 8617.82764344697, 8286.668533258844, 13006.479740810262, 13187.274699891597, 13383.866161338263, 13613.591592252249, 11800.70498237551, 11801.409929503663, 11989.22070065443, 12027.518899244282, 12210.389480526115, 10425.9478702606, 10613.681589552325, 11003.504842281956, 11531.192816502764, 11753.294802311888, 10683.626073087406, 11805.23265987708, 11811.586594469152, 11928.76142477163, 12116.636500904988, 7949.8410031800195, 7983.0, 8304.501729895985, 8337.0, 8428.0729119798, 11713.238639488405, 11912.642620721392, 11940.582079627171, 12057.457414416196, 12180.390980450764], "value": 8830.700749931286, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1146.1747541769932, 1503.9849601503981, 1506.5856889355427, 1659.6348803263277, 1780.9732854007193, 7960.522368657877, 8182.590870456475, 8261.17388261175, 8275.958620206906, 8291.543965081919, 8094.757157285276, 8241.29948954338, 8242.381821363391, 8245.67017319308, 8248.463849849755, 2007.7891821358767, 2069.4412508622663, 2070.937871863839, 2274.7270327560673, 2303.5047464795066, 2259.8644081355087, 2329.033451117784, 2330.475642980333, 2331.568659797943, 2331.7901388875, 2229.398062523118, 2254.41385239838, 2271.375371772759, 2275.1468199425217, 2279.669447930049, 1957.4910523263911, 2023.8380929525604, 2248.1119957616734, 2260.3219034289727, 2262.864228146308, 1998.2406685459432, 2194.3746032380695, 2261.604219261623, 2262.9547409051725, 2264.467850055236, 8129.674813007473, 8193.385496087758, 8193.71322003727, 8195.221453961864, 8200.83598328036, 4901.387326584177, 5071.822486213002, 5081.110805609041, 5083.262926875583, 5093.414257360377, 3029.439553682576, 3083.5528848316867, 3108.145260053481, 3188.872445102193, 3220.533022711694, 990.7671697151163, 1015.2588610314511, 1021.2697920986456, 1024.1704219582161, 1044.4151275285788, 11468.082553395709, 11481.425928703615, 11492.94253528727, 11500.884991150066, 11687.824682629767, 4388.253996820548, 4485.282354823278, 4583.427071616048, 4635.6986795858165, 4668.229742092584, 9797.06927841854, 9808.16630586408, 9808.9019109808, 9813.411195328303, 9820.901790982141, 5102.158143906214, 5143.691378517301, 5154.433012368637, 5173.896522069537, 5191.480851914854, 4133.9793301033305, 4615.284630882219, 4714.717116972993, 4717.410323709536, 4732.0, 3824.770513769183, 3931.4102884566973, 3935.5474120475938, 3963.7621742695533, 3976.900577485528, 1236.6784635994527, 1238.8761123887539, 1267.3789842976876, 1276.6616846535676, 1278.7378587389749, 981.1709105805583, 988.367444835306, 991.5934466868694, 997.1673652500099, 1018.495844556949, 5956.106584012433, 5975.19334889793, 5992.250968628921, 5996.850078748066, 6266.780662676784, 393.2076865116792, 424.35074336264944, 425.6956276262425, 430.2148578843623, 433.59458905922435, 3979.9204015919518, 4143.606357396043, 4172.435336748719, 4302.720323178984, 4475.686701930834, 9930.900690993141, 10278.743031424125, 10409.167266618653, 10417.0, 10418.635347762793, 9926.2059035277, 10252.128569071712, 10286.434246116385, 10288.742781430374, 10306.639267625596, 7724.188960159102, 7772.68909243624, 7852.293293603603, 7870.0, 7880.290773830268, 5837.182794408871, 5898.351181370132, 5951.523878089829, 5960.731767070579, 6067.575269731076, 9302.441853488812, 9541.427514348883, 9541.522923853918, 9547.809043818947, 9610.375325603813, 9487.33588648788, 9543.9045609543, 9761.219102471927, 9912.207023438252, 10042.0, 6722.932770672231, 7352.889706654511, 7525.661345239368, 7587.203343648837, 7705.691772329044, 8100.108988011196, 8103.594820259082, 8140.674373025013, 8152.103268640326, 8164.306033987177, 7357.5217610855125, 7394.55632662042, 7544.585047822423, 7566.167721550515, 7692.307692307719, 7394.889076663747, 7404.518706284074, 7452.962735186289, 7520.473566850268, 7571.848563028819, 6801.251862295243, 6866.725330986705, 6899.344562266577, 6909.516333856582, 6923.065386173003, 5013.523715247045, 5041.369828771403, 5156.535911767959, 5177.508136727005, 5186.974065129802, 5074.898502030012, 5219.137837999929, 5314.395148420057, 5355.277037599874, 5357.008953343644], "value": 4385.8182296760715, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.26462078735756, 315.60234105027666, 319.59411547334884, 325.92666650003736, 329.8169515918664, 5675.914861277084, 5775.3358363788175, 5807.332156801969, 5811.0, 5817.272840894888, 5729.28383952006, 5741.645925170681, 5748.0, 5753.453421924911, 5759.510441612468, 4084.8774536763945, 4140.9378859316985, 4274.0, 4310.59049390309, 4352.7605981670995, 4298.763568003758, 4316.56834316569, 4316.913661726763, 4320.805563749639, 4321.611055005049, 4144.23331683638, 4327.052826228194, 4333.2416827055295, 4337.848175313865, 4349.630281426083, 4233.005243767712, 4337.956620433818, 4348.717333373321, 4360.585744354281, 4389.47326320842, 3776.131489757371, 3786.4320351947154, 3790.8673196438267, 3795.2788970095594, 3797.4493698413576, 5428.647137936022, 5507.5593952484105, 5537.224788529609, 5542.501174894279, 5551.666899986014, 4991.276264941564, 5002.974985125087, 5021.196608542652, 5035.496450354972, 5041.395032596075, 5918.970405147989, 5956.195913551658, 5963.403659634046, 5968.074948383008, 5984.73068711909, 4097.569755175693, 4204.327307630764, 4208.810603522848, 4223.64099051581, 4230.851920182809, 6190.21440351839, 6208.596441231305, 6209.937900620981, 6225.035119556476, 6232.252129744415, 4299.527052024275, 4301.78491075445, 4302.290122129813, 4317.525072242051, 4332.870013899588, 4275.1022285319805, 4363.323684828857, 4381.78091095444, 4384.167008268419, 4391.6486681065435, 4987.875303117451, 5010.949890501049, 5016.724080175552, 5027.296178535008, 5043.293938848564, 5992.730327135289, 6021.157038014681, 6022.2171117754415, 6034.24571928509, 6041.184440100618, 1486.9851301487063, 1508.7284288828116, 1510.5996910818421, 1515.4165646226281, 1532.1190315568592, 944.622151139536, 986.4623780039896, 1020.0972139656458, 1026.5585798106886, 1037.5642230263309, 5754.539636829044, 5778.566607504454, 5785.739641716133, 5788.27646544182, 5794.536437085023, 6091.573589848668, 6093.177421048191, 6104.4200800923845, 6114.144019837227, 6148.323684394713, 5650.717464126859, 5684.403137670568, 5771.769129234784, 5782.913256301159, 5786.623869448473, 3232.0, 3246.935061298761, 3268.6731326867125, 3273.018094571619, 3276.6887145721116, 6179.598326108879, 6361.0, 6362.236531616143, 6377.266614339317, 6394.776182833672, 6147.262328520606, 6161.106639537242, 6166.907496387557, 6186.597871138361, 6202.658853762996, 6149.692515374124, 6357.4914006879335, 6371.649559274192, 6376.608771710608, 6380.234371875312, 6038.486728628116, 6103.328633850362, 6106.480949119375, 6112.32764395925, 6115.785947491817, 6035.849103772441, 6057.636541807506, 6058.4850287724275, 6066.605670631395, 6077.4834139098675, 6162.414570615784, 6351.697901930001, 6355.650439225702, 6368.522360822864, 6372.171617689856, 4192.83228670862, 4209.116085621931, 4219.7046206765235, 4227.556106608762, 4230.95769042318, 5924.6149000314845, 6148.231471066117, 6168.475679567107, 6181.721822518088, 6183.659898705434, 5914.846604912001, 5942.7920022799, 5946.494547963299, 5952.345242023461, 5973.462388385066, 5887.8233652990475, 5919.733611987384, 5959.821205363846, 5972.193753843176, 5974.014287642491, 5847.1229315603, 5854.180414741854, 5863.73613187399, 5999.0, 6026.909596356146, 6095.1466794647895, 6122.0, 6150.0, 6160.10678451631, 6168.691565421801, 5840.240768700041, 6008.669523176092, 6013.368596297326, 6019.4883434908525, 6024.638521688713], "value": 4520.161614028024, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [801.4950581133886, 836.5984327522789, 846.3271698999295, 848.376443314164, 857.1214505132239, 13235.801462978052, 13496.730065398686, 13511.0, 13550.18698878068, 13550.525731599397, 12786.936065319684, 13133.540326088565, 13341.399637016333, 13343.799843002364, 13355.666108347295, 659.8614290998896, 783.4202690009381, 788.6254029336062, 802.1697543042923, 811.2698571285861, 806.5846089264033, 807.4751411582489, 809.2150613904503, 809.9311558517533, 810.2707563193144, 834.9707760228393, 840.7477756672973, 842.2672275120623, 842.4271495383138, 842.4692443760422, 676.3067855448156, 828.3994104274414, 839.2949922065434, 842.2882664148784, 845.0197770586152, 616.9012957926755, 617.783775678509, 617.8949578571609, 619.9845003874894, 621.4034526854225, 11492.712682182928, 11533.423328833609, 11535.59625413115, 11624.825627615508, 11633.825492617618, 12948.611541653765, 13111.737765244738, 13128.86871131286, 13243.404046817916, 13817.930910345383, 10343.637972670997, 10387.37675739458, 10435.113015393701, 10531.73670658232, 10779.08377787889, 3765.1340191755776, 3776.3580191367532, 3865.3428917083884, 3908.6286802753693, 3909.4722212501515, 54182.72908635471, 55240.44759552393, 55255.72372138114, 55346.0, 55526.0, 10504.474776261157, 10504.632337868137, 10619.203559732896, 10706.89293107059, 11044.0, 17253.223604937808, 17259.827401726074, 17268.740968885475, 17413.12934353277, 19081.0, 7764.3788496920115, 7766.922330776733, 7869.0950540688555, 7895.842083158304, 8005.599720013976, 4335.111302183045, 4369.213541562549, 4378.146261478981, 4417.469903611523, 4450.0, 3088.135322109813, 3156.873725050972, 3167.0, 3170.936581268362, 3170.9682903171133, 574.8390450673818, 575.9769609215584, 577.3648986115239, 585.9033259512217, 585.9267591551056, 652.7160685101995, 667.6127845849396, 717.9676914538858, 724.8260417499868, 738.674983007475, 7929.365650747926, 8122.309603683753, 8694.565271736388, 9137.76640153584, 9156.084391560924, 673.6867356679168, 674.7267356720536, 754.7773406844993, 767.6545554500497, 773.6518566645033, 3825.387937929918, 4191.8532851350665, 4219.535851056381, 4265.50946641134, 4283.225944909978, 58897.41102589005, 59375.70312148498, 59720.0, 59788.0, 61510.69244653748, 51120.42157049045, 51167.0, 51779.0, 51838.74080629648, 52102.99999999924, 46051.5394846054, 47128.999999999316, 47374.5262547377, 47754.76122619434, 47901.520984790404, 7993.6802527898235, 8012.799680008046, 8098.51408915467, 8197.877031844408, 8198.180181981754, 32027.67972320247, 33110.337793244486, 33591.83204083964, 33755.32489350248, 33859.661403386635, 39334.40998384969, 40988.59011409967, 42507.0, 42616.360754589325, 42907.0, 9811.901880981384, 10376.0, 10432.113270372103, 10558.155347572023, 10710.30383025132, 10159.390436573829, 10979.890201097887, 11166.218364714718, 11189.272697274651, 11387.487563059516, 11463.197576169589, 11706.239094458833, 11861.110416718953, 11921.105917056082, 12346.629601111981, 10933.180011499011, 11024.889751102386, 11071.0, 11329.660110196708, 11901.464434100313, 10724.89275107239, 10879.510422031186, 11211.55153793839, 11213.383263920563, 11446.94276528612, 9781.315307928377, 10093.545790439595, 10122.949385253027, 10526.578936842441, 10611.469426528796, 9851.261155413229, 9965.651202207888, 10079.193664506969, 10112.696619101438, 10220.233482488695], "value": 6356.440335754593, "unit": "score"}], "name": "dromaeo_css", "value": 6927.613197583745, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 106.0, 110.0, 112.0, 112.0, 101.0, 110.0, 109.0, 110.0], "value": 109.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 134.0, 163.0, 191.0, 128.0, 153.0, 157.0, 128.0, 206.0, 128.0], "value": 154.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 161.0, 161.0, 162.0, 162.0, 164.0, 160.0, 160.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 76.0, 100.0, 118.0, 75.0, 72.0, 76.0, 116.0, 105.0, 76.0], "value": 88.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 147.0, 92.0, 92.0, 92.0, 151.0, 92.0, 92.0, 145.0], "value": 108.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 147.0, 145.0, 146.0, 146.0, 146.0, 145.0, 147.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 94.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 58.0, 64.0, 59.0, 60.0, 57.0, 81.0, 55.0, 54.0], "value": 60.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 61.0, 61.0, 64.0, 62.0, 61.0, 64.0, 60.0], "value": 61.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 83.0, 77.0, 76.0, 77.0, 77.0, 77.0, 77.0, 77.0], "value": 77.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 130.0, 124.0, 125.0, 122.0, 123.0, 131.0, 130.0, 203.0, 125.0], "value": 134.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 165.0, 164.0, 170.0, 171.0, 167.0, 172.0, 166.0, 172.0, 165.0], "value": 167.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 68.0, 67.0, 66.0, 67.0, 68.0, 67.0, 69.0, 68.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1548.9999999999998, "alertThreshold": 2.0}]} 23:27:34 INFO - Return code: 0 23:27:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:27:34 ERROR - # TBPL SUCCESS # 23:27:34 INFO - Running post-action listener: _resource_record_post_action 23:27:34 INFO - Running post-run listener: _resource_record_post_run 23:27:35 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 535961600; Read time: 12; Write time: 1080864 23:27:35 INFO - install - Wall time: 17s; CPU: 17.0%; Read bytes: 0; Write bytes: 251412480; Read time: 0; Write time: 813236 23:27:35 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 284549120; Read time: 12; Write time: 267628 23:27:35 INFO - Running post-run listener: _upload_blobber_files 23:27:35 INFO - Blob upload gear active. 23:27:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:27:35 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:27:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:27:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:27:35 INFO - (blobuploader) - INFO - Open directory for files ... 23:27:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:27:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:27:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:27:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:27:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:27:36 INFO - (blobuploader) - INFO - Done attempting. 23:27:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:27:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:27:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:27:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:27:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:27:37 INFO - (blobuploader) - INFO - Done attempting. 23:27:37 INFO - (blobuploader) - INFO - Iteration through files over. 23:27:37 INFO - Return code: 0 23:27:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:27:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:27:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3a904f1666ffe46e08d90bee16a11ee200b3179308a8d93233c3387a3517f9d23fb2f78851cc8a6cc0d7c12a7d65297efc814ee073f3c6c792ff73a1317530b4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a69290d7fa29c9976efd2edba35e6955b9de0e02ef6dee8997aab11eaa6e0f9fa8beff3e1d140fc45aecc31493cd1f57200d5146d19146bbb79870dbb3f0c00"} 23:27:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:27:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:27:37 INFO - Contents: 23:27:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3a904f1666ffe46e08d90bee16a11ee200b3179308a8d93233c3387a3517f9d23fb2f78851cc8a6cc0d7c12a7d65297efc814ee073f3c6c792ff73a1317530b4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a69290d7fa29c9976efd2edba35e6955b9de0e02ef6dee8997aab11eaa6e0f9fa8beff3e1d140fc45aecc31493cd1f57200d5146d19146bbb79870dbb3f0c00"} 23:27:37 INFO - Running post-run listener: copy_logs_to_upload_dir 23:27:37 INFO - Copying logs to upload dir... 23:27:37 INFO - mkdir: /builds/slave/test/build/upload/logs 23:27:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.509025 ========= master_lag: 286.32 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 25 mins, 39 secs) (at 2016-04-24 23:32:22.009309) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-24 23:32:22.017327) ========= 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=af724c981e2a0f424dbabf99000001e7-1461561215.764862-835822559 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3a904f1666ffe46e08d90bee16a11ee200b3179308a8d93233c3387a3517f9d23fb2f78851cc8a6cc0d7c12a7d65297efc814ee073f3c6c792ff73a1317530b4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a69290d7fa29c9976efd2edba35e6955b9de0e02ef6dee8997aab11eaa6e0f9fa8beff3e1d140fc45aecc31493cd1f57200d5146d19146bbb79870dbb3f0c00"} build_url:https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011357 build_url: 'https://queue.taskcluster.net/v1/task/Nb0la4KST6aIQ-du5KEH_g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3a904f1666ffe46e08d90bee16a11ee200b3179308a8d93233c3387a3517f9d23fb2f78851cc8a6cc0d7c12a7d65297efc814ee073f3c6c792ff73a1317530b4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a69290d7fa29c9976efd2edba35e6955b9de0e02ef6dee8997aab11eaa6e0f9fa8beff3e1d140fc45aecc31493cd1f57200d5146d19146bbb79870dbb3f0c00"}' ========= master_lag: 0.48 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-24 23:32:22.512326) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 23:32:22.512738) ========= 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=af724c981e2a0f424dbabf99000001e7-1461561215.764862-835822559 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004530 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-24 23:32:22.538540) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-24 23:32:22.538843) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-24 23:32:22.539118) ========= ========= Total master_lag: 286.90 =========