builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-009 starttime: 1462980683.92 results: success (0) buildid: 20160511030221 builduid: b506c7b4dc59436f95acca86e65699f1 revision: 674a552743785c28c75866969aad513bd8eaf6ae ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 08:31:23.922662) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 08:31:23.923163) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 08:31:23.923439) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 08:31:23.932359) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 08:31:23.932765) ========= 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=aa52b8aba932b19cc50b5275000001de-1462980683.677055-2137373613 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-11 08:31:24-- 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% 169M=0s 2016-05-11 08:31:24 (169 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.065864 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 08:31:24.006654) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 08:31:24.006979) ========= 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=aa52b8aba932b19cc50b5275000001de-1462980683.677055-2137373613 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.009493 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 08:31:24.053495) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 08:31:24.053855) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 674a552743785c28c75866969aad513bd8eaf6ae --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 674a552743785c28c75866969aad513bd8eaf6ae --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=aa52b8aba932b19cc50b5275000001de-1462980683.677055-2137373613 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-11 08:31:24,532 truncating revision to first 12 chars 2016-05-11 08:31:24,532 Setting DEBUG logging. 2016-05-11 08:31:24,532 attempt 1/10 2016-05-11 08:31:24,532 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/674a55274378?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 08:31:25,907 unpacking tar archive at: mozilla-central-674a55274378/testing/mozharness/ program finished with exit code 0 elapsedTime=1.650404 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 08:31:25.714401) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 08:31:25.714964) ========= script_repo_revision: 674a552743785c28c75866969aad513bd8eaf6ae ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 08:31:25.715602) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 08:31:25.716085) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 08:31:25.723550) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 26 secs) (at 2016-05-11 08:31:25.724041) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=aa52b8aba932b19cc50b5275000001de-1462980683.677055-2137373613 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:31:26 INFO - MultiFileLogger online at 20160511 08:31:26 in /builds/slave/test 08:31:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:31:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:31:26 INFO - {'append_to_log': False, 08:31:26 INFO - 'base_work_dir': '/builds/slave/test', 08:31:26 INFO - 'blob_upload_branch': 'Firefox', 08:31:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:31:26 INFO - 'branch': 'Firefox', 08:31:26 INFO - 'buildbot_json_path': 'buildprops.json', 08:31:26 INFO - 'config_files': ('talos/linux_config.py',), 08:31:26 INFO - 'default_actions': ('clobber', 08:31:26 INFO - 'read-buildbot-config', 08:31:26 INFO - 'download-and-extract', 08:31:26 INFO - 'populate-webroot', 08:31:26 INFO - 'create-virtualenv', 08:31:26 INFO - 'install', 08:31:26 INFO - 'run-tests'), 08:31:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:31:26 INFO - 'download_minidump_stackwalk': True, 08:31:26 INFO - 'download_symbols': 'ondemand', 08:31:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:31:26 INFO - 'tooltool.py': '/tools/tooltool.py', 08:31:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:31:26 INFO - '/tools/misc-python/virtualenv.py')}, 08:31:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:31:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:31:26 INFO - 'installer_path': 'installer.exe', 08:31:26 INFO - 'log_level': 'info', 08:31:26 INFO - 'log_name': 'talos', 08:31:26 INFO - 'log_to_console': True, 08:31:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:31:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:31:26 INFO - 'opt_config_files': (), 08:31:26 INFO - 'pip_index': False, 08:31:26 INFO - 'sps_profile': False, 08:31:26 INFO - 'sps_profile_interval': 0, 08:31:26 INFO - 'suite': 'dromaeojs', 08:31:26 INFO - 'system_bits': '32', 08:31:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:31:26 INFO - 'title': 'talos-linux64-ix-009', 08:31:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:31:26 INFO - 'use_talos_json': True, 08:31:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:31:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:31:26 INFO - 'work_dir': 'build'} 08:31:26 INFO - ##### 08:31:26 INFO - ##### Running clobber step. 08:31:26 INFO - ##### 08:31:26 INFO - Running pre-action listener: _resource_record_pre_action 08:31:26 INFO - Running main action method: clobber 08:31:26 INFO - rmtree: /builds/slave/test/build 08:31:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:31:26 INFO - Running post-action listener: _resource_record_post_action 08:31:26 INFO - ##### 08:31:26 INFO - ##### Finished clobber step (success) 08:31:26 INFO - ##### 08:31:26 INFO - ##### 08:31:26 INFO - ##### Running read-buildbot-config step. 08:31:26 INFO - ##### 08:31:26 INFO - Running pre-action listener: _resource_record_pre_action 08:31:26 INFO - Running main action method: read_buildbot_config 08:31:26 INFO - Using buildbot properties: 08:31:26 INFO - { 08:31:26 INFO - "project": "", 08:31:26 INFO - "product": "firefox", 08:31:26 INFO - "script_repo_revision": "production", 08:31:26 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 08:31:26 INFO - "repository": "", 08:31:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 08:31:26 INFO - "buildid": "20160511030221", 08:31:26 INFO - "stage_platform": "linux64-pgo", 08:31:26 INFO - "basedir": "/builds/slave/test", 08:31:26 INFO - "nightly_build": "True", 08:31:26 INFO - "buildnumber": 768, 08:31:26 INFO - "slavename": "talos-linux64-ix-009", 08:31:26 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:31:26 INFO - "platform": "ubuntu64_hw", 08:31:26 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 08:31:26 INFO - "branch": "mozilla-central", 08:31:26 INFO - "repo_path": "mozilla-central", 08:31:26 INFO - "revision": "674a552743785c28c75866969aad513bd8eaf6ae", 08:31:26 INFO - "pgo_build": "True", 08:31:26 INFO - "builduid": "b506c7b4dc59436f95acca86e65699f1", 08:31:26 INFO - "slavebuilddir": "test" 08:31:26 INFO - } 08:31:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 08:31:26 INFO - Running post-action listener: _resource_record_post_action 08:31:26 INFO - ##### 08:31:26 INFO - ##### Finished read-buildbot-config step (success) 08:31:26 INFO - ##### 08:31:26 INFO - ##### 08:31:26 INFO - ##### Running download-and-extract step. 08:31:26 INFO - ##### 08:31:26 INFO - Running pre-action listener: _resource_record_pre_action 08:31:26 INFO - Running main action method: download_and_extract 08:31:26 INFO - mkdir: /builds/slave/test/build/tests 08:31:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:26 INFO - https://queue.taskcluster.net/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:31:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:31:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:31:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/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 08:31:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/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 08:31:27 INFO - Downloaded 1361 bytes. 08:31:27 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:31:27 INFO - Using the following test package requirements: 08:31:27 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 08:31:27 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:31:27 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:31:27 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:31:27 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:31:27 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:31:27 INFO - u'jsshell-linux-x86_64.zip'], 08:31:27 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:31:27 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:31:27 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 08:31:27 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:31:27 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:31:27 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:31:27 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:31:27 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:31:27 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:31:27 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:31:27 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:31:27 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:31:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:27 INFO - https://queue.taskcluster.net/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:31:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:31:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:31:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/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 08:31:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/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 08:31:28 INFO - Downloaded 23902834 bytes. 08:31:28 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'] 08:31:28 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 08:31:29 INFO - Return code: 0 08:31:29 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 08:31:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:29 INFO - https://queue.taskcluster.net/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:31:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:31:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:31:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/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 08:31:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/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 08:31:30 INFO - Downloaded 23902834 bytes. 08:31:30 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'] 08:31:30 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 08:31:32 INFO - Return code: 0 08:31:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:32 INFO - https://queue.taskcluster.net/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:31:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 08:31:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 08:31:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/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 08:31:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/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 08:31:32 INFO - Downloaded 10840152 bytes. 08:31:32 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'] 08:31:32 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 08:31:33 INFO - Return code: 0 08:31:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:33 INFO - https://queue.taskcluster.net/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:31:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:31:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:31:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/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 08:31:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/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 08:31:34 INFO - Downloaded 58318586 bytes. 08:31:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:31:34 INFO - mkdir: /builds/slave/test/properties 08:31:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:31:34 INFO - Writing to file /builds/slave/test/properties/build_url 08:31:34 INFO - Contents: 08:31:34 INFO - build_url:https://queue.taskcluster.net/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 08:31:35 INFO - Running post-action listener: _resource_record_post_action 08:31:35 INFO - Running post-action listener: set_extra_try_arguments 08:31:35 INFO - ##### 08:31:35 INFO - ##### Finished download-and-extract step (success) 08:31:35 INFO - ##### 08:31:35 INFO - ##### 08:31:35 INFO - ##### Running populate-webroot step. 08:31:35 INFO - ##### 08:31:35 INFO - Running pre-action listener: _resource_record_pre_action 08:31:35 INFO - Running main action method: populate_webroot 08:31:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:31:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:31:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:31:35 INFO - '--tppagecycles', 08:31:35 INFO - '7'], 08:31:35 INFO - 'tests': ['tsvgm']}}, 08:31:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:31:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:31:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:31:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:31:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:31:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:31:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:35 INFO - 'talos_options': ['--disable-e10s'], 08:31:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:31:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:31:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:35 INFO - 'talos_options': ['--disable-e10s'], 08:31:35 INFO - 'tests': ['damp', 'tps']}, 08:31:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:35 INFO - 'tests': ['damp', 'tps']}, 08:31:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:31:35 INFO - 'tests': ['dromaeo_dom']}, 08:31:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:31:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:31:35 INFO - 'tests': ['a11yr', 08:31:35 INFO - 'ts_paint', 08:31:35 INFO - 'tpaint', 08:31:35 INFO - 'sessionrestore', 08:31:35 INFO - 'sessionrestore_no_auto_restore', 08:31:35 INFO - 'tabpaint']}, 08:31:35 INFO - 'other-e10s': {'tests': ['a11yr', 08:31:35 INFO - 'ts_paint', 08:31:35 INFO - 'tpaint', 08:31:35 INFO - 'sessionrestore', 08:31:35 INFO - 'sessionrestore_no_auto_restore', 08:31:35 INFO - 'tabpaint']}, 08:31:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:31:35 INFO - 'ts_paint', 08:31:35 INFO - 'tpaint', 08:31:35 INFO - 'sessionrestore', 08:31:35 INFO - 'sessionrestore_no_auto_restore', 08:31:35 INFO - 'tabpaint']}, 08:31:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:31:35 INFO - 'ts_paint', 08:31:35 INFO - 'tpaint', 08:31:35 INFO - 'sessionrestore', 08:31:35 INFO - 'sessionrestore_no_auto_restore', 08:31:35 INFO - 'tabpaint']}, 08:31:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:31:35 INFO - 'tests': ['a11yr', 08:31:35 INFO - 'ts_paint', 08:31:35 INFO - 'tpaint', 08:31:35 INFO - 'sessionrestore', 08:31:35 INFO - 'sessionrestore_no_auto_restore', 08:31:35 INFO - 'tabpaint']}, 08:31:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:31:35 INFO - 'tests': ['a11yr', 08:31:35 INFO - 'ts_paint', 08:31:35 INFO - 'tpaint', 08:31:35 INFO - 'sessionrestore', 08:31:35 INFO - 'sessionrestore_no_auto_restore', 08:31:35 INFO - 'tabpaint']}, 08:31:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:31:35 INFO - 'tests': ['tsvgx', 08:31:35 INFO - 'tsvgr_opacity', 08:31:35 INFO - 'tart', 08:31:35 INFO - 'tscrollx', 08:31:35 INFO - 'cart']}, 08:31:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:31:35 INFO - 'tsvgr_opacity', 08:31:35 INFO - 'tart', 08:31:35 INFO - 'tscrollx', 08:31:35 INFO - 'cart']}, 08:31:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:35 INFO - 'talos_options': ['--disable-e10s'], 08:31:35 INFO - 'tests': ['tp5o']}, 08:31:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:35 INFO - 'tests': ['tp5o']}, 08:31:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:35 INFO - 'talos_options': ['--disable-e10s', 08:31:35 INFO - '--xperf_path', 08:31:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:31:35 INFO - 'tests': ['tp5n']}, 08:31:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:35 INFO - 'talos_options': ['--xperf_path', 08:31:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:31:35 INFO - 'tests': ['tp5n']}}, 08:31:35 INFO - 'talos.zip': {'path': '', 08:31:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:31:35 INFO - Running post-action listener: _resource_record_post_action 08:31:35 INFO - ##### 08:31:35 INFO - ##### Finished populate-webroot step (success) 08:31:35 INFO - ##### 08:31:35 INFO - ##### 08:31:35 INFO - ##### Running create-virtualenv step. 08:31:35 INFO - ##### 08:31:35 INFO - Running pre-action listener: _resource_record_pre_action 08:31:35 INFO - Running main action method: create_virtualenv 08:31:35 INFO - Creating virtualenv /builds/slave/test/build/venv 08:31:35 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:31:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:31:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:31:35 INFO - Using real prefix '/usr' 08:31:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:31:36 INFO - Installing distribute.............................................................................................................................................................................................done. 08:31:38 INFO - Installing pip.................done. 08:31:38 INFO - Return code: 0 08:31:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4c224df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd22770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb19c0>, '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 0xdb28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdb2d50>, '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': 'aa52b8aba932b19cc50b5275000001de-1462980683.677055-2137373613', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:31:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:31:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:31:38 INFO - Using env: {'DISPLAY': ':0', 08:31:38 INFO - 'HOME': '/home/cltbld', 08:31:38 INFO - 'LANG': 'en_US.UTF-8', 08:31:38 INFO - 'LANGUAGE': 'en_US:en', 08:31:38 INFO - 'LOGNAME': 'cltbld', 08:31:38 INFO - 'MAIL': '/var/mail/cltbld', 08:31:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:38 INFO - 'MOZ_NO_REMOTE': '1', 08:31:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:38 INFO - 'NO_EM_RESTART': '1', 08:31:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:38 INFO - 'PWD': '/builds/slave/test', 08:31:38 INFO - 'SHELL': '/bin/bash', 08:31:38 INFO - 'SHLVL': '1', 08:31:38 INFO - 'TERM': 'linux', 08:31:38 INFO - 'TMOUT': '86400', 08:31:38 INFO - 'USER': 'cltbld', 08:31:38 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1462980683.677055-2137373613', 08:31:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:38 INFO - '_': '/tools/buildbot/bin/python'} 08:31:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:38 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:31:38 INFO - Cleaning up... 08:31:38 INFO - Return code: 0 08:31:38 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 08:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fb4c224df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd22770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb19c0>, '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 0xdb28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdb2d50>, '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': 'aa52b8aba932b19cc50b5275000001de-1462980683.677055-2137373613', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:31:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 08:31:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 08:31:38 INFO - Using env: {'DISPLAY': ':0', 08:31:38 INFO - 'HOME': '/home/cltbld', 08:31:38 INFO - 'LANG': 'en_US.UTF-8', 08:31:38 INFO - 'LANGUAGE': 'en_US:en', 08:31:38 INFO - 'LOGNAME': 'cltbld', 08:31:38 INFO - 'MAIL': '/var/mail/cltbld', 08:31:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:38 INFO - 'MOZ_NO_REMOTE': '1', 08:31:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:38 INFO - 'NO_EM_RESTART': '1', 08:31:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:38 INFO - 'PWD': '/builds/slave/test', 08:31:38 INFO - 'SHELL': '/bin/bash', 08:31:38 INFO - 'SHLVL': '1', 08:31:38 INFO - 'TERM': 'linux', 08:31:38 INFO - 'TMOUT': '86400', 08:31:38 INFO - 'USER': 'cltbld', 08:31:38 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1462980683.677055-2137373613', 08:31:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:38 INFO - '_': '/tools/buildbot/bin/python'} 08:31:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:38 INFO - Downloading/unpacking psutil>=0.7.1 08:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:31:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:31:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:31:40 INFO - Installing collected packages: psutil 08:31:40 INFO - Running setup.py install for psutil 08:31:40 INFO - building 'psutil._psutil_linux' extension 08:31:40 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:31:40 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 08:31:40 INFO - building 'psutil._psutil_posix' extension 08:31:40 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 08:31:40 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 08:31:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:31:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:31:40 INFO - Successfully installed psutil 08:31:40 INFO - Cleaning up... 08:31:40 INFO - Return code: 0 08:31:40 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 08:31:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:40 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 0x7fb4c224df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd22770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb19c0>, '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 0xdb28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdb2d50>, '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': 'aa52b8aba932b19cc50b5275000001de-1462980683.677055-2137373613', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:31:40 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 08:31:40 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 08:31:40 INFO - Using env: {'DISPLAY': ':0', 08:31:40 INFO - 'HOME': '/home/cltbld', 08:31:40 INFO - 'LANG': 'en_US.UTF-8', 08:31:40 INFO - 'LANGUAGE': 'en_US:en', 08:31:40 INFO - 'LOGNAME': 'cltbld', 08:31:40 INFO - 'MAIL': '/var/mail/cltbld', 08:31:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:40 INFO - 'MOZ_NO_REMOTE': '1', 08:31:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:40 INFO - 'NO_EM_RESTART': '1', 08:31:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:40 INFO - 'PWD': '/builds/slave/test', 08:31:40 INFO - 'SHELL': '/bin/bash', 08:31:40 INFO - 'SHLVL': '1', 08:31:40 INFO - 'TERM': 'linux', 08:31:40 INFO - 'TMOUT': '86400', 08:31:40 INFO - 'USER': 'cltbld', 08:31:40 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1462980683.677055-2137373613', 08:31:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:40 INFO - '_': '/tools/buildbot/bin/python'} 08:31:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:41 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:31:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:42 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:31:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:31:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:31:42 INFO - Installing collected packages: mozsystemmonitor 08:31:42 INFO - Running setup.py install for mozsystemmonitor 08:31:43 INFO - Successfully installed mozsystemmonitor 08:31:43 INFO - Cleaning up... 08:31:43 INFO - Return code: 0 08:31:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:31:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:43 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 0x7fb4c224df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd22770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb19c0>, '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 0xdb28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdb2d50>, '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': 'aa52b8aba932b19cc50b5275000001de-1462980683.677055-2137373613', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:31:43 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 08:31:43 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 08:31:43 INFO - Using env: {'DISPLAY': ':0', 08:31:43 INFO - 'HOME': '/home/cltbld', 08:31:43 INFO - 'LANG': 'en_US.UTF-8', 08:31:43 INFO - 'LANGUAGE': 'en_US:en', 08:31:43 INFO - 'LOGNAME': 'cltbld', 08:31:43 INFO - 'MAIL': '/var/mail/cltbld', 08:31:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:43 INFO - 'MOZ_NO_REMOTE': '1', 08:31:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:43 INFO - 'NO_EM_RESTART': '1', 08:31:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:43 INFO - 'PWD': '/builds/slave/test', 08:31:43 INFO - 'SHELL': '/bin/bash', 08:31:43 INFO - 'SHLVL': '1', 08:31:43 INFO - 'TERM': 'linux', 08:31:43 INFO - 'TMOUT': '86400', 08:31:43 INFO - 'USER': 'cltbld', 08:31:43 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1462980683.677055-2137373613', 08:31:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:43 INFO - '_': '/tools/buildbot/bin/python'} 08:31:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:43 INFO - Downloading/unpacking blobuploader==1.2.4 08:31:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:45 INFO - Downloading blobuploader-1.2.4.tar.gz 08:31:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:31:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:31:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:31:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:31:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:45 INFO - Downloading docopt-0.6.1.tar.gz 08:31:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:31:45 INFO - Installing collected packages: blobuploader, requests, docopt 08:31:45 INFO - Running setup.py install for blobuploader 08:31:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:31:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:31:45 INFO - Running setup.py install for requests 08:31:46 INFO - Running setup.py install for docopt 08:31:46 INFO - Successfully installed blobuploader requests docopt 08:31:46 INFO - Cleaning up... 08:31:46 INFO - Return code: 0 08:31:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:31:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:46 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 0x7fb4c224df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd22770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb19c0>, '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 0xdb28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdb2d50>, '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': 'aa52b8aba932b19cc50b5275000001de-1462980683.677055-2137373613', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:31:46 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 08:31:46 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 08:31:46 INFO - Using env: {'DISPLAY': ':0', 08:31:46 INFO - 'HOME': '/home/cltbld', 08:31:46 INFO - 'LANG': 'en_US.UTF-8', 08:31:46 INFO - 'LANGUAGE': 'en_US:en', 08:31:46 INFO - 'LOGNAME': 'cltbld', 08:31:46 INFO - 'MAIL': '/var/mail/cltbld', 08:31:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:46 INFO - 'MOZ_NO_REMOTE': '1', 08:31:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:46 INFO - 'NO_EM_RESTART': '1', 08:31:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:46 INFO - 'PWD': '/builds/slave/test', 08:31:46 INFO - 'SHELL': '/bin/bash', 08:31:46 INFO - 'SHLVL': '1', 08:31:46 INFO - 'TERM': 'linux', 08:31:46 INFO - 'TMOUT': '86400', 08:31:46 INFO - 'USER': 'cltbld', 08:31:46 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1462980683.677055-2137373613', 08:31:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:46 INFO - '_': '/tools/buildbot/bin/python'} 08:31:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:31:46 INFO - Running setup.py (path:/tmp/pip-0Sfgfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:31:46 INFO - Running setup.py (path:/tmp/pip-gkqJje-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:31:46 INFO - Running setup.py (path:/tmp/pip-D70qsf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:31:46 INFO - Running setup.py (path:/tmp/pip-yxlaFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:31:46 INFO - Running setup.py (path:/tmp/pip-lhlZLO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:31:46 INFO - Running setup.py (path:/tmp/pip-l4Kef_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:31:46 INFO - Running setup.py (path:/tmp/pip-8QXawq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:31:46 INFO - Running setup.py (path:/tmp/pip-9rDb1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:31:46 INFO - Running setup.py (path:/tmp/pip-9NFs8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:31:47 INFO - Running setup.py (path:/tmp/pip-PAxe6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:31:47 INFO - Running setup.py (path:/tmp/pip-82DzbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:31:47 INFO - Running setup.py (path:/tmp/pip-n9ZjdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:31:47 INFO - Running setup.py (path:/tmp/pip-RhVoOC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:31:47 INFO - Running setup.py (path:/tmp/pip-ow9PMQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:47 INFO - Running setup.py (path:/tmp/pip-Igtus3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:31:47 INFO - Running setup.py (path:/tmp/pip-69ngMp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:31:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:31:47 INFO - Running setup.py (path:/tmp/pip-9fuGIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:31:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:31:47 INFO - Running setup.py install for manifestparser 08:31:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:31:47 INFO - Running setup.py install for mozcrash 08:31:47 INFO - Running setup.py install for mozdebug 08:31:47 INFO - Running setup.py install for mozdevice 08:31:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:31:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:31:47 INFO - Running setup.py install for mozfile 08:31:47 INFO - Running setup.py install for mozhttpd 08:31:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:31:48 INFO - Running setup.py install for mozinfo 08:31:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:31:48 INFO - Running setup.py install for mozInstall 08:31:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:31:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:31:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:31:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:31:48 INFO - Running setup.py install for mozleak 08:31:48 INFO - Running setup.py install for mozlog 08:31:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:31:48 INFO - Running setup.py install for moznetwork 08:31:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:31:48 INFO - Running setup.py install for mozprocess 08:31:48 INFO - Running setup.py install for mozprofile 08:31:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:31:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:31:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:31:48 INFO - Running setup.py install for mozrunner 08:31:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:31:48 INFO - Running setup.py install for mozscreenshot 08:31:48 INFO - Running setup.py install for moztest 08:31:48 INFO - Running setup.py install for mozversion 08:31:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:31:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:31:49 INFO - Cleaning up... 08:31:49 INFO - Return code: 0 08:31:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:49 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 0x7fb4c224df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd22770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb19c0>, '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 0xdb28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdb2d50>, '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': 'aa52b8aba932b19cc50b5275000001de-1462980683.677055-2137373613', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:31:49 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 08:31:49 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 08:31:49 INFO - Using env: {'DISPLAY': ':0', 08:31:49 INFO - 'HOME': '/home/cltbld', 08:31:49 INFO - 'LANG': 'en_US.UTF-8', 08:31:49 INFO - 'LANGUAGE': 'en_US:en', 08:31:49 INFO - 'LOGNAME': 'cltbld', 08:31:49 INFO - 'MAIL': '/var/mail/cltbld', 08:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:49 INFO - 'MOZ_NO_REMOTE': '1', 08:31:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:49 INFO - 'NO_EM_RESTART': '1', 08:31:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:49 INFO - 'PWD': '/builds/slave/test', 08:31:49 INFO - 'SHELL': '/bin/bash', 08:31:49 INFO - 'SHLVL': '1', 08:31:49 INFO - 'TERM': 'linux', 08:31:49 INFO - 'TMOUT': '86400', 08:31:49 INFO - 'USER': 'cltbld', 08:31:49 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1462980683.677055-2137373613', 08:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:49 INFO - '_': '/tools/buildbot/bin/python'} 08:31:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:31:49 INFO - Running setup.py (path:/tmp/pip-knuKKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:31:49 INFO - Running setup.py (path:/tmp/pip-MMY_sP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:31:49 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)) 08:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:31:49 INFO - Running setup.py (path:/tmp/pip-4jKWbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:31:49 INFO - Running setup.py (path:/tmp/pip-snYUPk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:31:49 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)) 08:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:31:49 INFO - Running setup.py (path:/tmp/pip-sAdcEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:31:49 INFO - Running setup.py (path:/tmp/pip-dFEXzy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:31:49 INFO - Running setup.py (path:/tmp/pip-QkZSjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:31:49 INFO - Running setup.py (path:/tmp/pip-piGcj1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:31:49 INFO - Running setup.py (path:/tmp/pip-jCwsaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:31:49 INFO - Running setup.py (path:/tmp/pip-LmwiRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:31:49 INFO - Running setup.py (path:/tmp/pip-p8BLyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:31:49 INFO - Running setup.py (path:/tmp/pip-Z2cMNS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:31:49 INFO - Running setup.py (path:/tmp/pip-lGSlf6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:31:50 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)) 08:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:31:50 INFO - Running setup.py (path:/tmp/pip-PBuLl9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:31:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:50 INFO - Running setup.py (path:/tmp/pip-ELWxPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:31:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:31:50 INFO - Running setup.py (path:/tmp/pip-uSU65t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:31:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:31:50 INFO - Running setup.py (path:/tmp/pip-I22azB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:31:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:31:50 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)) 08:31:50 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)) 08:31:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:31:50 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)) 08:31:50 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)) 08:31:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:52 INFO - Downloading blessings-1.6.tar.gz 08:31:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:31:52 INFO - Installing collected packages: blessings 08:31:52 INFO - Running setup.py install for blessings 08:31:52 INFO - Successfully installed blessings 08:31:52 INFO - Cleaning up... 08:31:52 INFO - Return code: 0 08:31:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:31:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:31:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:31:52 INFO - Reading from file tmpfile_stdout 08:31:52 INFO - Current package versions: 08:31:52 INFO - argparse == 1.2.1 08:31:52 INFO - blessings == 1.6 08:31:52 INFO - blobuploader == 1.2.4 08:31:52 INFO - docopt == 0.6.1 08:31:52 INFO - manifestparser == 1.1 08:31:52 INFO - mozInstall == 1.12 08:31:52 INFO - mozcrash == 0.17 08:31:52 INFO - mozdebug == 0.1 08:31:52 INFO - mozdevice == 0.48 08:31:52 INFO - mozfile == 1.2 08:31:52 INFO - mozhttpd == 0.7 08:31:52 INFO - mozinfo == 0.9 08:31:52 INFO - mozleak == 0.1 08:31:52 INFO - mozlog == 3.1 08:31:52 INFO - moznetwork == 0.27 08:31:52 INFO - mozprocess == 0.22 08:31:52 INFO - mozprofile == 0.28 08:31:52 INFO - mozrunner == 6.11 08:31:52 INFO - mozscreenshot == 0.1 08:31:52 INFO - mozsystemmonitor == 0.0 08:31:52 INFO - moztest == 0.7 08:31:52 INFO - mozversion == 1.4 08:31:52 INFO - psutil == 3.1.1 08:31:52 INFO - requests == 1.2.3 08:31:52 INFO - wsgiref == 0.1.2 08:31:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:52 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 0x7fb4c224df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd22770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb19c0>, '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 0xdb28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdb2d50>, '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': 'aa52b8aba932b19cc50b5275000001de-1462980683.677055-2137373613', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:31:52 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 08:31:52 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 08:31:52 INFO - Using env: {'DISPLAY': ':0', 08:31:52 INFO - 'HOME': '/home/cltbld', 08:31:52 INFO - 'LANG': 'en_US.UTF-8', 08:31:52 INFO - 'LANGUAGE': 'en_US:en', 08:31:52 INFO - 'LOGNAME': 'cltbld', 08:31:52 INFO - 'MAIL': '/var/mail/cltbld', 08:31:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:52 INFO - 'MOZ_NO_REMOTE': '1', 08:31:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:52 INFO - 'NO_EM_RESTART': '1', 08:31:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:52 INFO - 'PWD': '/builds/slave/test', 08:31:52 INFO - 'SHELL': '/bin/bash', 08:31:52 INFO - 'SHLVL': '1', 08:31:52 INFO - 'TERM': 'linux', 08:31:52 INFO - 'TMOUT': '86400', 08:31:52 INFO - 'USER': 'cltbld', 08:31:52 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1462980683.677055-2137373613', 08:31:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:52 INFO - '_': '/tools/buildbot/bin/python'} 08:31:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:52 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)) 08:31:52 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)) 08:31:52 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)) 08:31:52 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)) 08:31:52 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)) 08:31:52 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)) 08:31:52 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)) 08:31:52 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)) 08:31:52 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)) 08:31:52 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)) 08:31:52 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)) 08:31:52 INFO - Cleaning up... 08:31:52 INFO - Return code: 0 08:31:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4c224df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd22770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb19c0>, '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 0xdb28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdb2d50>, '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': 'aa52b8aba932b19cc50b5275000001de-1462980683.677055-2137373613', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:31:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:31:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:31:52 INFO - Using env: {'DISPLAY': ':0', 08:31:52 INFO - 'HOME': '/home/cltbld', 08:31:52 INFO - 'LANG': 'en_US.UTF-8', 08:31:52 INFO - 'LANGUAGE': 'en_US:en', 08:31:52 INFO - 'LOGNAME': 'cltbld', 08:31:52 INFO - 'MAIL': '/var/mail/cltbld', 08:31:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:52 INFO - 'MOZ_NO_REMOTE': '1', 08:31:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:52 INFO - 'NO_EM_RESTART': '1', 08:31:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:52 INFO - 'PWD': '/builds/slave/test', 08:31:52 INFO - 'SHELL': '/bin/bash', 08:31:52 INFO - 'SHLVL': '1', 08:31:52 INFO - 'TERM': 'linux', 08:31:52 INFO - 'TMOUT': '86400', 08:31:52 INFO - 'USER': 'cltbld', 08:31:52 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1462980683.677055-2137373613', 08:31:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:52 INFO - '_': '/tools/buildbot/bin/python'} 08:31:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:52 INFO - Downloading/unpacking jsonschema 08:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:31:54 INFO - Downloading/unpacking functools32 (from jsonschema) 08:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:54 INFO - Downloading functools32-3.2.3-2.tar.gz 08:31:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:31:55 INFO - warning: no files found matching '*.txt' 08:31:55 INFO - no previously-included directories found matching 'build' 08:31:55 INFO - no previously-included directories found matching 'dist' 08:31:55 INFO - no previously-included directories found matching '.git*' 08:31:55 INFO - Installing collected packages: jsonschema, functools32 08:31:55 INFO - Running setup.py install for functools32 08:31:55 INFO - warning: no files found matching '*.txt' 08:31:55 INFO - no previously-included directories found matching 'build' 08:31:55 INFO - no previously-included directories found matching 'dist' 08:31:55 INFO - no previously-included directories found matching '.git*' 08:31:55 INFO - Successfully installed jsonschema functools32 08:31:55 INFO - Cleaning up... 08:31:55 INFO - Return code: 0 08:31:55 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:31:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:31:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:31:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:31:55 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 0x7fb4c224df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc578a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd22770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb19c0>, '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 0xdb28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdb2d50>, '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': 'aa52b8aba932b19cc50b5275000001de-1462980683.677055-2137373613', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:31:55 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 08:31:55 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 08:31:55 INFO - Using env: {'DISPLAY': ':0', 08:31:55 INFO - 'HOME': '/home/cltbld', 08:31:55 INFO - 'LANG': 'en_US.UTF-8', 08:31:55 INFO - 'LANGUAGE': 'en_US:en', 08:31:55 INFO - 'LOGNAME': 'cltbld', 08:31:55 INFO - 'MAIL': '/var/mail/cltbld', 08:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:55 INFO - 'MOZ_NO_REMOTE': '1', 08:31:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:31:55 INFO - 'NO_EM_RESTART': '1', 08:31:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:55 INFO - 'PWD': '/builds/slave/test', 08:31:55 INFO - 'SHELL': '/bin/bash', 08:31:55 INFO - 'SHLVL': '1', 08:31:55 INFO - 'TERM': 'linux', 08:31:55 INFO - 'TMOUT': '86400', 08:31:55 INFO - 'USER': 'cltbld', 08:31:55 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1462980683.677055-2137373613', 08:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:55 INFO - '_': '/tools/buildbot/bin/python'} 08:31:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:31:55 INFO - Downloading/unpacking flake8 08:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:57 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:31:57 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:57 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:31:57 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:57 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:31:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:31:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:31:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:31:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:31:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:31:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:31:57 INFO - no previously-included directories found matching 'docs/_build' 08:31:57 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:31:57 INFO - Running setup.py install for pep8 08:31:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:31:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:31:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:31:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:31:58 INFO - no previously-included directories found matching 'docs/_build' 08:31:58 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:31:58 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:31:58 INFO - Cleaning up... 08:31:58 INFO - Return code: 0 08:31:58 INFO - Running post-action listener: _resource_record_post_action 08:31:58 INFO - Running post-action listener: _start_resource_monitoring 08:31:58 INFO - Starting resource monitoring. 08:31:58 INFO - ##### 08:31:58 INFO - ##### Finished create-virtualenv step (success) 08:31:58 INFO - ##### 08:31:58 INFO - ##### 08:31:58 INFO - ##### Running install step. 08:31:58 INFO - ##### 08:31:58 INFO - Running pre-action listener: _resource_record_pre_action 08:31:58 INFO - Running main action method: install 08:31:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:31:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:31:58 INFO - Reading from file tmpfile_stdout 08:31:58 INFO - Detecting whether we're running mozinstall >=1.0... 08:31:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:31:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:31:58 INFO - Reading from file tmpfile_stdout 08:31:58 INFO - Output received: 08:31:58 INFO - Usage: mozinstall [options] installer 08:31:58 INFO - Options: 08:31:58 INFO - -h, --help show this help message and exit 08:31:58 INFO - -d DEST, --destination=DEST 08:31:58 INFO - Directory to install application into. [default: 08:31:58 INFO - "/builds/slave/test"] 08:31:58 INFO - --app=APP Application being installed. [default: firefox] 08:31:58 INFO - mkdir: /builds/slave/test/build/application 08:31:58 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'] 08:31:58 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 08:32:15 INFO - Reading from file tmpfile_stdout 08:32:15 INFO - Output received: 08:32:15 INFO - /builds/slave/test/build/application/firefox/firefox 08:32:15 INFO - Running post-action listener: _resource_record_post_action 08:32:15 INFO - ##### 08:32:15 INFO - ##### Finished install step (success) 08:32:15 INFO - ##### 08:32:15 INFO - ##### 08:32:15 INFO - ##### Running run-tests step. 08:32:15 INFO - ##### 08:32:15 INFO - Running pre-action listener: _resource_record_pre_action 08:32:15 INFO - Running main action method: run_tests 08:32:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:32:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:32:15 INFO - Python 2.7.3 08:32:15 INFO - Return code: 0 08:32:15 INFO - grabbing minidump binary from tooltool 08:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:32:15 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb19c0>, '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 0xdb28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdb2d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:32:15 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:32:15 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 08:32:15 INFO - Calling ['/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'] with output_timeout 600 08:32:15 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:32:15 INFO - Return code: 0 08:32:15 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:32:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:32:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:32:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:32:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:32:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:32:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:32:15 INFO - Return code: 0 08:32:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 08:32:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-009 --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 08:32:15 INFO - Using env: {'DISPLAY': ':0', 08:32:15 INFO - 'HOME': '/home/cltbld', 08:32:15 INFO - 'LANG': 'en_US.UTF-8', 08:32:15 INFO - 'LANGUAGE': 'en_US:en', 08:32:15 INFO - 'LOGNAME': 'cltbld', 08:32:15 INFO - 'MAIL': '/var/mail/cltbld', 08:32:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:32:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:32:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:15 INFO - 'MOZ_NO_REMOTE': '1', 08:32:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:32:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:32:15 INFO - 'NO_EM_RESTART': '1', 08:32:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:32:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:32:15 INFO - 'PWD': '/builds/slave/test', 08:32:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:32:15 INFO - 'SHELL': '/bin/bash', 08:32:15 INFO - 'SHLVL': '1', 08:32:15 INFO - 'TERM': 'linux', 08:32:15 INFO - 'TMOUT': '86400', 08:32:15 INFO - 'USER': 'cltbld', 08:32:15 INFO - 'XDG_SESSION_COOKIE': 'aa52b8aba932b19cc50b5275000001de-1462980683.677055-2137373613', 08:32:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:32:16 INFO - '_': '/tools/buildbot/bin/python'} 08:32:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 08:32:16 INFO - mozversion application_buildid: 20160511030221 08:32:16 INFO - mozversion application_changeset: 674a552743785c28c75866969aad513bd8eaf6ae 08:32:16 INFO - mozversion application_display_name: Nightly 08:32:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:32:16 INFO - mozversion application_name: Firefox 08:32:16 INFO - mozversion application_remotingname: firefox 08:32:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:32:16 INFO - mozversion application_vendor: Mozilla 08:32:16 INFO - mozversion application_version: 49.0a1 08:32:16 INFO - mozversion platform_buildid: 20160511030221 08:32:16 INFO - mozversion platform_changeset: 674a552743785c28c75866969aad513bd8eaf6ae 08:32:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:32:16 INFO - mozversion platform_version: 49.0a1 08:32:16 INFO - using testdate: 1462980736 08:32:16 INFO - actual date: 1462980736 08:32:16 INFO - starting webserver on 'localhost:43435' 08:32:16 INFO - SUITE-START | Running 2 tests 08:32:16 INFO - TEST-START | dromaeo_css 08:32:16 INFO - operating with platform_type : linux_ 08:32:16 INFO - Initialising browser for dromaeo_css test... 08:32:16 INFO - TEST-INFO | started process 7831 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzp4nPy/profile http://localhost:43435/getInfo.html) 08:32:19 INFO - PROCESS | 7831 | __metrics Screen width/height:1600/1200 08:32:19 INFO - PROCESS | 7831 | colorDepth:24 08:32:19 INFO - PROCESS | 7831 | Browser inner width/height: 1024/697 08:32:19 INFO - PROCESS | 7831 | __metrics 08:32:20 ERROR - PROCESS | 7831 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:32:20 INFO - PROCESS | 7831 | console.error: PushService: 08:32:20 INFO - PROCESS | 7831 | stateChangeProcessEnqueue: Error transitioning state 08:32:20 INFO - PROCESS | 7831 | UnknownError 08:32:21 INFO - TEST-INFO | 7831: exit 0 08:32:21 INFO - Browser initialized. 08:32:21 INFO - Running cycle 1/1 for dromaeo_css test... 08:32:21 INFO - TEST-INFO | started process 7910 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzp4nPy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:32:33 INFO - PROCESS | 7910 | RSS: Main: 150323200 08:32:33 INFO - PROCESS | 7910 | 08:33:24 INFO - PROCESS | 7910 | 1462980804036 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:35:31 INFO - PROCESS | 7910 | Cycle 1(1): loaded http://localhost:43435/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43435/tests/dromaeo/cssquery-ext.html) 08:35:32 INFO - PROCESS | 7910 | RSS: Main: 252641280 08:35:32 INFO - PROCESS | 7910 | 08:38:35 INFO - PROCESS | 7910 | Cycle 1(1): loaded http://localhost:43435/tests/dromaeo/cssquery-ext.html (next: http://localhost:43435/tests/dromaeo/cssquery-jquery.html) 08:38:35 INFO - PROCESS | 7910 | RSS: Main: 236945408 08:38:35 INFO - PROCESS | 7910 | 08:41:37 INFO - PROCESS | 7910 | Cycle 1(1): loaded http://localhost:43435/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43435/tests/dromaeo/cssquery-mootools.html) 08:41:38 INFO - PROCESS | 7910 | RSS: Main: 237531136 08:41:38 INFO - PROCESS | 7910 | 08:44:40 INFO - PROCESS | 7910 | Cycle 1(1): loaded http://localhost:43435/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43435/tests/dromaeo/cssquery-prototype.html) 08:44:40 INFO - PROCESS | 7910 | RSS: Main: 225648640 08:44:40 INFO - PROCESS | 7910 | 08:47:43 INFO - PROCESS | 7910 | Cycle 1(1): loaded http://localhost:43435/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43435/tests/dromaeo/cssquery-yui.html) 08:47:44 INFO - PROCESS | 7910 | RSS: Main: 218574848 08:47:44 INFO - PROCESS | 7910 | 08:50:46 INFO - PROCESS | 7910 | Cycle 1(1): loaded http://localhost:43435/tests/dromaeo/cssquery-yui.html (next: http://localhost:43435/tests/dromaeo/cssquery-dojo.html) 08:50:46 INFO - PROCESS | 7910 | RSS: Main: 262012928 08:50:46 INFO - PROCESS | 7910 | 08:50:46 INFO - PROCESS | 7910 | __start_tp_report 08:50:46 INFO - PROCESS | 7910 | _x_x_mozilla_page_load 08:50:46 INFO - PROCESS | 7910 | _x_x_mozilla_page_load_details 08:50:46 INFO - PROCESS | 7910 | |i|pagename|runs| 08:50:46 INFO - PROCESS | 7910 | |0;dojo.html;3086.6450358208804;3122.4691802393577;3134.9686503134963;3140.607424071991;3193.042087373787;5174.094533456649;5208.42707302197;5214.243934629486;5277.736113194339;5299.735013249337;5273.472652734725;5313.9734301328535;5412.2152287918225;5415.458454154593;5416.187571864222;4793.472718001034;4807.326974223612;4889.706617602955;4907.460179380265;4957.752112394366;4844.249141383091;4856.125897338477;4896.853094407174;4902.534259245366;4931.186354251544;4645.442546894377;4745.311929770181;4798.904021919577;4809.711417314972;4820.325154478376;4808.278758186265;4817.277408388734;4817.397825271841;4820.590249828769;4856.68431551948;4680.461746899082;4800.519948005206;4807.158747219222;4829.203181475053;4836.540528649773;5265.894682106336;5315.255864179018;5355.464453554652;5394.973025134849;5414.945850541523;5801.6228945118855;5801.7099145043;5811.27359080115;5828.3297420796725;5831.708414579254;5576.163575463673;5578.3584887738025;5850.912236316459;5875.059990401558;5908.793192238251;5302.628815982883;5393.164059570773;5394.595405344615;5407.567394608501;5443.2107344435235;5886.852828679232;5917.940820591825;5929.466348028698;5941.70291485424;5942.286925568959;5540.889182216333;5599.524040456607;5602;5649.350324712628;5685.374608793029;5738.024535828919;5743.885122297531;5748.19525266463;5766.913496297559;5790.739416726257;5674;5697.287839020122;5737.454941780524;5737.627054241461;5745.712714364348;5518.724063796794;5607.719614019283;5622.072358060874;5629.971850140723;5694.629849059798;4276.593723596254;4277.871663850089;4280.165367753259;4283.336082907154;4309.806058727302;3806.1245913440066;3819.52255968004;3848.980020294624;3877.728559000843;3879.4568760373572;5188.9740551297;5255.816046438356;5323.201519772065;5388.353397592314;5401.108817045144;5756.597038207369;5775.220345253337;5776.104703770922;5777.19119323295;5801.9129713054335;5393.352797664305;5398.676079435168;5413;5424.864378390572;5431.2396264522995;4807.230843065093;4918.778654960535;4967.528084831935;4970.602351811846;4974.328465657162;5985.042393217152;6009.909851352233;6011.969940150271;6017.518598512109;6025.3974602540275;5578.30271216098;5788.160716696139;5791.0734282514595;5795.246617939641;5806.042003069446;5852.882942341214;5887.61730487517;5898.262717160355;5901.852453688521;5918.23063001807;5548.112302031655;5585.106382978704;5604.607677462538;5629.0712032514175;5636.549076073986;5541.861453463695;5653.858653533694;5697.145428185805;5708.086706127;5719.799807006735;5575.303087114111;5582.441755824385;5626.493615574454;5630.549556035589;5688.203651488712;5544.833654990357;5605.943940560543;5606.523445507178;5629.577781666473;5634.4365563443325;5260.86847828792;5408.269883565668;5431.619786614899;5450.345958485007;5506.834794956158;5630.831075067755;5652.576056795839;5679.716014199191;5709.486146246858;5721.113227449752;5517.668939863621;5532.695701736443;5534.640248383842;5542.63972841764;5584.050711379156 08:50:46 INFO - PROCESS | 7910 | |1;ext.html;586.5483577645214;603.2911329188204;612.9846753831151;621.782376168341;628.7673560782512;31053.223669408275;41565.58434415655;42481;42527.36208956868;45852.770736146354;43819.342709859375;43972.3404148938;44057;44116.33825492612;44523.10953780931;7663.846723065535;8593.312534997185;8810.295176385906;8817.074207208247;10338.638147664833;9917.90082099177;10387.584496620126;10393.584256629725;10399.32404393712;10408.63569775058;10391.168706503462;10395.84406233907;10448.895511044868;10455.634052808153;10456.843147352796;7869.645865936047;10185.388876667424;10284.58861645537;10335.276530642857;10488.108510776594;10296.021877921585;10429.113525350354;10447.320924139982;10457.267991240617;10461.424621645807;40112.799436002926;40376.79811600923;40395.79802101;40435.191296173914;40612.593874061175;3732.794696291703;4036.990752311922;4110.301248787713;4190.182914331706;4211.7051806373565;31231.531527027113;31454.213644659067;31766.04701858948;31809.681903180903;33148.3370332592;4213.241616509027;4233;4280.400743895857;4396.912061758779;4424.424824772791;299261;304362.4781876099;304780;304875;309757;58241.70879145577;58822.70588647115;59018;59242;59316.70341648264;61907.690461547405;62092.51629934843;62147.6892615534;62248;62324.37675623276;16678.916605417136;17217.827821721872;17235.22441490136;17245;17354.826451735575;11995.640130795915;12949.611511654666;13206.669833254246;13292.73414531704;13364.532241371506;17324.220410081325;17512.21195046226;17585.824141758676;17619.647607047787;17654.205560749797;5308.708021058802;6137.263528376623;6177.7837775677635;6213;6240.251169859555;970.5535453691381;1133.257716195893;1162.924409913353;1185.5909711149638;1239.876012398753;30910.845445773077;31033.379332413224;31219.531707024413;31223.843880780452;31312.686873130977;1109.589451902801;1122.5060973171942;1126.8253420719802;1183.7336599265097;1235.71578536937;15903.920480397524;16349.754753678704;16525.504234872973;16670.499885003468;16906.607237018823;418169;420433;420588;420667;421633;56968.29706069092;57177.14234286489;57232.42767572271;57485.425145748006;57693;1284.6916739982523;1373.0800363756107;1391.72165566885;1394.8814350780094;1414.2887106601129;28237.43525129527;31889.202769930936;32616;32667;32920.506192406654;47507.52492475031;47658.52341476541;47778.761106195634;47881.76059119682;48172.5182748168;301856;305918;306056;306936;307038;35266.64733352736;39855.80072099737;40692.38961415522;41156.58843411528;41635.37546936859;46110.538894610625;46423.30365044459;46483.3027504581;46995;47006.76496617495;38526;39275;40043;40069.59930400659;41059.58940410558;39600.80199598984;39616.80191599023;39846.402303966046;40019;40123.59876401316;40228.79885600553;41554.792226039885;42505.5749442514;43033;44628.8804963855;33412.498812518315;36476;36719.26561468702;36757.63242367542;39027.41458878176;27169.320766980985;27475.31311717223;27490;27684.723152768216;27772.305692357044 08:50:46 INFO - PROCESS | 7910 | |2;jquery.html;397.77128151313;399.0940564917635;400.1916129418574;401.6927050806133;420.26453706014473;13559.221163097493;16693.49919502414;17922.103894805256;19275.51811204719;19287.807121928796;11923.880761192386;12337.383130843444;12420;18437.7234341485;18773.342932997348;5101.54086132248;5252.422233554306;5259.447757985414;5284.577233821305;5428;5518.255035570207;5534.4742249486435;5597.972010139943;5631.60578759487;5667.149927510885;5334.386545547273;5609.747561359728;5629.493345598895;5629.971850140743;5645.407232240596;5682.119271512922;5696.800611978581;5705.62913410627;5717.885642287151;5727.742251598688;5227.764750586232;5420.539254163401;5438.885028569134;5464.726763661801;5535.169724541311;13951.302434878215;14107.435702571885;14152.433902643881;14317.928410357881;22147.557048858933;2336.1356298169617;2394.580948334052;2396.3290278721984;2425.834440281987;2920.751736102434;10062.748431289203;10135.363790186155;10553.314034587727;10622.521986510626;10891.128709703284;1919.4625504858661;1954.6334441852414;1988.9801101989085;1993.4916596268004;2001.329554599211;73478.63260683662;75065.24934750637;75080.62459687666;75969.62015189943;76813.84779228372;11124.55501779936;11277.011591927981;11668.183227174186;11792.351420671774;12186.329751863732;13657;14159.292035398188;15635.296411661502;15721.921390392974;15735.134567598663;6537.378948999837;6880.174379074543;8099.878501822477;8404.537750423664;8481.575921203916;8089.919100809035;8117;8122.593870306461;8201.507909525448;8215.137410571924;4746.833860814924;4831.613470922318;4877.365942427462;4912.533309335608;4940.110780059552;1945.7081437784163;1997.2011195521618;1999.1303782854504;2001.5896741168026;2038.6738121900426;928.2852203803034;974.2108891797667;1037.7042542875245;1043.160255993917;1103.536514663843;18552.7340166515;18892.527686807938;19004.714929276073;19138.330158444387;19455.416337509898;977.6529332087149;977.8044391121662;990.960361585543;1038.7351225437465;1076.8707755069286;9914.504274786232;10136.189104871593;10389.584416623402;10525.21060920434;10567.524461399256;446704;447923;452132;496413;499379;84683.5765821167;89586.55206723924;89794.10205897858;90074;90177;1006.8187726209206;1009.3893194617298;1018.7249442650591;1029.3926583315729;1041.8124737547162;16093.195340233176;16429.83570164283;16487;16498.75251871247;16695;25431.457265754598;30330.090097297118;37418;37739.43390849084;38789.6121038786;48879.511204887494;49493.752531237114;50080.749596251786;50297.74851125721;50376.74811626065;7087.255838137127;8349.332053435832;8528.530930798866;8562.058173601023;8580.099089595502;11076.446177690923;12221.38893055326;13114.737705246032;13233.338333083115;13293.468261269827;10650.627228046982;11161.162912781412;11231.38227397501;11524.423778811193;11555.2489088209;11154.72113197144;11165.944170279097;11343.319400835977;11441.199116061796;11459.828102578635;11722.355270460206;11729.765404692029;11974.22167559106;12087.697807554881;12152.21010634306;7876.212378762078;7957.960210199143;8013.639386227517;8132.390070744599;8180.520533365146;10087.546060427445;11295.3787541686;11333.886661133613;11712.590059347882;11830.05359571215 08:50:46 INFO - PROCESS | 7910 | |3;mootools.html;1359.3611002828668;1553.0526378908867;1581.2410043179275;1719.2435328455479;1870.5136664467243;8389.748307550768;8724.345674074446;8738.73783786486;8919.910800891988;8929;8641.481511109338;8756.168164024424;8804.383693141483;8810.030896601369;8814.206721395074;2044.5399785048394;2190.7809219078126;2231.9107235710553;2257.0407576779885;2261.321603518946;2367.562001029815;2371.430856594422;2377.9048838046456;2380.8928598213124;2387.2122199674154;2266.5693518231483;2295.185209250717;2297.0467256088705;2311.919082832101;2321.3848330192513;2006.508405440669;2238.138316748056;2303.5968705476557;2305.216226483004;2313.838031337807;2273.6475846243857;2287.039443433745;2291.163725240292;2298.9425264368356;2341.9297421077395;8900.154485323885;8903.55482225886;8943.955280223623;8967.730968070902;9003.414778039472;5140.563052140573;5284.867878303035;5302.708351040691;5308.124159513675;5322.813701520467;3385.4583266677214;3526.559180102487;3537.327907697529;3539.982300088483;3585.659362360572;1087.722630729167;1096.983545246822;1107.4075369677184;1130.152385710717;1135.432283858071;12427.689307767288;12610.243385396814;12627.242365458102;12688.36558172097;12709.93645031769;4651.906961860744;4684.812607495662;4762.357081793983;4790.4251489821445;4805.975970120127;10645.0951669109;10668.679939601825;10682.359058456517;10729.356238625707;10734.570617175226;4819.060283244804;5332.30680011596;5336.91994620081;5380.757865896044;5386.784528618889;4712.976435117803;4835.20219163834;4847.733374664357;4851.393575803025;4865.6107511399005;3908.1988192420486;3927.7643341399607;3948.2498325318097;4041.777702226347;4061.0659548304056;1238.7150955280335;1239.6652903716126;1255.384861417917;1262.6464589914838;1272.866349033338;932.7108596335158;973.9756506087405;997.7455748784014;1002.4035698759227;1015.9898401016043;5690.715464226772;5734.713264336767;5904.76380944766;5935.109733540004;6020.69896505173;445.9464864216315;447.3602748070291;452.61301587143174;457.34142834318476;463.39295522865154;3924.489816323917;4060.675145988314;4067.00358412185;4077.2660921033907;4080.2859499587453;10747.83878241827;10889.83665245022;11060.115190784718;11105.777884442266;11396.544138234543;10705.785884282272;11060.889391106148;11080.778384432428;11297.774044519063;11319.773604527863;8180;8359.456635318686;8379.245867871921;8425.283850872622;8482.363822713198;5746.827595172152;5792.507636850914;5816.82549523515;5905.5570832186895;5938.2280303560265;10010.149137323118;10164.644237451654;10186.083252507013;10219.846702299621;10285;10001.34991225568;10444.060034596923;10532.631357902437;10576.629817956335;10614.787704246026;7098.716051357887;7607.4674772765375;7730.381569474316;7830.647620856961;8016.078151012475;8659.826803464022;8738.650453981769;8750.51872147038;8825.338099642424;8854.778630534289;7657.846843063219;7812.492188007761;7813.921860781319;7917.604119794102;8093.312068474245;7579.54522728616;7696.692132314644;7804.648790804314;7933.3653307736395;7958.880616790869;6675.766348177791;6793.388595026471;6800.6599670017295;6845.794626161223;6852.040714299902;4931.753412329441;5039.72281524505;5199.948000519947;5229.869253268699;5249.08141075318;5312.601554883477;5328.17413300939;5347.973260133675;5368.4631536846;5371.973140134431 08:50:46 INFO - PROCESS | 7910 | |4;prototype.html;313.7348940145579;325.18054502653314;325.98370081495926;332.77870216306155;333.69800330700707;5128.025675121724;5167.509086636773;5203.9219411708855;5224.843254702365;5249.4750524947485;5151.43334233235;5159.174532074869;5171.327727395443;5173.456787037363;5188.6367954243215;3838.5201849768778;3872.535295764498;3988.421678856564;4002.8799136025964;4028.5769994150633;4001.2197621463824;4007.5792041835625;4007.899802504946;4010.2981978153707;4020.336644453662;3885.863994760184;4007.559168491478;4008.3586626139822;4010.498687664042;4031.9395209071886;3925.411188321746;4051.210014047247;4060.8832571042904;4065.7763822989723;4071.389291606253;3549.538559987211;3559.394902866519;3575.696065834381;3576.7675101118343;3587.892363229081;4899.755012249373;4903.681260718077;4964.131277026542;4974.577160941324;4990.4011518618;4536.682432229745;4602.309653551994;4608.377868987677;4627.768611569408;4629.305604159369;5339.3058902342445;5344.465553444663;5344.706041167734;5390.973045134789;5446.836594902159;3904.511936007999;3928.5464378179972;3945.10634895249;3974.1917427570675;3976.304146774332;5296.523312901857;5548.668319603266;5561.805336813192;5583.609147359686;5635.943640563582;3981.422693709396;4000.219957108336;4012.237674841829;4019.9196016079513;4037.4145748866836;4054.3107671695884;4057.3102572562734;4071.5521292657786;4072.266991941479;4096.877093687194;4472.821087156477;4569.200389931749;4605.102493433739;4616.030633566988;4621.930671039937;5357.955198736209;5370.597205209547;5411.215373770781;5473;5473.616846820684;1394.3307212537836;1423.2101183843067;1424.0956992309957;1429.9499517516838;1431.334429490292;908.3459908865606;908.6365453818526;961.1925982174866;984.7242772023844;986.7977064701719;5053.166227572483;5112.923306150411;5158.484151584811;5167;5169.379674439091;5371;5499.487640898785;5503.972480137573;5525.088360420566;5527.640703354349;5006.949930500648;5140.768665409991;5158.509941555546;5162.586993040548;5209.6874187548865;2922.8976985805393;2929.721676440735;2939.955900661492;2946.6169397978124;2949.3069128754723;5575.693336866511;5609.326880774252;5640.858978525488;5665.348484924287;5723.76938958126;5566.526845218121;5632.633878797865;5650.954573403851;5666.716664166857;5671;5585;5620.241267428926;5646.717664116778;5680.971595141998;5697.230873832063;5233.790648374023;5459.808906688247;5493.752781124779;5499.450054994469;5506.146547285177;5398.865028374322;5400.540954018952;5414.783408663767;5442.2108794225405;5442.292501974718;5623.240862483676;5674.347450043298;5728.770849165987;5739.311282646109;5751.296890461476;3960.7227494075137;4028.637422631977;4078;4088.202800453883;4092.3247664135083;5467.917981230205;5479.260299859628;5482.506574408322;5505.119180931032;5514.503694667499;5314.28257185275;5340.412554619066;5367.597430192829;5386.64986775874;5399.865003374947;5181.740912954262;5414.29614150158;5477.20580515815;5494.972525137348;5500.4499550044675;5084.618653600921;5199.974000129975;5278.287431196739;5294.2852714883;5324.760385782572;5392.757325920422;5417.214503896834;5429.67421954684;5449.918251226156;5457.781688732406;5371.946280537145;5374.596905232045;5379.919301210563;5398.62209645321;5406.513413792777 08:50:46 INFO - PROCESS | 7910 | |5;yui.html;751.063342331484;810.8297257575908;852.4970267542149;861.2205953611983;867.726666100178;14262;14644.487442939499;14783.704325913475;14915.701685966274;15024.098554086762;12855.678608034805;12906.548270810523;12966.02754793392;13011;13018.414171362288;681.107748849007;796.0964305513227;813.4102775487754;819.8852160697508;827.735124760077;816.4652152839899;824.8061705499202;826.8222332198576;827.2141465607667;827.7020272701823;876.8947726272825;882.095851751953;884.9646014159426;884.9734507964739;886.0474989386403;728.7230852276155;861.814709837388;877.1404024056396;881.180502133016;887.0641473245723;626.0515319291277;627.6547898655721;629.9905501417483;632.11503894548;634.1217413881807;13974.441022359093;13996.720065598733;14017.158970461805;14040.438382464688;14044.438222471088;13343.132696374705;13653.726925461535;13774.586762397144;13781.173129612154;13934.442622295095;11187.552497900155;11210.831837522444;11386.886131138748;11416.315021098677;11501.482433290439;4043.4945631796027;4087;4112.712110152291;4179.310413781723;4183.769892655902;47379.289310660366;49234;49544;53986.19020714692;54395.72802136004;10566.630167944086;11183.552657893755;11288.793124847838;11448.885511144781;11542.59600913964;19051.14269857832;19538.413847584594;19599.412017639494;20142.899285503772;20236.797632023492;8656.8879782103;8694.782630434289;8716.782080447912;8770.254528365032;8916.331275154389;4712.24604063355;4787.40157480315;4830.4444988826035;4867.683600565952;4902.044101400193;3458.239187378773;3559.270349578382;3566.7146628269675;3589.533360663097;3592.263585964871;627.595201095291;649.2695717318018;657.5495785387026;662.6653539962289;673.6328700858044;690.2303931116769;717.9732981835921;734.6033142103205;779.5595488548952;785.4487387409933;9864.950675246579;10299.588016479256;10318.329308595068;10356.326838755607;10495.107915827088;680.4590350671228;713.6039498078512;733.4059411876398;767.3515879082166;800.2677550041647;4487.640988720895;4854.660173787872;4855.951440485621;4861.756912154378;4878.463369029404;65203.673981629785;66339.33660663331;67176;67421.66289168621;67667.66166169236;54402.45597543974;54488.72755636196;56427;57733.42266577364;60535;52468.21297680538;52778.73610631922;52987;53444;53895.73052134714;9272.860907086264;9476.241900648069;9516.809663806824;9529.809403812023;9584.185344245818;32480.51279230766;33003;33893.491597625565;34436.65563344335;34613.82693086619;45232.77383613061;48766.268505971726;48810.5118948806;49064.75467622639;49445.752771235915;12004.699882502659;12009.39953002329;12126.269920194376;12813.167144135601;13142.145760525535;13191.340432978504;13281.66795830112;13618.455261789813;14068.859311406755;14077.788833167306;12851.357432128543;12919.160254583421;13301.800472993107;13335.733285334432;13457.663558411117;12440.875591243972;12538.185017973803;12555.372231388212;12617;13014.21914685122;12144.817827732768;12286.815697764361;12465.626031219077;13062.608121756362;13459.663508412368;10390.844137337795;10652.201084918817;10793.56825726962;11095.833562496407;11132.666020019413;11225.559181962413;11305.26515776472;11498.653126296313;11570.421478925851;11617.244879083171 08:50:46 INFO - PROCESS | 7910 | __end_tp_report 08:50:46 INFO - PROCESS | 7910 | __start_cc_report 08:50:46 INFO - PROCESS | 7910 | _x_x_mozilla_cycle_collect,798 08:50:46 INFO - PROCESS | 7910 | __end_cc_report 08:50:46 INFO - PROCESS | 7910 | __startTimestamp1462981846867__endTimestamp 08:50:46 INFO - PROCESS | 7910 | 08:50:46 INFO - PROCESS | 7910 | ------- Summary: start ------- 08:50:46 INFO - PROCESS | 7910 | Number of tests: 6 08:50:46 INFO - PROCESS | 7910 | 08:50:46 INFO - PROCESS | 7910 | [#0] dojo.html Cycles:165 Average:5307.56 Median:5532.70 stddev:619.31 (11.2%) stddev-sans-first:596.19 08:50:46 INFO - PROCESS | 7910 | Values: 3086.6 3122.5 3135.0 3140.6 3193.0 5174.1 5208.4 5214.2 5277.7 5299.7 5273.5 5314.0 5412.2 5415.5 5416.2 4793.5 4807.3 4889.7 4907.5 4957.8 4844.2 4856.1 4896.9 4902.5 4931.2 4645.4 4745.3 4798.9 4809.7 4820.3 4808.3 4817.3 4817.4 4820.6 4856.7 4680.5 4800.5 4807.2 4829.2 4836.5 5265.9 5315.3 5355.5 5395.0 5414.9 5801.6 5801.7 5811.3 5828.3 5831.7 5576.2 5578.4 5850.9 5875.1 5908.8 5302.6 5393.2 5394.6 5407.6 5443.2 5886.9 5917.9 5929.5 5941.7 5942.3 5540.9 5599.5 5602.0 5649.4 5685.4 5738.0 5743.9 5748.2 5766.9 5790.7 5674.0 5697.3 5737.5 5737.6 5745.7 5518.7 5607.7 5622.1 5630.0 5694.6 4276.6 4277.9 4280.2 4283.3 4309.8 3806.1 3819.5 3849.0 3877.7 3879.5 5189.0 5255.8 5323.2 5388.4 5401.1 5756.6 5775.2 5776.1 5777.2 5801.9 5393.4 5398.7 5413.0 5424.9 5431.2 4807.2 4918.8 4967.5 4970.6 4974.3 5985.0 6009.9 6012.0 6017.5 6025.4 5578.3 5788.2 5791.1 5795.2 5806.0 5852.9 5887.6 5898.3 5901.9 5918.2 5548.1 5585.1 5604.6 5629.1 5636.5 5541.9 5653.9 5697.1 5708.1 5719.8 5575.3 5582.4 5626.5 5630.5 5688.2 5544.8 5605.9 5606.5 5629.6 5634.4 5260.9 5408.3 5431.6 5450.3 5506.8 5630.8 5652.6 5679.7 5709.5 5721.1 5517.7 5532.7 5534.6 5542.6 5584.1 08:50:46 INFO - PROCESS | 7910 | 08:50:46 INFO - PROCESS | 7910 | [#1] ext.html Cycles:180 Average:52263.02 Median:38776.71 stddev:91247.83 (235.3%) stddev-sans-first:91421.31 08:50:46 INFO - PROCESS | 7910 | Values: 586.5 603.3 613.0 621.8 628.8 31053.2 41565.6 42481.0 42527.4 45852.8 43819.3 43972.3 44057.0 44116.3 44523.1 7663.8 8593.3 8810.3 8817.1 10338.6 9917.9 10387.6 10393.6 10399.3 10408.6 10391.2 10395.8 10448.9 10455.6 10456.8 7869.6 10185.4 10284.6 10335.3 10488.1 10296.0 10429.1 10447.3 10457.3 10461.4 40112.8 40376.8 40395.8 40435.2 40612.6 3732.8 4037.0 4110.3 4190.2 4211.7 31231.5 31454.2 31766.0 31809.7 33148.3 4213.2 4233.0 4280.4 4396.9 4424.4 299261.0 304362.5 304780.0 304875.0 309757.0 58241.7 58822.7 59018.0 59242.0 59316.7 61907.7 62092.5 62147.7 62248.0 62324.4 16678.9 17217.8 17235.2 17245.0 17354.8 11995.6 12949.6 13206.7 13292.7 13364.5 17324.2 17512.2 17585.8 17619.6 17654.2 5308.7 6137.3 6177.8 6213.0 6240.3 970.6 1133.3 1162.9 1185.6 1239.9 30910.8 31033.4 31219.5 31223.8 31312.7 1109.6 1122.5 1126.8 1183.7 1235.7 15903.9 16349.8 16525.5 16670.5 16906.6 418169.0 420433.0 420588.0 420667.0 421633.0 56968.3 57177.1 57232.4 57485.4 57693.0 1284.7 1373.1 1391.7 1394.9 1414.3 28237.4 31889.2 32616.0 32667.0 32920.5 47507.5 47658.5 47778.8 47881.8 48172.5 301856.0 305918.0 306056.0 306936.0 307038.0 35266.6 39855.8 40692.4 41156.6 41635.4 46110.5 46423.3 46483.3 46995.0 47006.8 38526.0 39275.0 40043.0 40069.6 41059.6 39600.8 39616.8 39846.4 40019.0 40123.6 40228.8 41554.8 42505.6 43033.0 44628.9 33412.5 36476.0 36719.3 36757.6 39027.4 27169.3 27475.3 27490.0 27684.7 27772.3 08:50:46 INFO - PROCESS | 7910 | 08:50:46 INFO - PROCESS | 7910 | [#2] jquery.html Cycles:180 Average:27216.83 Median:2395.45 stddev:77275.43 (3225.9%) stddev-sans-first:77465.97 08:50:46 INFO - PROCESS | 7910 | Values: 397.8 399.1 400.2 401.7 420.3 13559.2 16693.5 17922.1 19275.5 19287.8 11923.9 12337.4 12420.0 18437.7 18773.3 5101.5 5252.4 5259.4 5284.6 5428.0 5518.3 5534.5 5598.0 5631.6 5667.1 5334.4 5609.7 5629.5 5630.0 5645.4 5682.1 5696.8 5705.6 5717.9 5727.7 5227.8 5420.5 5438.9 5464.7 5535.2 13951.3 14107.4 14152.4 14317.9 22147.6 2336.1 2394.6 2396.3 2425.8 2920.8 10062.7 10135.4 10553.3 10622.5 10891.1 1919.5 1954.6 1989.0 1993.5 2001.3 73478.6 75065.2 75080.6 75969.6 76813.8 11124.6 11277.0 11668.2 11792.4 12186.3 13657.0 14159.3 15635.3 15721.9 15735.1 6537.4 6880.2 8099.9 8404.5 8481.6 8089.9 8117.0 8122.6 8201.5 8215.1 4746.8 4831.6 4877.4 4912.5 4940.1 1945.7 1997.2 1999.1 2001.6 2038.7 928.3 974.2 1037.7 1043.2 1103.5 18552.7 18892.5 19004.7 19138.3 19455.4 977.7 977.8 991.0 1038.7 1076.9 9914.5 10136.2 10389.6 10525.2 10567.5 446704.0 447923.0 452132.0 496413.0 499379.0 84683.6 89586.6 89794.1 90074.0 90177.0 1006.8 1009.4 1018.7 1029.4 1041.8 16093.2 16429.8 16487.0 16498.8 16695.0 25431.5 30330.1 37418.0 37739.4 38789.6 48879.5 49493.8 50080.7 50297.7 50376.7 7087.3 8349.3 8528.5 8562.1 8580.1 11076.4 12221.4 13114.7 13233.3 13293.5 10650.6 11161.2 11231.4 11524.4 11555.2 11154.7 11165.9 11343.3 11441.2 11459.8 11722.4 11729.8 11974.2 12087.7 12152.2 7876.2 7958.0 8013.6 8132.4 8180.5 10087.5 11295.4 11333.9 11712.6 11830.1 08:50:46 INFO - PROCESS | 7910 | 08:50:46 INFO - PROCESS | 7910 | [#3] mootools.html Cycles:180 Average:5842.94 Median:460.37 stddev:3388.46 (736.0%) stddev-sans-first:3381.21 08:50:46 INFO - PROCESS | 7910 | Values: 1359.4 1553.1 1581.2 1719.2 1870.5 8389.7 8724.3 8738.7 8919.9 8929.0 8641.5 8756.2 8804.4 8810.0 8814.2 2044.5 2190.8 2231.9 2257.0 2261.3 2367.6 2371.4 2377.9 2380.9 2387.2 2266.6 2295.2 2297.0 2311.9 2321.4 2006.5 2238.1 2303.6 2305.2 2313.8 2273.6 2287.0 2291.2 2298.9 2341.9 8900.2 8903.6 8944.0 8967.7 9003.4 5140.6 5284.9 5302.7 5308.1 5322.8 3385.5 3526.6 3537.3 3540.0 3585.7 1087.7 1097.0 1107.4 1130.2 1135.4 12427.7 12610.2 12627.2 12688.4 12709.9 4651.9 4684.8 4762.4 4790.4 4806.0 10645.1 10668.7 10682.4 10729.4 10734.6 4819.1 5332.3 5336.9 5380.8 5386.8 4713.0 4835.2 4847.7 4851.4 4865.6 3908.2 3927.8 3948.2 4041.8 4061.1 1238.7 1239.7 1255.4 1262.6 1272.9 932.7 974.0 997.7 1002.4 1016.0 5690.7 5734.7 5904.8 5935.1 6020.7 445.9 447.4 452.6 457.3 463.4 3924.5 4060.7 4067.0 4077.3 4080.3 10747.8 10889.8 11060.1 11105.8 11396.5 10705.8 11060.9 11080.8 11297.8 11319.8 8180.0 8359.5 8379.2 8425.3 8482.4 5746.8 5792.5 5816.8 5905.6 5938.2 10010.1 10164.6 10186.1 10219.8 10285.0 10001.3 10444.1 10532.6 10576.6 10614.8 7098.7 7607.5 7730.4 7830.6 8016.1 8659.8 8738.7 8750.5 8825.3 8854.8 7657.8 7812.5 7813.9 7917.6 8093.3 7579.5 7696.7 7804.6 7933.4 7958.9 6675.8 6793.4 6800.7 6845.8 6852.0 4931.8 5039.7 5199.9 5229.9 5249.1 5312.6 5328.2 5348.0 5368.5 5372.0 08:50:46 INFO - PROCESS | 7910 | 08:50:46 INFO - PROCESS | 7910 | [#4] prototype.html Cycles:180 Average:4545.08 Median:5177.60 stddev:1318.19 (25.5%) stddev-sans-first:1283.06 08:50:46 INFO - PROCESS | 7910 | Values: 313.7 325.2 326.0 332.8 333.7 5128.0 5167.5 5203.9 5224.8 5249.5 5151.4 5159.2 5171.3 5173.5 5188.6 3838.5 3872.5 3988.4 4002.9 4028.6 4001.2 4007.6 4007.9 4010.3 4020.3 3885.9 4007.6 4008.4 4010.5 4031.9 3925.4 4051.2 4060.9 4065.8 4071.4 3549.5 3559.4 3575.7 3576.8 3587.9 4899.8 4903.7 4964.1 4974.6 4990.4 4536.7 4602.3 4608.4 4627.8 4629.3 5339.3 5344.5 5344.7 5391.0 5446.8 3904.5 3928.5 3945.1 3974.2 3976.3 5296.5 5548.7 5561.8 5583.6 5635.9 3981.4 4000.2 4012.2 4019.9 4037.4 4054.3 4057.3 4071.6 4072.3 4096.9 4472.8 4569.2 4605.1 4616.0 4621.9 5358.0 5370.6 5411.2 5473.0 5473.6 1394.3 1423.2 1424.1 1429.9 1431.3 908.3 908.6 961.2 984.7 986.8 5053.2 5112.9 5158.5 5167.0 5169.4 5371.0 5499.5 5504.0 5525.1 5527.6 5006.9 5140.8 5158.5 5162.6 5209.7 2922.9 2929.7 2940.0 2946.6 2949.3 5575.7 5609.3 5640.9 5665.3 5723.8 5566.5 5632.6 5651.0 5666.7 5671.0 5585.0 5620.2 5646.7 5681.0 5697.2 5233.8 5459.8 5493.8 5499.5 5506.1 5398.9 5400.5 5414.8 5442.2 5442.3 5623.2 5674.3 5728.8 5739.3 5751.3 3960.7 4028.6 4078.0 4088.2 4092.3 5467.9 5479.3 5482.5 5505.1 5514.5 5314.3 5340.4 5367.6 5386.6 5399.9 5181.7 5414.3 5477.2 5495.0 5500.4 5084.6 5200.0 5278.3 5294.3 5324.8 5392.8 5417.2 5429.7 5449.9 5457.8 5371.9 5374.6 5379.9 5398.6 5406.5 08:50:46 INFO - PROCESS | 7910 | 08:50:46 INFO - PROCESS | 7910 | [#5] yui.html Cycles:180 Average:15184.06 Median:24972.51 stddev:17621.13 (70.6%) stddev-sans-first:17637.23 08:50:46 INFO - PROCESS | 7910 | Values: 751.1 810.8 852.5 861.2 867.7 14262.0 14644.5 14783.7 14915.7 15024.1 12855.7 12906.5 12966.0 13011.0 13018.4 681.1 796.1 813.4 819.9 827.7 816.5 824.8 826.8 827.2 827.7 876.9 882.1 885.0 885.0 886.0 728.7 861.8 877.1 881.2 887.1 626.1 627.7 630.0 632.1 634.1 13974.4 13996.7 14017.2 14040.4 14044.4 13343.1 13653.7 13774.6 13781.2 13934.4 11187.6 11210.8 11386.9 11416.3 11501.5 4043.5 4087.0 4112.7 4179.3 4183.8 47379.3 49234.0 49544.0 53986.2 54395.7 10566.6 11183.6 11288.8 11448.9 11542.6 19051.1 19538.4 19599.4 20142.9 20236.8 8656.9 8694.8 8716.8 8770.3 8916.3 4712.2 4787.4 4830.4 4867.7 4902.0 3458.2 3559.3 3566.7 3589.5 3592.3 627.6 649.3 657.5 662.7 673.6 690.2 718.0 734.6 779.6 785.4 9865.0 10299.6 10318.3 10356.3 10495.1 680.5 713.6 733.4 767.4 800.3 4487.6 4854.7 4856.0 4861.8 4878.5 65203.7 66339.3 67176.0 67421.7 67667.7 54402.5 54488.7 56427.0 57733.4 60535.0 52468.2 52778.7 52987.0 53444.0 53895.7 9272.9 9476.2 9516.8 9529.8 9584.2 32480.5 33003.0 33893.5 34436.7 34613.8 45232.8 48766.3 48810.5 49064.8 49445.8 12004.7 12009.4 12126.3 12813.2 13142.1 13191.3 13281.7 13618.5 14068.9 14077.8 12851.4 12919.2 13301.8 13335.7 13457.7 12440.9 12538.2 12555.4 12617.0 13014.2 12144.8 12286.8 12465.6 13062.6 13459.7 10390.8 10652.2 10793.6 11095.8 11132.7 11225.6 11305.3 11498.7 11570.4 11617.2 08:50:46 INFO - PROCESS | 7910 | -------- Summary: end -------- 08:50:46 INFO - PROCESS | 7910 | 08:50:47 INFO - TEST-INFO | 7910: exit 0 08:50:47 INFO - TEST-OK | dromaeo_css | took 1111237ms 08:50:47 INFO - TEST-START | kraken 08:50:47 INFO - operating with platform_type : linux_ 08:50:47 INFO - Initialising browser for kraken test... 08:50:47 INFO - TEST-INFO | started process 8085 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAbgsiS/profile http://localhost:43435/getInfo.html) 08:50:51 INFO - PROCESS | 8085 | __metrics Screen width/height:1600/1200 08:50:51 INFO - PROCESS | 8085 | colorDepth:24 08:50:51 INFO - PROCESS | 8085 | Browser inner width/height: 1024/697 08:50:51 INFO - PROCESS | 8085 | __metrics 08:50:52 ERROR - PROCESS | 8085 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:50:52 INFO - TEST-INFO | 8085: exit 0 08:50:52 INFO - Browser initialized. 08:50:52 INFO - Running cycle 1/1 for kraken test... 08:50:52 INFO - TEST-INFO | started process 8168 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAbgsiS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:51:04 INFO - PROCESS | 8168 | RSS: Main: 150433792 08:51:04 INFO - PROCESS | 8168 | 08:51:06 INFO - PROCESS | 8168 | Cycle 1(1): loaded http://localhost:43435/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43435/tests/kraken/driver.html?testName=audio-beat-detection) 08:51:07 INFO - PROCESS | 8168 | RSS: Main: 207331328 08:51:07 INFO - PROCESS | 8168 | 08:51:10 INFO - PROCESS | 8168 | Cycle 1(1): loaded http://localhost:43435/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43435/tests/kraken/driver.html?testName=audio-dft) 08:51:11 INFO - PROCESS | 8168 | RSS: Main: 195956736 08:51:11 INFO - PROCESS | 8168 | 08:51:14 INFO - PROCESS | 8168 | Cycle 1(1): loaded http://localhost:43435/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43435/tests/kraken/driver.html?testName=audio-fft) 08:51:14 INFO - PROCESS | 8168 | RSS: Main: 263958528 08:51:14 INFO - PROCESS | 8168 | 08:51:16 INFO - PROCESS | 8168 | Cycle 1(1): loaded http://localhost:43435/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43435/tests/kraken/driver.html?testName=audio-oscillator) 08:51:17 INFO - PROCESS | 8168 | RSS: Main: 172789760 08:51:17 INFO - PROCESS | 8168 | 08:51:19 INFO - PROCESS | 8168 | Cycle 1(1): loaded http://localhost:43435/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43435/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:51:20 INFO - PROCESS | 8168 | RSS: Main: 176664576 08:51:20 INFO - PROCESS | 8168 | 08:51:23 INFO - PROCESS | 8168 | Cycle 1(1): loaded http://localhost:43435/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43435/tests/kraken/driver.html?testName=imaging-darkroom) 08:51:24 INFO - PROCESS | 8168 | RSS: Main: 213372928 08:51:24 INFO - PROCESS | 8168 | 08:51:27 INFO - PROCESS | 8168 | Cycle 1(1): loaded http://localhost:43435/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43435/tests/kraken/driver.html?testName=imaging-desaturate) 08:51:28 INFO - PROCESS | 8168 | RSS: Main: 211288064 08:51:28 INFO - PROCESS | 8168 | 08:51:31 INFO - PROCESS | 8168 | Cycle 1(1): loaded http://localhost:43435/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43435/tests/kraken/driver.html?testName=json-parse-financial) 08:51:32 INFO - PROCESS | 8168 | RSS: Main: 212099072 08:51:32 INFO - PROCESS | 8168 | 08:51:33 INFO - PROCESS | 8168 | Cycle 1(1): loaded http://localhost:43435/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43435/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:51:34 INFO - PROCESS | 8168 | RSS: Main: 201826304 08:51:34 INFO - PROCESS | 8168 | 08:51:35 INFO - PROCESS | 8168 | Cycle 1(1): loaded http://localhost:43435/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43435/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:51:36 INFO - PROCESS | 8168 | RSS: Main: 205950976 08:51:36 INFO - PROCESS | 8168 | 08:51:38 INFO - PROCESS | 8168 | Cycle 1(1): loaded http://localhost:43435/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43435/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:51:39 INFO - PROCESS | 8168 | RSS: Main: 214700032 08:51:39 INFO - PROCESS | 8168 | 08:51:42 INFO - PROCESS | 8168 | Cycle 1(1): loaded http://localhost:43435/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43435/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:51:42 INFO - PROCESS | 8168 | RSS: Main: 220880896 08:51:42 INFO - PROCESS | 8168 | 08:51:45 INFO - PROCESS | 8168 | Cycle 1(1): loaded http://localhost:43435/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43435/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:51:46 INFO - PROCESS | 8168 | RSS: Main: 217313280 08:51:46 INFO - PROCESS | 8168 | 08:51:47 INFO - PROCESS | 8168 | Cycle 1(1): loaded http://localhost:43435/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43435/tests/kraken/driver.html?testName=ai-astar) 08:51:47 INFO - PROCESS | 8168 | RSS: Main: 263376896 08:51:47 INFO - PROCESS | 8168 | 08:51:47 INFO - PROCESS | 8168 | __start_tp_report 08:51:47 INFO - PROCESS | 8168 | _x_x_mozilla_page_load 08:51:47 INFO - PROCESS | 8168 | _x_x_mozilla_page_load_details 08:51:47 INFO - PROCESS | 8168 | |i|pagename|runs| 08:51:47 INFO - PROCESS | 8168 | |0;ai-astar;111;105;112;111;110;111;110;113;111;112 08:51:47 INFO - PROCESS | 8168 | |1;audio-beat-detection;155;136;154;215;136;160;155;132;196;134 08:51:47 INFO - PROCESS | 8168 | |2;audio-dft;161;162;161;162;164;163;163;162;164;163 08:51:47 INFO - PROCESS | 8168 | |3;audio-fft;77;78;78;144;86;77;78;76;132;78 08:51:47 INFO - PROCESS | 8168 | |4;audio-oscillator;93;93;145;93;94;94;121;92;93;118 08:51:47 INFO - PROCESS | 8168 | |5;imaging-gaussian-blur;118;118;119;117;118;118;119;118;118;117 08:51:47 INFO - PROCESS | 8168 | |6;imaging-darkroom;147;148;147;147;148;149;149;149;150;149 08:51:47 INFO - PROCESS | 8168 | |7;imaging-desaturate;96;98;97;96;96;97;96;95;97;97 08:51:47 INFO - PROCESS | 8168 | |8;json-parse-financial;57;59;59;57;58;58;58;113;55;55 08:51:47 INFO - PROCESS | 8168 | |9;json-stringify-tinderbox;60;60;60;60;60;60;61;61;60;61 08:51:47 INFO - PROCESS | 8168 | |10;stanford-crypto-aes;86;80;81;81;86;81;82;87;87;81 08:51:47 INFO - PROCESS | 8168 | |11;stanford-crypto-ccm;139;132;131;129;135;128;133;130;215;134 08:51:47 INFO - PROCESS | 8168 | |12;stanford-crypto-pbkdf2;171;167;170;176;169;167;170;170;167;171 08:51:47 INFO - PROCESS | 8168 | |13;stanford-crypto-sha256-iterative;67;66;70;67;65;68;66;66;65;66 08:51:47 INFO - PROCESS | 8168 | __end_tp_report 08:51:47 INFO - PROCESS | 8168 | __start_cc_report 08:51:47 INFO - PROCESS | 8168 | _x_x_mozilla_cycle_collect,1707 08:51:47 INFO - PROCESS | 8168 | __end_cc_report 08:51:47 INFO - PROCESS | 8168 | __startTimestamp1462981907954__endTimestamp 08:51:47 INFO - PROCESS | 8168 | 08:51:47 INFO - PROCESS | 8168 | ------- Summary: start ------- 08:51:47 INFO - PROCESS | 8168 | Number of tests: 14 08:51:47 INFO - PROCESS | 8168 | 08:51:47 INFO - PROCESS | 8168 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.00 stddev:2.17 (2.0%) stddev-sans-first:2.30 08:51:47 INFO - PROCESS | 8168 | Values: 111.0 105.0 112.0 111.0 110.0 111.0 110.0 113.0 111.0 112.0 08:51:47 INFO - PROCESS | 8168 | 08:51:47 INFO - PROCESS | 8168 | [#1] audio-beat-detection Cycles:10 Average:157.30 Median:155.00 stddev:27.78 (17.9%) stddev-sans-first:29.45 08:51:47 INFO - PROCESS | 8168 | Values: 155.0 136.0 154.0 215.0 136.0 160.0 155.0 132.0 196.0 134.0 08:51:47 INFO - PROCESS | 8168 | 08:51:47 INFO - PROCESS | 8168 | [#2] audio-dft Cycles:10 Average:162.50 Median:163.00 stddev:1.08 (0.7%) stddev-sans-first:1.00 08:51:47 INFO - PROCESS | 8168 | Values: 161.0 162.0 161.0 162.0 164.0 163.0 163.0 162.0 164.0 163.0 08:51:47 INFO - PROCESS | 8168 | 08:51:47 INFO - PROCESS | 8168 | [#3] audio-fft Cycles:10 Average:90.40 Median:78.00 stddev:25.40 (32.6%) stddev-sans-first:26.47 08:51:47 INFO - PROCESS | 8168 | Values: 77.0 78.0 78.0 144.0 86.0 77.0 78.0 76.0 132.0 78.0 08:51:47 INFO - PROCESS | 8168 | 08:51:47 INFO - PROCESS | 8168 | [#4] audio-oscillator Cycles:10 Average:103.60 Median:93.00 stddev:18.23 (19.6%) stddev-sans-first:18.93 08:51:47 INFO - PROCESS | 8168 | Values: 93.0 93.0 145.0 93.0 94.0 94.0 121.0 92.0 93.0 118.0 08:51:47 INFO - PROCESS | 8168 | 08:51:47 INFO - PROCESS | 8168 | [#5] imaging-gaussian-blur Cycles:10 Average:118.00 Median:118.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 08:51:47 INFO - PROCESS | 8168 | Values: 118.0 118.0 119.0 117.0 118.0 118.0 119.0 118.0 118.0 117.0 08:51:47 INFO - PROCESS | 8168 | 08:51:47 INFO - PROCESS | 8168 | [#6] imaging-darkroom Cycles:10 Average:148.30 Median:149.00 stddev:1.06 (0.7%) stddev-sans-first:1.01 08:51:47 INFO - PROCESS | 8168 | Values: 147.0 148.0 147.0 147.0 148.0 149.0 149.0 149.0 150.0 149.0 08:51:47 INFO - PROCESS | 8168 | 08:51:47 INFO - PROCESS | 8168 | [#7] imaging-desaturate Cycles:10 Average:96.50 Median:97.00 stddev:0.85 (0.9%) stddev-sans-first:0.88 08:51:47 INFO - PROCESS | 8168 | Values: 96.0 98.0 97.0 96.0 96.0 97.0 96.0 95.0 97.0 97.0 08:51:47 INFO - PROCESS | 8168 | 08:51:47 INFO - PROCESS | 8168 | [#8] json-parse-financial Cycles:10 Average:62.90 Median:58.00 stddev:17.66 (30.4%) stddev-sans-first:18.60 08:51:47 INFO - PROCESS | 8168 | Values: 57.0 59.0 59.0 57.0 58.0 58.0 58.0 113.0 55.0 55.0 08:51:47 INFO - PROCESS | 8168 | 08:51:47 INFO - PROCESS | 8168 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:60.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 08:51:47 INFO - PROCESS | 8168 | Values: 60.0 60.0 60.0 60.0 60.0 60.0 61.0 61.0 60.0 61.0 08:51:47 INFO - PROCESS | 8168 | 08:51:47 INFO - PROCESS | 8168 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:84.00 stddev:2.90 (3.5%) stddev-sans-first:2.89 08:51:47 INFO - PROCESS | 8168 | Values: 86.0 80.0 81.0 81.0 86.0 81.0 82.0 87.0 87.0 81.0 08:51:47 INFO - PROCESS | 8168 | 08:51:47 INFO - PROCESS | 8168 | [#11] stanford-crypto-ccm Cycles:10 Average:140.60 Median:133.50 stddev:26.34 (19.7%) stddev-sans-first:27.93 08:51:47 INFO - PROCESS | 8168 | Values: 139.0 132.0 131.0 129.0 135.0 128.0 133.0 130.0 215.0 134.0 08:51:47 INFO - PROCESS | 8168 | 08:51:47 INFO - PROCESS | 8168 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.80 Median:170.00 stddev:2.70 (1.6%) stddev-sans-first:2.83 08:51:47 INFO - PROCESS | 8168 | Values: 171.0 167.0 170.0 176.0 169.0 167.0 170.0 170.0 167.0 171.0 08:51:47 INFO - PROCESS | 8168 | 08:51:47 INFO - PROCESS | 8168 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:66.50 stddev:1.51 (2.3%) stddev-sans-first:1.59 08:51:47 INFO - PROCESS | 8168 | Values: 67.0 66.0 70.0 67.0 65.0 68.0 66.0 66.0 65.0 66.0 08:51:47 INFO - PROCESS | 8168 | -------- Summary: end -------- 08:51:47 INFO - PROCESS | 8168 | 08:51:48 INFO - TEST-INFO | 8168: exit 0 08:51:48 INFO - TEST-OK | kraken | took 61409ms 08:51:48 INFO - SUITE-END | took 1172s 08:51:49 INFO - Completed test suite (00:19:32) 08:51:49 INFO - javascript benchmark 08:51:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3086.6450358208804, 3122.4691802393577, 3134.9686503134963, 3140.607424071991, 3193.042087373787, 5174.094533456649, 5208.42707302197, 5214.243934629486, 5277.736113194339, 5299.735013249337, 5273.472652734725, 5313.9734301328535, 5412.2152287918225, 5415.458454154593, 5416.187571864222, 4793.472718001034, 4807.326974223612, 4889.706617602955, 4907.460179380265, 4957.752112394366, 4844.249141383091, 4856.125897338477, 4896.853094407174, 4902.534259245366, 4931.186354251544, 4645.442546894377, 4745.311929770181, 4798.904021919577, 4809.711417314972, 4820.325154478376, 4808.278758186265, 4817.277408388734, 4817.397825271841, 4820.590249828769, 4856.68431551948, 4680.461746899082, 4800.519948005206, 4807.158747219222, 4829.203181475053, 4836.540528649773, 5265.894682106336, 5315.255864179018, 5355.464453554652, 5394.973025134849, 5414.945850541523, 5801.6228945118855, 5801.7099145043, 5811.27359080115, 5828.3297420796725, 5831.708414579254, 5576.163575463673, 5578.3584887738025, 5850.912236316459, 5875.059990401558, 5908.793192238251, 5302.628815982883, 5393.164059570773, 5394.595405344615, 5407.567394608501, 5443.2107344435235, 5886.852828679232, 5917.940820591825, 5929.466348028698, 5941.70291485424, 5942.286925568959, 5540.889182216333, 5599.524040456607, 5602.0, 5649.350324712628, 5685.374608793029, 5738.024535828919, 5743.885122297531, 5748.19525266463, 5766.913496297559, 5790.739416726257, 5674.0, 5697.287839020122, 5737.454941780524, 5737.627054241461, 5745.712714364348, 5518.724063796794, 5607.719614019283, 5622.072358060874, 5629.971850140723, 5694.629849059798, 4276.593723596254, 4277.871663850089, 4280.165367753259, 4283.336082907154, 4309.806058727302, 3806.1245913440066, 3819.52255968004, 3848.980020294624, 3877.728559000843, 3879.4568760373572, 5188.9740551297, 5255.816046438356, 5323.201519772065, 5388.353397592314, 5401.108817045144, 5756.597038207369, 5775.220345253337, 5776.104703770922, 5777.19119323295, 5801.9129713054335, 5393.352797664305, 5398.676079435168, 5413.0, 5424.864378390572, 5431.2396264522995, 4807.230843065093, 4918.778654960535, 4967.528084831935, 4970.602351811846, 4974.328465657162, 5985.042393217152, 6009.909851352233, 6011.969940150271, 6017.518598512109, 6025.3974602540275, 5578.30271216098, 5788.160716696139, 5791.0734282514595, 5795.246617939641, 5806.042003069446, 5852.882942341214, 5887.61730487517, 5898.262717160355, 5901.852453688521, 5918.23063001807, 5548.112302031655, 5585.106382978704, 5604.607677462538, 5629.0712032514175, 5636.549076073986, 5541.861453463695, 5653.858653533694, 5697.145428185805, 5708.086706127, 5719.799807006735, 5575.303087114111, 5582.441755824385, 5626.493615574454, 5630.549556035589, 5688.203651488712, 5544.833654990357, 5605.943940560543, 5606.523445507178, 5629.577781666473, 5634.4365563443325, 5260.86847828792, 5408.269883565668, 5431.619786614899, 5450.345958485007, 5506.834794956158, 5630.831075067755, 5652.576056795839, 5679.716014199191, 5709.486146246858, 5721.113227449752, 5517.668939863621, 5532.695701736443, 5534.640248383842, 5542.63972841764, 5584.050711379156], "value": 5265.147875829937, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.5483577645214, 603.2911329188204, 612.9846753831151, 621.782376168341, 628.7673560782512, 31053.223669408275, 41565.58434415655, 42481.0, 42527.36208956868, 45852.770736146354, 43819.342709859375, 43972.3404148938, 44057.0, 44116.33825492612, 44523.10953780931, 7663.846723065535, 8593.312534997185, 8810.295176385906, 8817.074207208247, 10338.638147664833, 9917.90082099177, 10387.584496620126, 10393.584256629725, 10399.32404393712, 10408.63569775058, 10391.168706503462, 10395.84406233907, 10448.895511044868, 10455.634052808153, 10456.843147352796, 7869.645865936047, 10185.388876667424, 10284.58861645537, 10335.276530642857, 10488.108510776594, 10296.021877921585, 10429.113525350354, 10447.320924139982, 10457.267991240617, 10461.424621645807, 40112.799436002926, 40376.79811600923, 40395.79802101, 40435.191296173914, 40612.593874061175, 3732.794696291703, 4036.990752311922, 4110.301248787713, 4190.182914331706, 4211.7051806373565, 31231.531527027113, 31454.213644659067, 31766.04701858948, 31809.681903180903, 33148.3370332592, 4213.241616509027, 4233.0, 4280.400743895857, 4396.912061758779, 4424.424824772791, 299261.0, 304362.4781876099, 304780.0, 304875.0, 309757.0, 58241.70879145577, 58822.70588647115, 59018.0, 59242.0, 59316.70341648264, 61907.690461547405, 62092.51629934843, 62147.6892615534, 62248.0, 62324.37675623276, 16678.916605417136, 17217.827821721872, 17235.22441490136, 17245.0, 17354.826451735575, 11995.640130795915, 12949.611511654666, 13206.669833254246, 13292.73414531704, 13364.532241371506, 17324.220410081325, 17512.21195046226, 17585.824141758676, 17619.647607047787, 17654.205560749797, 5308.708021058802, 6137.263528376623, 6177.7837775677635, 6213.0, 6240.251169859555, 970.5535453691381, 1133.257716195893, 1162.924409913353, 1185.5909711149638, 1239.876012398753, 30910.845445773077, 31033.379332413224, 31219.531707024413, 31223.843880780452, 31312.686873130977, 1109.589451902801, 1122.5060973171942, 1126.8253420719802, 1183.7336599265097, 1235.71578536937, 15903.920480397524, 16349.754753678704, 16525.504234872973, 16670.499885003468, 16906.607237018823, 418169.0, 420433.0, 420588.0, 420667.0, 421633.0, 56968.29706069092, 57177.14234286489, 57232.42767572271, 57485.425145748006, 57693.0, 1284.6916739982523, 1373.0800363756107, 1391.72165566885, 1394.8814350780094, 1414.2887106601129, 28237.43525129527, 31889.202769930936, 32616.0, 32667.0, 32920.506192406654, 47507.52492475031, 47658.52341476541, 47778.761106195634, 47881.76059119682, 48172.5182748168, 301856.0, 305918.0, 306056.0, 306936.0, 307038.0, 35266.64733352736, 39855.80072099737, 40692.38961415522, 41156.58843411528, 41635.37546936859, 46110.538894610625, 46423.30365044459, 46483.3027504581, 46995.0, 47006.76496617495, 38526.0, 39275.0, 40043.0, 40069.59930400659, 41059.58940410558, 39600.80199598984, 39616.80191599023, 39846.402303966046, 40019.0, 40123.59876401316, 40228.79885600553, 41554.792226039885, 42505.5749442514, 43033.0, 44628.8804963855, 33412.498812518315, 36476.0, 36719.26561468702, 36757.63242367542, 39027.41458878176, 27169.320766980985, 27475.31311717223, 27490.0, 27684.723152768216, 27772.305692357044], "value": 20093.150592458718, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.77128151313, 399.0940564917635, 400.1916129418574, 401.6927050806133, 420.26453706014473, 13559.221163097493, 16693.49919502414, 17922.103894805256, 19275.51811204719, 19287.807121928796, 11923.880761192386, 12337.383130843444, 12420.0, 18437.7234341485, 18773.342932997348, 5101.54086132248, 5252.422233554306, 5259.447757985414, 5284.577233821305, 5428.0, 5518.255035570207, 5534.4742249486435, 5597.972010139943, 5631.60578759487, 5667.149927510885, 5334.386545547273, 5609.747561359728, 5629.493345598895, 5629.971850140743, 5645.407232240596, 5682.119271512922, 5696.800611978581, 5705.62913410627, 5717.885642287151, 5727.742251598688, 5227.764750586232, 5420.539254163401, 5438.885028569134, 5464.726763661801, 5535.169724541311, 13951.302434878215, 14107.435702571885, 14152.433902643881, 14317.928410357881, 22147.557048858933, 2336.1356298169617, 2394.580948334052, 2396.3290278721984, 2425.834440281987, 2920.751736102434, 10062.748431289203, 10135.363790186155, 10553.314034587727, 10622.521986510626, 10891.128709703284, 1919.4625504858661, 1954.6334441852414, 1988.9801101989085, 1993.4916596268004, 2001.329554599211, 73478.63260683662, 75065.24934750637, 75080.62459687666, 75969.62015189943, 76813.84779228372, 11124.55501779936, 11277.011591927981, 11668.183227174186, 11792.351420671774, 12186.329751863732, 13657.0, 14159.292035398188, 15635.296411661502, 15721.921390392974, 15735.134567598663, 6537.378948999837, 6880.174379074543, 8099.878501822477, 8404.537750423664, 8481.575921203916, 8089.919100809035, 8117.0, 8122.593870306461, 8201.507909525448, 8215.137410571924, 4746.833860814924, 4831.613470922318, 4877.365942427462, 4912.533309335608, 4940.110780059552, 1945.7081437784163, 1997.2011195521618, 1999.1303782854504, 2001.5896741168026, 2038.6738121900426, 928.2852203803034, 974.2108891797667, 1037.7042542875245, 1043.160255993917, 1103.536514663843, 18552.7340166515, 18892.527686807938, 19004.714929276073, 19138.330158444387, 19455.416337509898, 977.6529332087149, 977.8044391121662, 990.960361585543, 1038.7351225437465, 1076.8707755069286, 9914.504274786232, 10136.189104871593, 10389.584416623402, 10525.21060920434, 10567.524461399256, 446704.0, 447923.0, 452132.0, 496413.0, 499379.0, 84683.5765821167, 89586.55206723924, 89794.10205897858, 90074.0, 90177.0, 1006.8187726209206, 1009.3893194617298, 1018.7249442650591, 1029.3926583315729, 1041.8124737547162, 16093.195340233176, 16429.83570164283, 16487.0, 16498.75251871247, 16695.0, 25431.457265754598, 30330.090097297118, 37418.0, 37739.43390849084, 38789.6121038786, 48879.511204887494, 49493.752531237114, 50080.749596251786, 50297.74851125721, 50376.74811626065, 7087.255838137127, 8349.332053435832, 8528.530930798866, 8562.058173601023, 8580.099089595502, 11076.446177690923, 12221.38893055326, 13114.737705246032, 13233.338333083115, 13293.468261269827, 10650.627228046982, 11161.162912781412, 11231.38227397501, 11524.423778811193, 11555.2489088209, 11154.72113197144, 11165.944170279097, 11343.319400835977, 11441.199116061796, 11459.828102578635, 11722.355270460206, 11729.765404692029, 11974.22167559106, 12087.697807554881, 12152.21010634306, 7876.212378762078, 7957.960210199143, 8013.639386227517, 8132.390070744599, 8180.520533365146, 10087.546060427445, 11295.3787541686, 11333.886661133613, 11712.590059347882, 11830.05359571215], "value": 8832.977092563784, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1359.3611002828668, 1553.0526378908867, 1581.2410043179275, 1719.2435328455479, 1870.5136664467243, 8389.748307550768, 8724.345674074446, 8738.73783786486, 8919.910800891988, 8929.0, 8641.481511109338, 8756.168164024424, 8804.383693141483, 8810.030896601369, 8814.206721395074, 2044.5399785048394, 2190.7809219078126, 2231.9107235710553, 2257.0407576779885, 2261.321603518946, 2367.562001029815, 2371.430856594422, 2377.9048838046456, 2380.8928598213124, 2387.2122199674154, 2266.5693518231483, 2295.185209250717, 2297.0467256088705, 2311.919082832101, 2321.3848330192513, 2006.508405440669, 2238.138316748056, 2303.5968705476557, 2305.216226483004, 2313.838031337807, 2273.6475846243857, 2287.039443433745, 2291.163725240292, 2298.9425264368356, 2341.9297421077395, 8900.154485323885, 8903.55482225886, 8943.955280223623, 8967.730968070902, 9003.414778039472, 5140.563052140573, 5284.867878303035, 5302.708351040691, 5308.124159513675, 5322.813701520467, 3385.4583266677214, 3526.559180102487, 3537.327907697529, 3539.982300088483, 3585.659362360572, 1087.722630729167, 1096.983545246822, 1107.4075369677184, 1130.152385710717, 1135.432283858071, 12427.689307767288, 12610.243385396814, 12627.242365458102, 12688.36558172097, 12709.93645031769, 4651.906961860744, 4684.812607495662, 4762.357081793983, 4790.4251489821445, 4805.975970120127, 10645.0951669109, 10668.679939601825, 10682.359058456517, 10729.356238625707, 10734.570617175226, 4819.060283244804, 5332.30680011596, 5336.91994620081, 5380.757865896044, 5386.784528618889, 4712.976435117803, 4835.20219163834, 4847.733374664357, 4851.393575803025, 4865.6107511399005, 3908.1988192420486, 3927.7643341399607, 3948.2498325318097, 4041.777702226347, 4061.0659548304056, 1238.7150955280335, 1239.6652903716126, 1255.384861417917, 1262.6464589914838, 1272.866349033338, 932.7108596335158, 973.9756506087405, 997.7455748784014, 1002.4035698759227, 1015.9898401016043, 5690.715464226772, 5734.713264336767, 5904.76380944766, 5935.109733540004, 6020.69896505173, 445.9464864216315, 447.3602748070291, 452.61301587143174, 457.34142834318476, 463.39295522865154, 3924.489816323917, 4060.675145988314, 4067.00358412185, 4077.2660921033907, 4080.2859499587453, 10747.83878241827, 10889.83665245022, 11060.115190784718, 11105.777884442266, 11396.544138234543, 10705.785884282272, 11060.889391106148, 11080.778384432428, 11297.774044519063, 11319.773604527863, 8180.0, 8359.456635318686, 8379.245867871921, 8425.283850872622, 8482.363822713198, 5746.827595172152, 5792.507636850914, 5816.82549523515, 5905.5570832186895, 5938.2280303560265, 10010.149137323118, 10164.644237451654, 10186.083252507013, 10219.846702299621, 10285.0, 10001.34991225568, 10444.060034596923, 10532.631357902437, 10576.629817956335, 10614.787704246026, 7098.716051357887, 7607.4674772765375, 7730.381569474316, 7830.647620856961, 8016.078151012475, 8659.826803464022, 8738.650453981769, 8750.51872147038, 8825.338099642424, 8854.778630534289, 7657.846843063219, 7812.492188007761, 7813.921860781319, 7917.604119794102, 8093.312068474245, 7579.54522728616, 7696.692132314644, 7804.648790804314, 7933.3653307736395, 7958.880616790869, 6675.766348177791, 6793.388595026471, 6800.6599670017295, 6845.794626161223, 6852.040714299902, 4931.753412329441, 5039.72281524505, 5199.948000519947, 5229.869253268699, 5249.08141075318, 5312.601554883477, 5328.17413300939, 5347.973260133675, 5368.4631536846, 5371.973140134431], "value": 4573.098310432802, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.7348940145579, 325.18054502653314, 325.98370081495926, 332.77870216306155, 333.69800330700707, 5128.025675121724, 5167.509086636773, 5203.9219411708855, 5224.843254702365, 5249.4750524947485, 5151.43334233235, 5159.174532074869, 5171.327727395443, 5173.456787037363, 5188.6367954243215, 3838.5201849768778, 3872.535295764498, 3988.421678856564, 4002.8799136025964, 4028.5769994150633, 4001.2197621463824, 4007.5792041835625, 4007.899802504946, 4010.2981978153707, 4020.336644453662, 3885.863994760184, 4007.559168491478, 4008.3586626139822, 4010.498687664042, 4031.9395209071886, 3925.411188321746, 4051.210014047247, 4060.8832571042904, 4065.7763822989723, 4071.389291606253, 3549.538559987211, 3559.394902866519, 3575.696065834381, 3576.7675101118343, 3587.892363229081, 4899.755012249373, 4903.681260718077, 4964.131277026542, 4974.577160941324, 4990.4011518618, 4536.682432229745, 4602.309653551994, 4608.377868987677, 4627.768611569408, 4629.305604159369, 5339.3058902342445, 5344.465553444663, 5344.706041167734, 5390.973045134789, 5446.836594902159, 3904.511936007999, 3928.5464378179972, 3945.10634895249, 3974.1917427570675, 3976.304146774332, 5296.523312901857, 5548.668319603266, 5561.805336813192, 5583.609147359686, 5635.943640563582, 3981.422693709396, 4000.219957108336, 4012.237674841829, 4019.9196016079513, 4037.4145748866836, 4054.3107671695884, 4057.3102572562734, 4071.5521292657786, 4072.266991941479, 4096.877093687194, 4472.821087156477, 4569.200389931749, 4605.102493433739, 4616.030633566988, 4621.930671039937, 5357.955198736209, 5370.597205209547, 5411.215373770781, 5473.0, 5473.616846820684, 1394.3307212537836, 1423.2101183843067, 1424.0956992309957, 1429.9499517516838, 1431.334429490292, 908.3459908865606, 908.6365453818526, 961.1925982174866, 984.7242772023844, 986.7977064701719, 5053.166227572483, 5112.923306150411, 5158.484151584811, 5167.0, 5169.379674439091, 5371.0, 5499.487640898785, 5503.972480137573, 5525.088360420566, 5527.640703354349, 5006.949930500648, 5140.768665409991, 5158.509941555546, 5162.586993040548, 5209.6874187548865, 2922.8976985805393, 2929.721676440735, 2939.955900661492, 2946.6169397978124, 2949.3069128754723, 5575.693336866511, 5609.326880774252, 5640.858978525488, 5665.348484924287, 5723.76938958126, 5566.526845218121, 5632.633878797865, 5650.954573403851, 5666.716664166857, 5671.0, 5585.0, 5620.241267428926, 5646.717664116778, 5680.971595141998, 5697.230873832063, 5233.790648374023, 5459.808906688247, 5493.752781124779, 5499.450054994469, 5506.146547285177, 5398.865028374322, 5400.540954018952, 5414.783408663767, 5442.2108794225405, 5442.292501974718, 5623.240862483676, 5674.347450043298, 5728.770849165987, 5739.311282646109, 5751.296890461476, 3960.7227494075137, 4028.637422631977, 4078.0, 4088.202800453883, 4092.3247664135083, 5467.917981230205, 5479.260299859628, 5482.506574408322, 5505.119180931032, 5514.503694667499, 5314.28257185275, 5340.412554619066, 5367.597430192829, 5386.64986775874, 5399.865003374947, 5181.740912954262, 5414.29614150158, 5477.20580515815, 5494.972525137348, 5500.4499550044675, 5084.618653600921, 5199.974000129975, 5278.287431196739, 5294.2852714883, 5324.760385782572, 5392.757325920422, 5417.214503896834, 5429.67421954684, 5449.918251226156, 5457.781688732406, 5371.946280537145, 5374.596905232045, 5379.919301210563, 5398.62209645321, 5406.513413792777], "value": 4128.517094447616, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [751.063342331484, 810.8297257575908, 852.4970267542149, 861.2205953611983, 867.726666100178, 14262.0, 14644.487442939499, 14783.704325913475, 14915.701685966274, 15024.098554086762, 12855.678608034805, 12906.548270810523, 12966.02754793392, 13011.0, 13018.414171362288, 681.107748849007, 796.0964305513227, 813.4102775487754, 819.8852160697508, 827.735124760077, 816.4652152839899, 824.8061705499202, 826.8222332198576, 827.2141465607667, 827.7020272701823, 876.8947726272825, 882.095851751953, 884.9646014159426, 884.9734507964739, 886.0474989386403, 728.7230852276155, 861.814709837388, 877.1404024056396, 881.180502133016, 887.0641473245723, 626.0515319291277, 627.6547898655721, 629.9905501417483, 632.11503894548, 634.1217413881807, 13974.441022359093, 13996.720065598733, 14017.158970461805, 14040.438382464688, 14044.438222471088, 13343.132696374705, 13653.726925461535, 13774.586762397144, 13781.173129612154, 13934.442622295095, 11187.552497900155, 11210.831837522444, 11386.886131138748, 11416.315021098677, 11501.482433290439, 4043.4945631796027, 4087.0, 4112.712110152291, 4179.310413781723, 4183.769892655902, 47379.289310660366, 49234.0, 49544.0, 53986.19020714692, 54395.72802136004, 10566.630167944086, 11183.552657893755, 11288.793124847838, 11448.885511144781, 11542.59600913964, 19051.14269857832, 19538.413847584594, 19599.412017639494, 20142.899285503772, 20236.797632023492, 8656.8879782103, 8694.782630434289, 8716.782080447912, 8770.254528365032, 8916.331275154389, 4712.24604063355, 4787.40157480315, 4830.4444988826035, 4867.683600565952, 4902.044101400193, 3458.239187378773, 3559.270349578382, 3566.7146628269675, 3589.533360663097, 3592.263585964871, 627.595201095291, 649.2695717318018, 657.5495785387026, 662.6653539962289, 673.6328700858044, 690.2303931116769, 717.9732981835921, 734.6033142103205, 779.5595488548952, 785.4487387409933, 9864.950675246579, 10299.588016479256, 10318.329308595068, 10356.326838755607, 10495.107915827088, 680.4590350671228, 713.6039498078512, 733.4059411876398, 767.3515879082166, 800.2677550041647, 4487.640988720895, 4854.660173787872, 4855.951440485621, 4861.756912154378, 4878.463369029404, 65203.673981629785, 66339.33660663331, 67176.0, 67421.66289168621, 67667.66166169236, 54402.45597543974, 54488.72755636196, 56427.0, 57733.42266577364, 60535.0, 52468.21297680538, 52778.73610631922, 52987.0, 53444.0, 53895.73052134714, 9272.860907086264, 9476.241900648069, 9516.809663806824, 9529.809403812023, 9584.185344245818, 32480.51279230766, 33003.0, 33893.491597625565, 34436.65563344335, 34613.82693086619, 45232.77383613061, 48766.268505971726, 48810.5118948806, 49064.75467622639, 49445.752771235915, 12004.699882502659, 12009.39953002329, 12126.269920194376, 12813.167144135601, 13142.145760525535, 13191.340432978504, 13281.66795830112, 13618.455261789813, 14068.859311406755, 14077.788833167306, 12851.357432128543, 12919.160254583421, 13301.800472993107, 13335.733285334432, 13457.663558411117, 12440.875591243972, 12538.185017973803, 12555.372231388212, 12617.0, 13014.21914685122, 12144.817827732768, 12286.815697764361, 12465.626031219077, 13062.608121756362, 13459.663508412368, 10390.844137337795, 10652.201084918817, 10793.56825726962, 11095.833562496407, 11132.666020019413, 11225.559181962413, 11305.26515776472, 11498.653126296313, 11570.421478925851, 11617.244879083171], "value": 6923.392074111891, "unit": "score"}], "name": "dromaeo_css", "value": 7044.052451312935, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 105.0, 112.0, 111.0, 110.0, 111.0, 110.0, 113.0, 111.0, 112.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 136.0, 154.0, 215.0, 136.0, 160.0, 155.0, 132.0, 196.0, 134.0], "value": 157.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 162.0, 164.0, 163.0, 163.0, 162.0, 164.0, 163.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 78.0, 144.0, 86.0, 77.0, 78.0, 76.0, 132.0, 78.0], "value": 90.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 145.0, 93.0, 94.0, 94.0, 121.0, 92.0, 93.0, 118.0], "value": 103.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 119.0, 117.0, 118.0, 118.0, 119.0, 118.0, 118.0, 117.0], "value": 118.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 147.0, 147.0, 148.0, 149.0, 149.0, 149.0, 150.0, 149.0], "value": 148.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 97.0, 96.0, 96.0, 97.0, 96.0, 95.0, 97.0, 97.0], "value": 96.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 59.0, 57.0, 58.0, 58.0, 58.0, 113.0, 55.0, 55.0], "value": 62.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 61.0, 61.0, 60.0, 61.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 80.0, 81.0, 81.0, 86.0, 81.0, 82.0, 87.0, 87.0, 81.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 132.0, 131.0, 129.0, 135.0, 128.0, 133.0, 130.0, 215.0, 134.0], "value": 140.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 167.0, 170.0, 176.0, 169.0, 167.0, 170.0, 170.0, 167.0, 171.0], "value": 169.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 70.0, 67.0, 65.0, 68.0, 66.0, 66.0, 65.0, 66.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1570.6, "alertThreshold": 2.0}]} 08:51:49 INFO - Return code: 0 08:51:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:51:49 ERROR - # TBPL SUCCESS # 08:51:49 INFO - Running post-action listener: _resource_record_post_action 08:51:49 INFO - ##### 08:51:49 INFO - ##### Finished run-tests step (success) 08:51:49 INFO - ##### 08:51:49 INFO - Running post-run listener: _resource_record_post_run 08:51:50 INFO - Total resource usage - Wall time: 1190s; CPU: 26.0%; Read bytes: 122880; Write bytes: 545099776; Read time: 1284; Write time: 646632 08:51:50 INFO - install - Wall time: 17s; CPU: 28.0%; Read bytes: 110592; Write bytes: 251985920; Read time: 848; Write time: 296132 08:51:50 INFO - run-tests - Wall time: 1174s; CPU: 25.0%; Read bytes: 12288; Write bytes: 293113856; Read time: 436; Write time: 350500 08:51:50 INFO - Running post-run listener: _upload_blobber_files 08:51:50 INFO - Blob upload gear active. 08:51:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:51:50 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 08:51:50 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:51:50 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:51:50 INFO - (blobuploader) - INFO - Open directory for files ... 08:51:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:51:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:51:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:51:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:51:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:51:51 INFO - (blobuploader) - INFO - Done attempting. 08:51:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:51:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:51:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:51:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:51:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:51:52 INFO - (blobuploader) - INFO - Done attempting. 08:51:52 INFO - (blobuploader) - INFO - Iteration through files over. 08:51:52 INFO - Return code: 0 08:51:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:51:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:51:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2718c141536d71e9d6d4585edd6e2debc6b030fb6ce2a66838ba70ac770c9a212ee6769cab753ba78144f5dd223cec327bd288a17617aa7c6b056c1b74ee8556", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2fac2d11eadd4b26c3f044a8f8e27f6e2ee3ab0ace0e8adade45cee1eddbbeda67c09cd7fd8dde721fb71191f4172d68c05a2ab20a944ef5aa5c4fd89c2fab86"} 08:51:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:51:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:51:52 INFO - Contents: 08:51:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2718c141536d71e9d6d4585edd6e2debc6b030fb6ce2a66838ba70ac770c9a212ee6769cab753ba78144f5dd223cec327bd288a17617aa7c6b056c1b74ee8556", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2fac2d11eadd4b26c3f044a8f8e27f6e2ee3ab0ace0e8adade45cee1eddbbeda67c09cd7fd8dde721fb71191f4172d68c05a2ab20a944ef5aa5c4fd89c2fab86"} 08:51:52 INFO - Running post-run listener: copy_logs_to_upload_dir 08:51:52 INFO - Copying logs to upload dir... 08:51:52 INFO - mkdir: /builds/slave/test/build/upload/logs 08:51:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1226.100727 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 26 secs) (at 2016-05-11 08:51:51.870656) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 08:51:51.874261) ========= 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=aa52b8aba932b19cc50b5275000001de-1462980683.677055-2137373613 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/sha512/2718c141536d71e9d6d4585edd6e2debc6b030fb6ce2a66838ba70ac770c9a212ee6769cab753ba78144f5dd223cec327bd288a17617aa7c6b056c1b74ee8556", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2fac2d11eadd4b26c3f044a8f8e27f6e2ee3ab0ace0e8adade45cee1eddbbeda67c09cd7fd8dde721fb71191f4172d68c05a2ab20a944ef5aa5c4fd89c2fab86"} build_url:https://queue.taskcluster.net/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.005377 build_url: 'https://queue.taskcluster.net/v1/task/EqvP_Ni8SSaO_LOfA4Iq2w/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/sha512/2718c141536d71e9d6d4585edd6e2debc6b030fb6ce2a66838ba70ac770c9a212ee6769cab753ba78144f5dd223cec327bd288a17617aa7c6b056c1b74ee8556", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2fac2d11eadd4b26c3f044a8f8e27f6e2ee3ab0ace0e8adade45cee1eddbbeda67c09cd7fd8dde721fb71191f4172d68c05a2ab20a944ef5aa5c4fd89c2fab86"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 08:51:51.891012) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 08:51:51.891364) ========= 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=aa52b8aba932b19cc50b5275000001de-1462980683.677055-2137373613 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.002137 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 08:51:51.973511) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 08:51:51.974085) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 08:51:51.975155) ========= ========= Total master_lag: 0.19 =========