builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-038 starttime: 1466131345.59 results: success (0) buildid: 20160616163918 builduid: 33b8ff5c1cca4c00b8eae3034b4275b2 revision: 5f95858f8ddf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-16 19:42:25.586423) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-16 19:42:25.586915) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-16 19:42:25.587257) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-16 19:42:25.667326) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 19:42:25.667656) ========= 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=8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-16 19:42: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% 223M=0s 2016-06-16 19:42:25 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.101049 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 19:42:25.790536) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 19:42:25.790865) ========= 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=8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014508 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 19:42:25.850172) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 19:42:25.850535) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5f95858f8ddf --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 5f95858f8ddf --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=8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-16 19:42:25,958 Setting DEBUG logging. 2016-06-16 19:42:25,958 attempt 1/10 2016-06-16 19:42:25,958 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5f95858f8ddf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-16 19:42:26,430 unpacking tar archive at: mozilla-central-5f95858f8ddf/testing/mozharness/ program finished with exit code 0 elapsedTime=0.803582 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 19:42:26.670285) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-16 19:42:26.670648) ========= script_repo_revision: 5f95858f8ddf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-16 19:42:26.671110) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-16 19:42:26.671446) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-16 19:42:26.691093) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2016-06-16 19:42:26.691389) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:42:26 INFO - MultiFileLogger online at 20160616 19:42:26 in /builds/slave/test 19:42:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:42:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:42:26 INFO - {'append_to_log': False, 19:42:26 INFO - 'base_work_dir': '/builds/slave/test', 19:42:26 INFO - 'blob_upload_branch': 'Firefox', 19:42:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:42:26 INFO - 'branch': 'Firefox', 19:42:26 INFO - 'buildbot_json_path': 'buildprops.json', 19:42:26 INFO - 'config_files': ('talos/linux_config.py',), 19:42:26 INFO - 'default_actions': ('clobber', 19:42:26 INFO - 'read-buildbot-config', 19:42:26 INFO - 'download-and-extract', 19:42:26 INFO - 'populate-webroot', 19:42:26 INFO - 'create-virtualenv', 19:42:26 INFO - 'install', 19:42:26 INFO - 'run-tests'), 19:42:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:42:26 INFO - 'download_minidump_stackwalk': True, 19:42:26 INFO - 'download_symbols': 'ondemand', 19:42:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:42:26 INFO - 'tooltool.py': '/tools/tooltool.py', 19:42:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:42:26 INFO - '/tools/misc-python/virtualenv.py')}, 19:42:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:42:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:42:26 INFO - 'installer_path': 'installer.exe', 19:42:26 INFO - 'log_level': 'info', 19:42:26 INFO - 'log_name': 'talos', 19:42:26 INFO - 'log_to_console': True, 19:42:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:42:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:42:26 INFO - 'opt_config_files': (), 19:42:26 INFO - 'pip_index': False, 19:42:26 INFO - 'sps_profile': False, 19:42:26 INFO - 'sps_profile_interval': 0, 19:42:26 INFO - 'suite': 'dromaeojs', 19:42:26 INFO - 'system_bits': '32', 19:42:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:42:26 INFO - 'title': 'talos-linux64-ix-038', 19:42:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:42:26 INFO - 'use_talos_json': True, 19:42:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:42:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:42:26 INFO - 'work_dir': 'build'} 19:42:26 INFO - ##### 19:42:26 INFO - ##### Running clobber step. 19:42:26 INFO - ##### 19:42:26 INFO - Running pre-action listener: _resource_record_pre_action 19:42:26 INFO - Running main action method: clobber 19:42:26 INFO - rmtree: /builds/slave/test/build 19:42:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:42:27 INFO - Running post-action listener: _resource_record_post_action 19:42:27 INFO - ##### 19:42:27 INFO - ##### Finished clobber step (success) 19:42:27 INFO - ##### 19:42:27 INFO - ##### 19:42:27 INFO - ##### Running read-buildbot-config step. 19:42:27 INFO - ##### 19:42:27 INFO - Running pre-action listener: _resource_record_pre_action 19:42:27 INFO - Running main action method: read_buildbot_config 19:42:27 INFO - Using buildbot properties: 19:42:27 INFO - { 19:42:27 INFO - "project": "", 19:42:27 INFO - "product": "firefox", 19:42:27 INFO - "script_repo_revision": "production", 19:42:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 19:42:27 INFO - "repository": "", 19:42:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 19:42:27 INFO - "buildid": "20160616163918", 19:42:27 INFO - "stage_platform": "linux64-pgo", 19:42:27 INFO - "basedir": "/builds/slave/test", 19:42:27 INFO - "nightly_build": "True", 19:42:27 INFO - "buildnumber": 345, 19:42:27 INFO - "slavename": "talos-linux64-ix-038", 19:42:27 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 19:42:27 INFO - "platform": "ubuntu64_hw", 19:42:27 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 19:42:27 INFO - "branch": "mozilla-central", 19:42:27 INFO - "repo_path": "mozilla-central", 19:42:27 INFO - "revision": "5f95858f8ddf", 19:42:27 INFO - "pgo_build": "True", 19:42:27 INFO - "builduid": "33b8ff5c1cca4c00b8eae3034b4275b2", 19:42:27 INFO - "slavebuilddir": "test" 19:42:27 INFO - } 19:42:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/Jl_cIzTkRkOUlEZunaxnTw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 19:42:27 INFO - Running post-action listener: _resource_record_post_action 19:42:27 INFO - ##### 19:42:27 INFO - ##### Finished read-buildbot-config step (success) 19:42:27 INFO - ##### 19:42:27 INFO - ##### 19:42:27 INFO - ##### Running download-and-extract step. 19:42:27 INFO - ##### 19:42:27 INFO - Running pre-action listener: _resource_record_pre_action 19:42:27 INFO - Running main action method: download_and_extract 19:42:27 INFO - mkdir: /builds/slave/test/build/tests 19:42:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:42:27 INFO - https://queue.taskcluster.net/v1/task/Jl_cIzTkRkOUlEZunaxnTw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 19:42:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jl_cIzTkRkOUlEZunaxnTw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 19:42:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jl_cIzTkRkOUlEZunaxnTw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 19:42:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jl_cIzTkRkOUlEZunaxnTw/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 19:42:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jl_cIzTkRkOUlEZunaxnTw/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 19:42:27 INFO - retry: attempt #1 caught exception: '' 19:42:27 INFO - retry: Failed, sleeping 30 seconds before retrying 19:42:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jl_cIzTkRkOUlEZunaxnTw/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 #2 19:43:07 INFO - Downloaded 1361 bytes. 19:43:07 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 19:43:07 INFO - Using the following test package requirements: 19:43:07 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 19:43:07 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:43:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:43:07 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:43:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:43:07 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:43:07 INFO - u'jsshell-linux-x86_64.zip'], 19:43:07 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:43:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:43:07 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 19:43:07 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:43:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:43:07 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:43:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:43:07 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:43:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:43:07 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 19:43:07 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:43:07 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:43:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:07 INFO - https://queue.taskcluster.net/v1/task/Jl_cIzTkRkOUlEZunaxnTw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:43:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jl_cIzTkRkOUlEZunaxnTw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 19:43:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jl_cIzTkRkOUlEZunaxnTw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 19:43:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jl_cIzTkRkOUlEZunaxnTw/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 19:43:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jl_cIzTkRkOUlEZunaxnTw/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 19:43:20 INFO - Downloaded 24220784 bytes. 19:43:20 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'] 19:43:20 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 19:43:21 INFO - Return code: 0 19:43:21 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 19:43:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:21 INFO - https://queue.taskcluster.net/v1/task/Jl_cIzTkRkOUlEZunaxnTw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:43:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jl_cIzTkRkOUlEZunaxnTw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 19:43:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jl_cIzTkRkOUlEZunaxnTw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 19:43:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jl_cIzTkRkOUlEZunaxnTw/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 19:43:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jl_cIzTkRkOUlEZunaxnTw/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 19:43:22 INFO - Downloaded 24220784 bytes. 19:43:22 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'] 19:43:22 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 19:43:23 INFO - Return code: 0 19:43:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:23 INFO - https://queue.taskcluster.net/v1/task/Jl_cIzTkRkOUlEZunaxnTw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:43:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jl_cIzTkRkOUlEZunaxnTw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 19:43:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jl_cIzTkRkOUlEZunaxnTw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 19:43:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jl_cIzTkRkOUlEZunaxnTw/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 19:43:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jl_cIzTkRkOUlEZunaxnTw/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 19:43:25 INFO - Downloaded 14405384 bytes. 19:43:25 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'] 19:43:25 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 19:43:25 INFO - Return code: 0 19:43:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:25 INFO - https://queue.taskcluster.net/v1/task/Jl_cIzTkRkOUlEZunaxnTw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:43:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jl_cIzTkRkOUlEZunaxnTw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 19:43:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jl_cIzTkRkOUlEZunaxnTw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 19:43:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jl_cIzTkRkOUlEZunaxnTw/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 19:43:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jl_cIzTkRkOUlEZunaxnTw/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 19:43:27 INFO - Downloaded 59424279 bytes. 19:43:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Jl_cIzTkRkOUlEZunaxnTw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 19:43:27 INFO - mkdir: /builds/slave/test/properties 19:43:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:43:27 INFO - Writing to file /builds/slave/test/properties/build_url 19:43:27 INFO - Contents: 19:43:27 INFO - build_url:https://queue.taskcluster.net/v1/task/Jl_cIzTkRkOUlEZunaxnTw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 19:43:28 INFO - Running post-action listener: _resource_record_post_action 19:43:28 INFO - Running post-action listener: set_extra_try_arguments 19:43:28 INFO - ##### 19:43:28 INFO - ##### Finished download-and-extract step (success) 19:43:28 INFO - ##### 19:43:28 INFO - ##### 19:43:28 INFO - ##### Running populate-webroot step. 19:43:28 INFO - ##### 19:43:28 INFO - Running pre-action listener: _resource_record_pre_action 19:43:28 INFO - Running main action method: populate_webroot 19:43:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:43:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:43:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:43:28 INFO - '--tppagecycles', 19:43:28 INFO - '7'], 19:43:28 INFO - 'tests': ['tsvgm']}}, 19:43:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:43:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:43:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:43:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:43:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:43:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:43:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:43:28 INFO - 'talos_options': ['--disable-e10s'], 19:43:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:43:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:43:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:43:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:43:28 INFO - 'talos_options': ['--disable-e10s'], 19:43:28 INFO - 'tests': ['damp', 'tps']}, 19:43:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:43:28 INFO - 'tests': ['damp', 'tps']}, 19:43:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:43:28 INFO - 'tests': ['dromaeo_dom']}, 19:43:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:43:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:43:28 INFO - 'tests': ['basic_compositor_video']}, 19:43:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:43:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:43:28 INFO - 'tests': ['a11yr', 19:43:28 INFO - 'ts_paint', 19:43:28 INFO - 'tpaint', 19:43:28 INFO - 'sessionrestore', 19:43:28 INFO - 'sessionrestore_no_auto_restore', 19:43:28 INFO - 'tabpaint']}, 19:43:28 INFO - 'other-e10s': {'tests': ['a11yr', 19:43:28 INFO - 'ts_paint', 19:43:28 INFO - 'tpaint', 19:43:28 INFO - 'sessionrestore', 19:43:28 INFO - 'sessionrestore_no_auto_restore', 19:43:28 INFO - 'tabpaint']}, 19:43:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:43:28 INFO - 'ts_paint', 19:43:28 INFO - 'tpaint', 19:43:28 INFO - 'sessionrestore', 19:43:28 INFO - 'sessionrestore_no_auto_restore', 19:43:28 INFO - 'tabpaint']}, 19:43:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:43:28 INFO - 'ts_paint', 19:43:28 INFO - 'tpaint', 19:43:28 INFO - 'sessionrestore', 19:43:28 INFO - 'sessionrestore_no_auto_restore', 19:43:28 INFO - 'tabpaint']}, 19:43:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:43:28 INFO - 'tests': ['a11yr', 19:43:28 INFO - 'ts_paint', 19:43:28 INFO - 'tpaint', 19:43:28 INFO - 'sessionrestore', 19:43:28 INFO - 'sessionrestore_no_auto_restore', 19:43:28 INFO - 'tabpaint']}, 19:43:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:43:28 INFO - 'tests': ['a11yr', 19:43:28 INFO - 'ts_paint', 19:43:28 INFO - 'tpaint', 19:43:28 INFO - 'sessionrestore', 19:43:28 INFO - 'sessionrestore_no_auto_restore', 19:43:28 INFO - 'tabpaint']}, 19:43:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:43:28 INFO - 'tests': ['tsvgx', 19:43:28 INFO - 'tsvgr_opacity', 19:43:28 INFO - 'tart', 19:43:28 INFO - 'tscrollx', 19:43:28 INFO - 'cart']}, 19:43:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:43:28 INFO - 'tsvgr_opacity', 19:43:28 INFO - 'tart', 19:43:28 INFO - 'tscrollx', 19:43:28 INFO - 'cart']}, 19:43:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:43:28 INFO - 'talos_options': ['--disable-e10s'], 19:43:28 INFO - 'tests': ['tp5o']}, 19:43:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:43:28 INFO - 'tests': ['tp5o']}, 19:43:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:43:28 INFO - 'talos_options': ['--disable-e10s', 19:43:28 INFO - '--xperf_path', 19:43:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:43:28 INFO - 'tests': ['tp5n']}, 19:43:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:43:28 INFO - 'talos_options': ['--xperf_path', 19:43:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:43:28 INFO - 'tests': ['tp5n']}}, 19:43:28 INFO - 'talos.zip': {'path': '', 19:43:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:43:28 INFO - Running post-action listener: _resource_record_post_action 19:43:28 INFO - ##### 19:43:28 INFO - ##### Finished populate-webroot step (success) 19:43:28 INFO - ##### 19:43:28 INFO - ##### 19:43:28 INFO - ##### Running create-virtualenv step. 19:43:28 INFO - ##### 19:43:28 INFO - Running pre-action listener: _resource_record_pre_action 19:43:28 INFO - Running main action method: create_virtualenv 19:43:28 INFO - Creating virtualenv /builds/slave/test/build/venv 19:43:28 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:43:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:43:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:43:28 INFO - Using real prefix '/usr' 19:43:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:43:29 INFO - Installing distribute.............................................................................................................................................................................................done. 19:43:31 INFO - Installing pip.................done. 19:43:31 INFO - Return code: 0 19:43:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:43:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:31 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 0x7f2491d2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f5b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fea1c0>, '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 0x1feb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1feb550>, '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': '8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:43:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:43:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:43:31 INFO - Using env: {'DISPLAY': ':0', 19:43:31 INFO - 'HOME': '/home/cltbld', 19:43:31 INFO - 'LANG': 'en_US.UTF-8', 19:43:31 INFO - 'LANGUAGE': 'en_US:en', 19:43:31 INFO - 'LOGNAME': 'cltbld', 19:43:31 INFO - 'MAIL': '/var/mail/cltbld', 19:43:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:31 INFO - 'MOZ_NO_REMOTE': '1', 19:43:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:31 INFO - 'NO_EM_RESTART': '1', 19:43:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:43:31 INFO - 'PWD': '/builds/slave/test', 19:43:31 INFO - 'SHELL': '/bin/bash', 19:43:31 INFO - 'SHLVL': '1', 19:43:31 INFO - 'TERM': 'linux', 19:43:31 INFO - 'TMOUT': '86400', 19:43:31 INFO - 'USER': 'cltbld', 19:43:31 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206', 19:43:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:31 INFO - '_': '/tools/buildbot/bin/python'} 19:43:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:31 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:43:31 INFO - Cleaning up... 19:43:31 INFO - Return code: 0 19:43:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:43:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:31 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 0x7f2491d2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f5b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fea1c0>, '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 0x1feb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1feb550>, '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': '8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:43:31 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 19:43:31 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 19:43:31 INFO - Using env: {'DISPLAY': ':0', 19:43:31 INFO - 'HOME': '/home/cltbld', 19:43:31 INFO - 'LANG': 'en_US.UTF-8', 19:43:31 INFO - 'LANGUAGE': 'en_US:en', 19:43:31 INFO - 'LOGNAME': 'cltbld', 19:43:31 INFO - 'MAIL': '/var/mail/cltbld', 19:43:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:31 INFO - 'MOZ_NO_REMOTE': '1', 19:43:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:31 INFO - 'NO_EM_RESTART': '1', 19:43:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:43:31 INFO - 'PWD': '/builds/slave/test', 19:43:31 INFO - 'SHELL': '/bin/bash', 19:43:31 INFO - 'SHLVL': '1', 19:43:31 INFO - 'TERM': 'linux', 19:43:31 INFO - 'TMOUT': '86400', 19:43:31 INFO - 'USER': 'cltbld', 19:43:31 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206', 19:43:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:31 INFO - '_': '/tools/buildbot/bin/python'} 19:43:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:31 INFO - Downloading/unpacking psutil>=3.1.1 19:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:43:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:43:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:43:33 INFO - Installing collected packages: psutil 19:43:33 INFO - Running setup.py install for psutil 19:43:33 INFO - building 'psutil._psutil_linux' extension 19:43:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 19:43:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 19:43:33 INFO - building 'psutil._psutil_posix' extension 19:43:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 19:43:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 19:43:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:43:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:43:34 INFO - Successfully installed psutil 19:43:34 INFO - Cleaning up... 19:43:34 INFO - Return code: 0 19:43:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:43:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:34 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 0x7f2491d2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f5b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fea1c0>, '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 0x1feb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1feb550>, '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': '8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:43:34 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 19:43:34 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 19:43:34 INFO - Using env: {'DISPLAY': ':0', 19:43:34 INFO - 'HOME': '/home/cltbld', 19:43:34 INFO - 'LANG': 'en_US.UTF-8', 19:43:34 INFO - 'LANGUAGE': 'en_US:en', 19:43:34 INFO - 'LOGNAME': 'cltbld', 19:43:34 INFO - 'MAIL': '/var/mail/cltbld', 19:43:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:34 INFO - 'MOZ_NO_REMOTE': '1', 19:43:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:34 INFO - 'NO_EM_RESTART': '1', 19:43:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:43:34 INFO - 'PWD': '/builds/slave/test', 19:43:34 INFO - 'SHELL': '/bin/bash', 19:43:34 INFO - 'SHLVL': '1', 19:43:34 INFO - 'TERM': 'linux', 19:43:34 INFO - 'TMOUT': '86400', 19:43:34 INFO - 'USER': 'cltbld', 19:43:34 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206', 19:43:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:34 INFO - '_': '/tools/buildbot/bin/python'} 19:43:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:43:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:43:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:43:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:43:36 INFO - Installing collected packages: mozsystemmonitor 19:43:36 INFO - Running setup.py install for mozsystemmonitor 19:43:36 INFO - Successfully installed mozsystemmonitor 19:43:36 INFO - Cleaning up... 19:43:36 INFO - Return code: 0 19:43:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:43:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:36 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 0x7f2491d2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f5b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fea1c0>, '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 0x1feb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1feb550>, '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': '8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:43:36 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 19:43:36 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 19:43:36 INFO - Using env: {'DISPLAY': ':0', 19:43:36 INFO - 'HOME': '/home/cltbld', 19:43:36 INFO - 'LANG': 'en_US.UTF-8', 19:43:36 INFO - 'LANGUAGE': 'en_US:en', 19:43:36 INFO - 'LOGNAME': 'cltbld', 19:43:36 INFO - 'MAIL': '/var/mail/cltbld', 19:43:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:36 INFO - 'MOZ_NO_REMOTE': '1', 19:43:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:36 INFO - 'NO_EM_RESTART': '1', 19:43:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:43:36 INFO - 'PWD': '/builds/slave/test', 19:43:36 INFO - 'SHELL': '/bin/bash', 19:43:36 INFO - 'SHLVL': '1', 19:43:36 INFO - 'TERM': 'linux', 19:43:36 INFO - 'TMOUT': '86400', 19:43:36 INFO - 'USER': 'cltbld', 19:43:36 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206', 19:43:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:36 INFO - '_': '/tools/buildbot/bin/python'} 19:43:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:36 INFO - Downloading/unpacking jsonschema==2.5.1 19:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:43:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:43:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:38 INFO - Downloading functools32-3.2.3-2.tar.gz 19:43:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:43:38 INFO - warning: no files found matching '*.txt' 19:43:38 INFO - no previously-included directories found matching 'build' 19:43:38 INFO - no previously-included directories found matching 'dist' 19:43:38 INFO - no previously-included directories found matching '.git*' 19:43:38 INFO - Installing collected packages: jsonschema, functools32 19:43:38 INFO - Running setup.py install for functools32 19:43:38 INFO - warning: no files found matching '*.txt' 19:43:38 INFO - no previously-included directories found matching 'build' 19:43:38 INFO - no previously-included directories found matching 'dist' 19:43:38 INFO - no previously-included directories found matching '.git*' 19:43:38 INFO - Successfully installed jsonschema functools32 19:43:38 INFO - Cleaning up... 19:43:38 INFO - Return code: 0 19:43:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:43:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2491d2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f5b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fea1c0>, '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 0x1feb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1feb550>, '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': '8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:43:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:43:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:43:38 INFO - Using env: {'DISPLAY': ':0', 19:43:38 INFO - 'HOME': '/home/cltbld', 19:43:38 INFO - 'LANG': 'en_US.UTF-8', 19:43:38 INFO - 'LANGUAGE': 'en_US:en', 19:43:38 INFO - 'LOGNAME': 'cltbld', 19:43:38 INFO - 'MAIL': '/var/mail/cltbld', 19:43:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:38 INFO - 'MOZ_NO_REMOTE': '1', 19:43:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:38 INFO - 'NO_EM_RESTART': '1', 19:43:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:43:38 INFO - 'PWD': '/builds/slave/test', 19:43:38 INFO - 'SHELL': '/bin/bash', 19:43:38 INFO - 'SHLVL': '1', 19:43:38 INFO - 'TERM': 'linux', 19:43:38 INFO - 'TMOUT': '86400', 19:43:38 INFO - 'USER': 'cltbld', 19:43:38 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206', 19:43:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:38 INFO - '_': '/tools/buildbot/bin/python'} 19:43:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:39 INFO - Downloading/unpacking blobuploader==1.2.4 19:43:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:41 INFO - Downloading blobuploader-1.2.4.tar.gz 19:43:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:43:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:43:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:43:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:43:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:41 INFO - Downloading docopt-0.6.1.tar.gz 19:43:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:43:41 INFO - Installing collected packages: blobuploader, requests, docopt 19:43:41 INFO - Running setup.py install for blobuploader 19:43:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:43:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:43:41 INFO - Running setup.py install for requests 19:43:42 INFO - Running setup.py install for docopt 19:43:42 INFO - Successfully installed blobuploader requests docopt 19:43:42 INFO - Cleaning up... 19:43:42 INFO - Return code: 0 19:43:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:43:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:42 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 0x7f2491d2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f5b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fea1c0>, '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 0x1feb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1feb550>, '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': '8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:43:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:43:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:43:42 INFO - Using env: {'DISPLAY': ':0', 19:43:42 INFO - 'HOME': '/home/cltbld', 19:43:42 INFO - 'LANG': 'en_US.UTF-8', 19:43:42 INFO - 'LANGUAGE': 'en_US:en', 19:43:42 INFO - 'LOGNAME': 'cltbld', 19:43:42 INFO - 'MAIL': '/var/mail/cltbld', 19:43:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:42 INFO - 'MOZ_NO_REMOTE': '1', 19:43:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:42 INFO - 'NO_EM_RESTART': '1', 19:43:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:43:42 INFO - 'PWD': '/builds/slave/test', 19:43:42 INFO - 'SHELL': '/bin/bash', 19:43:42 INFO - 'SHLVL': '1', 19:43:42 INFO - 'TERM': 'linux', 19:43:42 INFO - 'TMOUT': '86400', 19:43:42 INFO - 'USER': 'cltbld', 19:43:42 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206', 19:43:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:42 INFO - '_': '/tools/buildbot/bin/python'} 19:43:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:43:42 INFO - Running setup.py (path:/tmp/pip-ZaxYC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:43:42 INFO - Running setup.py (path:/tmp/pip-KYLyA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:43:42 INFO - Running setup.py (path:/tmp/pip-t0YoBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:43:42 INFO - Running setup.py (path:/tmp/pip-GxRe7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:43:42 INFO - Running setup.py (path:/tmp/pip-4efZUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:43:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:43:42 INFO - Running setup.py (path:/tmp/pip-teHxz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:43:43 INFO - Running setup.py (path:/tmp/pip-80YSHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:43:43 INFO - Running setup.py (path:/tmp/pip-PcnjQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:43:43 INFO - Running setup.py (path:/tmp/pip-Sb3O0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:43:43 INFO - Running setup.py (path:/tmp/pip-erQXz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:43:43 INFO - Running setup.py (path:/tmp/pip-46zHzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:43:43 INFO - Running setup.py (path:/tmp/pip-jFf2XH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:43:43 INFO - Running setup.py (path:/tmp/pip-MoC7Jp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:43:43 INFO - Running setup.py (path:/tmp/pip-zNkFZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:43:43 INFO - Running setup.py (path:/tmp/pip-DWwbxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:43:43 INFO - Running setup.py (path:/tmp/pip-HnW27z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:43:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:43:43 INFO - Running setup.py (path:/tmp/pip-btVjMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:43:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:43:43 INFO - Running setup.py install for manifestparser 19:43:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:43:43 INFO - Running setup.py install for mozcrash 19:43:44 INFO - Running setup.py install for mozdebug 19:43:44 INFO - Running setup.py install for mozdevice 19:43:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:43:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:43:44 INFO - Running setup.py install for mozfile 19:43:44 INFO - Running setup.py install for mozhttpd 19:43:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:43:44 INFO - Running setup.py install for mozinfo 19:43:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:43:44 INFO - Running setup.py install for mozInstall 19:43:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:43:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:43:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:43:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:43:44 INFO - Running setup.py install for mozleak 19:43:44 INFO - Running setup.py install for mozlog 19:43:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:43:44 INFO - Running setup.py install for moznetwork 19:43:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:43:44 INFO - Running setup.py install for mozprocess 19:43:45 INFO - Running setup.py install for mozprofile 19:43:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:43:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:43:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:43:45 INFO - Running setup.py install for mozrunner 19:43:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:43:45 INFO - Running setup.py install for mozscreenshot 19:43:45 INFO - Running setup.py install for moztest 19:43:45 INFO - Running setup.py install for mozversion 19:43:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:43:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:43:45 INFO - Cleaning up... 19:43:45 INFO - Return code: 0 19:43:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:43: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')]} 19:43:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43: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')]} 19:43:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:45 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 0x7f2491d2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f5b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fea1c0>, '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 0x1feb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1feb550>, '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': '8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:43:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:43:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:43:45 INFO - Using env: {'DISPLAY': ':0', 19:43:45 INFO - 'HOME': '/home/cltbld', 19:43:45 INFO - 'LANG': 'en_US.UTF-8', 19:43:45 INFO - 'LANGUAGE': 'en_US:en', 19:43:45 INFO - 'LOGNAME': 'cltbld', 19:43:45 INFO - 'MAIL': '/var/mail/cltbld', 19:43:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:45 INFO - 'MOZ_NO_REMOTE': '1', 19:43:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:45 INFO - 'NO_EM_RESTART': '1', 19:43:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:43:45 INFO - 'PWD': '/builds/slave/test', 19:43:45 INFO - 'SHELL': '/bin/bash', 19:43:45 INFO - 'SHLVL': '1', 19:43:45 INFO - 'TERM': 'linux', 19:43:45 INFO - 'TMOUT': '86400', 19:43:45 INFO - 'USER': 'cltbld', 19:43:45 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206', 19:43:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:45 INFO - '_': '/tools/buildbot/bin/python'} 19:43:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:43:45 INFO - Running setup.py (path:/tmp/pip-dY1I6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:43:45 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:43:45 INFO - Running setup.py (path:/tmp/pip-SX9hhQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:43:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:43:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:43:45 INFO - Running setup.py (path:/tmp/pip-tWoNcP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:43:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:43:46 INFO - Running setup.py (path:/tmp/pip-bfibaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:43:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:43:46 INFO - Running setup.py (path:/tmp/pip-FEvpvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:43:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:43:46 INFO - Running setup.py (path:/tmp/pip-ehE_nm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:43:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:43:46 INFO - Running setup.py (path:/tmp/pip-jSXH5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:43:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:43:46 INFO - Running setup.py (path:/tmp/pip-tSBj_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:43:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:43:46 INFO - Running setup.py (path:/tmp/pip-fME9AZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:43:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:43:46 INFO - Running setup.py (path:/tmp/pip-9HKMjV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:43:46 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)) 19:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:43:46 INFO - Running setup.py (path:/tmp/pip-J5NczK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:43:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:43:46 INFO - Running setup.py (path:/tmp/pip-CX1JYa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:43:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:43:46 INFO - Running setup.py (path:/tmp/pip-iCuHHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:43:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:43:46 INFO - Running setup.py (path:/tmp/pip-ZPdoOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:43:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:43:46 INFO - Running setup.py (path:/tmp/pip-sg0mIP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:43:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:43:46 INFO - Running setup.py (path:/tmp/pip-31mmV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:43:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:43:47 INFO - Running setup.py (path:/tmp/pip-pYjOCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:43:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:43:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:43:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:43:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:43:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:43:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:43:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:43:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:48 INFO - Downloading blessings-1.6.tar.gz 19:43:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:43:49 INFO - Installing collected packages: blessings 19:43:49 INFO - Running setup.py install for blessings 19:43:49 INFO - Successfully installed blessings 19:43:49 INFO - Cleaning up... 19:43:49 INFO - Return code: 0 19:43:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:43:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:43:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:43:49 INFO - Reading from file tmpfile_stdout 19:43:49 INFO - Current package versions: 19:43:49 INFO - argparse == 1.2.1 19:43:49 INFO - blessings == 1.6 19:43:49 INFO - blobuploader == 1.2.4 19:43:49 INFO - docopt == 0.6.1 19:43:49 INFO - functools32 == 3.2.3-2 19:43:49 INFO - jsonschema == 2.5.1 19:43:49 INFO - manifestparser == 1.1 19:43:49 INFO - mozInstall == 1.12 19:43:49 INFO - mozcrash == 0.17 19:43:49 INFO - mozdebug == 0.1 19:43:49 INFO - mozdevice == 0.48 19:43:49 INFO - mozfile == 1.2 19:43:49 INFO - mozhttpd == 0.7 19:43:49 INFO - mozinfo == 0.9 19:43:49 INFO - mozleak == 0.1 19:43:49 INFO - mozlog == 3.2 19:43:49 INFO - moznetwork == 0.27 19:43:49 INFO - mozprocess == 0.22 19:43:49 INFO - mozprofile == 0.28 19:43:49 INFO - mozrunner == 6.11 19:43:49 INFO - mozscreenshot == 0.1 19:43:49 INFO - mozsystemmonitor == 0.3 19:43:49 INFO - moztest == 0.7 19:43:49 INFO - mozversion == 1.4 19:43:49 INFO - psutil == 3.1.1 19:43:49 INFO - requests == 1.2.3 19:43:49 INFO - wsgiref == 0.1.2 19:43:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:49 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 0x7f2491d2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f5b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fea1c0>, '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 0x1feb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1feb550>, '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': '8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:43:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:43:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:43:49 INFO - Using env: {'DISPLAY': ':0', 19:43:49 INFO - 'HOME': '/home/cltbld', 19:43:49 INFO - 'LANG': 'en_US.UTF-8', 19:43:49 INFO - 'LANGUAGE': 'en_US:en', 19:43:49 INFO - 'LOGNAME': 'cltbld', 19:43:49 INFO - 'MAIL': '/var/mail/cltbld', 19:43:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:49 INFO - 'MOZ_NO_REMOTE': '1', 19:43:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:49 INFO - 'NO_EM_RESTART': '1', 19:43:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:43:49 INFO - 'PWD': '/builds/slave/test', 19:43:49 INFO - 'SHELL': '/bin/bash', 19:43:49 INFO - 'SHLVL': '1', 19:43:49 INFO - 'TERM': 'linux', 19:43:49 INFO - 'TMOUT': '86400', 19:43:49 INFO - 'USER': 'cltbld', 19:43:49 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206', 19:43:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:49 INFO - '_': '/tools/buildbot/bin/python'} 19:43:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:43:49 INFO - Cleaning up... 19:43:49 INFO - Return code: 0 19:43:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:49 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 0x7f2491d2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f5b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fea1c0>, '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 0x1feb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1feb550>, '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': '8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:43:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:43:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:43:49 INFO - Using env: {'DISPLAY': ':0', 19:43:49 INFO - 'HOME': '/home/cltbld', 19:43:49 INFO - 'LANG': 'en_US.UTF-8', 19:43:49 INFO - 'LANGUAGE': 'en_US:en', 19:43:49 INFO - 'LOGNAME': 'cltbld', 19:43:49 INFO - 'MAIL': '/var/mail/cltbld', 19:43:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:49 INFO - 'MOZ_NO_REMOTE': '1', 19:43:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:49 INFO - 'NO_EM_RESTART': '1', 19:43:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:43:49 INFO - 'PWD': '/builds/slave/test', 19:43:49 INFO - 'SHELL': '/bin/bash', 19:43:49 INFO - 'SHLVL': '1', 19:43:49 INFO - 'TERM': 'linux', 19:43:49 INFO - 'TMOUT': '86400', 19:43:49 INFO - 'USER': 'cltbld', 19:43:49 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206', 19:43:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:49 INFO - '_': '/tools/buildbot/bin/python'} 19:43:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:43:49 INFO - Cleaning up... 19:43:49 INFO - Return code: 0 19:43:49 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:43:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:43:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:43:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:43:49 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 0x7f2491d2ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f5b350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fea1c0>, '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 0x1feb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1feb550>, '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': '8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:43:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 19:43:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 19:43:49 INFO - Using env: {'DISPLAY': ':0', 19:43:49 INFO - 'HOME': '/home/cltbld', 19:43:49 INFO - 'LANG': 'en_US.UTF-8', 19:43:49 INFO - 'LANGUAGE': 'en_US:en', 19:43:49 INFO - 'LOGNAME': 'cltbld', 19:43:49 INFO - 'MAIL': '/var/mail/cltbld', 19:43:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:43:49 INFO - 'MOZ_NO_REMOTE': '1', 19:43:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:43:49 INFO - 'NO_EM_RESTART': '1', 19:43:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:43:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:43:49 INFO - 'PWD': '/builds/slave/test', 19:43:49 INFO - 'SHELL': '/bin/bash', 19:43:49 INFO - 'SHLVL': '1', 19:43:49 INFO - 'TERM': 'linux', 19:43:49 INFO - 'TMOUT': '86400', 19:43:49 INFO - 'USER': 'cltbld', 19:43:49 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206', 19:43:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:43:49 INFO - '_': '/tools/buildbot/bin/python'} 19:43:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:43:50 INFO - Downloading/unpacking flake8 19:43:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:51 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:43:51 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:43:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:52 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:43:52 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:43:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:52 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:43:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:43:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:43:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:43:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:43:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:43:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:43:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:43:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:43:52 INFO - no previously-included directories found matching 'docs/_build' 19:43:52 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:43:52 INFO - Running setup.py install for pep8 19:43:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:43:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:43:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:43:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:43:52 INFO - no previously-included directories found matching 'docs/_build' 19:43:52 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:43:52 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:43:52 INFO - Cleaning up... 19:43:52 INFO - Return code: 0 19:43:52 INFO - Running post-action listener: _resource_record_post_action 19:43:52 INFO - Running post-action listener: _start_resource_monitoring 19:43:52 INFO - Starting resource monitoring. 19:43:52 INFO - ##### 19:43:52 INFO - ##### Finished create-virtualenv step (success) 19:43:52 INFO - ##### 19:43:52 INFO - ##### 19:43:52 INFO - ##### Running install step. 19:43:52 INFO - ##### 19:43:52 INFO - Running pre-action listener: _resource_record_pre_action 19:43:52 INFO - Running main action method: install 19:43:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:43:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:43:53 INFO - Reading from file tmpfile_stdout 19:43:53 INFO - Detecting whether we're running mozinstall >=1.0... 19:43:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:43:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:43:53 INFO - Reading from file tmpfile_stdout 19:43:53 INFO - Output received: 19:43:53 INFO - Usage: mozinstall [options] installer 19:43:53 INFO - Options: 19:43:53 INFO - -h, --help show this help message and exit 19:43:53 INFO - -d DEST, --destination=DEST 19:43:53 INFO - Directory to install application into. [default: 19:43:53 INFO - "/builds/slave/test"] 19:43:53 INFO - --app=APP Application being installed. [default: firefox] 19:43:53 INFO - mkdir: /builds/slave/test/build/application 19:43:53 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'] 19:43:53 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 19:44:10 INFO - Reading from file tmpfile_stdout 19:44:10 INFO - Output received: 19:44:10 INFO - /builds/slave/test/build/application/firefox/firefox 19:44:10 INFO - Running post-action listener: _resource_record_post_action 19:44:10 INFO - ##### 19:44:10 INFO - ##### Finished install step (success) 19:44:10 INFO - ##### 19:44:10 INFO - ##### 19:44:10 INFO - ##### Running run-tests step. 19:44:10 INFO - ##### 19:44:10 INFO - Running pre-action listener: _resource_record_pre_action 19:44:10 INFO - Running main action method: run_tests 19:44:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:44:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:44:10 INFO - Python 2.7.3 19:44:10 INFO - Return code: 0 19:44:10 INFO - grabbing minidump binary from tooltool 19:44:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:44:10 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 0x1fea1c0>, '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 0x1feb0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1feb550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:44:10 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:44:10 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 19:44:10 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 19:44:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:44:10 INFO - Return code: 0 19:44:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:44:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:44:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:44:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:44:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:44:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:44:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:44:11 INFO - Return code: 0 19:44:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Jl_cIzTkRkOUlEZunaxnTw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:44:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Jl_cIzTkRkOUlEZunaxnTw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-038 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:44:11 INFO - Using env: {'DISPLAY': ':0', 19:44:11 INFO - 'HOME': '/home/cltbld', 19:44:11 INFO - 'LANG': 'en_US.UTF-8', 19:44:11 INFO - 'LANGUAGE': 'en_US:en', 19:44:11 INFO - 'LOGNAME': 'cltbld', 19:44:11 INFO - 'MAIL': '/var/mail/cltbld', 19:44:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:44:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:44:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:44:11 INFO - 'MOZ_NO_REMOTE': '1', 19:44:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:44:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:44:11 INFO - 'NO_EM_RESTART': '1', 19:44:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:44:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:44:11 INFO - 'PWD': '/builds/slave/test', 19:44:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:44:11 INFO - 'SHELL': '/bin/bash', 19:44:11 INFO - 'SHLVL': '1', 19:44:11 INFO - 'TERM': 'linux', 19:44:11 INFO - 'TMOUT': '86400', 19:44:11 INFO - 'USER': 'cltbld', 19:44:11 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206', 19:44:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:44:11 INFO - '_': '/tools/buildbot/bin/python'} 19:44:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Jl_cIzTkRkOUlEZunaxnTw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:44:11 INFO - mozversion application_buildid: 20160616163918 19:44:11 INFO - mozversion application_changeset: 5f95858f8ddf21ea2271a12810332efd09eff138 19:44:11 INFO - mozversion application_display_name: Nightly 19:44:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:44:11 INFO - mozversion application_name: Firefox 19:44:11 INFO - mozversion application_remotingname: firefox 19:44:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:44:11 INFO - mozversion application_vendor: Mozilla 19:44:11 INFO - mozversion application_version: 50.0a1 19:44:11 INFO - mozversion platform_buildid: 20160616163918 19:44:11 INFO - mozversion platform_changeset: 5f95858f8ddf21ea2271a12810332efd09eff138 19:44:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:44:11 INFO - mozversion platform_version: 50.0a1 19:44:11 INFO - using testdate: 1466131451 19:44:11 INFO - actual date: 1466131451 19:44:11 INFO - starting webserver on 'localhost:41094' 19:44:11 INFO - SUITE-START | Running 2 tests 19:44:11 INFO - TEST-START | dromaeo_css 19:44:11 INFO - operating with platform_type : linux_ 19:44:11 INFO - Initialising browser for dromaeo_css test... 19:44:11 INFO - TEST-INFO | started process 29080 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpthce4W/profile http://localhost:41094/getInfo.html) 19:44:16 INFO - PROCESS | 29080 | __metrics Screen width/height:1600/1200 19:44:16 INFO - PROCESS | 29080 | colorDepth:24 19:44:16 INFO - PROCESS | 29080 | Browser inner width/height: 1024/697 19:44:16 INFO - PROCESS | 29080 | __metrics 19:44:17 INFO - PROCESS | 29080 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:44:18 INFO - TEST-INFO | 29080: exit 0 19:44:18 INFO - Browser initialized. 19:44:18 INFO - Running cycle 1/1 for dromaeo_css test... 19:44:18 INFO - TEST-INFO | started process 29165 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpthce4W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:44:30 INFO - PROCESS | 29165 | RSS: Main: 173867008 19:44:30 INFO - PROCESS | 29165 | 19:45:22 INFO - PROCESS | 29165 | 1466131522031 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:47:30 INFO - PROCESS | 29165 | Cycle 1(1): loaded http://localhost:41094/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41094/tests/dromaeo/cssquery-ext.html) 19:47:31 INFO - PROCESS | 29165 | RSS: Main: 266256384 19:47:31 INFO - PROCESS | 29165 | 19:50:33 INFO - PROCESS | 29165 | Cycle 1(1): loaded http://localhost:41094/tests/dromaeo/cssquery-ext.html (next: http://localhost:41094/tests/dromaeo/cssquery-jquery.html) 19:50:34 INFO - PROCESS | 29165 | RSS: Main: 267087872 19:50:34 INFO - PROCESS | 29165 | 19:53:36 INFO - PROCESS | 29165 | Cycle 1(1): loaded http://localhost:41094/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41094/tests/dromaeo/cssquery-mootools.html) 19:53:37 INFO - PROCESS | 29165 | RSS: Main: 267157504 19:53:37 INFO - PROCESS | 29165 | 19:56:39 INFO - PROCESS | 29165 | Cycle 1(1): loaded http://localhost:41094/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41094/tests/dromaeo/cssquery-prototype.html) 19:56:39 INFO - PROCESS | 29165 | RSS: Main: 263634944 19:56:39 INFO - PROCESS | 29165 | 19:59:42 INFO - PROCESS | 29165 | Cycle 1(1): loaded http://localhost:41094/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41094/tests/dromaeo/cssquery-yui.html) 19:59:42 INFO - PROCESS | 29165 | RSS: Main: 239992832 19:59:42 INFO - PROCESS | 29165 | 20:02:45 INFO - PROCESS | 29165 | Cycle 1(1): loaded http://localhost:41094/tests/dromaeo/cssquery-yui.html (next: http://localhost:41094/tests/dromaeo/cssquery-dojo.html) 20:02:46 INFO - PROCESS | 29165 | RSS: Main: 290574336 20:02:46 INFO - PROCESS | 29165 | 20:02:46 INFO - PROCESS | 29165 | __start_tp_report 20:02:46 INFO - PROCESS | 29165 | _x_x_mozilla_page_load 20:02:46 INFO - PROCESS | 29165 | _x_x_mozilla_page_load_details 20:02:46 INFO - PROCESS | 29165 | |i|pagename|runs| 20:02:46 INFO - PROCESS | 29165 | |0;dojo.html;3096.8296743679102;3113.034959162658;3129.139486641174;3142.0573827851645;3160.88936887209;5331.360236771583;5379.327584051994;5406.513413792758;5468.371137319209;5500.092484740018;5424.349078110632;5448;5455.918161227585;5511.862203444916;5587.413321601234;4909.361782968209;4977.07924034053;4978;4985.127602669537;5108.974455127719;5066.316047333618;5103.2855400244;5104.923426148593;5125.923556053233;5127.717975511346;4885.877853053685;4910.6317026223;4951.8266860659705;4976.701397916137;5057.393112826466;4924.532169443897;4943.456219815817;4943.579795717368;5040.722760248185;5090.898182036339;4970.304157417964;5013.022460620181;5017.2724954881705;5027.572656324217;5083;5356.169793681946;5388.973055134699;5469.0702580561;5475.178723191513;5566.137248726453;5882.147088672162;5897.793577224819;5914.615549989237;5924.496417804492;5950.077737950624;5688.573356998241;5943.613665111754;5974.8506287342725;5977.282726072855;6003.699815009276;5486.890262194734;5488.91766623497;5511.917321240185;5595.916061259084;5597.6641401516445;6030.703929230241;6039.77923565551;6062.954187590899;6076.817695469143;6134;5601.327840659147;5855.473007429352;5872.882542349129;5894.764209431574;5913.438223368773;5804.912926306109;5855.677937713382;5860.179574859523;5898.203742494794;5903.055511118287;5831.533477321804;5875.29496460419;5877.823665290048;5889.911651325234;5892.263467066617;5661.490465858093;5784.248047753765;5818.418158184148;5867.618604790675;5876.882462350729;4452.354408610733;4474.239379305526;4476.888077798081;4519.344695019204;4521.59305662492;3954.6045395460224;3968.8015599219925;3974.7615143091507;3982.203559288154;4011.919761604752;5349.5720342372515;5392.137258038695;5400.91898621521;5507.394186639466;5572.944270557324;5795.304563452412;5810.099434587646;5871.148683440877;5912.083627037816;5915.201447804578;5533.22534845122;5539.307586551681;5549.889002219933;5550.888982220333;5552.139418390156;4927.852164435073;5001.074801161797;5009.674371165863;5078.13671675816;5154.7164905929785;6090.878182436327;6096.603720758137;6097.664628445389;6103.664298463627;6143.508519318444;5840.065589505829;5878.500327472213;5882.94117058841;5887.028640274307;5887.116932460165;5863.413658634103;5932.970335148297;5933.199018132497;5983.9401605983385;6071.605345652519;5712.485876271156;5753.050746626927;5757.107648314518;5758.913616295843;5760.0783874579865;5779.768809247583;5851.502622277154;5854.180414742024;5861.238039054895;5863.765449381977;5472.20653005304;5549.0289199389135;5553.527950124123;5569.387367389665;5623.971880140573;5535.197396377584;5586.888262234814;5600.803971861128;5606.6916319602815;5610.18652295423;5461.617686761889;5502.422245664307;5513.117901135799;5514.09017512122;5543.861403464945;5620.775168993195;5712.7714891403875;5718.3423906249645;5822.184894114912;5823.18475413451;5613.101903695389;5618.775248989995;5626.2967129108865;5628.60599758013;5629.268195134607 20:02:46 INFO - PROCESS | 29165 | |1;ext.html;628.3339659960442;633.8256979330683;634.9904751428728;646.2729429391934;647.9870402591952;30655.080347589552;34536.65463345366;34608;42392.152156956916;43970.34044489335;46147.76926115365;46508.534914650845;46621.99999999991;46640.300395494094;46651.30023049657;8870.645174193041;9057.230135438496;9060.229880460169;9064.864027039566;9067.95466022669;8926.196642302191;9021.458712477272;9023.864642030374;9025.95487022564;9026.548672566345;9048.321375896801;9083.409578377417;9086.409383390059;9090.045545217723;9093.31800114994;8921.420107692978;8962.731118066436;8971.372003959725;8982.595783189772;9004.054574269672;8911.73264802057;8953;8967.417117887317;8967.955160224223;8971.596278167499;42527.36208956868;42861.57138428607;42914.356284655754;42955.35566966498;43002.78498607518;3760.341940160461;4232.492100947875;4237.258479766028;4260.360945858115;4379.64962802975;32513.51229731556;32520.674793252;33131.83434082838;33222.16944576381;33302.833485832416;4404.361367601679;4422.712523685982;4570.657200709927;4608.585227329557;4615.446146462446;349352;351941;352717;353905;356635.9999999974;57240.713796430755;57931.42068579345;57961;58005.70997144987;58018.4198158013;59834.70082649559;59857.40142598518;60112;60119.098213526835;60123.39876601265;16629.50111496657;16866.807315048965;16871.578210544834;16873.662526749642;16877.662446750997;12903.870961290455;12934.41795119222;12962.935185324013;12966.80549791754;12972.870271297166;14332.785008224884;16045;16058.839411605968;16082.874198805972;16126.274317655734;4991.625628077909;5755;6078.179445774852;6098.664573448415;6127.724252408652;994.8507723841482;1012;1107.5293000474926;1112.6703589210815;1113.6770336602456;31601.36797264042;32423.837880810446;32505.674943250266;32620.469932298078;32681.836590816893;1142.5658249865164;1144.6337172104847;1202.1765090912577;1222.113967373647;1249.375312343828;17000.659986800194;17205;17238.396656116976;17361.826381736275;17397.91301043512;569420;571045;576294;576824;577358;58855;59131;59163.704181479676;59172.40827591755;59224.703876480344;1251.474380760083;1365.528892532075;1366.590022993098;1387.535175716126;1391.5616580777023;32189;33857.49213761746;33903.830480848424;33940.49089263712;34484.310313794085;50133.49866501434;53326;55420.72289638526;55595.722021389636;61015.389846100974;483424;484572;485479;488978;490896.81928626593;24446;26870.462590748466;26973.595396069468;27559.86220068887;27573.31066723348;49063.53504997906;49515.25727114024;49709.75145124251;49787.253191202886;50226.24660630021;37942;40386.79806600948;42078.15843683048;42411.15177696367;42487.362689559064;32122.518162228054;35367.11582210465;35603.287934241685;35723.82138089397;35864.462033069;42289.78855105705;42342.153156936074;42731.572684274004;43055.35416968811;43137.56862431335;36186.45720314145;37585;38249;38294.617053829104;38589.80705096456;28062.27687425641;28157.43643329834;28338.918142651954;28506.287342816595;28585.85707071451 20:02:46 INFO - PROCESS | 29165 | |2;jquery.html;381.64888302761454;383.3258123838499;391.7277492142962;398.6910144637906;411.2679430613507;14534.636634084152;14807.92596037021;17201.65596688067;18871.339503117375;18894.24423023079;14789.482368117118;17098.31606735729;17628.20673069712;18201.08994550274;18517.2593096276;5518.475744804247;5739.62692424991;5753.712314384285;5770.2210201622665;5773.162891380747;4154.5222299435645;5656.179853921179;5757.251557297545;6070.696465176746;6349.396807303322;6349.71426285816;6412.615243085406;6429.8392540186405;6430.646314452703;6443.033544968245;6264.718087686065;6347.301796802348;6410.807675769688;6416.582922110071;6427.389398007182;5421.213923981;5470.17947307903;5553.861153471195;5592.496675299242;5598.832035038955;21858.234961776263;22510.212142574932;22581.661275080885;22590.77409225903;22764.77235227643;2364.2316247219724;2668.4796464689393;3090.9690903090905;3098.256418459576;3146.889858854952;13397.397117129654;14066;14151.150930944073;14339.569812905629;14674.339654715563;1935.2742721479444;1944.105711372756;1965.7739359973643;1986.9801301987084;2039.1945181653164;70249.64875175591;74034.99999999946;76608.61695691542;78968.60515697385;83599.5820020896;10310.43292618913;10737.51681174349;10738.838917416246;10875.401852898167;10974.421011062394;15131.01648392851;15181.6204594886;15221.695566088616;15249.085054896519;15297;7978.840423191504;8081.797955051054;8137.674493020331;8161.306288965386;8212.917870821335;6444.323346048691;7647.502912310682;7713.4363560425745;7788.532688038736;7839.09850367215;4386.451693538308;4637.234856248681;4643.326717625993;4720.45714742809;4749.715017098985;1885.8397036251797;1905.3807512558474;1933.3040105561986;1938.5928954919625;2017.2334512885009;936.279065119854;947.9004704506066;969.7236287658125;996.0985308296003;1009.7929924365634;17610.47168584919;18133.818661813475;18433.354832580797;18649.627007460047;18802.905985469984;969.970900872975;991.2615101749099;1019.8929112443235;1032.3941000368075;1039.7556574205055;10096.646617368504;10306.484675766182;10431.426271555138;10495.580176792995;10500;365868;366656;461636;462633;497112;96237;98791;99997.50001249947;104738;105131.4743426278;427.16276098845987;449.7751124437781;453.2634468987895;476.72111814588163;497.53729031999865;10014.449205293487;10047.799044018933;10133.695989120339;10425.218108642033;10501.054905058581;41743.58256417519;41995;42433;42683.786581066895;43256;59507.70246148742;61384.386156137865;61781.382186177565;62056.379436206866;62412;7962.203779622223;8160.714374997084;8237;8625.913740862761;8647.351448641251;9320;12650.493980240688;12995.805062923875;13166.078374513692;13407.86592134066;11308.264962777394;11467.483963221843;11553.364564949008;11570.247933884271;11627.709307267385;11543.480543375736;11693.181477296508;11714.70713232176;11773.234739741889;11855.34795586251;11510;11554.537818487166;11753.47109380097;12049.939750301193;12116.515339386326;8087.63605637736;8113.797155071171;8294.58527073632;8339.708110216114;8625.309975202093;8778.692745754124;10830.67507974762;11807.409629518661;11950.70123246891;12038.75922481563 20:02:46 INFO - PROCESS | 29165 | |3;mootools.html;1316.9341532923352;1462.7659574468084;1466.3914475492668;1603.647197616524;1792.3905872003525;8225.75322740318;8592;8609.139086091387;8848.026717061135;8872.467651940888;8448.281896038845;8587.742367728963;8644.351673624471;8652;8656.610452529636;1998.9300374486895;2276.430892276931;2353.976460235401;2359.28041947206;2391.258709799959;2322.291701031185;2324.721033475985;2325.1513197682807;2336.6495025746103;2344.0858065354523;2270.398344438725;2298;2330.5222429402015;2332;2332.125452955142;1922.711593261015;2140.8394370422125;2213.513027134044;2217.456723102834;2253.4929640830765;2278.5898538263104;2292.931212063641;2294.8393612447135;2295.4605667668084;2297.184499502686;8542.957285213597;8589.785255368603;8592.69925552609;8596.312295016383;8637.352198585131;5030.320906677625;5180;5185.248139019859;5185.948140518584;5186.273921650934;3299.1257316810825;3402.625711171769;3529.6470352964757;3560.608333083359;3575.731820113476;1207.0705556721275;1231.8029115341503;1233.6977440527128;1247.3700781105483;1290.0518119182393;11908.583199587973;11967;11986;12000.339981301026;12002.279863208236;4493.573110554492;4561.521040290721;4643.721376717408;4655.231886738718;4660.298990868377;10127.442990635585;10142.695719128438;10149;10152.746181345525;10160.949195254125;5134.2812006319145;5141.2288156776785;5147.485251474897;5149.073166829931;5149.330587023663;4213.705040647125;4532.7280363178315;4688.367070445446;4693.9765301173275;4744.786484608201;3942.7831469269468;3973.264945985002;4048.0689441428635;4053;4111.814968326373;1260.8928241099609;1267.7084270617809;1268.245393990574;1268.4165283969294;1282.9679258018625;958.2765012415582;994.7861209839774;998.7403275148514;1000.7548150703125;1007.0835539658875;6118.6022908511695;6143.662098584531;6205.03821907605;6224.37756224374;6261.499080073583;433.8199647146462;434.89127718070483;441.8961544037149;446.63376031653735;446.6739280325381;4281.422008028876;4292.720973136736;4324.8486302979245;4332.935005974913;4333.523312435629;10579.31234469757;10621.256512044238;10667.679969600924;10697.518611662494;10749.301295415773;10618.469076546142;10624.946875265729;10628.14974802014;10638.042576168182;10644.361338319572;7572.583507907118;7772.145064042951;7871.48835325702;7992.960035199903;8048.5170889746805;6009.9699501503965;6256.499480041677;6269.780557680459;6289.433950944437;6421;9457.33798634089;10115.544800483849;10308.12380947628;10320.638777642747;10329.276950613385;9988.60045598168;10238.539265733209;10244.282900197213;10451.94774026125;10504.317219380715;7014.964925175342;7018.5788852669;7847;7979.521228726295;8022.558759268296;8615.741527754177;8618;8702.912970870464;8707.434016788888;8720.694775682821;7578.810529736801;7645.61771911391;7725.398995897645;7845.333146682367;7864.134945156143;7570.772876813704;7667.693292268247;7680.3471704905705;7788.182240864854;7808.609569521387;6987.091678081817;7045.401140903081;7078.4691148163065;7079.823004424725;7082.5396349237135;5103.132467480463;5218.791248350023;5250.369955605352;5364.034473794754;5415.918761218506;4915;5180.559652429586;5215.478452154754;5315.4153043166;5388.084025715716 20:02:46 INFO - PROCESS | 29165 | |4;prototype.html;313.8117129722167;318.34262248456935;319.45532866462685;320.6120594081162;321.79083595662803;5264.499872512115;5315.415304316527;5324.946750532494;5341.252224688537;5366.275552800371;5271.578273738091;5271.578273738101;5273.920891186625;5276.15581506958;5283.920741188886;3944;3978.9204215915665;4107.979460102695;4115.506139263292;4155.833766649331;4059.8985025374304;4106.568810274923;4114.0948991221885;4128.174365126972;4140.296149654551;3882.1753775725365;4092.7339722918064;4102.651274641659;4110.280700877334;4110.876673699794;4009.939850902239;4095.9180816383505;4097.729703791819;4100.159467309194;4113.280175969194;3606.8737594184076;3625.093726568358;3639.1448009717697;3641.1443310821937;3659.5791483979415;5140.665856719302;5143.819966301199;5145.9485405146215;5158.2520534522655;5164.431912489623;4431.446069241345;4664.463586687507;4683.274092515666;4685.554872287127;4693.835715749967;5439.782408703647;5485.765702716857;5487.588430867661;5491.862703432406;5503.449655034465;3956.4065390191413;3972;4000.6399424051683;4013.478247827793;4015.7791321477307;5555.8888822223325;5683.175939488792;5700.971495142539;5701.515371193454;5706.572007099484;4028.576999415078;4038.273110840077;4041.3735870939463;4047.696422768245;4067.9796601017397;4098;4102.50769907613;4104.076582768913;4111.301078816608;4126.979365103155;4423.8230470781455;4577.198990176772;4608.585227329557;4653.7207767534055;4686.906261874743;5484.369297530755;5493.972530137404;5511;5544.306961629796;5571.888562228733;1379;1405.1943252919948;1407.3737186951707;1409.3017913414517;1409.7744360902207;892.9508877011697;903.7333986474006;905.9003509613938;913.6984795017628;930.251147825993;5252.553532949666;5265.657732247469;5267.868303292372;5273.894522109536;5297.337832770903;5462.644928079662;5506.944930550643;5589.496945274945;5596;5612.550995920316;5017.37282839645;5250.369955605352;5277.868053298698;5280.234366016983;5283.867903302448;2973.568832519316;2979.2998645318335;2987.8057926234724;2997.80514266572;3010.9849450752604;5796.188533605298;5806.4193580641595;5809.9419005809405;5832;5839.270091238595;5564.304461942257;5776.768929242868;5864.530837532987;5874.324452687995;5878.382769809108;5913.793017244376;5918.822435326947;5927.110933360031;5937.376575459514;5954.702264886825;5524.116141417354;5738.680103576117;5759.856003599776;5774.682392468287;5782.930157920798;5626.352969408406;5635.380108188014;5691.121929763251;5694;5695.487406133468;5866.823995280149;5871.7651293947765;5881.705914704333;5888.205092312654;5889.234399528034;3967.5437324708028;4087.4876295770755;4092.3452247640234;4095.9795201023803;4110.23960567296;5636.9436305638055;5688.5733569981585;5731.942680573141;5734.136405668091;5752.84943011391;5284.7316644004395;5334.839954801362;5569.275994120901;5604.747786349705;5608.635438696471;5519.39286678457;5522.2544956430365;5527.585431092764;5536.840632153544;5538.141588053858;5418.647787893932;5421.322334708161;5427.48438898304;5445.2104444854485;5470.64440811346;5297.682139071668;5514.255575497256;5553.916691249554;5558.861028474159;5572.387037425799;5420.457954204706;5462.644928079662;5478.91781623284;5500.559955203654;5535.667859928417 20:02:46 INFO - PROCESS | 29165 | |5;yui.html;742.9368503677188;762.0626629246027;805.6173317674104;821.6302663801291;832.5046597274621;14944.103353798766;15068.32192551335;15078.849211507883;15082.472113476031;15285.235738213101;14931.06893106893;15076.47232346868;15332.616684582863;15334.386624535033;15342.616434589141;683.3610574113195;799.5202878273019;822.8724547695117;832.0847068224965;839.4040231435688;809.5952023988006;819.9139090395512;820.1429506166041;820.429801288102;820.4625969989667;866.6316815353481;866.744310428422;870.0299166429418;870.3907264914553;871.4422769427572;695.1553862057574;828.5898480252254;836.4730219961385;861.6208868098048;862.0948004595182;630.7792272704562;632.2697284636253;632.3423639415003;633.2685747961112;635.7615894039735;15577.766333505006;15599.844001559952;15637.296321665552;15643.139627320494;15653;14014.719705605932;14492.855071449256;14624.853751462455;14695.559133226021;14740.631484212874;11494.597689080842;11534.250273732265;11604.593839215588;11717.88282117185;12054.517819287217;4462.598366147063;4508.549145085498;4622.884427889296;4702.459217190033;4749.382580264543;75807;76359.160905793;77253.61373193155;77374.13129343567;77402;11338.206325557298;11436.828447573293;11696.415179241005;11838.585649502225;12127.514899404101;22063.558728825654;22096.2266320678;22128.88935555344;22141.335759927228;22154.556908861734;8692.826143477096;8709.172628600272;8827.073157318517;8876.51179185138;9097.681581144629;4789.521047895252;4817.614590832725;4833.984863178772;4867.415910090741;4897.730624815598;3561.5904171020666;3667.376545987135;3683.244934788362;3686.2258925625383;3688.243909998444;627.6736097229415;637.1207733327977;637.7130291368902;645.8062581225707;652.7682672651235;538.2652679093088;598.8353202869246;607.3956413368726;716.874546954281;740.7777666700076;9772.609095636095;9772.706818795448;9930.950345248228;10095.697129086138;10260.281780275458;672.9899051514232;689.2074114768056;732.4433430592682;753.830388162659;755.1353700013564;4597.931031034537;4866.197077482247;4968.627352948543;4992.850214493571;5006.510059413655;117295;122308;125429;125752;126060.36969815276;79281.60359198166;82675.5866220665;83939.58030209932;102722.97277027134;107373;86155;86627;86923;87170;87381.5630921854;9509.429434233969;9900.900990989998;9920;9942.502874856373;9991.900080999098;36611.81694091512;44419.77790111158;47232.749052584055;47410.52589474061;49075.509244907094;58487;77098.2290177091;77400;77627.61186194033;80259.59870200611;13301.333226888242;13442.193468391928;13707.794383084061;13761.931190344385;14122.364493597608;14826;14914.627134321729;14933.551993440215;15200.695986080438;15500.302486387915;14128.108019100759;14362;14409.42362305496;14574.34415451286;14575.052621579563;13944.302784860514;14655.487057953347;14712.485063022743;14963.40146394132;15124.848751512343;13271.270080145685;13821.654458638615;13847.86152138506;13967.720645587235;14430;11005.559777608805;11147.498362573866;11267.098632109248;11566.65300041;11582.131340149353;10744.83882741775;11111;11698.181127321006;11904.523819047488;11949.64151075469 20:02:46 INFO - PROCESS | 29165 | __end_tp_report 20:02:46 INFO - PROCESS | 29165 | __start_cc_report 20:02:46 INFO - PROCESS | 29165 | _x_x_mozilla_cycle_collect,863 20:02:46 INFO - PROCESS | 29165 | __end_cc_report 20:02:46 INFO - PROCESS | 29165 | __startTimestamp1466132566214__endTimestamp 20:02:46 INFO - PROCESS | 29165 | 20:02:46 INFO - PROCESS | 29165 | ------- Summary: start ------- 20:02:46 INFO - PROCESS | 29165 | Number of tests: 6 20:02:46 INFO - PROCESS | 29165 | 20:02:46 INFO - PROCESS | 29165 | [#0] dojo.html Cycles:165 Average:5431.60 Median:5586.89 stddev:619.48 (11.1%) stddev-sans-first:593.68 20:02:46 INFO - PROCESS | 29165 | Values: 3096.8 3113.0 3129.1 3142.1 3160.9 5331.4 5379.3 5406.5 5468.4 5500.1 5424.3 5448.0 5455.9 5511.9 5587.4 4909.4 4977.1 4978.0 4985.1 5109.0 5066.3 5103.3 5104.9 5125.9 5127.7 4885.9 4910.6 4951.8 4976.7 5057.4 4924.5 4943.5 4943.6 5040.7 5090.9 4970.3 5013.0 5017.3 5027.6 5083.0 5356.2 5389.0 5469.1 5475.2 5566.1 5882.1 5897.8 5914.6 5924.5 5950.1 5688.6 5943.6 5974.9 5977.3 6003.7 5486.9 5488.9 5511.9 5595.9 5597.7 6030.7 6039.8 6063.0 6076.8 6134.0 5601.3 5855.5 5872.9 5894.8 5913.4 5804.9 5855.7 5860.2 5898.2 5903.1 5831.5 5875.3 5877.8 5889.9 5892.3 5661.5 5784.2 5818.4 5867.6 5876.9 4452.4 4474.2 4476.9 4519.3 4521.6 3954.6 3968.8 3974.8 3982.2 4011.9 5349.6 5392.1 5400.9 5507.4 5572.9 5795.3 5810.1 5871.1 5912.1 5915.2 5533.2 5539.3 5549.9 5550.9 5552.1 4927.9 5001.1 5009.7 5078.1 5154.7 6090.9 6096.6 6097.7 6103.7 6143.5 5840.1 5878.5 5882.9 5887.0 5887.1 5863.4 5933.0 5933.2 5983.9 6071.6 5712.5 5753.1 5757.1 5758.9 5760.1 5779.8 5851.5 5854.2 5861.2 5863.8 5472.2 5549.0 5553.5 5569.4 5624.0 5535.2 5586.9 5600.8 5606.7 5610.2 5461.6 5502.4 5513.1 5514.1 5543.9 5620.8 5712.8 5718.3 5822.2 5823.2 5613.1 5618.8 5626.3 5628.6 5629.3 20:02:46 INFO - PROCESS | 29165 | 20:02:46 INFO - PROCESS | 29165 | [#1] ext.html Cycles:180 Average:62752.61 Median:42796.57 stddev:127627.46 (298.2%) stddev-sans-first:127900.25 20:02:46 INFO - PROCESS | 29165 | Values: 628.3 633.8 635.0 646.3 648.0 30655.1 34536.7 34608.0 42392.2 43970.3 46147.8 46508.5 46622.0 46640.3 46651.3 8870.6 9057.2 9060.2 9064.9 9068.0 8926.2 9021.5 9023.9 9026.0 9026.5 9048.3 9083.4 9086.4 9090.0 9093.3 8921.4 8962.7 8971.4 8982.6 9004.1 8911.7 8953.0 8967.4 8968.0 8971.6 42527.4 42861.6 42914.4 42955.4 43002.8 3760.3 4232.5 4237.3 4260.4 4379.6 32513.5 32520.7 33131.8 33222.2 33302.8 4404.4 4422.7 4570.7 4608.6 4615.4 349352.0 351941.0 352717.0 353905.0 356636.0 57240.7 57931.4 57961.0 58005.7 58018.4 59834.7 59857.4 60112.0 60119.1 60123.4 16629.5 16866.8 16871.6 16873.7 16877.7 12903.9 12934.4 12962.9 12966.8 12972.9 14332.8 16045.0 16058.8 16082.9 16126.3 4991.6 5755.0 6078.2 6098.7 6127.7 994.9 1012.0 1107.5 1112.7 1113.7 31601.4 32423.8 32505.7 32620.5 32681.8 1142.6 1144.6 1202.2 1222.1 1249.4 17000.7 17205.0 17238.4 17361.8 17397.9 569420.0 571045.0 576294.0 576824.0 577358.0 58855.0 59131.0 59163.7 59172.4 59224.7 1251.5 1365.5 1366.6 1387.5 1391.6 32189.0 33857.5 33903.8 33940.5 34484.3 50133.5 53326.0 55420.7 55595.7 61015.4 483424.0 484572.0 485479.0 488978.0 490896.8 24446.0 26870.5 26973.6 27559.9 27573.3 49063.5 49515.3 49709.8 49787.3 50226.2 37942.0 40386.8 42078.2 42411.2 42487.4 32122.5 35367.1 35603.3 35723.8 35864.5 42289.8 42342.2 42731.6 43055.4 43137.6 36186.5 37585.0 38249.0 38294.6 38589.8 28062.3 28157.4 28338.9 28506.3 28585.9 20:02:46 INFO - PROCESS | 29165 | 20:02:46 INFO - PROCESS | 29165 | [#2] jquery.html Cycles:180 Average:27287.69 Median:183518.82 stddev:72223.21 (39.4%) stddev-sans-first:72397.56 20:02:46 INFO - PROCESS | 29165 | Values: 381.6 383.3 391.7 398.7 411.3 14534.6 14807.9 17201.7 18871.3 18894.2 14789.5 17098.3 17628.2 18201.1 18517.3 5518.5 5739.6 5753.7 5770.2 5773.2 4154.5 5656.2 5757.3 6070.7 6349.4 6349.7 6412.6 6429.8 6430.6 6443.0 6264.7 6347.3 6410.8 6416.6 6427.4 5421.2 5470.2 5553.9 5592.5 5598.8 21858.2 22510.2 22581.7 22590.8 22764.8 2364.2 2668.5 3091.0 3098.3 3146.9 13397.4 14066.0 14151.2 14339.6 14674.3 1935.3 1944.1 1965.8 1987.0 2039.2 70249.6 74035.0 76608.6 78968.6 83599.6 10310.4 10737.5 10738.8 10875.4 10974.4 15131.0 15181.6 15221.7 15249.1 15297.0 7978.8 8081.8 8137.7 8161.3 8212.9 6444.3 7647.5 7713.4 7788.5 7839.1 4386.5 4637.2 4643.3 4720.5 4749.7 1885.8 1905.4 1933.3 1938.6 2017.2 936.3 947.9 969.7 996.1 1009.8 17610.5 18133.8 18433.4 18649.6 18802.9 970.0 991.3 1019.9 1032.4 1039.8 10096.6 10306.5 10431.4 10495.6 10500.0 365868.0 366656.0 461636.0 462633.0 497112.0 96237.0 98791.0 99997.5 104738.0 105131.5 427.2 449.8 453.3 476.7 497.5 10014.4 10047.8 10133.7 10425.2 10501.1 41743.6 41995.0 42433.0 42683.8 43256.0 59507.7 61384.4 61781.4 62056.4 62412.0 7962.2 8160.7 8237.0 8625.9 8647.4 9320.0 12650.5 12995.8 13166.1 13407.9 11308.3 11467.5 11553.4 11570.2 11627.7 11543.5 11693.2 11714.7 11773.2 11855.3 11510.0 11554.5 11753.5 12049.9 12116.5 8087.6 8113.8 8294.6 8339.7 8625.3 8778.7 10830.7 11807.4 11950.7 12038.8 20:02:46 INFO - PROCESS | 29165 | 20:02:46 INFO - PROCESS | 29165 | [#3] mootools.html Cycles:180 Average:5756.92 Median:2470.12 stddev:3260.53 (132.0%) stddev-sans-first:3252.60 20:02:46 INFO - PROCESS | 29165 | Values: 1316.9 1462.8 1466.4 1603.6 1792.4 8225.8 8592.0 8609.1 8848.0 8872.5 8448.3 8587.7 8644.4 8652.0 8656.6 1998.9 2276.4 2354.0 2359.3 2391.3 2322.3 2324.7 2325.2 2336.6 2344.1 2270.4 2298.0 2330.5 2332.0 2332.1 1922.7 2140.8 2213.5 2217.5 2253.5 2278.6 2292.9 2294.8 2295.5 2297.2 8543.0 8589.8 8592.7 8596.3 8637.4 5030.3 5180.0 5185.2 5185.9 5186.3 3299.1 3402.6 3529.6 3560.6 3575.7 1207.1 1231.8 1233.7 1247.4 1290.1 11908.6 11967.0 11986.0 12000.3 12002.3 4493.6 4561.5 4643.7 4655.2 4660.3 10127.4 10142.7 10149.0 10152.7 10160.9 5134.3 5141.2 5147.5 5149.1 5149.3 4213.7 4532.7 4688.4 4694.0 4744.8 3942.8 3973.3 4048.1 4053.0 4111.8 1260.9 1267.7 1268.2 1268.4 1283.0 958.3 994.8 998.7 1000.8 1007.1 6118.6 6143.7 6205.0 6224.4 6261.5 433.8 434.9 441.9 446.6 446.7 4281.4 4292.7 4324.8 4332.9 4333.5 10579.3 10621.3 10667.7 10697.5 10749.3 10618.5 10624.9 10628.1 10638.0 10644.4 7572.6 7772.1 7871.5 7993.0 8048.5 6010.0 6256.5 6269.8 6289.4 6421.0 9457.3 10115.5 10308.1 10320.6 10329.3 9988.6 10238.5 10244.3 10451.9 10504.3 7015.0 7018.6 7847.0 7979.5 8022.6 8615.7 8618.0 8702.9 8707.4 8720.7 7578.8 7645.6 7725.4 7845.3 7864.1 7570.8 7667.7 7680.3 7788.2 7808.6 6987.1 7045.4 7078.5 7079.8 7082.5 5103.1 5218.8 5250.4 5364.0 5415.9 4915.0 5180.6 5215.5 5315.4 5388.1 20:02:46 INFO - PROCESS | 29165 | 20:02:46 INFO - PROCESS | 29165 | [#4] prototype.html Cycles:180 Average:4650.28 Median:5291.03 stddev:1374.79 (26.0%) stddev-sans-first:1339.57 20:02:46 INFO - PROCESS | 29165 | Values: 313.8 318.3 319.5 320.6 321.8 5264.5 5315.4 5324.9 5341.3 5366.3 5271.6 5271.6 5273.9 5276.2 5283.9 3944.0 3978.9 4108.0 4115.5 4155.8 4059.9 4106.6 4114.1 4128.2 4140.3 3882.2 4092.7 4102.7 4110.3 4110.9 4009.9 4095.9 4097.7 4100.2 4113.3 3606.9 3625.1 3639.1 3641.1 3659.6 5140.7 5143.8 5145.9 5158.3 5164.4 4431.4 4664.5 4683.3 4685.6 4693.8 5439.8 5485.8 5487.6 5491.9 5503.4 3956.4 3972.0 4000.6 4013.5 4015.8 5555.9 5683.2 5701.0 5701.5 5706.6 4028.6 4038.3 4041.4 4047.7 4068.0 4098.0 4102.5 4104.1 4111.3 4127.0 4423.8 4577.2 4608.6 4653.7 4686.9 5484.4 5494.0 5511.0 5544.3 5571.9 1379.0 1405.2 1407.4 1409.3 1409.8 893.0 903.7 905.9 913.7 930.3 5252.6 5265.7 5267.9 5273.9 5297.3 5462.6 5506.9 5589.5 5596.0 5612.6 5017.4 5250.4 5277.9 5280.2 5283.9 2973.6 2979.3 2987.8 2997.8 3011.0 5796.2 5806.4 5809.9 5832.0 5839.3 5564.3 5776.8 5864.5 5874.3 5878.4 5913.8 5918.8 5927.1 5937.4 5954.7 5524.1 5738.7 5759.9 5774.7 5782.9 5626.4 5635.4 5691.1 5694.0 5695.5 5866.8 5871.8 5881.7 5888.2 5889.2 3967.5 4087.5 4092.3 4096.0 4110.2 5636.9 5688.6 5731.9 5734.1 5752.8 5284.7 5334.8 5569.3 5604.7 5608.6 5519.4 5522.3 5527.6 5536.8 5538.1 5418.6 5421.3 5427.5 5445.2 5470.6 5297.7 5514.3 5553.9 5558.9 5572.4 5420.5 5462.6 5478.9 5500.6 5535.7 20:02:46 INFO - PROCESS | 29165 | 20:02:46 INFO - PROCESS | 29165 | [#5] yui.html Cycles:180 Average:20865.98 Median:25967.60 stddev:30077.69 (115.8%) stddev-sans-first:30124.12 20:02:46 INFO - PROCESS | 29165 | Values: 742.9 762.1 805.6 821.6 832.5 14944.1 15068.3 15078.8 15082.5 15285.2 14931.1 15076.5 15332.6 15334.4 15342.6 683.4 799.5 822.9 832.1 839.4 809.6 819.9 820.1 820.4 820.5 866.6 866.7 870.0 870.4 871.4 695.2 828.6 836.5 861.6 862.1 630.8 632.3 632.3 633.3 635.8 15577.8 15599.8 15637.3 15643.1 15653.0 14014.7 14492.9 14624.9 14695.6 14740.6 11494.6 11534.3 11604.6 11717.9 12054.5 4462.6 4508.5 4622.9 4702.5 4749.4 75807.0 76359.2 77253.6 77374.1 77402.0 11338.2 11436.8 11696.4 11838.6 12127.5 22063.6 22096.2 22128.9 22141.3 22154.6 8692.8 8709.2 8827.1 8876.5 9097.7 4789.5 4817.6 4834.0 4867.4 4897.7 3561.6 3667.4 3683.2 3686.2 3688.2 627.7 637.1 637.7 645.8 652.8 538.3 598.8 607.4 716.9 740.8 9772.6 9772.7 9931.0 10095.7 10260.3 673.0 689.2 732.4 753.8 755.1 4597.9 4866.2 4968.6 4992.9 5006.5 117295.0 122308.0 125429.0 125752.0 126060.4 79281.6 82675.6 83939.6 102723.0 107373.0 86155.0 86627.0 86923.0 87170.0 87381.6 9509.4 9900.9 9920.0 9942.5 9991.9 36611.8 44419.8 47232.7 47410.5 49075.5 58487.0 77098.2 77400.0 77627.6 80259.6 13301.3 13442.2 13707.8 13761.9 14122.4 14826.0 14914.6 14933.6 15200.7 15500.3 14128.1 14362.0 14409.4 14574.3 14575.1 13944.3 14655.5 14712.5 14963.4 15124.8 13271.3 13821.7 13847.9 13967.7 14430.0 11005.6 11147.5 11267.1 11566.7 11582.1 10744.8 11111.0 11698.2 11904.5 11949.6 20:02:46 INFO - PROCESS | 29165 | -------- Summary: end -------- 20:02:46 INFO - PROCESS | 29165 | 20:02:46 INFO - TEST-INFO | 29165: exit 0 20:02:47 INFO - TEST-OK | dromaeo_css | took 1115643ms 20:02:47 INFO - TEST-START | kraken 20:02:47 INFO - operating with platform_type : linux_ 20:02:47 INFO - Initialising browser for kraken test... 20:02:47 INFO - TEST-INFO | started process 29341 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEGhpCL/profile http://localhost:41094/getInfo.html) 20:02:51 INFO - PROCESS | 29341 | __metrics Screen width/height:1600/1200 20:02:51 INFO - PROCESS | 29341 | colorDepth:24 20:02:51 INFO - PROCESS | 29341 | Browser inner width/height: 1024/697 20:02:51 INFO - PROCESS | 29341 | __metrics 20:02:53 INFO - PROCESS | 29341 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:02:54 INFO - TEST-INFO | 29341: exit 0 20:02:54 INFO - Browser initialized. 20:02:54 INFO - Running cycle 1/1 for kraken test... 20:02:54 INFO - TEST-INFO | started process 29425 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEGhpCL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:03:06 INFO - PROCESS | 29425 | RSS: Main: 173703168 20:03:06 INFO - PROCESS | 29425 | 20:03:08 INFO - PROCESS | 29425 | Cycle 1(1): loaded http://localhost:41094/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41094/tests/kraken/driver.html?testName=audio-beat-detection) 20:03:09 INFO - PROCESS | 29425 | RSS: Main: 233115648 20:03:09 INFO - PROCESS | 29425 | 20:03:12 INFO - PROCESS | 29425 | Cycle 1(1): loaded http://localhost:41094/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41094/tests/kraken/driver.html?testName=audio-dft) 20:03:12 INFO - PROCESS | 29425 | RSS: Main: 221405184 20:03:12 INFO - PROCESS | 29425 | 20:03:15 INFO - PROCESS | 29425 | Cycle 1(1): loaded http://localhost:41094/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41094/tests/kraken/driver.html?testName=audio-fft) 20:03:16 INFO - PROCESS | 29425 | RSS: Main: 286990336 20:03:16 INFO - PROCESS | 29425 | 20:03:18 INFO - PROCESS | 29425 | Cycle 1(1): loaded http://localhost:41094/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41094/tests/kraken/driver.html?testName=audio-oscillator) 20:03:19 INFO - PROCESS | 29425 | RSS: Main: 199024640 20:03:19 INFO - PROCESS | 29425 | 20:03:21 INFO - PROCESS | 29425 | Cycle 1(1): loaded http://localhost:41094/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41094/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:03:21 INFO - PROCESS | 29425 | RSS: Main: 203485184 20:03:21 INFO - PROCESS | 29425 | 20:03:24 INFO - PROCESS | 29425 | Cycle 1(1): loaded http://localhost:41094/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41094/tests/kraken/driver.html?testName=imaging-darkroom) 20:03:25 INFO - PROCESS | 29425 | RSS: Main: 236937216 20:03:25 INFO - PROCESS | 29425 | 20:03:28 INFO - PROCESS | 29425 | Cycle 1(1): loaded http://localhost:41094/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41094/tests/kraken/driver.html?testName=imaging-desaturate) 20:03:29 INFO - PROCESS | 29425 | RSS: Main: 238501888 20:03:29 INFO - PROCESS | 29425 | 20:03:32 INFO - PROCESS | 29425 | Cycle 1(1): loaded http://localhost:41094/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41094/tests/kraken/driver.html?testName=json-parse-financial) 20:03:33 INFO - PROCESS | 29425 | RSS: Main: 238202880 20:03:33 INFO - PROCESS | 29425 | 20:03:34 INFO - PROCESS | 29425 | Cycle 1(1): loaded http://localhost:41094/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41094/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:03:35 INFO - PROCESS | 29425 | RSS: Main: 226938880 20:03:35 INFO - PROCESS | 29425 | 20:03:36 INFO - PROCESS | 29425 | Cycle 1(1): loaded http://localhost:41094/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41094/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:03:37 INFO - PROCESS | 29425 | RSS: Main: 232828928 20:03:37 INFO - PROCESS | 29425 | 20:03:39 INFO - PROCESS | 29425 | Cycle 1(1): loaded http://localhost:41094/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41094/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:03:40 INFO - PROCESS | 29425 | RSS: Main: 241078272 20:03:40 INFO - PROCESS | 29425 | 20:03:42 INFO - PROCESS | 29425 | Cycle 1(1): loaded http://localhost:41094/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41094/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:03:43 INFO - PROCESS | 29425 | RSS: Main: 240832512 20:03:43 INFO - PROCESS | 29425 | 20:03:46 INFO - PROCESS | 29425 | Cycle 1(1): loaded http://localhost:41094/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41094/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:03:46 INFO - PROCESS | 29425 | RSS: Main: 241942528 20:03:46 INFO - PROCESS | 29425 | 20:03:48 INFO - PROCESS | 29425 | Cycle 1(1): loaded http://localhost:41094/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41094/tests/kraken/driver.html?testName=ai-astar) 20:03:48 INFO - PROCESS | 29425 | RSS: Main: 285368320 20:03:48 INFO - PROCESS | 29425 | 20:03:48 INFO - PROCESS | 29425 | __start_tp_report 20:03:48 INFO - PROCESS | 29425 | _x_x_mozilla_page_load 20:03:48 INFO - PROCESS | 29425 | _x_x_mozilla_page_load_details 20:03:48 INFO - PROCESS | 29425 | |i|pagename|runs| 20:03:48 INFO - PROCESS | 29425 | |0;ai-astar;109;104;110;109;111;109;110;109;109;111 20:03:48 INFO - PROCESS | 29425 | |1;audio-beat-detection;161;125;129;178;127;132;153;126;164;128 20:03:48 INFO - PROCESS | 29425 | |2;audio-dft;160;160;159;160;160;161;161;160;160;160 20:03:48 INFO - PROCESS | 29425 | |3;audio-fft;74;76;76;124;75;76;75;75;100;76 20:03:48 INFO - PROCESS | 29425 | |4;audio-oscillator;86;85;127;86;85;84;126;85;87;125 20:03:48 INFO - PROCESS | 29425 | |5;imaging-gaussian-blur;117;117;117;117;117;117;118;117;118;117 20:03:48 INFO - PROCESS | 29425 | |6;imaging-darkroom;146;146;146;146;146;146;146;146;145;145 20:03:48 INFO - PROCESS | 29425 | |7;imaging-desaturate;95;95;95;95;95;94;95;95;95;95 20:03:48 INFO - PROCESS | 29425 | |8;json-parse-financial;53;52;52;55;54;53;53;55;53;54 20:03:48 INFO - PROCESS | 29425 | |9;json-stringify-tinderbox;61;64;61;65;62;61;62;61;61;61 20:03:48 INFO - PROCESS | 29425 | |10;stanford-crypto-aes;81;79;83;82;85;83;80;80;112;84 20:03:48 INFO - PROCESS | 29425 | |11;stanford-crypto-ccm;122;128;126;122;138;132;121;121;125;122 20:03:48 INFO - PROCESS | 29425 | |12;stanford-crypto-pbkdf2;158;159;162;159;169;160;158;159;160;162 20:03:48 INFO - PROCESS | 29425 | |13;stanford-crypto-sha256-iterative;63;62;62;63;66;62;67;64;62;61 20:03:48 INFO - PROCESS | 29425 | __end_tp_report 20:03:48 INFO - PROCESS | 29425 | __start_cc_report 20:03:48 INFO - PROCESS | 29425 | _x_x_mozilla_cycle_collect,1607 20:03:48 INFO - PROCESS | 29425 | __end_cc_report 20:03:48 INFO - PROCESS | 29425 | __startTimestamp1466132628625__endTimestamp 20:03:48 INFO - PROCESS | 29425 | 20:03:48 INFO - PROCESS | 29425 | ------- Summary: start ------- 20:03:48 INFO - PROCESS | 29425 | Number of tests: 14 20:03:48 INFO - PROCESS | 29425 | 20:03:48 INFO - PROCESS | 29425 | [#0] ai-astar Cycles:10 Average:109.10 Median:109.50 stddev:1.97 (1.8%) stddev-sans-first:2.09 20:03:48 INFO - PROCESS | 29425 | Values: 109.0 104.0 110.0 109.0 111.0 109.0 110.0 109.0 109.0 111.0 20:03:48 INFO - PROCESS | 29425 | 20:03:48 INFO - PROCESS | 29425 | [#1] audio-beat-detection Cycles:10 Average:142.30 Median:142.50 stddev:19.71 (13.8%) stddev-sans-first:19.71 20:03:48 INFO - PROCESS | 29425 | Values: 161.0 125.0 129.0 178.0 127.0 132.0 153.0 126.0 164.0 128.0 20:03:48 INFO - PROCESS | 29425 | 20:03:48 INFO - PROCESS | 29425 | [#2] audio-dft Cycles:10 Average:160.10 Median:160.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 20:03:48 INFO - PROCESS | 29425 | Values: 160.0 160.0 159.0 160.0 160.0 161.0 161.0 160.0 160.0 160.0 20:03:48 INFO - PROCESS | 29425 | 20:03:48 INFO - PROCESS | 29425 | [#3] audio-fft Cycles:10 Average:82.70 Median:75.50 stddev:16.46 (21.8%) stddev-sans-first:17.15 20:03:48 INFO - PROCESS | 29425 | Values: 74.0 76.0 76.0 124.0 75.0 76.0 75.0 75.0 100.0 76.0 20:03:48 INFO - PROCESS | 29425 | 20:03:48 INFO - PROCESS | 29425 | [#4] audio-oscillator Cycles:10 Average:97.60 Median:85.00 stddev:19.62 (23.1%) stddev-sans-first:20.36 20:03:48 INFO - PROCESS | 29425 | Values: 86.0 85.0 127.0 86.0 85.0 84.0 126.0 85.0 87.0 125.0 20:03:48 INFO - PROCESS | 29425 | 20:03:48 INFO - PROCESS | 29425 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 20:03:48 INFO - PROCESS | 29425 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 118.0 117.0 118.0 117.0 20:03:48 INFO - PROCESS | 29425 | 20:03:48 INFO - PROCESS | 29425 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 20:03:48 INFO - PROCESS | 29425 | Values: 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 145.0 145.0 20:03:48 INFO - PROCESS | 29425 | 20:03:48 INFO - PROCESS | 29425 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 20:03:48 INFO - PROCESS | 29425 | Values: 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 20:03:48 INFO - PROCESS | 29425 | 20:03:48 INFO - PROCESS | 29425 | [#8] json-parse-financial Cycles:10 Average:53.40 Median:53.50 stddev:1.07 (2.0%) stddev-sans-first:1.13 20:03:48 INFO - PROCESS | 29425 | Values: 53.0 52.0 52.0 55.0 54.0 53.0 53.0 55.0 53.0 54.0 20:03:48 INFO - PROCESS | 29425 | 20:03:48 INFO - PROCESS | 29425 | [#9] json-stringify-tinderbox Cycles:10 Average:61.90 Median:61.50 stddev:1.45 (2.4%) stddev-sans-first:1.50 20:03:48 INFO - PROCESS | 29425 | Values: 61.0 64.0 61.0 65.0 62.0 61.0 62.0 61.0 61.0 61.0 20:03:48 INFO - PROCESS | 29425 | 20:03:48 INFO - PROCESS | 29425 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:82.50 stddev:9.71 (11.8%) stddev-sans-first:10.20 20:03:48 INFO - PROCESS | 29425 | Values: 81.0 79.0 83.0 82.0 85.0 83.0 80.0 80.0 112.0 84.0 20:03:48 INFO - PROCESS | 29425 | 20:03:48 INFO - PROCESS | 29425 | [#11] stanford-crypto-ccm Cycles:10 Average:125.70 Median:125.50 stddev:5.60 (4.5%) stddev-sans-first:5.78 20:03:48 INFO - PROCESS | 29425 | Values: 122.0 128.0 126.0 122.0 138.0 132.0 121.0 121.0 125.0 122.0 20:03:48 INFO - PROCESS | 29425 | 20:03:48 INFO - PROCESS | 29425 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.60 Median:160.00 stddev:3.27 (2.0%) stddev-sans-first:3.33 20:03:48 INFO - PROCESS | 29425 | Values: 158.0 159.0 162.0 159.0 169.0 160.0 158.0 159.0 160.0 162.0 20:03:48 INFO - PROCESS | 29425 | 20:03:48 INFO - PROCESS | 29425 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:63.00 stddev:1.93 (3.1%) stddev-sans-first:2.05 20:03:48 INFO - PROCESS | 29425 | Values: 63.0 62.0 62.0 63.0 66.0 62.0 67.0 64.0 62.0 61.0 20:03:48 INFO - PROCESS | 29425 | -------- Summary: end -------- 20:03:48 INFO - PROCESS | 29425 | 20:03:49 INFO - TEST-INFO | 29425: exit 0 20:03:49 INFO - TEST-OK | kraken | took 62648ms 20:03:49 INFO - SUITE-END | took 1178s 20:03:49 INFO - Completed test suite (00:19:38) 20:03:49 INFO - javascript benchmark 20:03:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3096.8296743679102, 3113.034959162658, 3129.139486641174, 3142.0573827851645, 3160.88936887209, 5331.360236771583, 5379.327584051994, 5406.513413792758, 5468.371137319209, 5500.092484740018, 5424.349078110632, 5448.0, 5455.918161227585, 5511.862203444916, 5587.413321601234, 4909.361782968209, 4977.07924034053, 4978.0, 4985.127602669537, 5108.974455127719, 5066.316047333618, 5103.2855400244, 5104.923426148593, 5125.923556053233, 5127.717975511346, 4885.877853053685, 4910.6317026223, 4951.8266860659705, 4976.701397916137, 5057.393112826466, 4924.532169443897, 4943.456219815817, 4943.579795717368, 5040.722760248185, 5090.898182036339, 4970.304157417964, 5013.022460620181, 5017.2724954881705, 5027.572656324217, 5083.0, 5356.169793681946, 5388.973055134699, 5469.0702580561, 5475.178723191513, 5566.137248726453, 5882.147088672162, 5897.793577224819, 5914.615549989237, 5924.496417804492, 5950.077737950624, 5688.573356998241, 5943.613665111754, 5974.8506287342725, 5977.282726072855, 6003.699815009276, 5486.890262194734, 5488.91766623497, 5511.917321240185, 5595.916061259084, 5597.6641401516445, 6030.703929230241, 6039.77923565551, 6062.954187590899, 6076.817695469143, 6134.0, 5601.327840659147, 5855.473007429352, 5872.882542349129, 5894.764209431574, 5913.438223368773, 5804.912926306109, 5855.677937713382, 5860.179574859523, 5898.203742494794, 5903.055511118287, 5831.533477321804, 5875.29496460419, 5877.823665290048, 5889.911651325234, 5892.263467066617, 5661.490465858093, 5784.248047753765, 5818.418158184148, 5867.618604790675, 5876.882462350729, 4452.354408610733, 4474.239379305526, 4476.888077798081, 4519.344695019204, 4521.59305662492, 3954.6045395460224, 3968.8015599219925, 3974.7615143091507, 3982.203559288154, 4011.919761604752, 5349.5720342372515, 5392.137258038695, 5400.91898621521, 5507.394186639466, 5572.944270557324, 5795.304563452412, 5810.099434587646, 5871.148683440877, 5912.083627037816, 5915.201447804578, 5533.22534845122, 5539.307586551681, 5549.889002219933, 5550.888982220333, 5552.139418390156, 4927.852164435073, 5001.074801161797, 5009.674371165863, 5078.13671675816, 5154.7164905929785, 6090.878182436327, 6096.603720758137, 6097.664628445389, 6103.664298463627, 6143.508519318444, 5840.065589505829, 5878.500327472213, 5882.94117058841, 5887.028640274307, 5887.116932460165, 5863.413658634103, 5932.970335148297, 5933.199018132497, 5983.9401605983385, 6071.605345652519, 5712.485876271156, 5753.050746626927, 5757.107648314518, 5758.913616295843, 5760.0783874579865, 5779.768809247583, 5851.502622277154, 5854.180414742024, 5861.238039054895, 5863.765449381977, 5472.20653005304, 5549.0289199389135, 5553.527950124123, 5569.387367389665, 5623.971880140573, 5535.197396377584, 5586.888262234814, 5600.803971861128, 5606.6916319602815, 5610.18652295423, 5461.617686761889, 5502.422245664307, 5513.117901135799, 5514.09017512122, 5543.861403464945, 5620.775168993195, 5712.7714891403875, 5718.3423906249645, 5822.184894114912, 5823.18475413451, 5613.101903695389, 5618.775248989995, 5626.2967129108865, 5628.60599758013, 5629.268195134607], "value": 5389.398994155781, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [628.3339659960442, 633.8256979330683, 634.9904751428728, 646.2729429391934, 647.9870402591952, 30655.080347589552, 34536.65463345366, 34608.0, 42392.152156956916, 43970.34044489335, 46147.76926115365, 46508.534914650845, 46621.99999999991, 46640.300395494094, 46651.30023049657, 8870.645174193041, 9057.230135438496, 9060.229880460169, 9064.864027039566, 9067.95466022669, 8926.196642302191, 9021.458712477272, 9023.864642030374, 9025.95487022564, 9026.548672566345, 9048.321375896801, 9083.409578377417, 9086.409383390059, 9090.045545217723, 9093.31800114994, 8921.420107692978, 8962.731118066436, 8971.372003959725, 8982.595783189772, 9004.054574269672, 8911.73264802057, 8953.0, 8967.417117887317, 8967.955160224223, 8971.596278167499, 42527.36208956868, 42861.57138428607, 42914.356284655754, 42955.35566966498, 43002.78498607518, 3760.341940160461, 4232.492100947875, 4237.258479766028, 4260.360945858115, 4379.64962802975, 32513.51229731556, 32520.674793252, 33131.83434082838, 33222.16944576381, 33302.833485832416, 4404.361367601679, 4422.712523685982, 4570.657200709927, 4608.585227329557, 4615.446146462446, 349352.0, 351941.0, 352717.0, 353905.0, 356635.9999999974, 57240.713796430755, 57931.42068579345, 57961.0, 58005.70997144987, 58018.4198158013, 59834.70082649559, 59857.40142598518, 60112.0, 60119.098213526835, 60123.39876601265, 16629.50111496657, 16866.807315048965, 16871.578210544834, 16873.662526749642, 16877.662446750997, 12903.870961290455, 12934.41795119222, 12962.935185324013, 12966.80549791754, 12972.870271297166, 14332.785008224884, 16045.0, 16058.839411605968, 16082.874198805972, 16126.274317655734, 4991.625628077909, 5755.0, 6078.179445774852, 6098.664573448415, 6127.724252408652, 994.8507723841482, 1012.0, 1107.5293000474926, 1112.6703589210815, 1113.6770336602456, 31601.36797264042, 32423.837880810446, 32505.674943250266, 32620.469932298078, 32681.836590816893, 1142.5658249865164, 1144.6337172104847, 1202.1765090912577, 1222.113967373647, 1249.375312343828, 17000.659986800194, 17205.0, 17238.396656116976, 17361.826381736275, 17397.91301043512, 569420.0, 571045.0, 576294.0, 576824.0, 577358.0, 58855.0, 59131.0, 59163.704181479676, 59172.40827591755, 59224.703876480344, 1251.474380760083, 1365.528892532075, 1366.590022993098, 1387.535175716126, 1391.5616580777023, 32189.0, 33857.49213761746, 33903.830480848424, 33940.49089263712, 34484.310313794085, 50133.49866501434, 53326.0, 55420.72289638526, 55595.722021389636, 61015.389846100974, 483424.0, 484572.0, 485479.0, 488978.0, 490896.81928626593, 24446.0, 26870.462590748466, 26973.595396069468, 27559.86220068887, 27573.31066723348, 49063.53504997906, 49515.25727114024, 49709.75145124251, 49787.253191202886, 50226.24660630021, 37942.0, 40386.79806600948, 42078.15843683048, 42411.15177696367, 42487.362689559064, 32122.518162228054, 35367.11582210465, 35603.287934241685, 35723.82138089397, 35864.462033069, 42289.78855105705, 42342.153156936074, 42731.572684274004, 43055.35416968811, 43137.56862431335, 36186.45720314145, 37585.0, 38249.0, 38294.617053829104, 38589.80705096456, 28062.27687425641, 28157.43643329834, 28338.918142651954, 28506.287342816595, 28585.85707071451], "value": 20260.154698072056, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.64888302761454, 383.3258123838499, 391.7277492142962, 398.6910144637906, 411.2679430613507, 14534.636634084152, 14807.92596037021, 17201.65596688067, 18871.339503117375, 18894.24423023079, 14789.482368117118, 17098.31606735729, 17628.20673069712, 18201.08994550274, 18517.2593096276, 5518.475744804247, 5739.62692424991, 5753.712314384285, 5770.2210201622665, 5773.162891380747, 4154.5222299435645, 5656.179853921179, 5757.251557297545, 6070.696465176746, 6349.396807303322, 6349.71426285816, 6412.615243085406, 6429.8392540186405, 6430.646314452703, 6443.033544968245, 6264.718087686065, 6347.301796802348, 6410.807675769688, 6416.582922110071, 6427.389398007182, 5421.213923981, 5470.17947307903, 5553.861153471195, 5592.496675299242, 5598.832035038955, 21858.234961776263, 22510.212142574932, 22581.661275080885, 22590.77409225903, 22764.77235227643, 2364.2316247219724, 2668.4796464689393, 3090.9690903090905, 3098.256418459576, 3146.889858854952, 13397.397117129654, 14066.0, 14151.150930944073, 14339.569812905629, 14674.339654715563, 1935.2742721479444, 1944.105711372756, 1965.7739359973643, 1986.9801301987084, 2039.1945181653164, 70249.64875175591, 74034.99999999946, 76608.61695691542, 78968.60515697385, 83599.5820020896, 10310.43292618913, 10737.51681174349, 10738.838917416246, 10875.401852898167, 10974.421011062394, 15131.01648392851, 15181.6204594886, 15221.695566088616, 15249.085054896519, 15297.0, 7978.840423191504, 8081.797955051054, 8137.674493020331, 8161.306288965386, 8212.917870821335, 6444.323346048691, 7647.502912310682, 7713.4363560425745, 7788.532688038736, 7839.09850367215, 4386.451693538308, 4637.234856248681, 4643.326717625993, 4720.45714742809, 4749.715017098985, 1885.8397036251797, 1905.3807512558474, 1933.3040105561986, 1938.5928954919625, 2017.2334512885009, 936.279065119854, 947.9004704506066, 969.7236287658125, 996.0985308296003, 1009.7929924365634, 17610.47168584919, 18133.818661813475, 18433.354832580797, 18649.627007460047, 18802.905985469984, 969.970900872975, 991.2615101749099, 1019.8929112443235, 1032.3941000368075, 1039.7556574205055, 10096.646617368504, 10306.484675766182, 10431.426271555138, 10495.580176792995, 10500.0, 365868.0, 366656.0, 461636.0, 462633.0, 497112.0, 96237.0, 98791.0, 99997.50001249947, 104738.0, 105131.4743426278, 427.16276098845987, 449.7751124437781, 453.2634468987895, 476.72111814588163, 497.53729031999865, 10014.449205293487, 10047.799044018933, 10133.695989120339, 10425.218108642033, 10501.054905058581, 41743.58256417519, 41995.0, 42433.0, 42683.786581066895, 43256.0, 59507.70246148742, 61384.386156137865, 61781.382186177565, 62056.379436206866, 62412.0, 7962.203779622223, 8160.714374997084, 8237.0, 8625.913740862761, 8647.351448641251, 9320.0, 12650.493980240688, 12995.805062923875, 13166.078374513692, 13407.86592134066, 11308.264962777394, 11467.483963221843, 11553.364564949008, 11570.247933884271, 11627.709307267385, 11543.480543375736, 11693.181477296508, 11714.70713232176, 11773.234739741889, 11855.34795586251, 11510.0, 11554.537818487166, 11753.47109380097, 12049.939750301193, 12116.515339386326, 8087.63605637736, 8113.797155071171, 8294.58527073632, 8339.708110216114, 8625.309975202093, 8778.692745754124, 10830.67507974762, 11807.409629518661, 11950.70123246891, 12038.75922481563], "value": 8902.607350592401, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1316.9341532923352, 1462.7659574468084, 1466.3914475492668, 1603.647197616524, 1792.3905872003525, 8225.75322740318, 8592.0, 8609.139086091387, 8848.026717061135, 8872.467651940888, 8448.281896038845, 8587.742367728963, 8644.351673624471, 8652.0, 8656.610452529636, 1998.9300374486895, 2276.430892276931, 2353.976460235401, 2359.28041947206, 2391.258709799959, 2322.291701031185, 2324.721033475985, 2325.1513197682807, 2336.6495025746103, 2344.0858065354523, 2270.398344438725, 2298.0, 2330.5222429402015, 2332.0, 2332.125452955142, 1922.711593261015, 2140.8394370422125, 2213.513027134044, 2217.456723102834, 2253.4929640830765, 2278.5898538263104, 2292.931212063641, 2294.8393612447135, 2295.4605667668084, 2297.184499502686, 8542.957285213597, 8589.785255368603, 8592.69925552609, 8596.312295016383, 8637.352198585131, 5030.320906677625, 5180.0, 5185.248139019859, 5185.948140518584, 5186.273921650934, 3299.1257316810825, 3402.625711171769, 3529.6470352964757, 3560.608333083359, 3575.731820113476, 1207.0705556721275, 1231.8029115341503, 1233.6977440527128, 1247.3700781105483, 1290.0518119182393, 11908.583199587973, 11967.0, 11986.0, 12000.339981301026, 12002.279863208236, 4493.573110554492, 4561.521040290721, 4643.721376717408, 4655.231886738718, 4660.298990868377, 10127.442990635585, 10142.695719128438, 10149.0, 10152.746181345525, 10160.949195254125, 5134.2812006319145, 5141.2288156776785, 5147.485251474897, 5149.073166829931, 5149.330587023663, 4213.705040647125, 4532.7280363178315, 4688.367070445446, 4693.9765301173275, 4744.786484608201, 3942.7831469269468, 3973.264945985002, 4048.0689441428635, 4053.0, 4111.814968326373, 1260.8928241099609, 1267.7084270617809, 1268.245393990574, 1268.4165283969294, 1282.9679258018625, 958.2765012415582, 994.7861209839774, 998.7403275148514, 1000.7548150703125, 1007.0835539658875, 6118.6022908511695, 6143.662098584531, 6205.03821907605, 6224.37756224374, 6261.499080073583, 433.8199647146462, 434.89127718070483, 441.8961544037149, 446.63376031653735, 446.6739280325381, 4281.422008028876, 4292.720973136736, 4324.8486302979245, 4332.935005974913, 4333.523312435629, 10579.31234469757, 10621.256512044238, 10667.679969600924, 10697.518611662494, 10749.301295415773, 10618.469076546142, 10624.946875265729, 10628.14974802014, 10638.042576168182, 10644.361338319572, 7572.583507907118, 7772.145064042951, 7871.48835325702, 7992.960035199903, 8048.5170889746805, 6009.9699501503965, 6256.499480041677, 6269.780557680459, 6289.433950944437, 6421.0, 9457.33798634089, 10115.544800483849, 10308.12380947628, 10320.638777642747, 10329.276950613385, 9988.60045598168, 10238.539265733209, 10244.282900197213, 10451.94774026125, 10504.317219380715, 7014.964925175342, 7018.5788852669, 7847.0, 7979.521228726295, 8022.558759268296, 8615.741527754177, 8618.0, 8702.912970870464, 8707.434016788888, 8720.694775682821, 7578.810529736801, 7645.61771911391, 7725.398995897645, 7845.333146682367, 7864.134945156143, 7570.772876813704, 7667.693292268247, 7680.3471704905705, 7788.182240864854, 7808.609569521387, 6987.091678081817, 7045.401140903081, 7078.4691148163065, 7079.823004424725, 7082.5396349237135, 5103.132467480463, 5218.791248350023, 5250.369955605352, 5364.034473794754, 5415.918761218506, 4915.0, 5180.559652429586, 5215.478452154754, 5315.4153043166, 5388.084025715716], "value": 4540.23370431726, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.8117129722167, 318.34262248456935, 319.45532866462685, 320.6120594081162, 321.79083595662803, 5264.499872512115, 5315.415304316527, 5324.946750532494, 5341.252224688537, 5366.275552800371, 5271.578273738091, 5271.578273738101, 5273.920891186625, 5276.15581506958, 5283.920741188886, 3944.0, 3978.9204215915665, 4107.979460102695, 4115.506139263292, 4155.833766649331, 4059.8985025374304, 4106.568810274923, 4114.0948991221885, 4128.174365126972, 4140.296149654551, 3882.1753775725365, 4092.7339722918064, 4102.651274641659, 4110.280700877334, 4110.876673699794, 4009.939850902239, 4095.9180816383505, 4097.729703791819, 4100.159467309194, 4113.280175969194, 3606.8737594184076, 3625.093726568358, 3639.1448009717697, 3641.1443310821937, 3659.5791483979415, 5140.665856719302, 5143.819966301199, 5145.9485405146215, 5158.2520534522655, 5164.431912489623, 4431.446069241345, 4664.463586687507, 4683.274092515666, 4685.554872287127, 4693.835715749967, 5439.782408703647, 5485.765702716857, 5487.588430867661, 5491.862703432406, 5503.449655034465, 3956.4065390191413, 3972.0, 4000.6399424051683, 4013.478247827793, 4015.7791321477307, 5555.8888822223325, 5683.175939488792, 5700.971495142539, 5701.515371193454, 5706.572007099484, 4028.576999415078, 4038.273110840077, 4041.3735870939463, 4047.696422768245, 4067.9796601017397, 4098.0, 4102.50769907613, 4104.076582768913, 4111.301078816608, 4126.979365103155, 4423.8230470781455, 4577.198990176772, 4608.585227329557, 4653.7207767534055, 4686.906261874743, 5484.369297530755, 5493.972530137404, 5511.0, 5544.306961629796, 5571.888562228733, 1379.0, 1405.1943252919948, 1407.3737186951707, 1409.3017913414517, 1409.7744360902207, 892.9508877011697, 903.7333986474006, 905.9003509613938, 913.6984795017628, 930.251147825993, 5252.553532949666, 5265.657732247469, 5267.868303292372, 5273.894522109536, 5297.337832770903, 5462.644928079662, 5506.944930550643, 5589.496945274945, 5596.0, 5612.550995920316, 5017.37282839645, 5250.369955605352, 5277.868053298698, 5280.234366016983, 5283.867903302448, 2973.568832519316, 2979.2998645318335, 2987.8057926234724, 2997.80514266572, 3010.9849450752604, 5796.188533605298, 5806.4193580641595, 5809.9419005809405, 5832.0, 5839.270091238595, 5564.304461942257, 5776.768929242868, 5864.530837532987, 5874.324452687995, 5878.382769809108, 5913.793017244376, 5918.822435326947, 5927.110933360031, 5937.376575459514, 5954.702264886825, 5524.116141417354, 5738.680103576117, 5759.856003599776, 5774.682392468287, 5782.930157920798, 5626.352969408406, 5635.380108188014, 5691.121929763251, 5694.0, 5695.487406133468, 5866.823995280149, 5871.7651293947765, 5881.705914704333, 5888.205092312654, 5889.234399528034, 3967.5437324708028, 4087.4876295770755, 4092.3452247640234, 4095.9795201023803, 4110.23960567296, 5636.9436305638055, 5688.5733569981585, 5731.942680573141, 5734.136405668091, 5752.84943011391, 5284.7316644004395, 5334.839954801362, 5569.275994120901, 5604.747786349705, 5608.635438696471, 5519.39286678457, 5522.2544956430365, 5527.585431092764, 5536.840632153544, 5538.141588053858, 5418.647787893932, 5421.322334708161, 5427.48438898304, 5445.2104444854485, 5470.64440811346, 5297.682139071668, 5514.255575497256, 5553.916691249554, 5558.861028474159, 5572.387037425799, 5420.457954204706, 5462.644928079662, 5478.91781623284, 5500.559955203654, 5535.667859928417], "value": 4206.004473690163, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.9368503677188, 762.0626629246027, 805.6173317674104, 821.6302663801291, 832.5046597274621, 14944.103353798766, 15068.32192551335, 15078.849211507883, 15082.472113476031, 15285.235738213101, 14931.06893106893, 15076.47232346868, 15332.616684582863, 15334.386624535033, 15342.616434589141, 683.3610574113195, 799.5202878273019, 822.8724547695117, 832.0847068224965, 839.4040231435688, 809.5952023988006, 819.9139090395512, 820.1429506166041, 820.429801288102, 820.4625969989667, 866.6316815353481, 866.744310428422, 870.0299166429418, 870.3907264914553, 871.4422769427572, 695.1553862057574, 828.5898480252254, 836.4730219961385, 861.6208868098048, 862.0948004595182, 630.7792272704562, 632.2697284636253, 632.3423639415003, 633.2685747961112, 635.7615894039735, 15577.766333505006, 15599.844001559952, 15637.296321665552, 15643.139627320494, 15653.0, 14014.719705605932, 14492.855071449256, 14624.853751462455, 14695.559133226021, 14740.631484212874, 11494.597689080842, 11534.250273732265, 11604.593839215588, 11717.88282117185, 12054.517819287217, 4462.598366147063, 4508.549145085498, 4622.884427889296, 4702.459217190033, 4749.382580264543, 75807.0, 76359.160905793, 77253.61373193155, 77374.13129343567, 77402.0, 11338.206325557298, 11436.828447573293, 11696.415179241005, 11838.585649502225, 12127.514899404101, 22063.558728825654, 22096.2266320678, 22128.88935555344, 22141.335759927228, 22154.556908861734, 8692.826143477096, 8709.172628600272, 8827.073157318517, 8876.51179185138, 9097.681581144629, 4789.521047895252, 4817.614590832725, 4833.984863178772, 4867.415910090741, 4897.730624815598, 3561.5904171020666, 3667.376545987135, 3683.244934788362, 3686.2258925625383, 3688.243909998444, 627.6736097229415, 637.1207733327977, 637.7130291368902, 645.8062581225707, 652.7682672651235, 538.2652679093088, 598.8353202869246, 607.3956413368726, 716.874546954281, 740.7777666700076, 9772.609095636095, 9772.706818795448, 9930.950345248228, 10095.697129086138, 10260.281780275458, 672.9899051514232, 689.2074114768056, 732.4433430592682, 753.830388162659, 755.1353700013564, 4597.931031034537, 4866.197077482247, 4968.627352948543, 4992.850214493571, 5006.510059413655, 117295.0, 122308.0, 125429.0, 125752.0, 126060.36969815276, 79281.60359198166, 82675.5866220665, 83939.58030209932, 102722.97277027134, 107373.0, 86155.0, 86627.0, 86923.0, 87170.0, 87381.5630921854, 9509.429434233969, 9900.900990989998, 9920.0, 9942.502874856373, 9991.900080999098, 36611.81694091512, 44419.77790111158, 47232.749052584055, 47410.52589474061, 49075.509244907094, 58487.0, 77098.2290177091, 77400.0, 77627.61186194033, 80259.59870200611, 13301.333226888242, 13442.193468391928, 13707.794383084061, 13761.931190344385, 14122.364493597608, 14826.0, 14914.627134321729, 14933.551993440215, 15200.695986080438, 15500.302486387915, 14128.108019100759, 14362.0, 14409.42362305496, 14574.34415451286, 14575.052621579563, 13944.302784860514, 14655.487057953347, 14712.485063022743, 14963.40146394132, 15124.848751512343, 13271.270080145685, 13821.654458638615, 13847.86152138506, 13967.720645587235, 14430.0, 11005.559777608805, 11147.498362573866, 11267.098632109248, 11566.65300041, 11582.131340149353, 10744.83882741775, 11111.0, 11698.181127321006, 11904.523819047488, 11949.64151075469], "value": 7677.990829905949, "unit": "score"}], "name": "dromaeo_css", "value": 7227.5359726078605, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 104.0, 110.0, 109.0, 111.0, 109.0, 110.0, 109.0, 109.0, 111.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 125.0, 129.0, 178.0, 127.0, 132.0, 153.0, 126.0, 164.0, 128.0], "value": 142.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 159.0, 160.0, 160.0, 161.0, 161.0, 160.0, 160.0, 160.0], "value": 160.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 76.0, 124.0, 75.0, 76.0, 75.0, 75.0, 100.0, 76.0], "value": 82.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 127.0, 86.0, 85.0, 84.0, 126.0, 85.0, 87.0, 125.0], "value": 97.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 118.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 52.0, 55.0, 54.0, 53.0, 53.0, 55.0, 53.0, 54.0], "value": 53.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 61.0, 65.0, 62.0, 61.0, 62.0, 61.0, 61.0, 61.0], "value": 61.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 83.0, 82.0, 85.0, 83.0, 80.0, 80.0, 112.0, 84.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 128.0, 126.0, 122.0, 138.0, 132.0, 121.0, 121.0, 125.0, 122.0], "value": 125.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 159.0, 162.0, 159.0, 169.0, 160.0, 158.0, 159.0, 160.0, 162.0], "value": 160.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 63.0, 66.0, 62.0, 67.0, 64.0, 62.0, 61.0], "value": 63.2, "unit": "score"}], "name": "kraken", "value": 1499.4, "alertThreshold": 2.0}]} 20:03:49 INFO - Return code: 0 20:03:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:03:49 INFO - # TBPL SUCCESS # 20:03:49 INFO - Running post-action listener: _resource_record_post_action 20:03:49 INFO - ##### 20:03:49 INFO - ##### Finished run-tests step (success) 20:03:49 INFO - ##### 20:03:49 INFO - Running post-run listener: _resource_record_post_run 20:03:50 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 12288; Write bytes: 539418624; Read time: 60; Write time: 1334804 20:03:50 INFO - TinderboxPrint: CPU usage
13.2% 20:03:50 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 60 20:03:50 INFO - TinderboxPrint: I/O write bytes / time
539,418,624 / 1,334,804 20:03:50 INFO - TinderboxPrint: CPU idle
8,280.0 (86.7%) 20:03:50 INFO - TinderboxPrint: CPU user
1,205.4 (12.6%) 20:03:50 INFO - TinderboxPrint: Swap in / out
0 / 0 20:03:50 INFO - install - Wall time: 17s; CPU: 16.0%; Read bytes: 0; Write bytes: 197062656; Read time: 0; Write time: 574940 20:03:50 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 12288; Write bytes: 293027840; Read time: 60; Write time: 706972 20:03:50 INFO - Running post-run listener: _upload_blobber_files 20:03:50 INFO - Blob upload gear active. 20:03:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:03:50 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 20:03:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:03:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:03:50 INFO - (blobuploader) - INFO - Open directory for files ... 20:03:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:03:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:03:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:03:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:03:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:03:51 INFO - (blobuploader) - INFO - Done attempting. 20:03:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:03:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:03:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:03:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:03:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:03:55 INFO - (blobuploader) - INFO - Done attempting. 20:03:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:03:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:03:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:03:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:03:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:03:56 INFO - (blobuploader) - INFO - Done attempting. 20:03:56 INFO - (blobuploader) - INFO - Iteration through files over. 20:03:56 INFO - Return code: 0 20:03:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:03:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:03:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ca4ec73e7dee29b2730e18227664e0ea77157a37510584fd195ac377307a6395e2ac995aa2f2754ffae2744fb254c93da429ed7c7ac067ba437fe54355b7895", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a154c91c3fbf66ac841b0cb86168627fbba7269ae22997ae99879ffe1059ea55374fe990d01a8408d0b2ea370458ac7f024d19d5eec52b49b18471947c5fbec3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d545e2041f4be32e17c0b6a1383e415e5db5876d5edabe0a14a6491015255dbbda9fcb892ab31be5edbfcceeebcfeb7ed249c1ebd27b914f516e54cbe0d420a"} 20:03:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:03:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:03:56 INFO - Contents: 20:03:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ca4ec73e7dee29b2730e18227664e0ea77157a37510584fd195ac377307a6395e2ac995aa2f2754ffae2744fb254c93da429ed7c7ac067ba437fe54355b7895", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a154c91c3fbf66ac841b0cb86168627fbba7269ae22997ae99879ffe1059ea55374fe990d01a8408d0b2ea370458ac7f024d19d5eec52b49b18471947c5fbec3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d545e2041f4be32e17c0b6a1383e415e5db5876d5edabe0a14a6491015255dbbda9fcb892ab31be5edbfcceeebcfeb7ed249c1ebd27b914f516e54cbe0d420a"} 20:03:56 INFO - Running post-run listener: copy_logs_to_upload_dir 20:03:56 INFO - Copying logs to upload dir... 20:03:56 INFO - mkdir: /builds/slave/test/build/upload/logs 20:03:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1289.732777 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2016-06-16 20:03:56.453051) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-16 20:03:56.456952) ========= 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=8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ca4ec73e7dee29b2730e18227664e0ea77157a37510584fd195ac377307a6395e2ac995aa2f2754ffae2744fb254c93da429ed7c7ac067ba437fe54355b7895", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a154c91c3fbf66ac841b0cb86168627fbba7269ae22997ae99879ffe1059ea55374fe990d01a8408d0b2ea370458ac7f024d19d5eec52b49b18471947c5fbec3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d545e2041f4be32e17c0b6a1383e415e5db5876d5edabe0a14a6491015255dbbda9fcb892ab31be5edbfcceeebcfeb7ed249c1ebd27b914f516e54cbe0d420a"} build_url:https://queue.taskcluster.net/v1/task/Jl_cIzTkRkOUlEZunaxnTw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011345 build_url: 'https://queue.taskcluster.net/v1/task/Jl_cIzTkRkOUlEZunaxnTw/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/sha512/6ca4ec73e7dee29b2730e18227664e0ea77157a37510584fd195ac377307a6395e2ac995aa2f2754ffae2744fb254c93da429ed7c7ac067ba437fe54355b7895", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a154c91c3fbf66ac841b0cb86168627fbba7269ae22997ae99879ffe1059ea55374fe990d01a8408d0b2ea370458ac7f024d19d5eec52b49b18471947c5fbec3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d545e2041f4be32e17c0b6a1383e415e5db5876d5edabe0a14a6491015255dbbda9fcb892ab31be5edbfcceeebcfeb7ed249c1ebd27b914f516e54cbe0d420a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-16 20:03:56.484002) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 20:03:56.484348) ========= 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=8783db0840cc980e80bb0bd400000236-1466130139.562536-141562206 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004457 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 20:03:56.569697) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-16 20:03:56.570444) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-16 20:03:56.571149) ========= ========= Total master_lag: 0.21 =========