builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-031 starttime: 1461633340.88 results: success (0) buildid: 20160425171313 builduid: 322e28ed6f0a4eb7be79f0296b909785 revision: cfc7ebe592937ad937cebe04fdad4213eee72fae ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 18:15:40.878621) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 18:15:40.879345) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 18:15:40.879631) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 18:15:40.896183) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 18:15:40.896516) ========= 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=db0483c52d987ecb441a263b000001ad-1461629914.105575-1098230120 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-25 18:15:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2016-04-25 18:15:41 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.077762 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 18:15:40.987217) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 18:15:40.987494) ========= 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=db0483c52d987ecb441a263b000001ad-1461629914.105575-1098230120 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019767 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 18:15:41.038646) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 18:15:41.039028) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cfc7ebe592937ad937cebe04fdad4213eee72fae --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev cfc7ebe592937ad937cebe04fdad4213eee72fae --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=db0483c52d987ecb441a263b000001ad-1461629914.105575-1098230120 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-25 18:15:41,294 truncating revision to first 12 chars 2016-04-25 18:15:41,294 Setting DEBUG logging. 2016-04-25 18:15:41,294 attempt 1/10 2016-04-25 18:15:41,294 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cfc7ebe59293?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-25 18:15:41,559 unpacking tar archive at: mozilla-central-cfc7ebe59293/testing/mozharness/ program finished with exit code 0 elapsedTime=0.546651 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 18:15:41.599541) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 18:15:41.599898) ========= script_repo_revision: cfc7ebe592937ad937cebe04fdad4213eee72fae ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 18:15:41.600322) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 18:15:41.600592) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 18:15:41.612954) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-04-25 18:15:41.613232) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1461629914.105575-1098230120 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:15:41 INFO - MultiFileLogger online at 20160425 18:15:41 in /builds/slave/test 18:15:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 18:15:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:15:41 INFO - {'append_to_log': False, 18:15:41 INFO - 'base_work_dir': '/builds/slave/test', 18:15:41 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:15:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:15:41 INFO - 'branch': 'Firefox-Non-PGO', 18:15:41 INFO - 'buildbot_json_path': 'buildprops.json', 18:15:41 INFO - 'config_files': ('talos/linux_config.py',), 18:15:41 INFO - 'default_actions': ('clobber', 18:15:41 INFO - 'read-buildbot-config', 18:15:41 INFO - 'download-and-extract', 18:15:41 INFO - 'populate-webroot', 18:15:41 INFO - 'create-virtualenv', 18:15:41 INFO - 'install', 18:15:41 INFO - 'run-tests'), 18:15:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:15:41 INFO - 'download_minidump_stackwalk': True, 18:15:41 INFO - 'download_symbols': 'ondemand', 18:15:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:15:41 INFO - 'tooltool.py': '/tools/tooltool.py', 18:15:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:15:41 INFO - '/tools/misc-python/virtualenv.py')}, 18:15:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:15:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:15:41 INFO - 'installer_path': 'installer.exe', 18:15:41 INFO - 'log_level': 'info', 18:15:41 INFO - 'log_name': 'talos', 18:15:41 INFO - 'log_to_console': True, 18:15:41 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:15:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:15:41 INFO - 'opt_config_files': (), 18:15:41 INFO - 'pip_index': False, 18:15:41 INFO - 'sps_profile': False, 18:15:41 INFO - 'sps_profile_interval': 0, 18:15:41 INFO - 'suite': 'dromaeojs', 18:15:41 INFO - 'system_bits': '32', 18:15:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:15:41 INFO - 'title': 'talos-linux64-ix-031', 18:15:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:15:41 INFO - 'use_talos_json': True, 18:15:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:15:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:15:41 INFO - 'work_dir': 'build'} 18:15:41 INFO - ##### 18:15:41 INFO - ##### Running clobber step. 18:15:41 INFO - ##### 18:15:41 INFO - Running pre-action listener: _resource_record_pre_action 18:15:41 INFO - Running main action method: clobber 18:15:41 INFO - rmtree: /builds/slave/test/build 18:15:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:15:42 INFO - Running post-action listener: _resource_record_post_action 18:15:42 INFO - ##### 18:15:42 INFO - ##### Running read-buildbot-config step. 18:15:42 INFO - ##### 18:15:42 INFO - Running pre-action listener: _resource_record_pre_action 18:15:42 INFO - Running main action method: read_buildbot_config 18:15:42 INFO - Using buildbot properties: 18:15:42 INFO - { 18:15:42 INFO - "project": "", 18:15:42 INFO - "product": "firefox", 18:15:42 INFO - "script_repo_revision": "production", 18:15:42 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 18:15:42 INFO - "repository": "", 18:15:42 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 18:15:42 INFO - "buildid": "20160425171313", 18:15:42 INFO - "pgo_build": "False", 18:15:42 INFO - "basedir": "/builds/slave/test", 18:15:42 INFO - "buildnumber": 557, 18:15:42 INFO - "slavename": "talos-linux64-ix-031", 18:15:42 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:15:42 INFO - "platform": "ubuntu64_hw", 18:15:42 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 18:15:42 INFO - "branch": "mozilla-central", 18:15:42 INFO - "repo_path": "mozilla-central", 18:15:42 INFO - "revision": "cfc7ebe592937ad937cebe04fdad4213eee72fae", 18:15:42 INFO - "stage_platform": "linux64", 18:15:42 INFO - "builduid": "322e28ed6f0a4eb7be79f0296b909785", 18:15:42 INFO - "slavebuilddir": "test" 18:15:42 INFO - } 18:15:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 18:15:42 INFO - Running post-action listener: _resource_record_post_action 18:15:42 INFO - ##### 18:15:42 INFO - ##### Running download-and-extract step. 18:15:42 INFO - ##### 18:15:42 INFO - Running pre-action listener: _resource_record_pre_action 18:15:42 INFO - Running main action method: download_and_extract 18:15:42 INFO - mkdir: /builds/slave/test/build/tests 18:15:42 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:15:42 INFO - https://queue.taskcluster.net/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:15:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 18:15:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 18:15:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 18:15:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:15:47 INFO - Downloaded 1361 bytes. 18:15:47 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 18:15:47 INFO - Using the following test package requirements: 18:15:47 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 18:15:47 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:15:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:15:47 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:15:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:15:47 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:15:47 INFO - u'jsshell-linux-x86_64.zip'], 18:15:47 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:15:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:15:47 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 18:15:47 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:15:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:15:47 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:15:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:15:47 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:15:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:15:47 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 18:15:47 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:15:47 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:15:47 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:15:47 INFO - https://queue.taskcluster.net/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:15:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:15:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:15:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:15:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:15:50 INFO - Downloaded 22289915 bytes. 18:15:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:15:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:15:51 INFO - Return code: 0 18:15:51 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:15:51 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:15:51 INFO - https://queue.taskcluster.net/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:15:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:15:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:15:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 18:15:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:15:53 INFO - Downloaded 22289915 bytes. 18:15:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:15:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:15:54 INFO - Return code: 0 18:15:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:15:54 INFO - https://queue.taskcluster.net/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:15:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 18:15:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 18:15:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 18:15:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:15:55 INFO - Downloaded 10840145 bytes. 18:15:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:15:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 18:15:56 INFO - Return code: 0 18:15:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:15:56 INFO - https://queue.taskcluster.net/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:15:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:15:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:15:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:15:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:15:58 INFO - Downloaded 54815348 bytes. 18:15:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:15:58 INFO - mkdir: /builds/slave/test/properties 18:15:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:15:58 INFO - Writing to file /builds/slave/test/properties/build_url 18:15:58 INFO - Contents: 18:15:58 INFO - build_url:https://queue.taskcluster.net/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 18:15:59 INFO - Running post-action listener: _resource_record_post_action 18:15:59 INFO - Running post-action listener: set_extra_try_arguments 18:15:59 INFO - ##### 18:15:59 INFO - ##### Running populate-webroot step. 18:15:59 INFO - ##### 18:15:59 INFO - Running pre-action listener: _resource_record_pre_action 18:15:59 INFO - Running main action method: populate_webroot 18:15:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:15:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:15:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:15:59 INFO - '--tppagecycles', 18:15:59 INFO - '7'], 18:15:59 INFO - 'tests': ['tsvgm']}}, 18:15:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:15:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:15:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:15:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:15:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:15:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:15:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:15:59 INFO - 'talos_options': ['--disable-e10s'], 18:15:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:15:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:15:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:15:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:15:59 INFO - 'talos_options': ['--disable-e10s'], 18:15:59 INFO - 'tests': ['damp', 'tps']}, 18:15:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:15:59 INFO - 'tests': ['damp', 'tps']}, 18:15:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:15:59 INFO - 'tests': ['dromaeo_dom']}, 18:15:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:15:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:15:59 INFO - 'tests': ['a11yr', 18:15:59 INFO - 'ts_paint', 18:15:59 INFO - 'tpaint', 18:15:59 INFO - 'sessionrestore', 18:15:59 INFO - 'sessionrestore_no_auto_restore', 18:15:59 INFO - 'tabpaint']}, 18:15:59 INFO - 'other-e10s': {'tests': ['a11yr', 18:15:59 INFO - 'ts_paint', 18:15:59 INFO - 'tpaint', 18:15:59 INFO - 'sessionrestore', 18:15:59 INFO - 'sessionrestore_no_auto_restore', 18:15:59 INFO - 'tabpaint']}, 18:15:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:15:59 INFO - 'ts_paint', 18:15:59 INFO - 'tpaint', 18:15:59 INFO - 'sessionrestore', 18:15:59 INFO - 'sessionrestore_no_auto_restore', 18:15:59 INFO - 'tabpaint']}, 18:15:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:15:59 INFO - 'ts_paint', 18:15:59 INFO - 'tpaint', 18:15:59 INFO - 'sessionrestore', 18:15:59 INFO - 'sessionrestore_no_auto_restore', 18:15:59 INFO - 'tabpaint']}, 18:15:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:15:59 INFO - 'tests': ['a11yr', 18:15:59 INFO - 'ts_paint', 18:15:59 INFO - 'tpaint', 18:15:59 INFO - 'sessionrestore', 18:15:59 INFO - 'sessionrestore_no_auto_restore', 18:15:59 INFO - 'tabpaint']}, 18:15:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:15:59 INFO - 'tests': ['a11yr', 18:15:59 INFO - 'ts_paint', 18:15:59 INFO - 'tpaint', 18:15:59 INFO - 'sessionrestore', 18:15:59 INFO - 'sessionrestore_no_auto_restore', 18:15:59 INFO - 'tabpaint']}, 18:15:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:15:59 INFO - 'tests': ['tsvgx', 18:15:59 INFO - 'tsvgr_opacity', 18:15:59 INFO - 'tart', 18:15:59 INFO - 'tscrollx', 18:15:59 INFO - 'cart']}, 18:15:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:15:59 INFO - 'tsvgr_opacity', 18:15:59 INFO - 'tart', 18:15:59 INFO - 'tscrollx', 18:15:59 INFO - 'cart']}, 18:15:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:15:59 INFO - 'talos_options': ['--disable-e10s'], 18:15:59 INFO - 'tests': ['tp5o']}, 18:15:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:15:59 INFO - 'tests': ['tp5o']}, 18:15:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:15:59 INFO - 'talos_options': ['--disable-e10s', 18:15:59 INFO - '--xperf_path', 18:15:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:15:59 INFO - 'tests': ['tp5n']}, 18:15:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:15:59 INFO - 'talos_options': ['--xperf_path', 18:15:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:15:59 INFO - 'tests': ['tp5n']}}, 18:15:59 INFO - 'talos.zip': {'path': '', 18:15:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:15:59 INFO - Running post-action listener: _resource_record_post_action 18:15:59 INFO - ##### 18:15:59 INFO - ##### Running create-virtualenv step. 18:15:59 INFO - ##### 18:15:59 INFO - Running pre-action listener: _resource_record_pre_action 18:15:59 INFO - Running main action method: create_virtualenv 18:15:59 INFO - Creating virtualenv /builds/slave/test/build/venv 18:15:59 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:15:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:15:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:15:59 INFO - Using real prefix '/usr' 18:15:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:16:00 INFO - Installing distribute.............................................................................................................................................................................................done. 18:16:01 INFO - Installing pip.................done. 18:16:02 INFO - Return code: 0 18:16:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:16:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:16:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:16:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:02 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 0x7f2cad2d6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x209c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21675a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f6410>, '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 0x21f7310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f77a0>, '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': 'db0483c52d987ecb441a263b000001ad-1461629914.105575-1098230120', '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:16:02 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:16:02 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:16:02 INFO - Using env: {'DISPLAY': ':0', 18:16:02 INFO - 'HOME': '/home/cltbld', 18:16:02 INFO - 'LANG': 'en_US.UTF-8', 18:16:02 INFO - 'LANGUAGE': 'en_US:en', 18:16:02 INFO - 'LOGNAME': 'cltbld', 18:16:02 INFO - 'MAIL': '/var/mail/cltbld', 18:16:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:02 INFO - 'MOZ_NO_REMOTE': '1', 18:16:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:02 INFO - 'NO_EM_RESTART': '1', 18:16:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:02 INFO - 'PWD': '/builds/slave/test', 18:16:02 INFO - 'SHELL': '/bin/bash', 18:16:02 INFO - 'SHLVL': '1', 18:16:02 INFO - 'TERM': 'linux', 18:16:02 INFO - 'TMOUT': '86400', 18:16:02 INFO - 'USER': 'cltbld', 18:16:02 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1461629914.105575-1098230120', 18:16:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:02 INFO - '_': '/tools/buildbot/bin/python'} 18:16:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:02 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:16:02 INFO - Cleaning up... 18:16:02 INFO - Return code: 0 18:16:02 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:16:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:16:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:16:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:02 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 0x7f2cad2d6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x209c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21675a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f6410>, '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 0x21f7310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f77a0>, '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': 'db0483c52d987ecb441a263b000001ad-1461629914.105575-1098230120', '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:16:02 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:16:02 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:16:02 INFO - Using env: {'DISPLAY': ':0', 18:16:02 INFO - 'HOME': '/home/cltbld', 18:16:02 INFO - 'LANG': 'en_US.UTF-8', 18:16:02 INFO - 'LANGUAGE': 'en_US:en', 18:16:02 INFO - 'LOGNAME': 'cltbld', 18:16:02 INFO - 'MAIL': '/var/mail/cltbld', 18:16:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:02 INFO - 'MOZ_NO_REMOTE': '1', 18:16:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:02 INFO - 'NO_EM_RESTART': '1', 18:16:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:02 INFO - 'PWD': '/builds/slave/test', 18:16:02 INFO - 'SHELL': '/bin/bash', 18:16:02 INFO - 'SHLVL': '1', 18:16:02 INFO - 'TERM': 'linux', 18:16:02 INFO - 'TMOUT': '86400', 18:16:02 INFO - 'USER': 'cltbld', 18:16:02 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1461629914.105575-1098230120', 18:16:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:02 INFO - '_': '/tools/buildbot/bin/python'} 18:16:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:02 INFO - Downloading/unpacking psutil>=0.7.1 18:16:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:16:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:16:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:16:04 INFO - Installing collected packages: psutil 18:16:04 INFO - Running setup.py install for psutil 18:16:04 INFO - building 'psutil._psutil_linux' extension 18:16:04 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:16:04 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:16:04 INFO - building 'psutil._psutil_posix' extension 18:16:04 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:16:04 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:16:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:16:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:16:04 INFO - Successfully installed psutil 18:16:04 INFO - Cleaning up... 18:16:04 INFO - Return code: 0 18:16:04 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:16:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:16:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:04 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 0x7f2cad2d6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x209c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21675a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f6410>, '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 0x21f7310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f77a0>, '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': 'db0483c52d987ecb441a263b000001ad-1461629914.105575-1098230120', '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:16:04 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:16:04 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:16:04 INFO - Using env: {'DISPLAY': ':0', 18:16:04 INFO - 'HOME': '/home/cltbld', 18:16:04 INFO - 'LANG': 'en_US.UTF-8', 18:16:04 INFO - 'LANGUAGE': 'en_US:en', 18:16:04 INFO - 'LOGNAME': 'cltbld', 18:16:04 INFO - 'MAIL': '/var/mail/cltbld', 18:16:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:04 INFO - 'MOZ_NO_REMOTE': '1', 18:16:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:04 INFO - 'NO_EM_RESTART': '1', 18:16:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:04 INFO - 'PWD': '/builds/slave/test', 18:16:04 INFO - 'SHELL': '/bin/bash', 18:16:04 INFO - 'SHLVL': '1', 18:16:04 INFO - 'TERM': 'linux', 18:16:04 INFO - 'TMOUT': '86400', 18:16:04 INFO - 'USER': 'cltbld', 18:16:04 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1461629914.105575-1098230120', 18:16:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:04 INFO - '_': '/tools/buildbot/bin/python'} 18:16:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:05 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:07 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:16:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:16:07 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:16:07 INFO - Installing collected packages: mozsystemmonitor 18:16:07 INFO - Running setup.py install for mozsystemmonitor 18:16:07 INFO - Successfully installed mozsystemmonitor 18:16:07 INFO - Cleaning up... 18:16:07 INFO - Return code: 0 18:16:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:16:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f2cad2d6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x209c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21675a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f6410>, '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 0x21f7310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f77a0>, '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': 'db0483c52d987ecb441a263b000001ad-1461629914.105575-1098230120', '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:16:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:16:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:16:07 INFO - Using env: {'DISPLAY': ':0', 18:16:07 INFO - 'HOME': '/home/cltbld', 18:16:07 INFO - 'LANG': 'en_US.UTF-8', 18:16:07 INFO - 'LANGUAGE': 'en_US:en', 18:16:07 INFO - 'LOGNAME': 'cltbld', 18:16:07 INFO - 'MAIL': '/var/mail/cltbld', 18:16:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:07 INFO - 'MOZ_NO_REMOTE': '1', 18:16:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:07 INFO - 'NO_EM_RESTART': '1', 18:16:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:07 INFO - 'PWD': '/builds/slave/test', 18:16:07 INFO - 'SHELL': '/bin/bash', 18:16:07 INFO - 'SHLVL': '1', 18:16:07 INFO - 'TERM': 'linux', 18:16:07 INFO - 'TMOUT': '86400', 18:16:07 INFO - 'USER': 'cltbld', 18:16:07 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1461629914.105575-1098230120', 18:16:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:07 INFO - '_': '/tools/buildbot/bin/python'} 18:16:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:07 INFO - Downloading/unpacking blobuploader==1.2.4 18:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:09 INFO - Downloading blobuploader-1.2.4.tar.gz 18:16:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:16:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:16:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:10 INFO - Downloading docopt-0.6.1.tar.gz 18:16:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:16:10 INFO - Installing collected packages: blobuploader, requests, docopt 18:16:10 INFO - Running setup.py install for blobuploader 18:16:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:16:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:16:10 INFO - Running setup.py install for requests 18:16:10 INFO - Running setup.py install for docopt 18:16:10 INFO - Successfully installed blobuploader requests docopt 18:16:10 INFO - Cleaning up... 18:16:10 INFO - Return code: 0 18:16:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:16:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:16:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:16:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:10 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 0x7f2cad2d6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x209c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21675a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f6410>, '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 0x21f7310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f77a0>, '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': 'db0483c52d987ecb441a263b000001ad-1461629914.105575-1098230120', '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:16:10 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:16:10 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:16:10 INFO - Using env: {'DISPLAY': ':0', 18:16:10 INFO - 'HOME': '/home/cltbld', 18:16:10 INFO - 'LANG': 'en_US.UTF-8', 18:16:10 INFO - 'LANGUAGE': 'en_US:en', 18:16:10 INFO - 'LOGNAME': 'cltbld', 18:16:10 INFO - 'MAIL': '/var/mail/cltbld', 18:16:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:10 INFO - 'MOZ_NO_REMOTE': '1', 18:16:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:10 INFO - 'NO_EM_RESTART': '1', 18:16:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:10 INFO - 'PWD': '/builds/slave/test', 18:16:10 INFO - 'SHELL': '/bin/bash', 18:16:10 INFO - 'SHLVL': '1', 18:16:10 INFO - 'TERM': 'linux', 18:16:10 INFO - 'TMOUT': '86400', 18:16:10 INFO - 'USER': 'cltbld', 18:16:10 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1461629914.105575-1098230120', 18:16:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:10 INFO - '_': '/tools/buildbot/bin/python'} 18:16:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:16:10 INFO - Running setup.py (path:/tmp/pip-8qVw9c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:16:11 INFO - Running setup.py (path:/tmp/pip-DqGKUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:16:11 INFO - Running setup.py (path:/tmp/pip-E0Q2k7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:16:11 INFO - Running setup.py (path:/tmp/pip-eqWsfS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:16:11 INFO - Running setup.py (path:/tmp/pip-6FLjkK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:16:11 INFO - Running setup.py (path:/tmp/pip-PFm9gt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:16:11 INFO - Running setup.py (path:/tmp/pip-c0QxnS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:16:11 INFO - Running setup.py (path:/tmp/pip-DyvD9_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:16:11 INFO - Running setup.py (path:/tmp/pip-xAbbgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:16:11 INFO - Running setup.py (path:/tmp/pip-a2Iztj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:16:11 INFO - Running setup.py (path:/tmp/pip-3tWQzH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:16:11 INFO - Running setup.py (path:/tmp/pip-pLuCP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:16:11 INFO - Running setup.py (path:/tmp/pip-KxE0AK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:16:11 INFO - Running setup.py (path:/tmp/pip-iXl5Nc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:16:11 INFO - Running setup.py (path:/tmp/pip-24a5wm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:16:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:16:12 INFO - Running setup.py (path:/tmp/pip-ghUbR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:16:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:16:12 INFO - Running setup.py (path:/tmp/pip-Avc2wy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:16:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:16:12 INFO - Running setup.py install for manifestparser 18:16:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:16:12 INFO - Running setup.py install for mozcrash 18:16:12 INFO - Running setup.py install for mozdebug 18:16:12 INFO - Running setup.py install for mozdevice 18:16:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:16:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:16:12 INFO - Running setup.py install for mozfile 18:16:12 INFO - Running setup.py install for mozhttpd 18:16:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:16:12 INFO - Running setup.py install for mozinfo 18:16:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:16:12 INFO - Running setup.py install for mozInstall 18:16:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:16:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:16:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:16:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:16:12 INFO - Running setup.py install for mozleak 18:16:13 INFO - Running setup.py install for mozlog 18:16:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:16:13 INFO - Running setup.py install for moznetwork 18:16:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:16:13 INFO - Running setup.py install for mozprocess 18:16:13 INFO - Running setup.py install for mozprofile 18:16:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:16:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:16:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:16:13 INFO - Running setup.py install for mozrunner 18:16:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:16:13 INFO - Running setup.py install for mozscreenshot 18:16:13 INFO - Running setup.py install for moztest 18:16:13 INFO - Running setup.py install for mozversion 18:16:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:16:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:16:13 INFO - Cleaning up... 18:16:13 INFO - Return code: 0 18:16:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:16: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:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16: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:16:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:13 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 0x7f2cad2d6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x209c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21675a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f6410>, '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 0x21f7310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f77a0>, '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': 'db0483c52d987ecb441a263b000001ad-1461629914.105575-1098230120', '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:16:13 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:16:13 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:16:13 INFO - Using env: {'DISPLAY': ':0', 18:16:13 INFO - 'HOME': '/home/cltbld', 18:16:13 INFO - 'LANG': 'en_US.UTF-8', 18:16:13 INFO - 'LANGUAGE': 'en_US:en', 18:16:13 INFO - 'LOGNAME': 'cltbld', 18:16:13 INFO - 'MAIL': '/var/mail/cltbld', 18:16:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:13 INFO - 'MOZ_NO_REMOTE': '1', 18:16:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:13 INFO - 'NO_EM_RESTART': '1', 18:16:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:13 INFO - 'PWD': '/builds/slave/test', 18:16:13 INFO - 'SHELL': '/bin/bash', 18:16:13 INFO - 'SHLVL': '1', 18:16:13 INFO - 'TERM': 'linux', 18:16:13 INFO - 'TMOUT': '86400', 18:16:13 INFO - 'USER': 'cltbld', 18:16:13 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1461629914.105575-1098230120', 18:16:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:13 INFO - '_': '/tools/buildbot/bin/python'} 18:16:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:16:14 INFO - Running setup.py (path:/tmp/pip-d4lqkZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:16:14 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:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:16:14 INFO - Running setup.py (path:/tmp/pip-yVWd72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:16:14 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:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:16:14 INFO - Running setup.py (path:/tmp/pip-dWbYLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:16:14 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:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:16:14 INFO - Running setup.py (path:/tmp/pip-MZrdp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:16:14 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:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:16:14 INFO - Running setup.py (path:/tmp/pip-N6sFzb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:16:14 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:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:16:14 INFO - Running setup.py (path:/tmp/pip-DWlXwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:16:14 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:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:16:14 INFO - Running setup.py (path:/tmp/pip-kgvqP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:16:14 INFO - Running setup.py (path:/tmp/pip-eYrGqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:16:14 INFO - Running setup.py (path:/tmp/pip-monYyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:16:14 INFO - Running setup.py (path:/tmp/pip-aFq_zP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:16:14 INFO - Running setup.py (path:/tmp/pip-Furi9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:16:14 INFO - Running setup.py (path:/tmp/pip-Ykknat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:16:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:16:14 INFO - Running setup.py (path:/tmp/pip-ENjDkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:16:15 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:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:16:15 INFO - Running setup.py (path:/tmp/pip-MlBs22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:16:15 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:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:16:15 INFO - Running setup.py (path:/tmp/pip-DtPcYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:16:15 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:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:16:15 INFO - Running setup.py (path:/tmp/pip-lRIbIh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:16:15 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:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:16:15 INFO - Running setup.py (path:/tmp/pip-zehLWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:16:15 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:16:15 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:16:15 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:16:15 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:16:15 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:16:15 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:16:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:16:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:17 INFO - Downloading blessings-1.6.tar.gz 18:16:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:16:17 INFO - Installing collected packages: blessings 18:16:17 INFO - Running setup.py install for blessings 18:16:17 INFO - Successfully installed blessings 18:16:17 INFO - Cleaning up... 18:16:17 INFO - Return code: 0 18:16:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:16:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:16:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:16:17 INFO - Reading from file tmpfile_stdout 18:16:17 INFO - Current package versions: 18:16:17 INFO - argparse == 1.2.1 18:16:17 INFO - blessings == 1.6 18:16:17 INFO - blobuploader == 1.2.4 18:16:17 INFO - docopt == 0.6.1 18:16:17 INFO - manifestparser == 1.1 18:16:17 INFO - mozInstall == 1.12 18:16:17 INFO - mozcrash == 0.17 18:16:17 INFO - mozdebug == 0.1 18:16:17 INFO - mozdevice == 0.48 18:16:17 INFO - mozfile == 1.2 18:16:17 INFO - mozhttpd == 0.7 18:16:17 INFO - mozinfo == 0.9 18:16:17 INFO - mozleak == 0.1 18:16:17 INFO - mozlog == 3.1 18:16:17 INFO - moznetwork == 0.27 18:16:17 INFO - mozprocess == 0.22 18:16:17 INFO - mozprofile == 0.28 18:16:17 INFO - mozrunner == 6.11 18:16:17 INFO - mozscreenshot == 0.1 18:16:17 INFO - mozsystemmonitor == 0.0 18:16:17 INFO - moztest == 0.7 18:16:17 INFO - mozversion == 1.4 18:16:17 INFO - psutil == 3.1.1 18:16:17 INFO - requests == 1.2.3 18:16:17 INFO - wsgiref == 0.1.2 18:16:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:16:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:16:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:17 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 0x7f2cad2d6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x209c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21675a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f6410>, '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 0x21f7310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f77a0>, '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': 'db0483c52d987ecb441a263b000001ad-1461629914.105575-1098230120', '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:16:17 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:16:17 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:16:17 INFO - Using env: {'DISPLAY': ':0', 18:16:17 INFO - 'HOME': '/home/cltbld', 18:16:17 INFO - 'LANG': 'en_US.UTF-8', 18:16:17 INFO - 'LANGUAGE': 'en_US:en', 18:16:17 INFO - 'LOGNAME': 'cltbld', 18:16:17 INFO - 'MAIL': '/var/mail/cltbld', 18:16:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:17 INFO - 'MOZ_NO_REMOTE': '1', 18:16:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:17 INFO - 'NO_EM_RESTART': '1', 18:16:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:17 INFO - 'PWD': '/builds/slave/test', 18:16:17 INFO - 'SHELL': '/bin/bash', 18:16:17 INFO - 'SHLVL': '1', 18:16:17 INFO - 'TERM': 'linux', 18:16:17 INFO - 'TMOUT': '86400', 18:16:17 INFO - 'USER': 'cltbld', 18:16:17 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1461629914.105575-1098230120', 18:16:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:17 INFO - '_': '/tools/buildbot/bin/python'} 18:16:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:17 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:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:16:17 INFO - Cleaning up... 18:16:17 INFO - Return code: 0 18:16:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:16:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:16:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:17 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 0x7f2cad2d6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x209c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21675a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f6410>, '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 0x21f7310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f77a0>, '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': 'db0483c52d987ecb441a263b000001ad-1461629914.105575-1098230120', '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:16:17 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:16:17 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:16:17 INFO - Using env: {'DISPLAY': ':0', 18:16:17 INFO - 'HOME': '/home/cltbld', 18:16:17 INFO - 'LANG': 'en_US.UTF-8', 18:16:17 INFO - 'LANGUAGE': 'en_US:en', 18:16:17 INFO - 'LOGNAME': 'cltbld', 18:16:17 INFO - 'MAIL': '/var/mail/cltbld', 18:16:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:17 INFO - 'MOZ_NO_REMOTE': '1', 18:16:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:17 INFO - 'NO_EM_RESTART': '1', 18:16:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:17 INFO - 'PWD': '/builds/slave/test', 18:16:17 INFO - 'SHELL': '/bin/bash', 18:16:17 INFO - 'SHLVL': '1', 18:16:17 INFO - 'TERM': 'linux', 18:16:17 INFO - 'TMOUT': '86400', 18:16:17 INFO - 'USER': 'cltbld', 18:16:17 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1461629914.105575-1098230120', 18:16:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:17 INFO - '_': '/tools/buildbot/bin/python'} 18:16:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:18 INFO - Downloading/unpacking jsonschema 18:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:16:20 INFO - Downloading/unpacking functools32 (from jsonschema) 18:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:20 INFO - Downloading functools32-3.2.3-2.tar.gz 18:16:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:16:20 INFO - warning: no files found matching '*.txt' 18:16:20 INFO - no previously-included directories found matching 'build' 18:16:20 INFO - no previously-included directories found matching 'dist' 18:16:20 INFO - no previously-included directories found matching '.git*' 18:16:20 INFO - Installing collected packages: jsonschema, functools32 18:16:20 INFO - Running setup.py install for functools32 18:16:20 INFO - warning: no files found matching '*.txt' 18:16:20 INFO - no previously-included directories found matching 'build' 18:16:20 INFO - no previously-included directories found matching 'dist' 18:16:20 INFO - no previously-included directories found matching '.git*' 18:16:20 INFO - Successfully installed jsonschema functools32 18:16:20 INFO - Cleaning up... 18:16:20 INFO - Return code: 0 18:16:20 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:16:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:16:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:16:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:16:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:16:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2cad2d6f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x209c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21675a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21f6410>, '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 0x21f7310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f77a0>, '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': 'db0483c52d987ecb441a263b000001ad-1461629914.105575-1098230120', '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:16:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 18:16:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 18:16:20 INFO - Using env: {'DISPLAY': ':0', 18:16:20 INFO - 'HOME': '/home/cltbld', 18:16:20 INFO - 'LANG': 'en_US.UTF-8', 18:16:20 INFO - 'LANGUAGE': 'en_US:en', 18:16:20 INFO - 'LOGNAME': 'cltbld', 18:16:20 INFO - 'MAIL': '/var/mail/cltbld', 18:16:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:20 INFO - 'MOZ_NO_REMOTE': '1', 18:16:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:20 INFO - 'NO_EM_RESTART': '1', 18:16:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:20 INFO - 'PWD': '/builds/slave/test', 18:16:20 INFO - 'SHELL': '/bin/bash', 18:16:20 INFO - 'SHLVL': '1', 18:16:20 INFO - 'TERM': 'linux', 18:16:20 INFO - 'TMOUT': '86400', 18:16:20 INFO - 'USER': 'cltbld', 18:16:20 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1461629914.105575-1098230120', 18:16:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:20 INFO - '_': '/tools/buildbot/bin/python'} 18:16:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:16:20 INFO - Downloading/unpacking flake8 18:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:22 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:16:22 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:22 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:16:22 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:23 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:16:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:16:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:16:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:16:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:16:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:16:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:16:23 INFO - no previously-included directories found matching 'docs/_build' 18:16:23 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:16:23 INFO - Running setup.py install for pep8 18:16:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:16:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:16:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:16:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:16:23 INFO - no previously-included directories found matching 'docs/_build' 18:16:23 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:16:23 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:16:23 INFO - Cleaning up... 18:16:23 INFO - Return code: 0 18:16:23 INFO - Running post-action listener: _resource_record_post_action 18:16:23 INFO - Running post-action listener: _start_resource_monitoring 18:16:23 INFO - Starting resource monitoring. 18:16:23 INFO - ##### 18:16:23 INFO - ##### Running install step. 18:16:23 INFO - ##### 18:16:23 INFO - Running pre-action listener: _resource_record_pre_action 18:16:23 INFO - Running main action method: install 18:16:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:16:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:16:23 INFO - Reading from file tmpfile_stdout 18:16:23 INFO - Detecting whether we're running mozinstall >=1.0... 18:16:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:16:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:16:23 INFO - Reading from file tmpfile_stdout 18:16:23 INFO - Output received: 18:16:23 INFO - Usage: mozinstall [options] installer 18:16:23 INFO - Options: 18:16:23 INFO - -h, --help show this help message and exit 18:16:23 INFO - -d DEST, --destination=DEST 18:16:23 INFO - Directory to install application into. [default: 18:16:23 INFO - "/builds/slave/test"] 18:16:23 INFO - --app=APP Application being installed. [default: firefox] 18:16:23 INFO - mkdir: /builds/slave/test/build/application 18:16:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:16:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:16:39 INFO - Reading from file tmpfile_stdout 18:16:39 INFO - Output received: 18:16:39 INFO - /builds/slave/test/build/application/firefox/firefox 18:16:39 INFO - Running post-action listener: _resource_record_post_action 18:16:39 INFO - ##### 18:16:39 INFO - ##### Running run-tests step. 18:16:39 INFO - ##### 18:16:39 INFO - Running pre-action listener: _resource_record_pre_action 18:16:39 INFO - Running main action method: run_tests 18:16:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:16:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:16:39 INFO - Python 2.7.3 18:16:39 INFO - Return code: 0 18:16:39 INFO - grabbing minidump binary from tooltool 18:16: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:16:39 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 0x21f6410>, '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 0x21f7310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21f77a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:16:39 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:16:39 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:16:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:16:39 INFO - Return code: 0 18:16:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:16:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:16:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:16:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:16:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:16:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:16:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:16:40 INFO - Return code: 0 18:16:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:16:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-031 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:16:40 INFO - Using env: {'DISPLAY': ':0', 18:16:40 INFO - 'HOME': '/home/cltbld', 18:16:40 INFO - 'LANG': 'en_US.UTF-8', 18:16:40 INFO - 'LANGUAGE': 'en_US:en', 18:16:40 INFO - 'LOGNAME': 'cltbld', 18:16:40 INFO - 'MAIL': '/var/mail/cltbld', 18:16:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:16:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:16:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:16:40 INFO - 'MOZ_NO_REMOTE': '1', 18:16:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:16:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:16:40 INFO - 'NO_EM_RESTART': '1', 18:16:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:16:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:16:40 INFO - 'PWD': '/builds/slave/test', 18:16:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:16:40 INFO - 'SHELL': '/bin/bash', 18:16:40 INFO - 'SHLVL': '1', 18:16:40 INFO - 'TERM': 'linux', 18:16:40 INFO - 'TMOUT': '86400', 18:16:40 INFO - 'USER': 'cltbld', 18:16:40 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1461629914.105575-1098230120', 18:16:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:16:40 INFO - '_': '/tools/buildbot/bin/python'} 18:16:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:16:40 INFO - mozversion application_buildid: 20160425171313 18:16:40 INFO - mozversion application_changeset: cfc7ebe592937ad937cebe04fdad4213eee72fae 18:16:40 INFO - mozversion application_display_name: Nightly 18:16:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:16:40 INFO - mozversion application_name: Firefox 18:16:40 INFO - mozversion application_remotingname: firefox 18:16:40 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:16:40 INFO - mozversion application_vendor: Mozilla 18:16:40 INFO - mozversion application_version: 49.0a1 18:16:40 INFO - mozversion platform_buildid: 20160425171313 18:16:40 INFO - mozversion platform_changeset: cfc7ebe592937ad937cebe04fdad4213eee72fae 18:16:40 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:16:40 INFO - mozversion platform_version: 49.0a1 18:16:40 INFO - using testdate: 1461633400 18:16:40 INFO - actual date: 1461633400 18:16:40 INFO - starting webserver on 'localhost:43923' 18:16:40 INFO - SUITE-START | Running 2 tests 18:16:40 INFO - TEST-START | dromaeo_css 18:16:40 INFO - operating with platform_type : linux_ 18:16:40 INFO - Initialising browser for dromaeo_css test... 18:16:40 INFO - TEST-INFO | started process 8558 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprpAnGi/profile http://localhost:43923/getInfo.html) 18:16:45 INFO - PROCESS | 8558 | __metrics Screen width/height:1600/1200 18:16:45 INFO - PROCESS | 8558 | colorDepth:24 18:16:45 INFO - PROCESS | 8558 | Browser inner width/height: 1024/697 18:16:45 INFO - PROCESS | 8558 | __metrics 18:16:46 ERROR - PROCESS | 8558 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:16:46 INFO - TEST-INFO | 8558: exit 0 18:16:46 INFO - Browser initialized. 18:16:46 INFO - Running cycle 1/1 for dromaeo_css test... 18:16:46 INFO - TEST-INFO | started process 8636 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprpAnGi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:16:58 INFO - PROCESS | 8636 | RSS: Main: 156606464 18:16:58 INFO - PROCESS | 8636 | 18:17:50 INFO - PROCESS | 8636 | 1461633470075 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:20:00 INFO - PROCESS | 8636 | Cycle 1(1): loaded http://localhost:43923/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43923/tests/dromaeo/cssquery-ext.html) 18:20:02 INFO - PROCESS | 8636 | RSS: Main: 227618816 18:20:02 INFO - PROCESS | 8636 | 18:23:04 INFO - PROCESS | 8636 | Cycle 1(1): loaded http://localhost:43923/tests/dromaeo/cssquery-ext.html (next: http://localhost:43923/tests/dromaeo/cssquery-jquery.html) 18:23:05 INFO - PROCESS | 8636 | RSS: Main: 242102272 18:23:05 INFO - PROCESS | 8636 | 18:26:07 INFO - PROCESS | 8636 | Cycle 1(1): loaded http://localhost:43923/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43923/tests/dromaeo/cssquery-mootools.html) 18:26:08 INFO - PROCESS | 8636 | RSS: Main: 231800832 18:26:08 INFO - PROCESS | 8636 | 18:29:10 INFO - PROCESS | 8636 | Cycle 1(1): loaded http://localhost:43923/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43923/tests/dromaeo/cssquery-prototype.html) 18:29:10 INFO - PROCESS | 8636 | RSS: Main: 231776256 18:29:10 INFO - PROCESS | 8636 | 18:32:13 INFO - PROCESS | 8636 | Cycle 1(1): loaded http://localhost:43923/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43923/tests/dromaeo/cssquery-yui.html) 18:32:14 INFO - PROCESS | 8636 | RSS: Main: 210010112 18:32:14 INFO - PROCESS | 8636 | 18:35:17 INFO - PROCESS | 8636 | Cycle 1(1): loaded http://localhost:43923/tests/dromaeo/cssquery-yui.html (next: http://localhost:43923/tests/dromaeo/cssquery-dojo.html) 18:35:17 INFO - PROCESS | 8636 | RSS: Main: 292519936 18:35:17 INFO - PROCESS | 8636 | 18:35:17 INFO - PROCESS | 8636 | __start_tp_report 18:35:17 INFO - PROCESS | 8636 | _x_x_mozilla_page_load 18:35:17 INFO - PROCESS | 8636 | _x_x_mozilla_page_load_details 18:35:17 INFO - PROCESS | 8636 | |i|pagename|runs| 18:35:17 INFO - PROCESS | 8636 | |0;dojo.html;2561.410875498635;2625.789936805057;2642.273374821925;2649.7482739139787;2667.439837634097;4473.552644735525;4568.177728008957;4575.4509458864895;4581.45022597288;4596.586307232345;4514.52597477265;4609.930851037228;4676.532346765331;4688.953110468894;4699.154152252593;4166.104287578185;4215.578442155783;4262.254105531535;4326.891827704301;4350.825966961318;4288.849890253841;4341.3705012773125;4360.302351623741;4401.229784787665;4404.207242696313;4102.917941641181;4189;4209.2633789086785;4210.45264115666;4291.54938731435;4159.9792001039805;4187.602177793105;4192.517860446027;4202.957970420318;4234.957650423487;4211.768352740598;4239.02502424441;4240.639545638624;4266.2320782259185;4277.465316835395;4367.912641747147;4460.1525710114975;4495.14592227479;4539.228331183706;4571.268597024494;4877.439094504142;4904.0013909242125;4904.337914381548;4907.4356449008465;4931.063098011402;4905.901881962341;4927.802887884481;4934.950650493521;4939.209726443752;4939.950600493985;4451.799669014902;4508.594226519596;4551.749653769075;4582.587567118909;4587.678862479661;5035.7985680572365;5044.167712327425;5060.84817455477;5062.949370506248;5066.700999276982;4719.787609557578;4853.878653033702;4858.514148585184;4860.149473842063;4889.535494128123;4847.079054979613;4890.290907818347;4896.706197628154;4930.5069493050405;4938.481459446706;4884.853454396374;4919.065377578248;4924.778384972685;4924.778384972685;4969.503049695001;4671.579557839743;4865.610751139971;4868.123737727172;4868.829590964299;4917.065757506062;4135.152293779768;4152.356384760367;4187.748735075845;4187.895302617459;4198.85304014358;3637.727170462226;3654.3787556114994;3654.7076233901757;3685.6867166291167;3691.6492933171307;4077.938830917539;4143.37849322598;4146.357314616239;4160.063985603215;4213.705040647125;4812.5668689818085;4854.781534830941;4939.506049395031;4940.135476291634;4980.750962451935;4153.169366126786;4195.517515485697;4197.811098500575;4238.830446782156;4239.1521695660995;4274.807633656493;4420.756858372821;4421.834866960885;4424.84513042042;4454.710443821206;4657.930131048037;4660.813567457264;4676.859694209179;4686.179918514178;4723.031778485402;4954.157793175241;4958.206686930074;4959.62802789785;4961.479044700254;4962.776675049704;4505.414296141481;4612.7001744886475;4677.48547659764;4679.883002924954;4687.460941991578;4764.809407623657;4787.54518320759;4787.664863459524;4796.160671882476;4834.709917404967;4814.903701925872;4846.176150054429;4846.781894814671;4851.272309153655;4898.069366820293;4557;4657.883552911204;4726.385569875894;4742.241241401359;4751.382320298339;4798.328234047166;4812.8796780080775;4831.106245344622;4837.250226214942;4872.634552408513;4754.928676069792;4757.09615173116;4762.261849413347;4765.0946320199055;4791.377120974391;4576.931346029879;4752.144613969519;4827.227643577011;4840.878978025577;4844.8788780279365;4696.600788932842;4701.576858082789;4719.5752382285755;4722.669413141047;4767.61859051282 18:35:17 INFO - PROCESS | 8636 | |1;ext.html;573.1803520965018;591.5385998920838;591.6657088744859;602.445749910083;609.4301827791014;27211.455770884597;27868.44263114739;34552.48171277429;35345;35379.64620353796;41607.167856642845;42363.78818105913;42380.57619423805;42382.15235695284;42397.36403953943;7749.806254843632;7930.563818989954;7955.64199611016;7964.5619490927975;7968.482048666847;7764.728234511793;7848.45060845741;7887.645055972495;7899.644515996765;7903.8419231615335;7904.367650587939;7958.368534450424;7960.402969777261;7968.721094761684;7969.681212751483;7923.524588524707;7989.6404661789775;7996.160403157701;8000.63997120131;8001.959990200041;7774.144844067148;7915.604219788987;7921.20787921209;7921.722739704141;7922.485038472539;38325.23349533021;38924;38951.805240973896;38973.415398769044;39074;3052.6184226971627;3180.8568614412407;3322.80063196209;3333.233356328034;3520.5775306963073;28587.285317867012;28887.277818054506;29445.41109177826;29554.852225738734;30554.38891222163;3278.688524590164;3282.7373810095137;3283.4910588858525;3428.9485657714904;3433.2275238071484;236869;236910;237752;238144;238149;41723.7913810429;42830.357544636856;47182.5281747185;53975.730121349145;54275.72862135664;51495.48504514982;51613;51661.74169129205;51722.48277517177;51748;13838.169709817443;13971.371288292052;14077.648058798612;14082.647933801532;14091.506797262047;10127.848082278771;10178.083972442515;10316.1747060235;10383.376997380181;10390.844137337946;17122.914385427994;17174.484765457055;17205.56986075358;17296.827031729772;17428.738568921475;5814.226660868476;5912.70436478183;5952.315483719428;5991.910121348184;6076.513878889767;919.8620206969008;956.7560272130702;961.3558915526614;978.5889926231;1000.46475135802;27530.587041194398;28511.58698738343;28625.284367890552;28690.856545717554;29173.85413072921;963.3208587945481;969.0745338202141;986.5781783963266;1017.2980643356061;1030.1810061001522;14949.17779522115;15317.770233446507;15484.922575387276;15550.455734049254;15568.688626227413;300048;304218;304279;304611;304942;49125;49446.50553494491;49549.25676114861;49591.75204123956;49605.50394496081;1224.797908345131;1237.2143688757703;1264.2477725753347;1286.0933042205222;1298.1497119386818;25690.486190276464;27710;27822.165335039983;28189.43621127607;28191.43617127687;39083;39362.606373935894;39545.45227298897;39551.40672889967;39674.40488392734;231697;233279;233943;234417;234528;28497.715022850334;33576.16059598432;33853.66146338505;34050.65949340574;34169.60812743084;36711.449328259565;37241.6275837249;37494.625053749114;37747.245055099294;38076.809615951744;31491;31844;33022.33955320928;33314.833425832716;33603.66396336005;32724.83637581797;32872;33376.332473350885;34045.65954340524;35726.821365893;34847.65152348444;35051.82474087613;35266.29467410689;35313.47029794504;35624.64375356213;31688;32433.189170270933;32723.672763273014;32933.50599741054;32933.83533082319;24859.62710559307;24908.252752417455;25235.495290094463;25373;25561.87219063893 18:35:17 INFO - PROCESS | 8636 | |2;jquery.html;379.3248018527022;384.86722080882083;389.85770193879233;393.6063936063936;397.7633308181633;15885.205739713027;17593.3842315519;17825.108744562753;18025.369112081047;18069.1868865901;16761.66476670466;16790.160491975414;17219.91390043048;17588.736168957475;17620.383286584973;5065.113605119108;5184.844454666366;5194.298769666103;5219.947800521984;5239.292695486118;3704.7962362070075;3920.7451515651533;5842.298924129111;6159.907601385983;6162.260528736536;5969.253843269591;6098.908516372258;6102.908456373158;6121.204243448367;6136.202293701813;5798.768049278045;5948.167256584038;5963.344032156459;5977.701114944257;5978.910316345258;3398.609159946613;5111;5370.248165256867;5381.7578208980685;5382.596305277119;20539.897300513552;20707.896460517753;20889.37331880046;20955;20978.475538111517;1753.3863147898262;1864.9906750466296;1905.3617038292189;1958.285225892539;1967.931122410723;9172.312076594282;9537.618495260182;10256.435896025716;10356.430396328198;13218.07473476857;1705.326396073544;1731.471901070173;1793.0407232130879;1804.7653805005395;1813.274690123961;69030;70535;71275;71383.28616713818;72443;10191.82793978702;10222.386656800616;10228.386296822215;10249.231307651808;10453.68638940833;14313.92843035799;14355.425782968774;14380.856191437952;14394.496192633207;14419.711605767827;7309.378702810321;7564.621768911643;7575.356094732009;7626.69493220276;7692.73075442368;7075.858482830417;7131.71473141079;7280.890786638205;7319.853602927911;7349.8162545936775;4380.474343078851;4447.577480139382;4453.1761624099645;4515.729056256635;4549.771561678328;1707.795064592257;1824.14265295311;1833.0834582708646;1881.0782716468832;1903.6287923854993;837.2715737308581;905.7282815155427;913.7669894177076;950.5627411390699;988.2341185581089;15839.604009899615;16010.279537420844;16269;16344.918275408785;16547.586310342336;806.8265322955475;899.5097671768901;904.4030939579845;921.2767977138049;921.6589861751195;7439.51643143194;9749.707508774749;9857.901420985841;9886.950565247127;9986.151177149879;358035;368399;369768;381440;384427;78910;79683.60158199287;81776.59111704404;82384.58807706043;82890.17109828946;973.9172170365598;986.5412583149014;997.6159178716101;1007.6221416968637;1012.8379459286479;15238.695226095639;15325.46360877364;15439.768403473732;15461.458848940232;15708.68582628364;34804.65195348014;34826.651733482344;34962.300753985284;35150.64849351474;35471.29057418889;49702.751486242334;50775.2383714252;50847;51096;52136;8024.518528888286;8158.877616835871;8173.468724532886;8246.83506329858;8357.832843343222;11108.111351092055;11501.53993840237;11710.88289117132;12172.39138043112;12190.51237950472;10009.54957026921;10346.637867674595;10539.736506587396;10861.34831910088;10916.12670986335;9938.900610993798;9950.651727189514;9957.701268961942;10043.347182433119;10092.192624589869;10113.230184717499;10265.948670256601;10604;10633.308834925705;11079.612213572485;7448.813779655552;7680.6543705534505;7698.153203147537;7834.4907581007055;7960.562169080757;8056.395770317366;10373.740656483647;10499.107575855833;10556.102731267929;10654.946725266323 18:35:17 INFO - PROCESS | 8636 | |3;mootools.html;1198.8381568488262;1425.7219842130783;1475.0854470228458;1563.921803909805;1765.9691354941824;7141.357277845;7415.332620064193;7416.184219735825;7421.480496365257;7480.251974802517;7233.348998590126;7406.925930740691;7425.554466732;7435.330820226179;7440.181580026193;1780.163323238076;1826.9543261418437;1828.31438210671;2204.48194674251;2235.854669446489;2199.8570092943987;2279.7036385269976;2283;2283.235116236063;2283.851549649276;2183.770704076073;2199.098369668436;2211.380813372258;2212.811910987568;2229.052652622637;1800;2125.968110478344;2183.366823621156;2191.1783081344415;2192.0683709423433;2190.6494960806353;2221.7778222177653;2222.0556263588073;2224.610693128696;2226.109556177526;7095.254998225164;7134.108236470442;7142.892856607155;7142.892856607155;7146.464015198829;4338.110687309094;4449.26587113126;4469.865904022885;4473.642108631302;4474.7762611869275;2692.7711144552736;2699.2050286559415;2825.307799589093;2825.3784167483122;2853.771698264134;891.4963045879123;892.5091199840127;893.3210759822582;915.3043686798015;923.5243849417556;10061.245406594535;10241.897581024168;10247.282690211689;10264.127549158331;10265.538050787807;4126.463559737215;4180.895477613084;4308.612224899774;4328.8484903028875;4339.674524410681;8868.201861832466;8892.510911899908;8929.910700893039;9005.459672419676;9009.459432434074;4655.068986202773;4658.254679251304;4661.370714953506;4667.6265898728025;4697.084068606658;3566.268914872445;3623.3477973964414;4161.521425036099;4223.408722778813;4272.743635381825;3575.606683264839;3584.2652256287397;3681.447782832543;3712.461693054546;3716.814159292079;1158.25871442277;1173.061550759389;1184.141497414385;1195.7967145585271;1198.4547031100828;687.9071325371111;786.732510946281;801.9157988411249;838;844.9450785698909;4839.370881785346;5041.697498150123;5053.898922021539;5065.8733531662;5134.614903882224;880.8634661627458;910.3127139010137;911.7811725185908;927.8700981862545;928.8281667891462;3294.1270563300745;3368.7473439492137;3382.5771778527683;3399.8810041648426;3463.0649724574223;9033.232175265044;9226.58480368385;9370.95314523423;9474.857877131848;9476.194523465445;9206.723798285928;9328.580213890391;9349.298802589832;9349.532523373804;9356.812863742687;7016.158061032709;7194.38847697941;7201.495895287384;7213;7234.131904171428;4806.44725856517;4928.827491037797;4983.451820299837;5056.873578160428;5062.063518249136;8466.957665211634;8697.956510217662;8747.16901894319;8786.868196977179;8797.076306987674;8451.830963380575;8737.694180703646;8748.562571871254;8861.069587693199;8891.199792018491;5301.681899085913;6329.715162817776;6784.830379240559;7100.254473280438;7171.713131474683;6942.826429339307;6982.197047339621;7009.228984811564;7018.192907815463;7019.4033507150425;6496.155499784998;6618.470522358296;6651.16860392451;6729.798106056826;6765.898511522233;6483.513736469689;6492.091107244895;6591.176102987127;6619.371159739817;6651.63416012124;6323.9683801580695;6371.585846919936;6375.171227740364;6382.425581697669;6400.903986440114;4193.245215861174;4343.153085148366;4352.434183556117;4442.51132375432;4514.616257618008;4369.584889435499;4433.046894917608;4452.842261012225;4503.009337945647;4586.518415566317 18:35:17 INFO - PROCESS | 8636 | |4;prototype.html;303.12850554655364;303.33266813011375;307.2334525359228;307.53562121197;312.7169911230337;4619.8152073917;4675.672702910798;4677.555632214934;4703.824043988999;4709.411323584552;4594.40272764541;4620.90758184837;4628;4634.513376095512;4645.698029628072;3495.615482296945;3552.236269202121;3580.319739249547;3684.778913265199;3711.3876210425246;3677.687396571281;3689.797061161635;3691.4647376130447;3698.130939229279;3710.9072273193115;3472.2881809229175;3637.7453578249533;3639.7088232941433;3682.1346983458866;3682.9447558286647;3554.8933531993953;3684.9631503684886;3693.7968411737343;3708.332500149972;3728.328900797855;3266.7713260071805;3277.4100661880852;3290.7696461247724;3300.6204286506877;3301.4387554115856;4519.615832654228;4527.392775564693;4534.344964088084;4539.977300113512;4555.2256116460285;3984.880453586397;4167.416561681367;4232.809523571416;4235.449391579075;4240.8761678155315;4829.903401931977;4847.95152048482;4854.538818812207;4865.756712164378;4875.219964805614;3603.7297202709638;3608.7654302470255;3611.0972256935765;3615.5299811024397;3616.6383361663884;4940.901181976376;5008.474110218411;5058.190689489701;5091.821786237501;5100.5409513143995;3660;3668.596454390015;3673.577538583044;3691.741578089508;3694.6305369463375;3706.666400023957;3716.21959388526;3727.8499667780684;3738.5700644425697;3749.268892565978;4166.66666666666;4167.270727622654;4171.958280417157;4185.790710464526;4192.45498085247;4599.264117741145;4871.732054736953;4881.8535443936735;4890.144224760653;4935.90128197434;1365.3514580574147;1367.6580854786303;1369.5959691890916;1371.746226948018;1374.1274290825197;882.761654353321;907.6596276396351;910.5811326789751;911.7720569857536;939.3471537281524;4240.85157019509;4318.935215971763;4321.805518751664;4337.6963612546815;4365.323374876899;5023.14606516893;5023.82416615424;5026.52248036436;5047.3911153525405;5048.873778155576;4190.150697525712;4291.978540107342;4296.656267498593;4322.459692538433;4339.9403420588405;2829.4199689063694;2837;2838.3329917469378;2852.6434195725533;2864.255293623631;5001;5014.949850501521;5044.209305696499;5048.62135339851;5056.241563765465;5303.920441193385;5456.563474921997;5468.7265636718;5478.780848766005;5502.80740174092;4988.80044798204;4998.175301075282;5022.949770502321;5041.873953151201;5054.368203974504;5323.547498462519;5325.760340784596;5325.840224793263;5339.733013349394;5343.465653434625;5061.721605311744;5182.792688292426;5245.160774276097;5253.900198882678;5268.420473747961;4986.32684587576;5019.222020586815;5027.547520723152;5028.446870844131;5059.46875578059;3848.461215429898;3855.3445914194094;3864.2271545690414;3875.108724993211;3878.340682083996;4813.831515896927;5054.974725126351;5074.543291103819;5356.839294821161;5363.383210930637;4821.421429428491;4867.61059115277;4881.877168251251;4885.975570122127;4900.387451568554;4872.488388719134;4876.341693871282;4883.755812209446;4915.238138088602;4916.483769204325;4583.702059366131;4816.301636262792;4835.540623640749;4838.225883858566;4851.80592776285;4672.48602653701;4762.666613337033;4923.286123512143;4941.456439791692;4947.208446648519;4798.520147985174;4821.108095002436;4848.175810112219;4854.975725121351;4863.489333619919 18:35:17 INFO - PROCESS | 8636 | |5;yui.html;706.6466766616692;793.5080250244841;799.9760007199783;802.3340627279363;807.4872455990446;11942.940285298571;12439.378031098442;12647.936760316197;12664.11351205416;12669.746605067892;11772.47023883925;11801.527938882457;11837.704057398569;11859.466324015419;11913.225640333372;636.707114727226;786.6106277392698;794.3208556684039;802.0575823407502;803.0884945586743;805.5448671500612;810.4569938141459;810.8256724804166;812.2405550809982;812.3379445751723;859.0893652728097;866.5667166416791;866.757307953774;867.0245973280059;867.3364875869964;663.0584569910739;828.8135169586794;853.8463076646201;858.7295002074358;859.4198915731887;622.0234232255361;624.017172952597;624.996875015622;625.0624063904144;626.2047200055949;12162.148649594532;12226.14416990811;12227.755444891141;12233.510659573607;12242.143049986504;11286.379249141293;11482.138839587064;11894.286342819458;11941.164118511708;12699.04757143218;11145.609903653332;11157;11259.718507037307;11382.71543211418;11414.600488982845;4234.153169366139;4269.146170765828;4281.079567892918;4310.676699247569;4353.477582690066;41459.1708165835;42878.35682464766;42995.57004429947;43089;43544.99999999968;9696;9735.756606084904;10416.749990001097;10425.113865321533;10432.165426765841;17607.29570817153;17769.11154442273;17835.73246401305;17857.910710446624;17872.28510859577;6631.204255489372;6873.347032032049;7088.893666594902;7098.9290107099305;7135.607541585159;3633.6003039665616;3717.9442308365396;3718.1819999600043;3758.398656215047;3888.727789054739;2853.144056782998;2909.810862293944;2912.6504819421807;2920.7371336579386;2921.415716856637;527.5146864884316;527.7625068719092;527.8416475057544;533.3306700091438;536.1769683535754;473.07277735638536;521.2754271562532;569.9515541178963;572.9286234740978;574.7815829984579;7843.294103530709;8015.719449819228;8245.381596380295;8454.61954212062;8480.236778689947;470.282818701483;473.81047580967197;563.830052640772;588.4292236530558;601.1613798750806;3969.8610548631236;4003.159336539301;4041.7979101045416;4044.2315959967514;4136.482939632546;50111;50432.495675043516;50970.74514627403;52185.2172217417;52561.211581826305;42163.57836421658;43642.78178609087;44421.77789111034;44805;45091;38862.41706374407;40004.99925011271;42487.57512424898;43741.99999999937;44512;8131.2681858633005;8236.299914507334;8240.587970601566;8257.83484330322;8387.119352467904;27096.72903270942;27295.590566141127;28877.855610721814;29532.704672952994;31061.53407698841;37795.43306850454;37861;37866.432003520524;38094;38547.229055419295;10730.678079657622;11049.447527623426;11049.944750276198;11114.055305298816;11186.720331991766;11190.134672085282;11549.78727233662;11561.595344162914;12195.207311524722;12633.418390142517;10635.734106647396;10670.57317707283;10958.671239862817;11537;11765.588204412805;10919.290246133976;11012.889871101506;11460.369679667365;11845.881541184477;11911.166218364631;11249.66251012471;11316.717082073013;11552.768944621228;11802;11835.34905580167;9152.17630413266;9251.149660338951;9605.759856003655;9724.367916085432;9993.10062094415;9006.351076415853;9427.15155635996;9711.125998659872;9717.222622190066;9818.116369526777 18:35:17 INFO - PROCESS | 8636 | __end_tp_report 18:35:17 INFO - PROCESS | 8636 | __start_cc_report 18:35:17 INFO - PROCESS | 8636 | _x_x_mozilla_cycle_collect,1023 18:35:17 INFO - PROCESS | 8636 | __end_cc_report 18:35:17 INFO - PROCESS | 8636 | __startTimestamp1461634517567__endTimestamp 18:35:17 INFO - PROCESS | 8636 | 18:35:17 INFO - PROCESS | 8636 | ------- Summary: start ------- 18:35:17 INFO - PROCESS | 8636 | Number of tests: 6 18:35:17 INFO - PROCESS | 8636 | 18:35:17 INFO - PROCESS | 8636 | [#0] dojo.html Cycles:165 Average:4536.12 Median:4677.49 stddev:465.22 (9.9%) stddev-sans-first:440.10 18:35:17 INFO - PROCESS | 8636 | Values: 2561.4 2625.8 2642.3 2649.7 2667.4 4473.6 4568.2 4575.5 4581.5 4596.6 4514.5 4609.9 4676.5 4689.0 4699.2 4166.1 4215.6 4262.3 4326.9 4350.8 4288.8 4341.4 4360.3 4401.2 4404.2 4102.9 4189.0 4209.3 4210.5 4291.5 4160.0 4187.6 4192.5 4203.0 4235.0 4211.8 4239.0 4240.6 4266.2 4277.5 4367.9 4460.2 4495.1 4539.2 4571.3 4877.4 4904.0 4904.3 4907.4 4931.1 4905.9 4927.8 4935.0 4939.2 4940.0 4451.8 4508.6 4551.7 4582.6 4587.7 5035.8 5044.2 5060.8 5062.9 5066.7 4719.8 4853.9 4858.5 4860.1 4889.5 4847.1 4890.3 4896.7 4930.5 4938.5 4884.9 4919.1 4924.8 4924.8 4969.5 4671.6 4865.6 4868.1 4868.8 4917.1 4135.2 4152.4 4187.7 4187.9 4198.9 3637.7 3654.4 3654.7 3685.7 3691.6 4077.9 4143.4 4146.4 4160.1 4213.7 4812.6 4854.8 4939.5 4940.1 4980.8 4153.2 4195.5 4197.8 4238.8 4239.2 4274.8 4420.8 4421.8 4424.8 4454.7 4657.9 4660.8 4676.9 4686.2 4723.0 4954.2 4958.2 4959.6 4961.5 4962.8 4505.4 4612.7 4677.5 4679.9 4687.5 4764.8 4787.5 4787.7 4796.2 4834.7 4814.9 4846.2 4846.8 4851.3 4898.1 4557.0 4657.9 4726.4 4742.2 4751.4 4798.3 4812.9 4831.1 4837.3 4872.6 4754.9 4757.1 4762.3 4765.1 4791.4 4576.9 4752.1 4827.2 4840.9 4844.9 4696.6 4701.6 4719.6 4722.7 4767.6 18:35:17 INFO - PROCESS | 8636 | 18:35:17 INFO - PROCESS | 8636 | [#1] ext.html Cycles:180 Average:41789.63 Median:18799.28 stddev:67873.67 (361.0%) stddev-sans-first:67993.52 18:35:17 INFO - PROCESS | 8636 | Values: 573.2 591.5 591.7 602.4 609.4 27211.5 27868.4 34552.5 35345.0 35379.6 41607.2 42363.8 42380.6 42382.2 42397.4 7749.8 7930.6 7955.6 7964.6 7968.5 7764.7 7848.5 7887.6 7899.6 7903.8 7904.4 7958.4 7960.4 7968.7 7969.7 7923.5 7989.6 7996.2 8000.6 8002.0 7774.1 7915.6 7921.2 7921.7 7922.5 38325.2 38924.0 38951.8 38973.4 39074.0 3052.6 3180.9 3322.8 3333.2 3520.6 28587.3 28887.3 29445.4 29554.9 30554.4 3278.7 3282.7 3283.5 3428.9 3433.2 236869.0 236910.0 237752.0 238144.0 238149.0 41723.8 42830.4 47182.5 53975.7 54275.7 51495.5 51613.0 51661.7 51722.5 51748.0 13838.2 13971.4 14077.6 14082.6 14091.5 10127.8 10178.1 10316.2 10383.4 10390.8 17122.9 17174.5 17205.6 17296.8 17428.7 5814.2 5912.7 5952.3 5991.9 6076.5 919.9 956.8 961.4 978.6 1000.5 27530.6 28511.6 28625.3 28690.9 29173.9 963.3 969.1 986.6 1017.3 1030.2 14949.2 15317.8 15484.9 15550.5 15568.7 300048.0 304218.0 304279.0 304611.0 304942.0 49125.0 49446.5 49549.3 49591.8 49605.5 1224.8 1237.2 1264.2 1286.1 1298.1 25690.5 27710.0 27822.2 28189.4 28191.4 39083.0 39362.6 39545.5 39551.4 39674.4 231697.0 233279.0 233943.0 234417.0 234528.0 28497.7 33576.2 33853.7 34050.7 34169.6 36711.4 37241.6 37494.6 37747.2 38076.8 31491.0 31844.0 33022.3 33314.8 33603.7 32724.8 32872.0 33376.3 34045.7 35726.8 34847.7 35051.8 35266.3 35313.5 35624.6 31688.0 32433.2 32723.7 32933.5 32933.8 24859.6 24908.3 25235.5 25373.0 25561.9 18:35:17 INFO - PROCESS | 8636 | 18:35:17 INFO - PROCESS | 8636 | [#2] jquery.html Cycles:180 Average:24057.48 Median:2155.30 stddev:61729.65 (2864.1%) stddev-sans-first:61877.22 18:35:17 INFO - PROCESS | 8636 | Values: 379.3 384.9 389.9 393.6 397.8 15885.2 17593.4 17825.1 18025.4 18069.2 16761.7 16790.2 17219.9 17588.7 17620.4 5065.1 5184.8 5194.3 5219.9 5239.3 3704.8 3920.7 5842.3 6159.9 6162.3 5969.3 6098.9 6102.9 6121.2 6136.2 5798.8 5948.2 5963.3 5977.7 5978.9 3398.6 5111.0 5370.2 5381.8 5382.6 20539.9 20707.9 20889.4 20955.0 20978.5 1753.4 1865.0 1905.4 1958.3 1967.9 9172.3 9537.6 10256.4 10356.4 13218.1 1705.3 1731.5 1793.0 1804.8 1813.3 69030.0 70535.0 71275.0 71383.3 72443.0 10191.8 10222.4 10228.4 10249.2 10453.7 14313.9 14355.4 14380.9 14394.5 14419.7 7309.4 7564.6 7575.4 7626.7 7692.7 7075.9 7131.7 7280.9 7319.9 7349.8 4380.5 4447.6 4453.2 4515.7 4549.8 1707.8 1824.1 1833.1 1881.1 1903.6 837.3 905.7 913.8 950.6 988.2 15839.6 16010.3 16269.0 16344.9 16547.6 806.8 899.5 904.4 921.3 921.7 7439.5 9749.7 9857.9 9887.0 9986.2 358035.0 368399.0 369768.0 381440.0 384427.0 78910.0 79683.6 81776.6 82384.6 82890.2 973.9 986.5 997.6 1007.6 1012.8 15238.7 15325.5 15439.8 15461.5 15708.7 34804.7 34826.7 34962.3 35150.6 35471.3 49702.8 50775.2 50847.0 51096.0 52136.0 8024.5 8158.9 8173.5 8246.8 8357.8 11108.1 11501.5 11710.9 12172.4 12190.5 10009.5 10346.6 10539.7 10861.3 10916.1 9938.9 9950.7 9957.7 10043.3 10092.2 10113.2 10265.9 10604.0 10633.3 11079.6 7448.8 7680.7 7698.2 7834.5 7960.6 8056.4 10373.7 10499.1 10556.1 10654.9 18:35:17 INFO - PROCESS | 8636 | 18:35:17 INFO - PROCESS | 8636 | [#3] mootools.html Cycles:180 Average:4984.47 Median:5055.39 stddev:2763.12 (54.7%) stddev-sans-first:2756.23 18:35:17 INFO - PROCESS | 8636 | Values: 1198.8 1425.7 1475.1 1563.9 1766.0 7141.4 7415.3 7416.2 7421.5 7480.3 7233.3 7406.9 7425.6 7435.3 7440.2 1780.2 1827.0 1828.3 2204.5 2235.9 2199.9 2279.7 2283.0 2283.2 2283.9 2183.8 2199.1 2211.4 2212.8 2229.1 1800.0 2126.0 2183.4 2191.2 2192.1 2190.6 2221.8 2222.1 2224.6 2226.1 7095.3 7134.1 7142.9 7142.9 7146.5 4338.1 4449.3 4469.9 4473.6 4474.8 2692.8 2699.2 2825.3 2825.4 2853.8 891.5 892.5 893.3 915.3 923.5 10061.2 10241.9 10247.3 10264.1 10265.5 4126.5 4180.9 4308.6 4328.8 4339.7 8868.2 8892.5 8929.9 9005.5 9009.5 4655.1 4658.3 4661.4 4667.6 4697.1 3566.3 3623.3 4161.5 4223.4 4272.7 3575.6 3584.3 3681.4 3712.5 3716.8 1158.3 1173.1 1184.1 1195.8 1198.5 687.9 786.7 801.9 838.0 844.9 4839.4 5041.7 5053.9 5065.9 5134.6 880.9 910.3 911.8 927.9 928.8 3294.1 3368.7 3382.6 3399.9 3463.1 9033.2 9226.6 9371.0 9474.9 9476.2 9206.7 9328.6 9349.3 9349.5 9356.8 7016.2 7194.4 7201.5 7213.0 7234.1 4806.4 4928.8 4983.5 5056.9 5062.1 8467.0 8698.0 8747.2 8786.9 8797.1 8451.8 8737.7 8748.6 8861.1 8891.2 5301.7 6329.7 6784.8 7100.3 7171.7 6942.8 6982.2 7009.2 7018.2 7019.4 6496.2 6618.5 6651.2 6729.8 6765.9 6483.5 6492.1 6591.2 6619.4 6651.6 6324.0 6371.6 6375.2 6382.4 6400.9 4193.2 4343.2 4352.4 4442.5 4514.6 4369.6 4433.0 4452.8 4503.0 4586.5 18:35:17 INFO - PROCESS | 8636 | 18:35:17 INFO - PROCESS | 8636 | [#4] prototype.html Cycles:180 Average:4143.73 Median:4631.26 stddev:1187.42 (25.6%) stddev-sans-first:1155.23 18:35:17 INFO - PROCESS | 8636 | Values: 303.1 303.3 307.2 307.5 312.7 4619.8 4675.7 4677.6 4703.8 4709.4 4594.4 4620.9 4628.0 4634.5 4645.7 3495.6 3552.2 3580.3 3684.8 3711.4 3677.7 3689.8 3691.5 3698.1 3710.9 3472.3 3637.7 3639.7 3682.1 3682.9 3554.9 3685.0 3693.8 3708.3 3728.3 3266.8 3277.4 3290.8 3300.6 3301.4 4519.6 4527.4 4534.3 4540.0 4555.2 3984.9 4167.4 4232.8 4235.4 4240.9 4829.9 4848.0 4854.5 4865.8 4875.2 3603.7 3608.8 3611.1 3615.5 3616.6 4940.9 5008.5 5058.2 5091.8 5100.5 3660.0 3668.6 3673.6 3691.7 3694.6 3706.7 3716.2 3727.8 3738.6 3749.3 4166.7 4167.3 4172.0 4185.8 4192.5 4599.3 4871.7 4881.9 4890.1 4935.9 1365.4 1367.7 1369.6 1371.7 1374.1 882.8 907.7 910.6 911.8 939.3 4240.9 4318.9 4321.8 4337.7 4365.3 5023.1 5023.8 5026.5 5047.4 5048.9 4190.2 4292.0 4296.7 4322.5 4339.9 2829.4 2837.0 2838.3 2852.6 2864.3 5001.0 5014.9 5044.2 5048.6 5056.2 5303.9 5456.6 5468.7 5478.8 5502.8 4988.8 4998.2 5022.9 5041.9 5054.4 5323.5 5325.8 5325.8 5339.7 5343.5 5061.7 5182.8 5245.2 5253.9 5268.4 4986.3 5019.2 5027.5 5028.4 5059.5 3848.5 3855.3 3864.2 3875.1 3878.3 4813.8 5055.0 5074.5 5356.8 5363.4 4821.4 4867.6 4881.9 4886.0 4900.4 4872.5 4876.3 4883.8 4915.2 4916.5 4583.7 4816.3 4835.5 4838.2 4851.8 4672.5 4762.7 4923.3 4941.5 4947.2 4798.5 4821.1 4848.2 4855.0 4863.5 18:35:17 INFO - PROCESS | 8636 | 18:35:17 INFO - PROCESS | 8636 | [#5] yui.html Cycles:180 Average:12641.33 Median:23579.72 stddev:13877.72 (58.9%) stddev-sans-first:13887.71 18:35:17 INFO - PROCESS | 8636 | Values: 706.6 793.5 800.0 802.3 807.5 11942.9 12439.4 12647.9 12664.1 12669.7 11772.5 11801.5 11837.7 11859.5 11913.2 636.7 786.6 794.3 802.1 803.1 805.5 810.5 810.8 812.2 812.3 859.1 866.6 866.8 867.0 867.3 663.1 828.8 853.8 858.7 859.4 622.0 624.0 625.0 625.1 626.2 12162.1 12226.1 12227.8 12233.5 12242.1 11286.4 11482.1 11894.3 11941.2 12699.0 11145.6 11157.0 11259.7 11382.7 11414.6 4234.2 4269.1 4281.1 4310.7 4353.5 41459.2 42878.4 42995.6 43089.0 43545.0 9696.0 9735.8 10416.7 10425.1 10432.2 17607.3 17769.1 17835.7 17857.9 17872.3 6631.2 6873.3 7088.9 7098.9 7135.6 3633.6 3717.9 3718.2 3758.4 3888.7 2853.1 2909.8 2912.7 2920.7 2921.4 527.5 527.8 527.8 533.3 536.2 473.1 521.3 570.0 572.9 574.8 7843.3 8015.7 8245.4 8454.6 8480.2 470.3 473.8 563.8 588.4 601.2 3969.9 4003.2 4041.8 4044.2 4136.5 50111.0 50432.5 50970.7 52185.2 52561.2 42163.6 43642.8 44421.8 44805.0 45091.0 38862.4 40005.0 42487.6 43742.0 44512.0 8131.3 8236.3 8240.6 8257.8 8387.1 27096.7 27295.6 28877.9 29532.7 31061.5 37795.4 37861.0 37866.4 38094.0 38547.2 10730.7 11049.4 11049.9 11114.1 11186.7 11190.1 11549.8 11561.6 12195.2 12633.4 10635.7 10670.6 10958.7 11537.0 11765.6 10919.3 11012.9 11460.4 11845.9 11911.2 11249.7 11316.7 11552.8 11802.0 11835.3 9152.2 9251.1 9605.8 9724.4 9993.1 9006.4 9427.2 9711.1 9717.2 9818.1 18:35:17 INFO - PROCESS | 8636 | -------- Summary: end -------- 18:35:17 INFO - PROCESS | 8636 | 18:35:18 INFO - TEST-INFO | 8636: exit 0 18:35:18 INFO - TEST-OK | dromaeo_css | took 1117511ms 18:35:18 INFO - TEST-START | kraken 18:35:18 INFO - operating with platform_type : linux_ 18:35:18 INFO - Initialising browser for kraken test... 18:35:18 INFO - TEST-INFO | started process 8814 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxcRHZD/profile http://localhost:43923/getInfo.html) 18:35:22 INFO - PROCESS | 8814 | __metrics Screen width/height:1600/1200 18:35:22 INFO - PROCESS | 8814 | colorDepth:24 18:35:22 INFO - PROCESS | 8814 | Browser inner width/height: 1024/697 18:35:22 INFO - PROCESS | 8814 | __metrics 18:35:23 ERROR - PROCESS | 8814 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:35:24 INFO - TEST-INFO | 8814: exit 0 18:35:24 INFO - Browser initialized. 18:35:24 INFO - Running cycle 1/1 for kraken test... 18:35:24 INFO - TEST-INFO | started process 8896 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxcRHZD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:35:36 INFO - PROCESS | 8896 | RSS: Main: 156749824 18:35:36 INFO - PROCESS | 8896 | 18:35:38 INFO - PROCESS | 8896 | Cycle 1(1): loaded http://localhost:43923/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43923/tests/kraken/driver.html?testName=audio-beat-detection) 18:35:39 INFO - PROCESS | 8896 | RSS: Main: 210980864 18:35:39 INFO - PROCESS | 8896 | 18:35:42 INFO - PROCESS | 8896 | Cycle 1(1): loaded http://localhost:43923/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43923/tests/kraken/driver.html?testName=audio-dft) 18:35:43 INFO - PROCESS | 8896 | RSS: Main: 196411392 18:35:43 INFO - PROCESS | 8896 | 18:35:46 INFO - PROCESS | 8896 | Cycle 1(1): loaded http://localhost:43923/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43923/tests/kraken/driver.html?testName=audio-fft) 18:35:47 INFO - PROCESS | 8896 | RSS: Main: 268230656 18:35:47 INFO - PROCESS | 8896 | 18:35:49 INFO - PROCESS | 8896 | Cycle 1(1): loaded http://localhost:43923/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43923/tests/kraken/driver.html?testName=audio-oscillator) 18:35:49 INFO - PROCESS | 8896 | RSS: Main: 175771648 18:35:49 INFO - PROCESS | 8896 | 18:35:52 INFO - PROCESS | 8896 | Cycle 1(1): loaded http://localhost:43923/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43923/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:35:52 INFO - PROCESS | 8896 | RSS: Main: 179691520 18:35:52 INFO - PROCESS | 8896 | 18:35:55 INFO - PROCESS | 8896 | Cycle 1(1): loaded http://localhost:43923/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43923/tests/kraken/driver.html?testName=imaging-darkroom) 18:35:56 INFO - PROCESS | 8896 | RSS: Main: 215601152 18:35:56 INFO - PROCESS | 8896 | 18:35:59 INFO - PROCESS | 8896 | Cycle 1(1): loaded http://localhost:43923/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43923/tests/kraken/driver.html?testName=imaging-desaturate) 18:36:00 INFO - PROCESS | 8896 | RSS: Main: 216801280 18:36:00 INFO - PROCESS | 8896 | 18:36:03 INFO - PROCESS | 8896 | Cycle 1(1): loaded http://localhost:43923/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43923/tests/kraken/driver.html?testName=json-parse-financial) 18:36:03 INFO - PROCESS | 8896 | RSS: Main: 216735744 18:36:03 INFO - PROCESS | 8896 | 18:36:05 INFO - PROCESS | 8896 | Cycle 1(1): loaded http://localhost:43923/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43923/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:36:06 INFO - PROCESS | 8896 | RSS: Main: 203493376 18:36:06 INFO - PROCESS | 8896 | 18:36:08 INFO - PROCESS | 8896 | Cycle 1(1): loaded http://localhost:43923/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43923/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:36:08 INFO - PROCESS | 8896 | RSS: Main: 207847424 18:36:08 INFO - PROCESS | 8896 | 18:36:10 INFO - PROCESS | 8896 | Cycle 1(1): loaded http://localhost:43923/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43923/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:36:11 INFO - PROCESS | 8896 | RSS: Main: 217763840 18:36:11 INFO - PROCESS | 8896 | 18:36:14 INFO - PROCESS | 8896 | Cycle 1(1): loaded http://localhost:43923/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43923/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:36:14 INFO - PROCESS | 8896 | RSS: Main: 225988608 18:36:14 INFO - PROCESS | 8896 | 18:36:17 INFO - PROCESS | 8896 | Cycle 1(1): loaded http://localhost:43923/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43923/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:36:18 INFO - PROCESS | 8896 | RSS: Main: 221646848 18:36:18 INFO - PROCESS | 8896 | 18:36:20 INFO - PROCESS | 8896 | Cycle 1(1): loaded http://localhost:43923/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43923/tests/kraken/driver.html?testName=ai-astar) 18:36:20 INFO - PROCESS | 8896 | RSS: Main: 285003776 18:36:20 INFO - PROCESS | 8896 | 18:36:20 INFO - PROCESS | 8896 | __start_tp_report 18:36:20 INFO - PROCESS | 8896 | _x_x_mozilla_page_load 18:36:20 INFO - PROCESS | 8896 | _x_x_mozilla_page_load_details 18:36:20 INFO - PROCESS | 8896 | |i|pagename|runs| 18:36:20 INFO - PROCESS | 8896 | |0;ai-astar;112;113;112;113;116;113;103;114;115;114 18:36:20 INFO - PROCESS | 8896 | |1;audio-beat-detection;154;139;156;186;136;161;168;134;191;131 18:36:20 INFO - PROCESS | 8896 | |2;audio-dft;162;161;160;161;160;160;160;160;163;163 18:36:20 INFO - PROCESS | 8896 | |3;audio-fft;77;75;131;106;75;77;74;114;100;77 18:36:20 INFO - PROCESS | 8896 | |4;audio-oscillator;95;94;147;94;94;93;151;94;93;141 18:36:20 INFO - PROCESS | 8896 | |5;imaging-gaussian-blur;118;117;117;116;117;117;116;116;117;117 18:36:20 INFO - PROCESS | 8896 | |6;imaging-darkroom;146;146;146;147;145;146;145;146;145;146 18:36:20 INFO - PROCESS | 8896 | |7;imaging-desaturate;95;94;95;94;94;95;94;97;94;94 18:36:20 INFO - PROCESS | 8896 | |8;json-parse-financial;66;66;68;65;67;67;68;86;64;66 18:36:20 INFO - PROCESS | 8896 | |9;json-stringify-tinderbox;66;65;64;64;64;64;66;65;64;65 18:36:20 INFO - PROCESS | 8896 | |10;stanford-crypto-aes;81;80;85;80;81;83;82;80;80;80 18:36:20 INFO - PROCESS | 8896 | |11;stanford-crypto-ccm;131;129;130;129;130;132;128;130;164;132 18:36:20 INFO - PROCESS | 8896 | |12;stanford-crypto-pbkdf2;180;176;190;177;177;178;180;185;175;183 18:36:20 INFO - PROCESS | 8896 | |13;stanford-crypto-sha256-iterative;75;79;75;76;76;76;72;73;72;71 18:36:20 INFO - PROCESS | 8896 | __end_tp_report 18:36:20 INFO - PROCESS | 8896 | __start_cc_report 18:36:20 INFO - PROCESS | 8896 | _x_x_mozilla_cycle_collect,1912 18:36:20 INFO - PROCESS | 8896 | __end_cc_report 18:36:20 INFO - PROCESS | 8896 | __startTimestamp1461634580552__endTimestamp 18:36:20 INFO - PROCESS | 8896 | 18:36:20 INFO - PROCESS | 8896 | ------- Summary: start ------- 18:36:20 INFO - PROCESS | 8896 | Number of tests: 14 18:36:20 INFO - PROCESS | 8896 | 18:36:20 INFO - PROCESS | 8896 | [#0] ai-astar Cycles:10 Average:112.50 Median:113.50 stddev:3.57 (3.1%) stddev-sans-first:3.78 18:36:20 INFO - PROCESS | 8896 | Values: 112.0 113.0 112.0 113.0 116.0 113.0 103.0 114.0 115.0 114.0 18:36:20 INFO - PROCESS | 8896 | 18:36:20 INFO - PROCESS | 8896 | [#1] audio-beat-detection Cycles:10 Average:155.60 Median:158.50 stddev:21.33 (13.5%) stddev-sans-first:22.62 18:36:20 INFO - PROCESS | 8896 | Values: 154.0 139.0 156.0 186.0 136.0 161.0 168.0 134.0 191.0 131.0 18:36:20 INFO - PROCESS | 8896 | 18:36:20 INFO - PROCESS | 8896 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.25 (0.8%) stddev-sans-first:1.27 18:36:20 INFO - PROCESS | 8896 | Values: 162.0 161.0 160.0 161.0 160.0 160.0 160.0 160.0 163.0 163.0 18:36:20 INFO - PROCESS | 8896 | 18:36:20 INFO - PROCESS | 8896 | [#3] audio-fft Cycles:10 Average:90.60 Median:75.00 stddev:20.61 (27.5%) stddev-sans-first:21.26 18:36:20 INFO - PROCESS | 8896 | Values: 77.0 75.0 131.0 106.0 75.0 77.0 74.0 114.0 100.0 77.0 18:36:20 INFO - PROCESS | 8896 | 18:36:20 INFO - PROCESS | 8896 | [#4] audio-oscillator Cycles:10 Average:109.60 Median:94.00 stddev:25.47 (27.1%) stddev-sans-first:26.46 18:36:20 INFO - PROCESS | 8896 | Values: 95.0 94.0 147.0 94.0 94.0 93.0 151.0 94.0 93.0 141.0 18:36:20 INFO - PROCESS | 8896 | 18:36:20 INFO - PROCESS | 8896 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.50 18:36:20 INFO - PROCESS | 8896 | Values: 118.0 117.0 117.0 116.0 117.0 117.0 116.0 116.0 117.0 117.0 18:36:20 INFO - PROCESS | 8896 | 18:36:20 INFO - PROCESS | 8896 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 18:36:20 INFO - PROCESS | 8896 | Values: 146.0 146.0 146.0 147.0 145.0 146.0 145.0 146.0 145.0 146.0 18:36:20 INFO - PROCESS | 8896 | 18:36:20 INFO - PROCESS | 8896 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:94.50 stddev:0.97 (1.0%) stddev-sans-first:1.01 18:36:20 INFO - PROCESS | 8896 | Values: 95.0 94.0 95.0 94.0 94.0 95.0 94.0 97.0 94.0 94.0 18:36:20 INFO - PROCESS | 8896 | 18:36:20 INFO - PROCESS | 8896 | [#8] json-parse-financial Cycles:10 Average:68.30 Median:67.00 stddev:6.34 (9.5%) stddev-sans-first:6.67 18:36:20 INFO - PROCESS | 8896 | Values: 66.0 66.0 68.0 65.0 67.0 67.0 68.0 86.0 64.0 66.0 18:36:20 INFO - PROCESS | 8896 | 18:36:20 INFO - PROCESS | 8896 | [#9] json-stringify-tinderbox Cycles:10 Average:64.70 Median:65.00 stddev:0.82 (1.3%) stddev-sans-first:0.73 18:36:20 INFO - PROCESS | 8896 | Values: 66.0 65.0 64.0 64.0 64.0 64.0 66.0 65.0 64.0 65.0 18:36:20 INFO - PROCESS | 8896 | 18:36:20 INFO - PROCESS | 8896 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:81.00 stddev:1.69 (2.1%) stddev-sans-first:1.79 18:36:20 INFO - PROCESS | 8896 | Values: 81.0 80.0 85.0 80.0 81.0 83.0 82.0 80.0 80.0 80.0 18:36:20 INFO - PROCESS | 8896 | 18:36:20 INFO - PROCESS | 8896 | [#11] stanford-crypto-ccm Cycles:10 Average:133.50 Median:130.50 stddev:10.79 (8.3%) stddev-sans-first:11.41 18:36:20 INFO - PROCESS | 8896 | Values: 131.0 129.0 130.0 129.0 130.0 132.0 128.0 130.0 164.0 132.0 18:36:20 INFO - PROCESS | 8896 | 18:36:20 INFO - PROCESS | 8896 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.10 Median:180.00 stddev:4.68 (2.6%) stddev-sans-first:4.96 18:36:20 INFO - PROCESS | 8896 | Values: 180.0 176.0 190.0 177.0 177.0 178.0 180.0 185.0 175.0 183.0 18:36:20 INFO - PROCESS | 8896 | 18:36:20 INFO - PROCESS | 8896 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.50 Median:75.50 stddev:2.46 (3.3%) stddev-sans-first:2.60 18:36:20 INFO - PROCESS | 8896 | Values: 75.0 79.0 75.0 76.0 76.0 76.0 72.0 73.0 72.0 71.0 18:36:20 INFO - PROCESS | 8896 | -------- Summary: end -------- 18:36:20 INFO - PROCESS | 8896 | 18:36:21 INFO - TEST-INFO | 8896: exit 0 18:36:21 INFO - TEST-OK | kraken | took 63308ms 18:36:21 INFO - SUITE-END | took 1180s 18:36:21 INFO - Completed test suite (00:19:40) 18:36:21 INFO - javascript benchmark 18:36:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2561.410875498635, 2625.789936805057, 2642.273374821925, 2649.7482739139787, 2667.439837634097, 4473.552644735525, 4568.177728008957, 4575.4509458864895, 4581.45022597288, 4596.586307232345, 4514.52597477265, 4609.930851037228, 4676.532346765331, 4688.953110468894, 4699.154152252593, 4166.104287578185, 4215.578442155783, 4262.254105531535, 4326.891827704301, 4350.825966961318, 4288.849890253841, 4341.3705012773125, 4360.302351623741, 4401.229784787665, 4404.207242696313, 4102.917941641181, 4189.0, 4209.2633789086785, 4210.45264115666, 4291.54938731435, 4159.9792001039805, 4187.602177793105, 4192.517860446027, 4202.957970420318, 4234.957650423487, 4211.768352740598, 4239.02502424441, 4240.639545638624, 4266.2320782259185, 4277.465316835395, 4367.912641747147, 4460.1525710114975, 4495.14592227479, 4539.228331183706, 4571.268597024494, 4877.439094504142, 4904.0013909242125, 4904.337914381548, 4907.4356449008465, 4931.063098011402, 4905.901881962341, 4927.802887884481, 4934.950650493521, 4939.209726443752, 4939.950600493985, 4451.799669014902, 4508.594226519596, 4551.749653769075, 4582.587567118909, 4587.678862479661, 5035.7985680572365, 5044.167712327425, 5060.84817455477, 5062.949370506248, 5066.700999276982, 4719.787609557578, 4853.878653033702, 4858.514148585184, 4860.149473842063, 4889.535494128123, 4847.079054979613, 4890.290907818347, 4896.706197628154, 4930.5069493050405, 4938.481459446706, 4884.853454396374, 4919.065377578248, 4924.778384972685, 4924.778384972685, 4969.503049695001, 4671.579557839743, 4865.610751139971, 4868.123737727172, 4868.829590964299, 4917.065757506062, 4135.152293779768, 4152.356384760367, 4187.748735075845, 4187.895302617459, 4198.85304014358, 3637.727170462226, 3654.3787556114994, 3654.7076233901757, 3685.6867166291167, 3691.6492933171307, 4077.938830917539, 4143.37849322598, 4146.357314616239, 4160.063985603215, 4213.705040647125, 4812.5668689818085, 4854.781534830941, 4939.506049395031, 4940.135476291634, 4980.750962451935, 4153.169366126786, 4195.517515485697, 4197.811098500575, 4238.830446782156, 4239.1521695660995, 4274.807633656493, 4420.756858372821, 4421.834866960885, 4424.84513042042, 4454.710443821206, 4657.930131048037, 4660.813567457264, 4676.859694209179, 4686.179918514178, 4723.031778485402, 4954.157793175241, 4958.206686930074, 4959.62802789785, 4961.479044700254, 4962.776675049704, 4505.414296141481, 4612.7001744886475, 4677.48547659764, 4679.883002924954, 4687.460941991578, 4764.809407623657, 4787.54518320759, 4787.664863459524, 4796.160671882476, 4834.709917404967, 4814.903701925872, 4846.176150054429, 4846.781894814671, 4851.272309153655, 4898.069366820293, 4557.0, 4657.883552911204, 4726.385569875894, 4742.241241401359, 4751.382320298339, 4798.328234047166, 4812.8796780080775, 4831.106245344622, 4837.250226214942, 4872.634552408513, 4754.928676069792, 4757.09615173116, 4762.261849413347, 4765.0946320199055, 4791.377120974391, 4576.931346029879, 4752.144613969519, 4827.227643577011, 4840.878978025577, 4844.8788780279365, 4696.600788932842, 4701.576858082789, 4719.5752382285755, 4722.669413141047, 4767.61859051282], "value": 4507.500720612035, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.1803520965018, 591.5385998920838, 591.6657088744859, 602.445749910083, 609.4301827791014, 27211.455770884597, 27868.44263114739, 34552.48171277429, 35345.0, 35379.64620353796, 41607.167856642845, 42363.78818105913, 42380.57619423805, 42382.15235695284, 42397.36403953943, 7749.806254843632, 7930.563818989954, 7955.64199611016, 7964.5619490927975, 7968.482048666847, 7764.728234511793, 7848.45060845741, 7887.645055972495, 7899.644515996765, 7903.8419231615335, 7904.367650587939, 7958.368534450424, 7960.402969777261, 7968.721094761684, 7969.681212751483, 7923.524588524707, 7989.6404661789775, 7996.160403157701, 8000.63997120131, 8001.959990200041, 7774.144844067148, 7915.604219788987, 7921.20787921209, 7921.722739704141, 7922.485038472539, 38325.23349533021, 38924.0, 38951.805240973896, 38973.415398769044, 39074.0, 3052.6184226971627, 3180.8568614412407, 3322.80063196209, 3333.233356328034, 3520.5775306963073, 28587.285317867012, 28887.277818054506, 29445.41109177826, 29554.852225738734, 30554.38891222163, 3278.688524590164, 3282.7373810095137, 3283.4910588858525, 3428.9485657714904, 3433.2275238071484, 236869.0, 236910.0, 237752.0, 238144.0, 238149.0, 41723.7913810429, 42830.357544636856, 47182.5281747185, 53975.730121349145, 54275.72862135664, 51495.48504514982, 51613.0, 51661.74169129205, 51722.48277517177, 51748.0, 13838.169709817443, 13971.371288292052, 14077.648058798612, 14082.647933801532, 14091.506797262047, 10127.848082278771, 10178.083972442515, 10316.1747060235, 10383.376997380181, 10390.844137337946, 17122.914385427994, 17174.484765457055, 17205.56986075358, 17296.827031729772, 17428.738568921475, 5814.226660868476, 5912.70436478183, 5952.315483719428, 5991.910121348184, 6076.513878889767, 919.8620206969008, 956.7560272130702, 961.3558915526614, 978.5889926231, 1000.46475135802, 27530.587041194398, 28511.58698738343, 28625.284367890552, 28690.856545717554, 29173.85413072921, 963.3208587945481, 969.0745338202141, 986.5781783963266, 1017.2980643356061, 1030.1810061001522, 14949.17779522115, 15317.770233446507, 15484.922575387276, 15550.455734049254, 15568.688626227413, 300048.0, 304218.0, 304279.0, 304611.0, 304942.0, 49125.0, 49446.50553494491, 49549.25676114861, 49591.75204123956, 49605.50394496081, 1224.797908345131, 1237.2143688757703, 1264.2477725753347, 1286.0933042205222, 1298.1497119386818, 25690.486190276464, 27710.0, 27822.165335039983, 28189.43621127607, 28191.43617127687, 39083.0, 39362.606373935894, 39545.45227298897, 39551.40672889967, 39674.40488392734, 231697.0, 233279.0, 233943.0, 234417.0, 234528.0, 28497.715022850334, 33576.16059598432, 33853.66146338505, 34050.65949340574, 34169.60812743084, 36711.449328259565, 37241.6275837249, 37494.625053749114, 37747.245055099294, 38076.809615951744, 31491.0, 31844.0, 33022.33955320928, 33314.833425832716, 33603.66396336005, 32724.83637581797, 32872.0, 33376.332473350885, 34045.65954340524, 35726.821365893, 34847.65152348444, 35051.82474087613, 35266.29467410689, 35313.47029794504, 35624.64375356213, 31688.0, 32433.189170270933, 32723.672763273014, 32933.50599741054, 32933.83533082319, 24859.62710559307, 24908.252752417455, 25235.495290094463, 25373.0, 25561.87219063893], "value": 17042.53924642603, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.3248018527022, 384.86722080882083, 389.85770193879233, 393.6063936063936, 397.7633308181633, 15885.205739713027, 17593.3842315519, 17825.108744562753, 18025.369112081047, 18069.1868865901, 16761.66476670466, 16790.160491975414, 17219.91390043048, 17588.736168957475, 17620.383286584973, 5065.113605119108, 5184.844454666366, 5194.298769666103, 5219.947800521984, 5239.292695486118, 3704.7962362070075, 3920.7451515651533, 5842.298924129111, 6159.907601385983, 6162.260528736536, 5969.253843269591, 6098.908516372258, 6102.908456373158, 6121.204243448367, 6136.202293701813, 5798.768049278045, 5948.167256584038, 5963.344032156459, 5977.701114944257, 5978.910316345258, 3398.609159946613, 5111.0, 5370.248165256867, 5381.7578208980685, 5382.596305277119, 20539.897300513552, 20707.896460517753, 20889.37331880046, 20955.0, 20978.475538111517, 1753.3863147898262, 1864.9906750466296, 1905.3617038292189, 1958.285225892539, 1967.931122410723, 9172.312076594282, 9537.618495260182, 10256.435896025716, 10356.430396328198, 13218.07473476857, 1705.326396073544, 1731.471901070173, 1793.0407232130879, 1804.7653805005395, 1813.274690123961, 69030.0, 70535.0, 71275.0, 71383.28616713818, 72443.0, 10191.82793978702, 10222.386656800616, 10228.386296822215, 10249.231307651808, 10453.68638940833, 14313.92843035799, 14355.425782968774, 14380.856191437952, 14394.496192633207, 14419.711605767827, 7309.378702810321, 7564.621768911643, 7575.356094732009, 7626.69493220276, 7692.73075442368, 7075.858482830417, 7131.71473141079, 7280.890786638205, 7319.853602927911, 7349.8162545936775, 4380.474343078851, 4447.577480139382, 4453.1761624099645, 4515.729056256635, 4549.771561678328, 1707.795064592257, 1824.14265295311, 1833.0834582708646, 1881.0782716468832, 1903.6287923854993, 837.2715737308581, 905.7282815155427, 913.7669894177076, 950.5627411390699, 988.2341185581089, 15839.604009899615, 16010.279537420844, 16269.0, 16344.918275408785, 16547.586310342336, 806.8265322955475, 899.5097671768901, 904.4030939579845, 921.2767977138049, 921.6589861751195, 7439.51643143194, 9749.707508774749, 9857.901420985841, 9886.950565247127, 9986.151177149879, 358035.0, 368399.0, 369768.0, 381440.0, 384427.0, 78910.0, 79683.60158199287, 81776.59111704404, 82384.58807706043, 82890.17109828946, 973.9172170365598, 986.5412583149014, 997.6159178716101, 1007.6221416968637, 1012.8379459286479, 15238.695226095639, 15325.46360877364, 15439.768403473732, 15461.458848940232, 15708.68582628364, 34804.65195348014, 34826.651733482344, 34962.300753985284, 35150.64849351474, 35471.29057418889, 49702.751486242334, 50775.2383714252, 50847.0, 51096.0, 52136.0, 8024.518528888286, 8158.877616835871, 8173.468724532886, 8246.83506329858, 8357.832843343222, 11108.111351092055, 11501.53993840237, 11710.88289117132, 12172.39138043112, 12190.51237950472, 10009.54957026921, 10346.637867674595, 10539.736506587396, 10861.34831910088, 10916.12670986335, 9938.900610993798, 9950.651727189514, 9957.701268961942, 10043.347182433119, 10092.192624589869, 10113.230184717499, 10265.948670256601, 10604.0, 10633.308834925705, 11079.612213572485, 7448.813779655552, 7680.6543705534505, 7698.153203147537, 7834.4907581007055, 7960.562169080757, 8056.395770317366, 10373.740656483647, 10499.107575855833, 10556.102731267929, 10654.946725266323], "value": 8367.110616331713, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1198.8381568488262, 1425.7219842130783, 1475.0854470228458, 1563.921803909805, 1765.9691354941824, 7141.357277845, 7415.332620064193, 7416.184219735825, 7421.480496365257, 7480.251974802517, 7233.348998590126, 7406.925930740691, 7425.554466732, 7435.330820226179, 7440.181580026193, 1780.163323238076, 1826.9543261418437, 1828.31438210671, 2204.48194674251, 2235.854669446489, 2199.8570092943987, 2279.7036385269976, 2283.0, 2283.235116236063, 2283.851549649276, 2183.770704076073, 2199.098369668436, 2211.380813372258, 2212.811910987568, 2229.052652622637, 1800.0, 2125.968110478344, 2183.366823621156, 2191.1783081344415, 2192.0683709423433, 2190.6494960806353, 2221.7778222177653, 2222.0556263588073, 2224.610693128696, 2226.109556177526, 7095.254998225164, 7134.108236470442, 7142.892856607155, 7142.892856607155, 7146.464015198829, 4338.110687309094, 4449.26587113126, 4469.865904022885, 4473.642108631302, 4474.7762611869275, 2692.7711144552736, 2699.2050286559415, 2825.307799589093, 2825.3784167483122, 2853.771698264134, 891.4963045879123, 892.5091199840127, 893.3210759822582, 915.3043686798015, 923.5243849417556, 10061.245406594535, 10241.897581024168, 10247.282690211689, 10264.127549158331, 10265.538050787807, 4126.463559737215, 4180.895477613084, 4308.612224899774, 4328.8484903028875, 4339.674524410681, 8868.201861832466, 8892.510911899908, 8929.910700893039, 9005.459672419676, 9009.459432434074, 4655.068986202773, 4658.254679251304, 4661.370714953506, 4667.6265898728025, 4697.084068606658, 3566.268914872445, 3623.3477973964414, 4161.521425036099, 4223.408722778813, 4272.743635381825, 3575.606683264839, 3584.2652256287397, 3681.447782832543, 3712.461693054546, 3716.814159292079, 1158.25871442277, 1173.061550759389, 1184.141497414385, 1195.7967145585271, 1198.4547031100828, 687.9071325371111, 786.732510946281, 801.9157988411249, 838.0, 844.9450785698909, 4839.370881785346, 5041.697498150123, 5053.898922021539, 5065.8733531662, 5134.614903882224, 880.8634661627458, 910.3127139010137, 911.7811725185908, 927.8700981862545, 928.8281667891462, 3294.1270563300745, 3368.7473439492137, 3382.5771778527683, 3399.8810041648426, 3463.0649724574223, 9033.232175265044, 9226.58480368385, 9370.95314523423, 9474.857877131848, 9476.194523465445, 9206.723798285928, 9328.580213890391, 9349.298802589832, 9349.532523373804, 9356.812863742687, 7016.158061032709, 7194.38847697941, 7201.495895287384, 7213.0, 7234.131904171428, 4806.44725856517, 4928.827491037797, 4983.451820299837, 5056.873578160428, 5062.063518249136, 8466.957665211634, 8697.956510217662, 8747.16901894319, 8786.868196977179, 8797.076306987674, 8451.830963380575, 8737.694180703646, 8748.562571871254, 8861.069587693199, 8891.199792018491, 5301.681899085913, 6329.715162817776, 6784.830379240559, 7100.254473280438, 7171.713131474683, 6942.826429339307, 6982.197047339621, 7009.228984811564, 7018.192907815463, 7019.4033507150425, 6496.155499784998, 6618.470522358296, 6651.16860392451, 6729.798106056826, 6765.898511522233, 6483.513736469689, 6492.091107244895, 6591.176102987127, 6619.371159739817, 6651.63416012124, 6323.9683801580695, 6371.585846919936, 6375.171227740364, 6382.425581697669, 6400.903986440114, 4193.245215861174, 4343.153085148366, 4352.434183556117, 4442.51132375432, 4514.616257618008, 4369.584889435499, 4433.046894917608, 4452.842261012225, 4503.009337945647, 4586.518415566317], "value": 4037.779184381532, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.12850554655364, 303.33266813011375, 307.2334525359228, 307.53562121197, 312.7169911230337, 4619.8152073917, 4675.672702910798, 4677.555632214934, 4703.824043988999, 4709.411323584552, 4594.40272764541, 4620.90758184837, 4628.0, 4634.513376095512, 4645.698029628072, 3495.615482296945, 3552.236269202121, 3580.319739249547, 3684.778913265199, 3711.3876210425246, 3677.687396571281, 3689.797061161635, 3691.4647376130447, 3698.130939229279, 3710.9072273193115, 3472.2881809229175, 3637.7453578249533, 3639.7088232941433, 3682.1346983458866, 3682.9447558286647, 3554.8933531993953, 3684.9631503684886, 3693.7968411737343, 3708.332500149972, 3728.328900797855, 3266.7713260071805, 3277.4100661880852, 3290.7696461247724, 3300.6204286506877, 3301.4387554115856, 4519.615832654228, 4527.392775564693, 4534.344964088084, 4539.977300113512, 4555.2256116460285, 3984.880453586397, 4167.416561681367, 4232.809523571416, 4235.449391579075, 4240.8761678155315, 4829.903401931977, 4847.95152048482, 4854.538818812207, 4865.756712164378, 4875.219964805614, 3603.7297202709638, 3608.7654302470255, 3611.0972256935765, 3615.5299811024397, 3616.6383361663884, 4940.901181976376, 5008.474110218411, 5058.190689489701, 5091.821786237501, 5100.5409513143995, 3660.0, 3668.596454390015, 3673.577538583044, 3691.741578089508, 3694.6305369463375, 3706.666400023957, 3716.21959388526, 3727.8499667780684, 3738.5700644425697, 3749.268892565978, 4166.66666666666, 4167.270727622654, 4171.958280417157, 4185.790710464526, 4192.45498085247, 4599.264117741145, 4871.732054736953, 4881.8535443936735, 4890.144224760653, 4935.90128197434, 1365.3514580574147, 1367.6580854786303, 1369.5959691890916, 1371.746226948018, 1374.1274290825197, 882.761654353321, 907.6596276396351, 910.5811326789751, 911.7720569857536, 939.3471537281524, 4240.85157019509, 4318.935215971763, 4321.805518751664, 4337.6963612546815, 4365.323374876899, 5023.14606516893, 5023.82416615424, 5026.52248036436, 5047.3911153525405, 5048.873778155576, 4190.150697525712, 4291.978540107342, 4296.656267498593, 4322.459692538433, 4339.9403420588405, 2829.4199689063694, 2837.0, 2838.3329917469378, 2852.6434195725533, 2864.255293623631, 5001.0, 5014.949850501521, 5044.209305696499, 5048.62135339851, 5056.241563765465, 5303.920441193385, 5456.563474921997, 5468.7265636718, 5478.780848766005, 5502.80740174092, 4988.80044798204, 4998.175301075282, 5022.949770502321, 5041.873953151201, 5054.368203974504, 5323.547498462519, 5325.760340784596, 5325.840224793263, 5339.733013349394, 5343.465653434625, 5061.721605311744, 5182.792688292426, 5245.160774276097, 5253.900198882678, 5268.420473747961, 4986.32684587576, 5019.222020586815, 5027.547520723152, 5028.446870844131, 5059.46875578059, 3848.461215429898, 3855.3445914194094, 3864.2271545690414, 3875.108724993211, 3878.340682083996, 4813.831515896927, 5054.974725126351, 5074.543291103819, 5356.839294821161, 5363.383210930637, 4821.421429428491, 4867.61059115277, 4881.877168251251, 4885.975570122127, 4900.387451568554, 4872.488388719134, 4876.341693871282, 4883.755812209446, 4915.238138088602, 4916.483769204325, 4583.702059366131, 4816.301636262792, 4835.540623640749, 4838.225883858566, 4851.80592776285, 4672.48602653701, 4762.666613337033, 4923.286123512143, 4941.456439791692, 4947.208446648519, 4798.520147985174, 4821.108095002436, 4848.175810112219, 4854.975725121351, 4863.489333619919], "value": 3777.85213371581, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.6466766616692, 793.5080250244841, 799.9760007199783, 802.3340627279363, 807.4872455990446, 11942.940285298571, 12439.378031098442, 12647.936760316197, 12664.11351205416, 12669.746605067892, 11772.47023883925, 11801.527938882457, 11837.704057398569, 11859.466324015419, 11913.225640333372, 636.707114727226, 786.6106277392698, 794.3208556684039, 802.0575823407502, 803.0884945586743, 805.5448671500612, 810.4569938141459, 810.8256724804166, 812.2405550809982, 812.3379445751723, 859.0893652728097, 866.5667166416791, 866.757307953774, 867.0245973280059, 867.3364875869964, 663.0584569910739, 828.8135169586794, 853.8463076646201, 858.7295002074358, 859.4198915731887, 622.0234232255361, 624.017172952597, 624.996875015622, 625.0624063904144, 626.2047200055949, 12162.148649594532, 12226.14416990811, 12227.755444891141, 12233.510659573607, 12242.143049986504, 11286.379249141293, 11482.138839587064, 11894.286342819458, 11941.164118511708, 12699.04757143218, 11145.609903653332, 11157.0, 11259.718507037307, 11382.71543211418, 11414.600488982845, 4234.153169366139, 4269.146170765828, 4281.079567892918, 4310.676699247569, 4353.477582690066, 41459.1708165835, 42878.35682464766, 42995.57004429947, 43089.0, 43544.99999999968, 9696.0, 9735.756606084904, 10416.749990001097, 10425.113865321533, 10432.165426765841, 17607.29570817153, 17769.11154442273, 17835.73246401305, 17857.910710446624, 17872.28510859577, 6631.204255489372, 6873.347032032049, 7088.893666594902, 7098.9290107099305, 7135.607541585159, 3633.6003039665616, 3717.9442308365396, 3718.1819999600043, 3758.398656215047, 3888.727789054739, 2853.144056782998, 2909.810862293944, 2912.6504819421807, 2920.7371336579386, 2921.415716856637, 527.5146864884316, 527.7625068719092, 527.8416475057544, 533.3306700091438, 536.1769683535754, 473.07277735638536, 521.2754271562532, 569.9515541178963, 572.9286234740978, 574.7815829984579, 7843.294103530709, 8015.719449819228, 8245.381596380295, 8454.61954212062, 8480.236778689947, 470.282818701483, 473.81047580967197, 563.830052640772, 588.4292236530558, 601.1613798750806, 3969.8610548631236, 4003.159336539301, 4041.7979101045416, 4044.2315959967514, 4136.482939632546, 50111.0, 50432.495675043516, 50970.74514627403, 52185.2172217417, 52561.211581826305, 42163.57836421658, 43642.78178609087, 44421.77789111034, 44805.0, 45091.0, 38862.41706374407, 40004.99925011271, 42487.57512424898, 43741.99999999937, 44512.0, 8131.2681858633005, 8236.299914507334, 8240.587970601566, 8257.83484330322, 8387.119352467904, 27096.72903270942, 27295.590566141127, 28877.855610721814, 29532.704672952994, 31061.53407698841, 37795.43306850454, 37861.0, 37866.432003520524, 38094.0, 38547.229055419295, 10730.678079657622, 11049.447527623426, 11049.944750276198, 11114.055305298816, 11186.720331991766, 11190.134672085282, 11549.78727233662, 11561.595344162914, 12195.207311524722, 12633.418390142517, 10635.734106647396, 10670.57317707283, 10958.671239862817, 11537.0, 11765.588204412805, 10919.290246133976, 11012.889871101506, 11460.369679667365, 11845.881541184477, 11911.166218364631, 11249.66251012471, 11316.717082073013, 11552.768944621228, 11802.0, 11835.34905580167, 9152.17630413266, 9251.149660338951, 9605.759856003655, 9724.367916085432, 9993.10062094415, 9006.351076415853, 9427.15155635996, 9711.125998659872, 9717.222622190066, 9818.116369526777], "value": 5994.005519820848, "unit": "score"}], "name": "dromaeo_css", "value": 6235.436279103682, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 112.0, 113.0, 116.0, 113.0, 103.0, 114.0, 115.0, 114.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 139.0, 156.0, 186.0, 136.0, 161.0, 168.0, 134.0, 191.0, 131.0], "value": 155.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 160.0, 161.0, 160.0, 160.0, 160.0, 160.0, 163.0, 163.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 131.0, 106.0, 75.0, 77.0, 74.0, 114.0, 100.0, 77.0], "value": 90.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 147.0, 94.0, 94.0, 93.0, 151.0, 94.0, 93.0, 141.0], "value": 109.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 145.0, 146.0, 145.0, 146.0, 145.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 97.0, 94.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 68.0, 65.0, 67.0, 67.0, 68.0, 86.0, 64.0, 66.0], "value": 68.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 64.0, 64.0, 64.0, 64.0, 66.0, 65.0, 64.0, 65.0], "value": 64.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 85.0, 80.0, 81.0, 83.0, 82.0, 80.0, 80.0, 80.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 129.0, 130.0, 129.0, 130.0, 132.0, 128.0, 130.0, 164.0, 132.0], "value": 133.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 176.0, 190.0, 177.0, 177.0, 178.0, 180.0, 185.0, 175.0, 183.0], "value": 180.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 79.0, 75.0, 76.0, 76.0, 76.0, 72.0, 73.0, 72.0, 71.0], "value": 74.5, "unit": "score"}], "name": "kraken", "value": 1588.8000000000002, "alertThreshold": 2.0}]} 18:36:21 INFO - Return code: 0 18:36:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:36:21 ERROR - # TBPL SUCCESS # 18:36:21 INFO - Running post-action listener: _resource_record_post_action 18:36:21 INFO - Running post-run listener: _resource_record_post_run 18:36:22 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 517976064; Read time: 16; Write time: 456528 18:36:22 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 234704896; Read time: 0; Write time: 261464 18:36:22 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 4096; Write bytes: 283271168; Read time: 16; Write time: 195064 18:36:22 INFO - Running post-run listener: _upload_blobber_files 18:36:22 INFO - Blob upload gear active. 18:36:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:36:22 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:36:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:36:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:36:23 INFO - (blobuploader) - INFO - Open directory for files ... 18:36:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:36:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:36:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:36:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:36:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:36:24 INFO - (blobuploader) - INFO - Done attempting. 18:36:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:36:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:36:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:36:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:36:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:36:24 INFO - (blobuploader) - INFO - Done attempting. 18:36:24 INFO - (blobuploader) - INFO - Iteration through files over. 18:36:24 INFO - Return code: 0 18:36:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:36:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:36:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e0e2dc074daac8f91366b8c500286d8c22ef9bc00d03fef9d6a9ee4fb5d7c2002b544fe849ce8d03974277e0177a8c31d98f03f7aa72be72541b76867e979d0b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/05127bb66b9dd7f72ef9aedd5a560b0511b3ed36210d3d4d34ba88ee4435d1867ad7effe4c301265ff9d92493b76b55996eabc3b190ef2660db2e3065f01a964"} 18:36:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:36:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:36:24 INFO - Contents: 18:36:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e0e2dc074daac8f91366b8c500286d8c22ef9bc00d03fef9d6a9ee4fb5d7c2002b544fe849ce8d03974277e0177a8c31d98f03f7aa72be72541b76867e979d0b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/05127bb66b9dd7f72ef9aedd5a560b0511b3ed36210d3d4d34ba88ee4435d1867ad7effe4c301265ff9d92493b76b55996eabc3b190ef2660db2e3065f01a964"} 18:36:24 INFO - Running post-run listener: copy_logs_to_upload_dir 18:36:24 INFO - Copying logs to upload dir... 18:36:24 INFO - mkdir: /builds/slave/test/build/upload/logs 18:36:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.197179 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-04-25 18:36:24.833499) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 18:36:24.837144) ========= 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=db0483c52d987ecb441a263b000001ad-1461629914.105575-1098230120 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e0e2dc074daac8f91366b8c500286d8c22ef9bc00d03fef9d6a9ee4fb5d7c2002b544fe849ce8d03974277e0177a8c31d98f03f7aa72be72541b76867e979d0b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/05127bb66b9dd7f72ef9aedd5a560b0511b3ed36210d3d4d34ba88ee4435d1867ad7effe4c301265ff9d92493b76b55996eabc3b190ef2660db2e3065f01a964"} build_url:https://queue.taskcluster.net/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011216 build_url: 'https://queue.taskcluster.net/v1/task/RGrZ9dVsSKaGWFzOYD0zMw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e0e2dc074daac8f91366b8c500286d8c22ef9bc00d03fef9d6a9ee4fb5d7c2002b544fe849ce8d03974277e0177a8c31d98f03f7aa72be72541b76867e979d0b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/05127bb66b9dd7f72ef9aedd5a560b0511b3ed36210d3d4d34ba88ee4435d1867ad7effe4c301265ff9d92493b76b55996eabc3b190ef2660db2e3065f01a964"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 18:36:24.861486) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 18:36:24.861814) ========= 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=db0483c52d987ecb441a263b000001ad-1461629914.105575-1098230120 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004497 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 18:36:24.943668) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 18:36:24.943987) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 18:36:24.944317) ========= ========= Total master_lag: 0.17 =========