builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-006 starttime: 1461287470.82 results: success (0) buildid: 20160421154303 builduid: 9edda62606e04d9ca26a4b0b023c0e1c revision: 8e2c675bcdfa618c4c1329aca2bfb8c6f81ac102 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 18:11:10.822127) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 18:11:10.823531) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-21 18:11:10.823809) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-21 18:11:10.841200) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 18:11:10.841517) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1461285522.406794-378324586 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-21 18:11:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 69.9M=0s 2016-04-21 18:11:11 (69.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.100279 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 18:11:10.954855) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 18:11:10.955162) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1461285522.406794-378324586 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014291 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 18:11:11.005065) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-21 18:11:11.005427) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8e2c675bcdfa618c4c1329aca2bfb8c6f81ac102 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8e2c675bcdfa618c4c1329aca2bfb8c6f81ac102 --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=e31afd3c0f0de4e86ebe4d94000001b5-1461285522.406794-378324586 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-21 18:11:11,299 truncating revision to first 12 chars 2016-04-21 18:11:11,299 Setting DEBUG logging. 2016-04-21 18:11:11,299 attempt 1/10 2016-04-21 18:11:11,299 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8e2c675bcdfa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-21 18:11:12,606 unpacking tar archive at: mozilla-aurora-8e2c675bcdfa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.616880 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-21 18:11:12.635487) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 18:11:12.635799) ========= script_repo_revision: 8e2c675bcdfa618c4c1329aca2bfb8c6f81ac102 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 18:11:12.636201) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-21 18:11:12.636764) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-21 18:11:12.644376) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-04-21 18:11:12.644694) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=e31afd3c0f0de4e86ebe4d94000001b5-1461285522.406794-378324586 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:11:13 INFO - MultiFileLogger online at 20160421 18:11:13 in /builds/slave/test 18:11:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 18:11:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:11:13 INFO - {'append_to_log': False, 18:11:13 INFO - 'base_work_dir': '/builds/slave/test', 18:11:13 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:11:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:11:13 INFO - 'branch': 'Mozilla-Aurora', 18:11:13 INFO - 'buildbot_json_path': 'buildprops.json', 18:11:13 INFO - 'config_files': ('talos/linux_config.py',), 18:11:13 INFO - 'default_actions': ('clobber', 18:11:13 INFO - 'read-buildbot-config', 18:11:13 INFO - 'download-and-extract', 18:11:13 INFO - 'populate-webroot', 18:11:13 INFO - 'create-virtualenv', 18:11:13 INFO - 'install', 18:11:13 INFO - 'run-tests'), 18:11:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:11:13 INFO - 'download_minidump_stackwalk': True, 18:11:13 INFO - 'download_symbols': 'ondemand', 18:11:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:11:13 INFO - 'tooltool.py': '/tools/tooltool.py', 18:11:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:11:13 INFO - '/tools/misc-python/virtualenv.py')}, 18:11:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:11:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:11:13 INFO - 'installer_path': 'installer.exe', 18:11:13 INFO - 'log_level': 'info', 18:11:13 INFO - 'log_name': 'talos', 18:11:13 INFO - 'log_to_console': True, 18:11:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:11:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:11:13 INFO - 'opt_config_files': (), 18:11:13 INFO - 'pip_index': False, 18:11:13 INFO - 'sps_profile': False, 18:11:13 INFO - 'sps_profile_interval': 0, 18:11:13 INFO - 'suite': 'dromaeojs-e10s', 18:11:13 INFO - 'system_bits': '32', 18:11:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:11:13 INFO - 'title': 'talos-linux64-ix-006', 18:11:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:11:13 INFO - 'use_talos_json': True, 18:11:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:11:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:11:13 INFO - 'work_dir': 'build'} 18:11:13 INFO - ##### 18:11:13 INFO - ##### Running clobber step. 18:11:13 INFO - ##### 18:11:13 INFO - Running pre-action listener: _resource_record_pre_action 18:11:13 INFO - Running main action method: clobber 18:11:13 INFO - rmtree: /builds/slave/test/build 18:11:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:11:13 INFO - Running post-action listener: _resource_record_post_action 18:11:13 INFO - ##### 18:11:13 INFO - ##### Running read-buildbot-config step. 18:11:13 INFO - ##### 18:11:13 INFO - Running pre-action listener: _resource_record_pre_action 18:11:13 INFO - Running main action method: read_buildbot_config 18:11:13 INFO - Using buildbot properties: 18:11:13 INFO - { 18:11:13 INFO - "project": "", 18:11:13 INFO - "product": "firefox", 18:11:13 INFO - "script_repo_revision": "production", 18:11:13 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 18:11:13 INFO - "repository": "", 18:11:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 18:11:13 INFO - "buildid": "20160421154303", 18:11:13 INFO - "pgo_build": "True", 18:11:13 INFO - "basedir": "/builds/slave/test", 18:11:13 INFO - "buildnumber": 228, 18:11:13 INFO - "slavename": "talos-linux64-ix-006", 18:11:13 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:11:13 INFO - "platform": "ubuntu64_hw", 18:11:13 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 18:11:13 INFO - "branch": "mozilla-aurora", 18:11:13 INFO - "repo_path": "releases/mozilla-aurora", 18:11:13 INFO - "revision": "8e2c675bcdfa618c4c1329aca2bfb8c6f81ac102", 18:11:13 INFO - "stage_platform": "linux64-pgo", 18:11:13 INFO - "builduid": "9edda62606e04d9ca26a4b0b023c0e1c", 18:11:13 INFO - "slavebuilddir": "test" 18:11:13 INFO - } 18:11:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2. 18:11:13 INFO - Running post-action listener: _resource_record_post_action 18:11:13 INFO - ##### 18:11:13 INFO - ##### Running download-and-extract step. 18:11:13 INFO - ##### 18:11:13 INFO - Running pre-action listener: _resource_record_pre_action 18:11:13 INFO - Running main action method: download_and_extract 18:11:13 INFO - mkdir: /builds/slave/test/build/tests 18:11:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:11:13 INFO - https://queue.taskcluster.net/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:11:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json 18:11:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json 18:11:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json 18:11:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:11:16 INFO - Downloaded 1448 bytes. 18:11:16 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json 18:11:16 INFO - Using the following test package requirements: 18:11:16 INFO - {u'common': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'], 18:11:16 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 18:11:16 INFO - u'firefox-47.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 18:11:16 INFO - u'gtest': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 18:11:16 INFO - u'firefox-47.0a2.en-US.linux-x86_64.gtest.tests.zip'], 18:11:16 INFO - u'jittest': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 18:11:16 INFO - u'jsshell-linux-x86_64.zip'], 18:11:16 INFO - u'mochitest': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 18:11:16 INFO - u'firefox-47.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 18:11:16 INFO - u'mozbase': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'], 18:11:16 INFO - u'reftest': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 18:11:16 INFO - u'firefox-47.0a2.en-US.linux-x86_64.reftest.tests.zip'], 18:11:16 INFO - u'talos': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 18:11:16 INFO - u'firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip'], 18:11:16 INFO - u'web-platform': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 18:11:16 INFO - u'firefox-47.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 18:11:16 INFO - u'webapprt': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'], 18:11:16 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 18:11:16 INFO - u'firefox-47.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 18:11:16 INFO - Downloading packages: [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:11:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:11:16 INFO - https://queue.taskcluster.net/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:11:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 18:11:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 18:11:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 18:11:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:11:17 INFO - Downloaded 23354957 bytes. 18:11:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:11:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:11:19 INFO - Return code: 0 18:11:19 INFO - Downloading packages: [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:11: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')]} 18:11:19 INFO - https://queue.taskcluster.net/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:11:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 18:11:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 18:11:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 18:11:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:11:23 INFO - Downloaded 23354957 bytes. 18:11:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:11:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:11:24 INFO - Return code: 0 18:11:24 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')]} 18:11:24 INFO - https://queue.taskcluster.net/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:11:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip 18:11:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip 18:11:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip 18:11:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:11:25 INFO - Downloaded 10845198 bytes. 18:11:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:11:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 18:11:25 INFO - Return code: 0 18:11: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')]} 18:11:25 INFO - https://queue.taskcluster.net/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:11:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 18:11:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 18:11:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 18:11:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:11:30 INFO - Downloaded 60841011 bytes. 18:11:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 18:11:30 INFO - mkdir: /builds/slave/test/properties 18:11:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:11:30 INFO - Writing to file /builds/slave/test/properties/build_url 18:11:30 INFO - Contents: 18:11:30 INFO - build_url:https://queue.taskcluster.net/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 18:11:31 INFO - Running post-action listener: _resource_record_post_action 18:11:31 INFO - Running post-action listener: set_extra_try_arguments 18:11:31 INFO - ##### 18:11:31 INFO - ##### Running populate-webroot step. 18:11:31 INFO - ##### 18:11:31 INFO - Running pre-action listener: _resource_record_pre_action 18:11:31 INFO - Running main action method: populate_webroot 18:11:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:11:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:11:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:11:31 INFO - '--tppagecycles', 18:11:31 INFO - '7'], 18:11:31 INFO - 'tests': ['tsvgm']}}, 18:11:31 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:11:31 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:11:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:11:31 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 18:11:31 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:11:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:11:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:11:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:11:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:11:31 INFO - 'talos_options': ['--e10s'], 18:11:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:11:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:11:31 INFO - 'tests': ['damp', 'tps']}, 18:11:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:11:31 INFO - 'talos_options': ['--e10s'], 18:11:31 INFO - 'tests': ['damp', 'tps']}, 18:11:31 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:11:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:11:31 INFO - 'other': {'tests': ['a11yr', 18:11:31 INFO - 'ts_paint', 18:11:31 INFO - 'tpaint', 18:11:31 INFO - 'sessionrestore', 18:11:31 INFO - 'sessionrestore_no_auto_restore', 18:11:31 INFO - 'tabpaint']}, 18:11:31 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:11:31 INFO - 'tests': ['a11yr', 18:11:31 INFO - 'ts_paint', 18:11:31 INFO - 'tpaint', 18:11:31 INFO - 'sessionrestore', 18:11:31 INFO - 'sessionrestore_no_auto_restore', 18:11:31 INFO - 'tabpaint']}, 18:11:31 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:11:31 INFO - 'tests': ['a11yr', 18:11:31 INFO - 'ts_paint', 18:11:31 INFO - 'tpaint', 18:11:31 INFO - 'sessionrestore', 18:11:31 INFO - 'sessionrestore_no_auto_restore', 18:11:31 INFO - 'tabpaint']}, 18:11:31 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:11:31 INFO - 'tests': ['a11yr', 18:11:31 INFO - 'ts_paint', 18:11:31 INFO - 'tpaint', 18:11:31 INFO - 'sessionrestore', 18:11:31 INFO - 'sessionrestore_no_auto_restore', 18:11:31 INFO - 'tabpaint']}, 18:11:31 INFO - 'other_l64': {'tests': ['a11yr', 18:11:31 INFO - 'ts_paint', 18:11:31 INFO - 'tpaint', 18:11:31 INFO - 'sessionrestore', 18:11:31 INFO - 'sessionrestore_no_auto_restore', 18:11:31 INFO - 'tabpaint']}, 18:11:31 INFO - 'other_nol64': {'tests': ['a11yr', 18:11:31 INFO - 'ts_paint', 18:11:31 INFO - 'tpaint', 18:11:31 INFO - 'sessionrestore', 18:11:31 INFO - 'sessionrestore_no_auto_restore', 18:11:31 INFO - 'tabpaint']}, 18:11:31 INFO - 'svgr': {'tests': ['tsvgx', 18:11:31 INFO - 'tsvgr_opacity', 18:11:31 INFO - 'tart', 18:11:31 INFO - 'tscrollx', 18:11:31 INFO - 'cart']}, 18:11:31 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:11:31 INFO - 'tests': ['tsvgx', 18:11:31 INFO - 'tsvgr_opacity', 18:11:31 INFO - 'tart', 18:11:31 INFO - 'tscrollx', 18:11:31 INFO - 'cart']}, 18:11:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:11:31 INFO - 'tests': ['tp5o']}, 18:11:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:11:31 INFO - 'talos_options': ['--e10s'], 18:11:31 INFO - 'tests': ['tp5o']}, 18:11:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:11:31 INFO - 'talos_options': ['--xperf_path', 18:11:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:11:31 INFO - 'tests': ['tp5n']}, 18:11:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:11:31 INFO - 'talos_options': ['--e10s', 18:11:31 INFO - '--xperf_path', 18:11:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:11:31 INFO - 'tests': ['tp5n']}}, 18:11:31 INFO - 'talos.zip': {'path': '', 18:11:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:11:31 INFO - Running post-action listener: _resource_record_post_action 18:11:31 INFO - ##### 18:11:31 INFO - ##### Running create-virtualenv step. 18:11:31 INFO - ##### 18:11:31 INFO - Running pre-action listener: _resource_record_pre_action 18:11:31 INFO - Running main action method: create_virtualenv 18:11:31 INFO - Creating virtualenv /builds/slave/test/build/venv 18:11:31 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 18:11:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:11:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:11:31 INFO - Using real prefix '/usr' 18:11:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:11:31 INFO - Installing distribute.............................................................................................................................................................................................done. 18:11:33 INFO - Installing pip.................done. 18:11:33 INFO - Return code: 0 18:11:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:11:33 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')]} 18:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:33 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')]} 18:11:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:33 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 0x7fa69c378f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe11410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea0160>, '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 0xea1060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xea14f0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1461285522.406794-378324586', '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 18:11:33 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 18:11:33 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 18:11:33 INFO - Using env: {'DISPLAY': ':0', 18:11:33 INFO - 'HOME': '/home/cltbld', 18:11:33 INFO - 'LANG': 'en_US.UTF-8', 18:11:33 INFO - 'LANGUAGE': 'en_US:en', 18:11:33 INFO - 'LOGNAME': 'cltbld', 18:11:33 INFO - 'MAIL': '/var/mail/cltbld', 18:11:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:33 INFO - 'MOZ_NO_REMOTE': '1', 18:11:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:11:33 INFO - 'NO_EM_RESTART': '1', 18:11:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:11:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:33 INFO - 'PWD': '/builds/slave/test', 18:11:33 INFO - 'SHELL': '/bin/bash', 18:11:33 INFO - 'SHLVL': '1', 18:11:33 INFO - 'TERM': 'linux', 18:11:33 INFO - 'TMOUT': '86400', 18:11:33 INFO - 'USER': 'cltbld', 18:11:33 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1461285522.406794-378324586', 18:11:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:33 INFO - '_': '/tools/buildbot/bin/python'} 18:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:34 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 18:11:34 INFO - Cleaning up... 18:11:34 INFO - Return code: 0 18:11:34 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:11:34 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')]} 18:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:34 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')]} 18:11:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:34 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 0x7fa69c378f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe11410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea0160>, '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 0xea1060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xea14f0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1461285522.406794-378324586', '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 18:11:34 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 18:11:34 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 18:11:34 INFO - Using env: {'DISPLAY': ':0', 18:11:34 INFO - 'HOME': '/home/cltbld', 18:11:34 INFO - 'LANG': 'en_US.UTF-8', 18:11:34 INFO - 'LANGUAGE': 'en_US:en', 18:11:34 INFO - 'LOGNAME': 'cltbld', 18:11:34 INFO - 'MAIL': '/var/mail/cltbld', 18:11:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:34 INFO - 'MOZ_NO_REMOTE': '1', 18:11:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:11:34 INFO - 'NO_EM_RESTART': '1', 18:11:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:11:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:34 INFO - 'PWD': '/builds/slave/test', 18:11:34 INFO - 'SHELL': '/bin/bash', 18:11:34 INFO - 'SHLVL': '1', 18:11:34 INFO - 'TERM': 'linux', 18:11:34 INFO - 'TMOUT': '86400', 18:11:34 INFO - 'USER': 'cltbld', 18:11:34 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1461285522.406794-378324586', 18:11:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:34 INFO - '_': '/tools/buildbot/bin/python'} 18:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:34 INFO - Downloading/unpacking psutil>=0.7.1 18:11:34 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 18:11:34 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 18:11:34 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 18:11:34 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 18:11:34 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 18:11:34 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 18:11:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:11:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:11:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:11:36 INFO - Installing collected packages: psutil 18:11:36 INFO - Running setup.py install for psutil 18:11:36 INFO - building 'psutil._psutil_linux' extension 18:11:36 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 18:11:36 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 18:11:36 INFO - building 'psutil._psutil_posix' extension 18:11:36 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 18:11:36 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 18:11:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:11:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:11:36 INFO - Successfully installed psutil 18:11:36 INFO - Cleaning up... 18:11:37 INFO - Return code: 0 18:11:37 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:11:37 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')]} 18:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:37 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')]} 18:11:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:37 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 0x7fa69c378f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe11410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea0160>, '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 0xea1060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xea14f0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1461285522.406794-378324586', '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 18:11:37 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 18:11:37 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 18:11:37 INFO - Using env: {'DISPLAY': ':0', 18:11:37 INFO - 'HOME': '/home/cltbld', 18:11:37 INFO - 'LANG': 'en_US.UTF-8', 18:11:37 INFO - 'LANGUAGE': 'en_US:en', 18:11:37 INFO - 'LOGNAME': 'cltbld', 18:11:37 INFO - 'MAIL': '/var/mail/cltbld', 18:11:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:37 INFO - 'MOZ_NO_REMOTE': '1', 18:11:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:11:37 INFO - 'NO_EM_RESTART': '1', 18:11:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:11:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:37 INFO - 'PWD': '/builds/slave/test', 18:11:37 INFO - 'SHELL': '/bin/bash', 18:11:37 INFO - 'SHLVL': '1', 18:11:37 INFO - 'TERM': 'linux', 18:11:37 INFO - 'TMOUT': '86400', 18:11:37 INFO - 'USER': 'cltbld', 18:11:37 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1461285522.406794-378324586', 18:11:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:37 INFO - '_': '/tools/buildbot/bin/python'} 18:11:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:37 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:11: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 18:11: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 18:11: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 18:11: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 18:11: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 18:11: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 18:11:39 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:11:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:11:39 INFO - Installing collected packages: mozsystemmonitor 18:11:39 INFO - Running setup.py install for mozsystemmonitor 18:11:39 INFO - Successfully installed mozsystemmonitor 18:11:39 INFO - Cleaning up... 18:11:39 INFO - Return code: 0 18:11:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:11:39 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')]} 18:11:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:39 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')]} 18:11:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:39 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 0x7fa69c378f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe11410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea0160>, '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 0xea1060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xea14f0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1461285522.406794-378324586', '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 18:11:39 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 18:11:39 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 18:11:39 INFO - Using env: {'DISPLAY': ':0', 18:11:39 INFO - 'HOME': '/home/cltbld', 18:11:39 INFO - 'LANG': 'en_US.UTF-8', 18:11:39 INFO - 'LANGUAGE': 'en_US:en', 18:11:39 INFO - 'LOGNAME': 'cltbld', 18:11:39 INFO - 'MAIL': '/var/mail/cltbld', 18:11:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:39 INFO - 'MOZ_NO_REMOTE': '1', 18:11:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:11:39 INFO - 'NO_EM_RESTART': '1', 18:11:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:11:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:39 INFO - 'PWD': '/builds/slave/test', 18:11:39 INFO - 'SHELL': '/bin/bash', 18:11:39 INFO - 'SHLVL': '1', 18:11:39 INFO - 'TERM': 'linux', 18:11:39 INFO - 'TMOUT': '86400', 18:11:39 INFO - 'USER': 'cltbld', 18:11:39 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1461285522.406794-378324586', 18:11:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:39 INFO - '_': '/tools/buildbot/bin/python'} 18:11:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:39 INFO - Downloading/unpacking blobuploader==1.2.4 18:11:39 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 18:11:39 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 18:11:39 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 18:11:39 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 18:11:39 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 18:11:39 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 18:11:41 INFO - Downloading blobuploader-1.2.4.tar.gz 18:11:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:11:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:11:41 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 18:11:41 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 18:11:41 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 18:11:41 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 18:11:41 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 18:11:41 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 18:11:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:11:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:11:42 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 18:11:42 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 18:11:42 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 18:11:42 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 18:11:42 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 18:11:42 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 18:11:42 INFO - Downloading docopt-0.6.1.tar.gz 18:11:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:11:42 INFO - Installing collected packages: blobuploader, requests, docopt 18:11:42 INFO - Running setup.py install for blobuploader 18:11:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:11:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:11:42 INFO - Running setup.py install for requests 18:11:42 INFO - Running setup.py install for docopt 18:11:42 INFO - Successfully installed blobuploader requests docopt 18:11:42 INFO - Cleaning up... 18:11:43 INFO - Return code: 0 18:11:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:11:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:11:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:11:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:43 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 0x7fa69c378f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe11410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea0160>, '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 0xea1060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xea14f0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1461285522.406794-378324586', '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 18:11:43 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 18:11:43 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 18:11:43 INFO - Using env: {'DISPLAY': ':0', 18:11:43 INFO - 'HOME': '/home/cltbld', 18:11:43 INFO - 'LANG': 'en_US.UTF-8', 18:11:43 INFO - 'LANGUAGE': 'en_US:en', 18:11:43 INFO - 'LOGNAME': 'cltbld', 18:11:43 INFO - 'MAIL': '/var/mail/cltbld', 18:11:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:43 INFO - 'MOZ_NO_REMOTE': '1', 18:11:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:11:43 INFO - 'NO_EM_RESTART': '1', 18:11:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:11:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:43 INFO - 'PWD': '/builds/slave/test', 18:11:43 INFO - 'SHELL': '/bin/bash', 18:11:43 INFO - 'SHLVL': '1', 18:11:43 INFO - 'TERM': 'linux', 18:11:43 INFO - 'TMOUT': '86400', 18:11:43 INFO - 'USER': 'cltbld', 18:11:43 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1461285522.406794-378324586', 18:11:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:43 INFO - '_': '/tools/buildbot/bin/python'} 18:11:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:11:43 INFO - Running setup.py (path:/tmp/pip-PUNux_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:11:43 INFO - Running setup.py (path:/tmp/pip-L9sQGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:11:43 INFO - Running setup.py (path:/tmp/pip-i7Rah2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:11:43 INFO - Running setup.py (path:/tmp/pip-95Q2XK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:11:43 INFO - Running setup.py (path:/tmp/pip-ZeR_nr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:11:43 INFO - Running setup.py (path:/tmp/pip-DfFUxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:11:43 INFO - Running setup.py (path:/tmp/pip-Jnirsl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:11:43 INFO - Running setup.py (path:/tmp/pip-Jvi3xx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:11:43 INFO - Running setup.py (path:/tmp/pip-kudawi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:11:43 INFO - Running setup.py (path:/tmp/pip-BdopJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:11:43 INFO - Running setup.py (path:/tmp/pip-_lJkyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:11:44 INFO - Running setup.py (path:/tmp/pip-4bflJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:11:44 INFO - Running setup.py (path:/tmp/pip-frAunu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:11:44 INFO - Running setup.py (path:/tmp/pip-Ncrl8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:11:44 INFO - Running setup.py (path:/tmp/pip-OXN7s6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:11:44 INFO - Running setup.py (path:/tmp/pip-XAZsPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:11:44 INFO - Running setup.py (path:/tmp/pip-sS0lg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:11:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:11:44 INFO - Running setup.py install for manifestparser 18:11:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:11:44 INFO - Running setup.py install for mozcrash 18:11:44 INFO - Running setup.py install for mozdebug 18:11:44 INFO - Running setup.py install for mozdevice 18:11:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:11:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:11:44 INFO - Running setup.py install for mozfile 18:11:45 INFO - Running setup.py install for mozhttpd 18:11:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:11:45 INFO - Running setup.py install for mozinfo 18:11:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:11:45 INFO - Running setup.py install for mozInstall 18:11:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:11:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:11:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:11:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:11:45 INFO - Running setup.py install for mozleak 18:11:45 INFO - Running setup.py install for mozlog 18:11:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:11:45 INFO - Running setup.py install for moznetwork 18:11:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:11:45 INFO - Running setup.py install for mozprocess 18:11:45 INFO - Running setup.py install for mozprofile 18:11:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:11:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:11:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:11:45 INFO - Running setup.py install for mozrunner 18:11:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:11:45 INFO - Running setup.py install for mozscreenshot 18:11:46 INFO - Running setup.py install for moztest 18:11:46 INFO - Running setup.py install for mozversion 18:11:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:11:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:11:46 INFO - Cleaning up... 18:11:46 INFO - Return code: 0 18:11:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:11:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:11:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:11:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:46 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 0x7fa69c378f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe11410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea0160>, '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 0xea1060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xea14f0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1461285522.406794-378324586', '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 18:11:46 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 18:11:46 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 18:11:46 INFO - Using env: {'DISPLAY': ':0', 18:11:46 INFO - 'HOME': '/home/cltbld', 18:11:46 INFO - 'LANG': 'en_US.UTF-8', 18:11:46 INFO - 'LANGUAGE': 'en_US:en', 18:11:46 INFO - 'LOGNAME': 'cltbld', 18:11:46 INFO - 'MAIL': '/var/mail/cltbld', 18:11:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:46 INFO - 'MOZ_NO_REMOTE': '1', 18:11:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:11:46 INFO - 'NO_EM_RESTART': '1', 18:11:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:11:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:46 INFO - 'PWD': '/builds/slave/test', 18:11:46 INFO - 'SHELL': '/bin/bash', 18:11:46 INFO - 'SHLVL': '1', 18:11:46 INFO - 'TERM': 'linux', 18:11:46 INFO - 'TMOUT': '86400', 18:11:46 INFO - 'USER': 'cltbld', 18:11:46 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1461285522.406794-378324586', 18:11:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:46 INFO - '_': '/tools/buildbot/bin/python'} 18:11:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:11:46 INFO - Running setup.py (path:/tmp/pip-HHeiwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:11:46 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)) 18:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:11:46 INFO - Running setup.py (path:/tmp/pip-bJjptM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:11:46 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)) 18:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:11:46 INFO - Running setup.py (path:/tmp/pip-ZhaGDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:11:46 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)) 18:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:11:46 INFO - Running setup.py (path:/tmp/pip-Folp3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:11:46 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)) 18:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:11:46 INFO - Running setup.py (path:/tmp/pip-X6GvLx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:11:46 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)) 18:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:11:46 INFO - Running setup.py (path:/tmp/pip-e2QWs4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:11:46 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)) 18:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:11:46 INFO - Running setup.py (path:/tmp/pip-1nwgNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:11:46 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)) 18:11:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:11:46 INFO - Running setup.py (path:/tmp/pip-oHTYO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:11:47 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)) 18:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:11:47 INFO - Running setup.py (path:/tmp/pip-X_w6El-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:11:47 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)) 18:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:11:47 INFO - Running setup.py (path:/tmp/pip-7ynKoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:11:47 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)) 18:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:11:47 INFO - Running setup.py (path:/tmp/pip-64bMmu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:11:47 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)) 18:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:11:47 INFO - Running setup.py (path:/tmp/pip-VMmEer-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:11:47 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)) 18:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:11:47 INFO - Running setup.py (path:/tmp/pip-p847Bd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:11:47 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)) 18:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:11:47 INFO - Running setup.py (path:/tmp/pip-RiID3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:11:47 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)) 18:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:11:47 INFO - Running setup.py (path:/tmp/pip-wMN3pC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:11:47 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)) 18:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:11:47 INFO - Running setup.py (path:/tmp/pip-1WCDwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:11:47 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)) 18:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:11:47 INFO - Running setup.py (path:/tmp/pip-yrywki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:11:47 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)) 18:11:47 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)) 18:11:47 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)) 18:11:47 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)) 18:11:47 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)) 18:11:47 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)) 18:11:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:11:47 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 18:11:47 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 18:11:47 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 18:11:47 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 18:11:47 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 18:11:47 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 18:11:49 INFO - Downloading blessings-1.6.tar.gz 18:11:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:11:49 INFO - Installing collected packages: blessings 18:11:49 INFO - Running setup.py install for blessings 18:11:49 INFO - Successfully installed blessings 18:11:49 INFO - Cleaning up... 18:11:50 INFO - Return code: 0 18:11:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:11:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:11:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:11:50 INFO - Reading from file tmpfile_stdout 18:11:50 INFO - Current package versions: 18:11:50 INFO - argparse == 1.2.1 18:11:50 INFO - blessings == 1.6 18:11:50 INFO - blobuploader == 1.2.4 18:11:50 INFO - docopt == 0.6.1 18:11:50 INFO - manifestparser == 1.1 18:11:50 INFO - mozInstall == 1.12 18:11:50 INFO - mozcrash == 0.17 18:11:50 INFO - mozdebug == 0.1 18:11:50 INFO - mozdevice == 0.48 18:11:50 INFO - mozfile == 1.2 18:11:50 INFO - mozhttpd == 0.7 18:11:50 INFO - mozinfo == 0.9 18:11:50 INFO - mozleak == 0.1 18:11:50 INFO - mozlog == 3.1 18:11:50 INFO - moznetwork == 0.27 18:11:50 INFO - mozprocess == 0.22 18:11:50 INFO - mozprofile == 0.28 18:11:50 INFO - mozrunner == 6.11 18:11:50 INFO - mozscreenshot == 0.1 18:11:50 INFO - mozsystemmonitor == 0.0 18:11:50 INFO - moztest == 0.7 18:11:50 INFO - mozversion == 1.4 18:11:50 INFO - psutil == 3.1.1 18:11:50 INFO - requests == 1.2.3 18:11:50 INFO - wsgiref == 0.1.2 18:11:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:11:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:11:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:11:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:50 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 0x7fa69c378f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe11410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea0160>, '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 0xea1060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xea14f0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1461285522.406794-378324586', '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 18:11:50 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 18:11:50 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 18:11:50 INFO - Using env: {'DISPLAY': ':0', 18:11:50 INFO - 'HOME': '/home/cltbld', 18:11:50 INFO - 'LANG': 'en_US.UTF-8', 18:11:50 INFO - 'LANGUAGE': 'en_US:en', 18:11:50 INFO - 'LOGNAME': 'cltbld', 18:11:50 INFO - 'MAIL': '/var/mail/cltbld', 18:11:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:50 INFO - 'MOZ_NO_REMOTE': '1', 18:11:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:11:50 INFO - 'NO_EM_RESTART': '1', 18:11:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:11:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:50 INFO - 'PWD': '/builds/slave/test', 18:11:50 INFO - 'SHELL': '/bin/bash', 18:11:50 INFO - 'SHLVL': '1', 18:11:50 INFO - 'TERM': 'linux', 18:11:50 INFO - 'TMOUT': '86400', 18:11:50 INFO - 'USER': 'cltbld', 18:11:50 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1461285522.406794-378324586', 18:11:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:50 INFO - '_': '/tools/buildbot/bin/python'} 18:11:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:50 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)) 18:11:50 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)) 18:11:50 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)) 18:11:50 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)) 18:11:50 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)) 18:11:50 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)) 18:11:50 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)) 18:11:50 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)) 18:11:50 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)) 18:11:50 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)) 18:11:50 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)) 18:11:50 INFO - Cleaning up... 18:11:50 INFO - Return code: 0 18:11:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:11:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:11:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:11:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:50 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 0x7fa69c378f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe11410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea0160>, '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 0xea1060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xea14f0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1461285522.406794-378324586', '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 18:11:50 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 18:11:50 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 18:11:50 INFO - Using env: {'DISPLAY': ':0', 18:11:50 INFO - 'HOME': '/home/cltbld', 18:11:50 INFO - 'LANG': 'en_US.UTF-8', 18:11:50 INFO - 'LANGUAGE': 'en_US:en', 18:11:50 INFO - 'LOGNAME': 'cltbld', 18:11:50 INFO - 'MAIL': '/var/mail/cltbld', 18:11:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:50 INFO - 'MOZ_NO_REMOTE': '1', 18:11:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:11:50 INFO - 'NO_EM_RESTART': '1', 18:11:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:11:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:50 INFO - 'PWD': '/builds/slave/test', 18:11:50 INFO - 'SHELL': '/bin/bash', 18:11:50 INFO - 'SHLVL': '1', 18:11:50 INFO - 'TERM': 'linux', 18:11:50 INFO - 'TMOUT': '86400', 18:11:50 INFO - 'USER': 'cltbld', 18:11:50 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1461285522.406794-378324586', 18:11:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:50 INFO - '_': '/tools/buildbot/bin/python'} 18:11:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:50 INFO - Downloading/unpacking jsonschema 18:11:50 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 18:11:50 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 18:11:50 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 18:11:50 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 18:11:50 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 18:11:50 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 18:11:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:11:52 INFO - Downloading/unpacking functools32 (from jsonschema) 18:11:52 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 18:11:52 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 18:11:52 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 18:11:52 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 18:11:52 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 18:11:52 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 18:11:52 INFO - Downloading functools32-3.2.3-2.tar.gz 18:11:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:11:52 INFO - warning: no files found matching '*.txt' 18:11:52 INFO - no previously-included directories found matching 'build' 18:11:52 INFO - no previously-included directories found matching 'dist' 18:11:52 INFO - no previously-included directories found matching '.git*' 18:11:52 INFO - Installing collected packages: jsonschema, functools32 18:11:52 INFO - Running setup.py install for functools32 18:11:52 INFO - warning: no files found matching '*.txt' 18:11:52 INFO - no previously-included directories found matching 'build' 18:11:52 INFO - no previously-included directories found matching 'dist' 18:11:52 INFO - no previously-included directories found matching '.git*' 18:11:52 INFO - Successfully installed jsonschema functools32 18:11:52 INFO - Cleaning up... 18:11:52 INFO - Return code: 0 18:11:52 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:11:52 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')]} 18:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:52 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')]} 18:11:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:52 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 0x7fa69c378f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd448a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe11410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xea0160>, '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 0xea1060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xea14f0>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1461285522.406794-378324586', '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 18:11:52 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 18:11:52 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 18:11:52 INFO - Using env: {'DISPLAY': ':0', 18:11:52 INFO - 'HOME': '/home/cltbld', 18:11:52 INFO - 'LANG': 'en_US.UTF-8', 18:11:52 INFO - 'LANGUAGE': 'en_US:en', 18:11:52 INFO - 'LOGNAME': 'cltbld', 18:11:52 INFO - 'MAIL': '/var/mail/cltbld', 18:11:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:52 INFO - 'MOZ_NO_REMOTE': '1', 18:11:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:11:52 INFO - 'NO_EM_RESTART': '1', 18:11:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:11:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:52 INFO - 'PWD': '/builds/slave/test', 18:11:53 INFO - 'SHELL': '/bin/bash', 18:11:53 INFO - 'SHLVL': '1', 18:11:53 INFO - 'TERM': 'linux', 18:11:53 INFO - 'TMOUT': '86400', 18:11:53 INFO - 'USER': 'cltbld', 18:11:53 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1461285522.406794-378324586', 18:11:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:53 INFO - '_': '/tools/buildbot/bin/python'} 18:11:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:53 INFO - Downloading/unpacking flake8 18:11:53 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 18:11:53 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 18:11:53 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 18:11:53 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 18:11:53 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 18:11:53 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 18:11:55 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:11:55 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:55 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:11:55 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:55 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:11:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:11:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:11:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:11:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:11:55 INFO - no previously-included directories found matching 'docs/_build' 18:11:55 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:11:55 INFO - Running setup.py install for pep8 18:11:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:11:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:11:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:11:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:11:55 INFO - no previously-included directories found matching 'docs/_build' 18:11:55 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:11:55 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:11:55 INFO - Cleaning up... 18:11:56 INFO - Return code: 0 18:11:56 INFO - Running post-action listener: _resource_record_post_action 18:11:56 INFO - Running post-action listener: _start_resource_monitoring 18:11:56 INFO - Starting resource monitoring. 18:11:56 INFO - ##### 18:11:56 INFO - ##### Running install step. 18:11:56 INFO - ##### 18:11:56 INFO - Running pre-action listener: _resource_record_pre_action 18:11:56 INFO - Running main action method: install 18:11:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:11:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:11:56 INFO - Reading from file tmpfile_stdout 18:11:56 INFO - Detecting whether we're running mozinstall >=1.0... 18:11:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:11:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:11:56 INFO - Reading from file tmpfile_stdout 18:11:56 INFO - Output received: 18:11:56 INFO - Usage: mozinstall [options] installer 18:11:56 INFO - Options: 18:11:56 INFO - -h, --help show this help message and exit 18:11:56 INFO - -d DEST, --destination=DEST 18:11:56 INFO - Directory to install application into. [default: 18:11:56 INFO - "/builds/slave/test"] 18:11:56 INFO - --app=APP Application being installed. [default: firefox] 18:11:56 INFO - mkdir: /builds/slave/test/build/application 18:11:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:11:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:12:11 INFO - Reading from file tmpfile_stdout 18:12:11 INFO - Output received: 18:12:11 INFO - /builds/slave/test/build/application/firefox/firefox 18:12:11 INFO - Running post-action listener: _resource_record_post_action 18:12:11 INFO - ##### 18:12:11 INFO - ##### Running run-tests step. 18:12:11 INFO - ##### 18:12:11 INFO - Running pre-action listener: _resource_record_pre_action 18:12:11 INFO - Running main action method: run_tests 18:12:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:12:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:12:11 INFO - Python 2.7.3 18:12:11 INFO - Return code: 0 18:12:11 INFO - grabbing minidump binary from tooltool 18:12:11 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')]} 18:12:11 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 0xea0160>, '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 0xea1060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xea14f0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:12:11 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 18:12:11 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 18:12:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:12:11 INFO - Return code: 0 18:12:11 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:12:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:12:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:12:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:12:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:12:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:12:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:12:12 INFO - Return code: 0 18:12:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 18:12:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 18:12:12 INFO - Using env: {'DISPLAY': ':0', 18:12:12 INFO - 'HOME': '/home/cltbld', 18:12:12 INFO - 'LANG': 'en_US.UTF-8', 18:12:12 INFO - 'LANGUAGE': 'en_US:en', 18:12:12 INFO - 'LOGNAME': 'cltbld', 18:12:12 INFO - 'MAIL': '/var/mail/cltbld', 18:12:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:12:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:12 INFO - 'MOZ_NO_REMOTE': '1', 18:12:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:12:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:12 INFO - 'NO_EM_RESTART': '1', 18:12:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:12 INFO - 'PWD': '/builds/slave/test', 18:12:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:12:12 INFO - 'SHELL': '/bin/bash', 18:12:12 INFO - 'SHLVL': '1', 18:12:12 INFO - 'TERM': 'linux', 18:12:12 INFO - 'TMOUT': '86400', 18:12:12 INFO - 'USER': 'cltbld', 18:12:12 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1461285522.406794-378324586', 18:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:12 INFO - '_': '/tools/buildbot/bin/python'} 18:12:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 18:12:12 INFO - mozversion application_buildid: 20160421154303 18:12:12 INFO - mozversion application_changeset: 8e2c675bcdfa618c4c1329aca2bfb8c6f81ac102 18:12:12 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:12:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:12:12 INFO - mozversion application_name: Firefox 18:12:12 INFO - mozversion application_remotingname: firefox-dev 18:12:12 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:12:12 INFO - mozversion application_vendor: Mozilla 18:12:12 INFO - mozversion application_version: 47.0a2 18:12:12 INFO - mozversion platform_buildid: 20160421154303 18:12:12 INFO - mozversion platform_changeset: 8e2c675bcdfa618c4c1329aca2bfb8c6f81ac102 18:12:12 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:12:12 INFO - mozversion platform_version: 47.0a2 18:12:12 INFO - using testdate: 1461287532 18:12:12 INFO - actual date: 1461287532 18:12:12 INFO - starting webserver on 'localhost:50681' 18:12:12 INFO - SUITE-START | Running 2 tests 18:12:12 INFO - TEST-START | dromaeo_css 18:12:12 INFO - operating with platform_type : linux_ 18:12:12 INFO - Initialising browser for dromaeo_css test... 18:12:12 INFO - TEST-INFO | started process 9589 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptXzoNl/profile http://localhost:50681/getInfo.html) 18:12:16 INFO - PROCESS | 9589 | 18:12:16 INFO - PROCESS | 9589 | (process:9665): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 18:12:17 INFO - PROCESS | 9589 | __metrics Screen width/height:1600/1200 18:12:17 INFO - PROCESS | 9589 | colorDepth:24 18:12:17 INFO - PROCESS | 9589 | Browser inner width/height: 1024/697 18:12:17 INFO - PROCESS | 9589 | __metrics 18:12:17 ERROR - PROCESS | 9589 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 18:12:18 INFO - TEST-INFO | 9589: exit 0 18:12:18 INFO - Browser initialized. 18:12:18 INFO - Running cycle 1/1 for dromaeo_css test... 18:12:18 INFO - TEST-INFO | started process 9716 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptXzoNl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:12:20 INFO - PROCESS | 9716 | 18:12:20 INFO - PROCESS | 9716 | (process:9785): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 18:12:30 INFO - PROCESS | 9716 | RSS: Main: 151384064 18:12:30 INFO - PROCESS | 9716 | 18:13:20 INFO - PROCESS | 9716 | 1461287600282 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] 18:15:28 INFO - PROCESS | 9716 | Cycle 1(1): loaded http://localhost:50681/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50681/tests/dromaeo/cssquery-ext.html) 18:15:29 INFO - PROCESS | 9716 | RSS: Main: 154578944 18:15:29 INFO - PROCESS | 9716 | 18:18:30 INFO - PROCESS | 9716 | Cycle 1(1): loaded http://localhost:50681/tests/dromaeo/cssquery-ext.html (next: http://localhost:50681/tests/dromaeo/cssquery-jquery.html) 18:18:31 INFO - PROCESS | 9716 | RSS: Main: 153792512 18:18:31 INFO - PROCESS | 9716 | 18:21:33 INFO - PROCESS | 9716 | Cycle 1(1): loaded http://localhost:50681/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50681/tests/dromaeo/cssquery-mootools.html) 18:21:33 INFO - PROCESS | 9716 | RSS: Main: 152674304 18:21:33 INFO - PROCESS | 9716 | 18:24:35 INFO - PROCESS | 9716 | Cycle 1(1): loaded http://localhost:50681/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50681/tests/dromaeo/cssquery-prototype.html) 18:24:36 INFO - PROCESS | 9716 | RSS: Main: 151773184 18:24:36 INFO - PROCESS | 9716 | 18:27:38 INFO - PROCESS | 9716 | Cycle 1(1): loaded http://localhost:50681/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50681/tests/dromaeo/cssquery-yui.html) 18:27:39 INFO - PROCESS | 9716 | RSS: Main: 153559040 18:27:39 INFO - PROCESS | 9716 | 18:30:41 INFO - PROCESS | 9716 | Cycle 1(1): loaded http://localhost:50681/tests/dromaeo/cssquery-yui.html (next: http://localhost:50681/tests/dromaeo/cssquery-dojo.html) 18:30:41 INFO - PROCESS | 9716 | RSS: Main: 155811840 18:30:41 INFO - PROCESS | 9716 | 18:30:41 INFO - PROCESS | 9716 | __start_tp_report 18:30:41 INFO - PROCESS | 9716 | _x_x_mozilla_page_load 18:30:41 INFO - PROCESS | 9716 | _x_x_mozilla_page_load_details 18:30:41 INFO - PROCESS | 9716 | |i|pagename|runs| 18:30:41 INFO - PROCESS | 9716 | |0;dojo.html;3316.253842885351;3339.799612023278;3341.7493687973392;3363.1087761743156;3397.643247459017;5457.2359869618285;5466.508014278714;5480.314960629921;5493.505584497394;5493.587980901429;5481.725913704308;5496.136073932442;5501.477359650837;5514.172874068891;5551.139573366125;5013.523715247045;5057.443681195065;5095.439501654815;5109.2591574221715;5133.153029750087;5052.823151189709;5078.720670363128;5149.871253218681;5167.121589329804;5212.218167274901;5124.359455068116;5185.403678576974;5185.481451854822;5195.792168313263;5199.350081239845;5127.461616530267;5135.3837539495125;5143.665661731976;5160.122779127557;5180.171172612401;4966.6771659842;4984.728894132009;4989.251612258154;5062.11413002723;5065.544101030888;5358.5981051421295;5401.513863752242;5418.9458105418835;5442.482964118402;5447.591430642718;6012.428819262163;6043.335233124353;6047.637141771508;6049.48579370754;6060.121282414069;5958.463738263534;6025.427584379477;6032.426919442646;6055.27336719592;6061.848453788646;5595.328560572757;5602.047651899187;5602.943970560283;5607.383187849333;5612.831615051555;6045.153678485016;6099.725512351954;6104.786332478342;6111.84720381994;6117.113018612276;5776.653400795965;5961.940380596226;5970.432808883149;6000.069989151689;6023.156758053876;5950.19672344228;5999.400059994053;6004.369541198112;6017.819465416044;6020.729067191987;5859.53123750099;5953.9878220702585;5958.612690175125;5960.225170727777;5966.910496342558;5980.73086711099;5989.640621562633;6016.518678505709;6032.426919442646;6077.908831367533;4545.818167273338;4567.246404343313;4575;4587.610053145453;4604.746738929324;4042.8382864685673;4056.59434056592;4078.898027549335;4132.380142978577;4148.128892932457;5602.663840169603;5650.124230744241;5701.0308247598;5735.512481439041;5752;5966.433188847052;6022.427869352405;6027.547933904974;6037.818865434044;6038.365971572922;5577.079781836032;5717.42825717425;5740.1102829061565;5743.4543718346695;5747.224124743189;5152.175651895679;5157.742112894415;5162.148245539444;5165.302684137669;5195.948040519622;6172.382761723881;6179.938200618026;6181.7527298908435;6182.814515564541;6195.1636529068;5948;6012.819615411545;6014.48876845473;6017.3681763414215;6018.548608854266;6109.914811296778;6168.1672974149715;6172.321044684992;6231.53263505248;6268.122462855113;5960;5960.552958528041;5961.970190149022;5967.9701601491715;5988;5972.223610930551;5987.670678112746;6036.3661815510495;6036.939630603638;6046.667433291035;5866.442687944638;5889.52883769306;5894.852628684317;5899.941000589939;5956;5878.29460464747;5886.499647529837;5926.4666180044005;5928.525717942468;5930.614510056833;5602.915956260578;5730.111832665943;5811.79658711943;5816;5823.35943046273;5909.23179986599;5925.466707996301;5929.0513517836935;5931.881362372815;5995.760169593167;5744.4829965303325;5754;5767.682777447113;5783.913241301299;5793.044147715748 18:30:41 INFO - PROCESS | 9716 | |1;ext.html;555.2296046135573;565.6351653183699;574.485835177516;593.9910901336481;607.4138456389586;30976.53535196971;39917.4012389814;40452.19095618089;40637.79681101598;40684;43740.34389484152;44544.554554454444;44668.553314466844;44704.32943505842;44882;8829.911700882989;9025.458472491639;9028.413153145024;9043.864342034874;9050.140236677538;8929.77675558113;9008.144226298491;9014.36899417041;9018;9018.639254429847;8982.05688402718;9093.63625454981;9094.63621455141;9102.726918192431;9114.954425227865;8987.325950553703;9072.455652660828;9091.045440228812;9224.953875230649;9287.953560232156;9110.453372797587;9227.261819054527;9248.26013918885;9270.675526356612;9278.489683067428;43191.352129717765;43613.56386436127;43621.563784362064;43697.47058852957;43781.34327985083;3550.6449355064547;3778.112143646241;4225.492940847087;4341.39220509129;4510.143072816154;33510.49734253964;33721.156971075674;33745.83127084349;33814.66185338164;34931.82534087313;4278.871633850989;4310.073334233155;4357.4988876279;4363.323684828857;4409.559044095597;285631;286280;286740.9999999979;287705;289016;47162.52837471581;66394.33605663881;66682.33317666859;67173;67318.66340668265;45626;45822.77088614536;45906.54093459089;45923.77038114855;45953;15283.847161528465;15312.851536134835;15336.079835209923;15355;15416.537503874903;13755.243461609707;13807.309634518233;13807.861921380858;13839.723205535833;13843.723125537434;15481.845181548266;15631.531054068397;16006;16066.11636359988;16080.115593642462;6168.352323006109;6228.937710622836;6340.778072767431;6354.491640668735;6360.681965901779;1071.614218881202;1074.3929679730927;1084.2139448899484;1097.160672085855;1123.3035517978906;29835;30403;30458.69541304603;30594;31167.064988050395;1134.8638163420442;1149.9022583080532;1154.584349634131;1193.433119268358;1222.2544248008708;14551.490697825526;14573.635659108395;14764.40942362315;15054.397824086915;15191.316390762442;334013;502984;503719;508462;588658;62907;63867;63996.36003639997;64009;64182;1518.142249628956;1542.6297688554666;1549.7210502109729;1556.15189721602;1558.3377064747438;31524;31565;32027.199320017186;32132.83933580317;32526.83736581302;51846.22230666468;51925.480745192064;52104.47895520996;52225.7388713054;52482;303449;307124;311402;312320;312399;35647.465288021216;35747.64252357443;36022.4596631056;36181;36215.456768147975;47715;48461.5153848457;49453;52170;53368.4663153379;37808.62191378161;37950.67172648944;38106.61893381141;38390;38503.61496385001;36906.8154659225;37181.81409092937;37629.62370376261;37679;38194.618053819104;36720.63279367279;37182.8140859294;37231.44152837764;38048.80975595104;38199.6180038196;32243.838780805945;34384.48423273603;35436;35480.82259588686;36079.45880811843;27605.309867252676;27648.723512764616;27755.722442775314;27860.16419507418;27904.30239244035 18:30:41 INFO - PROCESS | 9716 | |2;jquery.html;366.8257577650616;382.1535299312024;384.3331819293527;387.6375588824451;389.8089935931393;17671.55821104473;18686.252549898003;19018.71471927922;19380.22479100838;19444.51388715281;12237.632871013862;12466.439010244538;12468.93765531171;12477.875221247785;12528.373581320942;3985.402189671558;5138.563222126115;5319.297684917013;5667.688277144755;5842.036064049426;5556.499915007648;5777.364489906128;5805.883882322351;5809;5813.651180929137;5725.914111288334;5746.770129194848;5753.137029445575;5755.021646320115;5905.7342419591005;6322.272938612026;6340.587861788992;6342.809715708536;6361.300256971729;6403.647799371032;5561.332640083176;5707.400722924075;5717.71411429431;5727.856803579903;5734.283214598176;20083.096260668306;20960;21942;22081.668774968388;22121.77878221213;2253.752087270399;2364.2552595932398;2401.9519609607883;2422.576049191402;2930.3260250142584;9345.345825792198;9389.295802814817;9592.856107158399;9618.422894626345;9753.073458021476;1951.0834093139854;1968.704694295867;2037.5313677854028;2052.2406709517427;2074.9066292016896;68255;71555.64222178856;72599.63700181466;72838.63580682116;74556.25443745547;10108.949455252676;10400.791984160274;10582.4708764563;10731;10866.185036122164;15300.387984480718;15344.616384590474;15358.385664573516;15551.377944882304;15624.92187539055;8087.595620218965;8234.723617839245;8265.090840007595;8350.164983501723;8355.373346999;8084.434089613788;8084.555349455836;8162.551059691774;8243.83512329762;8268.131846156188;4659.4175728034;4680.695754775928;4776.188048031843;4816.181249187646;4875.19559272716;1945.8637895347472;2008.2669825513583;2042.7855075217185;2047.5085979364844;2064.8024145995287;453.2498714627297;968.9079537443931;1036.9377837329785;1067.3083163186282;1083.7482707472882;10675.946620267005;11051.613193538187;11112.277701949348;11222.102231821435;11302.717432064264;484.4283745180721;489.1586471269411;494.7625139932852;500.18969269782457;511.6316252298339;6139.84650383744;6335.493160547145;6374.107624932513;6379.266384365857;6500.869982600416;447862;455069;468267;487350;488623;80830;82997.58501207456;84523;84755.5762221185;84982.5750871254;490.3429404597856;496.0376868874372;500.79467418358297;502.07617982910887;504.1454734225526;10733.892661073289;10805.405702686428;10854.34873907568;10862.565497380016;10909.072728818139;34044;34459.483107753906;35065.123371915906;35361.82319088388;35908.102297442776;53301.73349133229;53311.46688533065;53658;53739.73130134324;54009;8153;8295.592841609467;8312.418130731032;8438.83122337562;8445.07104218523;9532.380395274286;12638.2417054977;13570.321483925962;13633.522826701017;13793.793093103412;11068.778624427627;11349.659510214708;11398.715032123931;11492.310461372344;11509.539618415505;11290.604828830952;11806.46870890829;11997.340146292037;12067.81898271509;12127.241549975028;11523.711907202387;11547.191696581494;11552.480138393961;11701.122415818678;12074.45664945062;8033.919660803317;8346.068646240483;8410.158984101785;8614.65541378338;8830.602622882114;8738.69414570487;10864.619738309122;11697.824532632187;11707.941460292985;11829.349385783864 18:30:41 INFO - PROCESS | 9716 | |3;mootools.html;1578.0689393257976;1579.0604590268786;1856.2482194711138;1897.639448504785;1948.8051194880513;7131.536450130738;7805.687772489107;8007;8217.424780265377;8222.34221262299;7902.288794008538;8023.117457079731;8023.719169829057;8028.839423211547;8043.11525732169;2339.789418952298;2386.8567885926814;2395.137750409851;2395.556821987929;2399.1123284384716;2338.1348900906687;2344.367020904355;2388.641703744435;2393.114547617375;2394.6527753475734;2335.5795956727784;2344.5076533927895;2352.529494101178;2381.607034839258;2410.4937963027787;2325.6395258734924;2349.447879748258;2357.870317132557;2362.492064206195;2370.63255195445;2340.8946597403155;2365.6924599802087;2369.052379048377;2370.5258948210253;2383.011050414076;8189.672413103469;8198.057223419324;8202.548859812709;8212.219839115274;8310.916890831135;4957.652964292501;5101.260317254015;5118.309028281172;5123.1546794778815;5123.641345105844;3324.3683700096904;3344.66553344666;3346.866125354983;3429.8628054878022;3449.9827500862334;1079.1744315598573;1085.9674209773718;1100.2463312630794;1100.7193165742765;1108.9445527723663;11150.944245278803;11177.776444471147;11179.217454778169;11205.551777928873;11239.269447485887;4308.4399028126145;4351.912961740811;4396.516383197845;4427.158839820424;4427.623651989553;9592.328537002482;9598.712038638852;9613.759656008517;9654.951725241328;9697;4873.902521949542;4895.412550493964;4898.21628539432;4903.975480122576;4906.0433215522635;4026.295398305285;4399.670024748157;4443.511213766483;4484.372187893698;4524.366588677588;3788.886333409947;3815.9427608585893;3862.748921320105;3865.7100717446306;3913.295606790748;1250.5435516036582;1258.9685257868628;1274.6303571964213;1276.3681977421236;1296.5656505070717;1288.6391810293132;1307.4116647508658;1309.2798960571647;1349.9942542805672;1351.8107464954915;7368.557886526825;7388.519746216479;7474.551526908403;7509.699612015458;7513.39892808585;514.722050092953;518.245952139636;521.3691433365594;526.3026489900897;536.509094178822;5760.51035661965;5785.65286082828;5795.565332600072;5804;5829.62107463022;9456.479893605918;10030.448325342177;10059.5473203706;10094.646687365906;10104.191664666809;9718.514074296258;9766.023397660178;9889.604415823287;9898.703038908845;9916.256280778825;7350.5957172354965;7503.624818759149;7535.058117735283;7601.65792539326;7608.467407281437;7382.630868456663;7519;7567.356774674215;7640.694372225049;7648.158702542827;8525.232729054167;8674.913250867663;8680.479171249744;8693.782655433411;8751.868721969047;8632.352573556882;8737.388382813397;8866.069062748318;8923.286137108886;8951.73144805657;6559.278479367361;6798.218204906499;6962.7214911405;7140.857182856417;7233.891491627524;7680.308772210527;7706.807329816799;7755.3407960324;7762.728304509315;7763.184865589258;6975.546589471668;7029.753958611424;7062.15254169503;7186.029885965537;7196.532225405332;6697.397234248941;6795.864082718417;6841.452683785385;6891.724331026703;7034.085568876013;6819.931800682129;6850.760223392157;6876.828079298058;6953.930460695328;6989.231184569795;6473.190851143607;6521.152250207443;6541.08424820535;6551.80344589663;6580.341965803573;6570.408663220333;6615.735370585316;6705.832354191184;6740.258571557027;6749.628770417477 18:30:41 INFO - PROCESS | 9716 | |4;prototype.html;343.33221883436715;350.2067816395864;359.37110057399553;363.94176931690936;369.6765330335956;6400.5519613627;6424.678766061696;6465.127207826942;6500.544961852672;6505.739770409178;6381.680915954207;6408.10286559882;6413.90379144312;6415.775447859326;6416.4225219730215;4734.502877197896;4823.324734537167;4966.528179822911;4995.775190116436;4999.875028118662;4941.579965702919;4959.057779021974;4961;4968.751562421882;4978.5270399312;4871.366722326093;4912.557869791718;4920.70475771453;4927.827526036589;4928.285398617199;4924.261360795874;4931.26031095335;4940.629452791037;4942;4955.207166853323;4370.606645401929;4371.27873900806;4372.103718737651;4382.926183085133;4387.298032314814;5943.3462319144855;6095;6126.081087836816;6159.1069294951985;6165.290991535986;5694.430556944314;5710.886377156457;5725.971370143165;5738.311402631711;5757.337906140806;6664.266930637626;6706.83232919176;6707.1616047994;6710.396064354231;6755.594664320157;4820.276958456225;4823.107725070873;4828.73441960692;4829.782659780319;4831;7029.718811247544;7051.929480705178;7056.576605403692;7058.505904586681;7065.0815393998455;4668.346431499593;4811.807527698853;4812.037592481518;4842.443119041285;4948.752562371867;4887.828925987645;4890.290907818347;4901.313816065754;4908.2146856503505;4916.606671466274;5661.490465858093;5663.377028526858;5667.263255776723;5668.688222147739;5677.716114194273;6752.426043786235;6795.72817087311;6820.658967051628;6824.454043676593;6832.385085342244;1716.6395057037912;1741.6342568060595;1743.3026789284133;1746.1182103037884;1748.2569907789139;1112.933224006562;1145.633397312852;1147.6442302886132;1158.9362585057734;1160.442987366069;6646.036324732887;6653.767118150841;6673.065770792093;6691.59850408977;6691.631960242136;6913.3777959983845;7046.365827075588;7062.470314726416;7064.50548461613;7066.858662826715;6546.18172728409;6644.634545099969;6653.866922661519;6654.600723956578;6684.899726504106;3768.057985503624;3823.044238940265;3848.5189351331082;3853.2100919312034;3859.05453163971;7315.963420182971;7350.669219885118;7358.889616655647;7359.411247100219;7376.7786966391095;7060.646967651597;7270.7091716331815;7292.854142917112;7295.161056478467;7314.341709246193;7366.189719130892;7391.51955122926;7401.888971665429;7416.0729908761405;7432.479726419207;7113.6443177839865;7142.5000249982;7155.212926578176;7155.570665759863;7170.641467926687;6994.405475534637;7002.474814389043;7020.5787652741;7026.473014523829;7049.929500705132;7242.493025488166;7336.1930187678245;7352;7354.154272258758;7403.66683499233;4954.3311652926395;4986.451490336132;4987.351644286219;5004.299398084198;5029.396472423333;6980.057692211486;7037.859242815218;7041.50709450354;7046.647667616701;7064.717411303696;6821.454283657196;6856.9314306856295;6860.313968603299;6868.450523958171;6887.758928437481;6699.39705426514;6779.93220067793;6831.316868313129;6833.180018397824;6841.794746157623;6645.900311495235;6662.200535935719;6694.799156025327;6703.363180497845;6712.463002959849;6861.279565645735;6862.9313706862295;6873.20958089826;6879.793606191823;6895.275996020346;6640.867182656223;6646.966765166143;6680.83297917556;6703.765368212089;6718.126643536309 18:30:41 INFO - PROCESS | 9716 | |5;yui.html;769.8190925132593;799.4643588795507;876.7063033883649;895.3866601378056;918.875951746514;14700.338484768185;14781.926090369547;14853.92573037136;14973.401063957428;15072.54782356531;14008.719825603483;14093.083949543272;14096.365663545139;14133.010689251756;14174.149551026945;775.0660454152761;883.2051153961454;911.7492689510404;911.9407238529475;925.9537023148849;917.4357769971452;928.5496534180917;928.9535523223846;929.2148134826061;933.2160984772789;985.7929834734679;989.1938070472578;990.0248255468358;991.0485933503845;992.9056739609762;966.3863446711354;972.9708108756748;975.512243878061;975.8048390321928;977.7164622259536;728.2426276672254;732.1653315220652;732.3591857125016;732.9670164842595;735.4484136897327;15028.09831410119;15067.623309417242;15108.244587770567;15112.773308400383;15131.394744210218;13963.162210267417;13974.580762577038;13994.650133746738;14073.71852562943;14113.223772692498;10542;10596.576136954513;10647.84028239577;10786.298890572167;10796.514156862882;3871.845126194949;3873.2253549290253;3942.8817135485983;3947.5855035221175;3959;54956.90086198293;55076.72461637706;55483.722581386835;55630.72184639051;55885.161722574194;11339.886601133883;11416.257943233662;11434.771304573862;11454.599089031844;11503.827442588368;19680.40958771239;19798.307059252857;19804.40586782399;19819.30632427858;19908.70136947947;8082.676692932335;8102.230288122619;8177.1414001530175;8259.38054645904;8555.871661925075;4539.6368290536675;4601.079784043205;4620;4710.3405523226775;4711.128441238382;3321.551590535295;3371.898843034713;3390.796552206826;3391.0505058583635;3398.3373242218026;607.9939200607937;611.4130434782659;616.4698359410903;617.1853153836981;618.2056057965493;700.551646945955;733.6075199768098;748.9176190619028;761.1589193940727;761.8971438855683;8990.505522196341;9356.859647105299;9543.856842147374;9608.711738647851;9628.988956159643;588.7115313496356;603.9516838652897;662.4501663619185;684.0286792754301;691.0981169573654;4315.741055536678;4326.069894972596;4354.891127721832;4475.115223481112;4592.425946756655;58710.70644646749;59960.40039599635;60055;60627.393726062175;60694;51048.234276485884;53008.469915301124;54115.18827217595;54199.45800542023;54467.18299225515;48411.27383089256;48592.27111593329;48800.511994880304;48917;49031.264531032066;8431.704890328809;8534.231919127309;8568.057513673615;8585.227329540372;8698.869516957124;34992.82503587466;36526.45210321794;36531;36554.451683224244;36783.63216367802;41347.793261033505;41571.1685766289;44616;45152.32271515996;45486.54513454955;10996.945015274872;11054;12146.39268036577;12186.329751863732;12312.26126432417;13002;13074.084814062924;13467.32663366808;13769.31153442344;13803.2408217549;11916.642500724993;12194.756104877675;12320.137590368588;12350.567730129402;12746.872531274568;11973.820392693942;12169.779761637184;12430.316332601433;12723.427445765148;12950.611481655566;11846.703832404259;12018.639440816789;12117.09121815885;12528.185667931555;12634.810477842655;10552.472376381304;10682.57269709203;10686.625968091079;11225.438728063727;11239.325640461599;10686.045934818381;10916.454177291262;10926.836097458703;10974.945125274322;11018.338899665725 18:30:41 INFO - PROCESS | 9716 | __end_tp_report 18:30:41 INFO - PROCESS | 9716 | __start_cc_report 18:30:41 INFO - PROCESS | 9716 | _x_x_mozilla_cycle_collect,326 18:30:41 INFO - PROCESS | 9716 | __end_cc_report 18:30:41 INFO - PROCESS | 9716 | __startTimestamp1461288641311__endTimestamp 18:30:41 INFO - PROCESS | 9716 | 18:30:41 INFO - PROCESS | 9716 | ------- Summary: start ------- 18:30:41 INFO - PROCESS | 9716 | Number of tests: 6 18:30:41 INFO - PROCESS | 9716 | 18:30:41 INFO - PROCESS | 9716 | [#0] dojo.html Cycles:165 Average:5571.54 Median:5793.04 stddev:623.66 (10.8%) stddev-sans-first:599.96 18:30:41 INFO - PROCESS | 9716 | Values: 3316.3 3339.8 3341.7 3363.1 3397.6 5457.2 5466.5 5480.3 5493.5 5493.6 5481.7 5496.1 5501.5 5514.2 5551.1 5013.5 5057.4 5095.4 5109.3 5133.2 5052.8 5078.7 5149.9 5167.1 5212.2 5124.4 5185.4 5185.5 5195.8 5199.4 5127.5 5135.4 5143.7 5160.1 5180.2 4966.7 4984.7 4989.3 5062.1 5065.5 5358.6 5401.5 5418.9 5442.5 5447.6 6012.4 6043.3 6047.6 6049.5 6060.1 5958.5 6025.4 6032.4 6055.3 6061.8 5595.3 5602.0 5602.9 5607.4 5612.8 6045.2 6099.7 6104.8 6111.8 6117.1 5776.7 5961.9 5970.4 6000.1 6023.2 5950.2 5999.4 6004.4 6017.8 6020.7 5859.5 5954.0 5958.6 5960.2 5966.9 5980.7 5989.6 6016.5 6032.4 6077.9 4545.8 4567.2 4575.0 4587.6 4604.7 4042.8 4056.6 4078.9 4132.4 4148.1 5602.7 5650.1 5701.0 5735.5 5752.0 5966.4 6022.4 6027.5 6037.8 6038.4 5577.1 5717.4 5740.1 5743.5 5747.2 5152.2 5157.7 5162.1 5165.3 5195.9 6172.4 6179.9 6181.8 6182.8 6195.2 5948.0 6012.8 6014.5 6017.4 6018.5 6109.9 6168.2 6172.3 6231.5 6268.1 5960.0 5960.6 5962.0 5968.0 5988.0 5972.2 5987.7 6036.4 6036.9 6046.7 5866.4 5889.5 5894.9 5899.9 5956.0 5878.3 5886.5 5926.5 5928.5 5930.6 5602.9 5730.1 5811.8 5816.0 5823.4 5909.2 5925.5 5929.1 5931.9 5995.8 5744.5 5754.0 5767.7 5783.9 5793.0 18:30:41 INFO - PROCESS | 9716 | 18:30:41 INFO - PROCESS | 9716 | [#1] ext.html Cycles:180 Average:53274.53 Median:38197.12 stddev:99364.54 (260.1%) stddev-sans-first:99564.44 18:30:41 INFO - PROCESS | 9716 | Values: 555.2 565.6 574.5 594.0 607.4 30976.5 39917.4 40452.2 40637.8 40684.0 43740.3 44544.6 44668.6 44704.3 44882.0 8829.9 9025.5 9028.4 9043.9 9050.1 8929.8 9008.1 9014.4 9018.0 9018.6 8982.1 9093.6 9094.6 9102.7 9115.0 8987.3 9072.5 9091.0 9225.0 9288.0 9110.5 9227.3 9248.3 9270.7 9278.5 43191.4 43613.6 43621.6 43697.5 43781.3 3550.6 3778.1 4225.5 4341.4 4510.1 33510.5 33721.2 33745.8 33814.7 34931.8 4278.9 4310.1 4357.5 4363.3 4409.6 285631.0 286280.0 286741.0 287705.0 289016.0 47162.5 66394.3 66682.3 67173.0 67318.7 45626.0 45822.8 45906.5 45923.8 45953.0 15283.8 15312.9 15336.1 15355.0 15416.5 13755.2 13807.3 13807.9 13839.7 13843.7 15481.8 15631.5 16006.0 16066.1 16080.1 6168.4 6228.9 6340.8 6354.5 6360.7 1071.6 1074.4 1084.2 1097.2 1123.3 29835.0 30403.0 30458.7 30594.0 31167.1 1134.9 1149.9 1154.6 1193.4 1222.3 14551.5 14573.6 14764.4 15054.4 15191.3 334013.0 502984.0 503719.0 508462.0 588658.0 62907.0 63867.0 63996.4 64009.0 64182.0 1518.1 1542.6 1549.7 1556.2 1558.3 31524.0 31565.0 32027.2 32132.8 32526.8 51846.2 51925.5 52104.5 52225.7 52482.0 303449.0 307124.0 311402.0 312320.0 312399.0 35647.5 35747.6 36022.5 36181.0 36215.5 47715.0 48461.5 49453.0 52170.0 53368.5 37808.6 37950.7 38106.6 38390.0 38503.6 36906.8 37181.8 37629.6 37679.0 38194.6 36720.6 37182.8 37231.4 38048.8 38199.6 32243.8 34384.5 35436.0 35480.8 36079.5 27605.3 27648.7 27755.7 27860.2 27904.3 18:30:41 INFO - PROCESS | 9716 | 18:30:41 INFO - PROCESS | 9716 | [#2] jquery.html Cycles:180 Average:26780.78 Median:4670.06 stddev:77307.55 (1655.4%) stddev-sans-first:77498.97 18:30:41 INFO - PROCESS | 9716 | Values: 366.8 382.2 384.3 387.6 389.8 17671.6 18686.3 19018.7 19380.2 19444.5 12237.6 12466.4 12468.9 12477.9 12528.4 3985.4 5138.6 5319.3 5667.7 5842.0 5556.5 5777.4 5805.9 5809.0 5813.7 5725.9 5746.8 5753.1 5755.0 5905.7 6322.3 6340.6 6342.8 6361.3 6403.6 5561.3 5707.4 5717.7 5727.9 5734.3 20083.1 20960.0 21942.0 22081.7 22121.8 2253.8 2364.3 2402.0 2422.6 2930.3 9345.3 9389.3 9592.9 9618.4 9753.1 1951.1 1968.7 2037.5 2052.2 2074.9 68255.0 71555.6 72599.6 72838.6 74556.3 10108.9 10400.8 10582.5 10731.0 10866.2 15300.4 15344.6 15358.4 15551.4 15624.9 8087.6 8234.7 8265.1 8350.2 8355.4 8084.4 8084.6 8162.6 8243.8 8268.1 4659.4 4680.7 4776.2 4816.2 4875.2 1945.9 2008.3 2042.8 2047.5 2064.8 453.2 968.9 1036.9 1067.3 1083.7 10675.9 11051.6 11112.3 11222.1 11302.7 484.4 489.2 494.8 500.2 511.6 6139.8 6335.5 6374.1 6379.3 6500.9 447862.0 455069.0 468267.0 487350.0 488623.0 80830.0 82997.6 84523.0 84755.6 84982.6 490.3 496.0 500.8 502.1 504.1 10733.9 10805.4 10854.3 10862.6 10909.1 34044.0 34459.5 35065.1 35361.8 35908.1 53301.7 53311.5 53658.0 53739.7 54009.0 8153.0 8295.6 8312.4 8438.8 8445.1 9532.4 12638.2 13570.3 13633.5 13793.8 11068.8 11349.7 11398.7 11492.3 11509.5 11290.6 11806.5 11997.3 12067.8 12127.2 11523.7 11547.2 11552.5 11701.1 12074.5 8033.9 8346.1 8410.2 8614.7 8830.6 8738.7 10864.6 11697.8 11707.9 11829.3 18:30:41 INFO - PROCESS | 9716 | 18:30:41 INFO - PROCESS | 9716 | [#3] mootools.html Cycles:180 Average:5639.70 Median:5790.61 stddev:2937.58 (50.7%) stddev-sans-first:2929.96 18:30:41 INFO - PROCESS | 9716 | Values: 1578.1 1579.1 1856.2 1897.6 1948.8 7131.5 7805.7 8007.0 8217.4 8222.3 7902.3 8023.1 8023.7 8028.8 8043.1 2339.8 2386.9 2395.1 2395.6 2399.1 2338.1 2344.4 2388.6 2393.1 2394.7 2335.6 2344.5 2352.5 2381.6 2410.5 2325.6 2349.4 2357.9 2362.5 2370.6 2340.9 2365.7 2369.1 2370.5 2383.0 8189.7 8198.1 8202.5 8212.2 8310.9 4957.7 5101.3 5118.3 5123.2 5123.6 3324.4 3344.7 3346.9 3429.9 3450.0 1079.2 1086.0 1100.2 1100.7 1108.9 11150.9 11177.8 11179.2 11205.6 11239.3 4308.4 4351.9 4396.5 4427.2 4427.6 9592.3 9598.7 9613.8 9655.0 9697.0 4873.9 4895.4 4898.2 4904.0 4906.0 4026.3 4399.7 4443.5 4484.4 4524.4 3788.9 3815.9 3862.7 3865.7 3913.3 1250.5 1259.0 1274.6 1276.4 1296.6 1288.6 1307.4 1309.3 1350.0 1351.8 7368.6 7388.5 7474.6 7509.7 7513.4 514.7 518.2 521.4 526.3 536.5 5760.5 5785.7 5795.6 5804.0 5829.6 9456.5 10030.4 10059.5 10094.6 10104.2 9718.5 9766.0 9889.6 9898.7 9916.3 7350.6 7503.6 7535.1 7601.7 7608.5 7382.6 7519.0 7567.4 7640.7 7648.2 8525.2 8674.9 8680.5 8693.8 8751.9 8632.4 8737.4 8866.1 8923.3 8951.7 6559.3 6798.2 6962.7 7140.9 7233.9 7680.3 7706.8 7755.3 7762.7 7763.2 6975.5 7029.8 7062.2 7186.0 7196.5 6697.4 6795.9 6841.5 6891.7 7034.1 6819.9 6850.8 6876.8 6953.9 6989.2 6473.2 6521.2 6541.1 6551.8 6580.3 6570.4 6615.7 6705.8 6740.3 6749.6 18:30:41 INFO - PROCESS | 9716 | 18:30:41 INFO - PROCESS | 9716 | [#4] prototype.html Cycles:180 Average:5733.21 Median:6593.52 stddev:1723.71 (26.1%) stddev-sans-first:1680.40 18:30:41 INFO - PROCESS | 9716 | Values: 343.3 350.2 359.4 363.9 369.7 6400.6 6424.7 6465.1 6500.5 6505.7 6381.7 6408.1 6413.9 6415.8 6416.4 4734.5 4823.3 4966.5 4995.8 4999.9 4941.6 4959.1 4961.0 4968.8 4978.5 4871.4 4912.6 4920.7 4927.8 4928.3 4924.3 4931.3 4940.6 4942.0 4955.2 4370.6 4371.3 4372.1 4382.9 4387.3 5943.3 6095.0 6126.1 6159.1 6165.3 5694.4 5710.9 5726.0 5738.3 5757.3 6664.3 6706.8 6707.2 6710.4 6755.6 4820.3 4823.1 4828.7 4829.8 4831.0 7029.7 7051.9 7056.6 7058.5 7065.1 4668.3 4811.8 4812.0 4842.4 4948.8 4887.8 4890.3 4901.3 4908.2 4916.6 5661.5 5663.4 5667.3 5668.7 5677.7 6752.4 6795.7 6820.7 6824.5 6832.4 1716.6 1741.6 1743.3 1746.1 1748.3 1112.9 1145.6 1147.6 1158.9 1160.4 6646.0 6653.8 6673.1 6691.6 6691.6 6913.4 7046.4 7062.5 7064.5 7066.9 6546.2 6644.6 6653.9 6654.6 6684.9 3768.1 3823.0 3848.5 3853.2 3859.1 7316.0 7350.7 7358.9 7359.4 7376.8 7060.6 7270.7 7292.9 7295.2 7314.3 7366.2 7391.5 7401.9 7416.1 7432.5 7113.6 7142.5 7155.2 7155.6 7170.6 6994.4 7002.5 7020.6 7026.5 7049.9 7242.5 7336.2 7352.0 7354.2 7403.7 4954.3 4986.5 4987.4 5004.3 5029.4 6980.1 7037.9 7041.5 7046.6 7064.7 6821.5 6856.9 6860.3 6868.5 6887.8 6699.4 6779.9 6831.3 6833.2 6841.8 6645.9 6662.2 6694.8 6703.4 6712.5 6861.3 6862.9 6873.2 6879.8 6895.3 6640.9 6647.0 6680.8 6703.8 6718.1 18:30:41 INFO - PROCESS | 9716 | 18:30:41 INFO - PROCESS | 9716 | [#5] yui.html Cycles:180 Average:14758.29 Median:24545.56 stddev:16778.21 (68.4%) stddev-sans-first:16792.39 18:30:41 INFO - PROCESS | 9716 | Values: 769.8 799.5 876.7 895.4 918.9 14700.3 14781.9 14853.9 14973.4 15072.5 14008.7 14093.1 14096.4 14133.0 14174.1 775.1 883.2 911.7 911.9 926.0 917.4 928.5 929.0 929.2 933.2 985.8 989.2 990.0 991.0 992.9 966.4 973.0 975.5 975.8 977.7 728.2 732.2 732.4 733.0 735.4 15028.1 15067.6 15108.2 15112.8 15131.4 13963.2 13974.6 13994.7 14073.7 14113.2 10542.0 10596.6 10647.8 10786.3 10796.5 3871.8 3873.2 3942.9 3947.6 3959.0 54956.9 55076.7 55483.7 55630.7 55885.2 11339.9 11416.3 11434.8 11454.6 11503.8 19680.4 19798.3 19804.4 19819.3 19908.7 8082.7 8102.2 8177.1 8259.4 8555.9 4539.6 4601.1 4620.0 4710.3 4711.1 3321.6 3371.9 3390.8 3391.1 3398.3 608.0 611.4 616.5 617.2 618.2 700.6 733.6 748.9 761.2 761.9 8990.5 9356.9 9543.9 9608.7 9629.0 588.7 604.0 662.5 684.0 691.1 4315.7 4326.1 4354.9 4475.1 4592.4 58710.7 59960.4 60055.0 60627.4 60694.0 51048.2 53008.5 54115.2 54199.5 54467.2 48411.3 48592.3 48800.5 48917.0 49031.3 8431.7 8534.2 8568.1 8585.2 8698.9 34992.8 36526.5 36531.0 36554.5 36783.6 41347.8 41571.2 44616.0 45152.3 45486.5 10996.9 11054.0 12146.4 12186.3 12312.3 13002.0 13074.1 13467.3 13769.3 13803.2 11916.6 12194.8 12320.1 12350.6 12746.9 11973.8 12169.8 12430.3 12723.4 12950.6 11846.7 12018.6 12117.1 12528.2 12634.8 10552.5 10682.6 10686.6 11225.4 11239.3 10686.0 10916.5 10926.8 10974.9 11018.3 18:30:41 INFO - PROCESS | 9716 | -------- Summary: end -------- 18:30:41 INFO - PROCESS | 9716 | 18:30:41 INFO - TEST-INFO | 9716: exit 0 18:30:41 INFO - TEST-OK | dromaeo_css | took 1109109ms 18:30:41 INFO - TEST-START | kraken 18:30:41 INFO - operating with platform_type : linux_ 18:30:41 INFO - Initialising browser for kraken test... 18:30:41 INFO - TEST-INFO | started process 9967 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdWfM6n/profile http://localhost:50681/getInfo.html) 18:30:45 INFO - PROCESS | 9967 | 18:30:45 INFO - PROCESS | 9967 | (process:10049): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 18:30:46 INFO - PROCESS | 9967 | __metrics Screen width/height:1600/1200 18:30:46 INFO - PROCESS | 9967 | colorDepth:24 18:30:46 INFO - PROCESS | 9967 | Browser inner width/height: 1024/697 18:30:46 INFO - PROCESS | 9967 | __metrics 18:30:46 ERROR - PROCESS | 9967 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 18:30:47 INFO - TEST-INFO | 9967: exit 0 18:30:47 INFO - Browser initialized. 18:30:47 INFO - Running cycle 1/1 for kraken test... 18:30:47 INFO - TEST-INFO | started process 10100 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdWfM6n/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:30:49 INFO - PROCESS | 10100 | 18:30:49 INFO - PROCESS | 10100 | (process:10165): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 18:30:59 INFO - PROCESS | 10100 | RSS: Main: 151052288 18:30:59 INFO - PROCESS | 10100 | 18:31:02 INFO - PROCESS | 10100 | Cycle 1(1): loaded http://localhost:50681/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50681/tests/kraken/driver.html?testName=audio-beat-detection) 18:31:02 INFO - PROCESS | 10100 | RSS: Main: 155947008 18:31:02 INFO - PROCESS | 10100 | 18:31:05 INFO - PROCESS | 10100 | Cycle 1(1): loaded http://localhost:50681/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50681/tests/kraken/driver.html?testName=audio-dft) 18:31:06 INFO - PROCESS | 10100 | RSS: Main: 156008448 18:31:06 INFO - PROCESS | 10100 | 18:31:08 INFO - PROCESS | 10100 | Cycle 1(1): loaded http://localhost:50681/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50681/tests/kraken/driver.html?testName=audio-fft) 18:31:09 INFO - PROCESS | 10100 | RSS: Main: 156569600 18:31:09 INFO - PROCESS | 10100 | 18:31:11 INFO - PROCESS | 10100 | Cycle 1(1): loaded http://localhost:50681/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50681/tests/kraken/driver.html?testName=audio-oscillator) 18:31:12 INFO - PROCESS | 10100 | RSS: Main: 156839936 18:31:12 INFO - PROCESS | 10100 | 18:31:14 INFO - PROCESS | 10100 | Cycle 1(1): loaded http://localhost:50681/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50681/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:31:14 INFO - PROCESS | 10100 | RSS: Main: 156717056 18:31:14 INFO - PROCESS | 10100 | 18:31:17 INFO - PROCESS | 10100 | Cycle 1(1): loaded http://localhost:50681/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50681/tests/kraken/driver.html?testName=imaging-darkroom) 18:31:18 INFO - PROCESS | 10100 | RSS: Main: 156631040 18:31:18 INFO - PROCESS | 10100 | 18:31:21 INFO - PROCESS | 10100 | Cycle 1(1): loaded http://localhost:50681/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50681/tests/kraken/driver.html?testName=imaging-desaturate) 18:31:22 INFO - PROCESS | 10100 | RSS: Main: 156622848 18:31:22 INFO - PROCESS | 10100 | 18:31:25 INFO - PROCESS | 10100 | Cycle 1(1): loaded http://localhost:50681/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50681/tests/kraken/driver.html?testName=json-parse-financial) 18:31:25 INFO - PROCESS | 10100 | RSS: Main: 156798976 18:31:25 INFO - PROCESS | 10100 | 18:31:27 INFO - PROCESS | 10100 | Cycle 1(1): loaded http://localhost:50681/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50681/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:31:28 INFO - PROCESS | 10100 | RSS: Main: 156688384 18:31:28 INFO - PROCESS | 10100 | 18:31:29 INFO - PROCESS | 10100 | Cycle 1(1): loaded http://localhost:50681/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50681/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:31:30 INFO - PROCESS | 10100 | RSS: Main: 156123136 18:31:30 INFO - PROCESS | 10100 | 18:31:32 INFO - PROCESS | 10100 | Cycle 1(1): loaded http://localhost:50681/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50681/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:31:33 INFO - PROCESS | 10100 | RSS: Main: 156479488 18:31:33 INFO - PROCESS | 10100 | 18:31:35 INFO - PROCESS | 10100 | Cycle 1(1): loaded http://localhost:50681/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50681/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:31:36 INFO - PROCESS | 10100 | RSS: Main: 157163520 18:31:36 INFO - PROCESS | 10100 | 18:31:38 INFO - PROCESS | 10100 | Cycle 1(1): loaded http://localhost:50681/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50681/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:31:39 INFO - PROCESS | 10100 | RSS: Main: 157941760 18:31:39 INFO - PROCESS | 10100 | 18:31:40 INFO - PROCESS | 10100 | Cycle 1(1): loaded http://localhost:50681/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50681/tests/kraken/driver.html?testName=ai-astar) 18:31:41 INFO - PROCESS | 10100 | RSS: Main: 158613504 18:31:41 INFO - PROCESS | 10100 | 18:31:41 INFO - PROCESS | 10100 | __start_tp_report 18:31:41 INFO - PROCESS | 10100 | _x_x_mozilla_page_load 18:31:41 INFO - PROCESS | 10100 | _x_x_mozilla_page_load_details 18:31:41 INFO - PROCESS | 10100 | |i|pagename|runs| 18:31:41 INFO - PROCESS | 10100 | |0;ai-astar;111;107;113;113;110;122;111;111;110;111 18:31:41 INFO - PROCESS | 10100 | |1;audio-beat-detection;159;134;154;165;128;156;153;130;172;127 18:31:41 INFO - PROCESS | 10100 | |2;audio-dft;162;163;164;163;163;162;163;164;164;161 18:31:41 INFO - PROCESS | 10100 | |3;audio-fft;77;73;75;156;77;75;75;77;162;74 18:31:41 INFO - PROCESS | 10100 | |4;audio-oscillator;93;93;141;93;92;92;148;94;93;146 18:31:41 INFO - PROCESS | 10100 | |5;imaging-gaussian-blur;117;118;117;118;118;117;118;117;117;118 18:31:41 INFO - PROCESS | 10100 | |6;imaging-darkroom;147;146;147;147;147;146;147;148;147;147 18:31:41 INFO - PROCESS | 10100 | |7;imaging-desaturate;96;97;96;96;97;96;96;95;96;96 18:31:41 INFO - PROCESS | 10100 | |8;json-parse-financial;54;54;56;56;59;55;59;65;56;55 18:31:41 INFO - PROCESS | 10100 | |9;json-stringify-tinderbox;64;64;65;63;64;64;64;66;64;65 18:31:41 INFO - PROCESS | 10100 | |10;stanford-crypto-aes;79;88;79;78;78;79;79;78;79;78 18:31:41 INFO - PROCESS | 10100 | |11;stanford-crypto-ccm;129;115;114;112;116;113;121;124;114;118 18:31:41 INFO - PROCESS | 10100 | |12;stanford-crypto-pbkdf2;134;146;145;151;147;153;139;147;145;146 18:31:41 INFO - PROCESS | 10100 | |13;stanford-crypto-sha256-iterative;66;66;66;65;71;66;67;65;67;70 18:31:41 INFO - PROCESS | 10100 | __end_tp_report 18:31:41 INFO - PROCESS | 10100 | __start_cc_report 18:31:41 INFO - PROCESS | 10100 | _x_x_mozilla_cycle_collect,842 18:31:41 INFO - PROCESS | 10100 | __end_cc_report 18:31:41 INFO - PROCESS | 10100 | __startTimestamp1461288701108__endTimestamp 18:31:41 INFO - PROCESS | 10100 | 18:31:41 INFO - PROCESS | 10100 | ------- Summary: start ------- 18:31:41 INFO - PROCESS | 10100 | Number of tests: 14 18:31:41 INFO - PROCESS | 10100 | 18:31:41 INFO - PROCESS | 10100 | [#0] ai-astar Cycles:10 Average:111.90 Median:111.00 stddev:3.93 (3.5%) stddev-sans-first:4.15 18:31:41 INFO - PROCESS | 10100 | Values: 111.0 107.0 113.0 113.0 110.0 122.0 111.0 111.0 110.0 111.0 18:31:41 INFO - PROCESS | 10100 | 18:31:41 INFO - PROCESS | 10100 | [#1] audio-beat-detection Cycles:10 Average:147.80 Median:155.00 stddev:16.57 (10.7%) stddev-sans-first:17.07 18:31:41 INFO - PROCESS | 10100 | Values: 159.0 134.0 154.0 165.0 128.0 156.0 153.0 130.0 172.0 127.0 18:31:41 INFO - PROCESS | 10100 | 18:31:41 INFO - PROCESS | 10100 | [#2] audio-dft Cycles:10 Average:162.90 Median:163.00 stddev:0.99 (0.6%) stddev-sans-first:1.00 18:31:41 INFO - PROCESS | 10100 | Values: 162.0 163.0 164.0 163.0 163.0 162.0 163.0 164.0 164.0 161.0 18:31:41 INFO - PROCESS | 10100 | 18:31:41 INFO - PROCESS | 10100 | [#3] audio-fft Cycles:10 Average:92.10 Median:75.00 stddev:35.31 (47.1%) stddev-sans-first:37.03 18:31:41 INFO - PROCESS | 10100 | Values: 77.0 73.0 75.0 156.0 77.0 75.0 75.0 77.0 162.0 74.0 18:31:41 INFO - PROCESS | 10100 | 18:31:41 INFO - PROCESS | 10100 | [#4] audio-oscillator Cycles:10 Average:108.50 Median:93.00 stddev:25.25 (27.2%) stddev-sans-first:26.15 18:31:41 INFO - PROCESS | 10100 | Values: 93.0 93.0 141.0 93.0 92.0 92.0 148.0 94.0 93.0 146.0 18:31:41 INFO - PROCESS | 10100 | 18:31:41 INFO - PROCESS | 10100 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:118.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 18:31:41 INFO - PROCESS | 10100 | Values: 117.0 118.0 117.0 118.0 118.0 117.0 118.0 117.0 117.0 118.0 18:31:41 INFO - PROCESS | 10100 | 18:31:41 INFO - PROCESS | 10100 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 18:31:41 INFO - PROCESS | 10100 | Values: 147.0 146.0 147.0 147.0 147.0 146.0 147.0 148.0 147.0 147.0 18:31:41 INFO - PROCESS | 10100 | 18:31:41 INFO - PROCESS | 10100 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 18:31:41 INFO - PROCESS | 10100 | Values: 96.0 97.0 96.0 96.0 97.0 96.0 96.0 95.0 96.0 96.0 18:31:41 INFO - PROCESS | 10100 | 18:31:41 INFO - PROCESS | 10100 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:56.00 stddev:3.35 (6.0%) stddev-sans-first:3.38 18:31:41 INFO - PROCESS | 10100 | Values: 54.0 54.0 56.0 56.0 59.0 55.0 59.0 65.0 56.0 55.0 18:31:41 INFO - PROCESS | 10100 | 18:31:41 INFO - PROCESS | 10100 | [#9] json-stringify-tinderbox Cycles:10 Average:64.30 Median:64.00 stddev:0.82 (1.3%) stddev-sans-first:0.87 18:31:41 INFO - PROCESS | 10100 | Values: 64.0 64.0 65.0 63.0 64.0 64.0 64.0 66.0 64.0 65.0 18:31:41 INFO - PROCESS | 10100 | 18:31:41 INFO - PROCESS | 10100 | [#10] stanford-crypto-aes Cycles:10 Average:79.50 Median:79.00 stddev:3.03 (3.8%) stddev-sans-first:3.21 18:31:41 INFO - PROCESS | 10100 | Values: 79.0 88.0 79.0 78.0 78.0 79.0 79.0 78.0 79.0 78.0 18:31:41 INFO - PROCESS | 10100 | 18:31:41 INFO - PROCESS | 10100 | [#11] stanford-crypto-ccm Cycles:10 Average:117.60 Median:117.00 stddev:5.48 (4.7%) stddev-sans-first:3.97 18:31:41 INFO - PROCESS | 10100 | Values: 129.0 115.0 114.0 112.0 116.0 113.0 121.0 124.0 114.0 118.0 18:31:41 INFO - PROCESS | 10100 | 18:31:41 INFO - PROCESS | 10100 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:145.30 Median:146.50 stddev:5.44 (3.7%) stddev-sans-first:3.94 18:31:41 INFO - PROCESS | 10100 | Values: 134.0 146.0 145.0 151.0 147.0 153.0 139.0 147.0 145.0 146.0 18:31:41 INFO - PROCESS | 10100 | 18:31:41 INFO - PROCESS | 10100 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:66.50 stddev:2.02 (3.0%) stddev-sans-first:2.12 18:31:41 INFO - PROCESS | 10100 | Values: 66.0 66.0 66.0 65.0 71.0 66.0 67.0 65.0 67.0 70.0 18:31:41 INFO - PROCESS | 10100 | -------- Summary: end -------- 18:31:41 INFO - PROCESS | 10100 | 18:31:41 INFO - TEST-INFO | 10100: exit 0 18:31:42 INFO - TEST-OK | kraken | took 60070ms 18:31:42 INFO - SUITE-END | took 1169s 18:31:42 INFO - Completed test suite (00:19:29) 18:31:42 INFO - javascript benchmark 18:31:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3316.253842885351, 3339.799612023278, 3341.7493687973392, 3363.1087761743156, 3397.643247459017, 5457.2359869618285, 5466.508014278714, 5480.314960629921, 5493.505584497394, 5493.587980901429, 5481.725913704308, 5496.136073932442, 5501.477359650837, 5514.172874068891, 5551.139573366125, 5013.523715247045, 5057.443681195065, 5095.439501654815, 5109.2591574221715, 5133.153029750087, 5052.823151189709, 5078.720670363128, 5149.871253218681, 5167.121589329804, 5212.218167274901, 5124.359455068116, 5185.403678576974, 5185.481451854822, 5195.792168313263, 5199.350081239845, 5127.461616530267, 5135.3837539495125, 5143.665661731976, 5160.122779127557, 5180.171172612401, 4966.6771659842, 4984.728894132009, 4989.251612258154, 5062.11413002723, 5065.544101030888, 5358.5981051421295, 5401.513863752242, 5418.9458105418835, 5442.482964118402, 5447.591430642718, 6012.428819262163, 6043.335233124353, 6047.637141771508, 6049.48579370754, 6060.121282414069, 5958.463738263534, 6025.427584379477, 6032.426919442646, 6055.27336719592, 6061.848453788646, 5595.328560572757, 5602.047651899187, 5602.943970560283, 5607.383187849333, 5612.831615051555, 6045.153678485016, 6099.725512351954, 6104.786332478342, 6111.84720381994, 6117.113018612276, 5776.653400795965, 5961.940380596226, 5970.432808883149, 6000.069989151689, 6023.156758053876, 5950.19672344228, 5999.400059994053, 6004.369541198112, 6017.819465416044, 6020.729067191987, 5859.53123750099, 5953.9878220702585, 5958.612690175125, 5960.225170727777, 5966.910496342558, 5980.73086711099, 5989.640621562633, 6016.518678505709, 6032.426919442646, 6077.908831367533, 4545.818167273338, 4567.246404343313, 4575.0, 4587.610053145453, 4604.746738929324, 4042.8382864685673, 4056.59434056592, 4078.898027549335, 4132.380142978577, 4148.128892932457, 5602.663840169603, 5650.124230744241, 5701.0308247598, 5735.512481439041, 5752.0, 5966.433188847052, 6022.427869352405, 6027.547933904974, 6037.818865434044, 6038.365971572922, 5577.079781836032, 5717.42825717425, 5740.1102829061565, 5743.4543718346695, 5747.224124743189, 5152.175651895679, 5157.742112894415, 5162.148245539444, 5165.302684137669, 5195.948040519622, 6172.382761723881, 6179.938200618026, 6181.7527298908435, 6182.814515564541, 6195.1636529068, 5948.0, 6012.819615411545, 6014.48876845473, 6017.3681763414215, 6018.548608854266, 6109.914811296778, 6168.1672974149715, 6172.321044684992, 6231.53263505248, 6268.122462855113, 5960.0, 5960.552958528041, 5961.970190149022, 5967.9701601491715, 5988.0, 5972.223610930551, 5987.670678112746, 6036.3661815510495, 6036.939630603638, 6046.667433291035, 5866.442687944638, 5889.52883769306, 5894.852628684317, 5899.941000589939, 5956.0, 5878.29460464747, 5886.499647529837, 5926.4666180044005, 5928.525717942468, 5930.614510056833, 5602.915956260578, 5730.111832665943, 5811.79658711943, 5816.0, 5823.35943046273, 5909.23179986599, 5925.466707996301, 5929.0513517836935, 5931.881362372815, 5995.760169593167, 5744.4829965303325, 5754.0, 5767.682777447113, 5783.913241301299, 5793.044147715748], "unit": "score", "name": "dojo.html", "value": 5530.583859254629}, {"lowerIsBetter": false, "replicates": [555.2296046135573, 565.6351653183699, 574.485835177516, 593.9910901336481, 607.4138456389586, 30976.53535196971, 39917.4012389814, 40452.19095618089, 40637.79681101598, 40684.0, 43740.34389484152, 44544.554554454444, 44668.553314466844, 44704.32943505842, 44882.0, 8829.911700882989, 9025.458472491639, 9028.413153145024, 9043.864342034874, 9050.140236677538, 8929.77675558113, 9008.144226298491, 9014.36899417041, 9018.0, 9018.639254429847, 8982.05688402718, 9093.63625454981, 9094.63621455141, 9102.726918192431, 9114.954425227865, 8987.325950553703, 9072.455652660828, 9091.045440228812, 9224.953875230649, 9287.953560232156, 9110.453372797587, 9227.261819054527, 9248.26013918885, 9270.675526356612, 9278.489683067428, 43191.352129717765, 43613.56386436127, 43621.563784362064, 43697.47058852957, 43781.34327985083, 3550.6449355064547, 3778.112143646241, 4225.492940847087, 4341.39220509129, 4510.143072816154, 33510.49734253964, 33721.156971075674, 33745.83127084349, 33814.66185338164, 34931.82534087313, 4278.871633850989, 4310.073334233155, 4357.4988876279, 4363.323684828857, 4409.559044095597, 285631.0, 286280.0, 286740.9999999979, 287705.0, 289016.0, 47162.52837471581, 66394.33605663881, 66682.33317666859, 67173.0, 67318.66340668265, 45626.0, 45822.77088614536, 45906.54093459089, 45923.77038114855, 45953.0, 15283.847161528465, 15312.851536134835, 15336.079835209923, 15355.0, 15416.537503874903, 13755.243461609707, 13807.309634518233, 13807.861921380858, 13839.723205535833, 13843.723125537434, 15481.845181548266, 15631.531054068397, 16006.0, 16066.11636359988, 16080.115593642462, 6168.352323006109, 6228.937710622836, 6340.778072767431, 6354.491640668735, 6360.681965901779, 1071.614218881202, 1074.3929679730927, 1084.2139448899484, 1097.160672085855, 1123.3035517978906, 29835.0, 30403.0, 30458.69541304603, 30594.0, 31167.064988050395, 1134.8638163420442, 1149.9022583080532, 1154.584349634131, 1193.433119268358, 1222.2544248008708, 14551.490697825526, 14573.635659108395, 14764.40942362315, 15054.397824086915, 15191.316390762442, 334013.0, 502984.0, 503719.0, 508462.0, 588658.0, 62907.0, 63867.0, 63996.36003639997, 64009.0, 64182.0, 1518.142249628956, 1542.6297688554666, 1549.7210502109729, 1556.15189721602, 1558.3377064747438, 31524.0, 31565.0, 32027.199320017186, 32132.83933580317, 32526.83736581302, 51846.22230666468, 51925.480745192064, 52104.47895520996, 52225.7388713054, 52482.0, 303449.0, 307124.0, 311402.0, 312320.0, 312399.0, 35647.465288021216, 35747.64252357443, 36022.4596631056, 36181.0, 36215.456768147975, 47715.0, 48461.5153848457, 49453.0, 52170.0, 53368.4663153379, 37808.62191378161, 37950.67172648944, 38106.61893381141, 38390.0, 38503.61496385001, 36906.8154659225, 37181.81409092937, 37629.62370376261, 37679.0, 38194.618053819104, 36720.63279367279, 37182.8140859294, 37231.44152837764, 38048.80975595104, 38199.6180038196, 32243.838780805945, 34384.48423273603, 35436.0, 35480.82259588686, 36079.45880811843, 27605.309867252676, 27648.723512764616, 27755.722442775314, 27860.16419507418, 27904.30239244035], "unit": "score", "name": "ext.html", "value": 19671.347385624813}, {"lowerIsBetter": false, "replicates": [366.8257577650616, 382.1535299312024, 384.3331819293527, 387.6375588824451, 389.8089935931393, 17671.55821104473, 18686.252549898003, 19018.71471927922, 19380.22479100838, 19444.51388715281, 12237.632871013862, 12466.439010244538, 12468.93765531171, 12477.875221247785, 12528.373581320942, 3985.402189671558, 5138.563222126115, 5319.297684917013, 5667.688277144755, 5842.036064049426, 5556.499915007648, 5777.364489906128, 5805.883882322351, 5809.0, 5813.651180929137, 5725.914111288334, 5746.770129194848, 5753.137029445575, 5755.021646320115, 5905.7342419591005, 6322.272938612026, 6340.587861788992, 6342.809715708536, 6361.300256971729, 6403.647799371032, 5561.332640083176, 5707.400722924075, 5717.71411429431, 5727.856803579903, 5734.283214598176, 20083.096260668306, 20960.0, 21942.0, 22081.668774968388, 22121.77878221213, 2253.752087270399, 2364.2552595932398, 2401.9519609607883, 2422.576049191402, 2930.3260250142584, 9345.345825792198, 9389.295802814817, 9592.856107158399, 9618.422894626345, 9753.073458021476, 1951.0834093139854, 1968.704694295867, 2037.5313677854028, 2052.2406709517427, 2074.9066292016896, 68255.0, 71555.64222178856, 72599.63700181466, 72838.63580682116, 74556.25443745547, 10108.949455252676, 10400.791984160274, 10582.4708764563, 10731.0, 10866.185036122164, 15300.387984480718, 15344.616384590474, 15358.385664573516, 15551.377944882304, 15624.92187539055, 8087.595620218965, 8234.723617839245, 8265.090840007595, 8350.164983501723, 8355.373346999, 8084.434089613788, 8084.555349455836, 8162.551059691774, 8243.83512329762, 8268.131846156188, 4659.4175728034, 4680.695754775928, 4776.188048031843, 4816.181249187646, 4875.19559272716, 1945.8637895347472, 2008.2669825513583, 2042.7855075217185, 2047.5085979364844, 2064.8024145995287, 453.2498714627297, 968.9079537443931, 1036.9377837329785, 1067.3083163186282, 1083.7482707472882, 10675.946620267005, 11051.613193538187, 11112.277701949348, 11222.102231821435, 11302.717432064264, 484.4283745180721, 489.1586471269411, 494.7625139932852, 500.18969269782457, 511.6316252298339, 6139.84650383744, 6335.493160547145, 6374.107624932513, 6379.266384365857, 6500.869982600416, 447862.0, 455069.0, 468267.0, 487350.0, 488623.0, 80830.0, 82997.58501207456, 84523.0, 84755.5762221185, 84982.5750871254, 490.3429404597856, 496.0376868874372, 500.79467418358297, 502.07617982910887, 504.1454734225526, 10733.892661073289, 10805.405702686428, 10854.34873907568, 10862.565497380016, 10909.072728818139, 34044.0, 34459.483107753906, 35065.123371915906, 35361.82319088388, 35908.102297442776, 53301.73349133229, 53311.46688533065, 53658.0, 53739.73130134324, 54009.0, 8153.0, 8295.592841609467, 8312.418130731032, 8438.83122337562, 8445.07104218523, 9532.380395274286, 12638.2417054977, 13570.321483925962, 13633.522826701017, 13793.793093103412, 11068.778624427627, 11349.659510214708, 11398.715032123931, 11492.310461372344, 11509.539618415505, 11290.604828830952, 11806.46870890829, 11997.340146292037, 12067.81898271509, 12127.241549975028, 11523.711907202387, 11547.191696581494, 11552.480138393961, 11701.122415818678, 12074.45664945062, 8033.919660803317, 8346.068646240483, 8410.158984101785, 8614.65541378338, 8830.602622882114, 8738.69414570487, 10864.619738309122, 11697.824532632187, 11707.941460292985, 11829.349385783864], "unit": "score", "name": "jquery.html", "value": 8218.381369424384}, {"lowerIsBetter": false, "replicates": [1578.0689393257976, 1579.0604590268786, 1856.2482194711138, 1897.639448504785, 1948.8051194880513, 7131.536450130738, 7805.687772489107, 8007.0, 8217.424780265377, 8222.34221262299, 7902.288794008538, 8023.117457079731, 8023.719169829057, 8028.839423211547, 8043.11525732169, 2339.789418952298, 2386.8567885926814, 2395.137750409851, 2395.556821987929, 2399.1123284384716, 2338.1348900906687, 2344.367020904355, 2388.641703744435, 2393.114547617375, 2394.6527753475734, 2335.5795956727784, 2344.5076533927895, 2352.529494101178, 2381.607034839258, 2410.4937963027787, 2325.6395258734924, 2349.447879748258, 2357.870317132557, 2362.492064206195, 2370.63255195445, 2340.8946597403155, 2365.6924599802087, 2369.052379048377, 2370.5258948210253, 2383.011050414076, 8189.672413103469, 8198.057223419324, 8202.548859812709, 8212.219839115274, 8310.916890831135, 4957.652964292501, 5101.260317254015, 5118.309028281172, 5123.1546794778815, 5123.641345105844, 3324.3683700096904, 3344.66553344666, 3346.866125354983, 3429.8628054878022, 3449.9827500862334, 1079.1744315598573, 1085.9674209773718, 1100.2463312630794, 1100.7193165742765, 1108.9445527723663, 11150.944245278803, 11177.776444471147, 11179.217454778169, 11205.551777928873, 11239.269447485887, 4308.4399028126145, 4351.912961740811, 4396.516383197845, 4427.158839820424, 4427.623651989553, 9592.328537002482, 9598.712038638852, 9613.759656008517, 9654.951725241328, 9697.0, 4873.902521949542, 4895.412550493964, 4898.21628539432, 4903.975480122576, 4906.0433215522635, 4026.295398305285, 4399.670024748157, 4443.511213766483, 4484.372187893698, 4524.366588677588, 3788.886333409947, 3815.9427608585893, 3862.748921320105, 3865.7100717446306, 3913.295606790748, 1250.5435516036582, 1258.9685257868628, 1274.6303571964213, 1276.3681977421236, 1296.5656505070717, 1288.6391810293132, 1307.4116647508658, 1309.2798960571647, 1349.9942542805672, 1351.8107464954915, 7368.557886526825, 7388.519746216479, 7474.551526908403, 7509.699612015458, 7513.39892808585, 514.722050092953, 518.245952139636, 521.3691433365594, 526.3026489900897, 536.509094178822, 5760.51035661965, 5785.65286082828, 5795.565332600072, 5804.0, 5829.62107463022, 9456.479893605918, 10030.448325342177, 10059.5473203706, 10094.646687365906, 10104.191664666809, 9718.514074296258, 9766.023397660178, 9889.604415823287, 9898.703038908845, 9916.256280778825, 7350.5957172354965, 7503.624818759149, 7535.058117735283, 7601.65792539326, 7608.467407281437, 7382.630868456663, 7519.0, 7567.356774674215, 7640.694372225049, 7648.158702542827, 8525.232729054167, 8674.913250867663, 8680.479171249744, 8693.782655433411, 8751.868721969047, 8632.352573556882, 8737.388382813397, 8866.069062748318, 8923.286137108886, 8951.73144805657, 6559.278479367361, 6798.218204906499, 6962.7214911405, 7140.857182856417, 7233.891491627524, 7680.308772210527, 7706.807329816799, 7755.3407960324, 7762.728304509315, 7763.184865589258, 6975.546589471668, 7029.753958611424, 7062.15254169503, 7186.029885965537, 7196.532225405332, 6697.397234248941, 6795.864082718417, 6841.452683785385, 6891.724331026703, 7034.085568876013, 6819.931800682129, 6850.760223392157, 6876.828079298058, 6953.930460695328, 6989.231184569795, 6473.190851143607, 6521.152250207443, 6541.08424820535, 6551.80344589663, 6580.341965803573, 6570.408663220333, 6615.735370585316, 6705.832354191184, 6740.258571557027, 6749.628770417477], "unit": "score", "name": "mootools.html", "value": 4583.622952096961}, {"lowerIsBetter": false, "replicates": [343.33221883436715, 350.2067816395864, 359.37110057399553, 363.94176931690936, 369.6765330335956, 6400.5519613627, 6424.678766061696, 6465.127207826942, 6500.544961852672, 6505.739770409178, 6381.680915954207, 6408.10286559882, 6413.90379144312, 6415.775447859326, 6416.4225219730215, 4734.502877197896, 4823.324734537167, 4966.528179822911, 4995.775190116436, 4999.875028118662, 4941.579965702919, 4959.057779021974, 4961.0, 4968.751562421882, 4978.5270399312, 4871.366722326093, 4912.557869791718, 4920.70475771453, 4927.827526036589, 4928.285398617199, 4924.261360795874, 4931.26031095335, 4940.629452791037, 4942.0, 4955.207166853323, 4370.606645401929, 4371.27873900806, 4372.103718737651, 4382.926183085133, 4387.298032314814, 5943.3462319144855, 6095.0, 6126.081087836816, 6159.1069294951985, 6165.290991535986, 5694.430556944314, 5710.886377156457, 5725.971370143165, 5738.311402631711, 5757.337906140806, 6664.266930637626, 6706.83232919176, 6707.1616047994, 6710.396064354231, 6755.594664320157, 4820.276958456225, 4823.107725070873, 4828.73441960692, 4829.782659780319, 4831.0, 7029.718811247544, 7051.929480705178, 7056.576605403692, 7058.505904586681, 7065.0815393998455, 4668.346431499593, 4811.807527698853, 4812.037592481518, 4842.443119041285, 4948.752562371867, 4887.828925987645, 4890.290907818347, 4901.313816065754, 4908.2146856503505, 4916.606671466274, 5661.490465858093, 5663.377028526858, 5667.263255776723, 5668.688222147739, 5677.716114194273, 6752.426043786235, 6795.72817087311, 6820.658967051628, 6824.454043676593, 6832.385085342244, 1716.6395057037912, 1741.6342568060595, 1743.3026789284133, 1746.1182103037884, 1748.2569907789139, 1112.933224006562, 1145.633397312852, 1147.6442302886132, 1158.9362585057734, 1160.442987366069, 6646.036324732887, 6653.767118150841, 6673.065770792093, 6691.59850408977, 6691.631960242136, 6913.3777959983845, 7046.365827075588, 7062.470314726416, 7064.50548461613, 7066.858662826715, 6546.18172728409, 6644.634545099969, 6653.866922661519, 6654.600723956578, 6684.899726504106, 3768.057985503624, 3823.044238940265, 3848.5189351331082, 3853.2100919312034, 3859.05453163971, 7315.963420182971, 7350.669219885118, 7358.889616655647, 7359.411247100219, 7376.7786966391095, 7060.646967651597, 7270.7091716331815, 7292.854142917112, 7295.161056478467, 7314.341709246193, 7366.189719130892, 7391.51955122926, 7401.888971665429, 7416.0729908761405, 7432.479726419207, 7113.6443177839865, 7142.5000249982, 7155.212926578176, 7155.570665759863, 7170.641467926687, 6994.405475534637, 7002.474814389043, 7020.5787652741, 7026.473014523829, 7049.929500705132, 7242.493025488166, 7336.1930187678245, 7352.0, 7354.154272258758, 7403.66683499233, 4954.3311652926395, 4986.451490336132, 4987.351644286219, 5004.299398084198, 5029.396472423333, 6980.057692211486, 7037.859242815218, 7041.50709450354, 7046.647667616701, 7064.717411303696, 6821.454283657196, 6856.9314306856295, 6860.313968603299, 6868.450523958171, 6887.758928437481, 6699.39705426514, 6779.93220067793, 6831.316868313129, 6833.180018397824, 6841.794746157623, 6645.900311495235, 6662.200535935719, 6694.799156025327, 6703.363180497845, 6712.463002959849, 6861.279565645735, 6862.9313706862295, 6873.20958089826, 6879.793606191823, 6895.275996020346, 6640.867182656223, 6646.966765166143, 6680.83297917556, 6703.765368212089, 6718.126643536309], "unit": "score", "name": "prototype.html", "value": 5169.02214234858}, {"lowerIsBetter": false, "replicates": [769.8190925132593, 799.4643588795507, 876.7063033883649, 895.3866601378056, 918.875951746514, 14700.338484768185, 14781.926090369547, 14853.92573037136, 14973.401063957428, 15072.54782356531, 14008.719825603483, 14093.083949543272, 14096.365663545139, 14133.010689251756, 14174.149551026945, 775.0660454152761, 883.2051153961454, 911.7492689510404, 911.9407238529475, 925.9537023148849, 917.4357769971452, 928.5496534180917, 928.9535523223846, 929.2148134826061, 933.2160984772789, 985.7929834734679, 989.1938070472578, 990.0248255468358, 991.0485933503845, 992.9056739609762, 966.3863446711354, 972.9708108756748, 975.512243878061, 975.8048390321928, 977.7164622259536, 728.2426276672254, 732.1653315220652, 732.3591857125016, 732.9670164842595, 735.4484136897327, 15028.09831410119, 15067.623309417242, 15108.244587770567, 15112.773308400383, 15131.394744210218, 13963.162210267417, 13974.580762577038, 13994.650133746738, 14073.71852562943, 14113.223772692498, 10542.0, 10596.576136954513, 10647.84028239577, 10786.298890572167, 10796.514156862882, 3871.845126194949, 3873.2253549290253, 3942.8817135485983, 3947.5855035221175, 3959.0, 54956.90086198293, 55076.72461637706, 55483.722581386835, 55630.72184639051, 55885.161722574194, 11339.886601133883, 11416.257943233662, 11434.771304573862, 11454.599089031844, 11503.827442588368, 19680.40958771239, 19798.307059252857, 19804.40586782399, 19819.30632427858, 19908.70136947947, 8082.676692932335, 8102.230288122619, 8177.1414001530175, 8259.38054645904, 8555.871661925075, 4539.6368290536675, 4601.079784043205, 4620.0, 4710.3405523226775, 4711.128441238382, 3321.551590535295, 3371.898843034713, 3390.796552206826, 3391.0505058583635, 3398.3373242218026, 607.9939200607937, 611.4130434782659, 616.4698359410903, 617.1853153836981, 618.2056057965493, 700.551646945955, 733.6075199768098, 748.9176190619028, 761.1589193940727, 761.8971438855683, 8990.505522196341, 9356.859647105299, 9543.856842147374, 9608.711738647851, 9628.988956159643, 588.7115313496356, 603.9516838652897, 662.4501663619185, 684.0286792754301, 691.0981169573654, 4315.741055536678, 4326.069894972596, 4354.891127721832, 4475.115223481112, 4592.425946756655, 58710.70644646749, 59960.40039599635, 60055.0, 60627.393726062175, 60694.0, 51048.234276485884, 53008.469915301124, 54115.18827217595, 54199.45800542023, 54467.18299225515, 48411.27383089256, 48592.27111593329, 48800.511994880304, 48917.0, 49031.264531032066, 8431.704890328809, 8534.231919127309, 8568.057513673615, 8585.227329540372, 8698.869516957124, 34992.82503587466, 36526.45210321794, 36531.0, 36554.451683224244, 36783.63216367802, 41347.793261033505, 41571.1685766289, 44616.0, 45152.32271515996, 45486.54513454955, 10996.945015274872, 11054.0, 12146.39268036577, 12186.329751863732, 12312.26126432417, 13002.0, 13074.084814062924, 13467.32663366808, 13769.31153442344, 13803.2408217549, 11916.642500724993, 12194.756104877675, 12320.137590368588, 12350.567730129402, 12746.872531274568, 11973.820392693942, 12169.779761637184, 12430.316332601433, 12723.427445765148, 12950.611481655566, 11846.703832404259, 12018.639440816789, 12117.09121815885, 12528.185667931555, 12634.810477842655, 10552.472376381304, 10682.57269709203, 10686.625968091079, 11225.438728063727, 11239.325640461599, 10686.045934818381, 10916.454177291262, 10926.836097458703, 10974.945125274322, 11018.338899665725], "unit": "score", "name": "yui.html", "value": 6869.842440677891}], "name": "dromaeo_css", "value": 7252.68133337865}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 107.0, 113.0, 113.0, 110.0, 122.0, 111.0, 111.0, 110.0, 111.0], "unit": "score", "name": "ai-astar", "value": 111.9}, {"lowerIsBetter": true, "replicates": [159.0, 134.0, 154.0, 165.0, 128.0, 156.0, 153.0, 130.0, 172.0, 127.0], "unit": "score", "name": "audio-beat-detection", "value": 147.8}, {"lowerIsBetter": true, "replicates": [162.0, 163.0, 164.0, 163.0, 163.0, 162.0, 163.0, 164.0, 164.0, 161.0], "unit": "score", "name": "audio-dft", "value": 162.9}, {"lowerIsBetter": true, "replicates": [77.0, 73.0, 75.0, 156.0, 77.0, 75.0, 75.0, 77.0, 162.0, 74.0], "unit": "score", "name": "audio-fft", "value": 92.1}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 141.0, 93.0, 92.0, 92.0, 148.0, 94.0, 93.0, 146.0], "unit": "score", "name": "audio-oscillator", "value": 108.5}, {"lowerIsBetter": true, "replicates": [117.0, 118.0, 117.0, 118.0, 118.0, 117.0, 118.0, 117.0, 117.0, 118.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 117.5}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 147.0, 147.0, 147.0, 146.0, 147.0, 148.0, 147.0, 147.0], "unit": "score", "name": "imaging-darkroom", "value": 146.9}, {"lowerIsBetter": true, "replicates": [96.0, 97.0, 96.0, 96.0, 97.0, 96.0, 96.0, 95.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 96.1}, {"lowerIsBetter": true, "replicates": [54.0, 54.0, 56.0, 56.0, 59.0, 55.0, 59.0, 65.0, 56.0, 55.0], "unit": "score", "name": "json-parse-financial", "value": 56.9}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 65.0, 63.0, 64.0, 64.0, 64.0, 66.0, 64.0, 65.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 64.3}, {"lowerIsBetter": true, "replicates": [79.0, 88.0, 79.0, 78.0, 78.0, 79.0, 79.0, 78.0, 79.0, 78.0], "unit": "score", "name": "stanford-crypto-aes", "value": 79.5}, {"lowerIsBetter": true, "replicates": [129.0, 115.0, 114.0, 112.0, 116.0, 113.0, 121.0, 124.0, 114.0, 118.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 117.6}, {"lowerIsBetter": true, "replicates": [134.0, 146.0, 145.0, 151.0, 147.0, 153.0, 139.0, 147.0, 145.0, 146.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 145.3}, {"lowerIsBetter": true, "replicates": [66.0, 66.0, 66.0, 65.0, 71.0, 66.0, 67.0, 65.0, 67.0, 70.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.9}], "name": "kraken", "value": 1514.2}]} 18:31:42 INFO - Return code: 0 18:31:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:31:42 ERROR - # TBPL SUCCESS # 18:31:42 INFO - Running post-action listener: _resource_record_post_action 18:31:42 INFO - Running post-run listener: _resource_record_post_run 18:31:43 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 496910336; Read time: 16; Write time: 471224 18:31:43 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 131284992; Read time: 0; Write time: 201996 18:31:43 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 4096; Write bytes: 254640128; Read time: 16; Write time: 183592 18:31:43 INFO - Running post-run listener: _upload_blobber_files 18:31:43 INFO - Blob upload gear active. 18:31:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:31:43 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:31:43 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:31:43 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:31:43 INFO - (blobuploader) - INFO - Open directory for files ... 18:31:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:31:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:31:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:31:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:31:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:31:44 INFO - (blobuploader) - INFO - Done attempting. 18:31:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:31:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:31:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:31:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:31:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:31:45 INFO - (blobuploader) - INFO - Done attempting. 18:31:45 INFO - (blobuploader) - INFO - Iteration through files over. 18:31:45 INFO - Return code: 0 18:31:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:31:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:31:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/605333eca1a72a3d18f77f5f5ee9dbc2da922f4eb8353b204d2c3367ff49bf5b983c428c9209d837cb797f45dae979dd5d1eead0992e65a1a4cdc2f96d579e81", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7c6fb14d8f8d889e37d891909c9bf8c6c3d03c174648997aa6d51aa52b78cb5e069f6b578e66d5e31a15a48bded7a13f1a9fe4672938cedf15c1bb3a1a72b773"} 18:31:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:31:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:31:45 INFO - Contents: 18:31:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/605333eca1a72a3d18f77f5f5ee9dbc2da922f4eb8353b204d2c3367ff49bf5b983c428c9209d837cb797f45dae979dd5d1eead0992e65a1a4cdc2f96d579e81", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7c6fb14d8f8d889e37d891909c9bf8c6c3d03c174648997aa6d51aa52b78cb5e069f6b578e66d5e31a15a48bded7a13f1a9fe4672938cedf15c1bb3a1a72b773"} 18:31:45 INFO - Running post-run listener: copy_logs_to_upload_dir 18:31:45 INFO - Copying logs to upload dir... 18:31:45 INFO - mkdir: /builds/slave/test/build/upload/logs 18:31:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.712116 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-04-21 18:31:45.375066) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-21 18:31:45.378529) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1461285522.406794-378324586 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/605333eca1a72a3d18f77f5f5ee9dbc2da922f4eb8353b204d2c3367ff49bf5b983c428c9209d837cb797f45dae979dd5d1eead0992e65a1a4cdc2f96d579e81", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7c6fb14d8f8d889e37d891909c9bf8c6c3d03c174648997aa6d51aa52b78cb5e069f6b578e66d5e31a15a48bded7a13f1a9fe4672938cedf15c1bb3a1a72b773"} build_url:https://queue.taskcluster.net/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011264 build_url: 'https://queue.taskcluster.net/v1/task/VCvjgGkRQkCCN8vEinnOCQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/605333eca1a72a3d18f77f5f5ee9dbc2da922f4eb8353b204d2c3367ff49bf5b983c428c9209d837cb797f45dae979dd5d1eead0992e65a1a4cdc2f96d579e81", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7c6fb14d8f8d889e37d891909c9bf8c6c3d03c174648997aa6d51aa52b78cb5e069f6b578e66d5e31a15a48bded7a13f1a9fe4672938cedf15c1bb3a1a72b773"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-21 18:31:45.404102) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 18:31:45.404571) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1461285522.406794-378324586 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004409 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 18:31:45.487920) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-21 18:31:45.488655) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-21 18:31:45.489473) ========= ========= Total master_lag: 0.17 =========