builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-017 starttime: 1465253785.77 results: success (0) buildid: 20160606144430 builduid: d5cee6c3255f4f59ab44b342426dc421 revision: d54e29ad6279e546d01c4659e22cdee5a82514e1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 15:56:25.771842) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 15:56:25.772209) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 15:56:25.772517) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 15:56:25.790197) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 15:56:25.790461) ========= 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=0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-06 15:56:25-- 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% 95.3M=0s 2016-06-06 15:56:26 (95.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113444 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 15:56:25.917432) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 15:56:25.917760) ========= 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=0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016340 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 15:56:25.967966) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 15:56:25.968459) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d54e29ad6279e546d01c4659e22cdee5a82514e1 --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 d54e29ad6279e546d01c4659e22cdee5a82514e1 --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=0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-06 15:56:26,135 truncating revision to first 12 chars 2016-06-06 15:56:26,135 Setting DEBUG logging. 2016-06-06 15:56:26,135 attempt 1/10 2016-06-06 15:56:26,135 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d54e29ad6279?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-06 15:56:26,760 unpacking tar archive at: mozilla-central-d54e29ad6279/testing/mozharness/ program finished with exit code 0 elapsedTime=0.967579 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 15:56:26.951706) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 15:56:26.952031) ========= script_repo_revision: d54e29ad6279e546d01c4659e22cdee5a82514e1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 15:56:26.952446) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 15:56:26.952736) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 15:56:26.967662) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 52 secs) (at 2016-06-06 15:56:26.967984) ========= /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=0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:56:27 INFO - MultiFileLogger online at 20160606 15:56:27 in /builds/slave/test 15:56:27 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 15:56:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:56:27 INFO - {'append_to_log': False, 15:56:27 INFO - 'base_work_dir': '/builds/slave/test', 15:56:27 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:56:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:56:27 INFO - 'branch': 'Firefox-Non-PGO', 15:56:27 INFO - 'buildbot_json_path': 'buildprops.json', 15:56:27 INFO - 'config_files': ('talos/linux_config.py',), 15:56:27 INFO - 'default_actions': ('clobber', 15:56:27 INFO - 'read-buildbot-config', 15:56:27 INFO - 'download-and-extract', 15:56:27 INFO - 'populate-webroot', 15:56:27 INFO - 'create-virtualenv', 15:56:27 INFO - 'install', 15:56:27 INFO - 'run-tests'), 15:56:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:56:27 INFO - 'download_minidump_stackwalk': True, 15:56:27 INFO - 'download_symbols': 'ondemand', 15:56:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:56:27 INFO - 'tooltool.py': '/tools/tooltool.py', 15:56:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:56:27 INFO - '/tools/misc-python/virtualenv.py')}, 15:56:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:56:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:56:27 INFO - 'installer_path': 'installer.exe', 15:56:27 INFO - 'log_level': 'info', 15:56:27 INFO - 'log_name': 'talos', 15:56:27 INFO - 'log_to_console': True, 15:56:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:56:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:56:27 INFO - 'opt_config_files': (), 15:56:27 INFO - 'pip_index': False, 15:56:27 INFO - 'sps_profile': False, 15:56:27 INFO - 'sps_profile_interval': 0, 15:56:27 INFO - 'suite': 'dromaeojs', 15:56:27 INFO - 'system_bits': '32', 15:56:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:56:27 INFO - 'title': 'talos-linux64-ix-017', 15:56:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:56:27 INFO - 'use_talos_json': True, 15:56:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:56:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:56:27 INFO - 'work_dir': 'build'} 15:56:27 INFO - ##### 15:56:27 INFO - ##### Running clobber step. 15:56:27 INFO - ##### 15:56:27 INFO - Running pre-action listener: _resource_record_pre_action 15:56:27 INFO - Running main action method: clobber 15:56:27 INFO - rmtree: /builds/slave/test/build 15:56:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:56:28 INFO - Running post-action listener: _resource_record_post_action 15:56:28 INFO - ##### 15:56:28 INFO - ##### Finished clobber step (success) 15:56:28 INFO - ##### 15:56:28 INFO - ##### 15:56:28 INFO - ##### Running read-buildbot-config step. 15:56:28 INFO - ##### 15:56:28 INFO - Running pre-action listener: _resource_record_pre_action 15:56:28 INFO - Running main action method: read_buildbot_config 15:56:28 INFO - Using buildbot properties: 15:56:28 INFO - { 15:56:28 INFO - "project": "", 15:56:28 INFO - "product": "firefox", 15:56:28 INFO - "script_repo_revision": "production", 15:56:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 15:56:28 INFO - "repository": "", 15:56:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 15:56:28 INFO - "buildid": "20160606144430", 15:56:28 INFO - "pgo_build": "False", 15:56:28 INFO - "basedir": "/builds/slave/test", 15:56:28 INFO - "buildnumber": 108, 15:56:28 INFO - "slavename": "talos-linux64-ix-017", 15:56:28 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:56:28 INFO - "platform": "ubuntu64_hw", 15:56:28 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 15:56:28 INFO - "branch": "mozilla-central", 15:56:28 INFO - "repo_path": "mozilla-central", 15:56:28 INFO - "revision": "d54e29ad6279e546d01c4659e22cdee5a82514e1", 15:56:28 INFO - "stage_platform": "linux64", 15:56:28 INFO - "builduid": "d5cee6c3255f4f59ab44b342426dc421", 15:56:28 INFO - "slavebuilddir": "test" 15:56:28 INFO - } 15:56:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/DYkl1rXAQKyqhOB3_4xE_g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 15:56:28 INFO - Running post-action listener: _resource_record_post_action 15:56:28 INFO - ##### 15:56:28 INFO - ##### Finished read-buildbot-config step (success) 15:56:28 INFO - ##### 15:56:28 INFO - ##### 15:56:28 INFO - ##### Running download-and-extract step. 15:56:28 INFO - ##### 15:56:28 INFO - Running pre-action listener: _resource_record_pre_action 15:56:28 INFO - Running main action method: download_and_extract 15:56:28 INFO - mkdir: /builds/slave/test/build/tests 15:56:28 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')]} 15:56:28 INFO - https://queue.taskcluster.net/v1/task/DYkl1rXAQKyqhOB3_4xE_g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:56:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYkl1rXAQKyqhOB3_4xE_g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:56:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYkl1rXAQKyqhOB3_4xE_g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:56:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYkl1rXAQKyqhOB3_4xE_g/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 15:56:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYkl1rXAQKyqhOB3_4xE_g/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 15:56:37 INFO - Downloaded 1361 bytes. 15:56:37 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 15:56:37 INFO - Using the following test package requirements: 15:56:37 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:56:37 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:56:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:56:37 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:56:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 15:56:37 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:56:37 INFO - u'jsshell-linux-x86_64.zip'], 15:56:37 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:56:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:56:37 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 15:56:37 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:56:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:56:37 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:56:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:56:37 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:56:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:56:37 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 15:56:37 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:56:37 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:56:37 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')]} 15:56:37 INFO - https://queue.taskcluster.net/v1/task/DYkl1rXAQKyqhOB3_4xE_g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:56:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYkl1rXAQKyqhOB3_4xE_g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:56:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYkl1rXAQKyqhOB3_4xE_g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:56:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYkl1rXAQKyqhOB3_4xE_g/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 15:56:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYkl1rXAQKyqhOB3_4xE_g/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 15:57:09 WARNING - URL Error: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYkl1rXAQKyqhOB3_4xE_g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:57:09 INFO - Running command: ['nslookup', u'queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com'] 15:57:09 INFO - Copy/paste: nslookup queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com 15:57:09 INFO - Server: 10.26.75.40 15:57:09 INFO - Address: 10.26.75.40#53 15:57:09 INFO - queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com canonical name = proxxy1.srv.releng.scl3.mozilla.com. 15:57:09 INFO - Name: proxxy1.srv.releng.scl3.mozilla.com 15:57:09 INFO - Address: 10.26.48.46 15:57:09 INFO - Return code: 0 15:57:09 INFO - retry: attempt #1 caught exception: 15:57:09 INFO - retry: Failed, sleeping 30 seconds before retrying 15:57:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYkl1rXAQKyqhOB3_4xE_g/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 #2 15:57:40 INFO - Downloaded 22870975 bytes. 15:57:40 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'] 15:57:40 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 15:57:41 INFO - Return code: 0 15:57:41 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 15:57:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:57:41 INFO - https://queue.taskcluster.net/v1/task/DYkl1rXAQKyqhOB3_4xE_g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:57:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYkl1rXAQKyqhOB3_4xE_g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:57:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYkl1rXAQKyqhOB3_4xE_g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 15:57:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYkl1rXAQKyqhOB3_4xE_g/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 15:57:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYkl1rXAQKyqhOB3_4xE_g/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 15:57:42 INFO - Downloaded 22870975 bytes. 15:57:42 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'] 15:57:42 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 15:57:44 INFO - Return code: 0 15:57:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:57:44 INFO - https://queue.taskcluster.net/v1/task/DYkl1rXAQKyqhOB3_4xE_g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:57:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYkl1rXAQKyqhOB3_4xE_g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:57:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYkl1rXAQKyqhOB3_4xE_g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 15:57:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYkl1rXAQKyqhOB3_4xE_g/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 15:57:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYkl1rXAQKyqhOB3_4xE_g/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 15:57:45 INFO - Downloaded 10833369 bytes. 15:57:45 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'] 15:57:45 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 15:57:45 INFO - Return code: 0 15:57:45 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')]} 15:57:45 INFO - https://queue.taskcluster.net/v1/task/DYkl1rXAQKyqhOB3_4xE_g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:57:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYkl1rXAQKyqhOB3_4xE_g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:57:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYkl1rXAQKyqhOB3_4xE_g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:57:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYkl1rXAQKyqhOB3_4xE_g/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 15:57:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DYkl1rXAQKyqhOB3_4xE_g/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 15:57:46 INFO - Downloaded 55198162 bytes. 15:57:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DYkl1rXAQKyqhOB3_4xE_g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:57:46 INFO - mkdir: /builds/slave/test/properties 15:57:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:57:46 INFO - Writing to file /builds/slave/test/properties/build_url 15:57:46 INFO - Contents: 15:57:46 INFO - build_url:https://queue.taskcluster.net/v1/task/DYkl1rXAQKyqhOB3_4xE_g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 15:57:47 INFO - Running post-action listener: _resource_record_post_action 15:57:47 INFO - Running post-action listener: set_extra_try_arguments 15:57:47 INFO - ##### 15:57:47 INFO - ##### Finished download-and-extract step (success) 15:57:47 INFO - ##### 15:57:47 INFO - ##### 15:57:47 INFO - ##### Running populate-webroot step. 15:57:47 INFO - ##### 15:57:47 INFO - Running pre-action listener: _resource_record_pre_action 15:57:47 INFO - Running main action method: populate_webroot 15:57:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:57:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:57:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:57:47 INFO - '--tppagecycles', 15:57:47 INFO - '7'], 15:57:47 INFO - 'tests': ['tsvgm']}}, 15:57:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:57:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:57:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:57:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:57:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:57:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:57:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:57:47 INFO - 'talos_options': ['--disable-e10s'], 15:57:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:57:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:57:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:57:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:57:47 INFO - 'talos_options': ['--disable-e10s'], 15:57:47 INFO - 'tests': ['damp', 'tps']}, 15:57:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:57:47 INFO - 'tests': ['damp', 'tps']}, 15:57:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:57:47 INFO - 'tests': ['dromaeo_dom']}, 15:57:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:57:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:57:47 INFO - 'tests': ['a11yr', 15:57:47 INFO - 'ts_paint', 15:57:47 INFO - 'tpaint', 15:57:47 INFO - 'sessionrestore', 15:57:47 INFO - 'sessionrestore_no_auto_restore', 15:57:47 INFO - 'tabpaint']}, 15:57:47 INFO - 'other-e10s': {'tests': ['a11yr', 15:57:47 INFO - 'ts_paint', 15:57:47 INFO - 'tpaint', 15:57:47 INFO - 'sessionrestore', 15:57:47 INFO - 'sessionrestore_no_auto_restore', 15:57:47 INFO - 'tabpaint']}, 15:57:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:57:47 INFO - 'ts_paint', 15:57:47 INFO - 'tpaint', 15:57:47 INFO - 'sessionrestore', 15:57:47 INFO - 'sessionrestore_no_auto_restore', 15:57:47 INFO - 'tabpaint']}, 15:57:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:57:47 INFO - 'ts_paint', 15:57:47 INFO - 'tpaint', 15:57:47 INFO - 'sessionrestore', 15:57:47 INFO - 'sessionrestore_no_auto_restore', 15:57:47 INFO - 'tabpaint']}, 15:57:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:57:47 INFO - 'tests': ['a11yr', 15:57:47 INFO - 'ts_paint', 15:57:47 INFO - 'tpaint', 15:57:47 INFO - 'sessionrestore', 15:57:47 INFO - 'sessionrestore_no_auto_restore', 15:57:47 INFO - 'tabpaint']}, 15:57:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:57:47 INFO - 'tests': ['a11yr', 15:57:47 INFO - 'ts_paint', 15:57:47 INFO - 'tpaint', 15:57:47 INFO - 'sessionrestore', 15:57:47 INFO - 'sessionrestore_no_auto_restore', 15:57:47 INFO - 'tabpaint']}, 15:57:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:57:47 INFO - 'tests': ['tsvgx', 15:57:47 INFO - 'tsvgr_opacity', 15:57:47 INFO - 'tart', 15:57:47 INFO - 'tscrollx', 15:57:47 INFO - 'cart']}, 15:57:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:57:47 INFO - 'tsvgr_opacity', 15:57:47 INFO - 'tart', 15:57:47 INFO - 'tscrollx', 15:57:47 INFO - 'cart']}, 15:57:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:57:47 INFO - 'talos_options': ['--disable-e10s'], 15:57:47 INFO - 'tests': ['tp5o']}, 15:57:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:57:47 INFO - 'tests': ['tp5o']}, 15:57:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:57:47 INFO - 'talos_options': ['--disable-e10s', 15:57:47 INFO - '--xperf_path', 15:57:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:57:47 INFO - 'tests': ['tp5n']}, 15:57:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:57:47 INFO - 'talos_options': ['--xperf_path', 15:57:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:57:47 INFO - 'tests': ['tp5n']}}, 15:57:47 INFO - 'talos.zip': {'path': '', 15:57:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:57:47 INFO - Running post-action listener: _resource_record_post_action 15:57:47 INFO - ##### 15:57:47 INFO - ##### Finished populate-webroot step (success) 15:57:47 INFO - ##### 15:57:47 INFO - ##### 15:57:47 INFO - ##### Running create-virtualenv step. 15:57:47 INFO - ##### 15:57:47 INFO - Running pre-action listener: _resource_record_pre_action 15:57:47 INFO - Running main action method: create_virtualenv 15:57:47 INFO - Creating virtualenv /builds/slave/test/build/venv 15:57:47 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 15:57:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:57:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:57:47 INFO - Using real prefix '/usr' 15:57:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:57:48 INFO - Installing distribute.............................................................................................................................................................................................done. 15:57:50 INFO - Installing pip.................done. 15:57:50 INFO - Return code: 0 15:57:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:57: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')]} 15:57:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57: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')]} 15:57:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:50 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 0x7fd740769f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd83b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6a990>, '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 0x1cd6470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd6900>, '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': '0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057', '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 15:57:50 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 15:57:50 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 15:57:50 INFO - Using env: {'DISPLAY': ':0', 15:57:50 INFO - 'HOME': '/home/cltbld', 15:57:50 INFO - 'LANG': 'en_US.UTF-8', 15:57:50 INFO - 'LANGUAGE': 'en_US:en', 15:57:50 INFO - 'LOGNAME': 'cltbld', 15:57:50 INFO - 'MAIL': '/var/mail/cltbld', 15:57:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:50 INFO - 'MOZ_NO_REMOTE': '1', 15:57:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:50 INFO - 'NO_EM_RESTART': '1', 15:57:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:50 INFO - 'PWD': '/builds/slave/test', 15:57:50 INFO - 'SHELL': '/bin/bash', 15:57:50 INFO - 'SHLVL': '1', 15:57:50 INFO - 'TERM': 'linux', 15:57:50 INFO - 'TMOUT': '86400', 15:57:50 INFO - 'USER': 'cltbld', 15:57:50 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057', 15:57:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:50 INFO - '_': '/tools/buildbot/bin/python'} 15:57:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:50 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 15:57:50 INFO - Cleaning up... 15:57:50 INFO - Return code: 0 15:57:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:57: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')]} 15:57:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57: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')]} 15:57:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:50 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 0x7fd740769f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd83b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6a990>, '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 0x1cd6470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd6900>, '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': '0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057', '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 15:57:50 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 15:57:50 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 15:57:50 INFO - Using env: {'DISPLAY': ':0', 15:57:50 INFO - 'HOME': '/home/cltbld', 15:57:50 INFO - 'LANG': 'en_US.UTF-8', 15:57:50 INFO - 'LANGUAGE': 'en_US:en', 15:57:50 INFO - 'LOGNAME': 'cltbld', 15:57:50 INFO - 'MAIL': '/var/mail/cltbld', 15:57:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:50 INFO - 'MOZ_NO_REMOTE': '1', 15:57:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:50 INFO - 'NO_EM_RESTART': '1', 15:57:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:50 INFO - 'PWD': '/builds/slave/test', 15:57:50 INFO - 'SHELL': '/bin/bash', 15:57:50 INFO - 'SHLVL': '1', 15:57:50 INFO - 'TERM': 'linux', 15:57:50 INFO - 'TMOUT': '86400', 15:57:50 INFO - 'USER': 'cltbld', 15:57:50 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057', 15:57:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:50 INFO - '_': '/tools/buildbot/bin/python'} 15:57:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:50 INFO - Downloading/unpacking psutil>=3.1.1 15:57:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:57:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:57:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:57:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:57:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:57:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:57:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:57:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:57:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:57:52 INFO - Installing collected packages: psutil 15:57:52 INFO - Running setup.py install for psutil 15:57:52 INFO - building 'psutil._psutil_linux' extension 15:57:52 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 15:57:52 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 15:57:52 INFO - building 'psutil._psutil_posix' extension 15:57:52 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 15:57:52 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 15:57:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:57:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:57:53 INFO - Successfully installed psutil 15:57:53 INFO - Cleaning up... 15:57:53 INFO - Return code: 0 15:57:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:57: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')]} 15:57:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57: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')]} 15:57:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:53 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 0x7fd740769f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd83b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6a990>, '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 0x1cd6470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd6900>, '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': '0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057', '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 15:57:53 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 15:57:53 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 15:57:53 INFO - Using env: {'DISPLAY': ':0', 15:57:53 INFO - 'HOME': '/home/cltbld', 15:57:53 INFO - 'LANG': 'en_US.UTF-8', 15:57:53 INFO - 'LANGUAGE': 'en_US:en', 15:57:53 INFO - 'LOGNAME': 'cltbld', 15:57:53 INFO - 'MAIL': '/var/mail/cltbld', 15:57:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:53 INFO - 'MOZ_NO_REMOTE': '1', 15:57:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:53 INFO - 'NO_EM_RESTART': '1', 15:57:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:53 INFO - 'PWD': '/builds/slave/test', 15:57:53 INFO - 'SHELL': '/bin/bash', 15:57:53 INFO - 'SHLVL': '1', 15:57:53 INFO - 'TERM': 'linux', 15:57:53 INFO - 'TMOUT': '86400', 15:57:53 INFO - 'USER': 'cltbld', 15:57:53 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057', 15:57:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:53 INFO - '_': '/tools/buildbot/bin/python'} 15:57:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:57:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:57:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:57:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:57:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:57:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:57:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:57:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:57:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:57:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:57:55 INFO - Installing collected packages: mozsystemmonitor 15:57:55 INFO - Running setup.py install for mozsystemmonitor 15:57:55 INFO - Successfully installed mozsystemmonitor 15:57:55 INFO - Cleaning up... 15:57:55 INFO - Return code: 0 15:57:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:57:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fd740769f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd83b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6a990>, '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 0x1cd6470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd6900>, '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': '0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057', '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 15:57:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:57:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:57:55 INFO - Using env: {'DISPLAY': ':0', 15:57:55 INFO - 'HOME': '/home/cltbld', 15:57:55 INFO - 'LANG': 'en_US.UTF-8', 15:57:55 INFO - 'LANGUAGE': 'en_US:en', 15:57:55 INFO - 'LOGNAME': 'cltbld', 15:57:55 INFO - 'MAIL': '/var/mail/cltbld', 15:57:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:55 INFO - 'MOZ_NO_REMOTE': '1', 15:57:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:55 INFO - 'NO_EM_RESTART': '1', 15:57:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:55 INFO - 'PWD': '/builds/slave/test', 15:57:55 INFO - 'SHELL': '/bin/bash', 15:57:55 INFO - 'SHLVL': '1', 15:57:55 INFO - 'TERM': 'linux', 15:57:55 INFO - 'TMOUT': '86400', 15:57:55 INFO - 'USER': 'cltbld', 15:57:55 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057', 15:57:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:55 INFO - '_': '/tools/buildbot/bin/python'} 15:57:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:55 INFO - Downloading/unpacking jsonschema==2.5.1 15:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:57:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:57:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:57:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:57:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:57:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:57:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:57:58 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 15:57:58 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 15:57:58 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 15:57:58 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 15:57:58 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 15:57:58 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 15:57:58 INFO - Downloading functools32-3.2.3-2.tar.gz 15:57:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:57:58 INFO - warning: no files found matching '*.txt' 15:57:58 INFO - no previously-included directories found matching 'build' 15:57:58 INFO - no previously-included directories found matching 'dist' 15:57:58 INFO - no previously-included directories found matching '.git*' 15:57:58 INFO - Installing collected packages: jsonschema, functools32 15:57:58 INFO - Running setup.py install for functools32 15:57:58 INFO - warning: no files found matching '*.txt' 15:57:58 INFO - no previously-included directories found matching 'build' 15:57:58 INFO - no previously-included directories found matching 'dist' 15:57:58 INFO - no previously-included directories found matching '.git*' 15:57:58 INFO - Successfully installed jsonschema functools32 15:57:58 INFO - Cleaning up... 15:57:58 INFO - Return code: 0 15:57:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:57:58 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')]} 15:57:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:58 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')]} 15:57:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:57:58 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 0x7fd740769f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd83b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6a990>, '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 0x1cd6470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd6900>, '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': '0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057', '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 15:57:58 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 15:57:58 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 15:57:58 INFO - Using env: {'DISPLAY': ':0', 15:57:58 INFO - 'HOME': '/home/cltbld', 15:57:58 INFO - 'LANG': 'en_US.UTF-8', 15:57:58 INFO - 'LANGUAGE': 'en_US:en', 15:57:58 INFO - 'LOGNAME': 'cltbld', 15:57:58 INFO - 'MAIL': '/var/mail/cltbld', 15:57:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:58 INFO - 'MOZ_NO_REMOTE': '1', 15:57:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:58 INFO - 'NO_EM_RESTART': '1', 15:57:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:58 INFO - 'PWD': '/builds/slave/test', 15:57:58 INFO - 'SHELL': '/bin/bash', 15:57:58 INFO - 'SHLVL': '1', 15:57:58 INFO - 'TERM': 'linux', 15:57:58 INFO - 'TMOUT': '86400', 15:57:58 INFO - 'USER': 'cltbld', 15:57:58 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057', 15:57:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:58 INFO - '_': '/tools/buildbot/bin/python'} 15:57:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:59 INFO - Downloading/unpacking blobuploader==1.2.4 15:57:59 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 15:57:59 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 15:57:59 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 15:57:59 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 15:57:59 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 15:57:59 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 15:58:00 INFO - Downloading blobuploader-1.2.4.tar.gz 15:58:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:58:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:58:01 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 15:58:01 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 15:58:01 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 15:58:01 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 15:58:01 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 15:58:01 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 15:58:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:58:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:58:01 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 15:58:01 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 15:58:01 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 15:58:01 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 15:58:01 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 15:58:01 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 15:58:01 INFO - Downloading docopt-0.6.1.tar.gz 15:58:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:58:01 INFO - Installing collected packages: blobuploader, requests, docopt 15:58:01 INFO - Running setup.py install for blobuploader 15:58:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:58:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:58:01 INFO - Running setup.py install for requests 15:58:02 INFO - Running setup.py install for docopt 15:58:02 INFO - Successfully installed blobuploader requests docopt 15:58:02 INFO - Cleaning up... 15:58:02 INFO - Return code: 0 15:58:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:58:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:58:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:58:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:58:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:58:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:02 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 0x7fd740769f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd83b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6a990>, '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 0x1cd6470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd6900>, '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': '0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057', '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 15:58:02 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 15:58:02 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 15:58:02 INFO - Using env: {'DISPLAY': ':0', 15:58:02 INFO - 'HOME': '/home/cltbld', 15:58:02 INFO - 'LANG': 'en_US.UTF-8', 15:58:02 INFO - 'LANGUAGE': 'en_US:en', 15:58:02 INFO - 'LOGNAME': 'cltbld', 15:58:02 INFO - 'MAIL': '/var/mail/cltbld', 15:58:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:02 INFO - 'MOZ_NO_REMOTE': '1', 15:58:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:58:02 INFO - 'NO_EM_RESTART': '1', 15:58:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:58:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:02 INFO - 'PWD': '/builds/slave/test', 15:58:02 INFO - 'SHELL': '/bin/bash', 15:58:02 INFO - 'SHLVL': '1', 15:58:02 INFO - 'TERM': 'linux', 15:58:02 INFO - 'TMOUT': '86400', 15:58:02 INFO - 'USER': 'cltbld', 15:58:02 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057', 15:58:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:02 INFO - '_': '/tools/buildbot/bin/python'} 15:58:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:58:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:58:02 INFO - Running setup.py (path:/tmp/pip-ZvZ2qC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:58:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:58:02 INFO - Running setup.py (path:/tmp/pip-K8ZwdX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:58:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:58:02 INFO - Running setup.py (path:/tmp/pip-qoyUJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:58:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:58:02 INFO - Running setup.py (path:/tmp/pip-aRcm9Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:58:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:58:02 INFO - Running setup.py (path:/tmp/pip-Yov4EK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:58:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:58:02 INFO - Running setup.py (path:/tmp/pip-hZ4Ya6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:58:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:58:02 INFO - Running setup.py (path:/tmp/pip-pgV5S4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:58:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:58:03 INFO - Running setup.py (path:/tmp/pip-zGzPNY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:58:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:58:03 INFO - Running setup.py (path:/tmp/pip-oGxLmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:58:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:58:03 INFO - Running setup.py (path:/tmp/pip-FA2PXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:58:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:58:03 INFO - Running setup.py (path:/tmp/pip-4hV2dr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:58:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:58:03 INFO - Running setup.py (path:/tmp/pip-XJ6fFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:58:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:58:03 INFO - Running setup.py (path:/tmp/pip-RECKTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:58:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:58:03 INFO - Running setup.py (path:/tmp/pip-dNsR7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:58:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:58:03 INFO - Running setup.py (path:/tmp/pip-oLfl0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:58:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:58:03 INFO - Running setup.py (path:/tmp/pip-E5sY3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:58:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:58:03 INFO - Running setup.py (path:/tmp/pip-0RwvH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:58:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:58:03 INFO - Running setup.py install for manifestparser 15:58:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:58:03 INFO - Running setup.py install for mozcrash 15:58:03 INFO - Running setup.py install for mozdebug 15:58:04 INFO - Running setup.py install for mozdevice 15:58:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:58:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:58:04 INFO - Running setup.py install for mozfile 15:58:04 INFO - Running setup.py install for mozhttpd 15:58:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:58:04 INFO - Running setup.py install for mozinfo 15:58:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:58:04 INFO - Running setup.py install for mozInstall 15:58:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:58:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:58:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:58:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:58:04 INFO - Running setup.py install for mozleak 15:58:04 INFO - Running setup.py install for mozlog 15:58:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:58:04 INFO - Running setup.py install for moznetwork 15:58:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:58:04 INFO - Running setup.py install for mozprocess 15:58:05 INFO - Running setup.py install for mozprofile 15:58:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:58:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:58:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:58:05 INFO - Running setup.py install for mozrunner 15:58:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:58:05 INFO - Running setup.py install for mozscreenshot 15:58:05 INFO - Running setup.py install for moztest 15:58:05 INFO - Running setup.py install for mozversion 15:58:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:58:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:58:05 INFO - Cleaning up... 15:58:05 INFO - Return code: 0 15:58:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:58:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:58:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:58:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:58:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:05 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 0x7fd740769f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd83b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6a990>, '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 0x1cd6470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd6900>, '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': '0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057', '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 15:58:05 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 15:58:05 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 15:58:05 INFO - Using env: {'DISPLAY': ':0', 15:58:05 INFO - 'HOME': '/home/cltbld', 15:58:05 INFO - 'LANG': 'en_US.UTF-8', 15:58:05 INFO - 'LANGUAGE': 'en_US:en', 15:58:05 INFO - 'LOGNAME': 'cltbld', 15:58:05 INFO - 'MAIL': '/var/mail/cltbld', 15:58:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:05 INFO - 'MOZ_NO_REMOTE': '1', 15:58:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:58:05 INFO - 'NO_EM_RESTART': '1', 15:58:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:58:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:05 INFO - 'PWD': '/builds/slave/test', 15:58:05 INFO - 'SHELL': '/bin/bash', 15:58:05 INFO - 'SHLVL': '1', 15:58:05 INFO - 'TERM': 'linux', 15:58:05 INFO - 'TMOUT': '86400', 15:58:05 INFO - 'USER': 'cltbld', 15:58:05 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057', 15:58:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:05 INFO - '_': '/tools/buildbot/bin/python'} 15:58:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:58:05 INFO - Running setup.py (path:/tmp/pip-hlrwmT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:58:05 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)) 15:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:58:05 INFO - Running setup.py (path:/tmp/pip-QhFart-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:58:05 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)) 15:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:58:05 INFO - Running setup.py (path:/tmp/pip-gr3hsp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:58:06 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)) 15:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:58:06 INFO - Running setup.py (path:/tmp/pip-HKKg1k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:58:06 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)) 15:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:58:06 INFO - Running setup.py (path:/tmp/pip-wLJNMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:58:06 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)) 15:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:58:06 INFO - Running setup.py (path:/tmp/pip-GbQlEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:58:06 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)) 15:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:58:06 INFO - Running setup.py (path:/tmp/pip-_QqChX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:58:06 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)) 15:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:58:06 INFO - Running setup.py (path:/tmp/pip-SvpP5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:58:06 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)) 15:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:58:06 INFO - Running setup.py (path:/tmp/pip-wxL_Ey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:58:06 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)) 15:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:58:06 INFO - Running setup.py (path:/tmp/pip-VCNRep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:58:06 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)) 15:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:58:06 INFO - Running setup.py (path:/tmp/pip-X2ecf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:58:06 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)) 15:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:58:06 INFO - Running setup.py (path:/tmp/pip-_ci3qq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:58:06 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)) 15:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:58:06 INFO - Running setup.py (path:/tmp/pip-YSe37x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:58:06 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)) 15:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:58:06 INFO - Running setup.py (path:/tmp/pip-Rfs32W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:58:06 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)) 15:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:58:06 INFO - Running setup.py (path:/tmp/pip-t6VAiL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:58:06 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)) 15:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:58:06 INFO - Running setup.py (path:/tmp/pip-ddZrT4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:58:06 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)) 15:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:58:06 INFO - Running setup.py (path:/tmp/pip-KxamOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:58:07 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)) 15:58:07 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)) 15:58:07 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)) 15:58:07 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)) 15:58:07 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)) 15:58:07 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)) 15:58:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:58:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:58:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:58:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:58:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:58:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:58:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:58:08 INFO - Downloading blessings-1.6.tar.gz 15:58:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:58:09 INFO - Installing collected packages: blessings 15:58:09 INFO - Running setup.py install for blessings 15:58:09 INFO - Successfully installed blessings 15:58:09 INFO - Cleaning up... 15:58:09 INFO - Return code: 0 15:58:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:58:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:58:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:58:09 INFO - Reading from file tmpfile_stdout 15:58:09 INFO - Current package versions: 15:58:09 INFO - argparse == 1.2.1 15:58:09 INFO - blessings == 1.6 15:58:09 INFO - blobuploader == 1.2.4 15:58:09 INFO - docopt == 0.6.1 15:58:09 INFO - functools32 == 3.2.3-2 15:58:09 INFO - jsonschema == 2.5.1 15:58:09 INFO - manifestparser == 1.1 15:58:09 INFO - mozInstall == 1.12 15:58:09 INFO - mozcrash == 0.17 15:58:09 INFO - mozdebug == 0.1 15:58:09 INFO - mozdevice == 0.48 15:58:09 INFO - mozfile == 1.2 15:58:09 INFO - mozhttpd == 0.7 15:58:09 INFO - mozinfo == 0.9 15:58:09 INFO - mozleak == 0.1 15:58:09 INFO - mozlog == 3.2 15:58:09 INFO - moznetwork == 0.27 15:58:09 INFO - mozprocess == 0.22 15:58:09 INFO - mozprofile == 0.28 15:58:09 INFO - mozrunner == 6.11 15:58:09 INFO - mozscreenshot == 0.1 15:58:09 INFO - mozsystemmonitor == 0.3 15:58:09 INFO - moztest == 0.7 15:58:09 INFO - mozversion == 1.4 15:58:09 INFO - psutil == 3.1.1 15:58:09 INFO - requests == 1.2.3 15:58:09 INFO - wsgiref == 0.1.2 15:58:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:58:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:58:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:58:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:58:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:09 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 0x7fd740769f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd83b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6a990>, '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 0x1cd6470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd6900>, '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': '0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057', '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 15:58:09 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 15:58:09 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 15:58:09 INFO - Using env: {'DISPLAY': ':0', 15:58:09 INFO - 'HOME': '/home/cltbld', 15:58:09 INFO - 'LANG': 'en_US.UTF-8', 15:58:09 INFO - 'LANGUAGE': 'en_US:en', 15:58:09 INFO - 'LOGNAME': 'cltbld', 15:58:09 INFO - 'MAIL': '/var/mail/cltbld', 15:58:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:09 INFO - 'MOZ_NO_REMOTE': '1', 15:58:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:58:09 INFO - 'NO_EM_RESTART': '1', 15:58:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:58:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:09 INFO - 'PWD': '/builds/slave/test', 15:58:09 INFO - 'SHELL': '/bin/bash', 15:58:09 INFO - 'SHLVL': '1', 15:58:09 INFO - 'TERM': 'linux', 15:58:09 INFO - 'TMOUT': '86400', 15:58:09 INFO - 'USER': 'cltbld', 15:58:09 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057', 15:58:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:09 INFO - '_': '/tools/buildbot/bin/python'} 15:58:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:58:09 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)) 15:58:09 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)) 15:58:09 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)) 15:58:09 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)) 15:58:09 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)) 15:58:09 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)) 15:58:09 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)) 15:58:09 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)) 15:58:09 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)) 15:58:09 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)) 15:58:09 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)) 15:58:09 INFO - Cleaning up... 15:58:09 INFO - Return code: 0 15:58:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:58:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:58:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:58:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:58:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:09 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 0x7fd740769f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd83b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6a990>, '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 0x1cd6470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd6900>, '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': '0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057', '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 15:58:09 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 15:58:09 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 15:58:09 INFO - Using env: {'DISPLAY': ':0', 15:58:09 INFO - 'HOME': '/home/cltbld', 15:58:09 INFO - 'LANG': 'en_US.UTF-8', 15:58:09 INFO - 'LANGUAGE': 'en_US:en', 15:58:09 INFO - 'LOGNAME': 'cltbld', 15:58:09 INFO - 'MAIL': '/var/mail/cltbld', 15:58:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:09 INFO - 'MOZ_NO_REMOTE': '1', 15:58:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:58:09 INFO - 'NO_EM_RESTART': '1', 15:58:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:58:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:09 INFO - 'PWD': '/builds/slave/test', 15:58:09 INFO - 'SHELL': '/bin/bash', 15:58:09 INFO - 'SHLVL': '1', 15:58:09 INFO - 'TERM': 'linux', 15:58:09 INFO - 'TMOUT': '86400', 15:58:09 INFO - 'USER': 'cltbld', 15:58:09 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057', 15:58:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:09 INFO - '_': '/tools/buildbot/bin/python'} 15:58:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:58:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:58:09 INFO - Cleaning up... 15:58:09 INFO - Return code: 0 15:58:09 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:58:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:58:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:58:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:58:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:58:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:58:09 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 0x7fd740769f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c0a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd83b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d6a990>, '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 0x1cd6470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd6900>, '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': '0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057', '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 15:58:09 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 15:58:09 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 15:58:09 INFO - Using env: {'DISPLAY': ':0', 15:58:09 INFO - 'HOME': '/home/cltbld', 15:58:09 INFO - 'LANG': 'en_US.UTF-8', 15:58:09 INFO - 'LANGUAGE': 'en_US:en', 15:58:09 INFO - 'LOGNAME': 'cltbld', 15:58:09 INFO - 'MAIL': '/var/mail/cltbld', 15:58:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:09 INFO - 'MOZ_NO_REMOTE': '1', 15:58:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:58:09 INFO - 'NO_EM_RESTART': '1', 15:58:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:58:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:09 INFO - 'PWD': '/builds/slave/test', 15:58:09 INFO - 'SHELL': '/bin/bash', 15:58:09 INFO - 'SHLVL': '1', 15:58:09 INFO - 'TERM': 'linux', 15:58:09 INFO - 'TMOUT': '86400', 15:58:09 INFO - 'USER': 'cltbld', 15:58:09 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057', 15:58:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:09 INFO - '_': '/tools/buildbot/bin/python'} 15:58:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:58:10 INFO - Downloading/unpacking flake8 15:58:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:58:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:58:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:58:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:58:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:58:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:58:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:58:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:58:13 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 15:58:13 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 15:58:13 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 15:58:13 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 15:58:13 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 15:58:13 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 15:58:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:58:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:58:13 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 15:58:13 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 15:58:13 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 15:58:13 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 15:58:13 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 15:58:13 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 15:58:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:58:13 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 15:58:13 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 15:58:13 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 15:58:13 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 15:58:13 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 15:58:13 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 15:58:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:58:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:58:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:58:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:58:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:58:13 INFO - no previously-included directories found matching 'docs/_build' 15:58:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:58:13 INFO - Running setup.py install for pep8 15:58:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:58:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:58:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:58:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:58:13 INFO - no previously-included directories found matching 'docs/_build' 15:58:13 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:58:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:58:13 INFO - Cleaning up... 15:58:13 INFO - Return code: 0 15:58:13 INFO - Running post-action listener: _resource_record_post_action 15:58:13 INFO - Running post-action listener: _start_resource_monitoring 15:58:13 INFO - Starting resource monitoring. 15:58:14 INFO - ##### 15:58:14 INFO - ##### Finished create-virtualenv step (success) 15:58:14 INFO - ##### 15:58:14 INFO - ##### 15:58:14 INFO - ##### Running install step. 15:58:14 INFO - ##### 15:58:14 INFO - Running pre-action listener: _resource_record_pre_action 15:58:14 INFO - Running main action method: install 15:58:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:58:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:58:14 INFO - Reading from file tmpfile_stdout 15:58:14 INFO - Detecting whether we're running mozinstall >=1.0... 15:58:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:58:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:58:14 INFO - Reading from file tmpfile_stdout 15:58:14 INFO - Output received: 15:58:14 INFO - Usage: mozinstall [options] installer 15:58:14 INFO - Options: 15:58:14 INFO - -h, --help show this help message and exit 15:58:14 INFO - -d DEST, --destination=DEST 15:58:14 INFO - Directory to install application into. [default: 15:58:14 INFO - "/builds/slave/test"] 15:58:14 INFO - --app=APP Application being installed. [default: firefox] 15:58:14 INFO - mkdir: /builds/slave/test/build/application 15:58:14 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'] 15:58:14 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 15:58:30 INFO - Reading from file tmpfile_stdout 15:58:30 INFO - Output received: 15:58:30 INFO - /builds/slave/test/build/application/firefox/firefox 15:58:30 INFO - Running post-action listener: _resource_record_post_action 15:58:30 INFO - ##### 15:58:30 INFO - ##### Finished install step (success) 15:58:30 INFO - ##### 15:58:30 INFO - ##### 15:58:30 INFO - ##### Running run-tests step. 15:58:30 INFO - ##### 15:58:30 INFO - Running pre-action listener: _resource_record_pre_action 15:58:30 INFO - Running main action method: run_tests 15:58:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:58:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:58:30 INFO - Python 2.7.3 15:58:30 INFO - Return code: 0 15:58:30 INFO - grabbing minidump binary from tooltool 15:58:30 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')]} 15:58:30 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 0x1d6a990>, '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 0x1cd6470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1cd6900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:58:30 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 15:58:30 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 15:58:30 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 15:58:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:58:30 INFO - Return code: 0 15:58:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:58:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:58:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:58:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:58:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:58:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:58:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:58:31 INFO - Return code: 0 15:58:31 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/DYkl1rXAQKyqhOB3_4xE_g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 15:58:31 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/DYkl1rXAQKyqhOB3_4xE_g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --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 15:58:31 INFO - Using env: {'DISPLAY': ':0', 15:58:31 INFO - 'HOME': '/home/cltbld', 15:58:31 INFO - 'LANG': 'en_US.UTF-8', 15:58:31 INFO - 'LANGUAGE': 'en_US:en', 15:58:31 INFO - 'LOGNAME': 'cltbld', 15:58:31 INFO - 'MAIL': '/var/mail/cltbld', 15:58:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:58:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:58:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:58:31 INFO - 'MOZ_NO_REMOTE': '1', 15:58:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:58:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:58:31 INFO - 'NO_EM_RESTART': '1', 15:58:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:58:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:58:31 INFO - 'PWD': '/builds/slave/test', 15:58:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:58:31 INFO - 'SHELL': '/bin/bash', 15:58:31 INFO - 'SHLVL': '1', 15:58:31 INFO - 'TERM': 'linux', 15:58:31 INFO - 'TMOUT': '86400', 15:58:31 INFO - 'USER': 'cltbld', 15:58:31 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057', 15:58:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:58:31 INFO - '_': '/tools/buildbot/bin/python'} 15:58:31 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/DYkl1rXAQKyqhOB3_4xE_g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 15:58:31 INFO - mozversion application_buildid: 20160606144430 15:58:31 INFO - mozversion application_changeset: d54e29ad6279e546d01c4659e22cdee5a82514e1 15:58:31 INFO - mozversion application_display_name: Nightly 15:58:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:58:31 INFO - mozversion application_name: Firefox 15:58:31 INFO - mozversion application_remotingname: firefox 15:58:31 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:58:31 INFO - mozversion application_vendor: Mozilla 15:58:31 INFO - mozversion application_version: 50.0a1 15:58:31 INFO - mozversion platform_buildid: 20160606144430 15:58:31 INFO - mozversion platform_changeset: d54e29ad6279e546d01c4659e22cdee5a82514e1 15:58:31 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:58:31 INFO - mozversion platform_version: 50.0a1 15:58:31 INFO - using testdate: 1465253911 15:58:31 INFO - actual date: 1465253911 15:58:31 INFO - starting webserver on 'localhost:56751' 15:58:31 INFO - SUITE-START | Running 2 tests 15:58:31 INFO - TEST-START | dromaeo_css 15:58:31 INFO - operating with platform_type : linux_ 15:58:31 INFO - Initialising browser for dromaeo_css test... 15:58:31 INFO - TEST-INFO | started process 3635 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpv3hYaM/profile http://localhost:56751/getInfo.html) 15:58:35 INFO - PROCESS | 3635 | __metrics Screen width/height:1600/1200 15:58:35 INFO - PROCESS | 3635 | colorDepth:24 15:58:35 INFO - PROCESS | 3635 | Browser inner width/height: 1024/697 15:58:35 INFO - PROCESS | 3635 | __metrics 15:58:36 ERROR - PROCESS | 3635 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:58:36 INFO - PROCESS | 3635 | console.error: PushService: 15:58:36 INFO - PROCESS | 3635 | stateChangeProcessEnqueue: Error transitioning state 15:58:36 INFO - PROCESS | 3635 | UnknownError 15:58:37 INFO - TEST-INFO | 3635: exit 0 15:58:37 INFO - Browser initialized. 15:58:37 INFO - Running cycle 1/1 for dromaeo_css test... 15:58:37 INFO - TEST-INFO | started process 3716 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpv3hYaM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:58:49 INFO - PROCESS | 3716 | RSS: Main: 157478912 15:58:49 INFO - PROCESS | 3716 | 15:59:40 INFO - PROCESS | 3716 | 1465253980658 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] 16:01:52 INFO - PROCESS | 3716 | Cycle 1(1): loaded http://localhost:56751/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56751/tests/dromaeo/cssquery-ext.html) 16:01:53 INFO - PROCESS | 3716 | RSS: Main: 245051392 16:01:53 INFO - PROCESS | 3716 | 16:04:55 INFO - PROCESS | 3716 | Cycle 1(1): loaded http://localhost:56751/tests/dromaeo/cssquery-ext.html (next: http://localhost:56751/tests/dromaeo/cssquery-jquery.html) 16:04:56 INFO - PROCESS | 3716 | RSS: Main: 240525312 16:04:56 INFO - PROCESS | 3716 | 16:07:58 INFO - PROCESS | 3716 | Cycle 1(1): loaded http://localhost:56751/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56751/tests/dromaeo/cssquery-mootools.html) 16:07:58 INFO - PROCESS | 3716 | RSS: Main: 235646976 16:07:58 INFO - PROCESS | 3716 | 16:11:01 INFO - PROCESS | 3716 | Cycle 1(1): loaded http://localhost:56751/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56751/tests/dromaeo/cssquery-prototype.html) 16:11:01 INFO - PROCESS | 3716 | RSS: Main: 233033728 16:11:01 INFO - PROCESS | 3716 | 16:14:04 INFO - PROCESS | 3716 | Cycle 1(1): loaded http://localhost:56751/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56751/tests/dromaeo/cssquery-yui.html) 16:14:05 INFO - PROCESS | 3716 | RSS: Main: 217763840 16:14:05 INFO - PROCESS | 3716 | 16:17:08 INFO - PROCESS | 3716 | Cycle 1(1): loaded http://localhost:56751/tests/dromaeo/cssquery-yui.html (next: http://localhost:56751/tests/dromaeo/cssquery-dojo.html) 16:17:08 INFO - PROCESS | 3716 | RSS: Main: 320716800 16:17:08 INFO - PROCESS | 3716 | 16:17:08 INFO - PROCESS | 3716 | __start_tp_report 16:17:08 INFO - PROCESS | 3716 | _x_x_mozilla_page_load 16:17:08 INFO - PROCESS | 3716 | _x_x_mozilla_page_load_details 16:17:08 INFO - PROCESS | 3716 | |i|pagename|runs| 16:17:08 INFO - PROCESS | 3716 | |0;dojo.html;2704.891804327827;2734.6308248386463;2747.8351298922066;2777.236260028492;2788.9023884164044;4657.534246575341;4734.90530189396;4791.448983366914;4825.059113472874;4859.392575928009;4780.402449693788;4801.351817504636;4811.831585894494;4812.542808433193;4863.975680121604;4374.518802931683;4387.605115539601;4447.584175754195;4507.3689683444345;4568;4510.616597589209;4540.636749060084;4546.136234115524;4549.863504094866;4595.264757638778;4279.636230920375;4326.697131200817;4376.387305777193;4393.604575588212;4431;4356.237658409766;4356.803943822536;4397.384366188736;4471.28459446487;4477.9104417911785;4400.559944005606;4405.383246345515;4407.867763967054;4410.3825464435;4459.866204013885;4580.473245576768;4669.299605059268;4722.386089808303;4738.64460165489;4771.809127634925;5187;5241.816536421207;5251.6061295403;5253.6322457428;5255.605829562798;5214.582833373359;5226.817061402832;5280.102382594968;5289.3123893893935;5293.841184764463;4648.767561621906;4768.856934291976;4810.927836082462;4815.855524334275;4908.312836202935;5318.840434786963;5322.28149199853;5328.413874473882;5333.066713325153;5335.386430560535;5076.289319495274;5078.771455284521;5120.61595380348;5123.795048198031;5131.050755610224;4975.726335051534;5160.974195129001;5163.793448262102;5198.6880787152895;5214.32213812202;5124.641275110781;5132;5132.974335128375;5133.512316329943;5162.74186290684;5108.131617624938;5136.9743151284;5140.742962851842;5141.485851414828;5167.612429067835;4316.611504964505;4350.673699472489;4355.194289056535;4401.075774087413;4436.3567282743825;3841.4814000109627;3856.9421458678144;3862.729608927348;3863.3239183142837;3879.2047630235734;4352;4381.3208952612395;4387.561243875651;4404.779761011937;4444.777761111932;5123.103456895028;5124.92312615311;5128.948710512847;5170.793168273302;5180.430152683201;4297.505786834491;4391.187630288343;4392.077663690597;4393.165298593321;4441.267190913528;4674.11191873543;4675.719456832601;4680.461746899082;4689.460712018094;4700.9059818803435;4880.877978050577;4888.706677599355;4888.877778055577;4890.608751299958;4939.16034274167;4986.32684587576;5116.28372027909;5217.9478205217465;5224.764885580082;5273.628856497262;4832.468428472936;4832.613390928787;4899.853004409873;4904.288878112582;4906.092372911023;5092.185250359925;5095.745212739423;5098.719570423514;5099.490050994871;5147.536721695065;4953.826616068564;5136.8202112925865;5141.408737995179;5143.7428128594165;5148.0476111919415;5013.197888337849;5041.143005689115;5069.569086627678;5075.467075957119;5078.111330517218;5075.695458272516;5076.974615126901;5080;5093.108705976513;5132.486751324837;4839.370881785346;4858.5384388483035;5012.674176178537;5013.298138260573;5022.020705962448;4982.701037937735;4986.12742770021;4997.550220480174;5006.374203224597;5056.089903817348;4885.511448855086;4893.045856058035;4893.314935909047;4894.608431325414;4943.332650092191 16:17:08 INFO - PROCESS | 3716 | |1;ext.html;545.765320912008;594.7502049139362;595.5801160182073;609.7469550136693;615.1603061820616;28975.710242897563;31710.84144579274;31817.36365272696;39679.40480892789;41109.38335924964;41405.58594414055;41750.37374439378;41904.37143442843;42007.57992420075;42067.36898946518;7501.399888008974;7697.307242348187;7699.307062364386;7700.576468294242;7700.768976930701;7649.158592554814;7779.688812447495;7793.844123117534;7795.805104872366;7800.960995195044;7770.494917830323;7830.960845195766;7831.686732530692;7833.764987050369;7838.647260873246;7775.261350171724;7795.727149549795;7816.8827467588035;7818.257265559762;7819;7670.578118203553;7744.6514906829325;7746.186650401683;7752.379809615217;7754.922450775476;34793.478097828556;35089;35144.648553514395;35187;35197;2994.4909365407934;3264.5103234514777;3589.766665166782;3636.218213084173;3696.667299943018;29562.113136605938;29576.26059348512;29785;30085.398292034257;30118;3478.901567198371;3526.312369088029;3529.4705794130828;3572.392693242129;3577.2487777566744;232335;234105;234132.99999999828;235225.99999999828;235497;52404.47595523996;52648;52787.472125279026;52967.20549191765;53004.46995530072;33178.834105829796;34354;34407.139821504665;36221.637783622355;36327.81836090803;15473.767893481607;15675;15738.370465181493;15770.68458630844;15772.76340854888;10985.780284394426;11070.335779853234;11080.279781814157;11123;11150.888491115147;18225;18272.54318642018;18275.45173644793;18309.267629294678;18325.816741832678;6801.761938332134;6881.552699074544;6898.655067246718;7017.403520700797;7055.541389809749;793.9880901786478;862.9741107766777;927.0636656976368;942.9198518126036;1028.100412139378;26570.335741606228;26605.600915986277;26768.33079173036;27262.182134535997;27422.5886611701;881.3037700216757;992.6575331510762;1002.6039714312806;1062.9468526573794;1128.351198061118;14922.477713280201;15010.69978600422;15262.768246207766;15301.6174595636;15310.846891530942;385465;385612;389666;390095;391673;49349.25976110361;49992.75003625031;50041.24938125931;50245.74877125664;50253.73858176318;1049.9370037797603;1167.6146871532374;1168.3282112785182;1186.851643544557;1254.7615952968906;27619.44761104807;29069.47829114222;29316.85341573364;29349.41301174007;29438.852805735834;42362.15275694407;42796.572034279256;42951.785241073594;43010.784946076325;43065;292078;292791;293521;293774;294491;22233.333000010025;24542.263732088068;24774.752252477243;25176.37059073538;25372.238832835046;42124.15751685011;42189.57810421979;42507.14985700331;42550.78724606481;43066.13867722565;35550.46674299939;35636.821815890755;36247.63752362548;36343.273134536634;36489;36147.819260903525;36559;36711.449328259565;36785.63214367929;36955.26089478249;35583.46624800578;35931.46102808512;36126.63873361338;36443.45334819927;36529.81735091307;30284.394312114076;32921.67078329186;33113.17217069496;33177.17057073592;33609;25302;25760.61359079653;25815;25839.870800645876;25854.482910342063 16:17:08 INFO - PROCESS | 3716 | |2;jquery.html;349.11847584848255;356.30876100365305;356.6166371151012;359.9874004409845;382.3442795605536;15636.296366663499;16848.073355965418;17393.217305221264;17396.217170227337;17432;15873.682526349496;16476.505704828844;16734.58163545912;16741.330346786144;16762.664746705057;4930.753462326897;5105.795768169269;5171.09505836479;5175.482451754832;5180.430152683201;3696.6672999430048;3867.1105645701505;5161.793528258865;5701.173329867167;6047.062705280667;6068.908966365508;6082.118092876531;6095.3599872013465;6103.420175083382;6104.084387341912;5878.50032747217;5997.88004239915;6025.3372129065765;6059.6364218147055;6072.666003369791;3303.884364047247;4914.287428322909;5211.609129315317;5224.39919409269;5225.085610018232;19185.424437266764;19800.207991680316;19813.10841012158;20116.899415502976;20139.395818125333;2015.5162760937417;2035.6234096692012;2077.480629842539;2114.8731076135327;2389.438481956739;6259.561830671855;6293.3391993840905;11104.55581776728;12559.183653062613;12781.744365112738;1695.101596154028;1708.6924353616344;1737.1835237438384;1754.3421217043608;1765.2674140231788;50884;63502.04746928846;63837;64127.679361602895;65427.345726542604;7918.445708800328;8741;9014.729558113268;9134.706687856697;9364.063593640582;12850.29323387204;12901.935490322676;12926.224426534438;12953.222806631633;12967.286799225945;7068.611226382598;7184;7332.376747976375;7490.101187857492;7491.70033198666;7398.297161769623;7406.259374062551;7475.0656167979005;7577.991832164481;7609.809754756176;5869.4717475427415;5888.587798854125;5915.6098316895495;5997.160397544348;6023.66869822164;1556.6497538053848;1569.7723830044808;1572.3789103304132;1588.7855139556011;1674.6064674801412;839.5927974932275;848.2069265237024;873.6505397840788;882.960266787996;926.5969303352928;16188.190590470429;16898.239579218964;16956.406525771537;17161.056141912315;17210;863.8445079885555;888.5157589113949;902.0303174087751;925.2413021322451;940.036462625814;9862.802743945082;9962;9994.10053095225;10015.899841001497;10070.748231294276;354035;357229;358652;359432;372198;69788;70327.64836175786;70739;71546.3494872842;71559;969.2197780786535;975.7463059604554;983.2232536296389;1001.62439085343;1003.3628645809963;14892.851071489147;14931.17878516692;15111.848881511483;15151;15219.923900380427;32317;32877.50683739794;33474.832625836716;33626.159346016546;33774.83112584421;38861.41707874441;43510;45626.54373456223;51346;52111.218331724296;7210.206877243394;7376.7786966391095;7430.108386993596;7521.924780752342;7571.394288457021;9431.056894310514;11214.607488738182;11366.545338186379;11761.117916156494;11822.527098916291;9910.30627856043;9930.155936745168;9953.900460995586;10239.897601023895;10341;10077.848832267375;10196.796064078528;10322.432266225429;10348.327358721659;10567.788644227227;9507.524623768923;9960.701178964642;9980.650677226262;10168.33905796121;10328.535215915152;7397.556146631219;7461.067366579177;7493.512921660074;7519.736809211651;7682.347000505019;7640.656170472231;9586.66446674363;10086.546105425421;10145.695629131138;10225.846612300673 16:17:08 INFO - PROCESS | 3716 | |3;mootools.html;1274.2163569404806;1423.0536693099089;1459.5767227504025;1595.4017243533674;1754.2106052276472;6847.383735463807;7478.962605186974;7691.307782299585;7713;7926.445148839583;7916.0500739911295;7922.524648521078;7988.600569971492;7988.9600551997155;7991;1871.747314112591;2056.557840064386;2175.8041776240175;2215.4572129828216;2218.1127548980376;2189.737231532218;2227.209340684058;2231.3975226688785;2233.7542870284337;2234.4748983988748;2171.7176767020264;2173.739151301846;2185.0604240176717;2233.5421238646118;2239.261043855524;1757.4200513830406;2054.815066643994;2136.8717876927435;2175.8694478331195;2182.9126834926665;2210.6462965925534;2253.616885129532;2257.085880218514;2258.5708715344194;2262;7753.96123019387;7756.534607923542;7758.6896524138965;7765.961170194169;7788.727394541221;4596.931046034279;4738.2892566115015;4748.548887855648;4751;4753.833615823464;2701.8243814152215;2765.9170224893287;2851;2860.6567211934494;2877.1512403840707;877.9297656187554;880.4497189256715;895.7626229049307;915.345527947514;921.6912334367995;10767.730806729816;11016.559337626486;11018.11855051602;11022.614208502664;11028.50371733274;4191.182719369694;4313.352997050405;4334.306510958231;4381.868543943687;4387.3857659927635;9284.767880803034;9373.343865929455;9374.203192728559;9377;9382.953085234667;4724.220503616864;4743.1225223333795;4745.857624271277;4754.191787396151;4770.61835053195;3659.908502287411;4084.7549147051272;4237.491501019897;4280.72175308604;4281.01536646573;3623.0761155905075;3691.852325906934;3716.1638631307737;3744.737868349244;3781.300459415017;1167.258790667932;1172.4489489939715;1178.9705257368635;1188.4861682233836;1191.142377488207;809.3969992355615;832.558783396277;901.9774505637412;921.7234829551107;927.2906226736552;5466;5475.178723191473;5488.451154884479;5491.643043202179;5731.455511726379;806.7015204374419;881.9029906710257;912.2018234045211;933.500577191213;949.7957939043139;3859.26673931952;3882.359410697203;3927;3943.1127996200626;3959.5644479107277;9329.440233586007;9778.559964801601;9782.119609235204;9789.608415663293;9804.65683701067;9486.098820612031;9570;9583.760405989766;9586.66446674363;9665;7065.399441047466;7308.963455182797;7310.4151667866445;7314.268573142643;7320.926790732131;5282.55098316632;5415.756290966995;5487.670739755627;5553.722313884209;5563.082091454865;8628.050914399286;8636.913630863612;8725.869111963453;8891.510966896623;8894.42186257891;8779.692710755093;8959.820803583762;8960.86558701607;8975.640974360953;9039.502827344559;5813.883722325614;6074.605150665193;6077.544184186116;6174.8147555573405;6269.874602508015;6989.685464154024;7058.047163632844;7219;7498.812529686583;7522.398208143445;6468.838279043061;6588.308227636029;6593.967030164818;6660.200775906922;6690.330966903271;6738.191417029988;6744.7976560703255;6772.5936443813525;6789.592624442549;6840;6483.351664833479;6545.410913017851;6598.27418983921;6622.735090596322;6623.602583844985;4437.534058923896;4517.09658068381;4581.793819278208;4587.17430862448;4666.813327466999;4525.63794896414;4546.158960592301;4559.156556037011;4669.976650116728;4686.625069994324 16:17:08 INFO - PROCESS | 3716 | |4;prototype.html;276.4760778325074;283.4882937176615;288.88155856098996;290.23667753807507;293.1937172774869;4770.475247722748;4774.37933068701;4804.375431193945;4818.180909245432;4879.219324908016;4559.338895860098;4642.744649044301;4649.232876575369;4655.324977878206;4781.947971446285;3650.361186792314;3711.127884947042;3717.6654101130894;3758.2671379080953;3782.8865134046023;3733.8693145739903;3742.2141350316465;3756.6431189037;3764.341240282953;3796.7152463565203;3687.51093012217;3758.5113935188388;3787.0153760022317;3792.924141517168;3808.238352329531;3754.361758501048;3754.3805272130066;3763.8118094095184;3767.6044015378266;3783.1298801275725;3327.4010678077934;3329.417351963397;3333.983330083334;3336.8832090876704;3348.4262396673525;4655.7439340836245;4663.836765713184;4665.206914824454;4680.297955306697;4685.2269375553;4243.236217480852;4292.463442069741;4301.677374196947;4304.655627549789;4344.63070638996;5051.848444546669;5053.595712343041;5063.772130254148;5081.872953176163;5085.262636917626;3644.234710710754;3651.6994293594867;3656.341858465475;3665.8533658653623;3683.1344634010984;5181.611379146579;5209.6874187548865;5323.653962492464;5353.57171426285;5385.892282154374;3716.554013518395;3741.251749650081;3748.475213470116;3751.662350388478;3757.5866654667966;3783.3379158647126;3797.734158608871;3802.144517483544;3813.7986534241627;3826.349520581508;4316.69783115179;4323.416338794223;4338.219120558329;4357.673174511924;4374.518802931675;4971.1797553404;5093.286939828427;5095.541401273903;5097.770600322994;5138.614603904722;1305.5691621764795;1307.2483322089836;1307.8822905938512;1318.1497933832625;1322.365264672943;849.3969281809984;868.4615538366253;886.264400547544;896.4710820615868;901.4501154295874;4342.934855977162;4351.69538132334;4362.258416069276;4372.868813935587;4378.408914796525;5140.40885298188;5144.459831717616;5187.507186817246;5195.740212989335;5216.765245563959;4233.661307095425;4405.207062728739;4412.889677758018;4423;4432.534583868712;2730.225353520188;2730.5494593392264;2739.397332586828;2748.7663548598193;2763.3644261819895;4857.9028419431415;4882.51174882516;4888.120138375127;4891.828785992473;4918.163912134945;5097.655078663856;5231.933386312386;5240.9475905241225;5262.684508759534;5307.177387504943;4819.95180048202;4824.2632652245165;4824.372831531949;4849.9030019399415;4854.368932038954;5075.086484432837;5079.593632509316;5080.568151707146;5109.616778741535;5118.952419032416;4857.392825896763;5003.574696150868;5136.794528218829;5146.639735218499;5182.32629758129;4842;4848.442429120696;4922.138625740553;4922.975385123194;4927.969298881234;3624.1483251435366;3629.805770921678;3640.927181456409;3665.1386924073354;3669.0460480274787;5148.382194136728;5259.001579399804;5261.289725886956;5289.6032797539565;5312.389075256395;5039.697618142924;5075.771590278372;5092.490750924878;5103.8468845934685;5106.7446627669215;5090;5113.335266415343;5122.641415100908;5135.922961155511;5146.511081447256;4796.592289655418;4931.753412329441;4939.5554400104165;5043.798248070036;5053.393592768891;5030;5066.08810414129;5067.670601410897;5091.770870310771;5109.233614957786;4976.751162441936;5008.424031236309;5012.774425150804;5017;5020.196768517019 16:17:08 INFO - PROCESS | 3716 | |5;yui.html;664.8902931016382;679.2969276798515;705.6965504832922;724.9710011599536;741.7811745535066;12396;12703.872961270385;12921.806172907414;12939.223646581211;12958.963282937366;12702.80945785814;12884.226946383224;12896.548620798274;12902.870971290284;12908.483660653586;625.3402660193494;747.6299231880226;763.6143747407551;772.3280745751205;776.4875182379631;771.4753967302232;776.3207193705508;776.3362325211947;776.3827756933223;776.79026662801;822.5393779483478;823.1398188892591;823.8434697407473;824.0811495182858;824.5671022713094;643.8841008618447;781.4568874632133;806.899137607799;807.5316316536395;818.643889907889;598.530153829242;600.4355905448864;600.5075837812996;600.6786369292407;600.7416810771366;11783.057355411547;11806.350650714206;11807.40962951849;11807.881921180764;11826;12356.196847204992;12617.053720970964;13045.804312935314;13079.86920130796;13135.408906599227;10738.140948724084;11190.496427660775;11405.20163588549;11639.825402618968;11848.229865058744;4019.3970904364287;4047.3692100134854;4078.6737061035046;4152.501699796044;4164.479440069991;55082.44917550813;55184;55308.44691553073;55328.17007744927;55681;9914.504274786375;10021.699349019542;10026.448545329931;10440.73898152537;10453.738656533495;17633.647327053386;17728.645427091385;17738;17790.110494475484;17803.46589602314;6555.344465553502;6816.965915170393;6960.825979350456;6967.686454109577;7255.818604534823;3692.316921369555;3697.7411581189594;3702.5186725725484;3718.330700473941;3812.046988252937;2631.644727961739;2746.5056289868016;2748.532749432601;2750.917472475829;2755.407587368725;506.5365190850331;522.3966318901674;524.3210042994332;546.3416583017455;580.5587753307517;568.6360729133359;570.7916610437161;611.5107913669046;627.9246490421178;648.222133439874;8478.74563763088;8744.519051452231;8794.34042446819;8927.821443571092;8999.64001439935;636.5257882877194;640.7405000974612;646.1341801985362;647.5499527828208;662.1888186970922;4014.2774300625583;4138.296489596776;4172.7704976226;4257.10600773841;4270.974966008198;91890;96090.51954740321;97266.51366743262;98834.5058274704;99375.50312248392;60713;73966.63016684882;74013.62993185107;82497.17502825015;82899.5855020721;69085.3091469099;71124;71268.28731712721;71783.28216717766;71817.64091179511;8872.334574907038;9052.683156089506;9079.954600226956;9092.080100670713;9335.439873607605;30468.84765576158;30813.537796932615;30956.22609434782;36174.63825361713;37417.43873841949;56633.71683141558;61524.69237653783;61668.38331616626;61922;62450.68774656098;10278.126359259546;10283;10546.419946902686;11538.826917596074;12061.095417843522;12880;13352.86647133555;13627.114237574526;13640.931795340959;13830.170189788645;11677.299362038304;11930.403479826147;12193.69515762113;12461.501539938663;12528.93735531353;11755.588554400554;11889.643310700692;11967.880321197024;12136.575219867262;12597.307148106942;12405.565805196775;12451.190672606252;12515.74968500643;12588.811167832673;12635.05237107239;9383.812323753351;9501.524923753923;9575.916594611404;9581.952090239503;9933.106020458194;9171.54142292896;9449.480278584744;9951.701448956543;9982;10008.64969726056 16:17:08 INFO - PROCESS | 3716 | __end_tp_report 16:17:08 INFO - PROCESS | 3716 | __start_cc_report 16:17:08 INFO - PROCESS | 3716 | _x_x_mozilla_cycle_collect,846 16:17:08 INFO - PROCESS | 3716 | __end_cc_report 16:17:08 INFO - PROCESS | 3716 | __startTimestamp1465255028344__endTimestamp 16:17:08 INFO - PROCESS | 3716 | 16:17:08 INFO - PROCESS | 3716 | ------- Summary: start ------- 16:17:08 INFO - PROCESS | 3716 | Number of tests: 6 16:17:08 INFO - PROCESS | 3716 | 16:17:08 INFO - PROCESS | 3716 | [#0] dojo.html Cycles:165 Average:4768.61 Median:4890.61 stddev:498.24 (10.2%) stddev-sans-first:472.73 16:17:08 INFO - PROCESS | 3716 | Values: 2704.9 2734.6 2747.8 2777.2 2788.9 4657.5 4734.9 4791.4 4825.1 4859.4 4780.4 4801.4 4811.8 4812.5 4864.0 4374.5 4387.6 4447.6 4507.4 4568.0 4510.6 4540.6 4546.1 4549.9 4595.3 4279.6 4326.7 4376.4 4393.6 4431.0 4356.2 4356.8 4397.4 4471.3 4477.9 4400.6 4405.4 4407.9 4410.4 4459.9 4580.5 4669.3 4722.4 4738.6 4771.8 5187.0 5241.8 5251.6 5253.6 5255.6 5214.6 5226.8 5280.1 5289.3 5293.8 4648.8 4768.9 4810.9 4815.9 4908.3 5318.8 5322.3 5328.4 5333.1 5335.4 5076.3 5078.8 5120.6 5123.8 5131.1 4975.7 5161.0 5163.8 5198.7 5214.3 5124.6 5132.0 5133.0 5133.5 5162.7 5108.1 5137.0 5140.7 5141.5 5167.6 4316.6 4350.7 4355.2 4401.1 4436.4 3841.5 3856.9 3862.7 3863.3 3879.2 4352.0 4381.3 4387.6 4404.8 4444.8 5123.1 5124.9 5128.9 5170.8 5180.4 4297.5 4391.2 4392.1 4393.2 4441.3 4674.1 4675.7 4680.5 4689.5 4700.9 4880.9 4888.7 4888.9 4890.6 4939.2 4986.3 5116.3 5217.9 5224.8 5273.6 4832.5 4832.6 4899.9 4904.3 4906.1 5092.2 5095.7 5098.7 5099.5 5147.5 4953.8 5136.8 5141.4 5143.7 5148.0 5013.2 5041.1 5069.6 5075.5 5078.1 5075.7 5077.0 5080.0 5093.1 5132.5 4839.4 4858.5 5012.7 5013.3 5022.0 4982.7 4986.1 4997.6 5006.4 5056.1 4885.5 4893.0 4893.3 4894.6 4943.3 16:17:08 INFO - PROCESS | 3716 | 16:17:08 INFO - PROCESS | 3716 | [#1] ext.html Cycles:180 Average:45679.91 Median:19760.61 stddev:82068.97 (415.3%) stddev-sans-first:82229.23 16:17:08 INFO - PROCESS | 3716 | Values: 545.8 594.8 595.6 609.7 615.2 28975.7 31710.8 31817.4 39679.4 41109.4 41405.6 41750.4 41904.4 42007.6 42067.4 7501.4 7697.3 7699.3 7700.6 7700.8 7649.2 7779.7 7793.8 7795.8 7801.0 7770.5 7831.0 7831.7 7833.8 7838.6 7775.3 7795.7 7816.9 7818.3 7819.0 7670.6 7744.7 7746.2 7752.4 7754.9 34793.5 35089.0 35144.6 35187.0 35197.0 2994.5 3264.5 3589.8 3636.2 3696.7 29562.1 29576.3 29785.0 30085.4 30118.0 3478.9 3526.3 3529.5 3572.4 3577.2 232335.0 234105.0 234133.0 235226.0 235497.0 52404.5 52648.0 52787.5 52967.2 53004.5 33178.8 34354.0 34407.1 36221.6 36327.8 15473.8 15675.0 15738.4 15770.7 15772.8 10985.8 11070.3 11080.3 11123.0 11150.9 18225.0 18272.5 18275.5 18309.3 18325.8 6801.8 6881.6 6898.7 7017.4 7055.5 794.0 863.0 927.1 942.9 1028.1 26570.3 26605.6 26768.3 27262.2 27422.6 881.3 992.7 1002.6 1062.9 1128.4 14922.5 15010.7 15262.8 15301.6 15310.8 385465.0 385612.0 389666.0 390095.0 391673.0 49349.3 49992.8 50041.2 50245.7 50253.7 1049.9 1167.6 1168.3 1186.9 1254.8 27619.4 29069.5 29316.9 29349.4 29438.9 42362.2 42796.6 42951.8 43010.8 43065.0 292078.0 292791.0 293521.0 293774.0 294491.0 22233.3 24542.3 24774.8 25176.4 25372.2 42124.2 42189.6 42507.1 42550.8 43066.1 35550.5 35636.8 36247.6 36343.3 36489.0 36147.8 36559.0 36711.4 36785.6 36955.3 35583.5 35931.5 36126.6 36443.5 36529.8 30284.4 32921.7 33113.2 33177.2 33609.0 25302.0 25760.6 25815.0 25839.9 25854.5 16:17:08 INFO - PROCESS | 3716 | 16:17:08 INFO - PROCESS | 3716 | [#2] jquery.html Cycles:180 Average:22807.52 Median:27907.38 stddev:59347.23 (212.7%) stddev-sans-first:59489.76 16:17:08 INFO - PROCESS | 3716 | Values: 349.1 356.3 356.6 360.0 382.3 15636.3 16848.1 17393.2 17396.2 17432.0 15873.7 16476.5 16734.6 16741.3 16762.7 4930.8 5105.8 5171.1 5175.5 5180.4 3696.7 3867.1 5161.8 5701.2 6047.1 6068.9 6082.1 6095.4 6103.4 6104.1 5878.5 5997.9 6025.3 6059.6 6072.7 3303.9 4914.3 5211.6 5224.4 5225.1 19185.4 19800.2 19813.1 20116.9 20139.4 2015.5 2035.6 2077.5 2114.9 2389.4 6259.6 6293.3 11104.6 12559.2 12781.7 1695.1 1708.7 1737.2 1754.3 1765.3 50884.0 63502.0 63837.0 64127.7 65427.3 7918.4 8741.0 9014.7 9134.7 9364.1 12850.3 12901.9 12926.2 12953.2 12967.3 7068.6 7184.0 7332.4 7490.1 7491.7 7398.3 7406.3 7475.1 7578.0 7609.8 5869.5 5888.6 5915.6 5997.2 6023.7 1556.6 1569.8 1572.4 1588.8 1674.6 839.6 848.2 873.7 883.0 926.6 16188.2 16898.2 16956.4 17161.1 17210.0 863.8 888.5 902.0 925.2 940.0 9862.8 9962.0 9994.1 10015.9 10070.7 354035.0 357229.0 358652.0 359432.0 372198.0 69788.0 70327.6 70739.0 71546.3 71559.0 969.2 975.7 983.2 1001.6 1003.4 14892.9 14931.2 15111.8 15151.0 15219.9 32317.0 32877.5 33474.8 33626.2 33774.8 38861.4 43510.0 45626.5 51346.0 52111.2 7210.2 7376.8 7430.1 7521.9 7571.4 9431.1 11214.6 11366.5 11761.1 11822.5 9910.3 9930.2 9953.9 10239.9 10341.0 10077.8 10196.8 10322.4 10348.3 10567.8 9507.5 9960.7 9980.7 10168.3 10328.5 7397.6 7461.1 7493.5 7519.7 7682.3 7640.7 9586.7 10086.5 10145.7 10225.8 16:17:08 INFO - PROCESS | 3716 | 16:17:08 INFO - PROCESS | 3716 | [#3] mootools.html Cycles:180 Average:5160.88 Median:5490.05 stddev:2879.13 (52.4%) stddev-sans-first:2872.39 16:17:08 INFO - PROCESS | 3716 | Values: 1274.2 1423.1 1459.6 1595.4 1754.2 6847.4 7479.0 7691.3 7713.0 7926.4 7916.1 7922.5 7988.6 7989.0 7991.0 1871.7 2056.6 2175.8 2215.5 2218.1 2189.7 2227.2 2231.4 2233.8 2234.5 2171.7 2173.7 2185.1 2233.5 2239.3 1757.4 2054.8 2136.9 2175.9 2182.9 2210.6 2253.6 2257.1 2258.6 2262.0 7754.0 7756.5 7758.7 7766.0 7788.7 4596.9 4738.3 4748.5 4751.0 4753.8 2701.8 2765.9 2851.0 2860.7 2877.2 877.9 880.4 895.8 915.3 921.7 10767.7 11016.6 11018.1 11022.6 11028.5 4191.2 4313.4 4334.3 4381.9 4387.4 9284.8 9373.3 9374.2 9377.0 9383.0 4724.2 4743.1 4745.9 4754.2 4770.6 3659.9 4084.8 4237.5 4280.7 4281.0 3623.1 3691.9 3716.2 3744.7 3781.3 1167.3 1172.4 1179.0 1188.5 1191.1 809.4 832.6 902.0 921.7 927.3 5466.0 5475.2 5488.5 5491.6 5731.5 806.7 881.9 912.2 933.5 949.8 3859.3 3882.4 3927.0 3943.1 3959.6 9329.4 9778.6 9782.1 9789.6 9804.7 9486.1 9570.0 9583.8 9586.7 9665.0 7065.4 7309.0 7310.4 7314.3 7320.9 5282.6 5415.8 5487.7 5553.7 5563.1 8628.1 8636.9 8725.9 8891.5 8894.4 8779.7 8959.8 8960.9 8975.6 9039.5 5813.9 6074.6 6077.5 6174.8 6269.9 6989.7 7058.0 7219.0 7498.8 7522.4 6468.8 6588.3 6594.0 6660.2 6690.3 6738.2 6744.8 6772.6 6789.6 6840.0 6483.4 6545.4 6598.3 6622.7 6623.6 4437.5 4517.1 4581.8 4587.2 4666.8 4525.6 4546.2 4559.2 4670.0 4686.6 16:17:08 INFO - PROCESS | 3716 | 16:17:08 INFO - PROCESS | 3716 | [#4] prototype.html Cycles:180 Average:4195.91 Median:4778.16 stddev:1212.31 (25.4%) stddev-sans-first:1179.48 16:17:08 INFO - PROCESS | 3716 | Values: 276.5 283.5 288.9 290.2 293.2 4770.5 4774.4 4804.4 4818.2 4879.2 4559.3 4642.7 4649.2 4655.3 4781.9 3650.4 3711.1 3717.7 3758.3 3782.9 3733.9 3742.2 3756.6 3764.3 3796.7 3687.5 3758.5 3787.0 3792.9 3808.2 3754.4 3754.4 3763.8 3767.6 3783.1 3327.4 3329.4 3334.0 3336.9 3348.4 4655.7 4663.8 4665.2 4680.3 4685.2 4243.2 4292.5 4301.7 4304.7 4344.6 5051.8 5053.6 5063.8 5081.9 5085.3 3644.2 3651.7 3656.3 3665.9 3683.1 5181.6 5209.7 5323.7 5353.6 5385.9 3716.6 3741.3 3748.5 3751.7 3757.6 3783.3 3797.7 3802.1 3813.8 3826.3 4316.7 4323.4 4338.2 4357.7 4374.5 4971.2 5093.3 5095.5 5097.8 5138.6 1305.6 1307.2 1307.9 1318.1 1322.4 849.4 868.5 886.3 896.5 901.5 4342.9 4351.7 4362.3 4372.9 4378.4 5140.4 5144.5 5187.5 5195.7 5216.8 4233.7 4405.2 4412.9 4423.0 4432.5 2730.2 2730.5 2739.4 2748.8 2763.4 4857.9 4882.5 4888.1 4891.8 4918.2 5097.7 5231.9 5240.9 5262.7 5307.2 4820.0 4824.3 4824.4 4849.9 4854.4 5075.1 5079.6 5080.6 5109.6 5119.0 4857.4 5003.6 5136.8 5146.6 5182.3 4842.0 4848.4 4922.1 4923.0 4928.0 3624.1 3629.8 3640.9 3665.1 3669.0 5148.4 5259.0 5261.3 5289.6 5312.4 5039.7 5075.8 5092.5 5103.8 5106.7 5090.0 5113.3 5122.6 5135.9 5146.5 4796.6 4931.8 4939.6 5043.8 5053.4 5030.0 5066.1 5067.7 5091.8 5109.2 4976.8 5008.4 5012.8 5017.0 5020.2 16:17:08 INFO - PROCESS | 3716 | 16:17:08 INFO - PROCESS | 3716 | [#5] yui.html Cycles:180 Average:16877.27 Median:55246.22 stddev:23677.81 (42.9%) stddev-sans-first:23712.94 16:17:08 INFO - PROCESS | 3716 | Values: 664.9 679.3 705.7 725.0 741.8 12396.0 12703.9 12921.8 12939.2 12959.0 12702.8 12884.2 12896.5 12902.9 12908.5 625.3 747.6 763.6 772.3 776.5 771.5 776.3 776.3 776.4 776.8 822.5 823.1 823.8 824.1 824.6 643.9 781.5 806.9 807.5 818.6 598.5 600.4 600.5 600.7 600.7 11783.1 11806.4 11807.4 11807.9 11826.0 12356.2 12617.1 13045.8 13079.9 13135.4 10738.1 11190.5 11405.2 11639.8 11848.2 4019.4 4047.4 4078.7 4152.5 4164.5 55082.4 55184.0 55308.4 55328.2 55681.0 9914.5 10021.7 10026.4 10440.7 10453.7 17633.6 17728.6 17738.0 17790.1 17803.5 6555.3 6817.0 6960.8 6967.7 7255.8 3692.3 3697.7 3702.5 3718.3 3812.0 2631.6 2746.5 2748.5 2750.9 2755.4 506.5 522.4 524.3 546.3 580.6 568.6 570.8 611.5 627.9 648.2 8478.7 8744.5 8794.3 8927.8 8999.6 636.5 640.7 646.1 647.5 662.2 4014.3 4138.3 4172.8 4257.1 4271.0 91890.0 96090.5 97266.5 98834.5 99375.5 60713.0 73966.6 74013.6 82497.2 82899.6 69085.3 71124.0 71268.3 71783.3 71817.6 8872.3 9052.7 9080.0 9092.1 9335.4 30468.8 30813.5 30956.2 36174.6 37417.4 56633.7 61524.7 61668.4 61922.0 62450.7 10278.1 10283.0 10546.4 11538.8 12061.1 12880.0 13352.9 13627.1 13640.9 13830.2 11677.3 11930.4 12193.7 12461.5 12528.9 11755.6 11889.6 11967.9 12136.6 12597.3 12405.6 12451.2 12515.7 12588.8 12635.1 9383.8 9501.5 9575.9 9582.0 9933.1 9171.5 9449.5 9951.7 9982.0 10008.6 16:17:08 INFO - PROCESS | 3716 | -------- Summary: end -------- 16:17:08 INFO - PROCESS | 3716 | 16:17:09 INFO - TEST-INFO | 3716: exit 0 16:17:09 INFO - TEST-OK | dromaeo_css | took 1117613ms 16:17:09 INFO - TEST-START | kraken 16:17:09 INFO - operating with platform_type : linux_ 16:17:09 INFO - Initialising browser for kraken test... 16:17:09 INFO - TEST-INFO | started process 3900 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpurTYt8/profile http://localhost:56751/getInfo.html) 16:17:12 INFO - PROCESS | 3900 | __metrics Screen width/height:1600/1200 16:17:12 INFO - PROCESS | 3900 | colorDepth:24 16:17:12 INFO - PROCESS | 3900 | Browser inner width/height: 1024/697 16:17:12 INFO - PROCESS | 3900 | __metrics 16:17:13 ERROR - PROCESS | 3900 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:17:13 INFO - PROCESS | 3900 | console.error: PushService: 16:17:13 INFO - PROCESS | 3900 | stateChangeProcessEnqueue: Error transitioning state 16:17:13 INFO - PROCESS | 3900 | UnknownError 16:17:14 INFO - TEST-INFO | 3900: exit 0 16:17:14 INFO - Browser initialized. 16:17:14 INFO - Running cycle 1/1 for kraken test... 16:17:14 INFO - TEST-INFO | started process 3982 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpurTYt8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:17:26 INFO - PROCESS | 3982 | RSS: Main: 157728768 16:17:26 INFO - PROCESS | 3982 | 16:17:29 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:56751/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56751/tests/kraken/driver.html?testName=audio-beat-detection) 16:17:29 INFO - PROCESS | 3982 | RSS: Main: 215941120 16:17:29 INFO - PROCESS | 3982 | 16:17:32 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:56751/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56751/tests/kraken/driver.html?testName=audio-dft) 16:17:33 INFO - PROCESS | 3982 | RSS: Main: 201822208 16:17:33 INFO - PROCESS | 3982 | 16:17:36 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:56751/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56751/tests/kraken/driver.html?testName=audio-fft) 16:17:37 INFO - PROCESS | 3982 | RSS: Main: 270618624 16:17:37 INFO - PROCESS | 3982 | 16:17:39 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:56751/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56751/tests/kraken/driver.html?testName=audio-oscillator) 16:17:40 INFO - PROCESS | 3982 | RSS: Main: 180674560 16:17:40 INFO - PROCESS | 3982 | 16:17:42 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:56751/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56751/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:17:42 INFO - PROCESS | 3982 | RSS: Main: 184283136 16:17:42 INFO - PROCESS | 3982 | 16:17:46 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:56751/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56751/tests/kraken/driver.html?testName=imaging-darkroom) 16:17:46 INFO - PROCESS | 3982 | RSS: Main: 220401664 16:17:46 INFO - PROCESS | 3982 | 16:17:50 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:56751/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56751/tests/kraken/driver.html?testName=imaging-desaturate) 16:17:50 INFO - PROCESS | 3982 | RSS: Main: 216694784 16:17:50 INFO - PROCESS | 3982 | 16:17:53 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:56751/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56751/tests/kraken/driver.html?testName=json-parse-financial) 16:17:54 INFO - PROCESS | 3982 | RSS: Main: 219246592 16:17:54 INFO - PROCESS | 3982 | 16:17:56 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:56751/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56751/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:17:56 INFO - PROCESS | 3982 | RSS: Main: 208322560 16:17:56 INFO - PROCESS | 3982 | 16:17:58 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:56751/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56751/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:17:59 INFO - PROCESS | 3982 | RSS: Main: 212099072 16:17:59 INFO - PROCESS | 3982 | 16:18:01 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:56751/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56751/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:18:02 INFO - PROCESS | 3982 | RSS: Main: 217595904 16:18:02 INFO - PROCESS | 3982 | 16:18:04 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:56751/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56751/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:18:05 INFO - PROCESS | 3982 | RSS: Main: 226103296 16:18:05 INFO - PROCESS | 3982 | 16:18:08 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:56751/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56751/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:18:09 INFO - PROCESS | 3982 | RSS: Main: 226226176 16:18:09 INFO - PROCESS | 3982 | 16:18:10 INFO - PROCESS | 3982 | Cycle 1(1): loaded http://localhost:56751/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56751/tests/kraken/driver.html?testName=ai-astar) 16:18:11 INFO - PROCESS | 3982 | RSS: Main: 268943360 16:18:11 INFO - PROCESS | 3982 | 16:18:11 INFO - PROCESS | 3982 | __start_tp_report 16:18:11 INFO - PROCESS | 3982 | _x_x_mozilla_page_load 16:18:11 INFO - PROCESS | 3982 | _x_x_mozilla_page_load_details 16:18:11 INFO - PROCESS | 3982 | |i|pagename|runs| 16:18:11 INFO - PROCESS | 3982 | |0;ai-astar;113;108;113;114;113;112;113;112;114;112 16:18:11 INFO - PROCESS | 3982 | |1;audio-beat-detection;167;130;133;185;133;131;164;128;171;132 16:18:11 INFO - PROCESS | 3982 | |2;audio-dft;162;159;161;161;160;162;161;160;160;161 16:18:11 INFO - PROCESS | 3982 | |3;audio-fft;77;76;75;116;77;77;76;75;97;75 16:18:11 INFO - PROCESS | 3982 | |4;audio-oscillator;85;86;118;86;86;86;114;87;86;130 16:18:11 INFO - PROCESS | 3982 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 16:18:11 INFO - PROCESS | 3982 | |6;imaging-darkroom;146;146;145;146;146;147;146;146;146;147 16:18:11 INFO - PROCESS | 3982 | |7;imaging-desaturate;95;95;95;95;94;94;94;95;95;94 16:18:11 INFO - PROCESS | 3982 | |8;json-parse-financial;66;63;64;65;65;66;66;65;65;64 16:18:11 INFO - PROCESS | 3982 | |9;json-stringify-tinderbox;65;66;67;67;65;67;66;66;66;65 16:18:11 INFO - PROCESS | 3982 | |10;stanford-crypto-aes;85;83;84;90;87;83;84;86;122;89 16:18:11 INFO - PROCESS | 3982 | |11;stanford-crypto-ccm;145;132;131;132;141;158;132;150;149;142 16:18:11 INFO - PROCESS | 3982 | |12;stanford-crypto-pbkdf2;172;169;169;169;170;175;170;170;172;172 16:18:11 INFO - PROCESS | 3982 | |13;stanford-crypto-sha256-iterative;67;67;68;71;66;70;71;67;71;65 16:18:11 INFO - PROCESS | 3982 | __end_tp_report 16:18:11 INFO - PROCESS | 3982 | __start_cc_report 16:18:11 INFO - PROCESS | 3982 | _x_x_mozilla_cycle_collect,1879 16:18:11 INFO - PROCESS | 3982 | __end_cc_report 16:18:11 INFO - PROCESS | 3982 | __startTimestamp1465255091032__endTimestamp 16:18:11 INFO - PROCESS | 3982 | 16:18:11 INFO - PROCESS | 3982 | ------- Summary: start ------- 16:18:11 INFO - PROCESS | 3982 | Number of tests: 14 16:18:11 INFO - PROCESS | 3982 | 16:18:11 INFO - PROCESS | 3982 | [#0] ai-astar Cycles:10 Average:112.40 Median:113.00 stddev:1.71 (1.5%) stddev-sans-first:1.80 16:18:11 INFO - PROCESS | 3982 | Values: 113.0 108.0 113.0 114.0 113.0 112.0 113.0 112.0 114.0 112.0 16:18:11 INFO - PROCESS | 3982 | 16:18:11 INFO - PROCESS | 3982 | [#1] audio-beat-detection Cycles:10 Average:147.40 Median:148.50 stddev:21.68 (14.6%) stddev-sans-first:21.80 16:18:11 INFO - PROCESS | 3982 | Values: 167.0 130.0 133.0 185.0 133.0 131.0 164.0 128.0 171.0 132.0 16:18:11 INFO - PROCESS | 3982 | 16:18:11 INFO - PROCESS | 3982 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.95 (0.6%) stddev-sans-first:0.88 16:18:11 INFO - PROCESS | 3982 | Values: 162.0 159.0 161.0 161.0 160.0 162.0 161.0 160.0 160.0 161.0 16:18:11 INFO - PROCESS | 3982 | 16:18:11 INFO - PROCESS | 3982 | [#3] audio-fft Cycles:10 Average:82.10 Median:76.50 stddev:13.64 (17.8%) stddev-sans-first:14.34 16:18:11 INFO - PROCESS | 3982 | Values: 77.0 76.0 75.0 116.0 77.0 77.0 76.0 75.0 97.0 75.0 16:18:11 INFO - PROCESS | 3982 | 16:18:11 INFO - PROCESS | 3982 | [#4] audio-oscillator Cycles:10 Average:96.40 Median:86.00 stddev:17.21 (20.0%) stddev-sans-first:17.75 16:18:11 INFO - PROCESS | 3982 | Values: 85.0 86.0 118.0 86.0 86.0 86.0 114.0 87.0 86.0 130.0 16:18:11 INFO - PROCESS | 3982 | 16:18:11 INFO - PROCESS | 3982 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 16:18:11 INFO - PROCESS | 3982 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 16:18:11 INFO - PROCESS | 3982 | 16:18:11 INFO - PROCESS | 3982 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 16:18:11 INFO - PROCESS | 3982 | Values: 146.0 146.0 145.0 146.0 146.0 147.0 146.0 146.0 146.0 147.0 16:18:11 INFO - PROCESS | 3982 | 16:18:11 INFO - PROCESS | 3982 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 16:18:11 INFO - PROCESS | 3982 | Values: 95.0 95.0 95.0 95.0 94.0 94.0 94.0 95.0 95.0 94.0 16:18:11 INFO - PROCESS | 3982 | 16:18:11 INFO - PROCESS | 3982 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:65.00 stddev:0.99 (1.5%) stddev-sans-first:0.97 16:18:11 INFO - PROCESS | 3982 | Values: 66.0 63.0 64.0 65.0 65.0 66.0 66.0 65.0 65.0 64.0 16:18:11 INFO - PROCESS | 3982 | 16:18:11 INFO - PROCESS | 3982 | [#9] json-stringify-tinderbox Cycles:10 Average:66.00 Median:66.00 stddev:0.82 (1.2%) stddev-sans-first:0.78 16:18:11 INFO - PROCESS | 3982 | Values: 65.0 66.0 67.0 67.0 65.0 67.0 66.0 66.0 66.0 65.0 16:18:11 INFO - PROCESS | 3982 | 16:18:11 INFO - PROCESS | 3982 | [#10] stanford-crypto-aes Cycles:10 Average:89.30 Median:85.50 stddev:11.74 (13.7%) stddev-sans-first:12.35 16:18:11 INFO - PROCESS | 3982 | Values: 85.0 83.0 84.0 90.0 87.0 83.0 84.0 86.0 122.0 89.0 16:18:11 INFO - PROCESS | 3982 | 16:18:11 INFO - PROCESS | 3982 | [#11] stanford-crypto-ccm Cycles:10 Average:141.20 Median:143.50 stddev:9.39 (6.5%) stddev-sans-first:9.86 16:18:11 INFO - PROCESS | 3982 | Values: 145.0 132.0 131.0 132.0 141.0 158.0 132.0 150.0 149.0 142.0 16:18:11 INFO - PROCESS | 3982 | 16:18:11 INFO - PROCESS | 3982 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.80 Median:171.00 stddev:1.93 (1.1%) stddev-sans-first:2.00 16:18:11 INFO - PROCESS | 3982 | Values: 172.0 169.0 169.0 169.0 170.0 175.0 170.0 170.0 172.0 172.0 16:18:11 INFO - PROCESS | 3982 | 16:18:11 INFO - PROCESS | 3982 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:69.00 stddev:2.26 (3.3%) stddev-sans-first:2.35 16:18:11 INFO - PROCESS | 3982 | Values: 67.0 67.0 68.0 71.0 66.0 70.0 71.0 67.0 71.0 65.0 16:18:11 INFO - PROCESS | 3982 | -------- Summary: end -------- 16:18:11 INFO - PROCESS | 3982 | 16:18:11 INFO - TEST-INFO | 3982: exit 0 16:18:12 INFO - TEST-OK | kraken | took 62956ms 16:18:12 INFO - SUITE-END | took 1180s 16:18:12 INFO - Completed test suite (00:19:40) 16:18:12 INFO - javascript benchmark 16:18:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2704.891804327827, 2734.6308248386463, 2747.8351298922066, 2777.236260028492, 2788.9023884164044, 4657.534246575341, 4734.90530189396, 4791.448983366914, 4825.059113472874, 4859.392575928009, 4780.402449693788, 4801.351817504636, 4811.831585894494, 4812.542808433193, 4863.975680121604, 4374.518802931683, 4387.605115539601, 4447.584175754195, 4507.3689683444345, 4568.0, 4510.616597589209, 4540.636749060084, 4546.136234115524, 4549.863504094866, 4595.264757638778, 4279.636230920375, 4326.697131200817, 4376.387305777193, 4393.604575588212, 4431.0, 4356.237658409766, 4356.803943822536, 4397.384366188736, 4471.28459446487, 4477.9104417911785, 4400.559944005606, 4405.383246345515, 4407.867763967054, 4410.3825464435, 4459.866204013885, 4580.473245576768, 4669.299605059268, 4722.386089808303, 4738.64460165489, 4771.809127634925, 5187.0, 5241.816536421207, 5251.6061295403, 5253.6322457428, 5255.605829562798, 5214.582833373359, 5226.817061402832, 5280.102382594968, 5289.3123893893935, 5293.841184764463, 4648.767561621906, 4768.856934291976, 4810.927836082462, 4815.855524334275, 4908.312836202935, 5318.840434786963, 5322.28149199853, 5328.413874473882, 5333.066713325153, 5335.386430560535, 5076.289319495274, 5078.771455284521, 5120.61595380348, 5123.795048198031, 5131.050755610224, 4975.726335051534, 5160.974195129001, 5163.793448262102, 5198.6880787152895, 5214.32213812202, 5124.641275110781, 5132.0, 5132.974335128375, 5133.512316329943, 5162.74186290684, 5108.131617624938, 5136.9743151284, 5140.742962851842, 5141.485851414828, 5167.612429067835, 4316.611504964505, 4350.673699472489, 4355.194289056535, 4401.075774087413, 4436.3567282743825, 3841.4814000109627, 3856.9421458678144, 3862.729608927348, 3863.3239183142837, 3879.2047630235734, 4352.0, 4381.3208952612395, 4387.561243875651, 4404.779761011937, 4444.777761111932, 5123.103456895028, 5124.92312615311, 5128.948710512847, 5170.793168273302, 5180.430152683201, 4297.505786834491, 4391.187630288343, 4392.077663690597, 4393.165298593321, 4441.267190913528, 4674.11191873543, 4675.719456832601, 4680.461746899082, 4689.460712018094, 4700.9059818803435, 4880.877978050577, 4888.706677599355, 4888.877778055577, 4890.608751299958, 4939.16034274167, 4986.32684587576, 5116.28372027909, 5217.9478205217465, 5224.764885580082, 5273.628856497262, 4832.468428472936, 4832.613390928787, 4899.853004409873, 4904.288878112582, 4906.092372911023, 5092.185250359925, 5095.745212739423, 5098.719570423514, 5099.490050994871, 5147.536721695065, 4953.826616068564, 5136.8202112925865, 5141.408737995179, 5143.7428128594165, 5148.0476111919415, 5013.197888337849, 5041.143005689115, 5069.569086627678, 5075.467075957119, 5078.111330517218, 5075.695458272516, 5076.974615126901, 5080.0, 5093.108705976513, 5132.486751324837, 4839.370881785346, 4858.5384388483035, 5012.674176178537, 5013.298138260573, 5022.020705962448, 4982.701037937735, 4986.12742770021, 4997.550220480174, 5006.374203224597, 5056.089903817348, 4885.511448855086, 4893.045856058035, 4893.314935909047, 4894.608431325414, 4943.332650092191], "value": 4737.466238610921, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [545.765320912008, 594.7502049139362, 595.5801160182073, 609.7469550136693, 615.1603061820616, 28975.710242897563, 31710.84144579274, 31817.36365272696, 39679.40480892789, 41109.38335924964, 41405.58594414055, 41750.37374439378, 41904.37143442843, 42007.57992420075, 42067.36898946518, 7501.399888008974, 7697.307242348187, 7699.307062364386, 7700.576468294242, 7700.768976930701, 7649.158592554814, 7779.688812447495, 7793.844123117534, 7795.805104872366, 7800.960995195044, 7770.494917830323, 7830.960845195766, 7831.686732530692, 7833.764987050369, 7838.647260873246, 7775.261350171724, 7795.727149549795, 7816.8827467588035, 7818.257265559762, 7819.0, 7670.578118203553, 7744.6514906829325, 7746.186650401683, 7752.379809615217, 7754.922450775476, 34793.478097828556, 35089.0, 35144.648553514395, 35187.0, 35197.0, 2994.4909365407934, 3264.5103234514777, 3589.766665166782, 3636.218213084173, 3696.667299943018, 29562.113136605938, 29576.26059348512, 29785.0, 30085.398292034257, 30118.0, 3478.901567198371, 3526.312369088029, 3529.4705794130828, 3572.392693242129, 3577.2487777566744, 232335.0, 234105.0, 234132.99999999828, 235225.99999999828, 235497.0, 52404.47595523996, 52648.0, 52787.472125279026, 52967.20549191765, 53004.46995530072, 33178.834105829796, 34354.0, 34407.139821504665, 36221.637783622355, 36327.81836090803, 15473.767893481607, 15675.0, 15738.370465181493, 15770.68458630844, 15772.76340854888, 10985.780284394426, 11070.335779853234, 11080.279781814157, 11123.0, 11150.888491115147, 18225.0, 18272.54318642018, 18275.45173644793, 18309.267629294678, 18325.816741832678, 6801.761938332134, 6881.552699074544, 6898.655067246718, 7017.403520700797, 7055.541389809749, 793.9880901786478, 862.9741107766777, 927.0636656976368, 942.9198518126036, 1028.100412139378, 26570.335741606228, 26605.600915986277, 26768.33079173036, 27262.182134535997, 27422.5886611701, 881.3037700216757, 992.6575331510762, 1002.6039714312806, 1062.9468526573794, 1128.351198061118, 14922.477713280201, 15010.69978600422, 15262.768246207766, 15301.6174595636, 15310.846891530942, 385465.0, 385612.0, 389666.0, 390095.0, 391673.0, 49349.25976110361, 49992.75003625031, 50041.24938125931, 50245.74877125664, 50253.73858176318, 1049.9370037797603, 1167.6146871532374, 1168.3282112785182, 1186.851643544557, 1254.7615952968906, 27619.44761104807, 29069.47829114222, 29316.85341573364, 29349.41301174007, 29438.852805735834, 42362.15275694407, 42796.572034279256, 42951.785241073594, 43010.784946076325, 43065.0, 292078.0, 292791.0, 293521.0, 293774.0, 294491.0, 22233.333000010025, 24542.263732088068, 24774.752252477243, 25176.37059073538, 25372.238832835046, 42124.15751685011, 42189.57810421979, 42507.14985700331, 42550.78724606481, 43066.13867722565, 35550.46674299939, 35636.821815890755, 36247.63752362548, 36343.273134536634, 36489.0, 36147.819260903525, 36559.0, 36711.449328259565, 36785.63214367929, 36955.26089478249, 35583.46624800578, 35931.46102808512, 36126.63873361338, 36443.45334819927, 36529.81735091307, 30284.394312114076, 32921.67078329186, 33113.17217069496, 33177.17057073592, 33609.0, 25302.0, 25760.61359079653, 25815.0, 25839.870800645876, 25854.482910342063], "value": 17307.515855294143, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.11847584848255, 356.30876100365305, 356.6166371151012, 359.9874004409845, 382.3442795605536, 15636.296366663499, 16848.073355965418, 17393.217305221264, 17396.217170227337, 17432.0, 15873.682526349496, 16476.505704828844, 16734.58163545912, 16741.330346786144, 16762.664746705057, 4930.753462326897, 5105.795768169269, 5171.09505836479, 5175.482451754832, 5180.430152683201, 3696.6672999430048, 3867.1105645701505, 5161.793528258865, 5701.173329867167, 6047.062705280667, 6068.908966365508, 6082.118092876531, 6095.3599872013465, 6103.420175083382, 6104.084387341912, 5878.50032747217, 5997.88004239915, 6025.3372129065765, 6059.6364218147055, 6072.666003369791, 3303.884364047247, 4914.287428322909, 5211.609129315317, 5224.39919409269, 5225.085610018232, 19185.424437266764, 19800.207991680316, 19813.10841012158, 20116.899415502976, 20139.395818125333, 2015.5162760937417, 2035.6234096692012, 2077.480629842539, 2114.8731076135327, 2389.438481956739, 6259.561830671855, 6293.3391993840905, 11104.55581776728, 12559.183653062613, 12781.744365112738, 1695.101596154028, 1708.6924353616344, 1737.1835237438384, 1754.3421217043608, 1765.2674140231788, 50884.0, 63502.04746928846, 63837.0, 64127.679361602895, 65427.345726542604, 7918.445708800328, 8741.0, 9014.729558113268, 9134.706687856697, 9364.063593640582, 12850.29323387204, 12901.935490322676, 12926.224426534438, 12953.222806631633, 12967.286799225945, 7068.611226382598, 7184.0, 7332.376747976375, 7490.101187857492, 7491.70033198666, 7398.297161769623, 7406.259374062551, 7475.0656167979005, 7577.991832164481, 7609.809754756176, 5869.4717475427415, 5888.587798854125, 5915.6098316895495, 5997.160397544348, 6023.66869822164, 1556.6497538053848, 1569.7723830044808, 1572.3789103304132, 1588.7855139556011, 1674.6064674801412, 839.5927974932275, 848.2069265237024, 873.6505397840788, 882.960266787996, 926.5969303352928, 16188.190590470429, 16898.239579218964, 16956.406525771537, 17161.056141912315, 17210.0, 863.8445079885555, 888.5157589113949, 902.0303174087751, 925.2413021322451, 940.036462625814, 9862.802743945082, 9962.0, 9994.10053095225, 10015.899841001497, 10070.748231294276, 354035.0, 357229.0, 358652.0, 359432.0, 372198.0, 69788.0, 70327.64836175786, 70739.0, 71546.3494872842, 71559.0, 969.2197780786535, 975.7463059604554, 983.2232536296389, 1001.62439085343, 1003.3628645809963, 14892.851071489147, 14931.17878516692, 15111.848881511483, 15151.0, 15219.923900380427, 32317.0, 32877.50683739794, 33474.832625836716, 33626.159346016546, 33774.83112584421, 38861.41707874441, 43510.0, 45626.54373456223, 51346.0, 52111.218331724296, 7210.206877243394, 7376.7786966391095, 7430.108386993596, 7521.924780752342, 7571.394288457021, 9431.056894310514, 11214.607488738182, 11366.545338186379, 11761.117916156494, 11822.527098916291, 9910.30627856043, 9930.155936745168, 9953.900460995586, 10239.897601023895, 10341.0, 10077.848832267375, 10196.796064078528, 10322.432266225429, 10348.327358721659, 10567.788644227227, 9507.524623768923, 9960.701178964642, 9980.650677226262, 10168.33905796121, 10328.535215915152, 7397.556146631219, 7461.067366579177, 7493.512921660074, 7519.736809211651, 7682.347000505019, 7640.656170472231, 9586.66446674363, 10086.546105425421, 10145.695629131138, 10225.846612300673], "value": 8118.24624349566, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1274.2163569404806, 1423.0536693099089, 1459.5767227504025, 1595.4017243533674, 1754.2106052276472, 6847.383735463807, 7478.962605186974, 7691.307782299585, 7713.0, 7926.445148839583, 7916.0500739911295, 7922.524648521078, 7988.600569971492, 7988.9600551997155, 7991.0, 1871.747314112591, 2056.557840064386, 2175.8041776240175, 2215.4572129828216, 2218.1127548980376, 2189.737231532218, 2227.209340684058, 2231.3975226688785, 2233.7542870284337, 2234.4748983988748, 2171.7176767020264, 2173.739151301846, 2185.0604240176717, 2233.5421238646118, 2239.261043855524, 1757.4200513830406, 2054.815066643994, 2136.8717876927435, 2175.8694478331195, 2182.9126834926665, 2210.6462965925534, 2253.616885129532, 2257.085880218514, 2258.5708715344194, 2262.0, 7753.96123019387, 7756.534607923542, 7758.6896524138965, 7765.961170194169, 7788.727394541221, 4596.931046034279, 4738.2892566115015, 4748.548887855648, 4751.0, 4753.833615823464, 2701.8243814152215, 2765.9170224893287, 2851.0, 2860.6567211934494, 2877.1512403840707, 877.9297656187554, 880.4497189256715, 895.7626229049307, 915.345527947514, 921.6912334367995, 10767.730806729816, 11016.559337626486, 11018.11855051602, 11022.614208502664, 11028.50371733274, 4191.182719369694, 4313.352997050405, 4334.306510958231, 4381.868543943687, 4387.3857659927635, 9284.767880803034, 9373.343865929455, 9374.203192728559, 9377.0, 9382.953085234667, 4724.220503616864, 4743.1225223333795, 4745.857624271277, 4754.191787396151, 4770.61835053195, 3659.908502287411, 4084.7549147051272, 4237.491501019897, 4280.72175308604, 4281.01536646573, 3623.0761155905075, 3691.852325906934, 3716.1638631307737, 3744.737868349244, 3781.300459415017, 1167.258790667932, 1172.4489489939715, 1178.9705257368635, 1188.4861682233836, 1191.142377488207, 809.3969992355615, 832.558783396277, 901.9774505637412, 921.7234829551107, 927.2906226736552, 5466.0, 5475.178723191473, 5488.451154884479, 5491.643043202179, 5731.455511726379, 806.7015204374419, 881.9029906710257, 912.2018234045211, 933.500577191213, 949.7957939043139, 3859.26673931952, 3882.359410697203, 3927.0, 3943.1127996200626, 3959.5644479107277, 9329.440233586007, 9778.559964801601, 9782.119609235204, 9789.608415663293, 9804.65683701067, 9486.098820612031, 9570.0, 9583.760405989766, 9586.66446674363, 9665.0, 7065.399441047466, 7308.963455182797, 7310.4151667866445, 7314.268573142643, 7320.926790732131, 5282.55098316632, 5415.756290966995, 5487.670739755627, 5553.722313884209, 5563.082091454865, 8628.050914399286, 8636.913630863612, 8725.869111963453, 8891.510966896623, 8894.42186257891, 8779.692710755093, 8959.820803583762, 8960.86558701607, 8975.640974360953, 9039.502827344559, 5813.883722325614, 6074.605150665193, 6077.544184186116, 6174.8147555573405, 6269.874602508015, 6989.685464154024, 7058.047163632844, 7219.0, 7498.812529686583, 7522.398208143445, 6468.838279043061, 6588.308227636029, 6593.967030164818, 6660.200775906922, 6690.330966903271, 6738.191417029988, 6744.7976560703255, 6772.5936443813525, 6789.592624442549, 6840.0, 6483.351664833479, 6545.410913017851, 6598.27418983921, 6622.735090596322, 6623.602583844985, 4437.534058923896, 4517.09658068381, 4581.793819278208, 4587.17430862448, 4666.813327466999, 4525.63794896414, 4546.158960592301, 4559.156556037011, 4669.976650116728, 4686.625069994324], "value": 4165.0739494224, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [276.4760778325074, 283.4882937176615, 288.88155856098996, 290.23667753807507, 293.1937172774869, 4770.475247722748, 4774.37933068701, 4804.375431193945, 4818.180909245432, 4879.219324908016, 4559.338895860098, 4642.744649044301, 4649.232876575369, 4655.324977878206, 4781.947971446285, 3650.361186792314, 3711.127884947042, 3717.6654101130894, 3758.2671379080953, 3782.8865134046023, 3733.8693145739903, 3742.2141350316465, 3756.6431189037, 3764.341240282953, 3796.7152463565203, 3687.51093012217, 3758.5113935188388, 3787.0153760022317, 3792.924141517168, 3808.238352329531, 3754.361758501048, 3754.3805272130066, 3763.8118094095184, 3767.6044015378266, 3783.1298801275725, 3327.4010678077934, 3329.417351963397, 3333.983330083334, 3336.8832090876704, 3348.4262396673525, 4655.7439340836245, 4663.836765713184, 4665.206914824454, 4680.297955306697, 4685.2269375553, 4243.236217480852, 4292.463442069741, 4301.677374196947, 4304.655627549789, 4344.63070638996, 5051.848444546669, 5053.595712343041, 5063.772130254148, 5081.872953176163, 5085.262636917626, 3644.234710710754, 3651.6994293594867, 3656.341858465475, 3665.8533658653623, 3683.1344634010984, 5181.611379146579, 5209.6874187548865, 5323.653962492464, 5353.57171426285, 5385.892282154374, 3716.554013518395, 3741.251749650081, 3748.475213470116, 3751.662350388478, 3757.5866654667966, 3783.3379158647126, 3797.734158608871, 3802.144517483544, 3813.7986534241627, 3826.349520581508, 4316.69783115179, 4323.416338794223, 4338.219120558329, 4357.673174511924, 4374.518802931675, 4971.1797553404, 5093.286939828427, 5095.541401273903, 5097.770600322994, 5138.614603904722, 1305.5691621764795, 1307.2483322089836, 1307.8822905938512, 1318.1497933832625, 1322.365264672943, 849.3969281809984, 868.4615538366253, 886.264400547544, 896.4710820615868, 901.4501154295874, 4342.934855977162, 4351.69538132334, 4362.258416069276, 4372.868813935587, 4378.408914796525, 5140.40885298188, 5144.459831717616, 5187.507186817246, 5195.740212989335, 5216.765245563959, 4233.661307095425, 4405.207062728739, 4412.889677758018, 4423.0, 4432.534583868712, 2730.225353520188, 2730.5494593392264, 2739.397332586828, 2748.7663548598193, 2763.3644261819895, 4857.9028419431415, 4882.51174882516, 4888.120138375127, 4891.828785992473, 4918.163912134945, 5097.655078663856, 5231.933386312386, 5240.9475905241225, 5262.684508759534, 5307.177387504943, 4819.95180048202, 4824.2632652245165, 4824.372831531949, 4849.9030019399415, 4854.368932038954, 5075.086484432837, 5079.593632509316, 5080.568151707146, 5109.616778741535, 5118.952419032416, 4857.392825896763, 5003.574696150868, 5136.794528218829, 5146.639735218499, 5182.32629758129, 4842.0, 4848.442429120696, 4922.138625740553, 4922.975385123194, 4927.969298881234, 3624.1483251435366, 3629.805770921678, 3640.927181456409, 3665.1386924073354, 3669.0460480274787, 5148.382194136728, 5259.001579399804, 5261.289725886956, 5289.6032797539565, 5312.389075256395, 5039.697618142924, 5075.771590278372, 5092.490750924878, 5103.8468845934685, 5106.7446627669215, 5090.0, 5113.335266415343, 5122.641415100908, 5135.922961155511, 5146.511081447256, 4796.592289655418, 4931.753412329441, 4939.5554400104165, 5043.798248070036, 5053.393592768891, 5030.0, 5066.08810414129, 5067.670601410897, 5091.770870310771, 5109.233614957786, 4976.751162441936, 5008.424031236309, 5012.774425150804, 5017.0, 5020.196768517019], "value": 3808.657475552976, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [664.8902931016382, 679.2969276798515, 705.6965504832922, 724.9710011599536, 741.7811745535066, 12396.0, 12703.872961270385, 12921.806172907414, 12939.223646581211, 12958.963282937366, 12702.80945785814, 12884.226946383224, 12896.548620798274, 12902.870971290284, 12908.483660653586, 625.3402660193494, 747.6299231880226, 763.6143747407551, 772.3280745751205, 776.4875182379631, 771.4753967302232, 776.3207193705508, 776.3362325211947, 776.3827756933223, 776.79026662801, 822.5393779483478, 823.1398188892591, 823.8434697407473, 824.0811495182858, 824.5671022713094, 643.8841008618447, 781.4568874632133, 806.899137607799, 807.5316316536395, 818.643889907889, 598.530153829242, 600.4355905448864, 600.5075837812996, 600.6786369292407, 600.7416810771366, 11783.057355411547, 11806.350650714206, 11807.40962951849, 11807.881921180764, 11826.0, 12356.196847204992, 12617.053720970964, 13045.804312935314, 13079.86920130796, 13135.408906599227, 10738.140948724084, 11190.496427660775, 11405.20163588549, 11639.825402618968, 11848.229865058744, 4019.3970904364287, 4047.3692100134854, 4078.6737061035046, 4152.501699796044, 4164.479440069991, 55082.44917550813, 55184.0, 55308.44691553073, 55328.17007744927, 55681.0, 9914.504274786375, 10021.699349019542, 10026.448545329931, 10440.73898152537, 10453.738656533495, 17633.647327053386, 17728.645427091385, 17738.0, 17790.110494475484, 17803.46589602314, 6555.344465553502, 6816.965915170393, 6960.825979350456, 6967.686454109577, 7255.818604534823, 3692.316921369555, 3697.7411581189594, 3702.5186725725484, 3718.330700473941, 3812.046988252937, 2631.644727961739, 2746.5056289868016, 2748.532749432601, 2750.917472475829, 2755.407587368725, 506.5365190850331, 522.3966318901674, 524.3210042994332, 546.3416583017455, 580.5587753307517, 568.6360729133359, 570.7916610437161, 611.5107913669046, 627.9246490421178, 648.222133439874, 8478.74563763088, 8744.519051452231, 8794.34042446819, 8927.821443571092, 8999.64001439935, 636.5257882877194, 640.7405000974612, 646.1341801985362, 647.5499527828208, 662.1888186970922, 4014.2774300625583, 4138.296489596776, 4172.7704976226, 4257.10600773841, 4270.974966008198, 91890.0, 96090.51954740321, 97266.51366743262, 98834.5058274704, 99375.50312248392, 60713.0, 73966.63016684882, 74013.62993185107, 82497.17502825015, 82899.5855020721, 69085.3091469099, 71124.0, 71268.28731712721, 71783.28216717766, 71817.64091179511, 8872.334574907038, 9052.683156089506, 9079.954600226956, 9092.080100670713, 9335.439873607605, 30468.84765576158, 30813.537796932615, 30956.22609434782, 36174.63825361713, 37417.43873841949, 56633.71683141558, 61524.69237653783, 61668.38331616626, 61922.0, 62450.68774656098, 10278.126359259546, 10283.0, 10546.419946902686, 11538.826917596074, 12061.095417843522, 12880.0, 13352.86647133555, 13627.114237574526, 13640.931795340959, 13830.170189788645, 11677.299362038304, 11930.403479826147, 12193.69515762113, 12461.501539938663, 12528.93735531353, 11755.588554400554, 11889.643310700692, 11967.880321197024, 12136.575219867262, 12597.307148106942, 12405.565805196775, 12451.190672606252, 12515.74968500643, 12588.811167832673, 12635.05237107239, 9383.812323753351, 9501.524923753923, 9575.916594611404, 9581.952090239503, 9933.106020458194, 9171.54142292896, 9449.480278584744, 9951.701448956543, 9982.0, 10008.64969726056], "value": 6540.477835879915, "unit": "score"}], "name": "dromaeo_css", "value": 6405.443772573132, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 108.0, 113.0, 114.0, 113.0, 112.0, 113.0, 112.0, 114.0, 112.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 130.0, 133.0, 185.0, 133.0, 131.0, 164.0, 128.0, 171.0, 132.0], "value": 147.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 159.0, 161.0, 161.0, 160.0, 162.0, 161.0, 160.0, 160.0, 161.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 75.0, 116.0, 77.0, 77.0, 76.0, 75.0, 97.0, 75.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 118.0, 86.0, 86.0, 86.0, 114.0, 87.0, 86.0, 130.0], "value": 96.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 147.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 63.0, 64.0, 65.0, 65.0, 66.0, 66.0, 65.0, 65.0, 64.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 67.0, 65.0, 67.0, 66.0, 66.0, 66.0, 65.0], "value": 66.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 84.0, 90.0, 87.0, 83.0, 84.0, 86.0, 122.0, 89.0], "value": 89.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 132.0, 131.0, 132.0, 141.0, 158.0, 132.0, 150.0, 149.0, 142.0], "value": 141.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 169.0, 169.0, 169.0, 170.0, 175.0, 170.0, 170.0, 172.0, 172.0], "value": 170.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 71.0, 66.0, 70.0, 71.0, 67.0, 71.0, 65.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1557.1999999999998, "alertThreshold": 2.0}]} 16:18:12 INFO - Return code: 0 16:18:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:18:12 ERROR - # TBPL SUCCESS # 16:18:12 INFO - Running post-action listener: _resource_record_post_action 16:18:12 INFO - ##### 16:18:12 INFO - ##### Finished run-tests step (success) 16:18:12 INFO - ##### 16:18:12 INFO - Running post-run listener: _resource_record_post_run 16:18:12 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 540725248; Read time: 8; Write time: 459824 16:18:12 INFO - TinderboxPrint: CPU usage
13.1% 16:18:12 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 16:18:12 INFO - TinderboxPrint: I/O write bytes / time
540,725,248 / 459,824 16:18:12 INFO - TinderboxPrint: CPU idle
8,310.9 (86.9%) 16:18:12 INFO - TinderboxPrint: CPU user
1,209.9 (12.7%) 16:18:12 INFO - TinderboxPrint: Swap in / out
0 / 0 16:18:12 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 244310016; Read time: 0; Write time: 253348 16:18:12 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 4096; Write bytes: 296415232; Read time: 8; Write time: 206476 16:18:12 INFO - Running post-run listener: _upload_blobber_files 16:18:12 INFO - Blob upload gear active. 16:18:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:18:12 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 16:18:12 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'] 16:18:12 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 16:18:13 INFO - (blobuploader) - INFO - Open directory for files ... 16:18:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:18:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:18:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:18:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:18:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:18:14 INFO - (blobuploader) - INFO - Done attempting. 16:18:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:18:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:18:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:18:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:18:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:18:14 INFO - (blobuploader) - INFO - Done attempting. 16:18:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:18:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:18:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:18:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:18:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:18:19 INFO - (blobuploader) - INFO - Done attempting. 16:18:19 INFO - (blobuploader) - INFO - Iteration through files over. 16:18:19 INFO - Return code: 0 16:18:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:18:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:18:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9f9b543411542554409fcdd2651c33a79739cdb2d3d642e1a290955f0ea0fe872c4ea9df9c4bf73d5bb664f4a497d4af732b4a676e3038fb96daf28e05b47f1b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8389b9733d0b726e53b3188901ccad14267f1110ca4d3cc2f839781052492466826b4834cbfc5d3d55e5ce0df98d312c6c1dac2c12566b94f97a8b5eb2bd936a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/84a53b3969daf5b278a2ee2bca1116f93eaae3dc527a7c70646acd6a6851bc79d5e9e07b238a29e9b806522545f87e5f6fe1a847f35fa80b6c71fcc087eada4c"} 16:18:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:18:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:18:19 INFO - Contents: 16:18:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9f9b543411542554409fcdd2651c33a79739cdb2d3d642e1a290955f0ea0fe872c4ea9df9c4bf73d5bb664f4a497d4af732b4a676e3038fb96daf28e05b47f1b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8389b9733d0b726e53b3188901ccad14267f1110ca4d3cc2f839781052492466826b4834cbfc5d3d55e5ce0df98d312c6c1dac2c12566b94f97a8b5eb2bd936a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/84a53b3969daf5b278a2ee2bca1116f93eaae3dc527a7c70646acd6a6851bc79d5e9e07b238a29e9b806522545f87e5f6fe1a847f35fa80b6c71fcc087eada4c"} 16:18:19 INFO - Running post-run listener: copy_logs_to_upload_dir 16:18:19 INFO - Copying logs to upload dir... 16:18:19 INFO - mkdir: /builds/slave/test/build/upload/logs 16:18:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1312.760166 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 52 secs) (at 2016-06-06 16:18:19.740811) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 16:18:19.743868) ========= 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=0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057 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/9f9b543411542554409fcdd2651c33a79739cdb2d3d642e1a290955f0ea0fe872c4ea9df9c4bf73d5bb664f4a497d4af732b4a676e3038fb96daf28e05b47f1b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8389b9733d0b726e53b3188901ccad14267f1110ca4d3cc2f839781052492466826b4834cbfc5d3d55e5ce0df98d312c6c1dac2c12566b94f97a8b5eb2bd936a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/84a53b3969daf5b278a2ee2bca1116f93eaae3dc527a7c70646acd6a6851bc79d5e9e07b238a29e9b806522545f87e5f6fe1a847f35fa80b6c71fcc087eada4c"} build_url:https://queue.taskcluster.net/v1/task/DYkl1rXAQKyqhOB3_4xE_g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011156 build_url: 'https://queue.taskcluster.net/v1/task/DYkl1rXAQKyqhOB3_4xE_g/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/9f9b543411542554409fcdd2651c33a79739cdb2d3d642e1a290955f0ea0fe872c4ea9df9c4bf73d5bb664f4a497d4af732b4a676e3038fb96daf28e05b47f1b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8389b9733d0b726e53b3188901ccad14267f1110ca4d3cc2f839781052492466826b4834cbfc5d3d55e5ce0df98d312c6c1dac2c12566b94f97a8b5eb2bd936a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/84a53b3969daf5b278a2ee2bca1116f93eaae3dc527a7c70646acd6a6851bc79d5e9e07b238a29e9b806522545f87e5f6fe1a847f35fa80b6c71fcc087eada4c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 16:18:19.768203) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 16:18:19.768498) ========= 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=0333a256a5e4f052382dd180000001b1-1465253474.51591-812416057 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004453 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 16:18:19.854654) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 16:18:19.855026) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 16:18:19.855311) ========= ========= Total master_lag: 0.17 =========