builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-069 starttime: 1461982029.08 results: success (0) buildid: 20160429163051 builduid: 5a2544ad8c37425dabafe8a4b83478c7 revision: 2b7c421063ad7e30b6491d62ed8480ca333b628a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-29 19:07:09.081116) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-29 19:07:09.081607) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-29 19:07:09.081897) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-29 19:07:09.111300) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 19:07:09.111589) ========= 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=1861a34ebfea852630dec091000001fa-1461980955.598536-32888003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-29 19:07:09-- 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% 88.8M=0s 2016-04-29 19:07:10 (88.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.122492 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 19:07:09.247159) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 19:07:09.247464) ========= 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=1861a34ebfea852630dec091000001fa-1461980955.598536-32888003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016996 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 19:07:09.296384) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 19:07:09.296744) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2b7c421063ad7e30b6491d62ed8480ca333b628a --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 2b7c421063ad7e30b6491d62ed8480ca333b628a --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=1861a34ebfea852630dec091000001fa-1461980955.598536-32888003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-29 19:07:10,198 truncating revision to first 12 chars 2016-04-29 19:07:10,198 Setting DEBUG logging. 2016-04-29 19:07:10,198 attempt 1/10 2016-04-29 19:07:10,198 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2b7c421063ad?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-29 19:07:10,545 unpacking tar archive at: mozilla-central-2b7c421063ad/testing/mozharness/ program finished with exit code 0 elapsedTime=0.656484 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 19:07:09.967126) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-29 19:07:09.967465) ========= script_repo_revision: 2b7c421063ad7e30b6491d62ed8480ca333b628a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-29 19:07:09.967877) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-29 19:07:09.968140) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-29 19:07:09.978275) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-04-29 19:07:09.978585) ========= /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=1861a34ebfea852630dec091000001fa-1461980955.598536-32888003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:07:10 INFO - MultiFileLogger online at 20160429 19:07:10 in /builds/slave/test 19:07:10 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 19:07:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:07:10 INFO - {'append_to_log': False, 19:07:10 INFO - 'base_work_dir': '/builds/slave/test', 19:07:10 INFO - 'blob_upload_branch': 'Firefox', 19:07:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:07:10 INFO - 'branch': 'Firefox', 19:07:10 INFO - 'buildbot_json_path': 'buildprops.json', 19:07:10 INFO - 'config_files': ('talos/linux_config.py',), 19:07:10 INFO - 'default_actions': ('clobber', 19:07:10 INFO - 'read-buildbot-config', 19:07:10 INFO - 'download-and-extract', 19:07:10 INFO - 'populate-webroot', 19:07:10 INFO - 'create-virtualenv', 19:07:10 INFO - 'install', 19:07:10 INFO - 'run-tests'), 19:07:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:07:10 INFO - 'download_minidump_stackwalk': True, 19:07:10 INFO - 'download_symbols': 'ondemand', 19:07:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:07:10 INFO - 'tooltool.py': '/tools/tooltool.py', 19:07:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:07:10 INFO - '/tools/misc-python/virtualenv.py')}, 19:07:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:07:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:07:10 INFO - 'installer_path': 'installer.exe', 19:07:10 INFO - 'log_level': 'info', 19:07:10 INFO - 'log_name': 'talos', 19:07:10 INFO - 'log_to_console': True, 19:07:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:07:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:07:10 INFO - 'opt_config_files': (), 19:07:10 INFO - 'pip_index': False, 19:07:10 INFO - 'sps_profile': False, 19:07:10 INFO - 'sps_profile_interval': 0, 19:07:10 INFO - 'suite': 'dromaeojs', 19:07:10 INFO - 'system_bits': '32', 19:07:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:07:10 INFO - 'title': 'talos-linux64-ix-069', 19:07:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:07:10 INFO - 'use_talos_json': True, 19:07:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:07:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:07:10 INFO - 'work_dir': 'build'} 19:07:10 INFO - ##### 19:07:10 INFO - ##### Running clobber step. 19:07:10 INFO - ##### 19:07:10 INFO - Running pre-action listener: _resource_record_pre_action 19:07:10 INFO - Running main action method: clobber 19:07:10 INFO - rmtree: /builds/slave/test/build 19:07:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:07:11 INFO - Running post-action listener: _resource_record_post_action 19:07:11 INFO - ##### 19:07:11 INFO - ##### Running read-buildbot-config step. 19:07:11 INFO - ##### 19:07:11 INFO - Running pre-action listener: _resource_record_pre_action 19:07:11 INFO - Running main action method: read_buildbot_config 19:07:11 INFO - Using buildbot properties: 19:07:11 INFO - { 19:07:11 INFO - "project": "", 19:07:11 INFO - "product": "firefox", 19:07:11 INFO - "script_repo_revision": "production", 19:07:11 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 19:07:11 INFO - "repository": "", 19:07:11 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 19:07:11 INFO - "buildid": "20160429163051", 19:07:11 INFO - "pgo_build": "True", 19:07:11 INFO - "basedir": "/builds/slave/test", 19:07:11 INFO - "buildnumber": 759, 19:07:11 INFO - "slavename": "talos-linux64-ix-069", 19:07:11 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 19:07:11 INFO - "platform": "ubuntu64_hw", 19:07:11 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 19:07:11 INFO - "branch": "mozilla-central", 19:07:11 INFO - "repo_path": "mozilla-central", 19:07:11 INFO - "revision": "2b7c421063ad7e30b6491d62ed8480ca333b628a", 19:07:11 INFO - "stage_platform": "linux64-pgo", 19:07:11 INFO - "builduid": "5a2544ad8c37425dabafe8a4b83478c7", 19:07:11 INFO - "slavebuilddir": "test" 19:07:11 INFO - } 19:07:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 19:07:11 INFO - Running post-action listener: _resource_record_post_action 19:07:11 INFO - ##### 19:07:11 INFO - ##### Running download-and-extract step. 19:07:11 INFO - ##### 19:07:11 INFO - Running pre-action listener: _resource_record_pre_action 19:07:11 INFO - Running main action method: download_and_extract 19:07:11 INFO - mkdir: /builds/slave/test/build/tests 19:07:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:11 INFO - https://queue.taskcluster.net/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 19:07:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:07:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:07:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/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 19:07:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/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 19:07:14 INFO - Downloaded 1361 bytes. 19:07:14 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:07:14 INFO - Using the following test package requirements: 19:07:14 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 19:07:14 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:07:14 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:07:14 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:07:14 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:07:14 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:07:14 INFO - u'jsshell-linux-x86_64.zip'], 19:07:14 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:07:14 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:07:14 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 19:07:14 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:07:14 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:07:14 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:07:14 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:07:14 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:07:14 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:07:14 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:07:14 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:07:14 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:07:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:14 INFO - https://queue.taskcluster.net/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:07:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:07:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:07:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/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 19:07:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/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 19:07:19 INFO - Downloaded 23877549 bytes. 19:07:19 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'] 19:07:19 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 19:07:20 INFO - Return code: 0 19:07:20 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 19:07:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:20 INFO - https://queue.taskcluster.net/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:07:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:07:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:07:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/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 19:07:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/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 19:07:24 INFO - Downloaded 23877549 bytes. 19:07:24 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'] 19:07:24 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 19:07:26 INFO - Return code: 0 19:07: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')]} 19:07:26 INFO - https://queue.taskcluster.net/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:07:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:07:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:07:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/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 19:07:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/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 19:07:27 INFO - Downloaded 10840152 bytes. 19:07:27 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'] 19:07:27 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 19:07:27 INFO - Return code: 0 19:07: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')]} 19:07:27 INFO - https://queue.taskcluster.net/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:07:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:07:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:07:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/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 19:07:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/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 19:07:29 INFO - Downloaded 58578946 bytes. 19:07:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:07:29 INFO - mkdir: /builds/slave/test/properties 19:07:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:07:29 INFO - Writing to file /builds/slave/test/properties/build_url 19:07:29 INFO - Contents: 19:07:29 INFO - build_url:https://queue.taskcluster.net/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:07:30 INFO - Running post-action listener: _resource_record_post_action 19:07:30 INFO - Running post-action listener: set_extra_try_arguments 19:07:30 INFO - ##### 19:07:30 INFO - ##### Running populate-webroot step. 19:07:30 INFO - ##### 19:07:30 INFO - Running pre-action listener: _resource_record_pre_action 19:07:30 INFO - Running main action method: populate_webroot 19:07:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:07:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:07:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:07:30 INFO - '--tppagecycles', 19:07:30 INFO - '7'], 19:07:30 INFO - 'tests': ['tsvgm']}}, 19:07:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:07:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:07:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:07:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:07:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:07:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:07:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:30 INFO - 'talos_options': ['--disable-e10s'], 19:07:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:07:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:07:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:30 INFO - 'talos_options': ['--disable-e10s'], 19:07:30 INFO - 'tests': ['damp', 'tps']}, 19:07:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:30 INFO - 'tests': ['damp', 'tps']}, 19:07:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:07:30 INFO - 'tests': ['dromaeo_dom']}, 19:07:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:07:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:07:30 INFO - 'tests': ['a11yr', 19:07:30 INFO - 'ts_paint', 19:07:30 INFO - 'tpaint', 19:07:30 INFO - 'sessionrestore', 19:07:30 INFO - 'sessionrestore_no_auto_restore', 19:07:30 INFO - 'tabpaint']}, 19:07:30 INFO - 'other-e10s': {'tests': ['a11yr', 19:07:30 INFO - 'ts_paint', 19:07:30 INFO - 'tpaint', 19:07:30 INFO - 'sessionrestore', 19:07:30 INFO - 'sessionrestore_no_auto_restore', 19:07:30 INFO - 'tabpaint']}, 19:07:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:07:30 INFO - 'ts_paint', 19:07:30 INFO - 'tpaint', 19:07:30 INFO - 'sessionrestore', 19:07:30 INFO - 'sessionrestore_no_auto_restore', 19:07:30 INFO - 'tabpaint']}, 19:07:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:07:30 INFO - 'ts_paint', 19:07:30 INFO - 'tpaint', 19:07:30 INFO - 'sessionrestore', 19:07:30 INFO - 'sessionrestore_no_auto_restore', 19:07:30 INFO - 'tabpaint']}, 19:07:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:07:30 INFO - 'tests': ['a11yr', 19:07:30 INFO - 'ts_paint', 19:07:30 INFO - 'tpaint', 19:07:30 INFO - 'sessionrestore', 19:07:30 INFO - 'sessionrestore_no_auto_restore', 19:07:30 INFO - 'tabpaint']}, 19:07:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:07:30 INFO - 'tests': ['a11yr', 19:07:30 INFO - 'ts_paint', 19:07:30 INFO - 'tpaint', 19:07:30 INFO - 'sessionrestore', 19:07:30 INFO - 'sessionrestore_no_auto_restore', 19:07:30 INFO - 'tabpaint']}, 19:07:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:07:30 INFO - 'tests': ['tsvgx', 19:07:30 INFO - 'tsvgr_opacity', 19:07:30 INFO - 'tart', 19:07:30 INFO - 'tscrollx', 19:07:30 INFO - 'cart']}, 19:07:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:07:30 INFO - 'tsvgr_opacity', 19:07:30 INFO - 'tart', 19:07:30 INFO - 'tscrollx', 19:07:30 INFO - 'cart']}, 19:07:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:30 INFO - 'talos_options': ['--disable-e10s'], 19:07:30 INFO - 'tests': ['tp5o']}, 19:07:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:30 INFO - 'tests': ['tp5o']}, 19:07:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:30 INFO - 'talos_options': ['--disable-e10s', 19:07:30 INFO - '--xperf_path', 19:07:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:07:30 INFO - 'tests': ['tp5n']}, 19:07:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:30 INFO - 'talos_options': ['--xperf_path', 19:07:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:07:30 INFO - 'tests': ['tp5n']}}, 19:07:30 INFO - 'talos.zip': {'path': '', 19:07:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:07:30 INFO - Running post-action listener: _resource_record_post_action 19:07:30 INFO - ##### 19:07:30 INFO - ##### Running create-virtualenv step. 19:07:30 INFO - ##### 19:07:30 INFO - Running pre-action listener: _resource_record_pre_action 19:07:30 INFO - Running main action method: create_virtualenv 19:07:30 INFO - Creating virtualenv /builds/slave/test/build/venv 19:07:30 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 19:07:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:07:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:07:30 INFO - Using real prefix '/usr' 19:07:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:07:30 INFO - Installing distribute.............................................................................................................................................................................................done. 19:07:32 INFO - Installing pip.................done. 19:07:32 INFO - Return code: 0 19:07:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:07: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')]} 19:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07: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')]} 19:07:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:32 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 0x7fc010ec3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216f9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fe810>, '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 0x21ff710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ffba0>, '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': '1861a34ebfea852630dec091000001fa-1461980955.598536-32888003', '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 19:07:32 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 19:07:32 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 19:07:32 INFO - Using env: {'DISPLAY': ':0', 19:07:32 INFO - 'HOME': '/home/cltbld', 19:07:32 INFO - 'LANG': 'en_US.UTF-8', 19:07:32 INFO - 'LANGUAGE': 'en_US:en', 19:07:32 INFO - 'LOGNAME': 'cltbld', 19:07:32 INFO - 'MAIL': '/var/mail/cltbld', 19:07:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:32 INFO - 'MOZ_NO_REMOTE': '1', 19:07:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:07:32 INFO - 'NO_EM_RESTART': '1', 19:07:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:07:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:32 INFO - 'PWD': '/builds/slave/test', 19:07:32 INFO - 'SHELL': '/bin/bash', 19:07:32 INFO - 'SHLVL': '1', 19:07:32 INFO - 'TERM': 'linux', 19:07:32 INFO - 'TMOUT': '86400', 19:07:32 INFO - 'USER': 'cltbld', 19:07:32 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1461980955.598536-32888003', 19:07:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:32 INFO - '_': '/tools/buildbot/bin/python'} 19:07:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:33 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 19:07:33 INFO - Cleaning up... 19:07:33 INFO - Return code: 0 19:07:33 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 19:07: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')]} 19:07:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07: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')]} 19:07:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc010ec3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216f9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fe810>, '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 0x21ff710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ffba0>, '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': '1861a34ebfea852630dec091000001fa-1461980955.598536-32888003', '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 19:07:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 19:07:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 19:07:33 INFO - Using env: {'DISPLAY': ':0', 19:07:33 INFO - 'HOME': '/home/cltbld', 19:07:33 INFO - 'LANG': 'en_US.UTF-8', 19:07:33 INFO - 'LANGUAGE': 'en_US:en', 19:07:33 INFO - 'LOGNAME': 'cltbld', 19:07:33 INFO - 'MAIL': '/var/mail/cltbld', 19:07:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:33 INFO - 'MOZ_NO_REMOTE': '1', 19:07:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:07:33 INFO - 'NO_EM_RESTART': '1', 19:07:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:07:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:33 INFO - 'PWD': '/builds/slave/test', 19:07:33 INFO - 'SHELL': '/bin/bash', 19:07:33 INFO - 'SHLVL': '1', 19:07:33 INFO - 'TERM': 'linux', 19:07:33 INFO - 'TMOUT': '86400', 19:07:33 INFO - 'USER': 'cltbld', 19:07:33 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1461980955.598536-32888003', 19:07:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:33 INFO - '_': '/tools/buildbot/bin/python'} 19:07:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:33 INFO - Downloading/unpacking psutil>=0.7.1 19:07:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:07:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:07:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:07:35 INFO - Installing collected packages: psutil 19:07:35 INFO - Running setup.py install for psutil 19:07:35 INFO - building 'psutil._psutil_linux' extension 19:07:35 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 19:07:35 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 19:07:35 INFO - building 'psutil._psutil_posix' extension 19:07:35 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 19:07:35 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 19:07:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:07:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:07:35 INFO - Successfully installed psutil 19:07:35 INFO - Cleaning up... 19:07:35 INFO - Return code: 0 19:07:35 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 19:07:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc010ec3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216f9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fe810>, '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 0x21ff710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ffba0>, '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': '1861a34ebfea852630dec091000001fa-1461980955.598536-32888003', '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 19:07:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 19:07:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 19:07:35 INFO - Using env: {'DISPLAY': ':0', 19:07:35 INFO - 'HOME': '/home/cltbld', 19:07:35 INFO - 'LANG': 'en_US.UTF-8', 19:07:35 INFO - 'LANGUAGE': 'en_US:en', 19:07:35 INFO - 'LOGNAME': 'cltbld', 19:07:35 INFO - 'MAIL': '/var/mail/cltbld', 19:07:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:35 INFO - 'MOZ_NO_REMOTE': '1', 19:07:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:07:35 INFO - 'NO_EM_RESTART': '1', 19:07:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:07:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:35 INFO - 'PWD': '/builds/slave/test', 19:07:35 INFO - 'SHELL': '/bin/bash', 19:07:35 INFO - 'SHLVL': '1', 19:07:35 INFO - 'TERM': 'linux', 19:07:35 INFO - 'TMOUT': '86400', 19:07:35 INFO - 'USER': 'cltbld', 19:07:35 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1461980955.598536-32888003', 19:07:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:35 INFO - '_': '/tools/buildbot/bin/python'} 19:07:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:35 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 19:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:37 INFO - Downloading mozsystemmonitor-0.0.tar.gz 19:07:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:07:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 19:07:37 INFO - Installing collected packages: mozsystemmonitor 19:07:37 INFO - Running setup.py install for mozsystemmonitor 19:07:38 INFO - Successfully installed mozsystemmonitor 19:07:38 INFO - Cleaning up... 19:07:38 INFO - Return code: 0 19:07:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:07:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc010ec3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216f9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fe810>, '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 0x21ff710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ffba0>, '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': '1861a34ebfea852630dec091000001fa-1461980955.598536-32888003', '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 19:07:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:07:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:07:38 INFO - Using env: {'DISPLAY': ':0', 19:07:38 INFO - 'HOME': '/home/cltbld', 19:07:38 INFO - 'LANG': 'en_US.UTF-8', 19:07:38 INFO - 'LANGUAGE': 'en_US:en', 19:07:38 INFO - 'LOGNAME': 'cltbld', 19:07:38 INFO - 'MAIL': '/var/mail/cltbld', 19:07:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:38 INFO - 'MOZ_NO_REMOTE': '1', 19:07:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:07:38 INFO - 'NO_EM_RESTART': '1', 19:07:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:07:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:38 INFO - 'PWD': '/builds/slave/test', 19:07:38 INFO - 'SHELL': '/bin/bash', 19:07:38 INFO - 'SHLVL': '1', 19:07:38 INFO - 'TERM': 'linux', 19:07:38 INFO - 'TMOUT': '86400', 19:07:38 INFO - 'USER': 'cltbld', 19:07:38 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1461980955.598536-32888003', 19:07:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:38 INFO - '_': '/tools/buildbot/bin/python'} 19:07:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:38 INFO - Downloading/unpacking blobuploader==1.2.4 19:07:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:40 INFO - Downloading blobuploader-1.2.4.tar.gz 19:07:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:07:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:07:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:40 INFO - Downloading docopt-0.6.1.tar.gz 19:07:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:07:40 INFO - Installing collected packages: blobuploader, requests, docopt 19:07:40 INFO - Running setup.py install for blobuploader 19:07:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:07:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:07:40 INFO - Running setup.py install for requests 19:07:41 INFO - Running setup.py install for docopt 19:07:41 INFO - Successfully installed blobuploader requests docopt 19:07:41 INFO - Cleaning up... 19:07:41 INFO - Return code: 0 19:07:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:07:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:41 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 0x7fc010ec3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216f9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fe810>, '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 0x21ff710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ffba0>, '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': '1861a34ebfea852630dec091000001fa-1461980955.598536-32888003', '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 19:07:41 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 19:07:41 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 19:07:41 INFO - Using env: {'DISPLAY': ':0', 19:07:41 INFO - 'HOME': '/home/cltbld', 19:07:41 INFO - 'LANG': 'en_US.UTF-8', 19:07:41 INFO - 'LANGUAGE': 'en_US:en', 19:07:41 INFO - 'LOGNAME': 'cltbld', 19:07:41 INFO - 'MAIL': '/var/mail/cltbld', 19:07:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:41 INFO - 'MOZ_NO_REMOTE': '1', 19:07:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:07:41 INFO - 'NO_EM_RESTART': '1', 19:07:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:07:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:41 INFO - 'PWD': '/builds/slave/test', 19:07:41 INFO - 'SHELL': '/bin/bash', 19:07:41 INFO - 'SHLVL': '1', 19:07:41 INFO - 'TERM': 'linux', 19:07:41 INFO - 'TMOUT': '86400', 19:07:41 INFO - 'USER': 'cltbld', 19:07:41 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1461980955.598536-32888003', 19:07:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:41 INFO - '_': '/tools/buildbot/bin/python'} 19:07:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:07:41 INFO - Running setup.py (path:/tmp/pip-GJSeIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:07:41 INFO - Running setup.py (path:/tmp/pip-RcQRvV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:07:41 INFO - Running setup.py (path:/tmp/pip-7xMfnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:07:41 INFO - Running setup.py (path:/tmp/pip-DJ2aZa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:07:42 INFO - Running setup.py (path:/tmp/pip-_pROsq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:07:42 INFO - Running setup.py (path:/tmp/pip-Dfh4Ws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:07:42 INFO - Running setup.py (path:/tmp/pip-CjiaHP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:07:42 INFO - Running setup.py (path:/tmp/pip-byGHLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:07:42 INFO - Running setup.py (path:/tmp/pip-tAqP_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:07:42 INFO - Running setup.py (path:/tmp/pip-WHP3kT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:07:42 INFO - Running setup.py (path:/tmp/pip-pSsmpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:07:42 INFO - Running setup.py (path:/tmp/pip-CLsnNz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:07:42 INFO - Running setup.py (path:/tmp/pip-VHzaYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:07:42 INFO - Running setup.py (path:/tmp/pip-jlIY9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:07:42 INFO - Running setup.py (path:/tmp/pip-rVfq3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:07:42 INFO - Running setup.py (path:/tmp/pip-czScJp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:07:42 INFO - Running setup.py (path:/tmp/pip-rfuWlH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:07:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:07:42 INFO - Running setup.py install for manifestparser 19:07:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:07:43 INFO - Running setup.py install for mozcrash 19:07:43 INFO - Running setup.py install for mozdebug 19:07:43 INFO - Running setup.py install for mozdevice 19:07:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:07:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:07:43 INFO - Running setup.py install for mozfile 19:07:43 INFO - Running setup.py install for mozhttpd 19:07:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:07:43 INFO - Running setup.py install for mozinfo 19:07:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:07:43 INFO - Running setup.py install for mozInstall 19:07:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:07:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:07:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:07:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:07:43 INFO - Running setup.py install for mozleak 19:07:43 INFO - Running setup.py install for mozlog 19:07:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:07:43 INFO - Running setup.py install for moznetwork 19:07:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:07:44 INFO - Running setup.py install for mozprocess 19:07:44 INFO - Running setup.py install for mozprofile 19:07:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:07:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:07:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:07:44 INFO - Running setup.py install for mozrunner 19:07:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:07:44 INFO - Running setup.py install for mozscreenshot 19:07:44 INFO - Running setup.py install for moztest 19:07:44 INFO - Running setup.py install for mozversion 19:07:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:07:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:07:44 INFO - Cleaning up... 19:07:44 INFO - Return code: 0 19:07:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:07:44 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')]} 19:07:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:44 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')]} 19:07:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:44 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 0x7fc010ec3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216f9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fe810>, '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 0x21ff710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ffba0>, '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': '1861a34ebfea852630dec091000001fa-1461980955.598536-32888003', '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 19:07:44 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 19:07:44 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 19:07:44 INFO - Using env: {'DISPLAY': ':0', 19:07:44 INFO - 'HOME': '/home/cltbld', 19:07:44 INFO - 'LANG': 'en_US.UTF-8', 19:07:44 INFO - 'LANGUAGE': 'en_US:en', 19:07:44 INFO - 'LOGNAME': 'cltbld', 19:07:44 INFO - 'MAIL': '/var/mail/cltbld', 19:07:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:44 INFO - 'MOZ_NO_REMOTE': '1', 19:07:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:07:44 INFO - 'NO_EM_RESTART': '1', 19:07:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:07:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:44 INFO - 'PWD': '/builds/slave/test', 19:07:44 INFO - 'SHELL': '/bin/bash', 19:07:44 INFO - 'SHLVL': '1', 19:07:44 INFO - 'TERM': 'linux', 19:07:44 INFO - 'TMOUT': '86400', 19:07:44 INFO - 'USER': 'cltbld', 19:07:44 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1461980955.598536-32888003', 19:07:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:44 INFO - '_': '/tools/buildbot/bin/python'} 19:07:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:07:44 INFO - Running setup.py (path:/tmp/pip-3rUf4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:07:44 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)) 19:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:07:44 INFO - Running setup.py (path:/tmp/pip-elRQgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:07:45 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)) 19:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:07:45 INFO - Running setup.py (path:/tmp/pip-9sHru1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:07:45 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)) 19:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:07:45 INFO - Running setup.py (path:/tmp/pip-IKCz4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:07:45 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)) 19:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:07:45 INFO - Running setup.py (path:/tmp/pip-GmSuOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:07:45 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)) 19:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:07:45 INFO - Running setup.py (path:/tmp/pip-9yMP6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:07:45 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)) 19:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:07:45 INFO - Running setup.py (path:/tmp/pip-e_txRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:07:45 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)) 19:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:07:45 INFO - Running setup.py (path:/tmp/pip-A9jBQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:07:45 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)) 19:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:07:45 INFO - Running setup.py (path:/tmp/pip-7VHGuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:07:45 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)) 19:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:07:45 INFO - Running setup.py (path:/tmp/pip-5xvTU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:07:45 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)) 19:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:07:45 INFO - Running setup.py (path:/tmp/pip-Ob0Jzm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:07:45 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)) 19:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:07:45 INFO - Running setup.py (path:/tmp/pip-GJNxFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:07:45 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)) 19:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:07:45 INFO - Running setup.py (path:/tmp/pip-ZJ2n5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:07:45 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)) 19:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:07:45 INFO - Running setup.py (path:/tmp/pip-wlFGGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:07:45 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)) 19:07:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:07:45 INFO - Running setup.py (path:/tmp/pip-zGtLS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:07:46 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)) 19:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:07:46 INFO - Running setup.py (path:/tmp/pip-4Tce8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:07:46 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)) 19:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:07:46 INFO - Running setup.py (path:/tmp/pip-X9wU7d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:07:46 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)) 19:07:46 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)) 19:07:46 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)) 19:07:46 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)) 19:07:46 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)) 19:07:46 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)) 19:07:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:07:46 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 19:07:46 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 19:07:46 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 19:07:46 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 19:07:46 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 19:07:46 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 19:07:47 INFO - Downloading blessings-1.6.tar.gz 19:07:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:07:48 INFO - Installing collected packages: blessings 19:07:48 INFO - Running setup.py install for blessings 19:07:48 INFO - Successfully installed blessings 19:07:48 INFO - Cleaning up... 19:07:48 INFO - Return code: 0 19:07:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:07:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:07:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:07:48 INFO - Reading from file tmpfile_stdout 19:07:48 INFO - Current package versions: 19:07:48 INFO - argparse == 1.2.1 19:07:48 INFO - blessings == 1.6 19:07:48 INFO - blobuploader == 1.2.4 19:07:48 INFO - docopt == 0.6.1 19:07:48 INFO - manifestparser == 1.1 19:07:48 INFO - mozInstall == 1.12 19:07:48 INFO - mozcrash == 0.17 19:07:48 INFO - mozdebug == 0.1 19:07:48 INFO - mozdevice == 0.48 19:07:48 INFO - mozfile == 1.2 19:07:48 INFO - mozhttpd == 0.7 19:07:48 INFO - mozinfo == 0.9 19:07:48 INFO - mozleak == 0.1 19:07:48 INFO - mozlog == 3.1 19:07:48 INFO - moznetwork == 0.27 19:07:48 INFO - mozprocess == 0.22 19:07:48 INFO - mozprofile == 0.28 19:07:48 INFO - mozrunner == 6.11 19:07:48 INFO - mozscreenshot == 0.1 19:07:48 INFO - mozsystemmonitor == 0.0 19:07:48 INFO - moztest == 0.7 19:07:48 INFO - mozversion == 1.4 19:07:48 INFO - psutil == 3.1.1 19:07:48 INFO - requests == 1.2.3 19:07:48 INFO - wsgiref == 0.1.2 19:07:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:07:48 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')]} 19:07:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:48 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')]} 19:07:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:48 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 0x7fc010ec3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216f9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fe810>, '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 0x21ff710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ffba0>, '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': '1861a34ebfea852630dec091000001fa-1461980955.598536-32888003', '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 19:07:48 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 19:07:48 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 19:07:48 INFO - Using env: {'DISPLAY': ':0', 19:07:48 INFO - 'HOME': '/home/cltbld', 19:07:48 INFO - 'LANG': 'en_US.UTF-8', 19:07:48 INFO - 'LANGUAGE': 'en_US:en', 19:07:48 INFO - 'LOGNAME': 'cltbld', 19:07:48 INFO - 'MAIL': '/var/mail/cltbld', 19:07:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:48 INFO - 'MOZ_NO_REMOTE': '1', 19:07:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:07:48 INFO - 'NO_EM_RESTART': '1', 19:07:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:07:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:48 INFO - 'PWD': '/builds/slave/test', 19:07:48 INFO - 'SHELL': '/bin/bash', 19:07:48 INFO - 'SHLVL': '1', 19:07:48 INFO - 'TERM': 'linux', 19:07:48 INFO - 'TMOUT': '86400', 19:07:48 INFO - 'USER': 'cltbld', 19:07:48 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1461980955.598536-32888003', 19:07:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:48 INFO - '_': '/tools/buildbot/bin/python'} 19:07:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:48 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)) 19:07:48 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)) 19:07:48 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)) 19:07:48 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)) 19:07:48 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)) 19:07:48 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)) 19:07:48 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)) 19:07:48 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)) 19:07:48 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)) 19:07:48 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)) 19:07:48 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)) 19:07:48 INFO - Cleaning up... 19:07:48 INFO - Return code: 0 19:07:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:07:48 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')]} 19:07:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:48 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')]} 19:07:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:48 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 0x7fc010ec3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216f9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fe810>, '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 0x21ff710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ffba0>, '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': '1861a34ebfea852630dec091000001fa-1461980955.598536-32888003', '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 19:07:48 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 19:07:48 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 19:07:48 INFO - Using env: {'DISPLAY': ':0', 19:07:48 INFO - 'HOME': '/home/cltbld', 19:07:48 INFO - 'LANG': 'en_US.UTF-8', 19:07:48 INFO - 'LANGUAGE': 'en_US:en', 19:07:48 INFO - 'LOGNAME': 'cltbld', 19:07:48 INFO - 'MAIL': '/var/mail/cltbld', 19:07:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:48 INFO - 'MOZ_NO_REMOTE': '1', 19:07:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:07:48 INFO - 'NO_EM_RESTART': '1', 19:07:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:07:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:48 INFO - 'PWD': '/builds/slave/test', 19:07:48 INFO - 'SHELL': '/bin/bash', 19:07:48 INFO - 'SHLVL': '1', 19:07:48 INFO - 'TERM': 'linux', 19:07:48 INFO - 'TMOUT': '86400', 19:07:48 INFO - 'USER': 'cltbld', 19:07:48 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1461980955.598536-32888003', 19:07:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:48 INFO - '_': '/tools/buildbot/bin/python'} 19:07:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:48 INFO - Downloading/unpacking jsonschema 19:07:48 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 19:07:48 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 19:07:48 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 19:07:48 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 19:07:48 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 19:07:48 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 19:07:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:07:50 INFO - Downloading/unpacking functools32 (from jsonschema) 19:07: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 19:07: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 19:07: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 19:07: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 19:07: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 19:07: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 19:07:50 INFO - Downloading functools32-3.2.3-2.tar.gz 19:07:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:07:51 INFO - warning: no files found matching '*.txt' 19:07:51 INFO - no previously-included directories found matching 'build' 19:07:51 INFO - no previously-included directories found matching 'dist' 19:07:51 INFO - no previously-included directories found matching '.git*' 19:07:51 INFO - Installing collected packages: jsonschema, functools32 19:07:51 INFO - Running setup.py install for functools32 19:07:51 INFO - warning: no files found matching '*.txt' 19:07:51 INFO - no previously-included directories found matching 'build' 19:07:51 INFO - no previously-included directories found matching 'dist' 19:07:51 INFO - no previously-included directories found matching '.git*' 19:07:51 INFO - Successfully installed jsonschema functools32 19:07:51 INFO - Cleaning up... 19:07:51 INFO - Return code: 0 19:07:51 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:51 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 0x7fc010ec3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20a38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x216f9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fe810>, '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 0x21ff710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ffba0>, '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': '1861a34ebfea852630dec091000001fa-1461980955.598536-32888003', '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 19:07:51 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 19:07:51 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 19:07:51 INFO - Using env: {'DISPLAY': ':0', 19:07:51 INFO - 'HOME': '/home/cltbld', 19:07:51 INFO - 'LANG': 'en_US.UTF-8', 19:07:51 INFO - 'LANGUAGE': 'en_US:en', 19:07:51 INFO - 'LOGNAME': 'cltbld', 19:07:51 INFO - 'MAIL': '/var/mail/cltbld', 19:07:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:51 INFO - 'MOZ_NO_REMOTE': '1', 19:07:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:07:51 INFO - 'NO_EM_RESTART': '1', 19:07:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:07:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:51 INFO - 'PWD': '/builds/slave/test', 19:07:51 INFO - 'SHELL': '/bin/bash', 19:07:51 INFO - 'SHLVL': '1', 19:07:51 INFO - 'TERM': 'linux', 19:07:51 INFO - 'TMOUT': '86400', 19:07:51 INFO - 'USER': 'cltbld', 19:07:51 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1461980955.598536-32888003', 19:07:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:51 INFO - '_': '/tools/buildbot/bin/python'} 19:07:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:51 INFO - Downloading/unpacking flake8 19:07:51 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 19:07:51 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 19:07:51 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 19:07:51 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 19:07:51 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 19:07:51 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 19:07:53 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:07:53 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:07:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:53 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:07:53 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:07:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:53 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:07:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:07:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:07:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:07:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:07:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:07:54 INFO - no previously-included directories found matching 'docs/_build' 19:07:54 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:07:54 INFO - Running setup.py install for pep8 19:07:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:07:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:07:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:07:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:07:54 INFO - no previously-included directories found matching 'docs/_build' 19:07:54 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:07:54 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:07:54 INFO - Cleaning up... 19:07:54 INFO - Return code: 0 19:07:54 INFO - Running post-action listener: _resource_record_post_action 19:07:54 INFO - Running post-action listener: _start_resource_monitoring 19:07:54 INFO - Starting resource monitoring. 19:07:54 INFO - ##### 19:07:54 INFO - ##### Running install step. 19:07:54 INFO - ##### 19:07:54 INFO - Running pre-action listener: _resource_record_pre_action 19:07:54 INFO - Running main action method: install 19:07:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:07:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:07:54 INFO - Reading from file tmpfile_stdout 19:07:54 INFO - Detecting whether we're running mozinstall >=1.0... 19:07:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:07:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:07:54 INFO - Reading from file tmpfile_stdout 19:07:54 INFO - Output received: 19:07:54 INFO - Usage: mozinstall [options] installer 19:07:54 INFO - Options: 19:07:54 INFO - -h, --help show this help message and exit 19:07:54 INFO - -d DEST, --destination=DEST 19:07:54 INFO - Directory to install application into. [default: 19:07:54 INFO - "/builds/slave/test"] 19:07:54 INFO - --app=APP Application being installed. [default: firefox] 19:07:54 INFO - mkdir: /builds/slave/test/build/application 19:07:54 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'] 19:07:54 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 19:08:11 INFO - Reading from file tmpfile_stdout 19:08:11 INFO - Output received: 19:08:11 INFO - /builds/slave/test/build/application/firefox/firefox 19:08:11 INFO - Running post-action listener: _resource_record_post_action 19:08:11 INFO - ##### 19:08:11 INFO - ##### Running run-tests step. 19:08:11 INFO - ##### 19:08:11 INFO - Running pre-action listener: _resource_record_pre_action 19:08:11 INFO - Running main action method: run_tests 19:08:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:08:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:08:11 INFO - Python 2.7.3 19:08:11 INFO - Return code: 0 19:08:11 INFO - grabbing minidump binary from tooltool 19:08:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:11 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21fe810>, '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 0x21ff710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21ffba0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:08:11 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:08:11 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 19:08:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:08:11 INFO - Return code: 0 19:08:11 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:08:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:08:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:08:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:08:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:08:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:08:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:08:12 INFO - Return code: 0 19:08:12 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/Q4i0jQcxSwqZEw1Wqc42Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 19:08:12 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/Q4i0jQcxSwqZEw1Wqc42Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-069 --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 19:08:12 INFO - Using env: {'DISPLAY': ':0', 19:08:12 INFO - 'HOME': '/home/cltbld', 19:08:12 INFO - 'LANG': 'en_US.UTF-8', 19:08:12 INFO - 'LANGUAGE': 'en_US:en', 19:08:12 INFO - 'LOGNAME': 'cltbld', 19:08:12 INFO - 'MAIL': '/var/mail/cltbld', 19:08:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:08:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:08:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:12 INFO - 'MOZ_NO_REMOTE': '1', 19:08:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:08:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:08:12 INFO - 'NO_EM_RESTART': '1', 19:08:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:08:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:12 INFO - 'PWD': '/builds/slave/test', 19:08:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:08:12 INFO - 'SHELL': '/bin/bash', 19:08:12 INFO - 'SHLVL': '1', 19:08:12 INFO - 'TERM': 'linux', 19:08:12 INFO - 'TMOUT': '86400', 19:08:12 INFO - 'USER': 'cltbld', 19:08:12 INFO - 'XDG_SESSION_COOKIE': '1861a34ebfea852630dec091000001fa-1461980955.598536-32888003', 19:08:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:12 INFO - '_': '/tools/buildbot/bin/python'} 19:08:12 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/Q4i0jQcxSwqZEw1Wqc42Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 19:08:12 INFO - mozversion application_buildid: 20160429163051 19:08:12 INFO - mozversion application_changeset: 2b7c421063ad7e30b6491d62ed8480ca333b628a 19:08:12 INFO - mozversion application_display_name: Nightly 19:08:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:08:12 INFO - mozversion application_name: Firefox 19:08:12 INFO - mozversion application_remotingname: firefox 19:08:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:08:12 INFO - mozversion application_vendor: Mozilla 19:08:12 INFO - mozversion application_version: 49.0a1 19:08:12 INFO - mozversion platform_buildid: 20160429163051 19:08:12 INFO - mozversion platform_changeset: 2b7c421063ad7e30b6491d62ed8480ca333b628a 19:08:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:08:12 INFO - mozversion platform_version: 49.0a1 19:08:12 INFO - using testdate: 1461982092 19:08:12 INFO - actual date: 1461982092 19:08:12 INFO - starting webserver on 'localhost:44776' 19:08:12 INFO - SUITE-START | Running 2 tests 19:08:12 INFO - TEST-START | dromaeo_css 19:08:12 INFO - operating with platform_type : linux_ 19:08:12 INFO - Initialising browser for dromaeo_css test... 19:08:12 INFO - TEST-INFO | started process 11930 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJ_6bzz/profile http://localhost:44776/getInfo.html) 19:08:16 INFO - PROCESS | 11930 | __metrics Screen width/height:1600/1200 19:08:16 INFO - PROCESS | 11930 | colorDepth:24 19:08:16 INFO - PROCESS | 11930 | Browser inner width/height: 1024/697 19:08:16 INFO - PROCESS | 11930 | __metrics 19:08:18 ERROR - PROCESS | 11930 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:08:19 INFO - TEST-INFO | 11930: exit 0 19:08:19 INFO - Browser initialized. 19:08:19 INFO - Running cycle 1/1 for dromaeo_css test... 19:08:19 INFO - TEST-INFO | started process 12016 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJ_6bzz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:08:30 INFO - PROCESS | 12016 | RSS: Main: 154157056 19:08:30 INFO - PROCESS | 12016 | 19:09:21 INFO - PROCESS | 12016 | 1461982161893 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] 19:11:29 INFO - PROCESS | 12016 | Cycle 1(1): loaded http://localhost:44776/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44776/tests/dromaeo/cssquery-ext.html) 19:11:30 INFO - PROCESS | 12016 | RSS: Main: 255131648 19:11:30 INFO - PROCESS | 12016 | 19:14:33 INFO - PROCESS | 12016 | Cycle 1(1): loaded http://localhost:44776/tests/dromaeo/cssquery-ext.html (next: http://localhost:44776/tests/dromaeo/cssquery-jquery.html) 19:14:33 INFO - PROCESS | 12016 | RSS: Main: 232517632 19:14:33 INFO - PROCESS | 12016 | 19:17:35 INFO - PROCESS | 12016 | Cycle 1(1): loaded http://localhost:44776/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44776/tests/dromaeo/cssquery-mootools.html) 19:17:36 INFO - PROCESS | 12016 | RSS: Main: 235352064 19:17:36 INFO - PROCESS | 12016 | 19:20:37 INFO - PROCESS | 12016 | Cycle 1(1): loaded http://localhost:44776/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44776/tests/dromaeo/cssquery-prototype.html) 19:20:38 INFO - PROCESS | 12016 | RSS: Main: 231018496 19:20:38 INFO - PROCESS | 12016 | 19:23:40 INFO - PROCESS | 12016 | Cycle 1(1): loaded http://localhost:44776/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44776/tests/dromaeo/cssquery-yui.html) 19:23:41 INFO - PROCESS | 12016 | RSS: Main: 217796608 19:23:41 INFO - PROCESS | 12016 | 19:26:43 INFO - PROCESS | 12016 | Cycle 1(1): loaded http://localhost:44776/tests/dromaeo/cssquery-yui.html (next: http://localhost:44776/tests/dromaeo/cssquery-dojo.html) 19:26:44 INFO - PROCESS | 12016 | RSS: Main: 267329536 19:26:44 INFO - PROCESS | 12016 | 19:26:44 INFO - PROCESS | 12016 | __start_tp_report 19:26:44 INFO - PROCESS | 12016 | _x_x_mozilla_page_load 19:26:44 INFO - PROCESS | 12016 | _x_x_mozilla_page_load_details 19:26:44 INFO - PROCESS | 12016 | |i|pagename|runs| 19:26:44 INFO - PROCESS | 12016 | |0;dojo.html;3121.968780312197;3152.0228729093997;3187.8884239051636;3192.3615276944615;3214.1803840020784;5281.260623512701;5299.072662284099;5302.178162384832;5323.733813309329;5401.108817045188;5268.4994925482015;5351.170568561869;5358.59810514212;5377.784888604461;5389.137737961927;4789.5928846047955;4828.275758636215;4925.26121081837;4956.132676781567;4973.4280557735965;4885.951140488585;4932.704037757728;4956.0583489137125;4963.851084467472;4967.751612419383;4816.855494335158;4831.806727730886;4880.8047678092835;4894.36373271476;4945.876353091166;4874.463808981045;4876.292937524039;4880.755962201912;4891.608671306287;4935.136351138536;4794.041191761662;4847.806087756486;4854.199057155565;4865.635077369211;4885.706857588556;5268.473152684739;5272.156454967225;5278.313819203518;5291.94161167768;5299.417064122943;5680.772769089232;5698.43015698431;5700.515456186187;5703.458171473703;5709.914351284734;5653.123765816305;5697.088465845445;5722.084466485384;5728.942710572883;5729.369769325371;5299.152135658314;5315.734213289359;5316.946830531722;5318.893622127574;5363.329583802025;5765.740541675635;5773.942260577341;5780.768769249267;5782.479576838105;5805.419458054245;5519.337679478488;5542.2795036644975;5578.665280083208;5592.944070559323;5633.549316054706;5760.913586296209;5769.249997500298;5779.33537643168;5781.913271300934;5787.565932555075;5745;5760.683162426107;5767.567432442584;5768.567357448208;5768.653880767168;5615.0454422748235;5655.745491452894;5674.205611214434;5701.372848986608;5702.258706368146;4341.9131617367475;4351.69538132334;4353.172897149531;4361.389405483234;4392.758398288125;3831.59768224338;3865.690744740414;3886.0090676877217;3897.8051097445014;3949;5439.755211015435;5467.371252306036;5496.065668836308;5496.258005169251;5512.090505066619;5706.942930570641;5707.229524014288;5709.543236541067;5710.942890571124;5735.426457354231;5402.972985135128;5403.675779453245;5406.135018397038;5411.404745477994;5458.399576046631;4809.182438985381;4923.803047878045;4953.90092198154;4961.156603377434;4982.128127577732;5845.970770146122;5846.970765146147;5847.035239185487;5847.152162936351;5852.356240813592;5610.943890561042;5664.433556644302;5668.866226754748;5677.460641239076;5679.432056794288;5914.940850591439;5919.911201331897;5957.702114894325;5965.582409231312;5986.2517185351835;5533.695646739468;5537.529310008695;5542.0855558832345;5554.472325129106;5559.63862348946;5539.501444869981;5624.634398764068;5630.690312032878;5643.3792282848035;5776.653400795798;5588.636738611977;5589.385167631639;5595.468430499096;5613.989481893298;5639.295088113986;5523.558115350843;5582.441755824385;5582.9720851397105;5585.581081418991;5597.132444471114;5427;5454;5507.531859791962;5536.446355364431;5553.389127195925;5619.185218143264;5632.155176723524;5635.323761148688;5668.943310567007;5676.545876329801;5502.312210973629;5511.22842802;5515.117581186991;5516.862078448071;5525.11598144295 19:26:44 INFO - PROCESS | 12016 | |1;ext.html;601.7081715368048;645.1129696667083;647.9697281322696;661.2395744893375;662.3806740697448;34460.1384965376;37037.62962370379;38068.42897356542;38097.42853857194;38123.23753524935;45316.320255196195;45561.544384556146;45997.540024599824;46094.30858537116;46131.769341153245;7617.390608751314;8349.62426690799;8560.957195214016;8562.871556926651;8563.914360856405;7717.65270562826;7796.922030779676;9668.178204852597;9949.154321882648;10055.64805231817;10062.79874402515;10201.642942497014;10295.536700848443;10309.948450257738;10316.845247321296;10117.89882101177;10236.283460157792;10239.078482936497;10244.48777561119;10248.948755256251;9992.25058120644;10115.494225288707;10122.493875306205;10126.645567405105;10130.493475326204;41064.38403423951;41612.79193604013;41658;41658;41661.375079373836;3379.8648054077808;3457.360388328142;3501.1597216667815;3829.138443850139;3833.731638785286;31298;31499.212519686964;31550;31885.521717174262;32295.35409291801;4183.644390226835;4187.979060104711;4262.531121576624;4298.591633794785;4355.412019377375;331578;332105;332573;333168;333277.99999999756;32305.515417268758;33321.16697082544;33431.498527522104;59051.704741476016;59077.40922590805;34338;34432.311353772784;34436.48345274823;34438.827805861314;34506.20431752505;15103;15354.541318574718;15359.692806143814;15365.308561114553;15378.769318460232;11609.419529023684;11997.64007079789;12926.741465170644;12992.805107923388;13000.739985200433;8165.346772258205;8252;8252.133525979685;8300.750977470685;8311.709090181814;3119.859606317721;3234.5795046643784;3247.3018301065385;3253.967460325414;3270.7546933980047;968.8256113899424;984.478226539935;1035.8239099353127;1049.0663309654415;1109.3177695717077;32153.35693286121;32403;32461;32745.508817367758;33014.66985330116;1082.1396989393293;1096.1231015187977;1127.5658871334595;1145.8968692817687;1252.5803855708439;16782.160891955355;17464.825351746575;17549;17572.736408953875;17780.288788448317;481664;484585;485004;485631;488939;58627.70686146542;59226.70386648039;59309;59398;59515.702421487615;735.4300417176731;1164.8485696859354;1247.419949723383;1327.8273824402766;1367.3505085084705;28160;28926.421471570873;29389.853050734608;29431.26090412349;29974.400511990076;43680.34479482747;44042.11915761603;44050.77974610106;44057.339139913565;44065.559344407426;297337;303904;304997;305609;306720;24486.51026979486;25211.11761088353;25325;25734.613980789927;26048.739512604632;40517.59482405138;46199.78961117958;46299.3055104167;46814.76592617015;46911.530884690714;38356.616433835305;38517;39050.414243785795;39276.803615981735;39732.801335993136;38008.61991380051;39371.606283936795;39794.60205397909;39874.2025159501;40284.3957340646;38551.2289754209;39440.40839387469;39690.2061958765;40053.19893602053;40086.1982760349;31963.520547192275;33241.66758332482;34072.65927340695;34325.828370857984;35701.46447803337;25401.364965876;25562.872185638953;25648.358791030372;25778.742212578385;25950.87024564865 19:26:44 INFO - PROCESS | 12016 | |2;jquery.html;382.68810919100923;388.28749245135174;388.4173739390912;397.15009878859246;400.71949635255294;17367.652646947052;18299.451016469495;18824.15291311891;18839.434816955512;18851.622967540658;17202.483925482255;17864.285428582873;17952.82047179528;17972.101394930265;19304.710429343537;5529.364123125852;5605.383407825136;5616.943830561703;5704.201411802351;5706.914396284059;5441.319835020622;5619.409961953977;5646.830595082154;5652.293463317085;5654.575906807006;5543.946650136481;5607.887842243153;5614.214010038578;5615.298087739033;5627.831165065034;5076.340075790161;5090.440051594322;5122.359705036871;5123.180291153416;5124.538791508782;4808.879778005543;4944.53026962438;4967.180415231483;4991.925121123186;5063.873403164914;12552.874471255262;12611.495540178383;12682.74634507314;12699.174553653984;12744.61766147017;2025.9594808103902;2059;2219.6115679755976;2632.197179860142;2687.9865600672065;9271.768205794842;9761.853572196424;13673;13724.862751372459;13820.792688109686;1807.7921039080543;1956.1392987085637;2004.188303736989;2033.2375359240284;2052.332991777663;58877;60220.39779602236;76723.6163819183;79238.60380698118;81220.18779812186;10503.579856805642;10518.579256829642;10615.681529554126;10639.148868090535;10792.082672972885;14875.181864997312;14933.328000240015;14971.92514037423;14975.400983960737;15017.624559386102;7233.204347521769;7646.311831935152;7738.264864837832;7990.240927111915;8052.154523775036;7894.723684671124;7954.920450795418;8114.43198976066;8121;8141.674333026731;4377.518472967971;4476.104779044204;4490.416245888014;4567.429071366079;4583.358329833826;1813.4922221777924;1874.2034635279952;1905.9904700476686;1908.787919671019;1940.3402843033437;868.3574155125162;960.7550074731039;982.3418309732353;1016.4663551635302;1124.7525544380223;18271.54321141982;18282.54293642643;18435.90782046108;18699.90650046768;18752.156152973148;977.5698692575386;980.9019098090134;985.6353149334792;1028.7068185567227;1060.750723579958;10126.493675316206;10341.793164136676;10440.738981525523;10482.63310784119;10487.528061237263;478951;489458;496460;502468;504612;93707;96479.51760241154;96994;98453.50773246089;98457;980.2696990741942;985.2708995343394;997.850322451638;997.9700608981742;1003.8042581696645;16155.192240388169;16231.51305460838;16440.588985275463;16448.75326870074;16466.67066658636;27828.582571261853;30205.395892082473;38516.61483385242;39384.40923386209;39713.80143099266;50413.49586504088;54084.72957635186;54955.45044549663;55963.44036559582;56842.71578642246;8099.919000809917;8220.876686849582;8237.176282371714;8318.584070796314;8378;9416.387934784218;12985.28580928058;13547.525836595672;13560.389782459615;13630.386632601754;5961.523078153824;8235;11801.232919860182;11837.704057398634;11980.221285616408;11658.6502404928;11687.76624467489;11749.941250293694;11794.705132371759;11996.940015299868;11902.404879755804;12035.638930832089;12049.397530123284;12061.612914514943;12178.817317740064;8366.916330836857;8399.664013439395;8474;8632.525211113198;8845.805816214728;8521.82956340882;11324.207305488537;11937.46281417321;12277.324747138993;12292 19:26:44 INFO - PROCESS | 12016 | |3;mootools.html;1316.4602512969684;1422.4310275889643;1472.521430535076;1557.9454719084827;1727.1623262717571;7645.120811106717;7943.76168714938;7944.285014348708;7966.5618390988475;7967.800804979871;7732.072151341833;7878.212178782119;7884.526928384301;7907;7916.208379162081;2285.565742508926;2312.7802858728396;2314.9652755208685;2318.6637937499054;2320.2227253869974;2255.605269077913;2270.9886450567806;2274;2301.309607117866;2308.8383813133087;2164.545445456456;2200.3508964855405;2205.3273751505785;2252.8085112765352;2256.909723611054;2172.3048624440185;2175.434387059368;2182.476205710626;2182.967255491169;2184.9563008739815;2244.483768733184;2245.4498647831388;2245.7080579524722;2246.2587346175724;2248.3816950338623;7837.216278372117;7950.920490795076;8032.4778889372;8032.839343213162;8047.396445266629;4891.266310053485;5038.39539255288;5039.420466646346;5045.571126454256;5046.460829447017;3168.7306578940816;3196.5844440222854;3268.0522648431925;3345.2640419107756;3348.614909285415;1108.9725050611876;1118.7930232906938;1121.6803211084803;1123.1856903744801;1139.4188963628526;11338.546458141664;11463;11481.13891458144;11495.19533632646;11505.482253298622;4280.871573852789;4329.740215587075;4357.0196705740955;4515.932261016087;4520.18636645407;9005.189532942066;9019.639214431481;9039.81920361589;9060.184583387527;9072.90927090734;4904.337914381513;4951.331570238044;4960.975195124073;4970.527799859007;4975.975120124376;4156.376543518436;4581.496035436099;4665.136949664323;4751.1447939370555;4778.068276686083;3656.634336566375;3701.7038636908983;3783.7162212834146;3816.3893776995546;3820.8089595520114;1102.4818335382358;1132.8980391764617;1136.4885801389407;1140.1448913315014;1143.651186388143;729.4893574497872;956.5695437053354;1005.5374527717332;1006.5017816181037;1015.5328548867456;5493.972530137324;5574.136008918624;5588.860278492989;5646.237757902631;5725.484706376446;463.150119530665;471.8419329524648;484.6147312886264;486.5864015586723;643.5776932978171;3920;4105.97947010263;4179.1432756284885;4198.433211516467;4228.23891699497;9946.154576861049;10423.530941107669;10433.21750868688;10438;10480.266381353385;9568.378055426376;9887.406755594688;10280.897191028143;10356.741081473023;10372.948135259427;7442.776716698508;7545.811354716176;7568.3945284378215;7576.772696819105;7607.391408687292;5549.72251387421;5615.6630602164005;5672.517835983987;5691.402402747652;5769.365369809401;9025.277977761632;9162.450252984841;9192.080791921022;9208.355415120877;9253.58358873839;9241.537923103953;9324.487153206639;9605.327627066039;9736.561854716412;9740.318177727775;7607.619619018917;7706.07527096752;7784.065912090586;7798.415118866221;7802.6488808004915;7970.521768693897;7974.481658692166;8046.557439340892;8052.315553178046;8123.147069557847;7258.927410725825;7433.553986760811;7437.51656142349;7506.624668766649;7535.058117735283;7183.281671832775;7243.927560724536;7290.48966572356;7409.222031686811;7409.851802963803;6645.767398141042;6690.531662783753;6722.899156512558;6725.495587830835;6729.327067293231;4849.80600775965;4902.338184345068;4949.6287778416045;5031.270465782368;5032.220005899091;4985.8005679772405;4989.376327959005;5028.396592408788;5070.4929507049;5091.210862316347 19:26:44 INFO - PROCESS | 12016 | |4;prototype.html;297.0479612840845;299.5177763800281;299.87255416448005;301.82343329152445;306.5417201284078;5265.552428043616;5319.734013299334;5347.625666203362;5374.516293533581;5393.164059570773;5281.9207711884355;5294.285271488348;5295.4969277918535;5299.54953828925;5306.389765177006;3980.422838688381;4026.255142798577;4118.052847844998;4163.5836416358425;4186.623203911648;4130.864012396585;4145.875623731293;4147.585241475858;4165.958340416602;4199.803056129004;3930.626590473915;4108.6918481114035;4128.802647232299;4133.297339452285;4145.66834653227;3959.6040395960463;3959.6044393142024;4071.0662435343124;4162.458880345566;4167.270727622684;3654.543182102237;3667.2665466906724;3687.2441149564274;3690.372636651749;3692.6861216796606;5138.7173705446185;5156;5161.948380516222;5175.689458632494;5176.275321454999;4557.293619488984;4655.3947986761505;4719.315699223594;4732.805356384461;4778.307145463888;5515.558755299567;5533.501984821385;5537.529310008654;5542.4457554244655;5579.69311687857;4030.5969403059457;4033.9713373089967;4037.838486460538;4043.2317859606874;4051.9464939115746;5660.5471562274915;5749.59752817303;5773.971130144364;5783.537317014629;5799.507041901444;4088.5911408858874;4098.959010409858;4101.89745256365;4122.979385103055;4123.154753275571;4147;4150.833966641361;4164.12553363797;4169.353750168729;4169.70739070889;4566.292224705109;4620.67655264135;4710.8351207707565;4715.477219687451;4749.168895443284;5545.611807173459;5570.498655121059;5586.748596313176;5596.2165296858475;5602;1453.4448140489574;1457.7886206499998;1461.4811741831709;1468.5520916120472;1469.7874253740706;946.7349142240183;958.7890664053897;990.1237404896718;992.6079198716467;1015.4364327798143;5232.92150617741;5359.892802143935;5375.596830237748;5413.242146099549;5435.83692489226;5682.687452190086;5684.8010319638615;5690.573207009491;5735.369109397963;5740.856478588069;5222.947770522323;5380.542653874386;5429.891402172014;5451.400345961941;5497.120460726264;3243.248645731279;3255.690709382652;3261.59230096238;3270.7546933980047;3273.541704161419;6081.543884208702;6282.293446866371;6317.241930968313;6320.367963203735;6327.462165715966;6229.853923768468;6238.34683808791;6258.653005123078;6284;6285.842853928597;6283.748650053946;6304.905426418607;6312.147860038836;6351.364863513704;6354.205724284465;5489.505944465017;5768.365479797303;5787.421257874179;5806.535477161901;6128.908066378919;5748.281464816898;5750.7987220447085;5755.71221438918;5756.683382414008;5763.221965034834;5810.5061069809535;5917.556183286185;5957.408527290642;5995.520358371233;6003.759849606142;3999.1801680655976;4000.879973600797;4013.6789056874795;4035.3139966205226;4062.8171732271526;5804.506616937439;5808.970955145197;5809.36097029318;5812.814773363171;5828;5500.394956554695;5662.292213473316;5673.319201695823;5691.288588926384;5721.05602575587;5595;5637.661740295595;5639.295088113986;5646.588352911772;5678;5268.81559145428;5540.695261760642;5548.639338442988;5549.750261238173;5578.940001399721;5483.341998960151;5493.8410414011305;5552.305961754781;5603.299587551556;5630.127330263816;5489.325755644567;5495;5500.779968801363;5503.642263252876;5565.053940829987 19:26:44 INFO - PROCESS | 12016 | |5;yui.html;776.7475570439609;801.138775815998;823.4318320358952;851.1020872979004;856.9143085691429;13338.199708017526;13595.79606305905;13788.379522921468;13805.240711760849;13834.031617786733;11990.40047997601;13770.173789572633;13851.30743462828;13857.653558661039;13881.236531990737;800.3957012455602;830.4933990265963;836.1011912194385;836.861917783565;837.4054421360811;814.5927036481759;819.3568049081472;824.0893812337371;824.2005254902765;824.756696774453;718.1310614156877;858.1418581418582;858.4977787994031;858.9269912057482;859;841.4488510025943;867.6876324523166;868.4138206710477;868.6004437958546;868.7610907000562;629.0281515059245;629.3894921925724;629.5215636116538;629.9527535434814;629.987400251997;12232.694182645417;12249.816252756127;12265.938670306681;12277.20198187115;12277.447514861853;14105.083169594047;14321.570352889326;14356.784648230285;14400.927995360062;14409.639759006004;11283.717907052389;11377.658670239905;11492.655220343404;11629.883701162964;11771.411429428494;3799.625234228607;3843.404272337792;3857.787821669807;3933.8033098345254;3985.143194213258;54668;54734;54900.45099548993;55177;56009.87980240413;11256.099512039182;11257.662270131908;11411;11455.427228638535;11563;20321.89839050825;20404.897975510026;20415.79584204139;20470.078846451946;20483.38549843507;8291.170882911782;8292.626831792584;8434.325253979667;8572.228499435081;8637.0499245083;4585.243434833215;4593.724376537419;4603.516630753723;4670.276107203321;4709.1994360958715;3400.1669590950373;3475.721942244614;3484.8780292689635;3491.4762785581856;3513.877014304487;589.7287247866029;605.9515238780887;607.0742118269586;621.6611946489174;623.1462895832648;621.9315875253718;692.5498426023125;776.2354081229984;788.9723859664884;792.805762588158;9559;9690.224782017422;9784.412935223909;9934.950325248328;9943.105120539041;587.5622661117409;657.1949362031473;664.8238216872533;764.3464837563914;789.9210078992055;4460.77696115193;4621.584057434847;4694.554017368345;4705.364775755298;4743.0988112258565;63552.682236588524;63814;65021.34978650248;66652.66673666601;66764.66617666978;55147.448525515036;56195.71902140463;57159;57781.13328300079;58895.41104588985;52132.70142180098;52179;52387.47612523902;52813.207801883;53598;8387.622556984828;8485.193906579112;8527.317814574695;8613.655453782028;8667.523286219319;32691;32962.34075318528;34121.48817767785;34387.65612343844;34476.65523344835;43335.56664433315;45692.31461528146;46934.765326173154;47400.52599473961;47693.761531192125;12100.273983560663;12125.75748485043;12177.330246836154;12244;12500.937495312466;12177.33024683651;12284.877151228373;12360.38198090074;12986.545470908473;13841.238731869442;12076.577319793765;12081.75836483283;12273.202241854251;12328.19866708661;12411.069169812481;11494.310341379543;11847.940760296487;11989.1008174389;12033.939830301142;12398.132130751123;11255.268407553483;11496.310221386744;11671.941640291745;11756.94121529387;12137.271763694207;9772.804543909224;10844.67465976022;11077;11297;11332.546698132312;11354.148438867283;11366.715832104264;11439.313641181556;11605.477753500943;11642.41787910618 19:26:44 INFO - PROCESS | 12016 | __end_tp_report 19:26:44 INFO - PROCESS | 12016 | __start_cc_report 19:26:44 INFO - PROCESS | 12016 | _x_x_mozilla_cycle_collect,848 19:26:44 INFO - PROCESS | 12016 | __end_cc_report 19:26:44 INFO - PROCESS | 12016 | __startTimestamp1461983204086__endTimestamp 19:26:44 INFO - PROCESS | 12016 | 19:26:44 INFO - PROCESS | 12016 | ------- Summary: start ------- 19:26:44 INFO - PROCESS | 12016 | Number of tests: 6 19:26:44 INFO - PROCESS | 12016 | 19:26:44 INFO - PROCESS | 12016 | [#0] dojo.html Cycles:165 Average:5307.08 Median:5519.34 stddev:585.75 (10.6%) stddev-sans-first:561.91 19:26:44 INFO - PROCESS | 12016 | Values: 3122.0 3152.0 3187.9 3192.4 3214.2 5281.3 5299.1 5302.2 5323.7 5401.1 5268.5 5351.2 5358.6 5377.8 5389.1 4789.6 4828.3 4925.3 4956.1 4973.4 4886.0 4932.7 4956.1 4963.9 4967.8 4816.9 4831.8 4880.8 4894.4 4945.9 4874.5 4876.3 4880.8 4891.6 4935.1 4794.0 4847.8 4854.2 4865.6 4885.7 5268.5 5272.2 5278.3 5291.9 5299.4 5680.8 5698.4 5700.5 5703.5 5709.9 5653.1 5697.1 5722.1 5728.9 5729.4 5299.2 5315.7 5316.9 5318.9 5363.3 5765.7 5773.9 5780.8 5782.5 5805.4 5519.3 5542.3 5578.7 5592.9 5633.5 5760.9 5769.2 5779.3 5781.9 5787.6 5745.0 5760.7 5767.6 5768.6 5768.7 5615.0 5655.7 5674.2 5701.4 5702.3 4341.9 4351.7 4353.2 4361.4 4392.8 3831.6 3865.7 3886.0 3897.8 3949.0 5439.8 5467.4 5496.1 5496.3 5512.1 5706.9 5707.2 5709.5 5710.9 5735.4 5403.0 5403.7 5406.1 5411.4 5458.4 4809.2 4923.8 4953.9 4961.2 4982.1 5846.0 5847.0 5847.0 5847.2 5852.4 5610.9 5664.4 5668.9 5677.5 5679.4 5914.9 5919.9 5957.7 5965.6 5986.3 5533.7 5537.5 5542.1 5554.5 5559.6 5539.5 5624.6 5630.7 5643.4 5776.7 5588.6 5589.4 5595.5 5614.0 5639.3 5523.6 5582.4 5583.0 5585.6 5597.1 5427.0 5454.0 5507.5 5536.4 5553.4 5619.2 5632.2 5635.3 5668.9 5676.5 5502.3 5511.2 5515.1 5516.9 5525.1 19:26:44 INFO - PROCESS | 12016 | 19:26:44 INFO - PROCESS | 12016 | [#1] ext.html Cycles:180 Average:52365.95 Median:34385.16 stddev:101212.91 (294.4%) stddev-sans-first:101422.21 19:26:44 INFO - PROCESS | 12016 | Values: 601.7 645.1 648.0 661.2 662.4 34460.1 37037.6 38068.4 38097.4 38123.2 45316.3 45561.5 45997.5 46094.3 46131.8 7617.4 8349.6 8561.0 8562.9 8563.9 7717.7 7796.9 9668.2 9949.2 10055.6 10062.8 10201.6 10295.5 10309.9 10316.8 10117.9 10236.3 10239.1 10244.5 10248.9 9992.3 10115.5 10122.5 10126.6 10130.5 41064.4 41612.8 41658.0 41658.0 41661.4 3379.9 3457.4 3501.2 3829.1 3833.7 31298.0 31499.2 31550.0 31885.5 32295.4 4183.6 4188.0 4262.5 4298.6 4355.4 331578.0 332105.0 332573.0 333168.0 333278.0 32305.5 33321.2 33431.5 59051.7 59077.4 34338.0 34432.3 34436.5 34438.8 34506.2 15103.0 15354.5 15359.7 15365.3 15378.8 11609.4 11997.6 12926.7 12992.8 13000.7 8165.3 8252.0 8252.1 8300.8 8311.7 3119.9 3234.6 3247.3 3254.0 3270.8 968.8 984.5 1035.8 1049.1 1109.3 32153.4 32403.0 32461.0 32745.5 33014.7 1082.1 1096.1 1127.6 1145.9 1252.6 16782.2 17464.8 17549.0 17572.7 17780.3 481664.0 484585.0 485004.0 485631.0 488939.0 58627.7 59226.7 59309.0 59398.0 59515.7 735.4 1164.8 1247.4 1327.8 1367.4 28160.0 28926.4 29389.9 29431.3 29974.4 43680.3 44042.1 44050.8 44057.3 44065.6 297337.0 303904.0 304997.0 305609.0 306720.0 24486.5 25211.1 25325.0 25734.6 26048.7 40517.6 46199.8 46299.3 46814.8 46911.5 38356.6 38517.0 39050.4 39276.8 39732.8 38008.6 39371.6 39794.6 39874.2 40284.4 38551.2 39440.4 39690.2 40053.2 40086.2 31963.5 33241.7 34072.7 34325.8 35701.5 25401.4 25562.9 25648.4 25778.7 25950.9 19:26:44 INFO - PROCESS | 12016 | 19:26:44 INFO - PROCESS | 12016 | [#2] jquery.html Cycles:180 Average:28201.48 Median:19890.78 stddev:81503.27 (409.8%) stddev-sans-first:81705.14 19:26:44 INFO - PROCESS | 12016 | Values: 382.7 388.3 388.4 397.2 400.7 17367.7 18299.5 18824.2 18839.4 18851.6 17202.5 17864.3 17952.8 17972.1 19304.7 5529.4 5605.4 5616.9 5704.2 5706.9 5441.3 5619.4 5646.8 5652.3 5654.6 5543.9 5607.9 5614.2 5615.3 5627.8 5076.3 5090.4 5122.4 5123.2 5124.5 4808.9 4944.5 4967.2 4991.9 5063.9 12552.9 12611.5 12682.7 12699.2 12744.6 2026.0 2059.0 2219.6 2632.2 2688.0 9271.8 9761.9 13673.0 13724.9 13820.8 1807.8 1956.1 2004.2 2033.2 2052.3 58877.0 60220.4 76723.6 79238.6 81220.2 10503.6 10518.6 10615.7 10639.1 10792.1 14875.2 14933.3 14971.9 14975.4 15017.6 7233.2 7646.3 7738.3 7990.2 8052.2 7894.7 7954.9 8114.4 8121.0 8141.7 4377.5 4476.1 4490.4 4567.4 4583.4 1813.5 1874.2 1906.0 1908.8 1940.3 868.4 960.8 982.3 1016.5 1124.8 18271.5 18282.5 18435.9 18699.9 18752.2 977.6 980.9 985.6 1028.7 1060.8 10126.5 10341.8 10440.7 10482.6 10487.5 478951.0 489458.0 496460.0 502468.0 504612.0 93707.0 96479.5 96994.0 98453.5 98457.0 980.3 985.3 997.9 998.0 1003.8 16155.2 16231.5 16440.6 16448.8 16466.7 27828.6 30205.4 38516.6 39384.4 39713.8 50413.5 54084.7 54955.5 55963.4 56842.7 8099.9 8220.9 8237.2 8318.6 8378.0 9416.4 12985.3 13547.5 13560.4 13630.4 5961.5 8235.0 11801.2 11837.7 11980.2 11658.7 11687.8 11749.9 11794.7 11996.9 11902.4 12035.6 12049.4 12061.6 12178.8 8366.9 8399.7 8474.0 8632.5 8845.8 8521.8 11324.2 11937.5 12277.3 12292.0 19:26:44 INFO - PROCESS | 12016 | 19:26:44 INFO - PROCESS | 12016 | [#3] mootools.html Cycles:180 Average:5442.39 Median:4965.75 stddev:3036.11 (61.1%) stddev-sans-first:3028.79 19:26:44 INFO - PROCESS | 12016 | Values: 1316.5 1422.4 1472.5 1557.9 1727.2 7645.1 7943.8 7944.3 7966.6 7967.8 7732.1 7878.2 7884.5 7907.0 7916.2 2285.6 2312.8 2315.0 2318.7 2320.2 2255.6 2271.0 2274.0 2301.3 2308.8 2164.5 2200.4 2205.3 2252.8 2256.9 2172.3 2175.4 2182.5 2183.0 2185.0 2244.5 2245.4 2245.7 2246.3 2248.4 7837.2 7950.9 8032.5 8032.8 8047.4 4891.3 5038.4 5039.4 5045.6 5046.5 3168.7 3196.6 3268.1 3345.3 3348.6 1109.0 1118.8 1121.7 1123.2 1139.4 11338.5 11463.0 11481.1 11495.2 11505.5 4280.9 4329.7 4357.0 4515.9 4520.2 9005.2 9019.6 9039.8 9060.2 9072.9 4904.3 4951.3 4961.0 4970.5 4976.0 4156.4 4581.5 4665.1 4751.1 4778.1 3656.6 3701.7 3783.7 3816.4 3820.8 1102.5 1132.9 1136.5 1140.1 1143.7 729.5 956.6 1005.5 1006.5 1015.5 5494.0 5574.1 5588.9 5646.2 5725.5 463.2 471.8 484.6 486.6 643.6 3920.0 4106.0 4179.1 4198.4 4228.2 9946.2 10423.5 10433.2 10438.0 10480.3 9568.4 9887.4 10280.9 10356.7 10372.9 7442.8 7545.8 7568.4 7576.8 7607.4 5549.7 5615.7 5672.5 5691.4 5769.4 9025.3 9162.5 9192.1 9208.4 9253.6 9241.5 9324.5 9605.3 9736.6 9740.3 7607.6 7706.1 7784.1 7798.4 7802.6 7970.5 7974.5 8046.6 8052.3 8123.1 7258.9 7433.6 7437.5 7506.6 7535.1 7183.3 7243.9 7290.5 7409.2 7409.9 6645.8 6690.5 6722.9 6725.5 6729.3 4849.8 4902.3 4949.6 5031.3 5032.2 4985.8 4989.4 5028.4 5070.5 5091.2 19:26:44 INFO - PROCESS | 12016 | 19:26:44 INFO - PROCESS | 12016 | [#4] prototype.html Cycles:180 Average:4747.68 Median:5386.85 stddev:1415.39 (26.3%) stddev-sans-first:1379.38 19:26:44 INFO - PROCESS | 12016 | Values: 297.0 299.5 299.9 301.8 306.5 5265.6 5319.7 5347.6 5374.5 5393.2 5281.9 5294.3 5295.5 5299.5 5306.4 3980.4 4026.3 4118.1 4163.6 4186.6 4130.9 4145.9 4147.6 4166.0 4199.8 3930.6 4108.7 4128.8 4133.3 4145.7 3959.6 3959.6 4071.1 4162.5 4167.3 3654.5 3667.3 3687.2 3690.4 3692.7 5138.7 5156.0 5161.9 5175.7 5176.3 4557.3 4655.4 4719.3 4732.8 4778.3 5515.6 5533.5 5537.5 5542.4 5579.7 4030.6 4034.0 4037.8 4043.2 4051.9 5660.5 5749.6 5774.0 5783.5 5799.5 4088.6 4099.0 4101.9 4123.0 4123.2 4147.0 4150.8 4164.1 4169.4 4169.7 4566.3 4620.7 4710.8 4715.5 4749.2 5545.6 5570.5 5586.7 5596.2 5602.0 1453.4 1457.8 1461.5 1468.6 1469.8 946.7 958.8 990.1 992.6 1015.4 5232.9 5359.9 5375.6 5413.2 5435.8 5682.7 5684.8 5690.6 5735.4 5740.9 5222.9 5380.5 5429.9 5451.4 5497.1 3243.2 3255.7 3261.6 3270.8 3273.5 6081.5 6282.3 6317.2 6320.4 6327.5 6229.9 6238.3 6258.7 6284.0 6285.8 6283.7 6304.9 6312.1 6351.4 6354.2 5489.5 5768.4 5787.4 5806.5 6128.9 5748.3 5750.8 5755.7 5756.7 5763.2 5810.5 5917.6 5957.4 5995.5 6003.8 3999.2 4000.9 4013.7 4035.3 4062.8 5804.5 5809.0 5809.4 5812.8 5828.0 5500.4 5662.3 5673.3 5691.3 5721.1 5595.0 5637.7 5639.3 5646.6 5678.0 5268.8 5540.7 5548.6 5549.8 5578.9 5483.3 5493.8 5552.3 5603.3 5630.1 5489.3 5495.0 5500.8 5503.6 5565.1 19:26:44 INFO - PROCESS | 12016 | 19:26:44 INFO - PROCESS | 12016 | [#5] yui.html Cycles:180 Average:15007.87 Median:4645.93 stddev:17676.12 (380.5%) stddev-sans-first:17693.40 19:26:44 INFO - PROCESS | 12016 | Values: 776.7 801.1 823.4 851.1 856.9 13338.2 13595.8 13788.4 13805.2 13834.0 11990.4 13770.2 13851.3 13857.7 13881.2 800.4 830.5 836.1 836.9 837.4 814.6 819.4 824.1 824.2 824.8 718.1 858.1 858.5 858.9 859.0 841.4 867.7 868.4 868.6 868.8 629.0 629.4 629.5 630.0 630.0 12232.7 12249.8 12265.9 12277.2 12277.4 14105.1 14321.6 14356.8 14400.9 14409.6 11283.7 11377.7 11492.7 11629.9 11771.4 3799.6 3843.4 3857.8 3933.8 3985.1 54668.0 54734.0 54900.5 55177.0 56009.9 11256.1 11257.7 11411.0 11455.4 11563.0 20321.9 20404.9 20415.8 20470.1 20483.4 8291.2 8292.6 8434.3 8572.2 8637.0 4585.2 4593.7 4603.5 4670.3 4709.2 3400.2 3475.7 3484.9 3491.5 3513.9 589.7 606.0 607.1 621.7 623.1 621.9 692.5 776.2 789.0 792.8 9559.0 9690.2 9784.4 9935.0 9943.1 587.6 657.2 664.8 764.3 789.9 4460.8 4621.6 4694.6 4705.4 4743.1 63552.7 63814.0 65021.3 66652.7 66764.7 55147.4 56195.7 57159.0 57781.1 58895.4 52132.7 52179.0 52387.5 52813.2 53598.0 8387.6 8485.2 8527.3 8613.7 8667.5 32691.0 32962.3 34121.5 34387.7 34476.7 43335.6 45692.3 46934.8 47400.5 47693.8 12100.3 12125.8 12177.3 12244.0 12500.9 12177.3 12284.9 12360.4 12986.5 13841.2 12076.6 12081.8 12273.2 12328.2 12411.1 11494.3 11847.9 11989.1 12033.9 12398.1 11255.3 11496.3 11671.9 11756.9 12137.3 9772.8 10844.7 11077.0 11297.0 11332.5 11354.1 11366.7 11439.3 11605.5 11642.4 19:26:44 INFO - PROCESS | 12016 | -------- Summary: end -------- 19:26:44 INFO - PROCESS | 12016 | 19:26:44 INFO - TEST-INFO | 12016: exit 0 19:26:44 INFO - TEST-OK | dromaeo_css | took 1112425ms 19:26:44 INFO - TEST-START | kraken 19:26:44 INFO - operating with platform_type : linux_ 19:26:44 INFO - Initialising browser for kraken test... 19:26:44 INFO - TEST-INFO | started process 12199 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppfE8Ue/profile http://localhost:44776/getInfo.html) 19:26:49 INFO - PROCESS | 12199 | __metrics Screen width/height:1600/1200 19:26:49 INFO - PROCESS | 12199 | colorDepth:24 19:26:49 INFO - PROCESS | 12199 | Browser inner width/height: 1024/697 19:26:49 INFO - PROCESS | 12199 | __metrics 19:26:50 ERROR - PROCESS | 12199 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:26:51 INFO - TEST-INFO | 12199: exit 0 19:26:51 INFO - Browser initialized. 19:26:51 INFO - Running cycle 1/1 for kraken test... 19:26:51 INFO - TEST-INFO | started process 12282 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppfE8Ue/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:27:03 INFO - PROCESS | 12282 | RSS: Main: 154140672 19:27:03 INFO - PROCESS | 12282 | 19:27:05 INFO - PROCESS | 12282 | Cycle 1(1): loaded http://localhost:44776/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44776/tests/kraken/driver.html?testName=audio-beat-detection) 19:27:06 INFO - PROCESS | 12282 | RSS: Main: 209629184 19:27:06 INFO - PROCESS | 12282 | 19:27:09 INFO - PROCESS | 12282 | Cycle 1(1): loaded http://localhost:44776/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44776/tests/kraken/driver.html?testName=audio-dft) 19:27:09 INFO - PROCESS | 12282 | RSS: Main: 195510272 19:27:09 INFO - PROCESS | 12282 | 19:27:12 INFO - PROCESS | 12282 | Cycle 1(1): loaded http://localhost:44776/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44776/tests/kraken/driver.html?testName=audio-fft) 19:27:13 INFO - PROCESS | 12282 | RSS: Main: 265072640 19:27:13 INFO - PROCESS | 12282 | 19:27:15 INFO - PROCESS | 12282 | Cycle 1(1): loaded http://localhost:44776/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44776/tests/kraken/driver.html?testName=audio-oscillator) 19:27:16 INFO - PROCESS | 12282 | RSS: Main: 173309952 19:27:16 INFO - PROCESS | 12282 | 19:27:18 INFO - PROCESS | 12282 | Cycle 1(1): loaded http://localhost:44776/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44776/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:27:18 INFO - PROCESS | 12282 | RSS: Main: 177582080 19:27:18 INFO - PROCESS | 12282 | 19:27:21 INFO - PROCESS | 12282 | Cycle 1(1): loaded http://localhost:44776/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44776/tests/kraken/driver.html?testName=imaging-darkroom) 19:27:22 INFO - PROCESS | 12282 | RSS: Main: 214396928 19:27:22 INFO - PROCESS | 12282 | 19:27:25 INFO - PROCESS | 12282 | Cycle 1(1): loaded http://localhost:44776/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44776/tests/kraken/driver.html?testName=imaging-desaturate) 19:27:26 INFO - PROCESS | 12282 | RSS: Main: 215154688 19:27:26 INFO - PROCESS | 12282 | 19:27:29 INFO - PROCESS | 12282 | Cycle 1(1): loaded http://localhost:44776/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44776/tests/kraken/driver.html?testName=json-parse-financial) 19:27:29 INFO - PROCESS | 12282 | RSS: Main: 215441408 19:27:29 INFO - PROCESS | 12282 | 19:27:31 INFO - PROCESS | 12282 | Cycle 1(1): loaded http://localhost:44776/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44776/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:27:31 INFO - PROCESS | 12282 | RSS: Main: 206557184 19:27:31 INFO - PROCESS | 12282 | 19:27:33 INFO - PROCESS | 12282 | Cycle 1(1): loaded http://localhost:44776/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44776/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:27:34 INFO - PROCESS | 12282 | RSS: Main: 205152256 19:27:34 INFO - PROCESS | 12282 | 19:27:36 INFO - PROCESS | 12282 | Cycle 1(1): loaded http://localhost:44776/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44776/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:27:37 INFO - PROCESS | 12282 | RSS: Main: 216256512 19:27:37 INFO - PROCESS | 12282 | 19:27:39 INFO - PROCESS | 12282 | Cycle 1(1): loaded http://localhost:44776/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44776/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:27:40 INFO - PROCESS | 12282 | RSS: Main: 224067584 19:27:40 INFO - PROCESS | 12282 | 19:27:43 INFO - PROCESS | 12282 | Cycle 1(1): loaded http://localhost:44776/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44776/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:27:43 INFO - PROCESS | 12282 | RSS: Main: 219312128 19:27:43 INFO - PROCESS | 12282 | 19:27:45 INFO - PROCESS | 12282 | Cycle 1(1): loaded http://localhost:44776/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44776/tests/kraken/driver.html?testName=ai-astar) 19:27:45 INFO - PROCESS | 12282 | RSS: Main: 283652096 19:27:45 INFO - PROCESS | 12282 | 19:27:45 INFO - PROCESS | 12282 | __start_tp_report 19:27:45 INFO - PROCESS | 12282 | _x_x_mozilla_page_load 19:27:45 INFO - PROCESS | 12282 | _x_x_mozilla_page_load_details 19:27:45 INFO - PROCESS | 12282 | |i|pagename|runs| 19:27:45 INFO - PROCESS | 12282 | |0;ai-astar;113;113;107;112;114;112;101;112;112;111 19:27:45 INFO - PROCESS | 12282 | |1;audio-beat-detection;170;129;161;181;136;154;158;130;190;129 19:27:45 INFO - PROCESS | 12282 | |2;audio-dft;161;162;161;161;160;160;161;162;162;161 19:27:45 INFO - PROCESS | 12282 | |3;audio-fft;78;78;105;115;79;77;78;143;105;78 19:27:45 INFO - PROCESS | 12282 | |4;audio-oscillator;92;92;151;93;93;93;150;93;91;146 19:27:45 INFO - PROCESS | 12282 | |5;imaging-gaussian-blur;116;116;117;117;117;117;117;117;117;116 19:27:45 INFO - PROCESS | 12282 | |6;imaging-darkroom;145;146;146;145;146;145;145;145;145;145 19:27:45 INFO - PROCESS | 12282 | |7;imaging-desaturate;95;94;94;95;95;95;95;95;95;95 19:27:45 INFO - PROCESS | 12282 | |8;json-parse-financial;59;56;56;56;58;57;61;77;57;54 19:27:45 INFO - PROCESS | 12282 | |9;json-stringify-tinderbox;64;63;63;63;63;63;63;63;64;63 19:27:45 INFO - PROCESS | 12282 | |10;stanford-crypto-aes;78;77;78;77;81;78;78;79;79;79 19:27:45 INFO - PROCESS | 12282 | |11;stanford-crypto-ccm;129;123;124;123;123;131;121;134;154;125 19:27:45 INFO - PROCESS | 12282 | |12;stanford-crypto-pbkdf2;168;176;171;174;181;176;173;175;172;168 19:27:45 INFO - PROCESS | 12282 | |13;stanford-crypto-sha256-iterative;77;70;72;70;70;68;68;69;69;70 19:27:45 INFO - PROCESS | 12282 | __end_tp_report 19:27:45 INFO - PROCESS | 12282 | __start_cc_report 19:27:45 INFO - PROCESS | 12282 | _x_x_mozilla_cycle_collect,1624 19:27:45 INFO - PROCESS | 12282 | __end_cc_report 19:27:45 INFO - PROCESS | 12282 | __startTimestamp1461983265613__endTimestamp 19:27:45 INFO - PROCESS | 12282 | 19:27:45 INFO - PROCESS | 12282 | ------- Summary: start ------- 19:27:45 INFO - PROCESS | 12282 | Number of tests: 14 19:27:45 INFO - PROCESS | 12282 | 19:27:45 INFO - PROCESS | 12282 | [#0] ai-astar Cycles:10 Average:110.70 Median:112.00 stddev:3.89 (3.5%) stddev-sans-first:4.03 19:27:45 INFO - PROCESS | 12282 | Values: 113.0 113.0 107.0 112.0 114.0 112.0 101.0 112.0 112.0 111.0 19:27:45 INFO - PROCESS | 12282 | 19:27:45 INFO - PROCESS | 12282 | [#1] audio-beat-detection Cycles:10 Average:153.80 Median:159.50 stddev:22.35 (14.0%) stddev-sans-first:22.92 19:27:45 INFO - PROCESS | 12282 | Values: 170.0 129.0 161.0 181.0 136.0 154.0 158.0 130.0 190.0 129.0 19:27:45 INFO - PROCESS | 12282 | 19:27:45 INFO - PROCESS | 12282 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 19:27:45 INFO - PROCESS | 12282 | Values: 161.0 162.0 161.0 161.0 160.0 160.0 161.0 162.0 162.0 161.0 19:27:45 INFO - PROCESS | 12282 | 19:27:45 INFO - PROCESS | 12282 | [#3] audio-fft Cycles:10 Average:93.60 Median:78.00 stddev:22.66 (29.0%) stddev-sans-first:23.32 19:27:45 INFO - PROCESS | 12282 | Values: 78.0 78.0 105.0 115.0 79.0 77.0 78.0 143.0 105.0 78.0 19:27:45 INFO - PROCESS | 12282 | 19:27:45 INFO - PROCESS | 12282 | [#4] audio-oscillator Cycles:10 Average:109.40 Median:92.50 stddev:27.36 (29.6%) stddev-sans-first:28.29 19:27:45 INFO - PROCESS | 12282 | Values: 92.0 92.0 151.0 93.0 93.0 93.0 150.0 93.0 91.0 146.0 19:27:45 INFO - PROCESS | 12282 | 19:27:45 INFO - PROCESS | 12282 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 19:27:45 INFO - PROCESS | 12282 | Values: 116.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 19:27:45 INFO - PROCESS | 12282 | 19:27:45 INFO - PROCESS | 12282 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 19:27:45 INFO - PROCESS | 12282 | Values: 145.0 146.0 146.0 145.0 146.0 145.0 145.0 145.0 145.0 145.0 19:27:45 INFO - PROCESS | 12282 | 19:27:45 INFO - PROCESS | 12282 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 19:27:45 INFO - PROCESS | 12282 | Values: 95.0 94.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 19:27:45 INFO - PROCESS | 12282 | 19:27:45 INFO - PROCESS | 12282 | [#8] json-parse-financial Cycles:10 Average:59.10 Median:57.50 stddev:6.57 (11.4%) stddev-sans-first:6.97 19:27:45 INFO - PROCESS | 12282 | Values: 59.0 56.0 56.0 56.0 58.0 57.0 61.0 77.0 57.0 54.0 19:27:45 INFO - PROCESS | 12282 | 19:27:45 INFO - PROCESS | 12282 | [#9] json-stringify-tinderbox Cycles:10 Average:63.20 Median:63.00 stddev:0.42 (0.7%) stddev-sans-first:0.33 19:27:45 INFO - PROCESS | 12282 | Values: 64.0 63.0 63.0 63.0 63.0 63.0 63.0 63.0 64.0 63.0 19:27:45 INFO - PROCESS | 12282 | 19:27:45 INFO - PROCESS | 12282 | [#10] stanford-crypto-aes Cycles:10 Average:78.40 Median:78.50 stddev:1.17 (1.5%) stddev-sans-first:1.24 19:27:45 INFO - PROCESS | 12282 | Values: 78.0 77.0 78.0 77.0 81.0 78.0 78.0 79.0 79.0 79.0 19:27:45 INFO - PROCESS | 12282 | 19:27:45 INFO - PROCESS | 12282 | [#11] stanford-crypto-ccm Cycles:10 Average:128.70 Median:127.00 stddev:9.81 (7.7%) stddev-sans-first:10.40 19:27:45 INFO - PROCESS | 12282 | Values: 129.0 123.0 124.0 123.0 123.0 131.0 121.0 134.0 154.0 125.0 19:27:45 INFO - PROCESS | 12282 | 19:27:45 INFO - PROCESS | 12282 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.40 Median:174.50 stddev:3.95 (2.3%) stddev-sans-first:3.67 19:27:45 INFO - PROCESS | 12282 | Values: 168.0 176.0 171.0 174.0 181.0 176.0 173.0 175.0 172.0 168.0 19:27:45 INFO - PROCESS | 12282 | 19:27:45 INFO - PROCESS | 12282 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.30 Median:70.00 stddev:2.63 (3.8%) stddev-sans-first:1.24 19:27:45 INFO - PROCESS | 12282 | Values: 77.0 70.0 72.0 70.0 70.0 68.0 68.0 69.0 69.0 70.0 19:27:45 INFO - PROCESS | 12282 | -------- Summary: end -------- 19:27:45 INFO - PROCESS | 12282 | 19:27:46 INFO - TEST-INFO | 12282: exit 0 19:27:46 INFO - TEST-OK | kraken | took 61637ms 19:27:46 INFO - SUITE-END | took 1174s 19:27:46 INFO - Completed test suite (00:19:34) 19:27:46 INFO - javascript benchmark 19:27:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3121.968780312197, 3152.0228729093997, 3187.8884239051636, 3192.3615276944615, 3214.1803840020784, 5281.260623512701, 5299.072662284099, 5302.178162384832, 5323.733813309329, 5401.108817045188, 5268.4994925482015, 5351.170568561869, 5358.59810514212, 5377.784888604461, 5389.137737961927, 4789.5928846047955, 4828.275758636215, 4925.26121081837, 4956.132676781567, 4973.4280557735965, 4885.951140488585, 4932.704037757728, 4956.0583489137125, 4963.851084467472, 4967.751612419383, 4816.855494335158, 4831.806727730886, 4880.8047678092835, 4894.36373271476, 4945.876353091166, 4874.463808981045, 4876.292937524039, 4880.755962201912, 4891.608671306287, 4935.136351138536, 4794.041191761662, 4847.806087756486, 4854.199057155565, 4865.635077369211, 4885.706857588556, 5268.473152684739, 5272.156454967225, 5278.313819203518, 5291.94161167768, 5299.417064122943, 5680.772769089232, 5698.43015698431, 5700.515456186187, 5703.458171473703, 5709.914351284734, 5653.123765816305, 5697.088465845445, 5722.084466485384, 5728.942710572883, 5729.369769325371, 5299.152135658314, 5315.734213289359, 5316.946830531722, 5318.893622127574, 5363.329583802025, 5765.740541675635, 5773.942260577341, 5780.768769249267, 5782.479576838105, 5805.419458054245, 5519.337679478488, 5542.2795036644975, 5578.665280083208, 5592.944070559323, 5633.549316054706, 5760.913586296209, 5769.249997500298, 5779.33537643168, 5781.913271300934, 5787.565932555075, 5745.0, 5760.683162426107, 5767.567432442584, 5768.567357448208, 5768.653880767168, 5615.0454422748235, 5655.745491452894, 5674.205611214434, 5701.372848986608, 5702.258706368146, 4341.9131617367475, 4351.69538132334, 4353.172897149531, 4361.389405483234, 4392.758398288125, 3831.59768224338, 3865.690744740414, 3886.0090676877217, 3897.8051097445014, 3949.0, 5439.755211015435, 5467.371252306036, 5496.065668836308, 5496.258005169251, 5512.090505066619, 5706.942930570641, 5707.229524014288, 5709.543236541067, 5710.942890571124, 5735.426457354231, 5402.972985135128, 5403.675779453245, 5406.135018397038, 5411.404745477994, 5458.399576046631, 4809.182438985381, 4923.803047878045, 4953.90092198154, 4961.156603377434, 4982.128127577732, 5845.970770146122, 5846.970765146147, 5847.035239185487, 5847.152162936351, 5852.356240813592, 5610.943890561042, 5664.433556644302, 5668.866226754748, 5677.460641239076, 5679.432056794288, 5914.940850591439, 5919.911201331897, 5957.702114894325, 5965.582409231312, 5986.2517185351835, 5533.695646739468, 5537.529310008695, 5542.0855558832345, 5554.472325129106, 5559.63862348946, 5539.501444869981, 5624.634398764068, 5630.690312032878, 5643.3792282848035, 5776.653400795798, 5588.636738611977, 5589.385167631639, 5595.468430499096, 5613.989481893298, 5639.295088113986, 5523.558115350843, 5582.441755824385, 5582.9720851397105, 5585.581081418991, 5597.132444471114, 5427.0, 5454.0, 5507.531859791962, 5536.446355364431, 5553.389127195925, 5619.185218143264, 5632.155176723524, 5635.323761148688, 5668.943310567007, 5676.545876329801, 5502.312210973629, 5511.22842802, 5515.117581186991, 5516.862078448071, 5525.11598144295], "value": 5268.757804772772, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [601.7081715368048, 645.1129696667083, 647.9697281322696, 661.2395744893375, 662.3806740697448, 34460.1384965376, 37037.62962370379, 38068.42897356542, 38097.42853857194, 38123.23753524935, 45316.320255196195, 45561.544384556146, 45997.540024599824, 46094.30858537116, 46131.769341153245, 7617.390608751314, 8349.62426690799, 8560.957195214016, 8562.871556926651, 8563.914360856405, 7717.65270562826, 7796.922030779676, 9668.178204852597, 9949.154321882648, 10055.64805231817, 10062.79874402515, 10201.642942497014, 10295.536700848443, 10309.948450257738, 10316.845247321296, 10117.89882101177, 10236.283460157792, 10239.078482936497, 10244.48777561119, 10248.948755256251, 9992.25058120644, 10115.494225288707, 10122.493875306205, 10126.645567405105, 10130.493475326204, 41064.38403423951, 41612.79193604013, 41658.0, 41658.0, 41661.375079373836, 3379.8648054077808, 3457.360388328142, 3501.1597216667815, 3829.138443850139, 3833.731638785286, 31298.0, 31499.212519686964, 31550.0, 31885.521717174262, 32295.35409291801, 4183.644390226835, 4187.979060104711, 4262.531121576624, 4298.591633794785, 4355.412019377375, 331578.0, 332105.0, 332573.0, 333168.0, 333277.99999999756, 32305.515417268758, 33321.16697082544, 33431.498527522104, 59051.704741476016, 59077.40922590805, 34338.0, 34432.311353772784, 34436.48345274823, 34438.827805861314, 34506.20431752505, 15103.0, 15354.541318574718, 15359.692806143814, 15365.308561114553, 15378.769318460232, 11609.419529023684, 11997.64007079789, 12926.741465170644, 12992.805107923388, 13000.739985200433, 8165.346772258205, 8252.0, 8252.133525979685, 8300.750977470685, 8311.709090181814, 3119.859606317721, 3234.5795046643784, 3247.3018301065385, 3253.967460325414, 3270.7546933980047, 968.8256113899424, 984.478226539935, 1035.8239099353127, 1049.0663309654415, 1109.3177695717077, 32153.35693286121, 32403.0, 32461.0, 32745.508817367758, 33014.66985330116, 1082.1396989393293, 1096.1231015187977, 1127.5658871334595, 1145.8968692817687, 1252.5803855708439, 16782.160891955355, 17464.825351746575, 17549.0, 17572.736408953875, 17780.288788448317, 481664.0, 484585.0, 485004.0, 485631.0, 488939.0, 58627.70686146542, 59226.70386648039, 59309.0, 59398.0, 59515.702421487615, 735.4300417176731, 1164.8485696859354, 1247.419949723383, 1327.8273824402766, 1367.3505085084705, 28160.0, 28926.421471570873, 29389.853050734608, 29431.26090412349, 29974.400511990076, 43680.34479482747, 44042.11915761603, 44050.77974610106, 44057.339139913565, 44065.559344407426, 297337.0, 303904.0, 304997.0, 305609.0, 306720.0, 24486.51026979486, 25211.11761088353, 25325.0, 25734.613980789927, 26048.739512604632, 40517.59482405138, 46199.78961117958, 46299.3055104167, 46814.76592617015, 46911.530884690714, 38356.616433835305, 38517.0, 39050.414243785795, 39276.803615981735, 39732.801335993136, 38008.61991380051, 39371.606283936795, 39794.60205397909, 39874.2025159501, 40284.3957340646, 38551.2289754209, 39440.40839387469, 39690.2061958765, 40053.19893602053, 40086.1982760349, 31963.520547192275, 33241.66758332482, 34072.65927340695, 34325.828370857984, 35701.46447803337, 25401.364965876, 25562.872185638953, 25648.358791030372, 25778.742212578385, 25950.87024564865], "value": 18215.78048797088, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.68810919100923, 388.28749245135174, 388.4173739390912, 397.15009878859246, 400.71949635255294, 17367.652646947052, 18299.451016469495, 18824.15291311891, 18839.434816955512, 18851.622967540658, 17202.483925482255, 17864.285428582873, 17952.82047179528, 17972.101394930265, 19304.710429343537, 5529.364123125852, 5605.383407825136, 5616.943830561703, 5704.201411802351, 5706.914396284059, 5441.319835020622, 5619.409961953977, 5646.830595082154, 5652.293463317085, 5654.575906807006, 5543.946650136481, 5607.887842243153, 5614.214010038578, 5615.298087739033, 5627.831165065034, 5076.340075790161, 5090.440051594322, 5122.359705036871, 5123.180291153416, 5124.538791508782, 4808.879778005543, 4944.53026962438, 4967.180415231483, 4991.925121123186, 5063.873403164914, 12552.874471255262, 12611.495540178383, 12682.74634507314, 12699.174553653984, 12744.61766147017, 2025.9594808103902, 2059.0, 2219.6115679755976, 2632.197179860142, 2687.9865600672065, 9271.768205794842, 9761.853572196424, 13673.0, 13724.862751372459, 13820.792688109686, 1807.7921039080543, 1956.1392987085637, 2004.188303736989, 2033.2375359240284, 2052.332991777663, 58877.0, 60220.39779602236, 76723.6163819183, 79238.60380698118, 81220.18779812186, 10503.579856805642, 10518.579256829642, 10615.681529554126, 10639.148868090535, 10792.082672972885, 14875.181864997312, 14933.328000240015, 14971.92514037423, 14975.400983960737, 15017.624559386102, 7233.204347521769, 7646.311831935152, 7738.264864837832, 7990.240927111915, 8052.154523775036, 7894.723684671124, 7954.920450795418, 8114.43198976066, 8121.0, 8141.674333026731, 4377.518472967971, 4476.104779044204, 4490.416245888014, 4567.429071366079, 4583.358329833826, 1813.4922221777924, 1874.2034635279952, 1905.9904700476686, 1908.787919671019, 1940.3402843033437, 868.3574155125162, 960.7550074731039, 982.3418309732353, 1016.4663551635302, 1124.7525544380223, 18271.54321141982, 18282.54293642643, 18435.90782046108, 18699.90650046768, 18752.156152973148, 977.5698692575386, 980.9019098090134, 985.6353149334792, 1028.7068185567227, 1060.750723579958, 10126.493675316206, 10341.793164136676, 10440.738981525523, 10482.63310784119, 10487.528061237263, 478951.0, 489458.0, 496460.0, 502468.0, 504612.0, 93707.0, 96479.51760241154, 96994.0, 98453.50773246089, 98457.0, 980.2696990741942, 985.2708995343394, 997.850322451638, 997.9700608981742, 1003.8042581696645, 16155.192240388169, 16231.51305460838, 16440.588985275463, 16448.75326870074, 16466.67066658636, 27828.582571261853, 30205.395892082473, 38516.61483385242, 39384.40923386209, 39713.80143099266, 50413.49586504088, 54084.72957635186, 54955.45044549663, 55963.44036559582, 56842.71578642246, 8099.919000809917, 8220.876686849582, 8237.176282371714, 8318.584070796314, 8378.0, 9416.387934784218, 12985.28580928058, 13547.525836595672, 13560.389782459615, 13630.386632601754, 5961.523078153824, 8235.0, 11801.232919860182, 11837.704057398634, 11980.221285616408, 11658.6502404928, 11687.76624467489, 11749.941250293694, 11794.705132371759, 11996.940015299868, 11902.404879755804, 12035.638930832089, 12049.397530123284, 12061.612914514943, 12178.817317740064, 8366.916330836857, 8399.664013439395, 8474.0, 8632.525211113198, 8845.805816214728, 8521.82956340882, 11324.207305488537, 11937.46281417321, 12277.324747138993, 12292.0], "value": 8796.29939089957, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1316.4602512969684, 1422.4310275889643, 1472.521430535076, 1557.9454719084827, 1727.1623262717571, 7645.120811106717, 7943.76168714938, 7944.285014348708, 7966.5618390988475, 7967.800804979871, 7732.072151341833, 7878.212178782119, 7884.526928384301, 7907.0, 7916.208379162081, 2285.565742508926, 2312.7802858728396, 2314.9652755208685, 2318.6637937499054, 2320.2227253869974, 2255.605269077913, 2270.9886450567806, 2274.0, 2301.309607117866, 2308.8383813133087, 2164.545445456456, 2200.3508964855405, 2205.3273751505785, 2252.8085112765352, 2256.909723611054, 2172.3048624440185, 2175.434387059368, 2182.476205710626, 2182.967255491169, 2184.9563008739815, 2244.483768733184, 2245.4498647831388, 2245.7080579524722, 2246.2587346175724, 2248.3816950338623, 7837.216278372117, 7950.920490795076, 8032.4778889372, 8032.839343213162, 8047.396445266629, 4891.266310053485, 5038.39539255288, 5039.420466646346, 5045.571126454256, 5046.460829447017, 3168.7306578940816, 3196.5844440222854, 3268.0522648431925, 3345.2640419107756, 3348.614909285415, 1108.9725050611876, 1118.7930232906938, 1121.6803211084803, 1123.1856903744801, 1139.4188963628526, 11338.546458141664, 11463.0, 11481.13891458144, 11495.19533632646, 11505.482253298622, 4280.871573852789, 4329.740215587075, 4357.0196705740955, 4515.932261016087, 4520.18636645407, 9005.189532942066, 9019.639214431481, 9039.81920361589, 9060.184583387527, 9072.90927090734, 4904.337914381513, 4951.331570238044, 4960.975195124073, 4970.527799859007, 4975.975120124376, 4156.376543518436, 4581.496035436099, 4665.136949664323, 4751.1447939370555, 4778.068276686083, 3656.634336566375, 3701.7038636908983, 3783.7162212834146, 3816.3893776995546, 3820.8089595520114, 1102.4818335382358, 1132.8980391764617, 1136.4885801389407, 1140.1448913315014, 1143.651186388143, 729.4893574497872, 956.5695437053354, 1005.5374527717332, 1006.5017816181037, 1015.5328548867456, 5493.972530137324, 5574.136008918624, 5588.860278492989, 5646.237757902631, 5725.484706376446, 463.150119530665, 471.8419329524648, 484.6147312886264, 486.5864015586723, 643.5776932978171, 3920.0, 4105.97947010263, 4179.1432756284885, 4198.433211516467, 4228.23891699497, 9946.154576861049, 10423.530941107669, 10433.21750868688, 10438.0, 10480.266381353385, 9568.378055426376, 9887.406755594688, 10280.897191028143, 10356.741081473023, 10372.948135259427, 7442.776716698508, 7545.811354716176, 7568.3945284378215, 7576.772696819105, 7607.391408687292, 5549.72251387421, 5615.6630602164005, 5672.517835983987, 5691.402402747652, 5769.365369809401, 9025.277977761632, 9162.450252984841, 9192.080791921022, 9208.355415120877, 9253.58358873839, 9241.537923103953, 9324.487153206639, 9605.327627066039, 9736.561854716412, 9740.318177727775, 7607.619619018917, 7706.07527096752, 7784.065912090586, 7798.415118866221, 7802.6488808004915, 7970.521768693897, 7974.481658692166, 8046.557439340892, 8052.315553178046, 8123.147069557847, 7258.927410725825, 7433.553986760811, 7437.51656142349, 7506.624668766649, 7535.058117735283, 7183.281671832775, 7243.927560724536, 7290.48966572356, 7409.222031686811, 7409.851802963803, 6645.767398141042, 6690.531662783753, 6722.899156512558, 6725.495587830835, 6729.327067293231, 4849.80600775965, 4902.338184345068, 4949.6287778416045, 5031.270465782368, 5032.220005899091, 4985.8005679772405, 4989.376327959005, 5028.396592408788, 5070.4929507049, 5091.210862316347], "value": 4333.334336682941, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.0479612840845, 299.5177763800281, 299.87255416448005, 301.82343329152445, 306.5417201284078, 5265.552428043616, 5319.734013299334, 5347.625666203362, 5374.516293533581, 5393.164059570773, 5281.9207711884355, 5294.285271488348, 5295.4969277918535, 5299.54953828925, 5306.389765177006, 3980.422838688381, 4026.255142798577, 4118.052847844998, 4163.5836416358425, 4186.623203911648, 4130.864012396585, 4145.875623731293, 4147.585241475858, 4165.958340416602, 4199.803056129004, 3930.626590473915, 4108.6918481114035, 4128.802647232299, 4133.297339452285, 4145.66834653227, 3959.6040395960463, 3959.6044393142024, 4071.0662435343124, 4162.458880345566, 4167.270727622684, 3654.543182102237, 3667.2665466906724, 3687.2441149564274, 3690.372636651749, 3692.6861216796606, 5138.7173705446185, 5156.0, 5161.948380516222, 5175.689458632494, 5176.275321454999, 4557.293619488984, 4655.3947986761505, 4719.315699223594, 4732.805356384461, 4778.307145463888, 5515.558755299567, 5533.501984821385, 5537.529310008654, 5542.4457554244655, 5579.69311687857, 4030.5969403059457, 4033.9713373089967, 4037.838486460538, 4043.2317859606874, 4051.9464939115746, 5660.5471562274915, 5749.59752817303, 5773.971130144364, 5783.537317014629, 5799.507041901444, 4088.5911408858874, 4098.959010409858, 4101.89745256365, 4122.979385103055, 4123.154753275571, 4147.0, 4150.833966641361, 4164.12553363797, 4169.353750168729, 4169.70739070889, 4566.292224705109, 4620.67655264135, 4710.8351207707565, 4715.477219687451, 4749.168895443284, 5545.611807173459, 5570.498655121059, 5586.748596313176, 5596.2165296858475, 5602.0, 1453.4448140489574, 1457.7886206499998, 1461.4811741831709, 1468.5520916120472, 1469.7874253740706, 946.7349142240183, 958.7890664053897, 990.1237404896718, 992.6079198716467, 1015.4364327798143, 5232.92150617741, 5359.892802143935, 5375.596830237748, 5413.242146099549, 5435.83692489226, 5682.687452190086, 5684.8010319638615, 5690.573207009491, 5735.369109397963, 5740.856478588069, 5222.947770522323, 5380.542653874386, 5429.891402172014, 5451.400345961941, 5497.120460726264, 3243.248645731279, 3255.690709382652, 3261.59230096238, 3270.7546933980047, 3273.541704161419, 6081.543884208702, 6282.293446866371, 6317.241930968313, 6320.367963203735, 6327.462165715966, 6229.853923768468, 6238.34683808791, 6258.653005123078, 6284.0, 6285.842853928597, 6283.748650053946, 6304.905426418607, 6312.147860038836, 6351.364863513704, 6354.205724284465, 5489.505944465017, 5768.365479797303, 5787.421257874179, 5806.535477161901, 6128.908066378919, 5748.281464816898, 5750.7987220447085, 5755.71221438918, 5756.683382414008, 5763.221965034834, 5810.5061069809535, 5917.556183286185, 5957.408527290642, 5995.520358371233, 6003.759849606142, 3999.1801680655976, 4000.879973600797, 4013.6789056874795, 4035.3139966205226, 4062.8171732271526, 5804.506616937439, 5808.970955145197, 5809.36097029318, 5812.814773363171, 5828.0, 5500.394956554695, 5662.292213473316, 5673.319201695823, 5691.288588926384, 5721.05602575587, 5595.0, 5637.661740295595, 5639.295088113986, 5646.588352911772, 5678.0, 5268.81559145428, 5540.695261760642, 5548.639338442988, 5549.750261238173, 5578.940001399721, 5483.341998960151, 5493.8410414011305, 5552.305961754781, 5603.299587551556, 5630.127330263816, 5489.325755644567, 5495.0, 5500.779968801363, 5503.642263252876, 5565.053940829987], "value": 4289.954372729817, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [776.7475570439609, 801.138775815998, 823.4318320358952, 851.1020872979004, 856.9143085691429, 13338.199708017526, 13595.79606305905, 13788.379522921468, 13805.240711760849, 13834.031617786733, 11990.40047997601, 13770.173789572633, 13851.30743462828, 13857.653558661039, 13881.236531990737, 800.3957012455602, 830.4933990265963, 836.1011912194385, 836.861917783565, 837.4054421360811, 814.5927036481759, 819.3568049081472, 824.0893812337371, 824.2005254902765, 824.756696774453, 718.1310614156877, 858.1418581418582, 858.4977787994031, 858.9269912057482, 859.0, 841.4488510025943, 867.6876324523166, 868.4138206710477, 868.6004437958546, 868.7610907000562, 629.0281515059245, 629.3894921925724, 629.5215636116538, 629.9527535434814, 629.987400251997, 12232.694182645417, 12249.816252756127, 12265.938670306681, 12277.20198187115, 12277.447514861853, 14105.083169594047, 14321.570352889326, 14356.784648230285, 14400.927995360062, 14409.639759006004, 11283.717907052389, 11377.658670239905, 11492.655220343404, 11629.883701162964, 11771.411429428494, 3799.625234228607, 3843.404272337792, 3857.787821669807, 3933.8033098345254, 3985.143194213258, 54668.0, 54734.0, 54900.45099548993, 55177.0, 56009.87980240413, 11256.099512039182, 11257.662270131908, 11411.0, 11455.427228638535, 11563.0, 20321.89839050825, 20404.897975510026, 20415.79584204139, 20470.078846451946, 20483.38549843507, 8291.170882911782, 8292.626831792584, 8434.325253979667, 8572.228499435081, 8637.0499245083, 4585.243434833215, 4593.724376537419, 4603.516630753723, 4670.276107203321, 4709.1994360958715, 3400.1669590950373, 3475.721942244614, 3484.8780292689635, 3491.4762785581856, 3513.877014304487, 589.7287247866029, 605.9515238780887, 607.0742118269586, 621.6611946489174, 623.1462895832648, 621.9315875253718, 692.5498426023125, 776.2354081229984, 788.9723859664884, 792.805762588158, 9559.0, 9690.224782017422, 9784.412935223909, 9934.950325248328, 9943.105120539041, 587.5622661117409, 657.1949362031473, 664.8238216872533, 764.3464837563914, 789.9210078992055, 4460.77696115193, 4621.584057434847, 4694.554017368345, 4705.364775755298, 4743.0988112258565, 63552.682236588524, 63814.0, 65021.34978650248, 66652.66673666601, 66764.66617666978, 55147.448525515036, 56195.71902140463, 57159.0, 57781.13328300079, 58895.41104588985, 52132.70142180098, 52179.0, 52387.47612523902, 52813.207801883, 53598.0, 8387.622556984828, 8485.193906579112, 8527.317814574695, 8613.655453782028, 8667.523286219319, 32691.0, 32962.34075318528, 34121.48817767785, 34387.65612343844, 34476.65523344835, 43335.56664433315, 45692.31461528146, 46934.765326173154, 47400.52599473961, 47693.761531192125, 12100.273983560663, 12125.75748485043, 12177.330246836154, 12244.0, 12500.937495312466, 12177.33024683651, 12284.877151228373, 12360.38198090074, 12986.545470908473, 13841.238731869442, 12076.577319793765, 12081.75836483283, 12273.202241854251, 12328.19866708661, 12411.069169812481, 11494.310341379543, 11847.940760296487, 11989.1008174389, 12033.939830301142, 12398.132130751123, 11255.268407553483, 11496.310221386744, 11671.941640291745, 11756.94121529387, 12137.271763694207, 9772.804543909224, 10844.67465976022, 11077.0, 11297.0, 11332.546698132312, 11354.148438867283, 11366.715832104264, 11439.313641181556, 11605.477753500943, 11642.41787910618], "value": 6765.686701691025, "unit": "score"}], "name": "dromaeo_css", "value": 6881.464888865381, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 107.0, 112.0, 114.0, 112.0, 101.0, 112.0, 112.0, 111.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 129.0, 161.0, 181.0, 136.0, 154.0, 158.0, 130.0, 190.0, 129.0], "value": 153.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 161.0, 160.0, 160.0, 161.0, 162.0, 162.0, 161.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 105.0, 115.0, 79.0, 77.0, 78.0, 143.0, 105.0, 78.0], "value": 93.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 151.0, 93.0, 93.0, 93.0, 150.0, 93.0, 91.0, 146.0], "value": 109.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0], "value": 145.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 56.0, 56.0, 56.0, 58.0, 57.0, 61.0, 77.0, 57.0, 54.0], "value": 59.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 63.0, 64.0, 63.0], "value": 63.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 78.0, 77.0, 81.0, 78.0, 78.0, 79.0, 79.0, 79.0], "value": 78.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 123.0, 124.0, 123.0, 123.0, 131.0, 121.0, 134.0, 154.0, 125.0], "value": 128.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 176.0, 171.0, 174.0, 181.0, 176.0, 173.0, 175.0, 172.0, 168.0], "value": 173.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 70.0, 72.0, 70.0, 70.0, 68.0, 68.0, 69.0, 69.0, 70.0], "value": 70.3, "unit": "score"}], "name": "kraken", "value": 1558.5000000000002, "alertThreshold": 2.0}]} 19:27:46 INFO - Return code: 0 19:27:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:27:46 ERROR - # TBPL SUCCESS # 19:27:46 INFO - Running post-action listener: _resource_record_post_action 19:27:46 INFO - Running post-run listener: _resource_record_post_run 19:27:47 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 0; Write bytes: 534515712; Read time: 0; Write time: 942300 19:27:47 INFO - install - Wall time: 17s; CPU: 15.0%; Read bytes: 0; Write bytes: 219627520; Read time: 0; Write time: 423736 19:27:48 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 289611776; Read time: 0; Write time: 511132 19:27:48 INFO - Running post-run listener: _upload_blobber_files 19:27:48 INFO - Blob upload gear active. 19:27:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:27:48 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 19:27:48 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'] 19:27:48 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 19:27:48 INFO - (blobuploader) - INFO - Open directory for files ... 19:27:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:27:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:27:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:27:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:27:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:27:49 INFO - (blobuploader) - INFO - Done attempting. 19:27:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:27:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:27:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:27:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:27:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:27:49 INFO - (blobuploader) - INFO - Done attempting. 19:27:49 INFO - (blobuploader) - INFO - Iteration through files over. 19:27:49 INFO - Return code: 0 19:27:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:27:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:27:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0faadedb65a49a549d38c624101c97515d126a56353824343d202cde4f9bc0409c69797383d856dad5dc9d514f35876d6f1900c2845e37addf9be50b09c7f7dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b2eaf9d5df83ff7485a3d6a03930c2c6dbcfc1ea89b1b47775fdbbab7f248cae156b361e01c0e761a4cb4fa172583edca97f591340ba80882cf16ac18ca7b48d"} 19:27:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:27:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:27:49 INFO - Contents: 19:27:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0faadedb65a49a549d38c624101c97515d126a56353824343d202cde4f9bc0409c69797383d856dad5dc9d514f35876d6f1900c2845e37addf9be50b09c7f7dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b2eaf9d5df83ff7485a3d6a03930c2c6dbcfc1ea89b1b47775fdbbab7f248cae156b361e01c0e761a4cb4fa172583edca97f591340ba80882cf16ac18ca7b48d"} 19:27:49 INFO - Running post-run listener: copy_logs_to_upload_dir 19:27:49 INFO - Copying logs to upload dir... 19:27:49 INFO - mkdir: /builds/slave/test/build/upload/logs 19:27:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.160468 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-04-29 19:27:49.138193) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-29 19:27:49.142303) ========= 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=1861a34ebfea852630dec091000001fa-1461980955.598536-32888003 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/0faadedb65a49a549d38c624101c97515d126a56353824343d202cde4f9bc0409c69797383d856dad5dc9d514f35876d6f1900c2845e37addf9be50b09c7f7dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b2eaf9d5df83ff7485a3d6a03930c2c6dbcfc1ea89b1b47775fdbbab7f248cae156b361e01c0e761a4cb4fa172583edca97f591340ba80882cf16ac18ca7b48d"} build_url:https://queue.taskcluster.net/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011235 build_url: 'https://queue.taskcluster.net/v1/task/Q4i0jQcxSwqZEw1Wqc42Mw/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/0faadedb65a49a549d38c624101c97515d126a56353824343d202cde4f9bc0409c69797383d856dad5dc9d514f35876d6f1900c2845e37addf9be50b09c7f7dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b2eaf9d5df83ff7485a3d6a03930c2c6dbcfc1ea89b1b47775fdbbab7f248cae156b361e01c0e761a4cb4fa172583edca97f591340ba80882cf16ac18ca7b48d"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-29 19:27:49.167473) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 19:27:49.167894) ========= 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=1861a34ebfea852630dec091000001fa-1461980955.598536-32888003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004426 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 19:27:49.250969) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-29 19:27:49.251293) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-29 19:27:49.251600) ========= ========= Total master_lag: 0.15 =========