builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-029 starttime: 1469874467.39 results: success (0) buildid: 20160729170826 builduid: 5bb96b5f7e074e778445d151ad800bd4 revision: 589e1f984537e280ff84591e94afdc3c5bfe2710 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-30 03:27:47.386483) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-30 03:27:47.386948) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-30 03:27:47.387261) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-30 03:27:47.403465) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:27:47.404144) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-30 03:27:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 33.6M=0s 2016-07-30 03:27:48 (33.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.136723 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:27:47.560637) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:27:47.561413) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018972 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:27:47.611711) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-30 03:27:47.612098) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 589e1f984537e280ff84591e94afdc3c5bfe2710 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 589e1f984537e280ff84591e94afdc3c5bfe2710 --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=9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-30 03:27:48,438 truncating revision to first 12 chars 2016-07-30 03:27:48,438 Setting DEBUG logging. 2016-07-30 03:27:48,439 attempt 1/10 2016-07-30 03:27:48,439 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/589e1f984537?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-30 03:27:49,831 unpacking tar archive at: mozilla-aurora-589e1f984537/testing/mozharness/ program finished with exit code 0 elapsedTime=1.753776 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-30 03:27:49.383231) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-30 03:27:49.384058) ========= script_repo_revision: 589e1f984537e280ff84591e94afdc3c5bfe2710 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-30 03:27:49.385062) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-30 03:27:49.385749) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-30 03:27:49.397875) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-07-30 03:27:49.398207) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:27:50 INFO - MultiFileLogger online at 20160730 03:27:50 in /builds/slave/test 03:27:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:27:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:27:50 INFO - {'append_to_log': False, 03:27:50 INFO - 'base_work_dir': '/builds/slave/test', 03:27:50 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:27:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:27:50 INFO - 'branch': 'Mozilla-Aurora', 03:27:50 INFO - 'buildbot_json_path': 'buildprops.json', 03:27:50 INFO - 'config_files': ('talos/linux_config.py',), 03:27:50 INFO - 'default_actions': ('clobber', 03:27:50 INFO - 'read-buildbot-config', 03:27:50 INFO - 'download-and-extract', 03:27:50 INFO - 'populate-webroot', 03:27:50 INFO - 'create-virtualenv', 03:27:50 INFO - 'install', 03:27:50 INFO - 'run-tests'), 03:27:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:27:50 INFO - 'download_minidump_stackwalk': True, 03:27:50 INFO - 'download_symbols': 'ondemand', 03:27:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:27:50 INFO - 'tooltool.py': '/tools/tooltool.py', 03:27:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:27:50 INFO - '/tools/misc-python/virtualenv.py')}, 03:27:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:27:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:27:50 INFO - 'installer_path': 'installer.exe', 03:27:50 INFO - 'log_level': 'info', 03:27:50 INFO - 'log_name': 'talos', 03:27:50 INFO - 'log_to_console': True, 03:27:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:27:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:27:50 INFO - 'opt_config_files': (), 03:27:50 INFO - 'pip_index': False, 03:27:50 INFO - 'sps_profile': False, 03:27:50 INFO - 'sps_profile_interval': 0, 03:27:50 INFO - 'suite': 'dromaeojs-e10s', 03:27:50 INFO - 'system_bits': '32', 03:27:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:27:50 INFO - 'title': 'talos-linux64-ix-029', 03:27:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:27:50 INFO - 'use_talos_json': True, 03:27:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:27:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:27:50 INFO - 'work_dir': 'build'} 03:27:50 INFO - ##### 03:27:50 INFO - ##### Running clobber step. 03:27:50 INFO - ##### 03:27:50 INFO - Running pre-action listener: _resource_record_pre_action 03:27:50 INFO - Running main action method: clobber 03:27:50 INFO - rmtree: /builds/slave/test/build 03:27:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:27:51 INFO - Running post-action listener: _resource_record_post_action 03:27:51 INFO - ##### 03:27:51 INFO - ##### Finished clobber step (success) 03:27:51 INFO - ##### 03:27:51 INFO - ##### 03:27:51 INFO - ##### Running read-buildbot-config step. 03:27:51 INFO - ##### 03:27:51 INFO - Running pre-action listener: _resource_record_pre_action 03:27:51 INFO - Running main action method: read_buildbot_config 03:27:51 INFO - Using buildbot properties: 03:27:51 INFO - { 03:27:51 INFO - "project": "", 03:27:51 INFO - "product": "firefox", 03:27:51 INFO - "script_repo_revision": "production", 03:27:51 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 03:27:51 INFO - "repository": "", 03:27:51 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 03:27:51 INFO - "buildid": "20160729170826", 03:27:51 INFO - "pgo_build": "True", 03:27:51 INFO - "basedir": "/builds/slave/test", 03:27:51 INFO - "buildnumber": 419, 03:27:51 INFO - "slavename": "talos-linux64-ix-029", 03:27:51 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:27:51 INFO - "platform": "ubuntu64_hw", 03:27:51 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:27:51 INFO - "branch": "mozilla-aurora", 03:27:51 INFO - "repo_path": "releases/mozilla-aurora", 03:27:51 INFO - "revision": "589e1f984537e280ff84591e94afdc3c5bfe2710", 03:27:51 INFO - "stage_platform": "linux64-pgo", 03:27:51 INFO - "builduid": "5bb96b5f7e074e778445d151ad800bd4", 03:27:51 INFO - "slavebuilddir": "test" 03:27:51 INFO - } 03:27:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 03:27:51 INFO - Running post-action listener: _resource_record_post_action 03:27:51 INFO - ##### 03:27:51 INFO - ##### Finished read-buildbot-config step (success) 03:27:51 INFO - ##### 03:27:51 INFO - ##### 03:27:51 INFO - ##### Running download-and-extract step. 03:27:51 INFO - ##### 03:27:51 INFO - Running pre-action listener: _resource_record_pre_action 03:27:51 INFO - Running main action method: download_and_extract 03:27:51 INFO - mkdir: /builds/slave/test/build/tests 03:27: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')]} 03:27:51 INFO - https://queue.taskcluster.net/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:27:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:27:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:27:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:27:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:27:51 INFO - Downloaded 1361 bytes. 03:27:51 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 03:27:51 INFO - Using the following test package requirements: 03:27:51 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:27:51 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:27:51 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:27:51 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:27:51 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:27:51 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:27:51 INFO - u'jsshell-linux-x86_64.zip'], 03:27:51 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:27:51 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:27:51 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 03:27:51 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:27:51 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:27:51 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:27:51 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:27:51 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:27:51 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:27:51 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 03:27:51 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:27:51 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:27: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')]} 03:27:51 INFO - https://queue.taskcluster.net/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:27:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:27:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:27:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:27:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:27:52 INFO - Downloaded 24496939 bytes. 03:27:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:27:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:27:54 INFO - Return code: 0 03:27:54 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:27:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:27:54 INFO - https://queue.taskcluster.net/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:27:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:27:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:27:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 03:27:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:27:55 INFO - Downloaded 24496939 bytes. 03:27:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:27:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:27:56 INFO - Return code: 0 03:27:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:27:56 INFO - https://queue.taskcluster.net/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:27:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:27:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:27:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 03:27:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:27:57 INFO - Downloaded 10833369 bytes. 03:27:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:27:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 03:27:57 INFO - Return code: 0 03:27:57 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')]} 03:27:57 INFO - https://queue.taskcluster.net/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:27:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:27:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:27:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:27:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:27:58 INFO - Downloaded 63648271 bytes. 03:27:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:27:58 INFO - mkdir: /builds/slave/test/properties 03:27:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:27:58 INFO - Writing to file /builds/slave/test/properties/build_url 03:27:58 INFO - Contents: 03:27:58 INFO - build_url:https://queue.taskcluster.net/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 03:27:59 INFO - Running post-action listener: _resource_record_post_action 03:27:59 INFO - Running post-action listener: set_extra_try_arguments 03:27:59 INFO - ##### 03:27:59 INFO - ##### Finished download-and-extract step (success) 03:27:59 INFO - ##### 03:27:59 INFO - ##### 03:27:59 INFO - ##### Running populate-webroot step. 03:27:59 INFO - ##### 03:27:59 INFO - Running pre-action listener: _resource_record_pre_action 03:27:59 INFO - Running main action method: populate_webroot 03:27:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:27:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:27:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:27:59 INFO - '--tppagecycles', 03:27:59 INFO - '7'], 03:27:59 INFO - 'tests': ['tsvgm']}}, 03:27:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:27:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:27:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:27:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:27:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:27:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:27:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:27:59 INFO - 'talos_options': ['--disable-e10s'], 03:27:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:27:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:27:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:27:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:27:59 INFO - 'talos_options': ['--disable-e10s'], 03:27:59 INFO - 'tests': ['damp', 'tps']}, 03:27:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:27:59 INFO - 'tests': ['damp', 'tps']}, 03:27:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:27:59 INFO - 'tests': ['dromaeo_dom']}, 03:27:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:27:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:27:59 INFO - 'tests': ['a11yr', 03:27:59 INFO - 'ts_paint', 03:27:59 INFO - 'tpaint', 03:27:59 INFO - 'sessionrestore', 03:27:59 INFO - 'sessionrestore_no_auto_restore', 03:27:59 INFO - 'tabpaint']}, 03:27:59 INFO - 'other-e10s': {'tests': ['a11yr', 03:27:59 INFO - 'ts_paint', 03:27:59 INFO - 'tpaint', 03:27:59 INFO - 'sessionrestore', 03:27:59 INFO - 'sessionrestore_no_auto_restore', 03:27:59 INFO - 'tabpaint']}, 03:27:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:27:59 INFO - 'ts_paint', 03:27:59 INFO - 'tpaint', 03:27:59 INFO - 'sessionrestore', 03:27:59 INFO - 'sessionrestore_no_auto_restore', 03:27:59 INFO - 'tabpaint']}, 03:27:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:27:59 INFO - 'ts_paint', 03:27:59 INFO - 'tpaint', 03:27:59 INFO - 'sessionrestore', 03:27:59 INFO - 'sessionrestore_no_auto_restore', 03:27:59 INFO - 'tabpaint']}, 03:27:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:27:59 INFO - 'tests': ['a11yr', 03:27:59 INFO - 'ts_paint', 03:27:59 INFO - 'tpaint', 03:27:59 INFO - 'sessionrestore', 03:27:59 INFO - 'sessionrestore_no_auto_restore', 03:27:59 INFO - 'tabpaint']}, 03:27:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:27:59 INFO - 'tests': ['a11yr', 03:27:59 INFO - 'ts_paint', 03:27:59 INFO - 'tpaint', 03:27:59 INFO - 'sessionrestore', 03:27:59 INFO - 'sessionrestore_no_auto_restore', 03:27:59 INFO - 'tabpaint']}, 03:27:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:27:59 INFO - 'tests': ['tsvgx', 03:27:59 INFO - 'tsvgr_opacity', 03:27:59 INFO - 'tart', 03:27:59 INFO - 'tscrollx', 03:27:59 INFO - 'cart']}, 03:27:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:27:59 INFO - 'tsvgr_opacity', 03:27:59 INFO - 'tart', 03:27:59 INFO - 'tscrollx', 03:27:59 INFO - 'cart']}, 03:27:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:27:59 INFO - 'talos_options': ['--disable-e10s'], 03:27:59 INFO - 'tests': ['tp5o']}, 03:27:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:27:59 INFO - 'tests': ['tp5o']}, 03:27:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:27:59 INFO - 'talos_options': ['--disable-e10s', 03:27:59 INFO - '--xperf_path', 03:27:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:27:59 INFO - 'tests': ['tp5n']}, 03:27:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:27:59 INFO - 'talos_options': ['--xperf_path', 03:27:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:27:59 INFO - 'tests': ['tp5n']}}, 03:27:59 INFO - 'talos.zip': {'path': '', 03:27:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:27:59 INFO - Running post-action listener: _resource_record_post_action 03:27:59 INFO - ##### 03:27:59 INFO - ##### Finished populate-webroot step (success) 03:27:59 INFO - ##### 03:27:59 INFO - ##### 03:27:59 INFO - ##### Running create-virtualenv step. 03:27:59 INFO - ##### 03:27:59 INFO - Running pre-action listener: _resource_record_pre_action 03:27:59 INFO - Running main action method: create_virtualenv 03:27:59 INFO - Creating virtualenv /builds/slave/test/build/venv 03:27:59 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:27:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:27:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:27:59 INFO - Using real prefix '/usr' 03:27:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:28:00 INFO - Installing distribute.............................................................................................................................................................................................done. 03:28:02 INFO - Installing pip.................done. 03:28:02 INFO - Return code: 0 03:28:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:28:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:28:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3556a55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204e1c0>, '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 0x204f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204f550>, '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': '9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258', '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 03:28:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:28:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:28:02 INFO - Using env: {'DISPLAY': ':0', 03:28:02 INFO - 'HOME': '/home/cltbld', 03:28:02 INFO - 'LANG': 'en_US.UTF-8', 03:28:02 INFO - 'LANGUAGE': 'en_US:en', 03:28:02 INFO - 'LOGNAME': 'cltbld', 03:28:02 INFO - 'MAIL': '/var/mail/cltbld', 03:28:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:02 INFO - 'MOZ_NO_REMOTE': '1', 03:28:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:28:02 INFO - 'NO_EM_RESTART': '1', 03:28:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:28:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:02 INFO - 'PWD': '/builds/slave/test', 03:28:02 INFO - 'SHELL': '/bin/bash', 03:28:02 INFO - 'SHLVL': '1', 03:28:02 INFO - 'TERM': 'linux', 03:28:02 INFO - 'TMOUT': '86400', 03:28:02 INFO - 'USER': 'cltbld', 03:28:02 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258', 03:28:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:02 INFO - '_': '/tools/buildbot/bin/python'} 03:28:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:02 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:28:02 INFO - Cleaning up... 03:28:02 INFO - Return code: 0 03:28:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:28:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:28:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3556a55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204e1c0>, '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 0x204f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204f550>, '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': '9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258', '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 03:28:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:28:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:28:02 INFO - Using env: {'DISPLAY': ':0', 03:28:02 INFO - 'HOME': '/home/cltbld', 03:28:02 INFO - 'LANG': 'en_US.UTF-8', 03:28:02 INFO - 'LANGUAGE': 'en_US:en', 03:28:02 INFO - 'LOGNAME': 'cltbld', 03:28:02 INFO - 'MAIL': '/var/mail/cltbld', 03:28:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:02 INFO - 'MOZ_NO_REMOTE': '1', 03:28:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:28:02 INFO - 'NO_EM_RESTART': '1', 03:28:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:28:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:02 INFO - 'PWD': '/builds/slave/test', 03:28:02 INFO - 'SHELL': '/bin/bash', 03:28:02 INFO - 'SHLVL': '1', 03:28:02 INFO - 'TERM': 'linux', 03:28:02 INFO - 'TMOUT': '86400', 03:28:02 INFO - 'USER': 'cltbld', 03:28:02 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258', 03:28:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:02 INFO - '_': '/tools/buildbot/bin/python'} 03:28:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:02 INFO - Downloading/unpacking psutil>=3.1.1 03:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:28:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:28:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:28:04 INFO - Installing collected packages: psutil 03:28:04 INFO - Running setup.py install for psutil 03:28:04 INFO - building 'psutil._psutil_linux' extension 03:28:04 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:28:04 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:28:04 INFO - building 'psutil._psutil_posix' extension 03:28:04 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:28:05 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 03:28:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:28:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:28:05 INFO - Successfully installed psutil 03:28:05 INFO - Cleaning up... 03:28:05 INFO - Return code: 0 03:28:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:28:05 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')]} 03:28:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:05 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')]} 03:28:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:05 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3556a55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204e1c0>, '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 0x204f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204f550>, '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': '9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258', '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 03:28:05 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.3'] in /builds/slave/test/build 03:28:05 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.3 03:28:05 INFO - Using env: {'DISPLAY': ':0', 03:28:05 INFO - 'HOME': '/home/cltbld', 03:28:05 INFO - 'LANG': 'en_US.UTF-8', 03:28:05 INFO - 'LANGUAGE': 'en_US:en', 03:28:05 INFO - 'LOGNAME': 'cltbld', 03:28:05 INFO - 'MAIL': '/var/mail/cltbld', 03:28:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:05 INFO - 'MOZ_NO_REMOTE': '1', 03:28:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:28:05 INFO - 'NO_EM_RESTART': '1', 03:28:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:28:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:05 INFO - 'PWD': '/builds/slave/test', 03:28:05 INFO - 'SHELL': '/bin/bash', 03:28:05 INFO - 'SHLVL': '1', 03:28:05 INFO - 'TERM': 'linux', 03:28:05 INFO - 'TMOUT': '86400', 03:28:05 INFO - 'USER': 'cltbld', 03:28:05 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258', 03:28:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:05 INFO - '_': '/tools/buildbot/bin/python'} 03:28:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:28:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:28:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:28:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:28:07 INFO - Installing collected packages: mozsystemmonitor 03:28:07 INFO - Running setup.py install for mozsystemmonitor 03:28:07 INFO - Successfully installed mozsystemmonitor 03:28:07 INFO - Cleaning up... 03:28:07 INFO - Return code: 0 03:28:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:28:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:28:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3556a55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204e1c0>, '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 0x204f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204f550>, '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': '9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258', '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 03:28:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:28:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:28:07 INFO - Using env: {'DISPLAY': ':0', 03:28:07 INFO - 'HOME': '/home/cltbld', 03:28:07 INFO - 'LANG': 'en_US.UTF-8', 03:28:07 INFO - 'LANGUAGE': 'en_US:en', 03:28:07 INFO - 'LOGNAME': 'cltbld', 03:28:07 INFO - 'MAIL': '/var/mail/cltbld', 03:28:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:07 INFO - 'MOZ_NO_REMOTE': '1', 03:28:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:28:07 INFO - 'NO_EM_RESTART': '1', 03:28:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:28:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:07 INFO - 'PWD': '/builds/slave/test', 03:28:07 INFO - 'SHELL': '/bin/bash', 03:28:07 INFO - 'SHLVL': '1', 03:28:07 INFO - 'TERM': 'linux', 03:28:07 INFO - 'TMOUT': '86400', 03:28:07 INFO - 'USER': 'cltbld', 03:28:07 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258', 03:28:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:07 INFO - '_': '/tools/buildbot/bin/python'} 03:28:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:07 INFO - Downloading/unpacking jsonschema==2.5.1 03:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:28:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:09 INFO - Downloading functools32-3.2.3-2.tar.gz 03:28:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:28:09 INFO - warning: no files found matching '*.txt' 03:28:09 INFO - no previously-included directories found matching 'build' 03:28:09 INFO - no previously-included directories found matching 'dist' 03:28:09 INFO - no previously-included directories found matching '.git*' 03:28:09 INFO - Installing collected packages: jsonschema, functools32 03:28:09 INFO - Running setup.py install for functools32 03:28:09 INFO - warning: no files found matching '*.txt' 03:28:09 INFO - no previously-included directories found matching 'build' 03:28:09 INFO - no previously-included directories found matching 'dist' 03:28:09 INFO - no previously-included directories found matching '.git*' 03:28:09 INFO - Successfully installed jsonschema functools32 03:28:09 INFO - Cleaning up... 03:28:09 INFO - Return code: 0 03:28:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:28:09 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')]} 03:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:28:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:10 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 0x7f3556a55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204e1c0>, '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 0x204f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204f550>, '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': '9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258', '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 03:28:10 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 03:28:10 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 03:28:10 INFO - Using env: {'DISPLAY': ':0', 03:28:10 INFO - 'HOME': '/home/cltbld', 03:28:10 INFO - 'LANG': 'en_US.UTF-8', 03:28:10 INFO - 'LANGUAGE': 'en_US:en', 03:28:10 INFO - 'LOGNAME': 'cltbld', 03:28:10 INFO - 'MAIL': '/var/mail/cltbld', 03:28:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:10 INFO - 'MOZ_NO_REMOTE': '1', 03:28:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:28:10 INFO - 'NO_EM_RESTART': '1', 03:28:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:28:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:10 INFO - 'PWD': '/builds/slave/test', 03:28:10 INFO - 'SHELL': '/bin/bash', 03:28:10 INFO - 'SHLVL': '1', 03:28:10 INFO - 'TERM': 'linux', 03:28:10 INFO - 'TMOUT': '86400', 03:28:10 INFO - 'USER': 'cltbld', 03:28:10 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258', 03:28:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:10 INFO - '_': '/tools/buildbot/bin/python'} 03:28:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:10 INFO - Downloading/unpacking blobuploader==1.2.4 03:28:10 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 03:28:10 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 03:28:10 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 03:28:10 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 03:28:10 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 03:28:10 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 03:28:12 INFO - Downloading blobuploader-1.2.4.tar.gz 03:28:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:28:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:28:12 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 03:28:12 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 03:28:12 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 03:28:12 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 03:28:12 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 03:28:12 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 03:28:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:28:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:28:12 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 03:28:12 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 03:28:12 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 03:28:12 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 03:28:12 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 03:28:12 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 03:28:12 INFO - Downloading docopt-0.6.1.tar.gz 03:28:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:28:12 INFO - Installing collected packages: blobuploader, requests, docopt 03:28:12 INFO - Running setup.py install for blobuploader 03:28:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:28:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:28:12 INFO - Running setup.py install for requests 03:28:13 INFO - Running setup.py install for docopt 03:28:13 INFO - Successfully installed blobuploader requests docopt 03:28:13 INFO - Cleaning up... 03:28:13 INFO - Return code: 0 03:28:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:28:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:28:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:28:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:13 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 0x7f3556a55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204e1c0>, '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 0x204f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204f550>, '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': '9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258', '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 03:28:13 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 03:28:13 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 03:28:13 INFO - Using env: {'DISPLAY': ':0', 03:28:13 INFO - 'HOME': '/home/cltbld', 03:28:13 INFO - 'LANG': 'en_US.UTF-8', 03:28:13 INFO - 'LANGUAGE': 'en_US:en', 03:28:13 INFO - 'LOGNAME': 'cltbld', 03:28:13 INFO - 'MAIL': '/var/mail/cltbld', 03:28:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:13 INFO - 'MOZ_NO_REMOTE': '1', 03:28:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:28:13 INFO - 'NO_EM_RESTART': '1', 03:28:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:28:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:13 INFO - 'PWD': '/builds/slave/test', 03:28:13 INFO - 'SHELL': '/bin/bash', 03:28:13 INFO - 'SHLVL': '1', 03:28:13 INFO - 'TERM': 'linux', 03:28:13 INFO - 'TMOUT': '86400', 03:28:13 INFO - 'USER': 'cltbld', 03:28:13 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258', 03:28:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:13 INFO - '_': '/tools/buildbot/bin/python'} 03:28:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:28:13 INFO - Running setup.py (path:/tmp/pip-UdOv2k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:28:13 INFO - Running setup.py (path:/tmp/pip-MkX3JB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:28:13 INFO - Running setup.py (path:/tmp/pip-AfaqRn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:28:13 INFO - Running setup.py (path:/tmp/pip-mKf_V2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:28:13 INFO - Running setup.py (path:/tmp/pip-3skmfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:28:14 INFO - Running setup.py (path:/tmp/pip-Uuh5WS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:28:14 INFO - Running setup.py (path:/tmp/pip-imdWWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:28:14 INFO - Running setup.py (path:/tmp/pip-mxUf1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:28:14 INFO - Running setup.py (path:/tmp/pip-g1lkl6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:28:14 INFO - Running setup.py (path:/tmp/pip-J5OStF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:28:14 INFO - Running setup.py (path:/tmp/pip-FglhOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:28:14 INFO - Running setup.py (path:/tmp/pip-eZhFr0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:28:14 INFO - Running setup.py (path:/tmp/pip-eHzR_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:28:14 INFO - Running setup.py (path:/tmp/pip-Isl6wX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:28:14 INFO - Running setup.py (path:/tmp/pip-OBXZeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:28:14 INFO - Running setup.py (path:/tmp/pip-fKAlY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:28:14 INFO - Running setup.py (path:/tmp/pip-IqKU9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:28:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:28:14 INFO - Running setup.py install for manifestparser 03:28:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:28:15 INFO - Running setup.py install for mozcrash 03:28:15 INFO - Running setup.py install for mozdebug 03:28:15 INFO - Running setup.py install for mozdevice 03:28:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:28:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:28:15 INFO - Running setup.py install for mozfile 03:28:15 INFO - Running setup.py install for mozhttpd 03:28:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:28:15 INFO - Running setup.py install for mozinfo 03:28:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:28:15 INFO - Running setup.py install for mozInstall 03:28:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:28:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:28:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:28:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:28:15 INFO - Running setup.py install for mozleak 03:28:15 INFO - Running setup.py install for mozlog 03:28:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:28:15 INFO - Running setup.py install for moznetwork 03:28:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:28:16 INFO - Running setup.py install for mozprocess 03:28:16 INFO - Running setup.py install for mozprofile 03:28:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:28:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:28:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:28:16 INFO - Running setup.py install for mozrunner 03:28:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:28:16 INFO - Running setup.py install for mozscreenshot 03:28:16 INFO - Running setup.py install for moztest 03:28:16 INFO - Running setup.py install for mozversion 03:28:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:28:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:28:16 INFO - Cleaning up... 03:28:16 INFO - Return code: 0 03:28:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:28:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:28:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:28:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:16 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 0x7f3556a55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204e1c0>, '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 0x204f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204f550>, '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': '9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258', '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 03:28:16 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 03:28:16 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 03:28:16 INFO - Using env: {'DISPLAY': ':0', 03:28:16 INFO - 'HOME': '/home/cltbld', 03:28:16 INFO - 'LANG': 'en_US.UTF-8', 03:28:16 INFO - 'LANGUAGE': 'en_US:en', 03:28:16 INFO - 'LOGNAME': 'cltbld', 03:28:16 INFO - 'MAIL': '/var/mail/cltbld', 03:28:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:16 INFO - 'MOZ_NO_REMOTE': '1', 03:28:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:28:16 INFO - 'NO_EM_RESTART': '1', 03:28:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:28:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:16 INFO - 'PWD': '/builds/slave/test', 03:28:16 INFO - 'SHELL': '/bin/bash', 03:28:16 INFO - 'SHLVL': '1', 03:28:16 INFO - 'TERM': 'linux', 03:28:16 INFO - 'TMOUT': '86400', 03:28:16 INFO - 'USER': 'cltbld', 03:28:16 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258', 03:28:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:16 INFO - '_': '/tools/buildbot/bin/python'} 03:28:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:28:16 INFO - Running setup.py (path:/tmp/pip-EG6EO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:28:16 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)) 03:28:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:28:16 INFO - Running setup.py (path:/tmp/pip-Y_jcdo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:28:17 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)) 03:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:28:17 INFO - Running setup.py (path:/tmp/pip-bQBTHP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:28:17 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)) 03:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:28:17 INFO - Running setup.py (path:/tmp/pip-WWW3up-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:28:17 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)) 03:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:28:17 INFO - Running setup.py (path:/tmp/pip-WO5IXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:28:17 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)) 03:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:28:17 INFO - Running setup.py (path:/tmp/pip-L2DiEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:28:17 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)) 03:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:28:17 INFO - Running setup.py (path:/tmp/pip-aO8HpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:28:17 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)) 03:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:28:17 INFO - Running setup.py (path:/tmp/pip-4T1U8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:28:17 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)) 03:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:28:17 INFO - Running setup.py (path:/tmp/pip-FrlXq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:28:17 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)) 03:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:28:17 INFO - Running setup.py (path:/tmp/pip-XC29sc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:28:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 03:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:28:17 INFO - Running setup.py (path:/tmp/pip-Ff3NeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:28:17 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)) 03:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:28:17 INFO - Running setup.py (path:/tmp/pip-ExAa11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:28:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 03:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:28:17 INFO - Running setup.py (path:/tmp/pip-KpUQol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:28:17 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)) 03:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:28:17 INFO - Running setup.py (path:/tmp/pip-iIUnBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:28:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 03:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:28:17 INFO - Running setup.py (path:/tmp/pip-DsNNVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:28:18 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)) 03:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:28:18 INFO - Running setup.py (path:/tmp/pip-ji97o6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:28:18 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)) 03:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:28:18 INFO - Running setup.py (path:/tmp/pip-kZhQDB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:28:18 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)) 03:28:18 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)) 03:28:18 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)) 03:28:18 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)) 03:28:18 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)) 03:28:18 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)) 03:28:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:28:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:20 INFO - Downloading blessings-1.6.tar.gz 03:28:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:28:20 INFO - Installing collected packages: blessings 03:28:20 INFO - Running setup.py install for blessings 03:28:20 INFO - Successfully installed blessings 03:28:20 INFO - Cleaning up... 03:28:20 INFO - Return code: 0 03:28:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:28:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:28:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:28:20 INFO - Reading from file tmpfile_stdout 03:28:20 INFO - Current package versions: 03:28:20 INFO - argparse == 1.2.1 03:28:20 INFO - blessings == 1.6 03:28:20 INFO - blobuploader == 1.2.4 03:28:20 INFO - docopt == 0.6.1 03:28:20 INFO - functools32 == 3.2.3-2 03:28:20 INFO - jsonschema == 2.5.1 03:28:20 INFO - manifestparser == 1.1 03:28:20 INFO - mozInstall == 1.12 03:28:20 INFO - mozcrash == 0.17 03:28:20 INFO - mozdebug == 0.1 03:28:20 INFO - mozdevice == 0.48 03:28:20 INFO - mozfile == 1.2 03:28:20 INFO - mozhttpd == 0.7 03:28:20 INFO - mozinfo == 0.9 03:28:20 INFO - mozleak == 0.1 03:28:20 INFO - mozlog == 3.2 03:28:20 INFO - moznetwork == 0.27 03:28:20 INFO - mozprocess == 0.23 03:28:20 INFO - mozprofile == 0.28 03:28:20 INFO - mozrunner == 6.12 03:28:20 INFO - mozscreenshot == 0.1 03:28:20 INFO - mozsystemmonitor == 0.3 03:28:20 INFO - moztest == 0.7 03:28:20 INFO - mozversion == 1.4 03:28:20 INFO - psutil == 3.1.1 03:28:20 INFO - requests == 1.2.3 03:28:20 INFO - wsgiref == 0.1.2 03:28:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:28: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')]} 03:28:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28: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')]} 03:28:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:20 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 0x7f3556a55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204e1c0>, '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 0x204f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204f550>, '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': '9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258', '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 03:28:20 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 03:28:20 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 03:28:20 INFO - Using env: {'DISPLAY': ':0', 03:28:20 INFO - 'HOME': '/home/cltbld', 03:28:20 INFO - 'LANG': 'en_US.UTF-8', 03:28:20 INFO - 'LANGUAGE': 'en_US:en', 03:28:20 INFO - 'LOGNAME': 'cltbld', 03:28:20 INFO - 'MAIL': '/var/mail/cltbld', 03:28:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:20 INFO - 'MOZ_NO_REMOTE': '1', 03:28:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:28:20 INFO - 'NO_EM_RESTART': '1', 03:28:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:28:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:20 INFO - 'PWD': '/builds/slave/test', 03:28:20 INFO - 'SHELL': '/bin/bash', 03:28:20 INFO - 'SHLVL': '1', 03:28:20 INFO - 'TERM': 'linux', 03:28:20 INFO - 'TMOUT': '86400', 03:28:20 INFO - 'USER': 'cltbld', 03:28:20 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258', 03:28:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:20 INFO - '_': '/tools/buildbot/bin/python'} 03:28:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:20 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)) 03:28:20 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)) 03:28:20 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)) 03:28:20 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)) 03:28:20 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)) 03:28:20 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)) 03:28:20 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)) 03:28:20 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)) 03:28:20 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)) 03:28:20 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)) 03:28:20 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)) 03:28:20 INFO - Cleaning up... 03:28:20 INFO - Return code: 0 03:28:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:28: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')]} 03:28:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28: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')]} 03:28:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3556a55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204e1c0>, '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 0x204f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204f550>, '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': '9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258', '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 03:28:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:28:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:28:20 INFO - Using env: {'DISPLAY': ':0', 03:28:20 INFO - 'HOME': '/home/cltbld', 03:28:20 INFO - 'LANG': 'en_US.UTF-8', 03:28:20 INFO - 'LANGUAGE': 'en_US:en', 03:28:20 INFO - 'LOGNAME': 'cltbld', 03:28:20 INFO - 'MAIL': '/var/mail/cltbld', 03:28:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:20 INFO - 'MOZ_NO_REMOTE': '1', 03:28:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:28:20 INFO - 'NO_EM_RESTART': '1', 03:28:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:28:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:20 INFO - 'PWD': '/builds/slave/test', 03:28:20 INFO - 'SHELL': '/bin/bash', 03:28:20 INFO - 'SHLVL': '1', 03:28:20 INFO - 'TERM': 'linux', 03:28:20 INFO - 'TMOUT': '86400', 03:28:20 INFO - 'USER': 'cltbld', 03:28:20 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258', 03:28:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:20 INFO - '_': '/tools/buildbot/bin/python'} 03:28:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:28:20 INFO - Cleaning up... 03:28:20 INFO - Return code: 0 03:28:20 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:28: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')]} 03:28:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:28:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28: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')]} 03:28:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:28:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:28:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f3556a55f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ef48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fbf350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204e1c0>, '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 0x204f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204f550>, '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': '9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258', '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 03:28:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 03:28:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 03:28:20 INFO - Using env: {'DISPLAY': ':0', 03:28:20 INFO - 'HOME': '/home/cltbld', 03:28:20 INFO - 'LANG': 'en_US.UTF-8', 03:28:20 INFO - 'LANGUAGE': 'en_US:en', 03:28:20 INFO - 'LOGNAME': 'cltbld', 03:28:20 INFO - 'MAIL': '/var/mail/cltbld', 03:28:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:20 INFO - 'MOZ_NO_REMOTE': '1', 03:28:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:28:20 INFO - 'NO_EM_RESTART': '1', 03:28:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:28:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:20 INFO - 'PWD': '/builds/slave/test', 03:28:20 INFO - 'SHELL': '/bin/bash', 03:28:20 INFO - 'SHLVL': '1', 03:28:20 INFO - 'TERM': 'linux', 03:28:20 INFO - 'TMOUT': '86400', 03:28:20 INFO - 'USER': 'cltbld', 03:28:20 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258', 03:28:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:20 INFO - '_': '/tools/buildbot/bin/python'} 03:28:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:28:21 INFO - Downloading/unpacking flake8 03:28:21 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 03:28:21 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 03:28:21 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 03:28:21 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 03:28:21 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 03:28:21 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 03:28:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:28:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:28:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:23 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:28:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:28:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:23 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:28:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:28:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:28:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:28:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:28:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:28:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:28:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:28:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:28:23 INFO - no previously-included directories found matching 'docs/_build' 03:28:23 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:28:23 INFO - Running setup.py install for pep8 03:28:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:28:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:28:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:28:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:28:23 INFO - no previously-included directories found matching 'docs/_build' 03:28:23 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:28:23 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:28:23 INFO - Cleaning up... 03:28:24 INFO - Return code: 0 03:28:24 INFO - Running post-action listener: _resource_record_post_action 03:28:24 INFO - Running post-action listener: _start_resource_monitoring 03:28:24 INFO - Starting resource monitoring. 03:28:24 INFO - ##### 03:28:24 INFO - ##### Finished create-virtualenv step (success) 03:28:24 INFO - ##### 03:28:24 INFO - ##### 03:28:24 INFO - ##### Running install step. 03:28:24 INFO - ##### 03:28:24 INFO - Running pre-action listener: _resource_record_pre_action 03:28:24 INFO - Running main action method: install 03:28:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:28:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:28:24 INFO - Reading from file tmpfile_stdout 03:28:24 INFO - Detecting whether we're running mozinstall >=1.0... 03:28:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:28:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:28:24 INFO - Reading from file tmpfile_stdout 03:28:24 INFO - Output received: 03:28:24 INFO - Usage: mozinstall [options] installer 03:28:24 INFO - Options: 03:28:24 INFO - -h, --help show this help message and exit 03:28:24 INFO - -d DEST, --destination=DEST 03:28:24 INFO - Directory to install application into. [default: 03:28:24 INFO - "/builds/slave/test"] 03:28:24 INFO - --app=APP Application being installed. [default: firefox] 03:28:24 INFO - mkdir: /builds/slave/test/build/application 03:28:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:28:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:28:40 INFO - Reading from file tmpfile_stdout 03:28:40 INFO - Output received: 03:28:40 INFO - /builds/slave/test/build/application/firefox/firefox 03:28:40 INFO - Running post-action listener: _resource_record_post_action 03:28:40 INFO - ##### 03:28:40 INFO - ##### Finished install step (success) 03:28:40 INFO - ##### 03:28:40 INFO - ##### 03:28:40 INFO - ##### Running run-tests step. 03:28:40 INFO - ##### 03:28:40 INFO - Running pre-action listener: _resource_record_pre_action 03:28:40 INFO - Running main action method: run_tests 03:28:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:28:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:28:40 INFO - Python 2.7.3 03:28:40 INFO - Return code: 0 03:28:40 INFO - grabbing minidump binary from tooltool 03:28:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:28:40 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x204e1c0>, '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 0x204f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204f550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:28:40 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 03:28:40 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 03:28:40 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:28:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:28:40 INFO - Return code: 0 03:28:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:28:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:28:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:28:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:28:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:28:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:28:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:28:41 INFO - Return code: 0 03:28:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:28:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-029 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:28:41 INFO - Using env: {'DISPLAY': ':0', 03:28:41 INFO - 'HOME': '/home/cltbld', 03:28:41 INFO - 'LANG': 'en_US.UTF-8', 03:28:41 INFO - 'LANGUAGE': 'en_US:en', 03:28:41 INFO - 'LOGNAME': 'cltbld', 03:28:41 INFO - 'MAIL': '/var/mail/cltbld', 03:28:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:28:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:28:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:28:41 INFO - 'MOZ_NO_REMOTE': '1', 03:28:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:28:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:28:41 INFO - 'NO_EM_RESTART': '1', 03:28:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:28:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:28:41 INFO - 'PWD': '/builds/slave/test', 03:28:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:28:41 INFO - 'SHELL': '/bin/bash', 03:28:41 INFO - 'SHLVL': '1', 03:28:41 INFO - 'TERM': 'linux', 03:28:41 INFO - 'TMOUT': '86400', 03:28:41 INFO - 'USER': 'cltbld', 03:28:41 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258', 03:28:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:28:41 INFO - '_': '/tools/buildbot/bin/python'} 03:28:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:28:41 INFO - mozversion application_buildid: 20160729170826 03:28:41 INFO - mozversion application_changeset: 589e1f984537e280ff84591e94afdc3c5bfe2710 03:28:41 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:28:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:28:41 INFO - mozversion application_name: Firefox 03:28:41 INFO - mozversion application_remotingname: firefox-dev 03:28:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:28:41 INFO - mozversion application_vendor: Mozilla 03:28:41 INFO - mozversion application_version: 49.0a2 03:28:41 INFO - mozversion platform_buildid: 20160729170826 03:28:41 INFO - mozversion platform_changeset: 589e1f984537e280ff84591e94afdc3c5bfe2710 03:28:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:28:41 INFO - mozversion platform_version: 49.0a2 03:28:41 INFO - using testdate: 1469874521 03:28:41 INFO - actual date: 1469874521 03:28:41 INFO - starting webserver on 'localhost:47885' 03:28:41 INFO - SUITE-START | Running 2 tests 03:28:41 INFO - TEST-START | dromaeo_css 03:28:41 INFO - operating with platform_type : linux_ 03:28:41 INFO - Initialising browser for dromaeo_css test... 03:28:41 INFO - TEST-INFO | started process 28140 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiWbXz9/profile http://localhost:47885/getInfo.html) 03:28:46 INFO - PROCESS | 28140 | __metrics Screen width/height:1600/1200 03:28:46 INFO - PROCESS | 28140 | colorDepth:24 03:28:46 INFO - PROCESS | 28140 | Browser inner width/height: 1024/697 03:28:46 INFO - PROCESS | 28140 | __metrics 03:28:46 ERROR - PROCESS | 28140 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2947: TypeError: this.tabs is undefined 03:28:47 INFO - TEST-INFO | 28140: exit 0 03:28:47 INFO - Browser initialized. 03:28:47 INFO - Running cycle 1/1 for dromaeo_css test... 03:28:47 INFO - TEST-INFO | started process 28271 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiWbXz9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:28:59 INFO - PROCESS | 28271 | RSS: Main: 155828224 03:28:59 INFO - PROCESS | 28271 | 03:29:49 INFO - PROCESS | 28271 | 1469874589109 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] 03:31:56 INFO - PROCESS | 28271 | Cycle 1(1): loaded http://localhost:47885/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47885/tests/dromaeo/cssquery-ext.html) 03:31:57 INFO - PROCESS | 28271 | RSS: Main: 159850496 03:31:57 INFO - PROCESS | 28271 | 03:34:59 INFO - PROCESS | 28271 | Cycle 1(1): loaded http://localhost:47885/tests/dromaeo/cssquery-ext.html (next: http://localhost:47885/tests/dromaeo/cssquery-jquery.html) 03:35:00 INFO - PROCESS | 28271 | RSS: Main: 157155328 03:35:00 INFO - PROCESS | 28271 | 03:38:02 INFO - PROCESS | 28271 | Cycle 1(1): loaded http://localhost:47885/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47885/tests/dromaeo/cssquery-mootools.html) 03:38:02 INFO - PROCESS | 28271 | RSS: Main: 156753920 03:38:02 INFO - PROCESS | 28271 | 03:41:04 INFO - PROCESS | 28271 | Cycle 1(1): loaded http://localhost:47885/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47885/tests/dromaeo/cssquery-prototype.html) 03:41:05 INFO - PROCESS | 28271 | RSS: Main: 156237824 03:41:05 INFO - PROCESS | 28271 | 03:44:07 INFO - PROCESS | 28271 | Cycle 1(1): loaded http://localhost:47885/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47885/tests/dromaeo/cssquery-yui.html) 03:44:08 INFO - PROCESS | 28271 | RSS: Main: 158203904 03:44:08 INFO - PROCESS | 28271 | 03:47:10 INFO - PROCESS | 28271 | Cycle 1(1): loaded http://localhost:47885/tests/dromaeo/cssquery-yui.html (next: http://localhost:47885/tests/dromaeo/cssquery-dojo.html) 03:47:10 INFO - PROCESS | 28271 | RSS: Main: 158871552 03:47:10 INFO - PROCESS | 28271 | 03:47:10 INFO - PROCESS | 28271 | __start_tp_report 03:47:10 INFO - PROCESS | 28271 | _x_x_mozilla_page_load 03:47:10 INFO - PROCESS | 28271 | _x_x_mozilla_page_load_details 03:47:10 INFO - PROCESS | 28271 | |i|pagename|runs| 03:47:10 INFO - PROCESS | 28271 | |0;dojo.html;3366.6465021172776;3390.3049874775675;3415.334009868075;3418.8290585470722;3436.1581412553915;5482.232487451755;5488.423715509874;5518.889622207558;5557.444255574441;5573.247611572447;5517.586181036419;5519.668819870811;5565.443455654443;5582.86042848929;5592.217089607459;5005.6496045276845;5080.034793389244;5094.821681241157;5149.485051494839;5156.046131465672;5000.624953128512;5008.474110218429;5118.232265160219;5158.896822063556;5186.040582492251;5038.445770965191;5039.370078740158;5055.292259083732;5074.3657042869645;5100.489951004888;5036.294918711384;5099.413567439755;5101.617378696613;5102.2091575805625;5151.922721159186;4808;4980.726060066695;4985.800567977277;5044.697318160922;5069.645124841262;5306.151015837448;5353.0899747043095;5412.972935135299;5448.673079615236;5473.8357849264585;5870.090136028923;5915.994280972245;5923.5853490255695;5930.169776231288;5942.851428714317;5751.654900705971;5955.106733989893;5986.551008674367;5993.6403815770755;5995.430434108753;5507.091329930557;5557.5553955683445;5561.443855614447;5566.693831839248;5583;6030.095485677183;6044.909326360108;6046.939530604725;6047.274327080779;6057.3336932937345;5619;5657.8019769307875;5679.943200568024;5762.538996880239;5819.301683797971;5795.681237531892;5797.8550536236935;5801.88396232073;5814.06974884025;5854.385289544622;5734.971325143431;5752.9712351437975;5764;5766.990776614076;5796.565257605612;5721.313442386941;5751.079827227623;5823.097419899922;5823.505002074787;5888.381719919433;4667.5763892013265;4669.743164125938;4676.55572720591;4678.064387122589;4697.78859951303;4170.270202714573;4191.203671302443;4195.056112374752;4204.159168166318;4234.6823988201;5627.915581266284;5703.458171473703;5811.27359080115;5836.153757705109;5885.47030767233;5817.592768506164;5867.061270196748;5911.379305172981;5961.880762384728;5962.791302304398;5731.742071606787;5848.883022339614;5863.3257175424515;5866.6480011199465;5869.354371019184;5143.819966301162;5145.099607568661;5169.353830771153;5226.843194704165;5226.97386513065;6300.7479700811455;6309.242890853127;6359.109724638554;6373.267074286424;6378.043293505918;5798.710064496842;5886.676232807236;5929.792457263975;5951.285845698544;5963.224780778471;6234.501239900887;6354.046892966092;6376.075469057054;6381.968090159519;6404.487640988803;5819.4180581943165;5850.736716847667;5852.297724273115;5853.385394533512;5883.499902508163;5769.394213607511;5848.2689663792025;5887.911681324869;5939.881202376015;5942.316633587193;5769.394213607511;5801.1878337032;5811.767529298781;5825.126231065374;5834.18321434991;5740.827775166752;5758;5770.047942089508;5775.9133612996675;5807.593468457168;5463.453654634504;5598.86002849932;5625.718714064362;5644.745986430703;5691.089425691869;5745.971270143623;5821.09772985188;5821.650700957956;5836.387179346108;5845.240118784531;5653.321601407857;5659.858503537444;5677.347105082803;5690.772369105355;5696.829095127153 03:47:10 INFO - PROCESS | 28271 | |1;ext.html;597.4861619007653;649.191756263452;658.4600627485464;660.3627499463017;663.1147418196707;44636.55363446364;46061.07877842441;46490.76754616222;46835.297470537924;49944.75027624866;46104.308435373496;46107.769461152646;46181.07637847241;46652.766736166355;46660.53339466605;8977.102289771003;9112.362134650577;9137;9151.771205719844;9155.633774649039;8939.687110951118;8959.193672569467;9015.95492022539;9019.54902254888;9026.097390260988;9013.414128081686;9050.547472626342;9066.818663626724;9077.228435582983;9085.500297483635;9029.142231487998;9033.548322583878;9064.093590640949;9064.456132632064;9068.093190680913;9015.684451044248;9076.41003334781;9081.59132839024;9090.13643703847;9127.543622818832;43289.56710432887;43441;43713.78143109296;43758.12483750307;43818;4080.367543030835;4480.686351955364;4632.907341853144;4635.513271106553;4730.2431610942085;33347.6665233347;34216.48675269848;34563;34672.82663586666;35476.64523354759;4696;4700.694454860457;4705.764711764398;4770.976145119287;4807.326974223612;358647;359408.9999999974;360005;360085;361532;60902.39097609056;61252.08121878176;61350.38649613536;61433.38566614366;61556;38491.23017539633;39227.215455690726;39314.606853931095;41185.1762964745;41210.175796483905;16921.323147074225;17002.404915827887;17041.7443738344;17202.741958870625;17208.96746195232;13448.260345680887;13635.590932272047;13695.246761428018;13746;13748.93125534386;20976.47558811042;20996.265130720352;20998.37004889825;21039;21063.578728425346;7340.339369456775;7541.849163016709;7553.622318884034;7609.782434810404;7895.802604934807;1034.7826956339102;1082.5453309609982;1117.519466629341;1119.9664010079712;1170.607846371475;32886.506702399485;32975.835120824246;33450.330993380485;33539.66460335365;33629.49555756665;1131.4173200801595;1159.4144956796763;1202.6848316989935;1227.355638289887;1273.8471383434048;17273.481795546155;17349.392771252944;17479.12604369802;17512;17664.91167544154;574227;598957;604156;617677;933095;61972.38027619756;63493.682531587045;64190;64201.35798642047;64275.35724642694;1463.4804644351316;1467.2590341877071;1469.2433396800657;1479.8890083243584;1490.7936254343633;33639.83180084084;34256.82871585626;34301.48547771735;34303.65696343005;34373.82813085918;54285;55073;55099.17351239815;55229.1715624274;55259;479347;486766;486767;488829;490397.54801227193;30121.397572048874;30460.847695762266;30651.386972260876;30818.38363232678;30836.845815771674;52414.21378679399;53027.73486132544;53650.195247070544;54224;54298.18552721792;42929.78535107429;43422.34866477069;43556.56443435525;44792.55207447884;45063;43796.78101609471;44563.33155002613;45070.774646126556;46179;46532.30201547047;43804;43956.340654889565;44919.77540112409;45033;45222;38478.80760596179;38925.2214955705;39226.80386598049;39608;39890.60109398869;29735.55396669095;29868.70131298659;30104;30480.390392192476;30502.69497305087 03:47:10 INFO - PROCESS | 28271 | |2;jquery.html;412.14274309436377;416.1427459433566;418.2555052007426;419.30604848974957;424.32320448884036;19064.23743050278;19835.206591736332;19973;20050.097745601448;20672.27647032354;18892.527686807804;19224.231030758787;19605.509862253417;19635.214591416363;19749.308774192905;5376.784928602851;5765.884682306351;5777.480026797587;5787.276590426197;5789.884202315951;5500.80747173849;5619.859503512404;5657.2928383952;5689.459501347386;5721.828345149632;5680.772769089232;5689.260396148495;5706.229658996024;5715.657060576358;5720.370759216483;5467.17992301154;5662.716864156796;5695.003374409462;5701.372848986629;5733.971330143364;5107.565856902131;5149.485051494858;5182.093133701587;5182.792688292464;5197.246399272084;17656;17730.822691773046;17838.821611783846;22717.091316347327;22980.65529017066;2522.6216067589826;2553.7318581548857;2588.767010969003;2801.887924483018;2981.0013645428808;9926.305158638897;10453.895461045367;10538.631147909862;10602.416867072308;10639.840402393893;1952.1215453046186;2008.8292495137784;2032.2480682147552;2103.2638576498257;2185.4536365908525;76429;79671.20328796754;80085;80459;81070.59464702639;11086.445677716081;11136.220464567565;11163.665090047312;11329.603463878724;11415.429228538706;15332.310046047955;15437.30532126057;15457.072575645498;15471.922640386727;15483.148426836407;8252.46358986664;8544.401891867634;8625.870611940825;8640.913590864011;8669.783255418664;8028.11690714021;8028.518288902685;8226.05400378952;8226.095129535746;8266.214709602578;4552.635789136927;4702.412198475191;4707.223308154184;4816.277558366273;4821.373221481185;1959.7060440933974;2055.650539408304;2065.1945741160957;2067.514134178467;2073.5541858500496;956.4643799472318;987.7876256604865;1021.3106153346462;1038.2420832792104;1039.9064084232455;18478;18586;18988.335408260922;19345.90327048356;19358.80641193598;994.2856003111401;996.7607774134156;997.6159178716247;1023.7031260934394;1059.8039362717923;10508;10672.733181670521;10680.252382333318;10722.195835312383;10747.623833165802;517853;530505;535945;540269;540718;83930.58034709787;85925.57037214773;86702;86734;87318;1044.2744437019526;1056.4717641179411;1061.0609610494769;1067.556963860005;1073.2165519171049;16239.51281461558;17041.23314450828;17045.57386065358;17447;17580.73628895593;34814.82592587021;37517.624823751416;38515.807420962716;38989.02527436837;39829.60170398259;54454.72772636112;54717.1792423106;55266;55853.72073139608;55891.44108558862;8153.3477321812925;8187.549684767395;8306.70926517569;8399.622017009371;8684.826303474021;9127.634894604142;13023.283719395145;13123.146995445266;13152.868471315165;13166.275854828076;10832.566697332019;11270.922450926117;11701.648950531498;11740.180272057845;11750.70623234426;10957.342559446459;11360.693520244948;11767.705807354885;11792.41037948082;11802.704932376759;11106.833397499206;11284.492197851281;11294.209405341548;11617.88382116168;11674.824877627012;8393.706220282262;8425.5787210638;8447.408681392244;8524.318054555744;8690.78273043179;10900.291481054024;11210.327380357205;11232.213745037769;11309.943450282695;11465.885341146482 03:47:10 INFO - PROCESS | 28271 | |3;mootools.html;1561.281810367231;1605.4621701729923;1807.8463330616896;1931.381957773513;1933.3136736458566;9191.724248272545;9397.71806845794;9610.855837162431;9825.754356141091;9827.606895724171;9824.508774561262;9838;9952.353097048705;9982.30123891326;10025.348352357092;2378.54807586558;2381.2499062795164;2393.102586530051;2417.5648383291;2417.9395515112087;2416.093964763214;2420.8305418620703;2442.5969714997095;2453.325335532734;2453.7055553333535;2434.0263894442187;2441.1455990403306;2461.5815311397014;2468.7407822178684;2469.074097213545;2384.2727967969763;2391.8445301055376;2398.028798336668;2402.939926501834;2414.5291668124723;2388.593939030369;2395.700537432821;2405.314485371678;2409.204962362434;2412.601920683085;9585.376950498265;9684.515774211332;9717.805643887154;9725.319227654067;9758.170555502791;5433.212184233304;5586.664800111966;5590.077637189859;5610.326760788691;5627.0434026215235;3449.6377880322457;3462.601800792916;3500.877469288588;3528.841202145884;3620.5655321361605;1040;1041.177469798858;1041.4844651897283;1065.7815147894662;1081.8755843078068;13280.667983300398;13325.933370333087;13369.197848129046;13389.397477113456;13394.464221431033;4506.909861802811;4585.587297143273;4643.164230438486;4656.604188644003;4694.976525117352;10509.211809114195;10594.894051059546;10603.363798172135;10620.575176992988;10621.84067238992;5268.078086334953;5427.592930530226;5470.890582188334;5518.972405137949;5519.834404967857;4388.956110438919;4954.232094025432;5069.822556210515;5113.51421614946;5161.793528258902;3996.4005399190355;4008.9198216035516;4106.507219133663;4109.753414795122;4166.250074986471;1175.3594291111365;1176.1355403778216;1179.8820117988132;1181.6691326428613;1191.6117722852953;1000.2947921715173;1082.220801023262;1093.775775965925;1099.7745462180394;1123.3765260280459;6569.211694596679;6715.764948226789;6782.93217067823;6805.795826125224;6917.757878474229;388.9319369110451;389.49171331412776;391.43633168238216;392.8016351742352;394.4103565170089;4575.931361029588;4687.1328804171335;4714.363560919301;4719.315699223594;4748.050389922029;11362.886371136183;12075.215111017931;12090.214136081127;12092.57675981354;12096.879031209575;11695.88304116948;11750.882491174978;11818.34999075042;11949.940250298867;12055.57805476804;8843.823123537493;8865.822683546421;8947.955260223658;8948.8657670135;8981.236594889508;6550.181227346582;6561.573497722633;6644.900326495203;6695.531312808057;6720.970485392277;10772.192085593455;10897.237193396386;11041.83437248458;11046.171537134907;11121.666350009513;10999.560017599206;11081.27971681838;11302.604408845651;11414.029807466459;11416.543338266709;7310.634468276458;7770.961145194238;7985.041794984639;8056.476329038594;8135.918640813753;9436.764080898032;9439.52802359893;9474.526273686426;9529.094736000068;9562.95218523903;8383.119772424052;8387.161283871565;8438.957805210935;8552.315814734931;8644.697435589724;8149.6332665031405;8194.713185038496;8231.218034286732;8249.83500330002;8486.066532681276;7681.116671582616;7884.093329266978;7892.881606775788;7893.763187104167;7921.445498815027;5720.942790572041;5750.712464376848;5834.970825146017;5869.589128760946;5891.646501209942;5748.281464816898;5826.300843898759;5873.882522349614;5908.409159084057;5941 03:47:10 INFO - PROCESS | 28271 | |4;prototype.html;334.11293017039753;340.5487728759394;343.3270789253064;343.6752269105695;347.695766204571;5535;5551.306086739158;5569.275994120759;5603.355614104379;5615.298087739033;5476.863078423042;5499.175123731432;5500.367457742361;5531.557475401968;5555.722213889299;3966.305896468121;4152.480939882515;4203.264428724976;4326.762028088454;4349.238883195444;4157.958420415803;4165.687573431989;4180.47744031996;4198.958010419902;4200.97529778559;4021.296273152201;4161.459010328653;4195.66434685226;4216.093539888939;4221.430106935571;4192.350185721203;4208.621224089847;4221.957780422218;4222.915541689149;4226.4716910386205;3687.9815600922093;3711.7751142123034;3720.534933133358;3735.7198210134347;3745.700343972476;5296.894062118775;5342.305500284977;5354.678719276856;5391.919121213185;5410.188471729232;4649.5815376616265;4844.328706596219;4853.927191092102;4865.270209468608;4883.19427294496;5742.425757424266;5744.960539008675;5745.683987380692;5778.826635200909;5795.768169273224;4115.238680844053;4131.884391214386;4146.419501269825;4147.854825081138;4172.3532852407925;5708.486236238717;5939.316978547479;6003.14850446039;6019.946509360844;6031;4128.8348466061625;4172.4784401949755;4185.434966279574;4189.329707246826;4191.832326706897;4204.579542045771;4218.641415479658;4243.172581346669;4243.872683819429;4246.702730808814;4673.579377855941;4783.904321913542;4840.903181936342;4845.442774080955;4874.951250487521;5624.91562626561;5722.656640601577;5727.169560413717;5734.913976290359;5735.053716136874;1441.142520200479;1468.2585294426246;1470.6249906274045;1472.8968972172058;1475.697482016184;953.4898829126384;993.8260804359209;1000.6247657128577;1010.1161483701761;1015.907899008554;5614.6631202128;5640.802571909963;5651.604387692904;5667.433256674382;5749.275217434836;5905.46850783423;5906.8818623627285;5913.2608423947;5916.8462149881225;5984.192134061933;5547.2511210986;5624.437556244343;5803.65178089316;5810.82567522975;5811.709414529257;3122.4223518649123;3126.98436507816;3144.3239703463864;3151.6533181350032;3156.0531840447775;6155.230596175478;6322.217565706712;6364.64994425311;6364.77723279683;6386.137871387396;5995.940040599626;6026.457618814327;6041.456268935817;6055.212822333068;6072.089186622042;6170.167027451326;6357.777477788255;6404.231492220963;6442.6778661066755;6468.514861385415;5854.5609079320075;5883.705584771344;5884.235049443545;5915.852103697442;6019.458248757633;5859.388668116302;5871.6770577618645;5908.113782932594;5916.615419997686;5938.73275702603;6162.352952939877;6331.96834015827;6337.334579869232;6338.873222535616;6359.96820015897;4129.380592911088;4157.297756458326;4159.750414975111;4169.916601667889;4170.895727606834;5961;5966.463018328372;5970.552208584287;5971.940280597139;5982.371850955761;5453.182022696627;5798.796457346181;5821.446962538552;5825.475707186204;5857.414258574108;5833.358330583717;5840.2991641003355;5843.503302219189;5854.209681692917;5861.095144078106;5602.915956260578;5673.149027645886;5704.2014118024335;5775.162601422854;5807.9545681776;5777.884442311214;5848.070136854913;5906.852328691817;5931.584789064724;5937.198478205387;5549.223108764695;5739.598228124159;5781.682007489629;5782.624129431573;5802.274715660543 03:47:10 INFO - PROCESS | 28271 | |5;yui.html;786.3237615650539;809.7611204694615;846.1961136919925;860.4450129666366;881.2113158722947;16261.918690406546;16806.57983550412;16879.07165105919;16959;16977.66044679106;14764.926175369108;14849.406023759064;14903.776443353358;15080;15388.307526161321;858.159004175908;865.6364326982651;873.8077622922947;879.6305551668314;881.1981097201516;867.7787164273102;873.1966590736506;873.3668090634271;874.5408660453281;874.6107981948036;927.1794461901198;932.3287233192125;935.6912218967724;937.9062093790601;940.261894412886;919.7884486568094;928.22029495224;928.2759447630858;931.8276118917954;935.060264434246;681.4548361310931;682.5188242289224;682.8088135322117;684.1721516964479;684.2985939412092;16656.50030499075;16713.665726685518;16750.4137355192;16770.66458670832;16790.328386864632;14732.410703571952;14998.175100369475;15550.533483995498;15705.669361144748;16239.106849123293;13516.459341626323;13556.389962451713;13636.727265454734;13827.170369777845;13844.93077534616;4086.3870419437026;4090.34411381655;4139.275626765304;4174.853880114212;4185.414042034117;60811;80682.59658701668;81233;82457;83968.58015709944;11997.340146292037;12134.271943683407;12153.635390938285;12225.877741222652;12563.623091307276;23050.65424018641;23074.307770766904;23132.7686723134;23171.884140579525;23251.88374058153;8521.57392130391;8522.48865068098;8672.8699069514;8715.782105447415;8721.562833784166;5159.561437277798;5195.2986346843445;5200.895982080413;5273.894522109536;5339.252504649352;3777.9433308500393;3870.806459677005;3878.70909681775;3881;3904.316744569689;658.980230593083;667.7929841749074;673.6429692263108;674.1337381464795;691.2016620802981;778.7352300217954;802.1937952357843;813.2071230550284;821.7883347842309;850.8638617821119;11208.719782005352;11595.304281743123;11708.824367634492;11751.236169648946;12093.758124837454;804.2602986969277;840.844443777903;843.4981186194204;845.7505036014268;866.2939704141105;5032.345795046621;5107.208382700687;5113.821016264487;5143.305653736773;5169.302144210484;68338;74494.62752686202;114251.42874285576;117469.41265293618;118236;29729.702702972692;29807.85096074549;29833.85083074571;79002.6049869747;98474.89769437982;78580;79472.20527794649;89376.553117234;90066;91528;10896.50965706561;10957.123430125412;11013.559457621926;11073;11215.158863085138;38618.22763544769;40328.79835600803;51000.74499627626;52311.73844130755;54427.183592245354;75579;83467;83625;84412;84742;13665.453381864614;14051.36768845384;14078.155310681392;14227.255282137412;14407.351669175121;14986.775198371815;15228.695426091637;15422.305996230421;16722.247498862336;16995.065271410193;14744.410223590936;15226.238688065774;15304.540863774104;15360;15422.768658469908;14841.406343746561;15134.924325378302;15251.084934903942;15315.540533784004;15754.606134846721;15090;15123.31945062497;15173.772393414329;15203.163825989677;15792.842071579136;12274;12375.443105060474;12662.493500259887;12877.098603097693;12966.481340746264;12550.87449125497;12758.61724148277;12847.29339886278;12889.226646401246;12909.677258068623 03:47:10 INFO - PROCESS | 28271 | __end_tp_report 03:47:10 INFO - PROCESS | 28271 | __start_cc_report 03:47:10 INFO - PROCESS | 28271 | _x_x_mozilla_cycle_collect,327 03:47:10 INFO - PROCESS | 28271 | __end_cc_report 03:47:10 INFO - PROCESS | 28271 | __startTimestamp1469875630865__endTimestamp 03:47:10 INFO - PROCESS | 28271 | 03:47:10 INFO - PROCESS | 28271 | ------- Summary: start ------- 03:47:10 INFO - PROCESS | 28271 | Number of tests: 6 03:47:10 INFO - PROCESS | 28271 | 03:47:10 INFO - PROCESS | 28271 | [#0] dojo.html Cycles:165 Average:5525.07 Median:5734.97 stddev:588.95 (10.3%) stddev-sans-first:565.89 03:47:10 INFO - PROCESS | 28271 | Values: 3366.6 3390.3 3415.3 3418.8 3436.2 5482.2 5488.4 5518.9 5557.4 5573.2 5517.6 5519.7 5565.4 5582.9 5592.2 5005.6 5080.0 5094.8 5149.5 5156.0 5000.6 5008.5 5118.2 5158.9 5186.0 5038.4 5039.4 5055.3 5074.4 5100.5 5036.3 5099.4 5101.6 5102.2 5151.9 4808.0 4980.7 4985.8 5044.7 5069.6 5306.2 5353.1 5413.0 5448.7 5473.8 5870.1 5916.0 5923.6 5930.2 5942.9 5751.7 5955.1 5986.6 5993.6 5995.4 5507.1 5557.6 5561.4 5566.7 5583.0 6030.1 6044.9 6046.9 6047.3 6057.3 5619.0 5657.8 5679.9 5762.5 5819.3 5795.7 5797.9 5801.9 5814.1 5854.4 5735.0 5753.0 5764.0 5767.0 5796.6 5721.3 5751.1 5823.1 5823.5 5888.4 4667.6 4669.7 4676.6 4678.1 4697.8 4170.3 4191.2 4195.1 4204.2 4234.7 5627.9 5703.5 5811.3 5836.2 5885.5 5817.6 5867.1 5911.4 5961.9 5962.8 5731.7 5848.9 5863.3 5866.6 5869.4 5143.8 5145.1 5169.4 5226.8 5227.0 6300.7 6309.2 6359.1 6373.3 6378.0 5798.7 5886.7 5929.8 5951.3 5963.2 6234.5 6354.0 6376.1 6382.0 6404.5 5819.4 5850.7 5852.3 5853.4 5883.5 5769.4 5848.3 5887.9 5939.9 5942.3 5769.4 5801.2 5811.8 5825.1 5834.2 5740.8 5758.0 5770.0 5775.9 5807.6 5463.5 5598.9 5625.7 5644.7 5691.1 5746.0 5821.1 5821.7 5836.4 5845.2 5653.3 5659.9 5677.3 5690.8 5696.8 03:47:10 INFO - PROCESS | 28271 | 03:47:10 INFO - PROCESS | 28271 | [#1] ext.html Cycles:180 Average:66484.09 Median:24700.23 stddev:140258.18 (567.8%) stddev-sans-first:140564.40 03:47:10 INFO - PROCESS | 28271 | Values: 597.5 649.2 658.5 660.4 663.1 44636.6 46061.1 46490.8 46835.3 49944.8 46104.3 46107.8 46181.1 46652.8 46660.5 8977.1 9112.4 9137.0 9151.8 9155.6 8939.7 8959.2 9016.0 9019.5 9026.1 9013.4 9050.5 9066.8 9077.2 9085.5 9029.1 9033.5 9064.1 9064.5 9068.1 9015.7 9076.4 9081.6 9090.1 9127.5 43289.6 43441.0 43713.8 43758.1 43818.0 4080.4 4480.7 4632.9 4635.5 4730.2 33347.7 34216.5 34563.0 34672.8 35476.6 4696.0 4700.7 4705.8 4771.0 4807.3 358647.0 359409.0 360005.0 360085.0 361532.0 60902.4 61252.1 61350.4 61433.4 61556.0 38491.2 39227.2 39314.6 41185.2 41210.2 16921.3 17002.4 17041.7 17202.7 17209.0 13448.3 13635.6 13695.2 13746.0 13748.9 20976.5 20996.3 20998.4 21039.0 21063.6 7340.3 7541.8 7553.6 7609.8 7895.8 1034.8 1082.5 1117.5 1120.0 1170.6 32886.5 32975.8 33450.3 33539.7 33629.5 1131.4 1159.4 1202.7 1227.4 1273.8 17273.5 17349.4 17479.1 17512.0 17664.9 574227.0 598957.0 604156.0 617677.0 933095.0 61972.4 63493.7 64190.0 64201.4 64275.4 1463.5 1467.3 1469.2 1479.9 1490.8 33639.8 34256.8 34301.5 34303.7 34373.8 54285.0 55073.0 55099.2 55229.2 55259.0 479347.0 486766.0 486767.0 488829.0 490397.5 30121.4 30460.8 30651.4 30818.4 30836.8 52414.2 53027.7 53650.2 54224.0 54298.2 42929.8 43422.3 43556.6 44792.6 45063.0 43796.8 44563.3 45070.8 46179.0 46532.3 43804.0 43956.3 44919.8 45033.0 45222.0 38478.8 38925.2 39226.8 39608.0 39890.6 29735.6 29868.7 30104.0 30480.4 30502.7 03:47:10 INFO - PROCESS | 28271 | 03:47:10 INFO - PROCESS | 28271 | [#2] jquery.html Cycles:180 Average:29698.52 Median:2695.33 stddev:87528.86 (3247.4%) stddev-sans-first:87746.78 03:47:10 INFO - PROCESS | 28271 | Values: 412.1 416.1 418.3 419.3 424.3 19064.2 19835.2 19973.0 20050.1 20672.3 18892.5 19224.2 19605.5 19635.2 19749.3 5376.8 5765.9 5777.5 5787.3 5789.9 5500.8 5619.9 5657.3 5689.5 5721.8 5680.8 5689.3 5706.2 5715.7 5720.4 5467.2 5662.7 5695.0 5701.4 5734.0 5107.6 5149.5 5182.1 5182.8 5197.2 17656.0 17730.8 17838.8 22717.1 22980.7 2522.6 2553.7 2588.8 2801.9 2981.0 9926.3 10453.9 10538.6 10602.4 10639.8 1952.1 2008.8 2032.2 2103.3 2185.5 76429.0 79671.2 80085.0 80459.0 81070.6 11086.4 11136.2 11163.7 11329.6 11415.4 15332.3 15437.3 15457.1 15471.9 15483.1 8252.5 8544.4 8625.9 8640.9 8669.8 8028.1 8028.5 8226.1 8226.1 8266.2 4552.6 4702.4 4707.2 4816.3 4821.4 1959.7 2055.7 2065.2 2067.5 2073.6 956.5 987.8 1021.3 1038.2 1039.9 18478.0 18586.0 18988.3 19345.9 19358.8 994.3 996.8 997.6 1023.7 1059.8 10508.0 10672.7 10680.3 10722.2 10747.6 517853.0 530505.0 535945.0 540269.0 540718.0 83930.6 85925.6 86702.0 86734.0 87318.0 1044.3 1056.5 1061.1 1067.6 1073.2 16239.5 17041.2 17045.6 17447.0 17580.7 34814.8 37517.6 38515.8 38989.0 39829.6 54454.7 54717.2 55266.0 55853.7 55891.4 8153.3 8187.5 8306.7 8399.6 8684.8 9127.6 13023.3 13123.1 13152.9 13166.3 10832.6 11270.9 11701.6 11740.2 11750.7 10957.3 11360.7 11767.7 11792.4 11802.7 11106.8 11284.5 11294.2 11617.9 11674.8 8393.7 8425.6 8447.4 8524.3 8690.8 10900.3 11210.3 11232.2 11309.9 11465.9 03:47:10 INFO - PROCESS | 28271 | 03:47:10 INFO - PROCESS | 28271 | [#3] mootools.html Cycles:180 Average:6263.31 Median:4277.60 stddev:3650.92 (85.3%) stddev-sans-first:3644.07 03:47:10 INFO - PROCESS | 28271 | Values: 1561.3 1605.5 1807.8 1931.4 1933.3 9191.7 9397.7 9610.9 9825.8 9827.6 9824.5 9838.0 9952.4 9982.3 10025.3 2378.5 2381.2 2393.1 2417.6 2417.9 2416.1 2420.8 2442.6 2453.3 2453.7 2434.0 2441.1 2461.6 2468.7 2469.1 2384.3 2391.8 2398.0 2402.9 2414.5 2388.6 2395.7 2405.3 2409.2 2412.6 9585.4 9684.5 9717.8 9725.3 9758.2 5433.2 5586.7 5590.1 5610.3 5627.0 3449.6 3462.6 3500.9 3528.8 3620.6 1040.0 1041.2 1041.5 1065.8 1081.9 13280.7 13325.9 13369.2 13389.4 13394.5 4506.9 4585.6 4643.2 4656.6 4695.0 10509.2 10594.9 10603.4 10620.6 10621.8 5268.1 5427.6 5470.9 5519.0 5519.8 4389.0 4954.2 5069.8 5113.5 5161.8 3996.4 4008.9 4106.5 4109.8 4166.3 1175.4 1176.1 1179.9 1181.7 1191.6 1000.3 1082.2 1093.8 1099.8 1123.4 6569.2 6715.8 6782.9 6805.8 6917.8 388.9 389.5 391.4 392.8 394.4 4575.9 4687.1 4714.4 4719.3 4748.1 11362.9 12075.2 12090.2 12092.6 12096.9 11695.9 11750.9 11818.3 11949.9 12055.6 8843.8 8865.8 8948.0 8948.9 8981.2 6550.2 6561.6 6644.9 6695.5 6721.0 10772.2 10897.2 11041.8 11046.2 11121.7 10999.6 11081.3 11302.6 11414.0 11416.5 7310.6 7771.0 7985.0 8056.5 8135.9 9436.8 9439.5 9474.5 9529.1 9563.0 8383.1 8387.2 8439.0 8552.3 8644.7 8149.6 8194.7 8231.2 8249.8 8486.1 7681.1 7884.1 7892.9 7893.8 7921.4 5720.9 5750.7 5835.0 5869.6 5891.6 5748.3 5826.3 5873.9 5908.4 5941.0 03:47:10 INFO - PROCESS | 28271 | 03:47:10 INFO - PROCESS | 28271 | [#4] prototype.html Cycles:180 Average:4879.19 Median:5609.01 stddev:1466.05 (26.1%) stddev-sans-first:1429.92 03:47:10 INFO - PROCESS | 28271 | Values: 334.1 340.5 343.3 343.7 347.7 5535.0 5551.3 5569.3 5603.4 5615.3 5476.9 5499.2 5500.4 5531.6 5555.7 3966.3 4152.5 4203.3 4326.8 4349.2 4158.0 4165.7 4180.5 4199.0 4201.0 4021.3 4161.5 4195.7 4216.1 4221.4 4192.4 4208.6 4222.0 4222.9 4226.5 3688.0 3711.8 3720.5 3735.7 3745.7 5296.9 5342.3 5354.7 5391.9 5410.2 4649.6 4844.3 4853.9 4865.3 4883.2 5742.4 5745.0 5745.7 5778.8 5795.8 4115.2 4131.9 4146.4 4147.9 4172.4 5708.5 5939.3 6003.1 6019.9 6031.0 4128.8 4172.5 4185.4 4189.3 4191.8 4204.6 4218.6 4243.2 4243.9 4246.7 4673.6 4783.9 4840.9 4845.4 4875.0 5624.9 5722.7 5727.2 5734.9 5735.1 1441.1 1468.3 1470.6 1472.9 1475.7 953.5 993.8 1000.6 1010.1 1015.9 5614.7 5640.8 5651.6 5667.4 5749.3 5905.5 5906.9 5913.3 5916.8 5984.2 5547.3 5624.4 5803.7 5810.8 5811.7 3122.4 3127.0 3144.3 3151.7 3156.1 6155.2 6322.2 6364.6 6364.8 6386.1 5995.9 6026.5 6041.5 6055.2 6072.1 6170.2 6357.8 6404.2 6442.7 6468.5 5854.6 5883.7 5884.2 5915.9 6019.5 5859.4 5871.7 5908.1 5916.6 5938.7 6162.4 6332.0 6337.3 6338.9 6360.0 4129.4 4157.3 4159.8 4169.9 4170.9 5961.0 5966.5 5970.6 5971.9 5982.4 5453.2 5798.8 5821.4 5825.5 5857.4 5833.4 5840.3 5843.5 5854.2 5861.1 5602.9 5673.1 5704.2 5775.2 5808.0 5777.9 5848.1 5906.9 5931.6 5937.2 5549.2 5739.6 5781.7 5782.6 5802.3 03:47:10 INFO - PROCESS | 28271 | 03:47:10 INFO - PROCESS | 28271 | [#5] yui.html Cycles:180 Average:19902.99 Median:3892.66 stddev:26984.15 (693.2%) stddev-sans-first:27021.67 03:47:10 INFO - PROCESS | 28271 | Values: 786.3 809.8 846.2 860.4 881.2 16261.9 16806.6 16879.1 16959.0 16977.7 14764.9 14849.4 14903.8 15080.0 15388.3 858.2 865.6 873.8 879.6 881.2 867.8 873.2 873.4 874.5 874.6 927.2 932.3 935.7 937.9 940.3 919.8 928.2 928.3 931.8 935.1 681.5 682.5 682.8 684.2 684.3 16656.5 16713.7 16750.4 16770.7 16790.3 14732.4 14998.2 15550.5 15705.7 16239.1 13516.5 13556.4 13636.7 13827.2 13844.9 4086.4 4090.3 4139.3 4174.9 4185.4 60811.0 80682.6 81233.0 82457.0 83968.6 11997.3 12134.3 12153.6 12225.9 12563.6 23050.7 23074.3 23132.8 23171.9 23251.9 8521.6 8522.5 8672.9 8715.8 8721.6 5159.6 5195.3 5200.9 5273.9 5339.3 3777.9 3870.8 3878.7 3881.0 3904.3 659.0 667.8 673.6 674.1 691.2 778.7 802.2 813.2 821.8 850.9 11208.7 11595.3 11708.8 11751.2 12093.8 804.3 840.8 843.5 845.8 866.3 5032.3 5107.2 5113.8 5143.3 5169.3 68338.0 74494.6 114251.4 117469.4 118236.0 29729.7 29807.9 29833.9 79002.6 98474.9 78580.0 79472.2 89376.6 90066.0 91528.0 10896.5 10957.1 11013.6 11073.0 11215.2 38618.2 40328.8 51000.7 52311.7 54427.2 75579.0 83467.0 83625.0 84412.0 84742.0 13665.5 14051.4 14078.2 14227.3 14407.4 14986.8 15228.7 15422.3 16722.2 16995.1 14744.4 15226.2 15304.5 15360.0 15422.8 14841.4 15134.9 15251.1 15315.5 15754.6 15090.0 15123.3 15173.8 15203.2 15792.8 12274.0 12375.4 12662.5 12877.1 12966.5 12550.9 12758.6 12847.3 12889.2 12909.7 03:47:10 INFO - PROCESS | 28271 | -------- Summary: end -------- 03:47:10 INFO - PROCESS | 28271 | 03:47:11 INFO - TEST-INFO | 28271: exit 0 03:47:11 INFO - TEST-OK | dromaeo_css | took 1110146ms 03:47:11 INFO - TEST-START | kraken 03:47:11 INFO - operating with platform_type : linux_ 03:47:11 INFO - Initialising browser for kraken test... 03:47:11 INFO - TEST-INFO | started process 28517 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsygUMh/profile http://localhost:47885/getInfo.html) 03:47:16 INFO - PROCESS | 28517 | __metrics Screen width/height:1600/1200 03:47:16 INFO - PROCESS | 28517 | colorDepth:24 03:47:16 INFO - PROCESS | 28517 | Browser inner width/height: 1024/697 03:47:16 INFO - PROCESS | 28517 | __metrics 03:47:16 ERROR - PROCESS | 28517 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2947: TypeError: this.tabs is undefined 03:47:17 INFO - TEST-INFO | 28517: exit 0 03:47:17 INFO - Browser initialized. 03:47:17 INFO - Running cycle 1/1 for kraken test... 03:47:17 INFO - TEST-INFO | started process 28648 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsygUMh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:47:29 INFO - PROCESS | 28648 | RSS: Main: 156221440 03:47:29 INFO - PROCESS | 28648 | 03:47:31 INFO - PROCESS | 28648 | Cycle 1(1): loaded http://localhost:47885/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47885/tests/kraken/driver.html?testName=audio-beat-detection) 03:47:32 INFO - PROCESS | 28648 | RSS: Main: 161329152 03:47:32 INFO - PROCESS | 28648 | 03:47:35 INFO - PROCESS | 28648 | Cycle 1(1): loaded http://localhost:47885/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47885/tests/kraken/driver.html?testName=audio-dft) 03:47:36 INFO - PROCESS | 28648 | RSS: Main: 160563200 03:47:36 INFO - PROCESS | 28648 | 03:47:38 INFO - PROCESS | 28648 | Cycle 1(1): loaded http://localhost:47885/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47885/tests/kraken/driver.html?testName=audio-fft) 03:47:39 INFO - PROCESS | 28648 | RSS: Main: 161435648 03:47:39 INFO - PROCESS | 28648 | 03:47:41 INFO - PROCESS | 28648 | Cycle 1(1): loaded http://localhost:47885/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47885/tests/kraken/driver.html?testName=audio-oscillator) 03:47:42 INFO - PROCESS | 28648 | RSS: Main: 161103872 03:47:42 INFO - PROCESS | 28648 | 03:47:44 INFO - PROCESS | 28648 | Cycle 1(1): loaded http://localhost:47885/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47885/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:47:45 INFO - PROCESS | 28648 | RSS: Main: 161067008 03:47:45 INFO - PROCESS | 28648 | 03:47:48 INFO - PROCESS | 28648 | Cycle 1(1): loaded http://localhost:47885/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47885/tests/kraken/driver.html?testName=imaging-darkroom) 03:47:48 INFO - PROCESS | 28648 | RSS: Main: 161046528 03:47:48 INFO - PROCESS | 28648 | 03:47:52 INFO - PROCESS | 28648 | Cycle 1(1): loaded http://localhost:47885/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47885/tests/kraken/driver.html?testName=imaging-desaturate) 03:47:52 INFO - PROCESS | 28648 | RSS: Main: 161103872 03:47:52 INFO - PROCESS | 28648 | 03:47:55 INFO - PROCESS | 28648 | Cycle 1(1): loaded http://localhost:47885/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47885/tests/kraken/driver.html?testName=json-parse-financial) 03:47:56 INFO - PROCESS | 28648 | RSS: Main: 160931840 03:47:56 INFO - PROCESS | 28648 | 03:47:57 INFO - PROCESS | 28648 | Cycle 1(1): loaded http://localhost:47885/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47885/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:47:58 INFO - PROCESS | 28648 | RSS: Main: 160993280 03:47:58 INFO - PROCESS | 28648 | 03:48:00 INFO - PROCESS | 28648 | Cycle 1(1): loaded http://localhost:47885/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47885/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:48:00 INFO - PROCESS | 28648 | RSS: Main: 160968704 03:48:00 INFO - PROCESS | 28648 | 03:48:02 INFO - PROCESS | 28648 | Cycle 1(1): loaded http://localhost:47885/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47885/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:48:03 INFO - PROCESS | 28648 | RSS: Main: 161312768 03:48:03 INFO - PROCESS | 28648 | 03:48:06 INFO - PROCESS | 28648 | Cycle 1(1): loaded http://localhost:47885/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47885/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:48:06 INFO - PROCESS | 28648 | RSS: Main: 161607680 03:48:06 INFO - PROCESS | 28648 | 03:48:09 INFO - PROCESS | 28648 | Cycle 1(1): loaded http://localhost:47885/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47885/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:48:09 INFO - PROCESS | 28648 | RSS: Main: 161099776 03:48:09 INFO - PROCESS | 28648 | 03:48:11 INFO - PROCESS | 28648 | Cycle 1(1): loaded http://localhost:47885/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47885/tests/kraken/driver.html?testName=ai-astar) 03:48:11 INFO - PROCESS | 28648 | RSS: Main: 162115584 03:48:11 INFO - PROCESS | 28648 | 03:48:11 INFO - PROCESS | 28648 | __start_tp_report 03:48:11 INFO - PROCESS | 28648 | _x_x_mozilla_page_load 03:48:11 INFO - PROCESS | 28648 | _x_x_mozilla_page_load_details 03:48:11 INFO - PROCESS | 28648 | |i|pagename|runs| 03:48:11 INFO - PROCESS | 28648 | |0;ai-astar;111;107;110;112;110;110;113;110;110;110 03:48:11 INFO - PROCESS | 28648 | |1;audio-beat-detection;160;125;153;179;126;155;155;125;190;126 03:48:11 INFO - PROCESS | 28648 | |2;audio-dft;162;163;162;162;161;162;162;162;162;160 03:48:11 INFO - PROCESS | 28648 | |3;audio-fft;76;78;75;169;75;75;76;75;169;75 03:48:11 INFO - PROCESS | 28648 | |4;audio-oscillator;84;85;155;84;84;84;140;85;84;143 03:48:11 INFO - PROCESS | 28648 | |5;imaging-gaussian-blur;117;118;117;118;117;117;119;118;117;118 03:48:11 INFO - PROCESS | 28648 | |6;imaging-darkroom;146;150;146;146;146;149;147;147;148;147 03:48:11 INFO - PROCESS | 28648 | |7;imaging-desaturate;95;95;97;96;95;95;95;96;96;95 03:48:11 INFO - PROCESS | 28648 | |8;json-parse-financial;52;53;55;53;51;59;52;52;52;52 03:48:11 INFO - PROCESS | 28648 | |9;json-stringify-tinderbox;61;61;61;61;65;60;61;61;63;61 03:48:11 INFO - PROCESS | 28648 | |10;stanford-crypto-aes;77;82;77;79;85;79;78;78;104;82 03:48:11 INFO - PROCESS | 28648 | |11;stanford-crypto-ccm;131;129;121;134;119;124;119;122;128;121 03:48:11 INFO - PROCESS | 28648 | |12;stanford-crypto-pbkdf2;162;159;161;157;160;157;160;156;154;154 03:48:11 INFO - PROCESS | 28648 | |13;stanford-crypto-sha256-iterative;63;63;64;61;62;67;62;62;62;60 03:48:11 INFO - PROCESS | 28648 | __end_tp_report 03:48:11 INFO - PROCESS | 28648 | __start_cc_report 03:48:11 INFO - PROCESS | 28648 | _x_x_mozilla_cycle_collect,911 03:48:11 INFO - PROCESS | 28648 | __end_cc_report 03:48:11 INFO - PROCESS | 28648 | __startTimestamp1469875691858__endTimestamp 03:48:11 INFO - PROCESS | 28648 | 03:48:11 INFO - PROCESS | 28648 | ------- Summary: start ------- 03:48:11 INFO - PROCESS | 28648 | Number of tests: 14 03:48:11 INFO - PROCESS | 28648 | 03:48:11 INFO - PROCESS | 28648 | [#0] ai-astar Cycles:10 Average:110.30 Median:110.00 stddev:1.57 (1.4%) stddev-sans-first:1.64 03:48:11 INFO - PROCESS | 28648 | Values: 111.0 107.0 110.0 112.0 110.0 110.0 113.0 110.0 110.0 110.0 03:48:11 INFO - PROCESS | 28648 | 03:48:11 INFO - PROCESS | 28648 | [#1] audio-beat-detection Cycles:10 Average:149.40 Median:155.00 stddev:23.57 (15.2%) stddev-sans-first:24.68 03:48:11 INFO - PROCESS | 28648 | Values: 160.0 125.0 153.0 179.0 126.0 155.0 155.0 125.0 190.0 126.0 03:48:11 INFO - PROCESS | 28648 | 03:48:11 INFO - PROCESS | 28648 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 03:48:11 INFO - PROCESS | 28648 | Values: 162.0 163.0 162.0 162.0 161.0 162.0 162.0 162.0 162.0 160.0 03:48:11 INFO - PROCESS | 28648 | 03:48:11 INFO - PROCESS | 28648 | [#3] audio-fft Cycles:10 Average:94.30 Median:75.00 stddev:39.38 (52.5%) stddev-sans-first:41.21 03:48:11 INFO - PROCESS | 28648 | Values: 76.0 78.0 75.0 169.0 75.0 75.0 76.0 75.0 169.0 75.0 03:48:11 INFO - PROCESS | 28648 | 03:48:11 INFO - PROCESS | 28648 | [#4] audio-oscillator Cycles:10 Average:102.80 Median:84.00 stddev:30.05 (35.8%) stddev-sans-first:31.09 03:48:11 INFO - PROCESS | 28648 | Values: 84.0 85.0 155.0 84.0 84.0 84.0 140.0 85.0 84.0 143.0 03:48:11 INFO - PROCESS | 28648 | 03:48:11 INFO - PROCESS | 28648 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:118.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 03:48:11 INFO - PROCESS | 28648 | Values: 117.0 118.0 117.0 118.0 117.0 117.0 119.0 118.0 117.0 118.0 03:48:11 INFO - PROCESS | 28648 | 03:48:11 INFO - PROCESS | 28648 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.00 stddev:1.40 (1.0%) stddev-sans-first:1.41 03:48:11 INFO - PROCESS | 28648 | Values: 146.0 150.0 146.0 146.0 146.0 149.0 147.0 147.0 148.0 147.0 03:48:11 INFO - PROCESS | 28648 | 03:48:11 INFO - PROCESS | 28648 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 03:48:11 INFO - PROCESS | 28648 | Values: 95.0 95.0 97.0 96.0 95.0 95.0 95.0 96.0 96.0 95.0 03:48:11 INFO - PROCESS | 28648 | 03:48:11 INFO - PROCESS | 28648 | [#8] json-parse-financial Cycles:10 Average:53.10 Median:52.50 stddev:2.33 (4.4%) stddev-sans-first:2.44 03:48:11 INFO - PROCESS | 28648 | Values: 52.0 53.0 55.0 53.0 51.0 59.0 52.0 52.0 52.0 52.0 03:48:11 INFO - PROCESS | 28648 | 03:48:11 INFO - PROCESS | 28648 | [#9] json-stringify-tinderbox Cycles:10 Average:61.50 Median:61.00 stddev:1.43 (2.4%) stddev-sans-first:1.51 03:48:11 INFO - PROCESS | 28648 | Values: 61.0 61.0 61.0 61.0 65.0 60.0 61.0 61.0 63.0 61.0 03:48:11 INFO - PROCESS | 28648 | 03:48:11 INFO - PROCESS | 28648 | [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:79.00 stddev:8.12 (10.3%) stddev-sans-first:8.40 03:48:11 INFO - PROCESS | 28648 | Values: 77.0 82.0 77.0 79.0 85.0 79.0 78.0 78.0 104.0 82.0 03:48:11 INFO - PROCESS | 28648 | 03:48:11 INFO - PROCESS | 28648 | [#11] stanford-crypto-ccm Cycles:10 Average:124.80 Median:126.00 stddev:5.33 (4.2%) stddev-sans-first:5.16 03:48:11 INFO - PROCESS | 28648 | Values: 131.0 129.0 121.0 134.0 119.0 124.0 119.0 122.0 128.0 121.0 03:48:11 INFO - PROCESS | 28648 | 03:48:11 INFO - PROCESS | 28648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.00 Median:159.50 stddev:2.83 (1.8%) stddev-sans-first:2.60 03:48:11 INFO - PROCESS | 28648 | Values: 162.0 159.0 161.0 157.0 160.0 157.0 160.0 156.0 154.0 154.0 03:48:11 INFO - PROCESS | 28648 | 03:48:11 INFO - PROCESS | 28648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.60 Median:62.50 stddev:1.90 (3.0%) stddev-sans-first:2.01 03:48:11 INFO - PROCESS | 28648 | Values: 63.0 63.0 64.0 61.0 62.0 67.0 62.0 62.0 62.0 60.0 03:48:11 INFO - PROCESS | 28648 | -------- Summary: end -------- 03:48:11 INFO - PROCESS | 28648 | 03:48:12 INFO - TEST-INFO | 28648: exit 0 03:48:12 INFO - TEST-OK | kraken | took 61243ms 03:48:12 INFO - SUITE-END | took 1171s 03:48:13 INFO - Completed test suite (00:19:31) 03:48:13 INFO - javascript benchmark 03:48:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7545.103555253396, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3366.6465021172776, 3390.3049874775675, 3415.334009868075, 3418.8290585470722, 3436.1581412553915, 5482.232487451755, 5488.423715509874, 5518.889622207558, 5557.444255574441, 5573.247611572447, 5517.586181036419, 5519.668819870811, 5565.443455654443, 5582.86042848929, 5592.217089607459, 5005.6496045276845, 5080.034793389244, 5094.821681241157, 5149.485051494839, 5156.046131465672, 5000.624953128512, 5008.474110218429, 5118.232265160219, 5158.896822063556, 5186.040582492251, 5038.445770965191, 5039.370078740158, 5055.292259083732, 5074.3657042869645, 5100.489951004888, 5036.294918711384, 5099.413567439755, 5101.617378696613, 5102.2091575805625, 5151.922721159186, 4808.0, 4980.726060066695, 4985.800567977277, 5044.697318160922, 5069.645124841262, 5306.151015837448, 5353.0899747043095, 5412.972935135299, 5448.673079615236, 5473.8357849264585, 5870.090136028923, 5915.994280972245, 5923.5853490255695, 5930.169776231288, 5942.851428714317, 5751.654900705971, 5955.106733989893, 5986.551008674367, 5993.6403815770755, 5995.430434108753, 5507.091329930557, 5557.5553955683445, 5561.443855614447, 5566.693831839248, 5583.0, 6030.095485677183, 6044.909326360108, 6046.939530604725, 6047.274327080779, 6057.3336932937345, 5619.0, 5657.8019769307875, 5679.943200568024, 5762.538996880239, 5819.301683797971, 5795.681237531892, 5797.8550536236935, 5801.88396232073, 5814.06974884025, 5854.385289544622, 5734.971325143431, 5752.9712351437975, 5764.0, 5766.990776614076, 5796.565257605612, 5721.313442386941, 5751.079827227623, 5823.097419899922, 5823.505002074787, 5888.381719919433, 4667.5763892013265, 4669.743164125938, 4676.55572720591, 4678.064387122589, 4697.78859951303, 4170.270202714573, 4191.203671302443, 4195.056112374752, 4204.159168166318, 4234.6823988201, 5627.915581266284, 5703.458171473703, 5811.27359080115, 5836.153757705109, 5885.47030767233, 5817.592768506164, 5867.061270196748, 5911.379305172981, 5961.880762384728, 5962.791302304398, 5731.742071606787, 5848.883022339614, 5863.3257175424515, 5866.6480011199465, 5869.354371019184, 5143.819966301162, 5145.099607568661, 5169.353830771153, 5226.843194704165, 5226.97386513065, 6300.7479700811455, 6309.242890853127, 6359.109724638554, 6373.267074286424, 6378.043293505918, 5798.710064496842, 5886.676232807236, 5929.792457263975, 5951.285845698544, 5963.224780778471, 6234.501239900887, 6354.046892966092, 6376.075469057054, 6381.968090159519, 6404.487640988803, 5819.4180581943165, 5850.736716847667, 5852.297724273115, 5853.385394533512, 5883.499902508163, 5769.394213607511, 5848.2689663792025, 5887.911681324869, 5939.881202376015, 5942.316633587193, 5769.394213607511, 5801.1878337032, 5811.767529298781, 5825.126231065374, 5834.18321434991, 5740.827775166752, 5758.0, 5770.047942089508, 5775.9133612996675, 5807.593468457168, 5463.453654634504, 5598.86002849932, 5625.718714064362, 5644.745986430703, 5691.089425691869, 5745.971270143623, 5821.09772985188, 5821.650700957956, 5836.387179346108, 5845.240118784531, 5653.321601407857, 5659.858503537444, 5677.347105082803, 5690.772369105355, 5696.829095127153], "value": 5488.922308096704, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.4861619007653, 649.191756263452, 658.4600627485464, 660.3627499463017, 663.1147418196707, 44636.55363446364, 46061.07877842441, 46490.76754616222, 46835.297470537924, 49944.75027624866, 46104.308435373496, 46107.769461152646, 46181.07637847241, 46652.766736166355, 46660.53339466605, 8977.102289771003, 9112.362134650577, 9137.0, 9151.771205719844, 9155.633774649039, 8939.687110951118, 8959.193672569467, 9015.95492022539, 9019.54902254888, 9026.097390260988, 9013.414128081686, 9050.547472626342, 9066.818663626724, 9077.228435582983, 9085.500297483635, 9029.142231487998, 9033.548322583878, 9064.093590640949, 9064.456132632064, 9068.093190680913, 9015.684451044248, 9076.41003334781, 9081.59132839024, 9090.13643703847, 9127.543622818832, 43289.56710432887, 43441.0, 43713.78143109296, 43758.12483750307, 43818.0, 4080.367543030835, 4480.686351955364, 4632.907341853144, 4635.513271106553, 4730.2431610942085, 33347.6665233347, 34216.48675269848, 34563.0, 34672.82663586666, 35476.64523354759, 4696.0, 4700.694454860457, 4705.764711764398, 4770.976145119287, 4807.326974223612, 358647.0, 359408.9999999974, 360005.0, 360085.0, 361532.0, 60902.39097609056, 61252.08121878176, 61350.38649613536, 61433.38566614366, 61556.0, 38491.23017539633, 39227.215455690726, 39314.606853931095, 41185.1762964745, 41210.175796483905, 16921.323147074225, 17002.404915827887, 17041.7443738344, 17202.741958870625, 17208.96746195232, 13448.260345680887, 13635.590932272047, 13695.246761428018, 13746.0, 13748.93125534386, 20976.47558811042, 20996.265130720352, 20998.37004889825, 21039.0, 21063.578728425346, 7340.339369456775, 7541.849163016709, 7553.622318884034, 7609.782434810404, 7895.802604934807, 1034.7826956339102, 1082.5453309609982, 1117.519466629341, 1119.9664010079712, 1170.607846371475, 32886.506702399485, 32975.835120824246, 33450.330993380485, 33539.66460335365, 33629.49555756665, 1131.4173200801595, 1159.4144956796763, 1202.6848316989935, 1227.355638289887, 1273.8471383434048, 17273.481795546155, 17349.392771252944, 17479.12604369802, 17512.0, 17664.91167544154, 574227.0, 598957.0, 604156.0, 617677.0, 933095.0, 61972.38027619756, 63493.682531587045, 64190.0, 64201.35798642047, 64275.35724642694, 1463.4804644351316, 1467.2590341877071, 1469.2433396800657, 1479.8890083243584, 1490.7936254343633, 33639.83180084084, 34256.82871585626, 34301.48547771735, 34303.65696343005, 34373.82813085918, 54285.0, 55073.0, 55099.17351239815, 55229.1715624274, 55259.0, 479347.0, 486766.0, 486767.0, 488829.0, 490397.54801227193, 30121.397572048874, 30460.847695762266, 30651.386972260876, 30818.38363232678, 30836.845815771674, 52414.21378679399, 53027.73486132544, 53650.195247070544, 54224.0, 54298.18552721792, 42929.78535107429, 43422.34866477069, 43556.56443435525, 44792.55207447884, 45063.0, 43796.78101609471, 44563.33155002613, 45070.774646126556, 46179.0, 46532.30201547047, 43804.0, 43956.340654889565, 44919.77540112409, 45033.0, 45222.0, 38478.80760596179, 38925.2214955705, 39226.80386598049, 39608.0, 39890.60109398869, 29735.55396669095, 29868.70131298659, 30104.0, 30480.390392192476, 30502.69497305087], "value": 21313.48017346628, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.14274309436377, 416.1427459433566, 418.2555052007426, 419.30604848974957, 424.32320448884036, 19064.23743050278, 19835.206591736332, 19973.0, 20050.097745601448, 20672.27647032354, 18892.527686807804, 19224.231030758787, 19605.509862253417, 19635.214591416363, 19749.308774192905, 5376.784928602851, 5765.884682306351, 5777.480026797587, 5787.276590426197, 5789.884202315951, 5500.80747173849, 5619.859503512404, 5657.2928383952, 5689.459501347386, 5721.828345149632, 5680.772769089232, 5689.260396148495, 5706.229658996024, 5715.657060576358, 5720.370759216483, 5467.17992301154, 5662.716864156796, 5695.003374409462, 5701.372848986629, 5733.971330143364, 5107.565856902131, 5149.485051494858, 5182.093133701587, 5182.792688292464, 5197.246399272084, 17656.0, 17730.822691773046, 17838.821611783846, 22717.091316347327, 22980.65529017066, 2522.6216067589826, 2553.7318581548857, 2588.767010969003, 2801.887924483018, 2981.0013645428808, 9926.305158638897, 10453.895461045367, 10538.631147909862, 10602.416867072308, 10639.840402393893, 1952.1215453046186, 2008.8292495137784, 2032.2480682147552, 2103.2638576498257, 2185.4536365908525, 76429.0, 79671.20328796754, 80085.0, 80459.0, 81070.59464702639, 11086.445677716081, 11136.220464567565, 11163.665090047312, 11329.603463878724, 11415.429228538706, 15332.310046047955, 15437.30532126057, 15457.072575645498, 15471.922640386727, 15483.148426836407, 8252.46358986664, 8544.401891867634, 8625.870611940825, 8640.913590864011, 8669.783255418664, 8028.11690714021, 8028.518288902685, 8226.05400378952, 8226.095129535746, 8266.214709602578, 4552.635789136927, 4702.412198475191, 4707.223308154184, 4816.277558366273, 4821.373221481185, 1959.7060440933974, 2055.650539408304, 2065.1945741160957, 2067.514134178467, 2073.5541858500496, 956.4643799472318, 987.7876256604865, 1021.3106153346462, 1038.2420832792104, 1039.9064084232455, 18478.0, 18586.0, 18988.335408260922, 19345.90327048356, 19358.80641193598, 994.2856003111401, 996.7607774134156, 997.6159178716247, 1023.7031260934394, 1059.8039362717923, 10508.0, 10672.733181670521, 10680.252382333318, 10722.195835312383, 10747.623833165802, 517853.0, 530505.0, 535945.0, 540269.0, 540718.0, 83930.58034709787, 85925.57037214773, 86702.0, 86734.0, 87318.0, 1044.2744437019526, 1056.4717641179411, 1061.0609610494769, 1067.556963860005, 1073.2165519171049, 16239.51281461558, 17041.23314450828, 17045.57386065358, 17447.0, 17580.73628895593, 34814.82592587021, 37517.624823751416, 38515.807420962716, 38989.02527436837, 39829.60170398259, 54454.72772636112, 54717.1792423106, 55266.0, 55853.72073139608, 55891.44108558862, 8153.3477321812925, 8187.549684767395, 8306.70926517569, 8399.622017009371, 8684.826303474021, 9127.634894604142, 13023.283719395145, 13123.146995445266, 13152.868471315165, 13166.275854828076, 10832.566697332019, 11270.922450926117, 11701.648950531498, 11740.180272057845, 11750.70623234426, 10957.342559446459, 11360.693520244948, 11767.705807354885, 11792.41037948082, 11802.704932376759, 11106.833397499206, 11284.492197851281, 11294.209405341548, 11617.88382116168, 11674.824877627012, 8393.706220282262, 8425.5787210638, 8447.408681392244, 8524.318054555744, 8690.78273043179, 10900.291481054024, 11210.327380357205, 11232.213745037769, 11309.943450282695, 11465.885341146482], "value": 9196.16864035828, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1561.281810367231, 1605.4621701729923, 1807.8463330616896, 1931.381957773513, 1933.3136736458566, 9191.724248272545, 9397.71806845794, 9610.855837162431, 9825.754356141091, 9827.606895724171, 9824.508774561262, 9838.0, 9952.353097048705, 9982.30123891326, 10025.348352357092, 2378.54807586558, 2381.2499062795164, 2393.102586530051, 2417.5648383291, 2417.9395515112087, 2416.093964763214, 2420.8305418620703, 2442.5969714997095, 2453.325335532734, 2453.7055553333535, 2434.0263894442187, 2441.1455990403306, 2461.5815311397014, 2468.7407822178684, 2469.074097213545, 2384.2727967969763, 2391.8445301055376, 2398.028798336668, 2402.939926501834, 2414.5291668124723, 2388.593939030369, 2395.700537432821, 2405.314485371678, 2409.204962362434, 2412.601920683085, 9585.376950498265, 9684.515774211332, 9717.805643887154, 9725.319227654067, 9758.170555502791, 5433.212184233304, 5586.664800111966, 5590.077637189859, 5610.326760788691, 5627.0434026215235, 3449.6377880322457, 3462.601800792916, 3500.877469288588, 3528.841202145884, 3620.5655321361605, 1040.0, 1041.177469798858, 1041.4844651897283, 1065.7815147894662, 1081.8755843078068, 13280.667983300398, 13325.933370333087, 13369.197848129046, 13389.397477113456, 13394.464221431033, 4506.909861802811, 4585.587297143273, 4643.164230438486, 4656.604188644003, 4694.976525117352, 10509.211809114195, 10594.894051059546, 10603.363798172135, 10620.575176992988, 10621.84067238992, 5268.078086334953, 5427.592930530226, 5470.890582188334, 5518.972405137949, 5519.834404967857, 4388.956110438919, 4954.232094025432, 5069.822556210515, 5113.51421614946, 5161.793528258902, 3996.4005399190355, 4008.9198216035516, 4106.507219133663, 4109.753414795122, 4166.250074986471, 1175.3594291111365, 1176.1355403778216, 1179.8820117988132, 1181.6691326428613, 1191.6117722852953, 1000.2947921715173, 1082.220801023262, 1093.775775965925, 1099.7745462180394, 1123.3765260280459, 6569.211694596679, 6715.764948226789, 6782.93217067823, 6805.795826125224, 6917.757878474229, 388.9319369110451, 389.49171331412776, 391.43633168238216, 392.8016351742352, 394.4103565170089, 4575.931361029588, 4687.1328804171335, 4714.363560919301, 4719.315699223594, 4748.050389922029, 11362.886371136183, 12075.215111017931, 12090.214136081127, 12092.57675981354, 12096.879031209575, 11695.88304116948, 11750.882491174978, 11818.34999075042, 11949.940250298867, 12055.57805476804, 8843.823123537493, 8865.822683546421, 8947.955260223658, 8948.8657670135, 8981.236594889508, 6550.181227346582, 6561.573497722633, 6644.900326495203, 6695.531312808057, 6720.970485392277, 10772.192085593455, 10897.237193396386, 11041.83437248458, 11046.171537134907, 11121.666350009513, 10999.560017599206, 11081.27971681838, 11302.604408845651, 11414.029807466459, 11416.543338266709, 7310.634468276458, 7770.961145194238, 7985.041794984639, 8056.476329038594, 8135.918640813753, 9436.764080898032, 9439.52802359893, 9474.526273686426, 9529.094736000068, 9562.95218523903, 8383.119772424052, 8387.161283871565, 8438.957805210935, 8552.315814734931, 8644.697435589724, 8149.6332665031405, 8194.713185038496, 8231.218034286732, 8249.83500330002, 8486.066532681276, 7681.116671582616, 7884.093329266978, 7892.881606775788, 7893.763187104167, 7921.445498815027, 5720.942790572041, 5750.712464376848, 5834.970825146017, 5869.589128760946, 5891.646501209942, 5748.281464816898, 5826.300843898759, 5873.882522349614, 5908.409159084057, 5941.0], "value": 4841.148051748204, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.11293017039753, 340.5487728759394, 343.3270789253064, 343.6752269105695, 347.695766204571, 5535.0, 5551.306086739158, 5569.275994120759, 5603.355614104379, 5615.298087739033, 5476.863078423042, 5499.175123731432, 5500.367457742361, 5531.557475401968, 5555.722213889299, 3966.305896468121, 4152.480939882515, 4203.264428724976, 4326.762028088454, 4349.238883195444, 4157.958420415803, 4165.687573431989, 4180.47744031996, 4198.958010419902, 4200.97529778559, 4021.296273152201, 4161.459010328653, 4195.66434685226, 4216.093539888939, 4221.430106935571, 4192.350185721203, 4208.621224089847, 4221.957780422218, 4222.915541689149, 4226.4716910386205, 3687.9815600922093, 3711.7751142123034, 3720.534933133358, 3735.7198210134347, 3745.700343972476, 5296.894062118775, 5342.305500284977, 5354.678719276856, 5391.919121213185, 5410.188471729232, 4649.5815376616265, 4844.328706596219, 4853.927191092102, 4865.270209468608, 4883.19427294496, 5742.425757424266, 5744.960539008675, 5745.683987380692, 5778.826635200909, 5795.768169273224, 4115.238680844053, 4131.884391214386, 4146.419501269825, 4147.854825081138, 4172.3532852407925, 5708.486236238717, 5939.316978547479, 6003.14850446039, 6019.946509360844, 6031.0, 4128.8348466061625, 4172.4784401949755, 4185.434966279574, 4189.329707246826, 4191.832326706897, 4204.579542045771, 4218.641415479658, 4243.172581346669, 4243.872683819429, 4246.702730808814, 4673.579377855941, 4783.904321913542, 4840.903181936342, 4845.442774080955, 4874.951250487521, 5624.91562626561, 5722.656640601577, 5727.169560413717, 5734.913976290359, 5735.053716136874, 1441.142520200479, 1468.2585294426246, 1470.6249906274045, 1472.8968972172058, 1475.697482016184, 953.4898829126384, 993.8260804359209, 1000.6247657128577, 1010.1161483701761, 1015.907899008554, 5614.6631202128, 5640.802571909963, 5651.604387692904, 5667.433256674382, 5749.275217434836, 5905.46850783423, 5906.8818623627285, 5913.2608423947, 5916.8462149881225, 5984.192134061933, 5547.2511210986, 5624.437556244343, 5803.65178089316, 5810.82567522975, 5811.709414529257, 3122.4223518649123, 3126.98436507816, 3144.3239703463864, 3151.6533181350032, 3156.0531840447775, 6155.230596175478, 6322.217565706712, 6364.64994425311, 6364.77723279683, 6386.137871387396, 5995.940040599626, 6026.457618814327, 6041.456268935817, 6055.212822333068, 6072.089186622042, 6170.167027451326, 6357.777477788255, 6404.231492220963, 6442.6778661066755, 6468.514861385415, 5854.5609079320075, 5883.705584771344, 5884.235049443545, 5915.852103697442, 6019.458248757633, 5859.388668116302, 5871.6770577618645, 5908.113782932594, 5916.615419997686, 5938.73275702603, 6162.352952939877, 6331.96834015827, 6337.334579869232, 6338.873222535616, 6359.96820015897, 4129.380592911088, 4157.297756458326, 4159.750414975111, 4169.916601667889, 4170.895727606834, 5961.0, 5966.463018328372, 5970.552208584287, 5971.940280597139, 5982.371850955761, 5453.182022696627, 5798.796457346181, 5821.446962538552, 5825.475707186204, 5857.414258574108, 5833.358330583717, 5840.2991641003355, 5843.503302219189, 5854.209681692917, 5861.095144078106, 5602.915956260578, 5673.149027645886, 5704.2014118024335, 5775.162601422854, 5807.9545681776, 5777.884442311214, 5848.070136854913, 5906.852328691817, 5931.584789064724, 5937.198478205387, 5549.223108764695, 5739.598228124159, 5781.682007489629, 5782.624129431573, 5802.274715660543], "value": 4412.490656217815, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [786.3237615650539, 809.7611204694615, 846.1961136919925, 860.4450129666366, 881.2113158722947, 16261.918690406546, 16806.57983550412, 16879.07165105919, 16959.0, 16977.66044679106, 14764.926175369108, 14849.406023759064, 14903.776443353358, 15080.0, 15388.307526161321, 858.159004175908, 865.6364326982651, 873.8077622922947, 879.6305551668314, 881.1981097201516, 867.7787164273102, 873.1966590736506, 873.3668090634271, 874.5408660453281, 874.6107981948036, 927.1794461901198, 932.3287233192125, 935.6912218967724, 937.9062093790601, 940.261894412886, 919.7884486568094, 928.22029495224, 928.2759447630858, 931.8276118917954, 935.060264434246, 681.4548361310931, 682.5188242289224, 682.8088135322117, 684.1721516964479, 684.2985939412092, 16656.50030499075, 16713.665726685518, 16750.4137355192, 16770.66458670832, 16790.328386864632, 14732.410703571952, 14998.175100369475, 15550.533483995498, 15705.669361144748, 16239.106849123293, 13516.459341626323, 13556.389962451713, 13636.727265454734, 13827.170369777845, 13844.93077534616, 4086.3870419437026, 4090.34411381655, 4139.275626765304, 4174.853880114212, 4185.414042034117, 60811.0, 80682.59658701668, 81233.0, 82457.0, 83968.58015709944, 11997.340146292037, 12134.271943683407, 12153.635390938285, 12225.877741222652, 12563.623091307276, 23050.65424018641, 23074.307770766904, 23132.7686723134, 23171.884140579525, 23251.88374058153, 8521.57392130391, 8522.48865068098, 8672.8699069514, 8715.782105447415, 8721.562833784166, 5159.561437277798, 5195.2986346843445, 5200.895982080413, 5273.894522109536, 5339.252504649352, 3777.9433308500393, 3870.806459677005, 3878.70909681775, 3881.0, 3904.316744569689, 658.980230593083, 667.7929841749074, 673.6429692263108, 674.1337381464795, 691.2016620802981, 778.7352300217954, 802.1937952357843, 813.2071230550284, 821.7883347842309, 850.8638617821119, 11208.719782005352, 11595.304281743123, 11708.824367634492, 11751.236169648946, 12093.758124837454, 804.2602986969277, 840.844443777903, 843.4981186194204, 845.7505036014268, 866.2939704141105, 5032.345795046621, 5107.208382700687, 5113.821016264487, 5143.305653736773, 5169.302144210484, 68338.0, 74494.62752686202, 114251.42874285576, 117469.41265293618, 118236.0, 29729.702702972692, 29807.85096074549, 29833.85083074571, 79002.6049869747, 98474.89769437982, 78580.0, 79472.20527794649, 89376.553117234, 90066.0, 91528.0, 10896.50965706561, 10957.123430125412, 11013.559457621926, 11073.0, 11215.158863085138, 38618.22763544769, 40328.79835600803, 51000.74499627626, 52311.73844130755, 54427.183592245354, 75579.0, 83467.0, 83625.0, 84412.0, 84742.0, 13665.453381864614, 14051.36768845384, 14078.155310681392, 14227.255282137412, 14407.351669175121, 14986.775198371815, 15228.695426091637, 15422.305996230421, 16722.247498862336, 16995.065271410193, 14744.410223590936, 15226.238688065774, 15304.540863774104, 15360.0, 15422.768658469908, 14841.406343746561, 15134.924325378302, 15251.084934903942, 15315.540533784004, 15754.606134846721, 15090.0, 15123.31945062497, 15173.772393414329, 15203.163825989677, 15792.842071579136, 12274.0, 12375.443105060474, 12662.493500259887, 12877.098603097693, 12966.481340746264, 12550.87449125497, 12758.61724148277, 12847.29339886278, 12889.226646401246, 12909.677258068623], "value": 8027.255677820674, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1520.9999999999995, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 107.0, 110.0, 112.0, 110.0, 110.0, 113.0, 110.0, 110.0, 110.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 125.0, 153.0, 179.0, 126.0, 155.0, 155.0, 125.0, 190.0, 126.0], "value": 149.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 162.0, 162.0, 161.0, 162.0, 162.0, 162.0, 162.0, 160.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 75.0, 169.0, 75.0, 75.0, 76.0, 75.0, 169.0, 75.0], "value": 94.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 155.0, 84.0, 84.0, 84.0, 140.0, 85.0, 84.0, 143.0], "value": 102.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 118.0, 117.0, 117.0, 119.0, 118.0, 117.0, 118.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 150.0, 146.0, 146.0, 146.0, 149.0, 147.0, 147.0, 148.0, 147.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 97.0, 96.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 55.0, 53.0, 51.0, 59.0, 52.0, 52.0, 52.0, 52.0], "value": 53.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 61.0, 61.0, 65.0, 60.0, 61.0, 61.0, 63.0, 61.0], "value": 61.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 82.0, 77.0, 79.0, 85.0, 79.0, 78.0, 78.0, 104.0, 82.0], "value": 82.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 129.0, 121.0, 134.0, 119.0, 124.0, 119.0, 122.0, 128.0, 121.0], "value": 124.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 159.0, 161.0, 157.0, 160.0, 157.0, 160.0, 156.0, 154.0, 154.0], "value": 158.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 64.0, 61.0, 62.0, 67.0, 62.0, 62.0, 62.0, 60.0], "value": 62.6, "unit": "score"}]}]} 03:48:13 INFO - Return code: 0 03:48:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:48:13 ERROR - # TBPL SUCCESS # 03:48:13 INFO - Running post-action listener: _resource_record_post_action 03:48:13 INFO - ##### 03:48:13 INFO - ##### Finished run-tests step (success) 03:48:13 INFO - ##### 03:48:13 INFO - Running post-run listener: _resource_record_post_run 03:48:13 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 506073088; Read time: 20; Write time: 517364 03:48:13 INFO - TinderboxPrint: CPU usage
13.3% 03:48:13 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 03:48:13 INFO - TinderboxPrint: I/O write bytes / time
506,073,088 / 517,364 03:48:13 INFO - TinderboxPrint: CPU idle
8,220.6 (86.7%) 03:48:13 INFO - TinderboxPrint: CPU user
1,208.7 (12.7%) 03:48:13 INFO - TinderboxPrint: Swap in / out
0 / 0 03:48:13 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 252686336; Read time: 0; Write time: 337308 03:48:13 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 246657024; Read time: 20; Write time: 176908 03:48:13 INFO - Running post-run listener: _upload_blobber_files 03:48:13 INFO - Blob upload gear active. 03:48:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:48:13 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 03:48:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:48:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:48:14 INFO - (blobuploader) - INFO - Open directory for files ... 03:48:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:48:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:48:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:48:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:48:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:48:15 INFO - (blobuploader) - INFO - Done attempting. 03:48:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:48:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:48:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:48:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:48:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:48:18 INFO - (blobuploader) - INFO - Done attempting. 03:48:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:48:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:48:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:48:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:48:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:48:19 INFO - (blobuploader) - INFO - Done attempting. 03:48:19 INFO - (blobuploader) - INFO - Iteration through files over. 03:48:19 INFO - Return code: 0 03:48:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:48:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:48:19 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/609c8194c0aa5dd6dfc9af42544c2056db83c1c6dcfc3d4b8c3edc07a50beed6239504c6aaff38369459f1fae77ae8f7cd4ad1703d2c34678b08322a717c495c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c1346e14174c282de015f5b77bd57dfb24ae99b535b5f8acc2b6babf70a2ce2e56542a4798ada02f1fa381845a3ac0184c3a9aa5153912b2bba791b0eb075833", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c09e786da62caea96793d357401a727bc6a6c3c158de59fb5f3146a0fd997dbb144d43241b347208fa8de259b6b6f0282203a470812739deb931903f2b31116"} 03:48:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:48:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:48:19 INFO - Contents: 03:48:19 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/609c8194c0aa5dd6dfc9af42544c2056db83c1c6dcfc3d4b8c3edc07a50beed6239504c6aaff38369459f1fae77ae8f7cd4ad1703d2c34678b08322a717c495c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c1346e14174c282de015f5b77bd57dfb24ae99b535b5f8acc2b6babf70a2ce2e56542a4798ada02f1fa381845a3ac0184c3a9aa5153912b2bba791b0eb075833", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c09e786da62caea96793d357401a727bc6a6c3c158de59fb5f3146a0fd997dbb144d43241b347208fa8de259b6b6f0282203a470812739deb931903f2b31116"} 03:48:19 INFO - Running post-run listener: copy_logs_to_upload_dir 03:48:19 INFO - Copying logs to upload dir... 03:48:19 INFO - mkdir: /builds/slave/test/build/upload/logs 03:48:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1229.165870 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 29 secs) (at 2016-07-30 03:48:18.585946) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-30 03:48:18.589336) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/609c8194c0aa5dd6dfc9af42544c2056db83c1c6dcfc3d4b8c3edc07a50beed6239504c6aaff38369459f1fae77ae8f7cd4ad1703d2c34678b08322a717c495c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c1346e14174c282de015f5b77bd57dfb24ae99b535b5f8acc2b6babf70a2ce2e56542a4798ada02f1fa381845a3ac0184c3a9aa5153912b2bba791b0eb075833", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c09e786da62caea96793d357401a727bc6a6c3c158de59fb5f3146a0fd997dbb144d43241b347208fa8de259b6b6f0282203a470812739deb931903f2b31116"} build_url:https://queue.taskcluster.net/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011346 build_url: 'https://queue.taskcluster.net/v1/task/Wsfhh1rLTzW9EnkNArOCaw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/609c8194c0aa5dd6dfc9af42544c2056db83c1c6dcfc3d4b8c3edc07a50beed6239504c6aaff38369459f1fae77ae8f7cd4ad1703d2c34678b08322a717c495c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c1346e14174c282de015f5b77bd57dfb24ae99b535b5f8acc2b6babf70a2ce2e56542a4798ada02f1fa381845a3ac0184c3a9aa5153912b2bba791b0eb075833", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c09e786da62caea96793d357401a727bc6a6c3c158de59fb5f3146a0fd997dbb144d43241b347208fa8de259b6b6f0282203a470812739deb931903f2b31116"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-30 03:48:18.619678) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:48:18.620462) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1469874467.463141-558937258 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004485 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 03:48:18.705975) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-30 03:48:18.706340) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-30 03:48:18.706674) ========= ========= Total master_lag: 0.19 =========