builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-021 starttime: 1466125219.29 results: success (0) buildid: 20160616163123 builduid: 4da5b2e960374af499b5667814982984 revision: 05873df0c0c268e419a714b8a6204cb852049098 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-16 18:00:19.289494) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-16 18:00:19.290000) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-16 18:00:19.290285) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-16 18:00:19.305605) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 18:00:19.305998) ========= 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=df17445c3590ff7897617a0e00000225-1466125112.271062-518050420 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-16 18:00:19-- 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% 34.8M=0s 2016-06-16 18:00:19 (34.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113039 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 18:00:19.433103) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 18:00:19.433439) ========= 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=df17445c3590ff7897617a0e00000225-1466125112.271062-518050420 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018200 ========= master_lag: 0.26 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 18:00:19.713742) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-16 18:00:19.714220) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 05873df0c0c268e419a714b8a6204cb852049098 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 05873df0c0c268e419a714b8a6204cb852049098 --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=df17445c3590ff7897617a0e00000225-1466125112.271062-518050420 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-16 18:00:19,942 truncating revision to first 12 chars 2016-06-16 18:00:19,942 Setting DEBUG logging. 2016-06-16 18:00:19,943 attempt 1/10 2016-06-16 18:00:19,943 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/05873df0c0c2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-16 18:00:20,716 unpacking tar archive at: mozilla-central-05873df0c0c2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.113612 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-16 18:00:20.841829) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-16 18:00:20.842204) ========= script_repo_revision: 05873df0c0c268e419a714b8a6204cb852049098 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-16 18:00:20.842620) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-16 18:00:20.842896) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-16 18:00:20.851719) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-06-16 18:00:20.852166) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1466125112.271062-518050420 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:00:21 INFO - MultiFileLogger online at 20160616 18:00:21 in /builds/slave/test 18:00:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 18:00:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:00:21 INFO - {'append_to_log': False, 18:00:21 INFO - 'base_work_dir': '/builds/slave/test', 18:00:21 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:00:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:00:21 INFO - 'branch': 'Firefox-Non-PGO', 18:00:21 INFO - 'buildbot_json_path': 'buildprops.json', 18:00:21 INFO - 'config_files': ('talos/linux_config.py',), 18:00:21 INFO - 'default_actions': ('clobber', 18:00:21 INFO - 'read-buildbot-config', 18:00:21 INFO - 'download-and-extract', 18:00:21 INFO - 'populate-webroot', 18:00:21 INFO - 'create-virtualenv', 18:00:21 INFO - 'install', 18:00:21 INFO - 'run-tests'), 18:00:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:00:21 INFO - 'download_minidump_stackwalk': True, 18:00:21 INFO - 'download_symbols': 'ondemand', 18:00:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:00:21 INFO - 'tooltool.py': '/tools/tooltool.py', 18:00:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:00:21 INFO - '/tools/misc-python/virtualenv.py')}, 18:00:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:00:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:00:21 INFO - 'installer_path': 'installer.exe', 18:00:21 INFO - 'log_level': 'info', 18:00:21 INFO - 'log_name': 'talos', 18:00:21 INFO - 'log_to_console': True, 18:00:21 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:00:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:00:21 INFO - 'opt_config_files': (), 18:00:21 INFO - 'pip_index': False, 18:00:21 INFO - 'sps_profile': False, 18:00:21 INFO - 'sps_profile_interval': 0, 18:00:21 INFO - 'suite': 'dromaeojs', 18:00:21 INFO - 'system_bits': '32', 18:00:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:00:21 INFO - 'title': 'talos-linux64-ix-021', 18:00:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:00:21 INFO - 'use_talos_json': True, 18:00:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:00:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:00:21 INFO - 'work_dir': 'build'} 18:00:21 INFO - ##### 18:00:21 INFO - ##### Running clobber step. 18:00:21 INFO - ##### 18:00:21 INFO - Running pre-action listener: _resource_record_pre_action 18:00:21 INFO - Running main action method: clobber 18:00:21 INFO - rmtree: /builds/slave/test/build 18:00:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:00:21 INFO - Running post-action listener: _resource_record_post_action 18:00:21 INFO - ##### 18:00:21 INFO - ##### Finished clobber step (success) 18:00:21 INFO - ##### 18:00:21 INFO - ##### 18:00:21 INFO - ##### Running read-buildbot-config step. 18:00:21 INFO - ##### 18:00:21 INFO - Running pre-action listener: _resource_record_pre_action 18:00:21 INFO - Running main action method: read_buildbot_config 18:00:21 INFO - Using buildbot properties: 18:00:21 INFO - { 18:00:21 INFO - "project": "", 18:00:21 INFO - "product": "firefox", 18:00:21 INFO - "script_repo_revision": "production", 18:00:21 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 18:00:21 INFO - "repository": "", 18:00:21 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 18:00:21 INFO - "buildid": "20160616163123", 18:00:21 INFO - "pgo_build": "False", 18:00:21 INFO - "basedir": "/builds/slave/test", 18:00:21 INFO - "buildnumber": 118, 18:00:21 INFO - "slavename": "talos-linux64-ix-021", 18:00:21 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:00:21 INFO - "platform": "ubuntu64_hw", 18:00:21 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 18:00:21 INFO - "branch": "mozilla-central", 18:00:21 INFO - "repo_path": "mozilla-central", 18:00:21 INFO - "revision": "05873df0c0c268e419a714b8a6204cb852049098", 18:00:21 INFO - "stage_platform": "linux64", 18:00:21 INFO - "builduid": "4da5b2e960374af499b5667814982984", 18:00:21 INFO - "slavebuilddir": "test" 18:00:21 INFO - } 18:00:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 18:00:21 INFO - Running post-action listener: _resource_record_post_action 18:00:21 INFO - ##### 18:00:21 INFO - ##### Finished read-buildbot-config step (success) 18:00:21 INFO - ##### 18:00:21 INFO - ##### 18:00:21 INFO - ##### Running download-and-extract step. 18:00:21 INFO - ##### 18:00:21 INFO - Running pre-action listener: _resource_record_pre_action 18:00:21 INFO - Running main action method: download_and_extract 18:00:21 INFO - mkdir: /builds/slave/test/build/tests 18:00:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:21 INFO - https://queue.taskcluster.net/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:00:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:00:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:00:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:00:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:00:22 INFO - Downloaded 1361 bytes. 18:00:22 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:00:22 INFO - Using the following test package requirements: 18:00:22 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 18:00:22 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:00:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:00:22 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:00:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:00:22 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:00:22 INFO - u'jsshell-linux-x86_64.zip'], 18:00:22 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:00:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:00:22 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 18:00:22 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:00:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:00:22 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:00:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:00:22 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:00:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:00:22 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:00:22 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:00:22 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:00:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:22 INFO - https://queue.taskcluster.net/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:00:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:00:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:00:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:00:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:00:23 INFO - Downloaded 22654279 bytes. 18:00:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:00:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:00:25 INFO - Return code: 0 18:00:25 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:00:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:25 INFO - https://queue.taskcluster.net/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:00:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:00:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:00:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:00:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:00:26 INFO - Downloaded 22654279 bytes. 18:00:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:00:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:00:27 INFO - Return code: 0 18:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:27 INFO - https://queue.taskcluster.net/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:00:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:00:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:00:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:00:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:00:29 INFO - Downloaded 14405384 bytes. 18:00:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:00:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 18:00:29 INFO - Return code: 0 18:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:29 INFO - https://queue.taskcluster.net/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:00:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:00:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:00:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:00:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:00:32 INFO - Downloaded 55446540 bytes. 18:00:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:00:32 INFO - mkdir: /builds/slave/test/properties 18:00:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:00:32 INFO - Writing to file /builds/slave/test/properties/build_url 18:00:32 INFO - Contents: 18:00:32 INFO - build_url:https://queue.taskcluster.net/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:00:32 INFO - Running post-action listener: _resource_record_post_action 18:00:32 INFO - Running post-action listener: set_extra_try_arguments 18:00:32 INFO - ##### 18:00:32 INFO - ##### Finished download-and-extract step (success) 18:00:32 INFO - ##### 18:00:32 INFO - ##### 18:00:32 INFO - ##### Running populate-webroot step. 18:00:32 INFO - ##### 18:00:32 INFO - Running pre-action listener: _resource_record_pre_action 18:00:32 INFO - Running main action method: populate_webroot 18:00:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:00:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:00:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:00:32 INFO - '--tppagecycles', 18:00:32 INFO - '7'], 18:00:32 INFO - 'tests': ['tsvgm']}}, 18:00:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:00:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:00:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:00:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:00:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:00:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:00:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:00:32 INFO - 'talos_options': ['--disable-e10s'], 18:00:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:00:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:00:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:00:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:00:32 INFO - 'talos_options': ['--disable-e10s'], 18:00:32 INFO - 'tests': ['damp', 'tps']}, 18:00:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:00:32 INFO - 'tests': ['damp', 'tps']}, 18:00:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:00:32 INFO - 'tests': ['dromaeo_dom']}, 18:00:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:00:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:00:32 INFO - 'tests': ['basic_compositor_video']}, 18:00:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:00:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:00:32 INFO - 'tests': ['a11yr', 18:00:32 INFO - 'ts_paint', 18:00:32 INFO - 'tpaint', 18:00:32 INFO - 'sessionrestore', 18:00:32 INFO - 'sessionrestore_no_auto_restore', 18:00:32 INFO - 'tabpaint']}, 18:00:32 INFO - 'other-e10s': {'tests': ['a11yr', 18:00:32 INFO - 'ts_paint', 18:00:32 INFO - 'tpaint', 18:00:32 INFO - 'sessionrestore', 18:00:32 INFO - 'sessionrestore_no_auto_restore', 18:00:32 INFO - 'tabpaint']}, 18:00:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:00:32 INFO - 'ts_paint', 18:00:32 INFO - 'tpaint', 18:00:32 INFO - 'sessionrestore', 18:00:32 INFO - 'sessionrestore_no_auto_restore', 18:00:32 INFO - 'tabpaint']}, 18:00:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:00:32 INFO - 'ts_paint', 18:00:32 INFO - 'tpaint', 18:00:32 INFO - 'sessionrestore', 18:00:32 INFO - 'sessionrestore_no_auto_restore', 18:00:32 INFO - 'tabpaint']}, 18:00:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:00:32 INFO - 'tests': ['a11yr', 18:00:32 INFO - 'ts_paint', 18:00:32 INFO - 'tpaint', 18:00:32 INFO - 'sessionrestore', 18:00:32 INFO - 'sessionrestore_no_auto_restore', 18:00:32 INFO - 'tabpaint']}, 18:00:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:00:32 INFO - 'tests': ['a11yr', 18:00:32 INFO - 'ts_paint', 18:00:32 INFO - 'tpaint', 18:00:32 INFO - 'sessionrestore', 18:00:32 INFO - 'sessionrestore_no_auto_restore', 18:00:32 INFO - 'tabpaint']}, 18:00:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:00:32 INFO - 'tests': ['tsvgx', 18:00:32 INFO - 'tsvgr_opacity', 18:00:32 INFO - 'tart', 18:00:32 INFO - 'tscrollx', 18:00:32 INFO - 'cart']}, 18:00:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:00:32 INFO - 'tsvgr_opacity', 18:00:32 INFO - 'tart', 18:00:32 INFO - 'tscrollx', 18:00:32 INFO - 'cart']}, 18:00:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:00:32 INFO - 'talos_options': ['--disable-e10s'], 18:00:32 INFO - 'tests': ['tp5o']}, 18:00:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:00:32 INFO - 'tests': ['tp5o']}, 18:00:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:00:32 INFO - 'talos_options': ['--disable-e10s', 18:00:32 INFO - '--xperf_path', 18:00:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:00:32 INFO - 'tests': ['tp5n']}, 18:00:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:00:32 INFO - 'talos_options': ['--xperf_path', 18:00:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:00:32 INFO - 'tests': ['tp5n']}}, 18:00:32 INFO - 'talos.zip': {'path': '', 18:00:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:00:32 INFO - Running post-action listener: _resource_record_post_action 18:00:32 INFO - ##### 18:00:32 INFO - ##### Finished populate-webroot step (success) 18:00:32 INFO - ##### 18:00:32 INFO - ##### 18:00:32 INFO - ##### Running create-virtualenv step. 18:00:32 INFO - ##### 18:00:32 INFO - Running pre-action listener: _resource_record_pre_action 18:00:32 INFO - Running main action method: create_virtualenv 18:00:32 INFO - Creating virtualenv /builds/slave/test/build/venv 18:00:32 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:00:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:00:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:00:32 INFO - Using real prefix '/usr' 18:00:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:00:33 INFO - Installing distribute.............................................................................................................................................................................................done. 18:00:35 INFO - Installing pip.................done. 18:00:35 INFO - Return code: 0 18:00:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2716906f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20971c0>, '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 0x20980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098550>, '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': 'df17445c3590ff7897617a0e00000225-1466125112.271062-518050420', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:00:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:00:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:00:35 INFO - Using env: {'DISPLAY': ':0', 18:00:35 INFO - 'HOME': '/home/cltbld', 18:00:35 INFO - 'LANG': 'en_US.UTF-8', 18:00:35 INFO - 'LANGUAGE': 'en_US:en', 18:00:35 INFO - 'LOGNAME': 'cltbld', 18:00:35 INFO - 'MAIL': '/var/mail/cltbld', 18:00:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:35 INFO - 'MOZ_NO_REMOTE': '1', 18:00:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:00:35 INFO - 'NO_EM_RESTART': '1', 18:00:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:00:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:00:35 INFO - 'PWD': '/builds/slave/test', 18:00:35 INFO - 'SHELL': '/bin/bash', 18:00:35 INFO - 'SHLVL': '1', 18:00:35 INFO - 'TERM': 'linux', 18:00:35 INFO - 'TMOUT': '86400', 18:00:35 INFO - 'USER': 'cltbld', 18:00:35 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1466125112.271062-518050420', 18:00:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:00:35 INFO - '_': '/tools/buildbot/bin/python'} 18:00:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:00:35 INFO - Cleaning up... 18:00:35 INFO - Return code: 0 18:00:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f2716906f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20971c0>, '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 0x20980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098550>, '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': 'df17445c3590ff7897617a0e00000225-1466125112.271062-518050420', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:00:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:00:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:00:35 INFO - Using env: {'DISPLAY': ':0', 18:00:35 INFO - 'HOME': '/home/cltbld', 18:00:35 INFO - 'LANG': 'en_US.UTF-8', 18:00:35 INFO - 'LANGUAGE': 'en_US:en', 18:00:35 INFO - 'LOGNAME': 'cltbld', 18:00:35 INFO - 'MAIL': '/var/mail/cltbld', 18:00:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:35 INFO - 'MOZ_NO_REMOTE': '1', 18:00:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:00:35 INFO - 'NO_EM_RESTART': '1', 18:00:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:00:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:00:35 INFO - 'PWD': '/builds/slave/test', 18:00:35 INFO - 'SHELL': '/bin/bash', 18:00:35 INFO - 'SHLVL': '1', 18:00:35 INFO - 'TERM': 'linux', 18:00:35 INFO - 'TMOUT': '86400', 18:00:35 INFO - 'USER': 'cltbld', 18:00:35 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1466125112.271062-518050420', 18:00:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:00:35 INFO - '_': '/tools/buildbot/bin/python'} 18:00:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:35 INFO - Downloading/unpacking psutil>=3.1.1 18:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:00:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:00:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:00:37 INFO - Installing collected packages: psutil 18:00:37 INFO - Running setup.py install for psutil 18:00:37 INFO - building 'psutil._psutil_linux' extension 18:00:37 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 18:00:38 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 18:00:38 INFO - building 'psutil._psutil_posix' extension 18:00:38 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 18:00:38 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 18:00:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:00:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:00:38 INFO - Successfully installed psutil 18:00:38 INFO - Cleaning up... 18:00:38 INFO - Return code: 0 18:00:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:00:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2716906f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20971c0>, '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 0x20980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098550>, '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': 'df17445c3590ff7897617a0e00000225-1466125112.271062-518050420', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:00:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:00:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:00:38 INFO - Using env: {'DISPLAY': ':0', 18:00:38 INFO - 'HOME': '/home/cltbld', 18:00:38 INFO - 'LANG': 'en_US.UTF-8', 18:00:38 INFO - 'LANGUAGE': 'en_US:en', 18:00:38 INFO - 'LOGNAME': 'cltbld', 18:00:38 INFO - 'MAIL': '/var/mail/cltbld', 18:00:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:38 INFO - 'MOZ_NO_REMOTE': '1', 18:00:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:00:38 INFO - 'NO_EM_RESTART': '1', 18:00:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:00:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:00:38 INFO - 'PWD': '/builds/slave/test', 18:00:38 INFO - 'SHELL': '/bin/bash', 18:00:38 INFO - 'SHLVL': '1', 18:00:38 INFO - 'TERM': 'linux', 18:00:38 INFO - 'TMOUT': '86400', 18:00:38 INFO - 'USER': 'cltbld', 18:00:38 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1466125112.271062-518050420', 18:00:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:00:38 INFO - '_': '/tools/buildbot/bin/python'} 18:00:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:00:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:00:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:00:40 INFO - Installing collected packages: mozsystemmonitor 18:00:40 INFO - Running setup.py install for mozsystemmonitor 18:00:40 INFO - Successfully installed mozsystemmonitor 18:00:40 INFO - Cleaning up... 18:00:40 INFO - Return code: 0 18:00:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:00: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')]} 18:00:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00: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')]} 18:00:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f2716906f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20971c0>, '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 0x20980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098550>, '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': 'df17445c3590ff7897617a0e00000225-1466125112.271062-518050420', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:00:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:00:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:00:40 INFO - Using env: {'DISPLAY': ':0', 18:00:40 INFO - 'HOME': '/home/cltbld', 18:00:40 INFO - 'LANG': 'en_US.UTF-8', 18:00:40 INFO - 'LANGUAGE': 'en_US:en', 18:00:40 INFO - 'LOGNAME': 'cltbld', 18:00:40 INFO - 'MAIL': '/var/mail/cltbld', 18:00:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:40 INFO - 'MOZ_NO_REMOTE': '1', 18:00:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:00:40 INFO - 'NO_EM_RESTART': '1', 18:00:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:00:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:00:40 INFO - 'PWD': '/builds/slave/test', 18:00:40 INFO - 'SHELL': '/bin/bash', 18:00:40 INFO - 'SHLVL': '1', 18:00:40 INFO - 'TERM': 'linux', 18:00:40 INFO - 'TMOUT': '86400', 18:00:40 INFO - 'USER': 'cltbld', 18:00:40 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1466125112.271062-518050420', 18:00:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:00:40 INFO - '_': '/tools/buildbot/bin/python'} 18:00:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:40 INFO - Downloading/unpacking jsonschema==2.5.1 18:00:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:00:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:00:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:43 INFO - Downloading functools32-3.2.3-2.tar.gz 18:00:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:00:43 INFO - warning: no files found matching '*.txt' 18:00:43 INFO - no previously-included directories found matching 'build' 18:00:43 INFO - no previously-included directories found matching 'dist' 18:00:43 INFO - no previously-included directories found matching '.git*' 18:00:43 INFO - Installing collected packages: jsonschema, functools32 18:00:43 INFO - Running setup.py install for functools32 18:00:43 INFO - warning: no files found matching '*.txt' 18:00:43 INFO - no previously-included directories found matching 'build' 18:00:43 INFO - no previously-included directories found matching 'dist' 18:00:43 INFO - no previously-included directories found matching '.git*' 18:00:43 INFO - Successfully installed jsonschema functools32 18:00:43 INFO - Cleaning up... 18:00:43 INFO - Return code: 0 18:00:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:00:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2716906f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20971c0>, '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 0x20980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098550>, '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': 'df17445c3590ff7897617a0e00000225-1466125112.271062-518050420', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:00:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:00:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:00:43 INFO - Using env: {'DISPLAY': ':0', 18:00:43 INFO - 'HOME': '/home/cltbld', 18:00:43 INFO - 'LANG': 'en_US.UTF-8', 18:00:43 INFO - 'LANGUAGE': 'en_US:en', 18:00:43 INFO - 'LOGNAME': 'cltbld', 18:00:43 INFO - 'MAIL': '/var/mail/cltbld', 18:00:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:43 INFO - 'MOZ_NO_REMOTE': '1', 18:00:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:00:43 INFO - 'NO_EM_RESTART': '1', 18:00:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:00:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:00:43 INFO - 'PWD': '/builds/slave/test', 18:00:43 INFO - 'SHELL': '/bin/bash', 18:00:43 INFO - 'SHLVL': '1', 18:00:43 INFO - 'TERM': 'linux', 18:00:43 INFO - 'TMOUT': '86400', 18:00:43 INFO - 'USER': 'cltbld', 18:00:43 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1466125112.271062-518050420', 18:00:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:00:43 INFO - '_': '/tools/buildbot/bin/python'} 18:00:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:43 INFO - Downloading/unpacking blobuploader==1.2.4 18:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:45 INFO - Downloading blobuploader-1.2.4.tar.gz 18:00:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:00:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:00:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:00:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:46 INFO - Downloading docopt-0.6.1.tar.gz 18:00:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:00:46 INFO - Installing collected packages: blobuploader, requests, docopt 18:00:46 INFO - Running setup.py install for blobuploader 18:00:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:00:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:00:46 INFO - Running setup.py install for requests 18:00:46 INFO - Running setup.py install for docopt 18:00:46 INFO - Successfully installed blobuploader requests docopt 18:00:46 INFO - Cleaning up... 18:00:46 INFO - Return code: 0 18:00:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:00:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2716906f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20971c0>, '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 0x20980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098550>, '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': 'df17445c3590ff7897617a0e00000225-1466125112.271062-518050420', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:00:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:00:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:00:46 INFO - Using env: {'DISPLAY': ':0', 18:00:46 INFO - 'HOME': '/home/cltbld', 18:00:46 INFO - 'LANG': 'en_US.UTF-8', 18:00:46 INFO - 'LANGUAGE': 'en_US:en', 18:00:46 INFO - 'LOGNAME': 'cltbld', 18:00:46 INFO - 'MAIL': '/var/mail/cltbld', 18:00:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:46 INFO - 'MOZ_NO_REMOTE': '1', 18:00:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:00:46 INFO - 'NO_EM_RESTART': '1', 18:00:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:00:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:00:46 INFO - 'PWD': '/builds/slave/test', 18:00:46 INFO - 'SHELL': '/bin/bash', 18:00:46 INFO - 'SHLVL': '1', 18:00:46 INFO - 'TERM': 'linux', 18:00:46 INFO - 'TMOUT': '86400', 18:00:46 INFO - 'USER': 'cltbld', 18:00:46 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1466125112.271062-518050420', 18:00:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:00:46 INFO - '_': '/tools/buildbot/bin/python'} 18:00:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:00:47 INFO - Running setup.py (path:/tmp/pip-jiRi77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:00:47 INFO - Running setup.py (path:/tmp/pip-G28G0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:00:47 INFO - Running setup.py (path:/tmp/pip-gh4oB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:00:47 INFO - Running setup.py (path:/tmp/pip-YCXHWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:00:47 INFO - Running setup.py (path:/tmp/pip-p1K1Hh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:00:47 INFO - Running setup.py (path:/tmp/pip-eGwLCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:00:47 INFO - Running setup.py (path:/tmp/pip-9y7U1J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:00:47 INFO - Running setup.py (path:/tmp/pip-Sud_2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:00:47 INFO - Running setup.py (path:/tmp/pip-qinHmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:00:47 INFO - Running setup.py (path:/tmp/pip-EqLn_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:00:47 INFO - Running setup.py (path:/tmp/pip-04tbSt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:00:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:00:47 INFO - Running setup.py (path:/tmp/pip-naJUr9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:00:48 INFO - Running setup.py (path:/tmp/pip-Fe5jWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:00:48 INFO - Running setup.py (path:/tmp/pip-4XpOVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:00:48 INFO - Running setup.py (path:/tmp/pip-HlVK6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:00:48 INFO - Running setup.py (path:/tmp/pip-lICjvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:00:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:00:48 INFO - Running setup.py (path:/tmp/pip-oiw1hX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:00:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:00:48 INFO - Running setup.py install for manifestparser 18:00:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:00:48 INFO - Running setup.py install for mozcrash 18:00:48 INFO - Running setup.py install for mozdebug 18:00:48 INFO - Running setup.py install for mozdevice 18:00:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:00:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:00:48 INFO - Running setup.py install for mozfile 18:00:48 INFO - Running setup.py install for mozhttpd 18:00:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:00:48 INFO - Running setup.py install for mozinfo 18:00:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:00:49 INFO - Running setup.py install for mozInstall 18:00:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:00:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:00:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:00:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:00:49 INFO - Running setup.py install for mozleak 18:00:49 INFO - Running setup.py install for mozlog 18:00:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:00:49 INFO - Running setup.py install for moznetwork 18:00:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:00:49 INFO - Running setup.py install for mozprocess 18:00:49 INFO - Running setup.py install for mozprofile 18:00:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:00:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:00:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:00:49 INFO - Running setup.py install for mozrunner 18:00:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:00:49 INFO - Running setup.py install for mozscreenshot 18:00:49 INFO - Running setup.py install for moztest 18:00:50 INFO - Running setup.py install for mozversion 18:00:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:00:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:00:50 INFO - Cleaning up... 18:00:50 INFO - Return code: 0 18:00:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:50 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 0x7f2716906f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20971c0>, '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 0x20980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098550>, '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': 'df17445c3590ff7897617a0e00000225-1466125112.271062-518050420', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:00:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:00:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:00:50 INFO - Using env: {'DISPLAY': ':0', 18:00:50 INFO - 'HOME': '/home/cltbld', 18:00:50 INFO - 'LANG': 'en_US.UTF-8', 18:00:50 INFO - 'LANGUAGE': 'en_US:en', 18:00:50 INFO - 'LOGNAME': 'cltbld', 18:00:50 INFO - 'MAIL': '/var/mail/cltbld', 18:00:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:50 INFO - 'MOZ_NO_REMOTE': '1', 18:00:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:00:50 INFO - 'NO_EM_RESTART': '1', 18:00:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:00:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:00:50 INFO - 'PWD': '/builds/slave/test', 18:00:50 INFO - 'SHELL': '/bin/bash', 18:00:50 INFO - 'SHLVL': '1', 18:00:50 INFO - 'TERM': 'linux', 18:00:50 INFO - 'TMOUT': '86400', 18:00:50 INFO - 'USER': 'cltbld', 18:00:50 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1466125112.271062-518050420', 18:00:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:00:50 INFO - '_': '/tools/buildbot/bin/python'} 18:00:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:00:50 INFO - Running setup.py (path:/tmp/pip-ar_wgs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:00:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:00:50 INFO - Running setup.py (path:/tmp/pip-67XbOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:00:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:00:50 INFO - Running setup.py (path:/tmp/pip-rGOo65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:00:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:00:50 INFO - Running setup.py (path:/tmp/pip-pHPWNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:00:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:00:50 INFO - Running setup.py (path:/tmp/pip-rPhw_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:00:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:00:50 INFO - Running setup.py (path:/tmp/pip-2pG2N8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:00:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:00:50 INFO - Running setup.py (path:/tmp/pip-RQPzo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:00:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:00:50 INFO - Running setup.py (path:/tmp/pip-xp5oOM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:00:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:00:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:00:50 INFO - Running setup.py (path:/tmp/pip-fWflCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:00:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:00:51 INFO - Running setup.py (path:/tmp/pip-4_XNlk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:00:51 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)) 18:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:00:51 INFO - Running setup.py (path:/tmp/pip-pOiF5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:00:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:00:51 INFO - Running setup.py (path:/tmp/pip-39Uf2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:00:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:00:51 INFO - Running setup.py (path:/tmp/pip-MImUGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:00:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:00:51 INFO - Running setup.py (path:/tmp/pip-WO1bZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:00:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:00:51 INFO - Running setup.py (path:/tmp/pip-eH3CQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:00:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:00:51 INFO - Running setup.py (path:/tmp/pip-9WEANu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:00:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:00:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:00:51 INFO - Running setup.py (path:/tmp/pip-6fz3zQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:00:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:00:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:00:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:00:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:00:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:00:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:00:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:53 INFO - Downloading blessings-1.6.tar.gz 18:00:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:00:53 INFO - Installing collected packages: blessings 18:00:53 INFO - Running setup.py install for blessings 18:00:53 INFO - Successfully installed blessings 18:00:53 INFO - Cleaning up... 18:00:53 INFO - Return code: 0 18:00:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:00:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:00:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:00:53 INFO - Reading from file tmpfile_stdout 18:00:53 INFO - Current package versions: 18:00:53 INFO - argparse == 1.2.1 18:00:53 INFO - blessings == 1.6 18:00:53 INFO - blobuploader == 1.2.4 18:00:53 INFO - docopt == 0.6.1 18:00:53 INFO - functools32 == 3.2.3-2 18:00:53 INFO - jsonschema == 2.5.1 18:00:53 INFO - manifestparser == 1.1 18:00:53 INFO - mozInstall == 1.12 18:00:53 INFO - mozcrash == 0.17 18:00:53 INFO - mozdebug == 0.1 18:00:53 INFO - mozdevice == 0.48 18:00:53 INFO - mozfile == 1.2 18:00:53 INFO - mozhttpd == 0.7 18:00:53 INFO - mozinfo == 0.9 18:00:53 INFO - mozleak == 0.1 18:00:53 INFO - mozlog == 3.2 18:00:53 INFO - moznetwork == 0.27 18:00:53 INFO - mozprocess == 0.22 18:00:53 INFO - mozprofile == 0.28 18:00:53 INFO - mozrunner == 6.11 18:00:53 INFO - mozscreenshot == 0.1 18:00:53 INFO - mozsystemmonitor == 0.3 18:00:53 INFO - moztest == 0.7 18:00:53 INFO - mozversion == 1.4 18:00:53 INFO - psutil == 3.1.1 18:00:53 INFO - requests == 1.2.3 18:00:53 INFO - wsgiref == 0.1.2 18:00:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:53 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 0x7f2716906f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20971c0>, '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 0x20980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098550>, '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': 'df17445c3590ff7897617a0e00000225-1466125112.271062-518050420', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:00:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:00:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:00:53 INFO - Using env: {'DISPLAY': ':0', 18:00:53 INFO - 'HOME': '/home/cltbld', 18:00:53 INFO - 'LANG': 'en_US.UTF-8', 18:00:53 INFO - 'LANGUAGE': 'en_US:en', 18:00:53 INFO - 'LOGNAME': 'cltbld', 18:00:53 INFO - 'MAIL': '/var/mail/cltbld', 18:00:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:53 INFO - 'MOZ_NO_REMOTE': '1', 18:00:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:00:53 INFO - 'NO_EM_RESTART': '1', 18:00:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:00:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:00:53 INFO - 'PWD': '/builds/slave/test', 18:00:53 INFO - 'SHELL': '/bin/bash', 18:00:53 INFO - 'SHLVL': '1', 18:00:53 INFO - 'TERM': 'linux', 18:00:53 INFO - 'TMOUT': '86400', 18:00:53 INFO - 'USER': 'cltbld', 18:00:53 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1466125112.271062-518050420', 18:00:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:00:53 INFO - '_': '/tools/buildbot/bin/python'} 18:00:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:00:54 INFO - Cleaning up... 18:00:54 INFO - Return code: 0 18:00:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:00:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:54 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 0x7f2716906f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20971c0>, '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 0x20980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098550>, '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': 'df17445c3590ff7897617a0e00000225-1466125112.271062-518050420', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:00:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:00:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:00:54 INFO - Using env: {'DISPLAY': ':0', 18:00:54 INFO - 'HOME': '/home/cltbld', 18:00:54 INFO - 'LANG': 'en_US.UTF-8', 18:00:54 INFO - 'LANGUAGE': 'en_US:en', 18:00:54 INFO - 'LOGNAME': 'cltbld', 18:00:54 INFO - 'MAIL': '/var/mail/cltbld', 18:00:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:54 INFO - 'MOZ_NO_REMOTE': '1', 18:00:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:00:54 INFO - 'NO_EM_RESTART': '1', 18:00:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:00:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:00:54 INFO - 'PWD': '/builds/slave/test', 18:00:54 INFO - 'SHELL': '/bin/bash', 18:00:54 INFO - 'SHLVL': '1', 18:00:54 INFO - 'TERM': 'linux', 18:00:54 INFO - 'TMOUT': '86400', 18:00:54 INFO - 'USER': 'cltbld', 18:00:54 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1466125112.271062-518050420', 18:00:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:00:54 INFO - '_': '/tools/buildbot/bin/python'} 18:00:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:00:54 INFO - Cleaning up... 18:00:54 INFO - Return code: 0 18:00:54 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:00:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:00:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:00:54 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 0x7f2716906f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2008350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20971c0>, '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 0x20980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098550>, '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': 'df17445c3590ff7897617a0e00000225-1466125112.271062-518050420', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 18:00:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 18:00:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 18:00:54 INFO - Using env: {'DISPLAY': ':0', 18:00:54 INFO - 'HOME': '/home/cltbld', 18:00:54 INFO - 'LANG': 'en_US.UTF-8', 18:00:54 INFO - 'LANGUAGE': 'en_US:en', 18:00:54 INFO - 'LOGNAME': 'cltbld', 18:00:54 INFO - 'MAIL': '/var/mail/cltbld', 18:00:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:54 INFO - 'MOZ_NO_REMOTE': '1', 18:00:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:00:54 INFO - 'NO_EM_RESTART': '1', 18:00:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:00:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:00:54 INFO - 'PWD': '/builds/slave/test', 18:00:54 INFO - 'SHELL': '/bin/bash', 18:00:54 INFO - 'SHLVL': '1', 18:00:54 INFO - 'TERM': 'linux', 18:00:54 INFO - 'TMOUT': '86400', 18:00:54 INFO - 'USER': 'cltbld', 18:00:54 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1466125112.271062-518050420', 18:00:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:00:54 INFO - '_': '/tools/buildbot/bin/python'} 18:00:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:54 INFO - Downloading/unpacking flake8 18:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:56 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:00:56 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:00:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:56 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:00:56 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:00:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:56 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:00:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:00:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:00:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:00:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:00:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:00:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:00:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:00:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:00:57 INFO - no previously-included directories found matching 'docs/_build' 18:00:57 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:00:57 INFO - Running setup.py install for pep8 18:00:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:00:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:00:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:00:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:00:57 INFO - no previously-included directories found matching 'docs/_build' 18:00:57 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:00:57 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:00:57 INFO - Cleaning up... 18:00:57 INFO - Return code: 0 18:00:57 INFO - Running post-action listener: _resource_record_post_action 18:00:57 INFO - Running post-action listener: _start_resource_monitoring 18:00:57 INFO - Starting resource monitoring. 18:00:57 INFO - ##### 18:00:57 INFO - ##### Finished create-virtualenv step (success) 18:00:57 INFO - ##### 18:00:57 INFO - ##### 18:00:57 INFO - ##### Running install step. 18:00:57 INFO - ##### 18:00:57 INFO - Running pre-action listener: _resource_record_pre_action 18:00:57 INFO - Running main action method: install 18:00:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:00:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:00:57 INFO - Reading from file tmpfile_stdout 18:00:57 INFO - Detecting whether we're running mozinstall >=1.0... 18:00:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:00:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:00:57 INFO - Reading from file tmpfile_stdout 18:00:57 INFO - Output received: 18:00:57 INFO - Usage: mozinstall [options] installer 18:00:57 INFO - Options: 18:00:57 INFO - -h, --help show this help message and exit 18:00:57 INFO - -d DEST, --destination=DEST 18:00:57 INFO - Directory to install application into. [default: 18:00:57 INFO - "/builds/slave/test"] 18:00:57 INFO - --app=APP Application being installed. [default: firefox] 18:00:57 INFO - mkdir: /builds/slave/test/build/application 18:00:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 18:00:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 18:01:14 INFO - Reading from file tmpfile_stdout 18:01:14 INFO - Output received: 18:01:14 INFO - /builds/slave/test/build/application/firefox/firefox 18:01:14 INFO - Running post-action listener: _resource_record_post_action 18:01:14 INFO - ##### 18:01:14 INFO - ##### Finished install step (success) 18:01:14 INFO - ##### 18:01:14 INFO - ##### 18:01:14 INFO - ##### Running run-tests step. 18:01:14 INFO - ##### 18:01:14 INFO - Running pre-action listener: _resource_record_pre_action 18:01:14 INFO - Running main action method: run_tests 18:01:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:01:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:01:14 INFO - Python 2.7.3 18:01:14 INFO - Return code: 0 18:01:14 INFO - grabbing minidump binary from tooltool 18:01:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:01:14 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 0x20971c0>, '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 0x20980c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2098550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:01:14 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:01:14 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:01:14 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 18:01:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:01:14 INFO - Return code: 0 18:01:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:01:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:01:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:01:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:01:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:01:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:01:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:01:15 INFO - Return code: 0 18:01:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:01:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-021 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:01:15 INFO - Using env: {'DISPLAY': ':0', 18:01:15 INFO - 'HOME': '/home/cltbld', 18:01:15 INFO - 'LANG': 'en_US.UTF-8', 18:01:15 INFO - 'LANGUAGE': 'en_US:en', 18:01:15 INFO - 'LOGNAME': 'cltbld', 18:01:15 INFO - 'MAIL': '/var/mail/cltbld', 18:01:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:01:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:01:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:15 INFO - 'MOZ_NO_REMOTE': '1', 18:01:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:01:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:01:15 INFO - 'NO_EM_RESTART': '1', 18:01:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:01:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:15 INFO - 'PWD': '/builds/slave/test', 18:01:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:01:15 INFO - 'SHELL': '/bin/bash', 18:01:15 INFO - 'SHLVL': '1', 18:01:15 INFO - 'TERM': 'linux', 18:01:15 INFO - 'TMOUT': '86400', 18:01:15 INFO - 'USER': 'cltbld', 18:01:15 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1466125112.271062-518050420', 18:01:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:15 INFO - '_': '/tools/buildbot/bin/python'} 18:01:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:01:15 INFO - mozversion application_buildid: 20160616163123 18:01:15 INFO - mozversion application_changeset: 05873df0c0c268e419a714b8a6204cb852049098 18:01:15 INFO - mozversion application_display_name: Nightly 18:01:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:01:15 INFO - mozversion application_name: Firefox 18:01:15 INFO - mozversion application_remotingname: firefox 18:01:15 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:01:15 INFO - mozversion application_vendor: Mozilla 18:01:15 INFO - mozversion application_version: 50.0a1 18:01:15 INFO - mozversion platform_buildid: 20160616163123 18:01:15 INFO - mozversion platform_changeset: 05873df0c0c268e419a714b8a6204cb852049098 18:01:15 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:01:15 INFO - mozversion platform_version: 50.0a1 18:01:15 INFO - using testdate: 1466125275 18:01:15 INFO - actual date: 1466125275 18:01:15 INFO - starting webserver on 'localhost:49530' 18:01:15 INFO - SUITE-START | Running 2 tests 18:01:15 INFO - TEST-START | dromaeo_css 18:01:15 INFO - operating with platform_type : linux_ 18:01:15 INFO - Initialising browser for dromaeo_css test... 18:01:15 INFO - TEST-INFO | started process 4024 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppcCB9T/profile http://localhost:49530/getInfo.html) 18:01:21 INFO - PROCESS | 4024 | __metrics Screen width/height:1600/1200 18:01:21 INFO - PROCESS | 4024 | colorDepth:24 18:01:21 INFO - PROCESS | 4024 | Browser inner width/height: 1024/697 18:01:21 INFO - PROCESS | 4024 | __metrics 18:01:22 INFO - PROCESS | 4024 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:01:23 INFO - TEST-INFO | 4024: exit 0 18:01:23 INFO - Browser initialized. 18:01:23 INFO - Running cycle 1/1 for dromaeo_css test... 18:01:23 INFO - TEST-INFO | started process 4105 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppcCB9T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:01:36 INFO - PROCESS | 4105 | RSS: Main: 175452160 18:01:36 INFO - PROCESS | 4105 | 18:02:27 INFO - PROCESS | 4105 | 1466125347423 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:04:40 INFO - PROCESS | 4105 | Cycle 1(1): loaded http://localhost:49530/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49530/tests/dromaeo/cssquery-ext.html) 18:04:42 INFO - PROCESS | 4105 | RSS: Main: 296374272 18:04:42 INFO - PROCESS | 4105 | 18:07:44 INFO - PROCESS | 4105 | Cycle 1(1): loaded http://localhost:49530/tests/dromaeo/cssquery-ext.html (next: http://localhost:49530/tests/dromaeo/cssquery-jquery.html) 18:07:45 INFO - PROCESS | 4105 | RSS: Main: 253263872 18:07:45 INFO - PROCESS | 4105 | 18:10:47 INFO - PROCESS | 4105 | Cycle 1(1): loaded http://localhost:49530/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49530/tests/dromaeo/cssquery-mootools.html) 18:10:48 INFO - PROCESS | 4105 | RSS: Main: 256917504 18:10:48 INFO - PROCESS | 4105 | 18:13:50 INFO - PROCESS | 4105 | Cycle 1(1): loaded http://localhost:49530/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49530/tests/dromaeo/cssquery-prototype.html) 18:13:51 INFO - PROCESS | 4105 | RSS: Main: 257114112 18:13:51 INFO - PROCESS | 4105 | 18:16:54 INFO - PROCESS | 4105 | Cycle 1(1): loaded http://localhost:49530/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49530/tests/dromaeo/cssquery-yui.html) 18:16:54 INFO - PROCESS | 4105 | RSS: Main: 229941248 18:16:54 INFO - PROCESS | 4105 | 18:19:57 INFO - PROCESS | 4105 | Cycle 1(1): loaded http://localhost:49530/tests/dromaeo/cssquery-yui.html (next: http://localhost:49530/tests/dromaeo/cssquery-dojo.html) 18:19:57 INFO - PROCESS | 4105 | RSS: Main: 276258816 18:19:57 INFO - PROCESS | 4105 | 18:19:57 INFO - PROCESS | 4105 | __start_tp_report 18:19:57 INFO - PROCESS | 4105 | _x_x_mozilla_page_load 18:19:57 INFO - PROCESS | 4105 | _x_x_mozilla_page_load_details 18:19:57 INFO - PROCESS | 4105 | |i|pagename|runs| 18:19:57 INFO - PROCESS | 4105 | |0;dojo.html;2645.4444566641005;2661.9467610647775;2692.08469120499;2699.7840172786177;2705.6482657254564;4682.555157260063;4744.335792988975;4772.73749943753;4837.226043832983;4879.292502587127;4696.835610753625;4703.576678098971;4841.249606311018;4880.463149053601;4886.144924638183;4281.229378711839;4365.43249377582;4368.453943257093;4380.167768124046;4483.663725220606;4410.977945110286;4426.911461770763;4439.822407103728;4462.44219472566;4478.484974227957;4234.386014027979;4266.488021437416;4273.444452221192;4409.206342858284;4451.043025749479;4446.110777844444;4450.043240703264;4451.243288640939;4456.309272062836;4508.436445444319;4489.708168969007;4499.527549607277;4499.662525310615;4501.617362524189;4540.18276710192;4647.512011238805;4775.164346239429;4822.831200907986;4914.434839993411;4959.355283813117;5225.268462415265;5290.894182116374;5298.15229563268;5308.681479111266;5316.415194328621;5304.946950530484;5336.1195402758885;5347.41178470368;5352.036633405985;5354.544863686552;4687.742174180453;4772.665913386099;4879.194932836113;4888.877778055506;4941.802327906916;5373.543248823894;5406.810501689622;5433.972830135824;5434.864128396822;5439.972800135974;5147.948520514747;5179.637425380187;5197.090509160882;5202.401723801736;5204.42751297357;5037.093323201786;5153.227015947563;5212.113940630026;5223.190405487156;5236.9738151309;5174.24973378858;5200.5059519345605;5213.087709650796;5217.7912883485;5272.841814745564;5042.6470147090085;5174.146265866165;5186.066508028515;5191.532762051434;5193.324867767166;4396.384506169139;4407.47110346754;4441.911161776747;4456.264716321771;4489.0572979674635;3860.6911447084167;3862.439946207784;3877.1082650989865;3914.8825535233987;3918.275119102918;4445.599896009375;4452.977735111303;4453.532379100213;4456.24243878535;4499.460064792246;5117.565006974374;5157.613179011589;5197.142471492161;5223.973880130575;5238.869028274247;4400.801963911567;4440.555944405598;4441.733495990251;4443.355713421536;4485.551444855489;4589.908201835945;4708.434987801417;4714;4717.646176536773;4769.904601907942;4793.13723529768;4797.112534181188;4808.447028591757;4811.615070794398;4819.084373968934;5132.871678208075;5199.038177937094;5224.346956630421;5225.320708307896;5272.55183309423;4817.5182481752945;4860.684055536379;4871.683340582851;4875.366202393666;4918.409790825124;5151.278820965143;5168.53483186515;5170.870728231674;5172.818951336685;5256.211568264791;5233.927044955851;5242.554382877345;5248.396434409939;5248.448912864247;5302.549283310962;4944.925826112683;5069.2902993581665;5079.390473143247;5111.028904508132;5122.385313762373;5107.795688172431;5122.359705036871;5127.307813445137;5131.769070391767;5169.7673604687125;5010.348654674869;5051.191809310492;5057.216131499624;5065.13892638245;5065.240213967934;4873.707577545359;4933.432655244693;5005.749712514433;5028.899422011613;5046.444891061907;4958.2810492477665;4973.900521989613;4983.152864013054;4984.651074424901;4985.326980857538 18:19:57 INFO - PROCESS | 4105 | |1;ext.html;542.264291124523;578.2742657964255;586.4868240289746;596.0284735880514;604.6976511744128;26154.346141346454;28897.999999999975;28916;28938.710612893865;28958.855205723994;28616.14151575456;38116.42825357615;39177.21645567094;39243.41134882979;43673;7820.217978202177;7927.762167134995;7931.682732690685;7940.880886786703;7947.7218297359605;7472.626368681545;7856.803579910491;7939.325157361631;7942.245486678757;7942.444028917978;10083;10323.638672646459;10326.896731032706;10632.681019569425;10636.361818290927;10115;10200.591976320939;10206.591736330538;10206.744831379237;10206.846897296546;7508.737194198176;10034.648787292483;10085.596576136946;10164.847527287096;10393;34859.128521787155;38634.613653863664;38642.61357386418;38645.806770965966;38659;3051.8931837385585;3055.4958431858718;3112.8754849805982;3189.3621275744713;3240.659730728263;29047.854760726274;29532.409351812843;29850.85074574635;29899.70100299013;30420.84789576038;3286.8685252589867;3293.6212335581477;3305.454599991023;3329.467285234375;3330.217398911254;251120;252785;253101;253633;253908.99999999817;55998.440015600136;56604;56706.716466417405;56717.71641141851;56760.43239567634;60933.390666093655;61258.69370653118;61353;61396.386036139964;61404;12527.93736031314;12541;12652.81020784689;12696.301703406401;12698.42857071434;9882.654107106217;9989.500524973722;10001.449920254456;10012.899871001342;10014.849777253346;15784.447544335893;17991.466192995245;18096.45710628683;18383.72424413635;18436;6862.451003919675;6891.931080689229;6906.930930690729;7159.427245820322;7216.7113315466795;786.2491320788633;845.4631309118631;898.4384759525296;926.5321012888448;1021.5300961557618;26524.336891577866;27042.864785675945;27850;27893.58159627607;27914.441711165662;968.7384406210425;971.9076687714655;972.2027937091517;1007.9819382423658;1058.4125810175426;14108.858911410754;14560.708785824436;14719;14859.70280594382;15002.099874007594;367560;373982;374151;374860;375515;46797.85223625399;47086.76456617695;47110.05779884383;47134.29298560524;47288.817779555786;1168.293182624517;1169.7485040716288;1171.6719318590808;1173.7359094203737;1179.3926128044063;25832.870835645703;27102.72897271002;27375.315617109733;27596;27676.723232767414;40107;46647.06705865932;47126.76436617795;47144.528554714016;47188.29217561808;274079;275579;278884;278976;279976;21786.455338616663;22291.331260062223;22370.552588948456;25277;25687.871560642077;41212.793936031325;41648.375274371516;42025.36961945512;42096.579034209266;42431.36352954646;35379;35634.82182589071;35864;35979.10052248715;36188.45717314295;34095.82952085224;34900;34980.82509587538;34991;35498.64501354953;34277.65722342745;34624.82687586546;34720.305593887475;34886.65113348936;35277;30392.392152157274;31535.842320788248;31731.841340793148;31778.682213178497;31858;24553.877230613733;24646.260612181664;24670;24968.250952471455;25217.49565008653 18:19:57 INFO - PROCESS | 4105 | |2;jquery.html;369.93526132926746;376.57447084794177;378.37001392681185;380.69544364508386;388.23905145914006;15681.529554113366;16221.675566488679;17099.230534625956;17107.57231069221;17119.657606847886;14743.115413075224;15151.469698560553;15699.293531791069;15729.134897580629;15743.370265189378;4915.729634870081;5039.899202015958;5045.873853153664;5074.061298659742;5078.695278283296;3715.758475699071;4948.752562371867;5789.334226563936;6109.725062372204;6131.47882429994;6114.205153330083;6120.693965301717;6140.570160088796;6147.7233524491285;6168;5102.617303702219;5442.319710036246;5445.373782015079;5503.504684578367;5513.641613295123;3348.0458069450333;4892.3884514435695;5206.323177986876;5214.243934629495;5283.682979021271;11317.603883864025;11679.29924204542;11781.764364712657;11846.111541634413;11902.047836173171;1833.8807977481554;1892.176903047179;2169.6853956176265;2199.5820794049077;2365.7870791628834;8419.115992820727;9231.215346695539;9991.650292239738;10067.597296108146;13277.136986095873;1681.7813684221096;1700.4813531872774;1700.6718397501004;1714.1686282153146;1766.4788887278285;63992.68003659999;69464.30535694679;71437;72472.27527724761;72718;9807.13664403761;10021.899781002243;10216.540255688511;10224.64213752515;10517.579296828195;13545.796813047813;13733.45066197341;13749.725005500035;13752.174869507662;13758.93120534391;6717.462602991749;6740.62926539045;6911.308869113049;6954.374106330454;7027.227005029443;6245.687715614201;6354.332795056545;6420.646864422408;6430.903536446957;6439.066335381343;4130.504339479222;4239.59723826236;4254.85108021224;4353.825846966086;4379.64962802975;1675.6146086400197;1680.227095536067;1781.323097223047;1792.659394715;1831.3590243414699;392.70939504766267;395.87925682667066;396.2392206962639;413.2685147289281;788.2196625340871;10074.546645400975;10186.898131018595;10262.846057309147;10297.691069267934;10472.319299245524;397.05897024052604;409.0387589165497;409.2939679053609;429.55755571761136;429.86029540399494;5336.199570064521;5580.637258578179;5649.802256920988;5652.152177173457;5777.075667893117;363179;384531;397168;398865;399689;74985.6250718743;76610.2338976603;79198;80161.59919200484;81741.59129204316;423.819876552464;430.81905599648223;431.43266604415095;432.19611522568;433.3521385528592;8692.739217823475;9870;9971.45157016371;10176.287659863738;10399.844002340122;36312.72905448297;36413.63586364102;36989.26021479609;38528.2294354117;39259.21481570295;49517.50482495273;50344.74827625838;50367;50402;50449.747751261006;7528.774136775905;7705.691772329268;7732.342750866239;7733.381329493514;7771.067471903408;6900.171979362308;11431.885681143081;11563.884361156617;11780.293182409083;11781.882181178078;9010.189082982562;9563.713088607354;9727.708168754949;9926.25553083536;10479.214058945458;10060.949695251476;10193.337433066827;10297.279190456597;10299.227557933335;10415.895841041796;10652.307600005975;10708.411037393018;10716.35701857891;10775.946120269662;10840.295380800222;7606.391488681003;7650.1584825668015;7677.846443071219;7809.2190780921455;7960.800979975547;9410.764730881783;9959.352642078242;10424.895751042393;10494.737631559272;10501.369917804956 18:19:57 INFO - PROCESS | 4105 | |3;mootools.html;1208.5407545132848;1370.8492065872756;1415.271135365287;1456.5703117580313;1696.923638436271;6835.111435513382;7753.340966017889;7994.400419968497;8003.679852805881;8009.27916487516;7735.883961740578;7862.213778622135;7875;7879.133295337509;7885.053793544768;1875.493616723484;1940.922363105474;1949.9025048747576;2127.8936053197317;2159.88120653364;1938.2537722976615;2161.1139432832542;2227.5544891021777;2228.6099932511825;2244.7642997485273;2152.7201463809683;2174.586828502587;2198.120751699317;2202.6475763877784;2203.603351396748;1818.8544916406788;2097.9895100524477;2105.705201271823;2109.2512158183777;2109.2617583845686;2085.238887805955;2154.1814110638015;2155.8490905636613;2166.8483206175574;2168.447046003259;7912.920870791276;7935.246151615587;7939.841203175904;7941.205879412013;7966.44234903557;4313.245182093153;4434.68957172998;4445.288753799409;4445.755483448409;4445.9110817783785;2744.6157537944705;2853.2866783304175;2903.9274018149504;2918.9124326270035;2939.0448104366164;900.5497251374313;921.0605182713595;936.2697096264924;958.5111593087576;960.5293406230967;10924.2899211551;10925.453727313603;10935.015848573586;10943.343399396063;10950.178736594788;4189.832406703758;4236.131593023423;4334.089841133335;4364.890877728082;4368.912621747547;9238.58426370815;9282.628694852265;9282.978872324038;9285.210757085588;9289.117533834275;4449.621782148553;4467.15124126415;4495.190865644216;4504.09918016398;4504.256797628354;3487.3199726053176;3558.644135586421;3971.0469487322835;4026.154507553388;4026.6778657707314;3603.56757189139;3718.795466249382;3754.3617585010616;3761.4014044031724;3763.473113764075;1179.722765150189;1194.9103817213743;1202.2065436811838;1209.6008317255285;1213.4114954247184;792.2671528835918;795.2087672765635;810.1938571121772;812.8740045292989;813.341193633159;4976.576990955737;5040.445550989388;5240.4497527759795;5285.656432331886;5286.97356513215;380.9809509524568;382.08490664858067;382.12493390136274;397.72556935714266;405.5498396779572;3387.5257463955068;3400.4559270516597;3487.511748355232;3488.2151515908718;3490.842912068963;9331.813363732688;9570;9812.803743925224;9837.360571562825;9858.80282394348;9703.5148242589;9773.609055637695;9781.119699226962;9793.657221997197;9803.01969803014;7372.336489715844;7424.628768561551;7569.924300757032;7572.962135189288;7575.734849280359;5087.283288918148;5124.538791508782;5183.679756716792;5184.377874655065;5251.658642188246;9040.638374464948;9084.954575227082;9166.22087122602;9170;9183.724488265363;8885.600147993486;9171.495567743837;9369;9372.67195648149;9384.718458446258;6117;6557.344265573404;6701.530892837454;6815.897761533482;6916.481263905127;7644.197359277196;7648.08223013242;7676;7712.344450721527;7713.074431068308;6615.669216539058;6708.865822683617;6772.390484856386;6864.97025446187;6878.58728476293;6787;6812.693428795816;6827.180738311435;6854.862902741817;6972.930270697431;6220.066989951543;6223.222097237845;6293.181886354744;6293.748250070129;6300.905486417616;4308.590683885026;4430.800613972315;4499.122671079108;4545.068261006551;4608.124456353282;4597.080583883304;4610.423697037871;4679.883002924818;4704.57658810695;4786.736729479912 18:19:57 INFO - PROCESS | 4105 | |4;prototype.html;290.9912702618921;292.6853632345229;294.73915584707527;295.7205440858387;299.5940500621658;4909.63177761668;4918.827841025561;4921.384826896638;4930.679505832128;4936.925946110811;4815.940493091523;4836.661433699643;4838.951610483894;4858.611311095113;4878.463369029404;3602.963970360302;3741.4558100491654;3756.3426400379963;3759.642833930786;3764.1906989997146;3687.815609219542;3726.347889119393;3803.3344164771192;3837.6184573553496;3850.711196660248;3757.192203676209;3768.830402631874;3783.356829339019;3791.791451470168;3794.829232684522;3519.260955199411;3734.73856830022;3789.9621003789885;3803.315403227418;3805.1628641698644;3242.908324130946;3247.983760081208;3250.6749325067303;3250.951235731466;3254.1213872254357;4716.127516409475;4720.150372932871;4750.50119737426;4757.048590281957;4772.331873537708;4245.257080010986;4246.957530424687;4255.1064276501975;4258.53156152823;4266.248269865919;4949.232868905326;5057.140286151363;5066.569341605968;5086.847394578169;5126.359205099363;3540.6636369544854;3549.6450354964554;3572.267685124569;3577.123604716861;3585.3367127081574;5308.654937429054;5326.147816349366;5375.22059301403;5414.6480478769145;5447.754851031753;3498.2653642734804;3644.2893635740775;3721.7208709346905;3722.181120153562;3725.8882233533036;3699.9260014800093;3724.8510059597857;3729.1050147964293;3731.104534911656;3737.7009839212797;4265.6112576957;4278.294081476583;4298.4841818981295;4313.590208930146;4333.71830830995;4939.8271060513425;5089.796408143633;5125.538701516882;5126.92309615356;5152.407473140562;1285.6593002854252;1286.1575667937516;1292.360281660584;1295.33290355467;1298.5260379961435;801.5631480842954;811.342812322021;819.397742659145;833.7832163637377;839.0099682374856;4406.361077643724;4502.774861256924;4506.887327816831;4561.178987782165;4561.635069194456;5333.253344531769;5336.573074154058;5347.3583170019065;5360.4805741301525;5360.517553420211;4423.137488189836;4549.181147393501;4550.567696068868;4556.88607784808;4578.748168850745;2770.473610014091;2831.9433611328072;2833.8866445341955;2844.971550284512;2846.245744877609;4934.333864928189;4961.404631444249;4982.427020892571;4999.400071991385;5000.574951129122;5274.551663108678;5450.945490545044;5452.863678407992;5458.590605704588;5462.289902312754;4955.479674634112;4976.303317535548;4982.60139188864;4997.550220480101;4998.150314446553;5289.773238324542;5299.973500132629;5303.575713942798;5338.039152952506;5349.839504814861;4890.168671325812;5146.8970620588125;5169.379674438941;5189.4032186297545;5198.842480370755;4873.780679869344;4891.437484689306;4928.728919909296;4962.503749625009;4962.776675049704;3710.2208536206617;3716.2939041582013;3722.088088418355;3731.869384571527;3736.3461394255355;4948.678335908155;5249;5304.249597632849;5334.839954801362;5353.437889021752;5149.768260428365;5164.715940623302;5181.564664607628;5217.399999000012;5223.3209682741;5206.947930520646;5230.63385563007;5232.9738351308;5238.397584277857;5262.289590905179;4802.855914322576;5059.482155353453;5084.542391184811;5085.542301192911;5124.8206312778875;4978.975105124451;5116.9744151279;5159;5175.378954525481;5211.368259029317;5035.219540971156;5048.924266136084;5061.367329083864;5083.949160508348;5091.490850914879 18:19:57 INFO - PROCESS | 4105 | |5;yui.html;593.3777458579954;617.6355949989505;622.0423226881751;627.2379059442776;723.9239879812616;11407.258528195662;14024;14221.21783301918;14630.195339256337;14632.268386580668;13871.791923121162;14782.778258326134;14814.111153330809;14817.629559261024;14828.851711482908;629.9275583307932;780.7696729464822;793.0602236349915;794.0907660728462;794.630496818979;772.7449941519313;786.4101923557332;786.8937693411402;792.43341011177;793.9206079392072;817.4972391978949;819.2708489444399;820.6102101501768;821.4496287487368;824.5794644731201;807.2452257139594;810.1776696652931;811.6712731343815;811.9959400202961;812.1229072601576;594.3729365519375;594.5273507561499;594.7234535940765;594.9315828679714;598.9910151347733;13491.325433728372;13529.323533823368;13537.323133843367;13579.185248885098;13616.387262573207;12759.362031898369;12875.291858947754;12995;13113.86886131136;13123.409446574928;9632.855507167398;9832;9934.602615895355;10157.746056348651;10389.896101038968;3280.5735254416772;3316.038348878822;3357.17749151457;3357.61387440445;3389.4237979543536;59632.70183649054;59758.103628445606;59781.40218597802;59849;59852.700736496474;10006.19950403966;10044.146247569039;10060.446675432922;10147.644832430977;10284.074433301039;18485.5378615533;18514;18551.072446377628;18571.535711607317;18951.62096758057;6584.967075164594;6600.240972288152;6619.5035372347265;6650.800475985729;6694.464442844561;3556.8399422026073;3614.855405783792;3634.200475895299;3696.648818362305;3741.214344987529;2969.8960536381123;3066.5706801047872;3076.3078307381106;3081.0140754958647;3082.1678146900213;476.09066682636;491.33178876727624;500.7421178093285;503.6046703337862;503.64996327552;447.96864219504323;474.5586604457818;479.06821232702754;482.80929033031754;499.5129748495261;7287.890681639779;7366.7053317866685;7510.699572017058;7736.2650548197835;7833.960830195927;491.21650966708046;491.55268705477846;500.579513208899;512.3186162404069;524.1037825318672;3531.52324436203;3585.8744943926836;3710.8144592770795;3736.3834967230105;3798.905027374338;85902.57048714717;87436;90694;95073;95994.52002739941;52030;76208.61895690486;76282;77252;81910;69790;71289;71686.64156679183;73766;73843;6901.496818141045;7064.364207221375;7074.610896400747;7108.431325494052;7133.82165445868;43037.56962430335;43182.81806183278;43703.344449833916;43716.56283437125;45553.54446455493;37099.25801483901;38242.235155296185;40271.24576178455;41867.7906610465;49427.752861235465;10261.897381026094;10286.228532860216;10355.792884142425;10550.155987521133;10584.100351469904;11293.322400655987;11299.548018079186;11349.37578433161;11559.248648837798;11588;10970.725731856768;11005.449727513753;11027.338359698444;11495;11502.48238829238;10728.356298621795;11560.94219528897;11745.706357341134;11907.16649834538;12228.938855306022;10991.230613856953;11027.724306892393;11190.720231994264;11473.483693233658;11673.649790506299;9287.62849486013;9378.718638440858;9539.284553658643;9566.856497152687;9734.610615575297;9457.95271023668;9933.602655893683;10049.246306526893;10078.798424031625;10123.544440500342 18:19:57 INFO - PROCESS | 4105 | __end_tp_report 18:19:57 INFO - PROCESS | 4105 | __start_cc_report 18:19:57 INFO - PROCESS | 4105 | _x_x_mozilla_cycle_collect,1190 18:19:57 INFO - PROCESS | 4105 | __end_cc_report 18:19:57 INFO - PROCESS | 4105 | __startTimestamp1466126397771__endTimestamp 18:19:57 INFO - PROCESS | 4105 | 18:19:57 INFO - PROCESS | 4105 | ------- Summary: start ------- 18:19:57 INFO - PROCESS | 4105 | Number of tests: 6 18:19:57 INFO - PROCESS | 4105 | 18:19:57 INFO - PROCESS | 4105 | [#0] dojo.html Cycles:165 Average:4801.75 Median:4914.43 stddev:523.94 (10.7%) stddev-sans-first:497.49 18:19:57 INFO - PROCESS | 4105 | Values: 2645.4 2661.9 2692.1 2699.8 2705.6 4682.6 4744.3 4772.7 4837.2 4879.3 4696.8 4703.6 4841.2 4880.5 4886.1 4281.2 4365.4 4368.5 4380.2 4483.7 4411.0 4426.9 4439.8 4462.4 4478.5 4234.4 4266.5 4273.4 4409.2 4451.0 4446.1 4450.0 4451.2 4456.3 4508.4 4489.7 4499.5 4499.7 4501.6 4540.2 4647.5 4775.2 4822.8 4914.4 4959.4 5225.3 5290.9 5298.2 5308.7 5316.4 5304.9 5336.1 5347.4 5352.0 5354.5 4687.7 4772.7 4879.2 4888.9 4941.8 5373.5 5406.8 5434.0 5434.9 5440.0 5147.9 5179.6 5197.1 5202.4 5204.4 5037.1 5153.2 5212.1 5223.2 5237.0 5174.2 5200.5 5213.1 5217.8 5272.8 5042.6 5174.1 5186.1 5191.5 5193.3 4396.4 4407.5 4441.9 4456.3 4489.1 3860.7 3862.4 3877.1 3914.9 3918.3 4445.6 4453.0 4453.5 4456.2 4499.5 5117.6 5157.6 5197.1 5224.0 5238.9 4400.8 4440.6 4441.7 4443.4 4485.6 4589.9 4708.4 4714.0 4717.6 4769.9 4793.1 4797.1 4808.4 4811.6 4819.1 5132.9 5199.0 5224.3 5225.3 5272.6 4817.5 4860.7 4871.7 4875.4 4918.4 5151.3 5168.5 5170.9 5172.8 5256.2 5233.9 5242.6 5248.4 5248.4 5302.5 4944.9 5069.3 5079.4 5111.0 5122.4 5107.8 5122.4 5127.3 5131.8 5169.8 5010.3 5051.2 5057.2 5065.1 5065.2 4873.7 4933.4 5005.7 5028.9 5046.4 4958.3 4973.9 4983.2 4984.7 4985.3 18:19:57 INFO - PROCESS | 4105 | 18:19:57 INFO - PROCESS | 4105 | [#1] ext.html Cycles:180 Average:45768.42 Median:3263.76 stddev:80345.96 (2461.8%) stddev-sans-first:80499.59 18:19:57 INFO - PROCESS | 4105 | Values: 542.3 578.3 586.5 596.0 604.7 26154.3 28898.0 28916.0 28938.7 28958.9 28616.1 38116.4 39177.2 39243.4 43673.0 7820.2 7927.8 7931.7 7940.9 7947.7 7472.6 7856.8 7939.3 7942.2 7942.4 10083.0 10323.6 10326.9 10632.7 10636.4 10115.0 10200.6 10206.6 10206.7 10206.8 7508.7 10034.6 10085.6 10164.8 10393.0 34859.1 38634.6 38642.6 38645.8 38659.0 3051.9 3055.5 3112.9 3189.4 3240.7 29047.9 29532.4 29850.9 29899.7 30420.8 3286.9 3293.6 3305.5 3329.5 3330.2 251120.0 252785.0 253101.0 253633.0 253909.0 55998.4 56604.0 56706.7 56717.7 56760.4 60933.4 61258.7 61353.0 61396.4 61404.0 12527.9 12541.0 12652.8 12696.3 12698.4 9882.7 9989.5 10001.4 10012.9 10014.8 15784.4 17991.5 18096.5 18383.7 18436.0 6862.5 6891.9 6906.9 7159.4 7216.7 786.2 845.5 898.4 926.5 1021.5 26524.3 27042.9 27850.0 27893.6 27914.4 968.7 971.9 972.2 1008.0 1058.4 14108.9 14560.7 14719.0 14859.7 15002.1 367560.0 373982.0 374151.0 374860.0 375515.0 46797.9 47086.8 47110.1 47134.3 47288.8 1168.3 1169.7 1171.7 1173.7 1179.4 25832.9 27102.7 27375.3 27596.0 27676.7 40107.0 46647.1 47126.8 47144.5 47188.3 274079.0 275579.0 278884.0 278976.0 279976.0 21786.5 22291.3 22370.6 25277.0 25687.9 41212.8 41648.4 42025.4 42096.6 42431.4 35379.0 35634.8 35864.0 35979.1 36188.5 34095.8 34900.0 34980.8 34991.0 35498.6 34277.7 34624.8 34720.3 34886.7 35277.0 30392.4 31535.8 31731.8 31778.7 31858.0 24553.9 24646.3 24670.0 24968.3 25217.5 18:19:57 INFO - PROCESS | 4105 | 18:19:57 INFO - PROCESS | 4105 | [#2] jquery.html Cycles:180 Average:23526.74 Median:2277.16 stddev:64418.82 (2828.9%) stddev-sans-first:64576.07 18:19:57 INFO - PROCESS | 4105 | Values: 369.9 376.6 378.4 380.7 388.2 15681.5 16221.7 17099.2 17107.6 17119.7 14743.1 15151.5 15699.3 15729.1 15743.4 4915.7 5039.9 5045.9 5074.1 5078.7 3715.8 4948.8 5789.3 6109.7 6131.5 6114.2 6120.7 6140.6 6147.7 6168.0 5102.6 5442.3 5445.4 5503.5 5513.6 3348.0 4892.4 5206.3 5214.2 5283.7 11317.6 11679.3 11781.8 11846.1 11902.0 1833.9 1892.2 2169.7 2199.6 2365.8 8419.1 9231.2 9991.7 10067.6 13277.1 1681.8 1700.5 1700.7 1714.2 1766.5 63992.7 69464.3 71437.0 72472.3 72718.0 9807.1 10021.9 10216.5 10224.6 10517.6 13545.8 13733.5 13749.7 13752.2 13758.9 6717.5 6740.6 6911.3 6954.4 7027.2 6245.7 6354.3 6420.6 6430.9 6439.1 4130.5 4239.6 4254.9 4353.8 4379.6 1675.6 1680.2 1781.3 1792.7 1831.4 392.7 395.9 396.2 413.3 788.2 10074.5 10186.9 10262.8 10297.7 10472.3 397.1 409.0 409.3 429.6 429.9 5336.2 5580.6 5649.8 5652.2 5777.1 363179.0 384531.0 397168.0 398865.0 399689.0 74985.6 76610.2 79198.0 80161.6 81741.6 423.8 430.8 431.4 432.2 433.4 8692.7 9870.0 9971.5 10176.3 10399.8 36312.7 36413.6 36989.3 38528.2 39259.2 49517.5 50344.7 50367.0 50402.0 50449.7 7528.8 7705.7 7732.3 7733.4 7771.1 6900.2 11431.9 11563.9 11780.3 11781.9 9010.2 9563.7 9727.7 9926.3 10479.2 10060.9 10193.3 10297.3 10299.2 10415.9 10652.3 10708.4 10716.4 10775.9 10840.3 7606.4 7650.2 7677.8 7809.2 7960.8 9410.8 9959.4 10424.9 10494.7 10501.4 18:19:57 INFO - PROCESS | 4105 | 18:19:57 INFO - PROCESS | 4105 | [#3] mootools.html Cycles:180 Average:5141.09 Median:5008.51 stddev:2976.92 (59.4%) stddev-sans-first:2970.60 18:19:57 INFO - PROCESS | 4105 | Values: 1208.5 1370.8 1415.3 1456.6 1696.9 6835.1 7753.3 7994.4 8003.7 8009.3 7735.9 7862.2 7875.0 7879.1 7885.1 1875.5 1940.9 1949.9 2127.9 2159.9 1938.3 2161.1 2227.6 2228.6 2244.8 2152.7 2174.6 2198.1 2202.6 2203.6 1818.9 2098.0 2105.7 2109.3 2109.3 2085.2 2154.2 2155.8 2166.8 2168.4 7912.9 7935.2 7939.8 7941.2 7966.4 4313.2 4434.7 4445.3 4445.8 4445.9 2744.6 2853.3 2903.9 2918.9 2939.0 900.5 921.1 936.3 958.5 960.5 10924.3 10925.5 10935.0 10943.3 10950.2 4189.8 4236.1 4334.1 4364.9 4368.9 9238.6 9282.6 9283.0 9285.2 9289.1 4449.6 4467.2 4495.2 4504.1 4504.3 3487.3 3558.6 3971.0 4026.2 4026.7 3603.6 3718.8 3754.4 3761.4 3763.5 1179.7 1194.9 1202.2 1209.6 1213.4 792.3 795.2 810.2 812.9 813.3 4976.6 5040.4 5240.4 5285.7 5287.0 381.0 382.1 382.1 397.7 405.5 3387.5 3400.5 3487.5 3488.2 3490.8 9331.8 9570.0 9812.8 9837.4 9858.8 9703.5 9773.6 9781.1 9793.7 9803.0 7372.3 7424.6 7569.9 7573.0 7575.7 5087.3 5124.5 5183.7 5184.4 5251.7 9040.6 9085.0 9166.2 9170.0 9183.7 8885.6 9171.5 9369.0 9372.7 9384.7 6117.0 6557.3 6701.5 6815.9 6916.5 7644.2 7648.1 7676.0 7712.3 7713.1 6615.7 6708.9 6772.4 6865.0 6878.6 6787.0 6812.7 6827.2 6854.9 6972.9 6220.1 6223.2 6293.2 6293.7 6300.9 4308.6 4430.8 4499.1 4545.1 4608.1 4597.1 4610.4 4679.9 4704.6 4786.7 18:19:57 INFO - PROCESS | 4105 | 18:19:57 INFO - PROCESS | 4105 | [#4] prototype.html Cycles:180 Average:4243.45 Median:4876.12 stddev:1254.26 (25.7%) stddev-sans-first:1222.19 18:19:57 INFO - PROCESS | 4105 | Values: 291.0 292.7 294.7 295.7 299.6 4909.6 4918.8 4921.4 4930.7 4936.9 4815.9 4836.7 4839.0 4858.6 4878.5 3603.0 3741.5 3756.3 3759.6 3764.2 3687.8 3726.3 3803.3 3837.6 3850.7 3757.2 3768.8 3783.4 3791.8 3794.8 3519.3 3734.7 3790.0 3803.3 3805.2 3242.9 3248.0 3250.7 3251.0 3254.1 4716.1 4720.2 4750.5 4757.0 4772.3 4245.3 4247.0 4255.1 4258.5 4266.2 4949.2 5057.1 5066.6 5086.8 5126.4 3540.7 3549.6 3572.3 3577.1 3585.3 5308.7 5326.1 5375.2 5414.6 5447.8 3498.3 3644.3 3721.7 3722.2 3725.9 3699.9 3724.9 3729.1 3731.1 3737.7 4265.6 4278.3 4298.5 4313.6 4333.7 4939.8 5089.8 5125.5 5126.9 5152.4 1285.7 1286.2 1292.4 1295.3 1298.5 801.6 811.3 819.4 833.8 839.0 4406.4 4502.8 4506.9 4561.2 4561.6 5333.3 5336.6 5347.4 5360.5 5360.5 4423.1 4549.2 4550.6 4556.9 4578.7 2770.5 2831.9 2833.9 2845.0 2846.2 4934.3 4961.4 4982.4 4999.4 5000.6 5274.6 5450.9 5452.9 5458.6 5462.3 4955.5 4976.3 4982.6 4997.6 4998.2 5289.8 5300.0 5303.6 5338.0 5349.8 4890.2 5146.9 5169.4 5189.4 5198.8 4873.8 4891.4 4928.7 4962.5 4962.8 3710.2 3716.3 3722.1 3731.9 3736.3 4948.7 5249.0 5304.2 5334.8 5353.4 5149.8 5164.7 5181.6 5217.4 5223.3 5206.9 5230.6 5233.0 5238.4 5262.3 4802.9 5059.5 5084.5 5085.5 5124.8 4979.0 5117.0 5159.0 5175.4 5211.4 5035.2 5048.9 5061.4 5083.9 5091.5 18:19:57 INFO - PROCESS | 4105 | 18:19:57 INFO - PROCESS | 4105 | [#5] yui.html Cycles:180 Average:16407.38 Median:475.32 stddev:22891.37 (4815.9%) stddev-sans-first:22924.79 18:19:57 INFO - PROCESS | 4105 | Values: 593.4 617.6 622.0 627.2 723.9 11407.3 14024.0 14221.2 14630.2 14632.3 13871.8 14782.8 14814.1 14817.6 14828.9 629.9 780.8 793.1 794.1 794.6 772.7 786.4 786.9 792.4 793.9 817.5 819.3 820.6 821.4 824.6 807.2 810.2 811.7 812.0 812.1 594.4 594.5 594.7 594.9 599.0 13491.3 13529.3 13537.3 13579.2 13616.4 12759.4 12875.3 12995.0 13113.9 13123.4 9632.9 9832.0 9934.6 10157.7 10389.9 3280.6 3316.0 3357.2 3357.6 3389.4 59632.7 59758.1 59781.4 59849.0 59852.7 10006.2 10044.1 10060.4 10147.6 10284.1 18485.5 18514.0 18551.1 18571.5 18951.6 6585.0 6600.2 6619.5 6650.8 6694.5 3556.8 3614.9 3634.2 3696.6 3741.2 2969.9 3066.6 3076.3 3081.0 3082.2 476.1 491.3 500.7 503.6 503.6 448.0 474.6 479.1 482.8 499.5 7287.9 7366.7 7510.7 7736.3 7834.0 491.2 491.6 500.6 512.3 524.1 3531.5 3585.9 3710.8 3736.4 3798.9 85902.6 87436.0 90694.0 95073.0 95994.5 52030.0 76208.6 76282.0 77252.0 81910.0 69790.0 71289.0 71686.6 73766.0 73843.0 6901.5 7064.4 7074.6 7108.4 7133.8 43037.6 43182.8 43703.3 43716.6 45553.5 37099.3 38242.2 40271.2 41867.8 49427.8 10261.9 10286.2 10355.8 10550.2 10584.1 11293.3 11299.5 11349.4 11559.2 11588.0 10970.7 11005.4 11027.3 11495.0 11502.5 10728.4 11560.9 11745.7 11907.2 12228.9 10991.2 11027.7 11190.7 11473.5 11673.6 9287.6 9378.7 9539.3 9566.9 9734.6 9458.0 9933.6 10049.2 10078.8 10123.5 18:19:57 INFO - PROCESS | 4105 | -------- Summary: end -------- 18:19:57 INFO - PROCESS | 4105 | 18:19:58 INFO - TEST-INFO | 4105: exit 0 18:19:58 INFO - TEST-OK | dromaeo_css | took 1122925ms 18:19:58 INFO - TEST-START | kraken 18:19:58 INFO - operating with platform_type : linux_ 18:19:58 INFO - Initialising browser for kraken test... 18:19:58 INFO - TEST-INFO | started process 4285 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzl0P1x/profile http://localhost:49530/getInfo.html) 18:20:03 INFO - PROCESS | 4285 | __metrics Screen width/height:1600/1200 18:20:03 INFO - PROCESS | 4285 | colorDepth:24 18:20:03 INFO - PROCESS | 4285 | Browser inner width/height: 1024/697 18:20:03 INFO - PROCESS | 4285 | __metrics 18:20:05 INFO - PROCESS | 4285 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:20:05 INFO - PROCESS | 4285 | console.error: PushService: 18:20:05 INFO - PROCESS | 4285 | stateChangeProcessEnqueue: Error transitioning state 18:20:05 INFO - PROCESS | 4285 | UnknownError 18:20:06 INFO - TEST-INFO | 4285: exit 0 18:20:06 INFO - Browser initialized. 18:20:06 INFO - Running cycle 1/1 for kraken test... 18:20:06 INFO - TEST-INFO | started process 4365 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzl0P1x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:20:18 INFO - PROCESS | 4365 | RSS: Main: 175693824 18:20:18 INFO - PROCESS | 4365 | 18:20:21 INFO - PROCESS | 4365 | Cycle 1(1): loaded http://localhost:49530/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49530/tests/kraken/driver.html?testName=audio-beat-detection) 18:20:22 INFO - PROCESS | 4365 | RSS: Main: 233107456 18:20:22 INFO - PROCESS | 4365 | 18:20:24 INFO - PROCESS | 4365 | Cycle 1(1): loaded http://localhost:49530/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49530/tests/kraken/driver.html?testName=audio-dft) 18:20:25 INFO - PROCESS | 4365 | RSS: Main: 220643328 18:20:25 INFO - PROCESS | 4365 | 18:20:28 INFO - PROCESS | 4365 | Cycle 1(1): loaded http://localhost:49530/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49530/tests/kraken/driver.html?testName=audio-fft) 18:20:29 INFO - PROCESS | 4365 | RSS: Main: 288972800 18:20:29 INFO - PROCESS | 4365 | 18:20:31 INFO - PROCESS | 4365 | Cycle 1(1): loaded http://localhost:49530/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49530/tests/kraken/driver.html?testName=audio-oscillator) 18:20:32 INFO - PROCESS | 4365 | RSS: Main: 199077888 18:20:32 INFO - PROCESS | 4365 | 18:20:34 INFO - PROCESS | 4365 | Cycle 1(1): loaded http://localhost:49530/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49530/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:20:35 INFO - PROCESS | 4365 | RSS: Main: 203227136 18:20:35 INFO - PROCESS | 4365 | 18:20:38 INFO - PROCESS | 4365 | Cycle 1(1): loaded http://localhost:49530/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49530/tests/kraken/driver.html?testName=imaging-darkroom) 18:20:38 INFO - PROCESS | 4365 | RSS: Main: 238862336 18:20:38 INFO - PROCESS | 4365 | 18:20:42 INFO - PROCESS | 4365 | Cycle 1(1): loaded http://localhost:49530/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49530/tests/kraken/driver.html?testName=imaging-desaturate) 18:20:43 INFO - PROCESS | 4365 | RSS: Main: 234242048 18:20:43 INFO - PROCESS | 4365 | 18:20:45 INFO - PROCESS | 4365 | Cycle 1(1): loaded http://localhost:49530/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49530/tests/kraken/driver.html?testName=json-parse-financial) 18:20:46 INFO - PROCESS | 4365 | RSS: Main: 237395968 18:20:46 INFO - PROCESS | 4365 | 18:20:48 INFO - PROCESS | 4365 | Cycle 1(1): loaded http://localhost:49530/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49530/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:20:48 INFO - PROCESS | 4365 | RSS: Main: 226467840 18:20:48 INFO - PROCESS | 4365 | 18:20:50 INFO - PROCESS | 4365 | Cycle 1(1): loaded http://localhost:49530/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49530/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:20:51 INFO - PROCESS | 4365 | RSS: Main: 230195200 18:20:51 INFO - PROCESS | 4365 | 18:20:53 INFO - PROCESS | 4365 | Cycle 1(1): loaded http://localhost:49530/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49530/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:20:54 INFO - PROCESS | 4365 | RSS: Main: 238088192 18:20:54 INFO - PROCESS | 4365 | 18:20:56 INFO - PROCESS | 4365 | Cycle 1(1): loaded http://localhost:49530/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49530/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:20:57 INFO - PROCESS | 4365 | RSS: Main: 241868800 18:20:57 INFO - PROCESS | 4365 | 18:21:00 INFO - PROCESS | 4365 | Cycle 1(1): loaded http://localhost:49530/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49530/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:21:01 INFO - PROCESS | 4365 | RSS: Main: 239706112 18:21:01 INFO - PROCESS | 4365 | 18:21:02 INFO - PROCESS | 4365 | Cycle 1(1): loaded http://localhost:49530/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49530/tests/kraken/driver.html?testName=ai-astar) 18:21:03 INFO - PROCESS | 4365 | RSS: Main: 283017216 18:21:03 INFO - PROCESS | 4365 | 18:21:03 INFO - PROCESS | 4365 | __start_tp_report 18:21:03 INFO - PROCESS | 4365 | _x_x_mozilla_page_load 18:21:03 INFO - PROCESS | 4365 | _x_x_mozilla_page_load_details 18:21:03 INFO - PROCESS | 4365 | |i|pagename|runs| 18:21:03 INFO - PROCESS | 4365 | |0;ai-astar;114;108;113;114;114;114;114;113;112;114 18:21:03 INFO - PROCESS | 4365 | |1;audio-beat-detection;165;136;135;179;131;159;159;131;191;131 18:21:03 INFO - PROCESS | 4365 | |2;audio-dft;163;162;164;162;164;162;162;161;162;162 18:21:03 INFO - PROCESS | 4365 | |3;audio-fft;77;76;77;125;75;78;76;75;102;75 18:21:03 INFO - PROCESS | 4365 | |4;audio-oscillator;87;86;117;87;88;86;124;86;87;127 18:21:03 INFO - PROCESS | 4365 | |5;imaging-gaussian-blur;118;118;118;118;117;117;118;117;117;118 18:21:03 INFO - PROCESS | 4365 | |6;imaging-darkroom;147;147;148;149;149;147;147;147;146;146 18:21:03 INFO - PROCESS | 4365 | |7;imaging-desaturate;95;95;95;94;95;95;95;95;95;95 18:21:03 INFO - PROCESS | 4365 | |8;json-parse-financial;63;62;63;62;64;63;63;62;61;61 18:21:03 INFO - PROCESS | 4365 | |9;json-stringify-tinderbox;63;64;65;64;65;65;65;65;65;65 18:21:03 INFO - PROCESS | 4365 | |10;stanford-crypto-aes;83;83;82;89;84;85;90;85;118;84 18:21:03 INFO - PROCESS | 4365 | |11;stanford-crypto-ccm;143;144;140;132;134;134;133;149;147;136 18:21:03 INFO - PROCESS | 4365 | |12;stanford-crypto-pbkdf2;173;179;167;167;166;175;171;171;183;170 18:21:03 INFO - PROCESS | 4365 | |13;stanford-crypto-sha256-iterative;67;67;67;66;67;66;67;66;67;67 18:21:03 INFO - PROCESS | 4365 | __end_tp_report 18:21:03 INFO - PROCESS | 4365 | __start_cc_report 18:21:03 INFO - PROCESS | 4365 | _x_x_mozilla_cycle_collect,1904 18:21:03 INFO - PROCESS | 4365 | __end_cc_report 18:21:03 INFO - PROCESS | 4365 | __startTimestamp1466126463174__endTimestamp 18:21:03 INFO - PROCESS | 4365 | 18:21:03 INFO - PROCESS | 4365 | ------- Summary: start ------- 18:21:03 INFO - PROCESS | 4365 | Number of tests: 14 18:21:03 INFO - PROCESS | 4365 | 18:21:03 INFO - PROCESS | 4365 | [#0] ai-astar Cycles:10 Average:113.00 Median:114.00 stddev:1.89 (1.7%) stddev-sans-first:1.96 18:21:03 INFO - PROCESS | 4365 | Values: 114.0 108.0 113.0 114.0 114.0 114.0 114.0 113.0 112.0 114.0 18:21:03 INFO - PROCESS | 4365 | 18:21:03 INFO - PROCESS | 4365 | [#1] audio-beat-detection Cycles:10 Average:151.70 Median:159.00 stddev:22.07 (13.9%) stddev-sans-first:22.88 18:21:03 INFO - PROCESS | 4365 | Values: 165.0 136.0 135.0 179.0 131.0 159.0 159.0 131.0 191.0 131.0 18:21:03 INFO - PROCESS | 4365 | 18:21:03 INFO - PROCESS | 4365 | [#2] audio-dft Cycles:10 Average:162.40 Median:162.00 stddev:0.97 (0.6%) stddev-sans-first:1.00 18:21:03 INFO - PROCESS | 4365 | Values: 163.0 162.0 164.0 162.0 164.0 162.0 162.0 161.0 162.0 162.0 18:21:03 INFO - PROCESS | 4365 | 18:21:03 INFO - PROCESS | 4365 | [#3] audio-fft Cycles:10 Average:83.60 Median:76.00 stddev:16.69 (22.0%) stddev-sans-first:17.54 18:21:03 INFO - PROCESS | 4365 | Values: 77.0 76.0 77.0 125.0 75.0 78.0 76.0 75.0 102.0 75.0 18:21:03 INFO - PROCESS | 4365 | 18:21:03 INFO - PROCESS | 4365 | [#4] audio-oscillator Cycles:10 Average:97.50 Median:86.50 stddev:17.55 (20.3%) stddev-sans-first:18.19 18:21:03 INFO - PROCESS | 4365 | Values: 87.0 86.0 117.0 87.0 88.0 86.0 124.0 86.0 87.0 127.0 18:21:03 INFO - PROCESS | 4365 | 18:21:03 INFO - PROCESS | 4365 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:118.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 18:21:03 INFO - PROCESS | 4365 | Values: 118.0 118.0 118.0 118.0 117.0 117.0 118.0 117.0 117.0 118.0 18:21:03 INFO - PROCESS | 4365 | 18:21:03 INFO - PROCESS | 4365 | [#6] imaging-darkroom Cycles:10 Average:147.30 Median:147.00 stddev:1.06 (0.7%) stddev-sans-first:1.12 18:21:03 INFO - PROCESS | 4365 | Values: 147.0 147.0 148.0 149.0 149.0 147.0 147.0 147.0 146.0 146.0 18:21:03 INFO - PROCESS | 4365 | 18:21:03 INFO - PROCESS | 4365 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 18:21:03 INFO - PROCESS | 4365 | Values: 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 18:21:03 INFO - PROCESS | 4365 | 18:21:03 INFO - PROCESS | 4365 | [#8] json-parse-financial Cycles:10 Average:62.40 Median:63.00 stddev:0.97 (1.5%) stddev-sans-first:1.00 18:21:03 INFO - PROCESS | 4365 | Values: 63.0 62.0 63.0 62.0 64.0 63.0 63.0 62.0 61.0 61.0 18:21:03 INFO - PROCESS | 4365 | 18:21:03 INFO - PROCESS | 4365 | [#9] json-stringify-tinderbox Cycles:10 Average:64.60 Median:65.00 stddev:0.70 (1.1%) stddev-sans-first:0.44 18:21:03 INFO - PROCESS | 4365 | Values: 63.0 64.0 65.0 64.0 65.0 65.0 65.0 65.0 65.0 65.0 18:21:03 INFO - PROCESS | 4365 | 18:21:03 INFO - PROCESS | 4365 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:84.50 stddev:10.75 (12.7%) stddev-sans-first:11.23 18:21:03 INFO - PROCESS | 4365 | Values: 83.0 83.0 82.0 89.0 84.0 85.0 90.0 85.0 118.0 84.0 18:21:03 INFO - PROCESS | 4365 | 18:21:03 INFO - PROCESS | 4365 | [#11] stanford-crypto-ccm Cycles:10 Average:139.20 Median:141.50 stddev:6.23 (4.4%) stddev-sans-first:6.46 18:21:03 INFO - PROCESS | 4365 | Values: 143.0 144.0 140.0 132.0 134.0 134.0 133.0 149.0 147.0 136.0 18:21:03 INFO - PROCESS | 4365 | 18:21:03 INFO - PROCESS | 4365 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.20 Median:172.00 stddev:5.49 (3.2%) stddev-sans-first:5.82 18:21:03 INFO - PROCESS | 4365 | Values: 173.0 179.0 167.0 167.0 166.0 175.0 171.0 171.0 183.0 170.0 18:21:03 INFO - PROCESS | 4365 | 18:21:03 INFO - PROCESS | 4365 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 18:21:03 INFO - PROCESS | 4365 | Values: 67.0 67.0 67.0 66.0 67.0 66.0 67.0 66.0 67.0 67.0 18:21:03 INFO - PROCESS | 4365 | -------- Summary: end -------- 18:21:03 INFO - PROCESS | 4365 | 18:21:04 INFO - TEST-INFO | 4365: exit 0 18:21:04 INFO - TEST-OK | kraken | took 65793ms 18:21:04 INFO - SUITE-END | took 1188s 18:21:04 INFO - Completed test suite (00:19:49) 18:21:04 INFO - javascript benchmark 18:21:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2645.4444566641005, 2661.9467610647775, 2692.08469120499, 2699.7840172786177, 2705.6482657254564, 4682.555157260063, 4744.335792988975, 4772.73749943753, 4837.226043832983, 4879.292502587127, 4696.835610753625, 4703.576678098971, 4841.249606311018, 4880.463149053601, 4886.144924638183, 4281.229378711839, 4365.43249377582, 4368.453943257093, 4380.167768124046, 4483.663725220606, 4410.977945110286, 4426.911461770763, 4439.822407103728, 4462.44219472566, 4478.484974227957, 4234.386014027979, 4266.488021437416, 4273.444452221192, 4409.206342858284, 4451.043025749479, 4446.110777844444, 4450.043240703264, 4451.243288640939, 4456.309272062836, 4508.436445444319, 4489.708168969007, 4499.527549607277, 4499.662525310615, 4501.617362524189, 4540.18276710192, 4647.512011238805, 4775.164346239429, 4822.831200907986, 4914.434839993411, 4959.355283813117, 5225.268462415265, 5290.894182116374, 5298.15229563268, 5308.681479111266, 5316.415194328621, 5304.946950530484, 5336.1195402758885, 5347.41178470368, 5352.036633405985, 5354.544863686552, 4687.742174180453, 4772.665913386099, 4879.194932836113, 4888.877778055506, 4941.802327906916, 5373.543248823894, 5406.810501689622, 5433.972830135824, 5434.864128396822, 5439.972800135974, 5147.948520514747, 5179.637425380187, 5197.090509160882, 5202.401723801736, 5204.42751297357, 5037.093323201786, 5153.227015947563, 5212.113940630026, 5223.190405487156, 5236.9738151309, 5174.24973378858, 5200.5059519345605, 5213.087709650796, 5217.7912883485, 5272.841814745564, 5042.6470147090085, 5174.146265866165, 5186.066508028515, 5191.532762051434, 5193.324867767166, 4396.384506169139, 4407.47110346754, 4441.911161776747, 4456.264716321771, 4489.0572979674635, 3860.6911447084167, 3862.439946207784, 3877.1082650989865, 3914.8825535233987, 3918.275119102918, 4445.599896009375, 4452.977735111303, 4453.532379100213, 4456.24243878535, 4499.460064792246, 5117.565006974374, 5157.613179011589, 5197.142471492161, 5223.973880130575, 5238.869028274247, 4400.801963911567, 4440.555944405598, 4441.733495990251, 4443.355713421536, 4485.551444855489, 4589.908201835945, 4708.434987801417, 4714.0, 4717.646176536773, 4769.904601907942, 4793.13723529768, 4797.112534181188, 4808.447028591757, 4811.615070794398, 4819.084373968934, 5132.871678208075, 5199.038177937094, 5224.346956630421, 5225.320708307896, 5272.55183309423, 4817.5182481752945, 4860.684055536379, 4871.683340582851, 4875.366202393666, 4918.409790825124, 5151.278820965143, 5168.53483186515, 5170.870728231674, 5172.818951336685, 5256.211568264791, 5233.927044955851, 5242.554382877345, 5248.396434409939, 5248.448912864247, 5302.549283310962, 4944.925826112683, 5069.2902993581665, 5079.390473143247, 5111.028904508132, 5122.385313762373, 5107.795688172431, 5122.359705036871, 5127.307813445137, 5131.769070391767, 5169.7673604687125, 5010.348654674869, 5051.191809310492, 5057.216131499624, 5065.13892638245, 5065.240213967934, 4873.707577545359, 4933.432655244693, 5005.749712514433, 5028.899422011613, 5046.444891061907, 4958.2810492477665, 4973.900521989613, 4983.152864013054, 4984.651074424901, 4985.326980857538], "value": 4767.232275970461, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [542.264291124523, 578.2742657964255, 586.4868240289746, 596.0284735880514, 604.6976511744128, 26154.346141346454, 28897.999999999975, 28916.0, 28938.710612893865, 28958.855205723994, 28616.14151575456, 38116.42825357615, 39177.21645567094, 39243.41134882979, 43673.0, 7820.217978202177, 7927.762167134995, 7931.682732690685, 7940.880886786703, 7947.7218297359605, 7472.626368681545, 7856.803579910491, 7939.325157361631, 7942.245486678757, 7942.444028917978, 10083.0, 10323.638672646459, 10326.896731032706, 10632.681019569425, 10636.361818290927, 10115.0, 10200.591976320939, 10206.591736330538, 10206.744831379237, 10206.846897296546, 7508.737194198176, 10034.648787292483, 10085.596576136946, 10164.847527287096, 10393.0, 34859.128521787155, 38634.613653863664, 38642.61357386418, 38645.806770965966, 38659.0, 3051.8931837385585, 3055.4958431858718, 3112.8754849805982, 3189.3621275744713, 3240.659730728263, 29047.854760726274, 29532.409351812843, 29850.85074574635, 29899.70100299013, 30420.84789576038, 3286.8685252589867, 3293.6212335581477, 3305.454599991023, 3329.467285234375, 3330.217398911254, 251120.0, 252785.0, 253101.0, 253633.0, 253908.99999999817, 55998.440015600136, 56604.0, 56706.716466417405, 56717.71641141851, 56760.43239567634, 60933.390666093655, 61258.69370653118, 61353.0, 61396.386036139964, 61404.0, 12527.93736031314, 12541.0, 12652.81020784689, 12696.301703406401, 12698.42857071434, 9882.654107106217, 9989.500524973722, 10001.449920254456, 10012.899871001342, 10014.849777253346, 15784.447544335893, 17991.466192995245, 18096.45710628683, 18383.72424413635, 18436.0, 6862.451003919675, 6891.931080689229, 6906.930930690729, 7159.427245820322, 7216.7113315466795, 786.2491320788633, 845.4631309118631, 898.4384759525296, 926.5321012888448, 1021.5300961557618, 26524.336891577866, 27042.864785675945, 27850.0, 27893.58159627607, 27914.441711165662, 968.7384406210425, 971.9076687714655, 972.2027937091517, 1007.9819382423658, 1058.4125810175426, 14108.858911410754, 14560.708785824436, 14719.0, 14859.70280594382, 15002.099874007594, 367560.0, 373982.0, 374151.0, 374860.0, 375515.0, 46797.85223625399, 47086.76456617695, 47110.05779884383, 47134.29298560524, 47288.817779555786, 1168.293182624517, 1169.7485040716288, 1171.6719318590808, 1173.7359094203737, 1179.3926128044063, 25832.870835645703, 27102.72897271002, 27375.315617109733, 27596.0, 27676.723232767414, 40107.0, 46647.06705865932, 47126.76436617795, 47144.528554714016, 47188.29217561808, 274079.0, 275579.0, 278884.0, 278976.0, 279976.0, 21786.455338616663, 22291.331260062223, 22370.552588948456, 25277.0, 25687.871560642077, 41212.793936031325, 41648.375274371516, 42025.36961945512, 42096.579034209266, 42431.36352954646, 35379.0, 35634.82182589071, 35864.0, 35979.10052248715, 36188.45717314295, 34095.82952085224, 34900.0, 34980.82509587538, 34991.0, 35498.64501354953, 34277.65722342745, 34624.82687586546, 34720.305593887475, 34886.65113348936, 35277.0, 30392.392152157274, 31535.842320788248, 31731.841340793148, 31778.682213178497, 31858.0, 24553.877230613733, 24646.260612181664, 24670.0, 24968.250952471455, 25217.49565008653], "value": 17478.417337769926, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.93526132926746, 376.57447084794177, 378.37001392681185, 380.69544364508386, 388.23905145914006, 15681.529554113366, 16221.675566488679, 17099.230534625956, 17107.57231069221, 17119.657606847886, 14743.115413075224, 15151.469698560553, 15699.293531791069, 15729.134897580629, 15743.370265189378, 4915.729634870081, 5039.899202015958, 5045.873853153664, 5074.061298659742, 5078.695278283296, 3715.758475699071, 4948.752562371867, 5789.334226563936, 6109.725062372204, 6131.47882429994, 6114.205153330083, 6120.693965301717, 6140.570160088796, 6147.7233524491285, 6168.0, 5102.617303702219, 5442.319710036246, 5445.373782015079, 5503.504684578367, 5513.641613295123, 3348.0458069450333, 4892.3884514435695, 5206.323177986876, 5214.243934629495, 5283.682979021271, 11317.603883864025, 11679.29924204542, 11781.764364712657, 11846.111541634413, 11902.047836173171, 1833.8807977481554, 1892.176903047179, 2169.6853956176265, 2199.5820794049077, 2365.7870791628834, 8419.115992820727, 9231.215346695539, 9991.650292239738, 10067.597296108146, 13277.136986095873, 1681.7813684221096, 1700.4813531872774, 1700.6718397501004, 1714.1686282153146, 1766.4788887278285, 63992.68003659999, 69464.30535694679, 71437.0, 72472.27527724761, 72718.0, 9807.13664403761, 10021.899781002243, 10216.540255688511, 10224.64213752515, 10517.579296828195, 13545.796813047813, 13733.45066197341, 13749.725005500035, 13752.174869507662, 13758.93120534391, 6717.462602991749, 6740.62926539045, 6911.308869113049, 6954.374106330454, 7027.227005029443, 6245.687715614201, 6354.332795056545, 6420.646864422408, 6430.903536446957, 6439.066335381343, 4130.504339479222, 4239.59723826236, 4254.85108021224, 4353.825846966086, 4379.64962802975, 1675.6146086400197, 1680.227095536067, 1781.323097223047, 1792.659394715, 1831.3590243414699, 392.70939504766267, 395.87925682667066, 396.2392206962639, 413.2685147289281, 788.2196625340871, 10074.546645400975, 10186.898131018595, 10262.846057309147, 10297.691069267934, 10472.319299245524, 397.05897024052604, 409.0387589165497, 409.2939679053609, 429.55755571761136, 429.86029540399494, 5336.199570064521, 5580.637258578179, 5649.802256920988, 5652.152177173457, 5777.075667893117, 363179.0, 384531.0, 397168.0, 398865.0, 399689.0, 74985.6250718743, 76610.2338976603, 79198.0, 80161.59919200484, 81741.59129204316, 423.819876552464, 430.81905599648223, 431.43266604415095, 432.19611522568, 433.3521385528592, 8692.739217823475, 9870.0, 9971.45157016371, 10176.287659863738, 10399.844002340122, 36312.72905448297, 36413.63586364102, 36989.26021479609, 38528.2294354117, 39259.21481570295, 49517.50482495273, 50344.74827625838, 50367.0, 50402.0, 50449.747751261006, 7528.774136775905, 7705.691772329268, 7732.342750866239, 7733.381329493514, 7771.067471903408, 6900.171979362308, 11431.885681143081, 11563.884361156617, 11780.293182409083, 11781.882181178078, 9010.189082982562, 9563.713088607354, 9727.708168754949, 9926.25553083536, 10479.214058945458, 10060.949695251476, 10193.337433066827, 10297.279190456597, 10299.227557933335, 10415.895841041796, 10652.307600005975, 10708.411037393018, 10716.35701857891, 10775.946120269662, 10840.295380800222, 7606.391488681003, 7650.1584825668015, 7677.846443071219, 7809.2190780921455, 7960.800979975547, 9410.764730881783, 9959.352642078242, 10424.895751042393, 10494.737631559272, 10501.369917804956], "value": 7281.666912764849, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1208.5407545132848, 1370.8492065872756, 1415.271135365287, 1456.5703117580313, 1696.923638436271, 6835.111435513382, 7753.340966017889, 7994.400419968497, 8003.679852805881, 8009.27916487516, 7735.883961740578, 7862.213778622135, 7875.0, 7879.133295337509, 7885.053793544768, 1875.493616723484, 1940.922363105474, 1949.9025048747576, 2127.8936053197317, 2159.88120653364, 1938.2537722976615, 2161.1139432832542, 2227.5544891021777, 2228.6099932511825, 2244.7642997485273, 2152.7201463809683, 2174.586828502587, 2198.120751699317, 2202.6475763877784, 2203.603351396748, 1818.8544916406788, 2097.9895100524477, 2105.705201271823, 2109.2512158183777, 2109.2617583845686, 2085.238887805955, 2154.1814110638015, 2155.8490905636613, 2166.8483206175574, 2168.447046003259, 7912.920870791276, 7935.246151615587, 7939.841203175904, 7941.205879412013, 7966.44234903557, 4313.245182093153, 4434.68957172998, 4445.288753799409, 4445.755483448409, 4445.9110817783785, 2744.6157537944705, 2853.2866783304175, 2903.9274018149504, 2918.9124326270035, 2939.0448104366164, 900.5497251374313, 921.0605182713595, 936.2697096264924, 958.5111593087576, 960.5293406230967, 10924.2899211551, 10925.453727313603, 10935.015848573586, 10943.343399396063, 10950.178736594788, 4189.832406703758, 4236.131593023423, 4334.089841133335, 4364.890877728082, 4368.912621747547, 9238.58426370815, 9282.628694852265, 9282.978872324038, 9285.210757085588, 9289.117533834275, 4449.621782148553, 4467.15124126415, 4495.190865644216, 4504.09918016398, 4504.256797628354, 3487.3199726053176, 3558.644135586421, 3971.0469487322835, 4026.154507553388, 4026.6778657707314, 3603.56757189139, 3718.795466249382, 3754.3617585010616, 3761.4014044031724, 3763.473113764075, 1179.722765150189, 1194.9103817213743, 1202.2065436811838, 1209.6008317255285, 1213.4114954247184, 792.2671528835918, 795.2087672765635, 810.1938571121772, 812.8740045292989, 813.341193633159, 4976.576990955737, 5040.445550989388, 5240.4497527759795, 5285.656432331886, 5286.97356513215, 380.9809509524568, 382.08490664858067, 382.12493390136274, 397.72556935714266, 405.5498396779572, 3387.5257463955068, 3400.4559270516597, 3487.511748355232, 3488.2151515908718, 3490.842912068963, 9331.813363732688, 9570.0, 9812.803743925224, 9837.360571562825, 9858.80282394348, 9703.5148242589, 9773.609055637695, 9781.119699226962, 9793.657221997197, 9803.01969803014, 7372.336489715844, 7424.628768561551, 7569.924300757032, 7572.962135189288, 7575.734849280359, 5087.283288918148, 5124.538791508782, 5183.679756716792, 5184.377874655065, 5251.658642188246, 9040.638374464948, 9084.954575227082, 9166.22087122602, 9170.0, 9183.724488265363, 8885.600147993486, 9171.495567743837, 9369.0, 9372.67195648149, 9384.718458446258, 6117.0, 6557.344265573404, 6701.530892837454, 6815.897761533482, 6916.481263905127, 7644.197359277196, 7648.08223013242, 7676.0, 7712.344450721527, 7713.074431068308, 6615.669216539058, 6708.865822683617, 6772.390484856386, 6864.97025446187, 6878.58728476293, 6787.0, 6812.693428795816, 6827.180738311435, 6854.862902741817, 6972.930270697431, 6220.066989951543, 6223.222097237845, 6293.181886354744, 6293.748250070129, 6300.905486417616, 4308.590683885026, 4430.800613972315, 4499.122671079108, 4545.068261006551, 4608.124456353282, 4597.080583883304, 4610.423697037871, 4679.883002924818, 4704.57658810695, 4786.736729479912], "value": 4028.3621196067998, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.9912702618921, 292.6853632345229, 294.73915584707527, 295.7205440858387, 299.5940500621658, 4909.63177761668, 4918.827841025561, 4921.384826896638, 4930.679505832128, 4936.925946110811, 4815.940493091523, 4836.661433699643, 4838.951610483894, 4858.611311095113, 4878.463369029404, 3602.963970360302, 3741.4558100491654, 3756.3426400379963, 3759.642833930786, 3764.1906989997146, 3687.815609219542, 3726.347889119393, 3803.3344164771192, 3837.6184573553496, 3850.711196660248, 3757.192203676209, 3768.830402631874, 3783.356829339019, 3791.791451470168, 3794.829232684522, 3519.260955199411, 3734.73856830022, 3789.9621003789885, 3803.315403227418, 3805.1628641698644, 3242.908324130946, 3247.983760081208, 3250.6749325067303, 3250.951235731466, 3254.1213872254357, 4716.127516409475, 4720.150372932871, 4750.50119737426, 4757.048590281957, 4772.331873537708, 4245.257080010986, 4246.957530424687, 4255.1064276501975, 4258.53156152823, 4266.248269865919, 4949.232868905326, 5057.140286151363, 5066.569341605968, 5086.847394578169, 5126.359205099363, 3540.6636369544854, 3549.6450354964554, 3572.267685124569, 3577.123604716861, 3585.3367127081574, 5308.654937429054, 5326.147816349366, 5375.22059301403, 5414.6480478769145, 5447.754851031753, 3498.2653642734804, 3644.2893635740775, 3721.7208709346905, 3722.181120153562, 3725.8882233533036, 3699.9260014800093, 3724.8510059597857, 3729.1050147964293, 3731.104534911656, 3737.7009839212797, 4265.6112576957, 4278.294081476583, 4298.4841818981295, 4313.590208930146, 4333.71830830995, 4939.8271060513425, 5089.796408143633, 5125.538701516882, 5126.92309615356, 5152.407473140562, 1285.6593002854252, 1286.1575667937516, 1292.360281660584, 1295.33290355467, 1298.5260379961435, 801.5631480842954, 811.342812322021, 819.397742659145, 833.7832163637377, 839.0099682374856, 4406.361077643724, 4502.774861256924, 4506.887327816831, 4561.178987782165, 4561.635069194456, 5333.253344531769, 5336.573074154058, 5347.3583170019065, 5360.4805741301525, 5360.517553420211, 4423.137488189836, 4549.181147393501, 4550.567696068868, 4556.88607784808, 4578.748168850745, 2770.473610014091, 2831.9433611328072, 2833.8866445341955, 2844.971550284512, 2846.245744877609, 4934.333864928189, 4961.404631444249, 4982.427020892571, 4999.400071991385, 5000.574951129122, 5274.551663108678, 5450.945490545044, 5452.863678407992, 5458.590605704588, 5462.289902312754, 4955.479674634112, 4976.303317535548, 4982.60139188864, 4997.550220480101, 4998.150314446553, 5289.773238324542, 5299.973500132629, 5303.575713942798, 5338.039152952506, 5349.839504814861, 4890.168671325812, 5146.8970620588125, 5169.379674438941, 5189.4032186297545, 5198.842480370755, 4873.780679869344, 4891.437484689306, 4928.728919909296, 4962.503749625009, 4962.776675049704, 3710.2208536206617, 3716.2939041582013, 3722.088088418355, 3731.869384571527, 3736.3461394255355, 4948.678335908155, 5249.0, 5304.249597632849, 5334.839954801362, 5353.437889021752, 5149.768260428365, 5164.715940623302, 5181.564664607628, 5217.399999000012, 5223.3209682741, 5206.947930520646, 5230.63385563007, 5232.9738351308, 5238.397584277857, 5262.289590905179, 4802.855914322576, 5059.482155353453, 5084.542391184811, 5085.542301192911, 5124.8206312778875, 4978.975105124451, 5116.9744151279, 5159.0, 5175.378954525481, 5211.368259029317, 5035.219540971156, 5048.924266136084, 5061.367329083864, 5083.949160508348, 5091.490850914879], "value": 3839.6369103429665, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.3777458579954, 617.6355949989505, 622.0423226881751, 627.2379059442776, 723.9239879812616, 11407.258528195662, 14024.0, 14221.21783301918, 14630.195339256337, 14632.268386580668, 13871.791923121162, 14782.778258326134, 14814.111153330809, 14817.629559261024, 14828.851711482908, 629.9275583307932, 780.7696729464822, 793.0602236349915, 794.0907660728462, 794.630496818979, 772.7449941519313, 786.4101923557332, 786.8937693411402, 792.43341011177, 793.9206079392072, 817.4972391978949, 819.2708489444399, 820.6102101501768, 821.4496287487368, 824.5794644731201, 807.2452257139594, 810.1776696652931, 811.6712731343815, 811.9959400202961, 812.1229072601576, 594.3729365519375, 594.5273507561499, 594.7234535940765, 594.9315828679714, 598.9910151347733, 13491.325433728372, 13529.323533823368, 13537.323133843367, 13579.185248885098, 13616.387262573207, 12759.362031898369, 12875.291858947754, 12995.0, 13113.86886131136, 13123.409446574928, 9632.855507167398, 9832.0, 9934.602615895355, 10157.746056348651, 10389.896101038968, 3280.5735254416772, 3316.038348878822, 3357.17749151457, 3357.61387440445, 3389.4237979543536, 59632.70183649054, 59758.103628445606, 59781.40218597802, 59849.0, 59852.700736496474, 10006.19950403966, 10044.146247569039, 10060.446675432922, 10147.644832430977, 10284.074433301039, 18485.5378615533, 18514.0, 18551.072446377628, 18571.535711607317, 18951.62096758057, 6584.967075164594, 6600.240972288152, 6619.5035372347265, 6650.800475985729, 6694.464442844561, 3556.8399422026073, 3614.855405783792, 3634.200475895299, 3696.648818362305, 3741.214344987529, 2969.8960536381123, 3066.5706801047872, 3076.3078307381106, 3081.0140754958647, 3082.1678146900213, 476.09066682636, 491.33178876727624, 500.7421178093285, 503.6046703337862, 503.64996327552, 447.96864219504323, 474.5586604457818, 479.06821232702754, 482.80929033031754, 499.5129748495261, 7287.890681639779, 7366.7053317866685, 7510.699572017058, 7736.2650548197835, 7833.960830195927, 491.21650966708046, 491.55268705477846, 500.579513208899, 512.3186162404069, 524.1037825318672, 3531.52324436203, 3585.8744943926836, 3710.8144592770795, 3736.3834967230105, 3798.905027374338, 85902.57048714717, 87436.0, 90694.0, 95073.0, 95994.52002739941, 52030.0, 76208.61895690486, 76282.0, 77252.0, 81910.0, 69790.0, 71289.0, 71686.64156679183, 73766.0, 73843.0, 6901.496818141045, 7064.364207221375, 7074.610896400747, 7108.431325494052, 7133.82165445868, 43037.56962430335, 43182.81806183278, 43703.344449833916, 43716.56283437125, 45553.54446455493, 37099.25801483901, 38242.235155296185, 40271.24576178455, 41867.7906610465, 49427.752861235465, 10261.897381026094, 10286.228532860216, 10355.792884142425, 10550.155987521133, 10584.100351469904, 11293.322400655987, 11299.548018079186, 11349.37578433161, 11559.248648837798, 11588.0, 10970.725731856768, 11005.449727513753, 11027.338359698444, 11495.0, 11502.48238829238, 10728.356298621795, 11560.94219528897, 11745.706357341134, 11907.16649834538, 12228.938855306022, 10991.230613856953, 11027.724306892393, 11190.720231994264, 11473.483693233658, 11673.649790506299, 9287.62849486013, 9378.718638440858, 9539.284553658643, 9566.856497152687, 9734.610615575297, 9457.95271023668, 9933.602655893683, 10049.246306526893, 10078.798424031625, 10123.544440500342], "value": 6267.615083893805, "unit": "score"}], "name": "dromaeo_css", "value": 6236.3147380347755, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 108.0, 113.0, 114.0, 114.0, 114.0, 114.0, 113.0, 112.0, 114.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 136.0, 135.0, 179.0, 131.0, 159.0, 159.0, 131.0, 191.0, 131.0], "value": 151.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 164.0, 162.0, 164.0, 162.0, 162.0, 161.0, 162.0, 162.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 77.0, 125.0, 75.0, 78.0, 76.0, 75.0, 102.0, 75.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 117.0, 87.0, 88.0, 86.0, 124.0, 86.0, 87.0, 127.0], "value": 97.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 118.0, 118.0, 117.0, 117.0, 118.0, 117.0, 117.0, 118.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 148.0, 149.0, 149.0, 147.0, 147.0, 147.0, 146.0, 146.0], "value": 147.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 63.0, 62.0, 64.0, 63.0, 63.0, 62.0, 61.0, 61.0], "value": 62.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 65.0, 64.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0], "value": 64.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 82.0, 89.0, 84.0, 85.0, 90.0, 85.0, 118.0, 84.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 144.0, 140.0, 132.0, 134.0, 134.0, 133.0, 149.0, 147.0, 136.0], "value": 139.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 179.0, 167.0, 167.0, 166.0, 175.0, 171.0, 171.0, 183.0, 170.0], "value": 172.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 66.0, 67.0, 66.0, 67.0, 66.0, 67.0, 67.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1561.4, "alertThreshold": 2.0}]} 18:21:04 INFO - Return code: 0 18:21:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:21:04 INFO - # TBPL SUCCESS # 18:21:04 INFO - Running post-action listener: _resource_record_post_action 18:21:04 INFO - ##### 18:21:04 INFO - ##### Finished run-tests step (success) 18:21:04 INFO - ##### 18:21:04 INFO - Running post-run listener: _resource_record_post_run 18:21:05 INFO - Total resource usage - Wall time: 1207s; CPU: 13.0%; Read bytes: 4096; Write bytes: 547954688; Read time: 4; Write time: 2016320 18:21:05 INFO - TinderboxPrint: CPU usage
13.3% 18:21:05 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 18:21:05 INFO - TinderboxPrint: I/O write bytes / time
547,954,688 / 2,016,320 18:21:05 INFO - TinderboxPrint: CPU idle
8,351.8 (86.7%) 18:21:05 INFO - TinderboxPrint: CPU user
1,215.6 (12.6%) 18:21:05 INFO - TinderboxPrint: Swap in / out
0 / 0 18:21:05 INFO - install - Wall time: 17s; CPU: 21.0%; Read bytes: 0; Write bytes: 256954368; Read time: 0; Write time: 1327372 18:21:05 INFO - run-tests - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290414592; Read time: 4; Write time: 688880 18:21:05 INFO - Running post-run listener: _upload_blobber_files 18:21:05 INFO - Blob upload gear active. 18:21:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:21:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:21:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:21:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:21:06 INFO - (blobuploader) - INFO - Open directory for files ... 18:21:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:21:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:21:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:21:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:21:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:21:10 INFO - (blobuploader) - INFO - Done attempting. 18:21:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:21:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:21:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:21:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:21:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:21:11 INFO - (blobuploader) - INFO - Done attempting. 18:21:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:21:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:21:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:21:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:21:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:21:11 INFO - (blobuploader) - INFO - Done attempting. 18:21:11 INFO - (blobuploader) - INFO - Iteration through files over. 18:21:11 INFO - Return code: 0 18:21:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:21:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:21:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/51bc51fc4f7b381035aa656a9dad094cdfdbb800d5233e58231a4eb6877a1307692e9a06e952751907964b3d530f4b471205b78f37963c5197c2a526e4791544", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/25f26a5de3898f6ffac3f762fe087f7c207bd308ab048f6811cd514a15b9229e198c343d62041c3a9803e40d59ebb7a7aa9add22ce1abe5e661fc73410bcbbd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/029843b226be34b245c0ebea1a34dc1015400a19ad39785c21a9871b04cc9fffa4ff1829713f6376e69243ade9b6970ff574996304a5d18ec687b4a07edb56aa"} 18:21:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:21:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:21:11 INFO - Contents: 18:21:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/51bc51fc4f7b381035aa656a9dad094cdfdbb800d5233e58231a4eb6877a1307692e9a06e952751907964b3d530f4b471205b78f37963c5197c2a526e4791544", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/25f26a5de3898f6ffac3f762fe087f7c207bd308ab048f6811cd514a15b9229e198c343d62041c3a9803e40d59ebb7a7aa9add22ce1abe5e661fc73410bcbbd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/029843b226be34b245c0ebea1a34dc1015400a19ad39785c21a9871b04cc9fffa4ff1829713f6376e69243ade9b6970ff574996304a5d18ec687b4a07edb56aa"} 18:21:11 INFO - Running post-run listener: copy_logs_to_upload_dir 18:21:11 INFO - Copying logs to upload dir... 18:21:11 INFO - mkdir: /builds/slave/test/build/upload/logs 18:21:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.687381 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-06-16 18:21:11.555038) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-16 18:21:11.559061) ========= 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=df17445c3590ff7897617a0e00000225-1466125112.271062-518050420 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/51bc51fc4f7b381035aa656a9dad094cdfdbb800d5233e58231a4eb6877a1307692e9a06e952751907964b3d530f4b471205b78f37963c5197c2a526e4791544", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/25f26a5de3898f6ffac3f762fe087f7c207bd308ab048f6811cd514a15b9229e198c343d62041c3a9803e40d59ebb7a7aa9add22ce1abe5e661fc73410bcbbd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/029843b226be34b245c0ebea1a34dc1015400a19ad39785c21a9871b04cc9fffa4ff1829713f6376e69243ade9b6970ff574996304a5d18ec687b4a07edb56aa"} build_url:https://queue.taskcluster.net/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011245 build_url: 'https://queue.taskcluster.net/v1/task/HQ_BYAMIRqaZJG_LEYuNNg/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/51bc51fc4f7b381035aa656a9dad094cdfdbb800d5233e58231a4eb6877a1307692e9a06e952751907964b3d530f4b471205b78f37963c5197c2a526e4791544", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/25f26a5de3898f6ffac3f762fe087f7c207bd308ab048f6811cd514a15b9229e198c343d62041c3a9803e40d59ebb7a7aa9add22ce1abe5e661fc73410bcbbd8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/029843b226be34b245c0ebea1a34dc1015400a19ad39785c21a9871b04cc9fffa4ff1829713f6376e69243ade9b6970ff574996304a5d18ec687b4a07edb56aa"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-16 18:21:11.585329) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 18:21:11.585713) ========= 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=df17445c3590ff7897617a0e00000225-1466125112.271062-518050420 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004434 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 18:21:11.668893) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-16 18:21:11.669251) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-16 18:21:11.669620) ========= ========= Total master_lag: 0.40 =========