builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-021 starttime: 1467127119.8 results: success (0) buildid: 20160628070722 builduid: bf2972368771482bb72fc45e9a7631be revision: cf243ad179e9929358583f4b8e6f8ce709f85eb6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-28 08:18:39.802232) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-28 08:18:39.802771) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-28 08:18:39.803057) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-28 08:18:39.818569) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 08:18:39.818997) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1467127119.136077-232950574 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-28 08:18:39-- 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% 222M=0s 2016-06-28 08:18:39 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124506 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 08:18:39.958727) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 08:18:39.959056) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1467127119.136077-232950574 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013936 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 08:18:40.008455) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 08:18:40.008825) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cf243ad179e9929358583f4b8e6f8ce709f85eb6 --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 cf243ad179e9929358583f4b8e6f8ce709f85eb6 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1467127119.136077-232950574 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-28 08:18:40,116 truncating revision to first 12 chars 2016-06-28 08:18:40,116 Setting DEBUG logging. 2016-06-28 08:18:40,116 attempt 1/10 2016-06-28 08:18:40,116 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cf243ad179e9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-28 08:18:40,422 unpacking tar archive at: mozilla-central-cf243ad179e9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.641627 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 08:18:40.664295) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-28 08:18:40.664652) ========= script_repo_revision: cf243ad179e9929358583f4b8e6f8ce709f85eb6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-28 08:18:40.665059) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-28 08:18:40.665333) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-28 08:18:40.675563) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-06-28 08:18:40.675942) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1467127119.136077-232950574 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:18:40 INFO - MultiFileLogger online at 20160628 08:18:40 in /builds/slave/test 08:18:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 08:18:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:18:40 INFO - {'append_to_log': False, 08:18:40 INFO - 'base_work_dir': '/builds/slave/test', 08:18:40 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 08:18:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:18:40 INFO - 'branch': 'Firefox-Non-PGO', 08:18:40 INFO - 'buildbot_json_path': 'buildprops.json', 08:18:40 INFO - 'config_files': ('talos/linux_config.py',), 08:18:40 INFO - 'default_actions': ('clobber', 08:18:40 INFO - 'read-buildbot-config', 08:18:40 INFO - 'download-and-extract', 08:18:40 INFO - 'populate-webroot', 08:18:40 INFO - 'create-virtualenv', 08:18:40 INFO - 'install', 08:18:40 INFO - 'run-tests'), 08:18:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:18:40 INFO - 'download_minidump_stackwalk': True, 08:18:40 INFO - 'download_symbols': 'ondemand', 08:18:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:18:40 INFO - 'tooltool.py': '/tools/tooltool.py', 08:18:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:18:40 INFO - '/tools/misc-python/virtualenv.py')}, 08:18:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:18:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:18:40 INFO - 'installer_path': 'installer.exe', 08:18:40 INFO - 'log_level': 'info', 08:18:40 INFO - 'log_name': 'talos', 08:18:40 INFO - 'log_to_console': True, 08:18:40 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:18:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:18:40 INFO - 'opt_config_files': (), 08:18:40 INFO - 'pip_index': False, 08:18:40 INFO - 'sps_profile': False, 08:18:40 INFO - 'sps_profile_interval': 0, 08:18:40 INFO - 'suite': 'dromaeojs', 08:18:40 INFO - 'system_bits': '32', 08:18:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:18:40 INFO - 'title': 'talos-linux64-ix-021', 08:18:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:18:40 INFO - 'use_talos_json': True, 08:18:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:18:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:18:40 INFO - 'work_dir': 'build'} 08:18:40 INFO - ##### 08:18:40 INFO - ##### Running clobber step. 08:18:40 INFO - ##### 08:18:40 INFO - Running pre-action listener: _resource_record_pre_action 08:18:40 INFO - Running main action method: clobber 08:18:40 INFO - rmtree: /builds/slave/test/build 08:18:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:18:41 INFO - Running post-action listener: _resource_record_post_action 08:18:41 INFO - ##### 08:18:41 INFO - ##### Finished clobber step (success) 08:18:41 INFO - ##### 08:18:41 INFO - ##### 08:18:41 INFO - ##### Running read-buildbot-config step. 08:18:41 INFO - ##### 08:18:41 INFO - Running pre-action listener: _resource_record_pre_action 08:18:41 INFO - Running main action method: read_buildbot_config 08:18:41 INFO - Using buildbot properties: 08:18:41 INFO - { 08:18:41 INFO - "project": "", 08:18:41 INFO - "product": "firefox", 08:18:41 INFO - "script_repo_revision": "production", 08:18:41 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 08:18:41 INFO - "repository": "", 08:18:41 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 08:18:41 INFO - "buildid": "20160628070722", 08:18:41 INFO - "pgo_build": "False", 08:18:41 INFO - "basedir": "/builds/slave/test", 08:18:41 INFO - "buildnumber": 130, 08:18:41 INFO - "slavename": "talos-linux64-ix-021", 08:18:41 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 08:18:41 INFO - "platform": "ubuntu64_hw", 08:18:41 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 08:18:41 INFO - "branch": "mozilla-central", 08:18:41 INFO - "repo_path": "mozilla-central", 08:18:41 INFO - "revision": "cf243ad179e9929358583f4b8e6f8ce709f85eb6", 08:18:41 INFO - "stage_platform": "linux64", 08:18:41 INFO - "builduid": "bf2972368771482bb72fc45e9a7631be", 08:18:41 INFO - "slavebuilddir": "test" 08:18:41 INFO - } 08:18:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/eLYp0e0RTkyeVRt5CPLjQA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 08:18:41 INFO - Running post-action listener: _resource_record_post_action 08:18:41 INFO - ##### 08:18:41 INFO - ##### Finished read-buildbot-config step (success) 08:18:41 INFO - ##### 08:18:41 INFO - ##### 08:18:41 INFO - ##### Running download-and-extract step. 08:18:41 INFO - ##### 08:18:41 INFO - Running pre-action listener: _resource_record_pre_action 08:18:41 INFO - Running main action method: download_and_extract 08:18:41 INFO - mkdir: /builds/slave/test/build/tests 08:18:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:18:41 INFO - https://queue.taskcluster.net/v1/task/eLYp0e0RTkyeVRt5CPLjQA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:18:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLYp0e0RTkyeVRt5CPLjQA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:18:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLYp0e0RTkyeVRt5CPLjQA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:18:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLYp0e0RTkyeVRt5CPLjQA/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 08:18:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLYp0e0RTkyeVRt5CPLjQA/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 08:18:41 INFO - Downloaded 1361 bytes. 08:18:41 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 08:18:41 INFO - Using the following test package requirements: 08:18:41 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 08:18:41 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:18:41 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:18:41 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:18:41 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:18:41 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:18:41 INFO - u'jsshell-linux-x86_64.zip'], 08:18:41 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:18:41 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:18:41 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 08:18:41 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:18:41 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:18:41 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:18:41 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:18:41 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:18:41 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:18:41 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 08:18:41 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:18:41 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:18:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:18:41 INFO - https://queue.taskcluster.net/v1/task/eLYp0e0RTkyeVRt5CPLjQA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:18:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLYp0e0RTkyeVRt5CPLjQA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:18:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLYp0e0RTkyeVRt5CPLjQA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:18:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLYp0e0RTkyeVRt5CPLjQA/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 08:18:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLYp0e0RTkyeVRt5CPLjQA/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 08:18:42 INFO - Downloaded 22713317 bytes. 08:18:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:18:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:18:44 INFO - Return code: 0 08:18:44 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 08:18:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:18:44 INFO - https://queue.taskcluster.net/v1/task/eLYp0e0RTkyeVRt5CPLjQA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:18:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLYp0e0RTkyeVRt5CPLjQA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:18:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLYp0e0RTkyeVRt5CPLjQA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 08:18:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLYp0e0RTkyeVRt5CPLjQA/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 08:18:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLYp0e0RTkyeVRt5CPLjQA/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 08:18:45 INFO - Downloaded 22713317 bytes. 08:18:45 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'] 08:18:45 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 08:18:47 INFO - Return code: 0 08:18:47 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')]} 08:18:47 INFO - https://queue.taskcluster.net/v1/task/eLYp0e0RTkyeVRt5CPLjQA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:18:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLYp0e0RTkyeVRt5CPLjQA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 08:18:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLYp0e0RTkyeVRt5CPLjQA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 08:18:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLYp0e0RTkyeVRt5CPLjQA/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 08:18:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLYp0e0RTkyeVRt5CPLjQA/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 08:18:48 INFO - Downloaded 14405245 bytes. 08:18:48 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'] 08:18:48 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 08:18:48 INFO - Return code: 0 08:18:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:18:48 INFO - https://queue.taskcluster.net/v1/task/eLYp0e0RTkyeVRt5CPLjQA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:18:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLYp0e0RTkyeVRt5CPLjQA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:18:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLYp0e0RTkyeVRt5CPLjQA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:18:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLYp0e0RTkyeVRt5CPLjQA/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 08:18:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eLYp0e0RTkyeVRt5CPLjQA/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 08:18:50 INFO - Downloaded 56383639 bytes. 08:18:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eLYp0e0RTkyeVRt5CPLjQA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:18:50 INFO - mkdir: /builds/slave/test/properties 08:18:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:18:50 INFO - Writing to file /builds/slave/test/properties/build_url 08:18:50 INFO - Contents: 08:18:50 INFO - build_url:https://queue.taskcluster.net/v1/task/eLYp0e0RTkyeVRt5CPLjQA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 08:18:50 INFO - Running post-action listener: _resource_record_post_action 08:18:50 INFO - Running post-action listener: set_extra_try_arguments 08:18:50 INFO - ##### 08:18:50 INFO - ##### Finished download-and-extract step (success) 08:18:50 INFO - ##### 08:18:50 INFO - ##### 08:18:50 INFO - ##### Running populate-webroot step. 08:18:50 INFO - ##### 08:18:50 INFO - Running pre-action listener: _resource_record_pre_action 08:18:50 INFO - Running main action method: populate_webroot 08:18:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:18:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:18:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:18:50 INFO - '--tppagecycles', 08:18:50 INFO - '7'], 08:18:50 INFO - 'tests': ['tsvgm']}}, 08:18:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:18:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:18:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:18:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:18:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:18:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:18:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:18:50 INFO - 'talos_options': ['--disable-e10s'], 08:18:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:18:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:18:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:18:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:18:50 INFO - 'talos_options': ['--disable-e10s'], 08:18:50 INFO - 'tests': ['damp', 'tps']}, 08:18:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:18:50 INFO - 'tests': ['damp', 'tps']}, 08:18:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:18:50 INFO - 'tests': ['dromaeo_dom']}, 08:18:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:18:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:18:50 INFO - 'tests': ['basic_compositor_video']}, 08:18:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:18:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:18:50 INFO - 'tests': ['a11yr', 08:18:50 INFO - 'ts_paint', 08:18:50 INFO - 'tpaint', 08:18:50 INFO - 'sessionrestore', 08:18:50 INFO - 'sessionrestore_no_auto_restore', 08:18:50 INFO - 'tabpaint']}, 08:18:50 INFO - 'other-e10s': {'tests': ['a11yr', 08:18:50 INFO - 'ts_paint', 08:18:50 INFO - 'tpaint', 08:18:50 INFO - 'sessionrestore', 08:18:50 INFO - 'sessionrestore_no_auto_restore', 08:18:50 INFO - 'tabpaint']}, 08:18:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:18:50 INFO - 'ts_paint', 08:18:50 INFO - 'tpaint', 08:18:50 INFO - 'sessionrestore', 08:18:50 INFO - 'sessionrestore_no_auto_restore', 08:18:50 INFO - 'tabpaint']}, 08:18:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:18:50 INFO - 'ts_paint', 08:18:50 INFO - 'tpaint', 08:18:50 INFO - 'sessionrestore', 08:18:50 INFO - 'sessionrestore_no_auto_restore', 08:18:50 INFO - 'tabpaint']}, 08:18:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:18:50 INFO - 'tests': ['a11yr', 08:18:50 INFO - 'ts_paint', 08:18:50 INFO - 'tpaint', 08:18:50 INFO - 'sessionrestore', 08:18:50 INFO - 'sessionrestore_no_auto_restore', 08:18:50 INFO - 'tabpaint']}, 08:18:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:18:50 INFO - 'tests': ['a11yr', 08:18:50 INFO - 'ts_paint', 08:18:50 INFO - 'tpaint', 08:18:50 INFO - 'sessionrestore', 08:18:50 INFO - 'sessionrestore_no_auto_restore', 08:18:50 INFO - 'tabpaint']}, 08:18:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:18:50 INFO - 'tests': ['tsvgx', 08:18:50 INFO - 'tsvgr_opacity', 08:18:50 INFO - 'tart', 08:18:50 INFO - 'tscrollx', 08:18:50 INFO - 'cart']}, 08:18:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:18:50 INFO - 'tsvgr_opacity', 08:18:50 INFO - 'tart', 08:18:50 INFO - 'tscrollx', 08:18:50 INFO - 'cart']}, 08:18:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:18:50 INFO - 'talos_options': ['--disable-e10s'], 08:18:50 INFO - 'tests': ['tp5o']}, 08:18:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:18:50 INFO - 'tests': ['tp5o']}, 08:18:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:18:50 INFO - 'talos_options': ['--disable-e10s', 08:18:50 INFO - '--xperf_path', 08:18:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:18:50 INFO - 'tests': ['tp5n']}, 08:18:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:18:50 INFO - 'talos_options': ['--xperf_path', 08:18:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:18:50 INFO - 'tests': ['tp5n']}}, 08:18:50 INFO - 'talos.zip': {'path': '', 08:18:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:18:50 INFO - Running post-action listener: _resource_record_post_action 08:18:50 INFO - ##### 08:18:50 INFO - ##### Finished populate-webroot step (success) 08:18:50 INFO - ##### 08:18:50 INFO - ##### 08:18:50 INFO - ##### Running create-virtualenv step. 08:18:50 INFO - ##### 08:18:50 INFO - Running pre-action listener: _resource_record_pre_action 08:18:50 INFO - Running main action method: create_virtualenv 08:18:50 INFO - Creating virtualenv /builds/slave/test/build/venv 08:18:50 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 08:18:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:18:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:18:51 INFO - Using real prefix '/usr' 08:18:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:18:51 INFO - Installing distribute.............................................................................................................................................................................................done. 08:18:53 INFO - Installing pip.................done. 08:18:53 INFO - Return code: 0 08:18:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:18:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:18:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:18:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7388a65f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1adf7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6e520>, '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 0x1b6f420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f8b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467127119.136077-232950574', '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 08:18:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:18:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:18:53 INFO - Using env: {'DISPLAY': ':0', 08:18:53 INFO - 'HOME': '/home/cltbld', 08:18:53 INFO - 'LANG': 'en_US.UTF-8', 08:18:53 INFO - 'LANGUAGE': 'en_US:en', 08:18:53 INFO - 'LOGNAME': 'cltbld', 08:18:53 INFO - 'MAIL': '/var/mail/cltbld', 08:18:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:18:53 INFO - 'MOZ_NO_REMOTE': '1', 08:18:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:18:53 INFO - 'NO_EM_RESTART': '1', 08:18:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:18:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:18:53 INFO - 'PWD': '/builds/slave/test', 08:18:53 INFO - 'SHELL': '/bin/bash', 08:18:53 INFO - 'SHLVL': '1', 08:18:53 INFO - 'TERM': 'linux', 08:18:53 INFO - 'TMOUT': '86400', 08:18:53 INFO - 'USER': 'cltbld', 08:18:53 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467127119.136077-232950574', 08:18:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:18:53 INFO - '_': '/tools/buildbot/bin/python'} 08:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:18:53 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 08:18:53 INFO - Cleaning up... 08:18:53 INFO - Return code: 0 08:18:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:18:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:18:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:18:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f7388a65f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1adf7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6e520>, '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 0x1b6f420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f8b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467127119.136077-232950574', '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 08:18:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:18:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:18:53 INFO - Using env: {'DISPLAY': ':0', 08:18:53 INFO - 'HOME': '/home/cltbld', 08:18:53 INFO - 'LANG': 'en_US.UTF-8', 08:18:53 INFO - 'LANGUAGE': 'en_US:en', 08:18:53 INFO - 'LOGNAME': 'cltbld', 08:18:53 INFO - 'MAIL': '/var/mail/cltbld', 08:18:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:18:53 INFO - 'MOZ_NO_REMOTE': '1', 08:18:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:18:53 INFO - 'NO_EM_RESTART': '1', 08:18:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:18:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:18:53 INFO - 'PWD': '/builds/slave/test', 08:18:53 INFO - 'SHELL': '/bin/bash', 08:18:53 INFO - 'SHLVL': '1', 08:18:53 INFO - 'TERM': 'linux', 08:18:53 INFO - 'TMOUT': '86400', 08:18:53 INFO - 'USER': 'cltbld', 08:18:53 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467127119.136077-232950574', 08:18:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:18:53 INFO - '_': '/tools/buildbot/bin/python'} 08:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:18:53 INFO - Downloading/unpacking psutil>=3.1.1 08:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:18:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:18:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:18:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:18:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:18:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:18:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:18:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:18:55 INFO - Installing collected packages: psutil 08:18:55 INFO - Running setup.py install for psutil 08:18:56 INFO - building 'psutil._psutil_linux' extension 08:18:56 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 08:18:56 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 08:18:56 INFO - building 'psutil._psutil_posix' extension 08:18:56 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 08:18:56 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 08:18:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:18:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:18:56 INFO - Successfully installed psutil 08:18:56 INFO - Cleaning up... 08:18:56 INFO - Return code: 0 08:18:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:18:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:18:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:56 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 0x7f7388a65f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1adf7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6e520>, '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 0x1b6f420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f8b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467127119.136077-232950574', '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 08:18:56 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 08:18:56 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 08:18:56 INFO - Using env: {'DISPLAY': ':0', 08:18:56 INFO - 'HOME': '/home/cltbld', 08:18:56 INFO - 'LANG': 'en_US.UTF-8', 08:18:56 INFO - 'LANGUAGE': 'en_US:en', 08:18:56 INFO - 'LOGNAME': 'cltbld', 08:18:56 INFO - 'MAIL': '/var/mail/cltbld', 08:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:18:56 INFO - 'MOZ_NO_REMOTE': '1', 08:18:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:18:56 INFO - 'NO_EM_RESTART': '1', 08:18:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:18:56 INFO - 'PWD': '/builds/slave/test', 08:18:56 INFO - 'SHELL': '/bin/bash', 08:18:56 INFO - 'SHLVL': '1', 08:18:56 INFO - 'TERM': 'linux', 08:18:56 INFO - 'TMOUT': '86400', 08:18:56 INFO - 'USER': 'cltbld', 08:18:56 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467127119.136077-232950574', 08:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:18:56 INFO - '_': '/tools/buildbot/bin/python'} 08:18:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:18:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:18:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:18:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:18:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:18:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:18:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:18:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:18:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:18:58 INFO - Installing collected packages: mozsystemmonitor 08:18:58 INFO - Running setup.py install for mozsystemmonitor 08:18:58 INFO - Successfully installed mozsystemmonitor 08:18:58 INFO - Cleaning up... 08:18:58 INFO - Return code: 0 08:18:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:18:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:18:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:18:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:18:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f7388a65f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1adf7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6e520>, '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 0x1b6f420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f8b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467127119.136077-232950574', '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 08:18:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:18:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:18:58 INFO - Using env: {'DISPLAY': ':0', 08:18:58 INFO - 'HOME': '/home/cltbld', 08:18:58 INFO - 'LANG': 'en_US.UTF-8', 08:18:58 INFO - 'LANGUAGE': 'en_US:en', 08:18:58 INFO - 'LOGNAME': 'cltbld', 08:18:58 INFO - 'MAIL': '/var/mail/cltbld', 08:18:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:18:58 INFO - 'MOZ_NO_REMOTE': '1', 08:18:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:18:58 INFO - 'NO_EM_RESTART': '1', 08:18:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:18:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:18:58 INFO - 'PWD': '/builds/slave/test', 08:18:58 INFO - 'SHELL': '/bin/bash', 08:18:58 INFO - 'SHLVL': '1', 08:18:58 INFO - 'TERM': 'linux', 08:18:58 INFO - 'TMOUT': '86400', 08:18:58 INFO - 'USER': 'cltbld', 08:18:58 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467127119.136077-232950574', 08:18:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:18:58 INFO - '_': '/tools/buildbot/bin/python'} 08:18:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:18:58 INFO - Downloading/unpacking jsonschema==2.5.1 08:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:18:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:18:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:18:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:18:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:19:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:19:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:19:00 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 08:19:00 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 08:19:00 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 08:19:00 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 08:19:00 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 08:19:00 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 08:19:01 INFO - Downloading functools32-3.2.3-2.tar.gz 08:19:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:19:01 INFO - warning: no files found matching '*.txt' 08:19:01 INFO - no previously-included directories found matching 'build' 08:19:01 INFO - no previously-included directories found matching 'dist' 08:19:01 INFO - no previously-included directories found matching '.git*' 08:19:01 INFO - Installing collected packages: jsonschema, functools32 08:19:01 INFO - Running setup.py install for functools32 08:19:01 INFO - warning: no files found matching '*.txt' 08:19:01 INFO - no previously-included directories found matching 'build' 08:19:01 INFO - no previously-included directories found matching 'dist' 08:19:01 INFO - no previously-included directories found matching '.git*' 08:19:01 INFO - Successfully installed jsonschema functools32 08:19:01 INFO - Cleaning up... 08:19:01 INFO - Return code: 0 08:19:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:19:01 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')]} 08:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:19:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:19:01 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')]} 08:19:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:19:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:19:01 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 0x7f7388a65f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1adf7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6e520>, '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 0x1b6f420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f8b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467127119.136077-232950574', '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 08:19:01 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 08:19:01 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 08:19:01 INFO - Using env: {'DISPLAY': ':0', 08:19:01 INFO - 'HOME': '/home/cltbld', 08:19:01 INFO - 'LANG': 'en_US.UTF-8', 08:19:01 INFO - 'LANGUAGE': 'en_US:en', 08:19:01 INFO - 'LOGNAME': 'cltbld', 08:19:01 INFO - 'MAIL': '/var/mail/cltbld', 08:19:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:19:01 INFO - 'MOZ_NO_REMOTE': '1', 08:19:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:19:01 INFO - 'NO_EM_RESTART': '1', 08:19:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:19:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:19:01 INFO - 'PWD': '/builds/slave/test', 08:19:01 INFO - 'SHELL': '/bin/bash', 08:19:01 INFO - 'SHLVL': '1', 08:19:01 INFO - 'TERM': 'linux', 08:19:01 INFO - 'TMOUT': '86400', 08:19:01 INFO - 'USER': 'cltbld', 08:19:01 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467127119.136077-232950574', 08:19:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:19:01 INFO - '_': '/tools/buildbot/bin/python'} 08:19:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:19:01 INFO - Downloading/unpacking blobuploader==1.2.4 08:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:19:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:19:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:19:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:19:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:19:03 INFO - Downloading blobuploader-1.2.4.tar.gz 08:19:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:19:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:19:03 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 08:19:03 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 08:19:03 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 08:19:03 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 08:19:03 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 08:19:03 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 08:19:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:19:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:19:03 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 08:19:03 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 08:19:03 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 08:19:03 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 08:19:03 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 08:19:03 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 08:19:04 INFO - Downloading docopt-0.6.1.tar.gz 08:19:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:19:04 INFO - Installing collected packages: blobuploader, requests, docopt 08:19:04 INFO - Running setup.py install for blobuploader 08:19:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:19:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:19:04 INFO - Running setup.py install for requests 08:19:04 INFO - Running setup.py install for docopt 08:19:04 INFO - Successfully installed blobuploader requests docopt 08:19:04 INFO - Cleaning up... 08:19:04 INFO - Return code: 0 08:19:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:19:04 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')]} 08:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:19:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:19:04 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')]} 08:19:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:19:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:19:04 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 0x7f7388a65f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1adf7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6e520>, '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 0x1b6f420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f8b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467127119.136077-232950574', '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 08:19:04 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 08:19:04 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 08:19:04 INFO - Using env: {'DISPLAY': ':0', 08:19:04 INFO - 'HOME': '/home/cltbld', 08:19:04 INFO - 'LANG': 'en_US.UTF-8', 08:19:04 INFO - 'LANGUAGE': 'en_US:en', 08:19:04 INFO - 'LOGNAME': 'cltbld', 08:19:04 INFO - 'MAIL': '/var/mail/cltbld', 08:19:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:19:04 INFO - 'MOZ_NO_REMOTE': '1', 08:19:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:19:04 INFO - 'NO_EM_RESTART': '1', 08:19:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:19:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:19:04 INFO - 'PWD': '/builds/slave/test', 08:19:04 INFO - 'SHELL': '/bin/bash', 08:19:04 INFO - 'SHLVL': '1', 08:19:04 INFO - 'TERM': 'linux', 08:19:04 INFO - 'TMOUT': '86400', 08:19:04 INFO - 'USER': 'cltbld', 08:19:04 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467127119.136077-232950574', 08:19:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:19:04 INFO - '_': '/tools/buildbot/bin/python'} 08:19:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:19:05 INFO - Running setup.py (path:/tmp/pip-V5ewio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:19:05 INFO - Running setup.py (path:/tmp/pip-Z5dnor-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:19:05 INFO - Running setup.py (path:/tmp/pip-UW2nU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:19:05 INFO - Running setup.py (path:/tmp/pip-EhMV86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:19:05 INFO - Running setup.py (path:/tmp/pip-5VgCGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:19:05 INFO - Running setup.py (path:/tmp/pip-AGgAR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:19:05 INFO - Running setup.py (path:/tmp/pip-fosOgL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:19:05 INFO - Running setup.py (path:/tmp/pip-ezWhE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:19:05 INFO - Running setup.py (path:/tmp/pip-QyOMDn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:19:05 INFO - Running setup.py (path:/tmp/pip-EjxoCK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:19:05 INFO - Running setup.py (path:/tmp/pip-Cy0naH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:19:05 INFO - Running setup.py (path:/tmp/pip-hZQsIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:19:05 INFO - Running setup.py (path:/tmp/pip-WQxyN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:19:05 INFO - Running setup.py (path:/tmp/pip-NGO6In-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:19:06 INFO - Running setup.py (path:/tmp/pip-w5LhWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:19:06 INFO - Running setup.py (path:/tmp/pip-CTVsLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:19:06 INFO - Running setup.py (path:/tmp/pip-m_Uqvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:19:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:19:06 INFO - Running setup.py install for manifestparser 08:19:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:19:06 INFO - Running setup.py install for mozcrash 08:19:06 INFO - Running setup.py install for mozdebug 08:19:06 INFO - Running setup.py install for mozdevice 08:19:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:19:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:19:06 INFO - Running setup.py install for mozfile 08:19:06 INFO - Running setup.py install for mozhttpd 08:19:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:19:06 INFO - Running setup.py install for mozinfo 08:19:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:19:06 INFO - Running setup.py install for mozInstall 08:19:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:19:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:19:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:19:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:19:07 INFO - Running setup.py install for mozleak 08:19:07 INFO - Running setup.py install for mozlog 08:19:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:19:07 INFO - Running setup.py install for moznetwork 08:19:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:19:07 INFO - Running setup.py install for mozprocess 08:19:07 INFO - Running setup.py install for mozprofile 08:19:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:19:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:19:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:19:07 INFO - Running setup.py install for mozrunner 08:19:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:19:07 INFO - Running setup.py install for mozscreenshot 08:19:07 INFO - Running setup.py install for moztest 08:19:07 INFO - Running setup.py install for mozversion 08:19:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:19:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:19:08 INFO - Cleaning up... 08:19:08 INFO - Return code: 0 08:19:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:19:08 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')]} 08:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:19:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:19:08 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')]} 08:19:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:19:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:19:08 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 0x7f7388a65f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1adf7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6e520>, '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 0x1b6f420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f8b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467127119.136077-232950574', '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 08:19:08 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 08:19:08 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 08:19:08 INFO - Using env: {'DISPLAY': ':0', 08:19:08 INFO - 'HOME': '/home/cltbld', 08:19:08 INFO - 'LANG': 'en_US.UTF-8', 08:19:08 INFO - 'LANGUAGE': 'en_US:en', 08:19:08 INFO - 'LOGNAME': 'cltbld', 08:19:08 INFO - 'MAIL': '/var/mail/cltbld', 08:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:19:08 INFO - 'MOZ_NO_REMOTE': '1', 08:19:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:19:08 INFO - 'NO_EM_RESTART': '1', 08:19:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:19:08 INFO - 'PWD': '/builds/slave/test', 08:19:08 INFO - 'SHELL': '/bin/bash', 08:19:08 INFO - 'SHLVL': '1', 08:19:08 INFO - 'TERM': 'linux', 08:19:08 INFO - 'TMOUT': '86400', 08:19:08 INFO - 'USER': 'cltbld', 08:19:08 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467127119.136077-232950574', 08:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:19:08 INFO - '_': '/tools/buildbot/bin/python'} 08:19:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:19:08 INFO - Running setup.py (path:/tmp/pip-iUs7RA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:19:08 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)) 08:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:19:08 INFO - Running setup.py (path:/tmp/pip-zARQCq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:19:08 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)) 08:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:19:08 INFO - Running setup.py (path:/tmp/pip-pNG8Dp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:19:08 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)) 08:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:19:08 INFO - Running setup.py (path:/tmp/pip-MKjHlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:19:08 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)) 08:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:19:08 INFO - Running setup.py (path:/tmp/pip-vF2iwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:19:08 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)) 08:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:19:08 INFO - Running setup.py (path:/tmp/pip-nL3c8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:19:08 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)) 08:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:19:08 INFO - Running setup.py (path:/tmp/pip-03UiVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:19:08 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)) 08:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:19:08 INFO - Running setup.py (path:/tmp/pip-7t0HMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:19:08 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)) 08:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:19:08 INFO - Running setup.py (path:/tmp/pip-r_ksX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:19:08 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)) 08:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:19:08 INFO - Running setup.py (path:/tmp/pip-A8lecu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:19:08 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)) 08:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:19:08 INFO - Running setup.py (path:/tmp/pip-4EIJpa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:19:09 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)) 08:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:19:09 INFO - Running setup.py (path:/tmp/pip-bqwYtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:19:09 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)) 08:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:19:09 INFO - Running setup.py (path:/tmp/pip-R8Nnfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:19:09 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)) 08:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:19:09 INFO - Running setup.py (path:/tmp/pip-wB0P80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:19:09 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)) 08:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:19:09 INFO - Running setup.py (path:/tmp/pip-35MC2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:19:09 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)) 08:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:19:09 INFO - Running setup.py (path:/tmp/pip-Me75R5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:19:09 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)) 08:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:19:09 INFO - Running setup.py (path:/tmp/pip-aNhInF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:19:09 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)) 08:19:09 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)) 08:19:09 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)) 08:19:09 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)) 08:19:09 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)) 08:19:09 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)) 08:19:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:19:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:19:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:19:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:19:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:19:11 INFO - Downloading blessings-1.6.tar.gz 08:19:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:19:11 INFO - Installing collected packages: blessings 08:19:11 INFO - Running setup.py install for blessings 08:19:11 INFO - Successfully installed blessings 08:19:11 INFO - Cleaning up... 08:19:11 INFO - Return code: 0 08:19:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:19:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:19:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:19:11 INFO - Reading from file tmpfile_stdout 08:19:11 INFO - Current package versions: 08:19:11 INFO - argparse == 1.2.1 08:19:11 INFO - blessings == 1.6 08:19:11 INFO - blobuploader == 1.2.4 08:19:11 INFO - docopt == 0.6.1 08:19:11 INFO - functools32 == 3.2.3-2 08:19:11 INFO - jsonschema == 2.5.1 08:19:11 INFO - manifestparser == 1.1 08:19:11 INFO - mozInstall == 1.12 08:19:11 INFO - mozcrash == 0.17 08:19:11 INFO - mozdebug == 0.1 08:19:11 INFO - mozdevice == 0.48 08:19:11 INFO - mozfile == 1.2 08:19:11 INFO - mozhttpd == 0.7 08:19:11 INFO - mozinfo == 0.9 08:19:11 INFO - mozleak == 0.1 08:19:11 INFO - mozlog == 3.2 08:19:11 INFO - moznetwork == 0.27 08:19:11 INFO - mozprocess == 0.22 08:19:11 INFO - mozprofile == 0.28 08:19:11 INFO - mozrunner == 6.11 08:19:11 INFO - mozscreenshot == 0.1 08:19:11 INFO - mozsystemmonitor == 0.3 08:19:11 INFO - moztest == 0.7 08:19:11 INFO - mozversion == 1.4 08:19:11 INFO - psutil == 3.1.1 08:19:11 INFO - requests == 1.2.3 08:19:11 INFO - wsgiref == 0.1.2 08:19:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:19:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:19:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:19:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:19:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:19:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:19:11 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 0x7f7388a65f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1adf7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6e520>, '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 0x1b6f420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f8b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467127119.136077-232950574', '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 08:19:11 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 08:19:11 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 08:19:11 INFO - Using env: {'DISPLAY': ':0', 08:19:11 INFO - 'HOME': '/home/cltbld', 08:19:11 INFO - 'LANG': 'en_US.UTF-8', 08:19:11 INFO - 'LANGUAGE': 'en_US:en', 08:19:11 INFO - 'LOGNAME': 'cltbld', 08:19:11 INFO - 'MAIL': '/var/mail/cltbld', 08:19:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:19:11 INFO - 'MOZ_NO_REMOTE': '1', 08:19:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:19:11 INFO - 'NO_EM_RESTART': '1', 08:19:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:19:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:19:11 INFO - 'PWD': '/builds/slave/test', 08:19:11 INFO - 'SHELL': '/bin/bash', 08:19:11 INFO - 'SHLVL': '1', 08:19:11 INFO - 'TERM': 'linux', 08:19:11 INFO - 'TMOUT': '86400', 08:19:11 INFO - 'USER': 'cltbld', 08:19:11 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467127119.136077-232950574', 08:19:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:19:11 INFO - '_': '/tools/buildbot/bin/python'} 08:19:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:19:12 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)) 08:19:12 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)) 08:19:12 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)) 08:19:12 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)) 08:19:12 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)) 08:19:12 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)) 08:19:12 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)) 08:19:12 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)) 08:19:12 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)) 08:19:12 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)) 08:19:12 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)) 08:19:12 INFO - Cleaning up... 08:19:12 INFO - Return code: 0 08:19:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:19:12 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')]} 08:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:19:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:19:12 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')]} 08:19:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:19:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:19:12 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 0x7f7388a65f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1adf7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6e520>, '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 0x1b6f420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f8b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467127119.136077-232950574', '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 08:19:12 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 08:19:12 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 08:19:12 INFO - Using env: {'DISPLAY': ':0', 08:19:12 INFO - 'HOME': '/home/cltbld', 08:19:12 INFO - 'LANG': 'en_US.UTF-8', 08:19:12 INFO - 'LANGUAGE': 'en_US:en', 08:19:12 INFO - 'LOGNAME': 'cltbld', 08:19:12 INFO - 'MAIL': '/var/mail/cltbld', 08:19:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:19:12 INFO - 'MOZ_NO_REMOTE': '1', 08:19:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:19:12 INFO - 'NO_EM_RESTART': '1', 08:19:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:19:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:19:12 INFO - 'PWD': '/builds/slave/test', 08:19:12 INFO - 'SHELL': '/bin/bash', 08:19:12 INFO - 'SHLVL': '1', 08:19:12 INFO - 'TERM': 'linux', 08:19:12 INFO - 'TMOUT': '86400', 08:19:12 INFO - 'USER': 'cltbld', 08:19:12 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467127119.136077-232950574', 08:19:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:19:12 INFO - '_': '/tools/buildbot/bin/python'} 08:19:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:19:12 INFO - Cleaning up... 08:19:12 INFO - Return code: 0 08:19:12 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:19:12 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')]} 08:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:19:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:19:12 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')]} 08:19:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:19:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:19:12 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 0x7f7388a65f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a158a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1adf7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b6e520>, '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 0x1b6f420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f8b0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467127119.136077-232950574', '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 08:19:12 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 08:19:12 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 08:19:12 INFO - Using env: {'DISPLAY': ':0', 08:19:12 INFO - 'HOME': '/home/cltbld', 08:19:12 INFO - 'LANG': 'en_US.UTF-8', 08:19:12 INFO - 'LANGUAGE': 'en_US:en', 08:19:12 INFO - 'LOGNAME': 'cltbld', 08:19:12 INFO - 'MAIL': '/var/mail/cltbld', 08:19:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:19:12 INFO - 'MOZ_NO_REMOTE': '1', 08:19:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:19:12 INFO - 'NO_EM_RESTART': '1', 08:19:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:19:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:19:12 INFO - 'PWD': '/builds/slave/test', 08:19:12 INFO - 'SHELL': '/bin/bash', 08:19:12 INFO - 'SHLVL': '1', 08:19:12 INFO - 'TERM': 'linux', 08:19:12 INFO - 'TMOUT': '86400', 08:19:12 INFO - 'USER': 'cltbld', 08:19:12 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467127119.136077-232950574', 08:19:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:19:12 INFO - '_': '/tools/buildbot/bin/python'} 08:19:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:19:12 INFO - Downloading/unpacking flake8 08:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:19:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:19:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:19:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:19:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:19:14 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:19:14 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:19:14 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 08:19:14 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 08:19:14 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 08:19:14 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 08:19:14 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 08:19:14 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 08:19:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:19:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:19:14 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 08:19:14 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 08:19:14 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 08:19:14 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 08:19:14 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 08:19:14 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 08:19:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:19:14 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 08:19:14 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 08:19:14 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 08:19:14 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 08:19:14 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 08:19:14 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 08:19:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:19:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:19:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:19:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:19:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:19:15 INFO - no previously-included directories found matching 'docs/_build' 08:19:15 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:19:15 INFO - Running setup.py install for pep8 08:19:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:19:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:19:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:19:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:19:15 INFO - no previously-included directories found matching 'docs/_build' 08:19:15 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:19:15 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:19:15 INFO - Cleaning up... 08:19:15 INFO - Return code: 0 08:19:15 INFO - Running post-action listener: _resource_record_post_action 08:19:15 INFO - Running post-action listener: _start_resource_monitoring 08:19:15 INFO - Starting resource monitoring. 08:19:15 INFO - ##### 08:19:15 INFO - ##### Finished create-virtualenv step (success) 08:19:15 INFO - ##### 08:19:15 INFO - ##### 08:19:15 INFO - ##### Running install step. 08:19:15 INFO - ##### 08:19:15 INFO - Running pre-action listener: _resource_record_pre_action 08:19:15 INFO - Running main action method: install 08:19:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:19:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:19:15 INFO - Reading from file tmpfile_stdout 08:19:15 INFO - Detecting whether we're running mozinstall >=1.0... 08:19:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:19:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:19:15 INFO - Reading from file tmpfile_stdout 08:19:15 INFO - Output received: 08:19:15 INFO - Usage: mozinstall [options] installer 08:19:15 INFO - Options: 08:19:15 INFO - -h, --help show this help message and exit 08:19:15 INFO - -d DEST, --destination=DEST 08:19:15 INFO - Directory to install application into. [default: 08:19:15 INFO - "/builds/slave/test"] 08:19:15 INFO - --app=APP Application being installed. [default: firefox] 08:19:15 INFO - mkdir: /builds/slave/test/build/application 08:19:15 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'] 08:19:15 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 08:19:32 INFO - Reading from file tmpfile_stdout 08:19:32 INFO - Output received: 08:19:32 INFO - /builds/slave/test/build/application/firefox/firefox 08:19:32 INFO - Running post-action listener: _resource_record_post_action 08:19:32 INFO - ##### 08:19:32 INFO - ##### Finished install step (success) 08:19:32 INFO - ##### 08:19:32 INFO - ##### 08:19:32 INFO - ##### Running run-tests step. 08:19:32 INFO - ##### 08:19:32 INFO - Running pre-action listener: _resource_record_pre_action 08:19:32 INFO - Running main action method: run_tests 08:19:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:19:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:19:32 INFO - Python 2.7.3 08:19:32 INFO - Return code: 0 08:19:32 INFO - grabbing minidump binary from tooltool 08:19:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:19:32 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 0x1b6e520>, '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 0x1b6f420>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b6f8b0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:19:32 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 08:19:32 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 08:19:32 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 08:19:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:19:32 INFO - Return code: 0 08:19:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:19:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:19:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:19:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:19:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:19:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:19:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:19:33 INFO - Return code: 0 08:19:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eLYp0e0RTkyeVRt5CPLjQA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:19:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eLYp0e0RTkyeVRt5CPLjQA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-021 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:19:33 INFO - Using env: {'DISPLAY': ':0', 08:19:33 INFO - 'HOME': '/home/cltbld', 08:19:33 INFO - 'LANG': 'en_US.UTF-8', 08:19:33 INFO - 'LANGUAGE': 'en_US:en', 08:19:33 INFO - 'LOGNAME': 'cltbld', 08:19:33 INFO - 'MAIL': '/var/mail/cltbld', 08:19:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:19:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:19:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:19:33 INFO - 'MOZ_NO_REMOTE': '1', 08:19:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:19:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:19:33 INFO - 'NO_EM_RESTART': '1', 08:19:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:19:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:19:33 INFO - 'PWD': '/builds/slave/test', 08:19:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:19:33 INFO - 'SHELL': '/bin/bash', 08:19:33 INFO - 'SHLVL': '1', 08:19:33 INFO - 'TERM': 'linux', 08:19:33 INFO - 'TMOUT': '86400', 08:19:33 INFO - 'USER': 'cltbld', 08:19:33 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467127119.136077-232950574', 08:19:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:19:33 INFO - '_': '/tools/buildbot/bin/python'} 08:19:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eLYp0e0RTkyeVRt5CPLjQA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:19:33 INFO - mozversion application_buildid: 20160628070722 08:19:33 INFO - mozversion application_changeset: cf243ad179e9929358583f4b8e6f8ce709f85eb6 08:19:33 INFO - mozversion application_display_name: Nightly 08:19:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:19:33 INFO - mozversion application_name: Firefox 08:19:33 INFO - mozversion application_remotingname: firefox 08:19:33 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:19:33 INFO - mozversion application_vendor: Mozilla 08:19:33 INFO - mozversion application_version: 50.0a1 08:19:33 INFO - mozversion platform_buildid: 20160628070722 08:19:33 INFO - mozversion platform_changeset: cf243ad179e9929358583f4b8e6f8ce709f85eb6 08:19:33 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:19:33 INFO - mozversion platform_version: 50.0a1 08:19:33 INFO - using testdate: 1467127173 08:19:33 INFO - actual date: 1467127173 08:19:33 INFO - starting webserver on 'localhost:42891' 08:19:33 INFO - SUITE-START | Running 2 tests 08:19:33 INFO - TEST-START | dromaeo_css 08:19:33 INFO - operating with platform_type : linux_ 08:19:33 INFO - Initialising browser for dromaeo_css test... 08:19:33 INFO - TEST-INFO | started process 23018 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOql2JY/profile http://localhost:42891/getInfo.html) 08:19:39 INFO - PROCESS | 23018 | __metrics Screen width/height:1600/1200 08:19:39 INFO - PROCESS | 23018 | colorDepth:24 08:19:39 INFO - PROCESS | 23018 | Browser inner width/height: 1024/697 08:19:39 INFO - PROCESS | 23018 | __metrics 08:19:40 INFO - PROCESS | 23018 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:19:41 INFO - TEST-INFO | 23018: exit 0 08:19:41 INFO - Browser initialized. 08:19:41 INFO - Running cycle 1/1 for dromaeo_css test... 08:19:41 INFO - TEST-INFO | started process 23100 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOql2JY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:19:53 INFO - PROCESS | 23100 | RSS: Main: 175628288 08:19:53 INFO - PROCESS | 23100 | 08:20:44 INFO - PROCESS | 23100 | 1467127244734 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] 08:22:58 INFO - PROCESS | 23100 | Cycle 1(1): loaded http://localhost:42891/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42891/tests/dromaeo/cssquery-ext.html) 08:22:59 INFO - PROCESS | 23100 | RSS: Main: 264089600 08:22:59 INFO - PROCESS | 23100 | 08:26:01 INFO - PROCESS | 23100 | Cycle 1(1): loaded http://localhost:42891/tests/dromaeo/cssquery-ext.html (next: http://localhost:42891/tests/dromaeo/cssquery-jquery.html) 08:26:02 INFO - PROCESS | 23100 | RSS: Main: 258785280 08:26:02 INFO - PROCESS | 23100 | 08:29:04 INFO - PROCESS | 23100 | Cycle 1(1): loaded http://localhost:42891/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42891/tests/dromaeo/cssquery-mootools.html) 08:29:05 INFO - PROCESS | 23100 | RSS: Main: 256405504 08:29:05 INFO - PROCESS | 23100 | 08:32:07 INFO - PROCESS | 23100 | Cycle 1(1): loaded http://localhost:42891/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42891/tests/dromaeo/cssquery-prototype.html) 08:32:08 INFO - PROCESS | 23100 | RSS: Main: 263024640 08:32:08 INFO - PROCESS | 23100 | 08:35:11 INFO - PROCESS | 23100 | Cycle 1(1): loaded http://localhost:42891/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42891/tests/dromaeo/cssquery-yui.html) 08:35:11 INFO - PROCESS | 23100 | RSS: Main: 231546880 08:35:11 INFO - PROCESS | 23100 | 08:38:14 INFO - PROCESS | 23100 | Cycle 1(1): loaded http://localhost:42891/tests/dromaeo/cssquery-yui.html (next: http://localhost:42891/tests/dromaeo/cssquery-dojo.html) 08:38:14 INFO - PROCESS | 23100 | RSS: Main: 345083904 08:38:14 INFO - PROCESS | 23100 | 08:38:14 INFO - PROCESS | 23100 | __start_tp_report 08:38:14 INFO - PROCESS | 23100 | _x_x_mozilla_page_load 08:38:14 INFO - PROCESS | 23100 | _x_x_mozilla_page_load_details 08:38:14 INFO - PROCESS | 23100 | |i|pagename|runs| 08:38:14 INFO - PROCESS | 23100 | |0;dojo.html;2641.6829980402354;2645.2461048601153;2686.597010448433;2691.125384250118;2703.6890757562874;4722.220833562466;4813;4835.105505481484;4908.239222920449;4931.3835770528685;4887.535684110013;4920.360353154085;4921.483244259355;4978.551930326279;4980.850574482763;4422.778861056942;4494.68537202396;4498.595126438619;4563.247064234414;4640.350350950869;4554.180247555422;4563.338315944186;4586.220342541776;4607;4627.62978961684;4409.625181859514;4412.867613971586;4456.509783923766;4467.5755803198645;4501.482329532113;4485.753283569403;4497;4503.639708823286;4548.363229147922;4598.2642777155825;4482.417285752831;4506.752128632924;4508.188526065307;4517.412736344287;4568.109218702354;4733.763311834395;4787.234042553175;4894.7063176209185;4959.082569724613;4983.800647974077;5253.106971814763;5258.605604579672;5261.894762104736;5302.045631786277;5314.096603577401;5267.157254839246;5315.202719592054;5355.812546560891;5359.490848369399;5374.059539580558;4810.230363141951;4845.466998630148;4881.4630390657;4900.044491324157;4967.627427942919;5409.7295135243085;5454.1546060359915;5476.452354764571;5476.7535460904355;5477.287952566141;5136.332276803992;5172.146595811724;5189.870253243699;5195.2986346843445;5199.688018718889;5111.207762796773;5207.791688332424;5247.2129180623215;5289.418164001957;5297.708626025528;5227.45111763267;5229.47705229474;5234.528892399702;5236.136037553837;5269.367675878842;5199.844004679866;5211.661242019257;5218.504242096995;5219.5041471060185;5279.683219006872;4441.489228738672;4517.186906356822;4522.1181869535785;4544.727316361029;4587.1972404829685;3974.3044967130636;4001.099752555652;4004.099077707551;4015.477987861559;4019.959800402017;4457.687961842705;4491.236489796743;4511.593956543927;4519.05099929012;4527.139843429738;5312.229726689686;5313.362396512443;5319.38827034896;5320.14877619579;5367.141257398797;4509.413776209138;4558.293464513005;4558.33904083906;4559.635229181657;4606.378138951199;4690;4775.6657034007985;4791.544803243686;4810.2063159578975;4839.080574690797;4982.153033984231;4996.850094497171;4999.250112483157;4999.500049995117;5049.570786483043;5335.653182543122;5350.357957045024;5352.116900711339;5359.14253719403;5361.463853614608;4801.975990120027;4859.829905953275;4899.4365647950945;4904.386951631046;4919.942212424346;5164.664296820694;5182.067227898863;5187;5190.480951904779;5238.869028274324;5076.289319495348;5239.973800130975;5258.553022993088;5259.105951988095;5261.105612045885;5007.84976450707;5021.6735912165595;5048.242763585345;5059.291699162194;5069.9746501267255;5056.84829455117;5113.7698803553185;5123.308353372247;5128.307678463509;5174.844754657216;4929.088118698052;4967.40391153064;5078.746062696924;5090.516400941905;5149.845504634867;5094.617903657315;5117.718525481134;5135.717535535432;5144.254083157846;5194.870128246824;5025.070361983045;5043.84868453947;5058.4683335901955;5064.67079639837;5106.744662766772 08:38:14 INFO - PROCESS | 23100 | |1;ext.html;535.8186196970346;566.6996491859313;581.6074149948784;585.3970410477204;599.8470390050536;26203.868980655094;29115.854420727894;29258.12225633229;29309.706902930964;38910.610893891055;40313.19373612526;40604.59395406045;40856;40905;40921.38617920734;7773.689052437896;7979.042514898191;7981.082175549799;7984.161663025386;7986.960065199666;7801.180876007994;7870.2916737493615;7896.091949425832;7896.802579935519;7898.605069746518;7861.842763144734;7893.486923349992;7908.841823163533;7921.881171782428;7923.9207607924045;7803.531788092732;7854.214578542157;7860.842783144334;7861.646225919847;7897.131315555284;7881;7934.484258523235;7940.563269020259;7942.007249093864;7945.721899733481;38431;38588.80705596482;38756.61243387558;38769;38781.612183878366;2990.7308342249303;3396.252824378633;3673.5775385830702;3792.9620703792884;3840.6941639842307;29611;29755.404891902257;29941;30181.547276790865;31265.687343126505;3485.9477107843404;3583.516225309602;3589.46158076288;3621.2395396966667;3715.8699445519546;238219;238965;238987;239275.99999999825;239295;31924.36151277008;52075.739621301655;52200.738996304775;52419;52575.47424525706;32460.83769581137;34261;34385.82807085948;34386;34393.31213375769;11347;11417.48621312043;11429.199956003;11434.085273178125;11435;9399.953000234955;9472.857907131398;9501.714948551555;9508.334416590911;9513.809723805485;7948.801279968047;8027.799305017421;8049.597520123971;8056.113827478973;8127.918720812834;3136.9372612547622;3159.1944054266014;3190.356264744786;3200.4239236937574;3210.2455922858107;772.563501621582;788.5268838696736;822.6462621072997;828.0808302783905;908.1917093786535;20460.69308960367;20777.06503207359;20957.68563471549;21263.255786047415;21325.146994120372;868.6699054359303;878.3280790195505;888.3515034025069;890.4835195586544;894.4927796662645;10763.94618026905;10834.566617335377;10985.505652245669;11116;11194.552217911192;372200;373554;375810;376978;380421;49848.750756246714;50197.24704129441;50295.99408011892;50367.49632503701;50458;1083.9349639021684;1161.7502237019082;1168.3106966889577;1186.371223251678;1208.6132437620054;28236;28840.423191536473;28891.42217155687;28957.710422895503;29038.564421534116;42237;42428;42603.36094958516;42688;42798.572014280704;286996;287610;288503;289011;289608;22538.774612253666;24468.14361497339;25273.74726252714;25462.23613291604;25736.742632573434;41371.793141034104;41587.37618935779;41916;42402.15195696131;42926;35269.82365088158;35964;36319;36364.454533181495;36477.63522364743;34986.82506587451;35936;35954.46068308925;36231.818840905624;36278.09304767296;35416.29167416689;36020.45969310515;36276;36427.27145457129;36438.4534231992;31509.684903151592;32386.352272953936;32487.837560812044;32623;33004.50493242555;24446.633300500864;24458.877705611358;24805.379865503506;25241.24276271715;25400 08:38:14 INFO - PROCESS | 23100 | |2;jquery.html;373.42306137018295;382.1840370808324;383.2679581998382;394.5029263128457;395.8752992807267;15742.449014284502;16538.33846646134;17098.743518847197;17137.74293385597;17165.39921102763;11556.422178891044;16178;16925.06912119833;16933;17401.607871370292;4711.222648263041;4875.951240487585;4913.1647619904525;4920.557149856512;4921.212605983025;3489.825508724566;5346.946530534703;5645.125005624135;5651.8304450866335;5732.2261494698305;5413.972930135364;5645.520130788867;5669.206311116426;5673.8581535461535;5717.514011309044;5558.44415558443;5577.274954255941;5606.635568688022;5626.212330273765;5638.464345887155;3378.6114596821435;5081.136206844845;5440.972795136038;5478.972605136989;5482.17767334903;11426.143039272089;11517.769644607144;11557.653270401901;16234.594135146597;20648.587028259502;1925.047101684675;1939.660559402099;2016.9092390842447;2196.2971849008322;2289.7252329720372;8722.215000649909;9318.068193180696;9704;11983.520659173622;12638.368081595883;1760.287083731091;1825.561845260009;1861.3857427049177;1870.5697689531348;1877.0802306869673;57588;61058.38941610616;65213;65676.6716166421;68456.31543684549;9740.902590974141;9925.602975880884;10102.393856368495;10251.743706407251;10262.538185781657;13961.720765584834;14062.22657753813;14064.2967851607;14066.367013484212;14081.647958801112;6748.392644662004;6815.246119910188;7008.649567521604;7017.052697885822;7086.50394472382;6299.748010079637;6321.336259692758;6479.481641468671;6504.5121615879;6525.412712855866;4339.7396156230725;4471.485779135376;4483.910321793546;4519.344695019204;4527.84152554659;1525.3745964154643;2716.660417447819;3290.7696461247483;3373.8481768320485;3496.860125594948;794.3843521270945;870.2907130688469;915.6383228624655;923.3351986569659;948.4783369146942;15965.28156232949;16058;16776.51946391841;16897.239624216938;17650.205740741698;832.256140001979;843.3169133002287;858.7295002074327;873.7029410000504;874.1739056591587;9252.90747092534;9496.43021418717;9666.806663866684;9676.951615241878;9843.606255749834;284621;290696.5465172661;295918;408460;410976;80025;86047;87552.56223718968;87591;87856;894.3605322194662;918.0727465260136;921.7833809054866;946.5740416812462;949.9002604726543;15486.5354039379;15716.292766825749;15731.370745170065;15924.124173170587;16244.106574138063;31612.209694757814;36220.4566931491;36700.44949325816;37491;38405;46211.537884620724;53483.7325813384;53741.73129134329;53777;53942.19086713781;7213.2065472799;7722.3049925506975;7784.532928024337;7888.3689304856625;8022;9763.80472390534;11533.1926765129;11607.5937342196;11696.298222106361;11721.531138754355;4713.08094921487;4788.042391521779;8400.915990840012;9331.766705832408;9839;9041.864372034557;9424.246060315296;9657.420554766737;9664.71005869825;10658.307210031324;9048.276137909083;9248.121428464285;9488.193503552275;9568.521573921415;10100.343477673927;6743.763968261087;6974.616396098263;7191.2449192834;7243;7301.233370496234;8492;9065.637374504946;9222.492762898106;9247.167754902091;9547.904520954702 08:38:14 INFO - PROCESS | 23100 | |3;mootools.html;1186.4423720851196;1369.383777300215;1398.6992796548743;1497.880169586433;1716.6909956207885;6868.725250989961;7786.883196752046;8026.9197308026905;8094.91905080949;8309.252167304941;8129.39029572783;8131.959340203305;8201.7129400471;8203.753887383373;8204.876926846087;1927.5662975830396;2209.07218968033;2302.412884714404;2334;2338.8362814602983;2303.6544518322303;2352.282553821084;2353.8469999450067;2356.7643235676383;2357.292812156355;2255.436140964759;2264.7395549511853;2272.6704627829035;2317.4901521665206;2319.5129022905126;1847.1687740516686;2144.9463763405884;2245.618244898363;2256.029907139937;2256.6050941085246;2282.4978504728933;2302.6661134135456;2305.216226483004;2305.6426253930667;2306.1582522379213;8175.427720059598;8227.54748488833;8228.958855205745;8229.83540329196;8237.876431853527;4722.268048452495;4868.367112275417;4872.683275587111;4874.8050077997195;4875.293082503017;2844.7297506736827;2871.3826527296524;3000.369922316316;3020.199647093522;3024.561438591414;974.9317547771659;1002.904724051214;1011.0900189829139;1012.0790081026231;1014.9188064954786;10924.126069914388;10967;10978.780424391467;10984.01143897053;11013.394263315435;4379.956200438019;4459.888502787457;4671.85984420468;4674;4748.477667456577;8777.385583009129;8824.691135810344;8880.245179159714;8880.51157186348;8892.8666070009;4867.196912509467;4867.610591152699;4872.342233798392;4872.342233798392;4872.78072486739;3822.808859557011;4015.8996025099605;4436.157130145262;4452.866414007585;4516.23224051912;3475.7740746851373;3491.336646037245;3568.411212149966;3593.137646964762;3598.9100272492874;1058.142904247562;1070.7430216747925;1077.7628921637227;1084.6258040975852;1085.7719878825378;781.1992707474884;791.6556298010383;881.6605606841284;894.9373543851998;920.636348642296;5499.615026948155;5499.890002199933;5567.582431317667;5607.915881261784;5752.712364381848;857.3826844671827;895.2837729816121;895.737010814748;917.3807679816231;918.2195134135931;3541.557305336833;3610.133567943727;3668.137987572918;3687.59436461989;3723.8138093095235;10014.949925250326;10223.795524089477;10241.84637230442;10354.068133867988;10357.844632330522;10184.083432490963;10223.59105635781;10237;10249.077583017564;10260.743481413025;7518.812029699192;7547.886781698279;7655.0813902332075;7723.111842138113;7725.884111738328;5501.257330260363;5566.415526369835;5611.57913156523;5620.634658747168;5765.308163020464;9280.767980800534;9449.102335278138;9533.809323813624;9581.137697607248;9589.904100958902;9192.402493837879;9547.4748888809;9560.043995600663;9571.952140239255;9606.615735370508;6322.462590679844;6899.586024838325;7125.35871771543;7149.356557909813;7261.092363454568;7893.644785984759;7932;7967.84064318722;7968;7998.280154786097;6856.554323968926;7048;7123.323284287984;7137.714491420493;7259.637018148966;6887.414569761627;7007.264237255219;7097;7102.893456598258;7209.315115064061;5882.323532793784;6020.789272375446;6058.0610005449225;6061.787837425669;6064.787732429344;4702.882427939329;4762.452317983338;4770.117528257283;4792.640551958687;4870.488598697086;4779.474257831566;4795.736234507136;4804.135255654016;4841.588464980516;4863.878403039952 08:38:14 INFO - PROCESS | 23100 | |4;prototype.html;303.7372672638169;308.834773396233;318.41729634768376;320.36567596159597;322.8208344368876;4732.90534189316;4864.148773964555;4867.53758392953;4931.679440836348;4959.47066995832;4647.976760116203;4828.444728856182;4830.275458681191;4839.7338146401935;4867.951320486794;3840.961590384088;3856.4408160816665;3866.729328946975;3870.283997460479;3875.8837234883;3630.782153070811;3745.576680861269;3876.8061596920184;3885.8834234972855;3886.9805650971707;3845.9807700961455;3859.459675645398;3860.9034774130732;3863.3625451800417;3865.1496670732395;3828.3109040372724;3837.2709185254853;3848.8652897148463;3850.788206648633;3870.7290489665734;3444.50054742061;3448.3620530201742;3451;3452.7928324300624;3452.8100954447495;4537;4742.5731684148595;4754.66717329787;4756.833510827105;4762;4302.472622219098;4362.389265502832;4365.2142614329405;4387.890302742457;4410.669199810027;5046.646734728571;5052.539010777629;5079.87300317495;5084.567811736007;5102.285680004802;3605.6118394418336;3721;3730.6082861299715;3741.8129093545485;3755.342815007363;5285.603579731535;5324.946750532484;5341.813036543741;5342.626016178869;5363.758630861659;3839.788811615388;3841.250956063541;3843.5408132680254;3846.480725102075;3865.468118382464;3786.5645450772963;3940.665043471337;3941.2708648900043;3951.565327813938;3980.402939559089;4354.869353919387;4372.256716358227;4388.341748737715;4392.341148827638;4397.802098905557;5015.746063484129;5068.569171620454;5071.13790655582;5095.770690318945;5100.617453690914;1274.6813296675832;1292.9030322725832;1336.2650542201945;1340.4035204333977;1344.4420565465418;869.9391042627082;879.9528561012374;889.6130183370254;892.8125093730389;895.7504281527363;4030.911653853443;4033.69747268956;4055.655269302142;4060.2082593894497;4067.593240675909;4590.954090459053;4715.221988371949;4725.763711814396;4730.574248317599;4732.266498692708;3994.9201015980098;4020.6582440492884;4022.899427514277;4044.8179831907637;4058.2086493134148;2503.4603718713224;2576.922692319196;2772.792040596923;2790.8185967912427;2806.396624725694;4917.188663870421;4931.5315045070665;4946.183879659888;4953.2074868020945;4969.453360130383;5339.81310654133;5361.169018802092;5385.515303622693;5388.568914486832;5409.107497262908;4694.765261736899;4910.975445122752;4962.429320628174;4988.850334489971;5041.8487445376695;5261.394939581997;5283.815066472655;5296.285001524745;5309.203619457112;5353.785848566014;4823.807047718052;5101.7194054327365;5135.764468384894;5153.510416510426;5165.405978312542;4873.904221184925;4912.263160525949;4917.655764096479;4940.209566469348;4950.084234416644;3714.4985426967014;3743.5694895087413;3766.9246615066995;3796.6583007529457;3797.2974999625158;4970.179920313108;5275.973620132028;5284.920726189187;5294.258803767399;5307.124324486571;4355.4577679990225;5052.8484145475695;5055.34280543527;5068.783491961976;5108.412532558803;5023.34696489454;5031.924521132113;5058.494150584912;5081.822136225214;5088.7201203932655;5013.849584512471;5023.799048038038;5025.723585202702;5030.446650868328;5036.143855544639;4879.902401952012;5027.522385373383;5133.820316288912;5136.178211486144;5146.8970620588125;4975.776090075883;4994.275830004556;5022.271770593316;5022.769421491612;5052.494750524918 08:38:14 INFO - PROCESS | 23100 | |5;yui.html;662.9834254143649;704.5490885833068;723.070853952671;745.2212438002288;753.3558807219827;12640.936795316022;13077.346132693363;13297.401616927238;13339.066265361429;13352.59942201733;13598.524051658194;13676.111052781587;13718.657033574165;13751;13820.239886806221;627.2754968011953;760.8630446519625;778.9883151752742;791.8535071011881;795.387551585279;780.8789637606152;781.2109769133187;784.725346128856;785.1716439156687;786.6184900323339;832.5629044751494;833.9749807505784;834.0408530190269;834.1533343656193;835.2607941971369;645.9612423254596;781.316348195329;808.8058865872207;812.5043723328823;827.4538804389105;593.1962191230906;593.2258402784365;593.6675461741438;597.8624916269282;598.3298705449924;12007.579734709243;12052.698682532919;12057.93971030148;12063.457144428434;12082.456289466994;11829.526818927232;12174.878251217464;12349.444275007647;12527.74722527749;12538;11787.115966302561;11877.821832672516;11879.762404751857;12030.759384812254;12049.15655904087;4221.894452638709;4259.925276720564;4303.935440968388;4329.220740266782;4471.2398892188085;51633;53268.467315326736;53336.199957000674;57525;57680;10860.185486088574;11158.386288754034;11444.313341199555;11537.250078744855;11624.244424112574;20527.178912843618;20783.584328313653;20800.791992079885;20806.167753290003;20832.270870519762;7137.10786151731;7402.777916662509;7403.8149046274275;7510.699572017058;7741.458097933203;3886.203328317688;3943.4873466449176;3989.3018721723583;3994.7603143811466;4093.9385909211387;2988.3126880817535;3099.7210251077513;3103.658597554267;3112.5019996800856;3115.9844200778853;497.8879752055831;512.8615273876107;521.5434115483205;524.9133892907704;539.2935254816203;588.2646691635456;591.973361198747;618.4650277509921;630.5302549600577;644.687326646576;8128.918710812816;8221.136780638066;8397.370197235232;8464.195901389357;8570.442921210102;528.8069854503078;564.2016546586616;575.9596828221984;579.1862433281246;609.6068036116768;3943.881683549498;3971.62269584389;4009.7594144351433;4123.2165888481095;4146.647534959562;76774;92347;93657.531712341;97138.514307428;97865;68182.65908670524;69697.65151174212;71584.64207679032;77896.22103779003;79855.60072199602;45915.54084459179;66191;69240;69517;70934;8586.441881277697;8846.867296990678;8942.418742781698;9148.954255228682;9249.429877411345;32076.037718868472;38208.23583528258;38300.23399531938;39024.41463378108;39089.80455097706;40950.59049409587;46940.295895560914;58343.70828145832;65080.34919650743;69066;11512.53949841997;12163.45264463119;12237.571684990982;12242.693932651755;12999.1550549214;13724.176549407068;13812.585622431343;13914.652133696738;14142.080764750259;14619.634509137357;12493.312867792358;12529.749405012031;12796.424160912595;13010.674733131747;13114.60656180316;12851;13181.538646147339;13674.31628418595;13678.726425471634;13728;12127.332996684914;12424.627261182179;12705.682357941125;12911.935440322739;12947.417366218731;9668.15571155069;9710.184046626398;9758.951205243928;9784.706458806248;9863.901360986298;9807.166390856857;9927.560503727063;9978.850317245102;10729.624463143753;10874.347539147677 08:38:14 INFO - PROCESS | 23100 | __end_tp_report 08:38:14 INFO - PROCESS | 23100 | __start_cc_report 08:38:14 INFO - PROCESS | 23100 | _x_x_mozilla_cycle_collect,899 08:38:14 INFO - PROCESS | 23100 | __end_cc_report 08:38:14 INFO - PROCESS | 23100 | __startTimestamp1467128294759__endTimestamp 08:38:14 INFO - PROCESS | 23100 | 08:38:14 INFO - PROCESS | 23100 | ------- Summary: start ------- 08:38:14 INFO - PROCESS | 23100 | Number of tests: 6 08:38:14 INFO - PROCESS | 23100 | 08:38:14 INFO - PROCESS | 23100 | [#0] dojo.html Cycles:165 Average:4863.53 Median:4983.80 stddev:516.66 (10.4%) stddev-sans-first:487.96 08:38:14 INFO - PROCESS | 23100 | Values: 2641.7 2645.2 2686.6 2691.1 2703.7 4722.2 4813.0 4835.1 4908.2 4931.4 4887.5 4920.4 4921.5 4978.6 4980.9 4422.8 4494.7 4498.6 4563.2 4640.4 4554.2 4563.3 4586.2 4607.0 4627.6 4409.6 4412.9 4456.5 4467.6 4501.5 4485.8 4497.0 4503.6 4548.4 4598.3 4482.4 4506.8 4508.2 4517.4 4568.1 4733.8 4787.2 4894.7 4959.1 4983.8 5253.1 5258.6 5261.9 5302.0 5314.1 5267.2 5315.2 5355.8 5359.5 5374.1 4810.2 4845.5 4881.5 4900.0 4967.6 5409.7 5454.2 5476.5 5476.8 5477.3 5136.3 5172.1 5189.9 5195.3 5199.7 5111.2 5207.8 5247.2 5289.4 5297.7 5227.5 5229.5 5234.5 5236.1 5269.4 5199.8 5211.7 5218.5 5219.5 5279.7 4441.5 4517.2 4522.1 4544.7 4587.2 3974.3 4001.1 4004.1 4015.5 4020.0 4457.7 4491.2 4511.6 4519.1 4527.1 5312.2 5313.4 5319.4 5320.1 5367.1 4509.4 4558.3 4558.3 4559.6 4606.4 4690.0 4775.7 4791.5 4810.2 4839.1 4982.2 4996.9 4999.3 4999.5 5049.6 5335.7 5350.4 5352.1 5359.1 5361.5 4802.0 4859.8 4899.4 4904.4 4919.9 5164.7 5182.1 5187.0 5190.5 5238.9 5076.3 5240.0 5258.6 5259.1 5261.1 5007.8 5021.7 5048.2 5059.3 5070.0 5056.8 5113.8 5123.3 5128.3 5174.8 4929.1 4967.4 5078.7 5090.5 5149.8 5094.6 5117.7 5135.7 5144.3 5194.9 5025.1 5043.8 5058.5 5064.7 5106.7 08:38:14 INFO - PROCESS | 23100 | 08:38:14 INFO - PROCESS | 23100 | [#1] ext.html Cycles:180 Average:44255.23 Median:20075.61 stddev:80817.42 (402.6%) stddev-sans-first:80977.47 08:38:14 INFO - PROCESS | 23100 | Values: 535.8 566.7 581.6 585.4 599.8 26203.9 29115.9 29258.1 29309.7 38910.6 40313.2 40604.6 40856.0 40905.0 40921.4 7773.7 7979.0 7981.1 7984.2 7987.0 7801.2 7870.3 7896.1 7896.8 7898.6 7861.8 7893.5 7908.8 7921.9 7923.9 7803.5 7854.2 7860.8 7861.6 7897.1 7881.0 7934.5 7940.6 7942.0 7945.7 38431.0 38588.8 38756.6 38769.0 38781.6 2990.7 3396.3 3673.6 3793.0 3840.7 29611.0 29755.4 29941.0 30181.5 31265.7 3485.9 3583.5 3589.5 3621.2 3715.9 238219.0 238965.0 238987.0 239276.0 239295.0 31924.4 52075.7 52200.7 52419.0 52575.5 32460.8 34261.0 34385.8 34386.0 34393.3 11347.0 11417.5 11429.2 11434.1 11435.0 9400.0 9472.9 9501.7 9508.3 9513.8 7948.8 8027.8 8049.6 8056.1 8127.9 3136.9 3159.2 3190.4 3200.4 3210.2 772.6 788.5 822.6 828.1 908.2 20460.7 20777.1 20957.7 21263.3 21325.1 868.7 878.3 888.4 890.5 894.5 10763.9 10834.6 10985.5 11116.0 11194.6 372200.0 373554.0 375810.0 376978.0 380421.0 49848.8 50197.2 50296.0 50367.5 50458.0 1083.9 1161.8 1168.3 1186.4 1208.6 28236.0 28840.4 28891.4 28957.7 29038.6 42237.0 42428.0 42603.4 42688.0 42798.6 286996.0 287610.0 288503.0 289011.0 289608.0 22538.8 24468.1 25273.7 25462.2 25736.7 41371.8 41587.4 41916.0 42402.2 42926.0 35269.8 35964.0 36319.0 36364.5 36477.6 34986.8 35936.0 35954.5 36231.8 36278.1 35416.3 36020.5 36276.0 36427.3 36438.5 31509.7 32386.4 32487.8 32623.0 33004.5 24446.6 24458.9 24805.4 25241.2 25400.0 08:38:14 INFO - PROCESS | 23100 | 08:38:14 INFO - PROCESS | 23100 | [#2] jquery.html Cycles:180 Average:22640.08 Median:53859.60 stddev:57266.69 (106.3%) stddev-sans-first:57402.94 08:38:14 INFO - PROCESS | 23100 | Values: 373.4 382.2 383.3 394.5 395.9 15742.4 16538.3 17098.7 17137.7 17165.4 11556.4 16178.0 16925.1 16933.0 17401.6 4711.2 4876.0 4913.2 4920.6 4921.2 3489.8 5346.9 5645.1 5651.8 5732.2 5414.0 5645.5 5669.2 5673.9 5717.5 5558.4 5577.3 5606.6 5626.2 5638.5 3378.6 5081.1 5441.0 5479.0 5482.2 11426.1 11517.8 11557.7 16234.6 20648.6 1925.0 1939.7 2016.9 2196.3 2289.7 8722.2 9318.1 9704.0 11983.5 12638.4 1760.3 1825.6 1861.4 1870.6 1877.1 57588.0 61058.4 65213.0 65676.7 68456.3 9740.9 9925.6 10102.4 10251.7 10262.5 13961.7 14062.2 14064.3 14066.4 14081.6 6748.4 6815.2 7008.6 7017.1 7086.5 6299.7 6321.3 6479.5 6504.5 6525.4 4339.7 4471.5 4483.9 4519.3 4527.8 1525.4 2716.7 3290.8 3373.8 3496.9 794.4 870.3 915.6 923.3 948.5 15965.3 16058.0 16776.5 16897.2 17650.2 832.3 843.3 858.7 873.7 874.2 9252.9 9496.4 9666.8 9677.0 9843.6 284621.0 290696.5 295918.0 408460.0 410976.0 80025.0 86047.0 87552.6 87591.0 87856.0 894.4 918.1 921.8 946.6 949.9 15486.5 15716.3 15731.4 15924.1 16244.1 31612.2 36220.5 36700.4 37491.0 38405.0 46211.5 53483.7 53741.7 53777.0 53942.2 7213.2 7722.3 7784.5 7888.4 8022.0 9763.8 11533.2 11607.6 11696.3 11721.5 4713.1 4788.0 8400.9 9331.8 9839.0 9041.9 9424.2 9657.4 9664.7 10658.3 9048.3 9248.1 9488.2 9568.5 10100.3 6743.8 6974.6 7191.2 7243.0 7301.2 8492.0 9065.6 9222.5 9247.2 9547.9 08:38:14 INFO - PROCESS | 23100 | 08:38:14 INFO - PROCESS | 23100 | [#3] mootools.html Cycles:180 Average:5340.12 Median:4867.99 stddev:3013.16 (61.9%) stddev-sans-first:3005.44 08:38:14 INFO - PROCESS | 23100 | Values: 1186.4 1369.4 1398.7 1497.9 1716.7 6868.7 7786.9 8026.9 8094.9 8309.3 8129.4 8132.0 8201.7 8203.8 8204.9 1927.6 2209.1 2302.4 2334.0 2338.8 2303.7 2352.3 2353.8 2356.8 2357.3 2255.4 2264.7 2272.7 2317.5 2319.5 1847.2 2144.9 2245.6 2256.0 2256.6 2282.5 2302.7 2305.2 2305.6 2306.2 8175.4 8227.5 8229.0 8229.8 8237.9 4722.3 4868.4 4872.7 4874.8 4875.3 2844.7 2871.4 3000.4 3020.2 3024.6 974.9 1002.9 1011.1 1012.1 1014.9 10924.1 10967.0 10978.8 10984.0 11013.4 4380.0 4459.9 4671.9 4674.0 4748.5 8777.4 8824.7 8880.2 8880.5 8892.9 4867.2 4867.6 4872.3 4872.3 4872.8 3822.8 4015.9 4436.2 4452.9 4516.2 3475.8 3491.3 3568.4 3593.1 3598.9 1058.1 1070.7 1077.8 1084.6 1085.8 781.2 791.7 881.7 894.9 920.6 5499.6 5499.9 5567.6 5607.9 5752.7 857.4 895.3 895.7 917.4 918.2 3541.6 3610.1 3668.1 3687.6 3723.8 10014.9 10223.8 10241.8 10354.1 10357.8 10184.1 10223.6 10237.0 10249.1 10260.7 7518.8 7547.9 7655.1 7723.1 7725.9 5501.3 5566.4 5611.6 5620.6 5765.3 9280.8 9449.1 9533.8 9581.1 9589.9 9192.4 9547.5 9560.0 9572.0 9606.6 6322.5 6899.6 7125.4 7149.4 7261.1 7893.6 7932.0 7967.8 7968.0 7998.3 6856.6 7048.0 7123.3 7137.7 7259.6 6887.4 7007.3 7097.0 7102.9 7209.3 5882.3 6020.8 6058.1 6061.8 6064.8 4702.9 4762.5 4770.1 4792.6 4870.5 4779.5 4795.7 4804.1 4841.6 4863.9 08:38:14 INFO - PROCESS | 23100 | 08:38:14 INFO - PROCESS | 23100 | [#4] prototype.html Cycles:180 Average:4208.59 Median:4748.62 stddev:1207.25 (25.4%) stddev-sans-first:1174.52 08:38:14 INFO - PROCESS | 23100 | Values: 303.7 308.8 318.4 320.4 322.8 4732.9 4864.1 4867.5 4931.7 4959.5 4648.0 4828.4 4830.3 4839.7 4868.0 3841.0 3856.4 3866.7 3870.3 3875.9 3630.8 3745.6 3876.8 3885.9 3887.0 3846.0 3859.5 3860.9 3863.4 3865.1 3828.3 3837.3 3848.9 3850.8 3870.7 3444.5 3448.4 3451.0 3452.8 3452.8 4537.0 4742.6 4754.7 4756.8 4762.0 4302.5 4362.4 4365.2 4387.9 4410.7 5046.6 5052.5 5079.9 5084.6 5102.3 3605.6 3721.0 3730.6 3741.8 3755.3 5285.6 5324.9 5341.8 5342.6 5363.8 3839.8 3841.3 3843.5 3846.5 3865.5 3786.6 3940.7 3941.3 3951.6 3980.4 4354.9 4372.3 4388.3 4392.3 4397.8 5015.7 5068.6 5071.1 5095.8 5100.6 1274.7 1292.9 1336.3 1340.4 1344.4 869.9 880.0 889.6 892.8 895.8 4030.9 4033.7 4055.7 4060.2 4067.6 4591.0 4715.2 4725.8 4730.6 4732.3 3994.9 4020.7 4022.9 4044.8 4058.2 2503.5 2576.9 2772.8 2790.8 2806.4 4917.2 4931.5 4946.2 4953.2 4969.5 5339.8 5361.2 5385.5 5388.6 5409.1 4694.8 4911.0 4962.4 4988.9 5041.8 5261.4 5283.8 5296.3 5309.2 5353.8 4823.8 5101.7 5135.8 5153.5 5165.4 4873.9 4912.3 4917.7 4940.2 4950.1 3714.5 3743.6 3766.9 3796.7 3797.3 4970.2 5276.0 5284.9 5294.3 5307.1 4355.5 5052.8 5055.3 5068.8 5108.4 5023.3 5031.9 5058.5 5081.8 5088.7 5013.8 5023.8 5025.7 5030.4 5036.1 4879.9 5027.5 5133.8 5136.2 5146.9 4975.8 4994.3 5022.3 5022.8 5052.5 08:38:14 INFO - PROCESS | 23100 | 08:38:14 INFO - PROCESS | 23100 | [#5] yui.html Cycles:180 Average:16781.08 Median:505.37 stddev:22599.34 (4471.8%) stddev-sans-first:22630.33 08:38:14 INFO - PROCESS | 23100 | Values: 663.0 704.5 723.1 745.2 753.4 12640.9 13077.3 13297.4 13339.1 13352.6 13598.5 13676.1 13718.7 13751.0 13820.2 627.3 760.9 779.0 791.9 795.4 780.9 781.2 784.7 785.2 786.6 832.6 834.0 834.0 834.2 835.3 646.0 781.3 808.8 812.5 827.5 593.2 593.2 593.7 597.9 598.3 12007.6 12052.7 12057.9 12063.5 12082.5 11829.5 12174.9 12349.4 12527.7 12538.0 11787.1 11877.8 11879.8 12030.8 12049.2 4221.9 4259.9 4303.9 4329.2 4471.2 51633.0 53268.5 53336.2 57525.0 57680.0 10860.2 11158.4 11444.3 11537.3 11624.2 20527.2 20783.6 20800.8 20806.2 20832.3 7137.1 7402.8 7403.8 7510.7 7741.5 3886.2 3943.5 3989.3 3994.8 4093.9 2988.3 3099.7 3103.7 3112.5 3116.0 497.9 512.9 521.5 524.9 539.3 588.3 592.0 618.5 630.5 644.7 8128.9 8221.1 8397.4 8464.2 8570.4 528.8 564.2 576.0 579.2 609.6 3943.9 3971.6 4009.8 4123.2 4146.6 76774.0 92347.0 93657.5 97138.5 97865.0 68182.7 69697.7 71584.6 77896.2 79855.6 45915.5 66191.0 69240.0 69517.0 70934.0 8586.4 8846.9 8942.4 9149.0 9249.4 32076.0 38208.2 38300.2 39024.4 39089.8 40950.6 46940.3 58343.7 65080.3 69066.0 11512.5 12163.5 12237.6 12242.7 12999.2 13724.2 13812.6 13914.7 14142.1 14619.6 12493.3 12529.7 12796.4 13010.7 13114.6 12851.0 13181.5 13674.3 13678.7 13728.0 12127.3 12424.6 12705.7 12911.9 12947.4 9668.2 9710.2 9759.0 9784.7 9863.9 9807.2 9927.6 9978.9 10729.6 10874.3 08:38:14 INFO - PROCESS | 23100 | -------- Summary: end -------- 08:38:14 INFO - PROCESS | 23100 | 08:38:15 INFO - TEST-INFO | 23100: exit 0 08:38:15 INFO - TEST-OK | dromaeo_css | took 1121964ms 08:38:15 INFO - TEST-START | kraken 08:38:15 INFO - operating with platform_type : linux_ 08:38:15 INFO - Initialising browser for kraken test... 08:38:15 INFO - TEST-INFO | started process 23278 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUAndtF/profile http://localhost:42891/getInfo.html) 08:38:20 INFO - PROCESS | 23278 | __metrics Screen width/height:1600/1200 08:38:20 INFO - PROCESS | 23278 | colorDepth:24 08:38:20 INFO - PROCESS | 23278 | Browser inner width/height: 1024/697 08:38:20 INFO - PROCESS | 23278 | __metrics 08:38:22 INFO - PROCESS | 23278 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:38:22 INFO - PROCESS | 23278 | console.error: PushService: 08:38:22 INFO - PROCESS | 23278 | stateChangeProcessEnqueue: Error transitioning state 08:38:22 INFO - PROCESS | 23278 | UnknownError 08:38:23 INFO - TEST-INFO | 23278: exit 0 08:38:23 INFO - Browser initialized. 08:38:23 INFO - Running cycle 1/1 for kraken test... 08:38:23 INFO - TEST-INFO | started process 23359 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUAndtF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:38:35 INFO - PROCESS | 23359 | RSS: Main: 175407104 08:38:35 INFO - PROCESS | 23359 | 08:38:38 INFO - PROCESS | 23359 | Cycle 1(1): loaded http://localhost:42891/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42891/tests/kraken/driver.html?testName=audio-beat-detection) 08:38:39 INFO - PROCESS | 23359 | RSS: Main: 232865792 08:38:39 INFO - PROCESS | 23359 | 08:38:42 INFO - PROCESS | 23359 | Cycle 1(1): loaded http://localhost:42891/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42891/tests/kraken/driver.html?testName=audio-dft) 08:38:42 INFO - PROCESS | 23359 | RSS: Main: 222265344 08:38:42 INFO - PROCESS | 23359 | 08:38:45 INFO - PROCESS | 23359 | Cycle 1(1): loaded http://localhost:42891/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42891/tests/kraken/driver.html?testName=audio-fft) 08:38:46 INFO - PROCESS | 23359 | RSS: Main: 288346112 08:38:46 INFO - PROCESS | 23359 | 08:38:48 INFO - PROCESS | 23359 | Cycle 1(1): loaded http://localhost:42891/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42891/tests/kraken/driver.html?testName=audio-oscillator) 08:38:49 INFO - PROCESS | 23359 | RSS: Main: 198356992 08:38:49 INFO - PROCESS | 23359 | 08:38:51 INFO - PROCESS | 23359 | Cycle 1(1): loaded http://localhost:42891/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42891/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:38:52 INFO - PROCESS | 23359 | RSS: Main: 201609216 08:38:52 INFO - PROCESS | 23359 | 08:38:55 INFO - PROCESS | 23359 | Cycle 1(1): loaded http://localhost:42891/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42891/tests/kraken/driver.html?testName=imaging-darkroom) 08:38:55 INFO - PROCESS | 23359 | RSS: Main: 237916160 08:38:55 INFO - PROCESS | 23359 | 08:38:59 INFO - PROCESS | 23359 | Cycle 1(1): loaded http://localhost:42891/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42891/tests/kraken/driver.html?testName=imaging-desaturate) 08:38:59 INFO - PROCESS | 23359 | RSS: Main: 239030272 08:38:59 INFO - PROCESS | 23359 | 08:39:02 INFO - PROCESS | 23359 | Cycle 1(1): loaded http://localhost:42891/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42891/tests/kraken/driver.html?testName=json-parse-financial) 08:39:03 INFO - PROCESS | 23359 | RSS: Main: 238735360 08:39:03 INFO - PROCESS | 23359 | 08:39:05 INFO - PROCESS | 23359 | Cycle 1(1): loaded http://localhost:42891/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42891/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:39:05 INFO - PROCESS | 23359 | RSS: Main: 225779712 08:39:05 INFO - PROCESS | 23359 | 08:39:07 INFO - PROCESS | 23359 | Cycle 1(1): loaded http://localhost:42891/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42891/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:39:08 INFO - PROCESS | 23359 | RSS: Main: 230879232 08:39:08 INFO - PROCESS | 23359 | 08:39:10 INFO - PROCESS | 23359 | Cycle 1(1): loaded http://localhost:42891/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42891/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:39:11 INFO - PROCESS | 23359 | RSS: Main: 238055424 08:39:11 INFO - PROCESS | 23359 | 08:39:13 INFO - PROCESS | 23359 | Cycle 1(1): loaded http://localhost:42891/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42891/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:39:14 INFO - PROCESS | 23359 | RSS: Main: 241618944 08:39:14 INFO - PROCESS | 23359 | 08:39:17 INFO - PROCESS | 23359 | Cycle 1(1): loaded http://localhost:42891/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42891/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:39:17 INFO - PROCESS | 23359 | RSS: Main: 241213440 08:39:17 INFO - PROCESS | 23359 | 08:39:19 INFO - PROCESS | 23359 | Cycle 1(1): loaded http://localhost:42891/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42891/tests/kraken/driver.html?testName=ai-astar) 08:39:19 INFO - PROCESS | 23359 | RSS: Main: 284069888 08:39:19 INFO - PROCESS | 23359 | 08:39:19 INFO - PROCESS | 23359 | __start_tp_report 08:39:19 INFO - PROCESS | 23359 | _x_x_mozilla_page_load 08:39:19 INFO - PROCESS | 23359 | _x_x_mozilla_page_load_details 08:39:19 INFO - PROCESS | 23359 | |i|pagename|runs| 08:39:19 INFO - PROCESS | 23359 | |0;ai-astar;104;115;114;114;115;113;113;113;114;114 08:39:19 INFO - PROCESS | 23359 | |1;audio-beat-detection;159;131;138;190;128;164;154;130;203;130 08:39:19 INFO - PROCESS | 23359 | |2;audio-dft;165;163;162;159;161;161;163;163;162;160 08:39:19 INFO - PROCESS | 23359 | |3;audio-fft;76;75;77;118;78;78;76;76;99;76 08:39:19 INFO - PROCESS | 23359 | |4;audio-oscillator;87;95;118;87;86;87;119;86;85;125 08:39:19 INFO - PROCESS | 23359 | |5;imaging-gaussian-blur;117;118;117;117;118;116;118;118;118;117 08:39:19 INFO - PROCESS | 23359 | |6;imaging-darkroom;147;146;146;147;146;147;147;146;146;146 08:39:19 INFO - PROCESS | 23359 | |7;imaging-desaturate;95;96;95;95;95;94;96;96;95;96 08:39:19 INFO - PROCESS | 23359 | |8;json-parse-financial;63;62;62;62;62;62;63;62;61;62 08:39:19 INFO - PROCESS | 23359 | |9;json-stringify-tinderbox;65;65;65;65;65;65;65;65;65;65 08:39:19 INFO - PROCESS | 23359 | |10;stanford-crypto-aes;85;87;82;82;85;82;86;83;112;82 08:39:19 INFO - PROCESS | 23359 | |11;stanford-crypto-ccm;143;135;136;134;138;130;130;141;143;130 08:39:19 INFO - PROCESS | 23359 | |12;stanford-crypto-pbkdf2;172;167;171;169;165;175;163;166;168;163 08:39:19 INFO - PROCESS | 23359 | |13;stanford-crypto-sha256-iterative;67;68;67;67;66;67;67;67;67;66 08:39:19 INFO - PROCESS | 23359 | __end_tp_report 08:39:19 INFO - PROCESS | 23359 | __start_cc_report 08:39:19 INFO - PROCESS | 23359 | _x_x_mozilla_cycle_collect,1879 08:39:19 INFO - PROCESS | 23359 | __end_cc_report 08:39:19 INFO - PROCESS | 23359 | __startTimestamp1467128359971__endTimestamp 08:39:19 INFO - PROCESS | 23359 | 08:39:19 INFO - PROCESS | 23359 | ------- Summary: start ------- 08:39:19 INFO - PROCESS | 23359 | Number of tests: 14 08:39:19 INFO - PROCESS | 23359 | 08:39:19 INFO - PROCESS | 23359 | [#0] ai-astar Cycles:10 Average:112.90 Median:114.00 stddev:3.21 (2.8%) stddev-sans-first:0.78 08:39:19 INFO - PROCESS | 23359 | Values: 104.0 115.0 114.0 114.0 115.0 113.0 113.0 113.0 114.0 114.0 08:39:19 INFO - PROCESS | 23359 | 08:39:19 INFO - PROCESS | 23359 | [#1] audio-beat-detection Cycles:10 Average:152.70 Median:156.50 stddev:26.70 (17.1%) stddev-sans-first:28.23 08:39:19 INFO - PROCESS | 23359 | Values: 159.0 131.0 138.0 190.0 128.0 164.0 154.0 130.0 203.0 130.0 08:39:19 INFO - PROCESS | 23359 | 08:39:19 INFO - PROCESS | 23359 | [#2] audio-dft Cycles:10 Average:161.90 Median:162.50 stddev:1.73 (1.1%) stddev-sans-first:1.42 08:39:19 INFO - PROCESS | 23359 | Values: 165.0 163.0 162.0 159.0 161.0 161.0 163.0 163.0 162.0 160.0 08:39:19 INFO - PROCESS | 23359 | 08:39:19 INFO - PROCESS | 23359 | [#3] audio-fft Cycles:10 Average:82.90 Median:76.50 stddev:14.25 (18.6%) stddev-sans-first:14.89 08:39:19 INFO - PROCESS | 23359 | Values: 76.0 75.0 77.0 118.0 78.0 78.0 76.0 76.0 99.0 76.0 08:39:19 INFO - PROCESS | 23359 | 08:39:19 INFO - PROCESS | 23359 | [#4] audio-oscillator Cycles:10 Average:97.50 Median:86.50 stddev:16.32 (18.9%) stddev-sans-first:16.86 08:39:19 INFO - PROCESS | 23359 | Values: 87.0 95.0 118.0 87.0 86.0 87.0 119.0 86.0 85.0 125.0 08:39:19 INFO - PROCESS | 23359 | 08:39:19 INFO - PROCESS | 23359 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:118.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 08:39:19 INFO - PROCESS | 23359 | Values: 117.0 118.0 117.0 117.0 118.0 116.0 118.0 118.0 118.0 117.0 08:39:19 INFO - PROCESS | 23359 | 08:39:19 INFO - PROCESS | 23359 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 08:39:19 INFO - PROCESS | 23359 | Values: 147.0 146.0 146.0 147.0 146.0 147.0 147.0 146.0 146.0 146.0 08:39:19 INFO - PROCESS | 23359 | 08:39:19 INFO - PROCESS | 23359 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.50 stddev:0.67 (0.7%) stddev-sans-first:0.71 08:39:19 INFO - PROCESS | 23359 | Values: 95.0 96.0 95.0 95.0 95.0 94.0 96.0 96.0 95.0 96.0 08:39:19 INFO - PROCESS | 23359 | 08:39:19 INFO - PROCESS | 23359 | [#8] json-parse-financial Cycles:10 Average:62.10 Median:62.00 stddev:0.57 (0.9%) stddev-sans-first:0.50 08:39:19 INFO - PROCESS | 23359 | Values: 63.0 62.0 62.0 62.0 62.0 62.0 63.0 62.0 61.0 62.0 08:39:19 INFO - PROCESS | 23359 | 08:39:19 INFO - PROCESS | 23359 | [#9] json-stringify-tinderbox Cycles:10 Average:65.00 Median:65.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 08:39:19 INFO - PROCESS | 23359 | Values: 65.0 65.0 65.0 65.0 65.0 65.0 65.0 65.0 65.0 65.0 08:39:19 INFO - PROCESS | 23359 | 08:39:19 INFO - PROCESS | 23359 | [#10] stanford-crypto-aes Cycles:10 Average:86.60 Median:84.00 stddev:9.12 (10.9%) stddev-sans-first:9.65 08:39:19 INFO - PROCESS | 23359 | Values: 85.0 87.0 82.0 82.0 85.0 82.0 86.0 83.0 112.0 82.0 08:39:19 INFO - PROCESS | 23359 | 08:39:19 INFO - PROCESS | 23359 | [#11] stanford-crypto-ccm Cycles:10 Average:136.00 Median:137.00 stddev:5.16 (3.8%) stddev-sans-first:4.82 08:39:19 INFO - PROCESS | 23359 | Values: 143.0 135.0 136.0 134.0 138.0 130.0 130.0 141.0 143.0 130.0 08:39:19 INFO - PROCESS | 23359 | 08:39:19 INFO - PROCESS | 23359 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.90 Median:168.50 stddev:3.93 (2.3%) stddev-sans-first:3.88 08:39:19 INFO - PROCESS | 23359 | Values: 172.0 167.0 171.0 169.0 165.0 175.0 163.0 166.0 168.0 163.0 08:39:19 INFO - PROCESS | 23359 | 08:39:19 INFO - PROCESS | 23359 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:0.57 (0.8%) stddev-sans-first:0.60 08:39:19 INFO - PROCESS | 23359 | Values: 67.0 68.0 67.0 67.0 66.0 67.0 67.0 67.0 67.0 66.0 08:39:19 INFO - PROCESS | 23359 | -------- Summary: end -------- 08:39:19 INFO - PROCESS | 23359 | 08:39:21 INFO - TEST-INFO | 23359: exit 0 08:39:21 INFO - TEST-OK | kraken | took 65400ms 08:39:21 INFO - SUITE-END | took 1187s 08:39:21 INFO - Completed test suite (00:19:47) 08:39:21 INFO - javascript benchmark 08:39:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2641.6829980402354, 2645.2461048601153, 2686.597010448433, 2691.125384250118, 2703.6890757562874, 4722.220833562466, 4813.0, 4835.105505481484, 4908.239222920449, 4931.3835770528685, 4887.535684110013, 4920.360353154085, 4921.483244259355, 4978.551930326279, 4980.850574482763, 4422.778861056942, 4494.68537202396, 4498.595126438619, 4563.247064234414, 4640.350350950869, 4554.180247555422, 4563.338315944186, 4586.220342541776, 4607.0, 4627.62978961684, 4409.625181859514, 4412.867613971586, 4456.509783923766, 4467.5755803198645, 4501.482329532113, 4485.753283569403, 4497.0, 4503.639708823286, 4548.363229147922, 4598.2642777155825, 4482.417285752831, 4506.752128632924, 4508.188526065307, 4517.412736344287, 4568.109218702354, 4733.763311834395, 4787.234042553175, 4894.7063176209185, 4959.082569724613, 4983.800647974077, 5253.106971814763, 5258.605604579672, 5261.894762104736, 5302.045631786277, 5314.096603577401, 5267.157254839246, 5315.202719592054, 5355.812546560891, 5359.490848369399, 5374.059539580558, 4810.230363141951, 4845.466998630148, 4881.4630390657, 4900.044491324157, 4967.627427942919, 5409.7295135243085, 5454.1546060359915, 5476.452354764571, 5476.7535460904355, 5477.287952566141, 5136.332276803992, 5172.146595811724, 5189.870253243699, 5195.2986346843445, 5199.688018718889, 5111.207762796773, 5207.791688332424, 5247.2129180623215, 5289.418164001957, 5297.708626025528, 5227.45111763267, 5229.47705229474, 5234.528892399702, 5236.136037553837, 5269.367675878842, 5199.844004679866, 5211.661242019257, 5218.504242096995, 5219.5041471060185, 5279.683219006872, 4441.489228738672, 4517.186906356822, 4522.1181869535785, 4544.727316361029, 4587.1972404829685, 3974.3044967130636, 4001.099752555652, 4004.099077707551, 4015.477987861559, 4019.959800402017, 4457.687961842705, 4491.236489796743, 4511.593956543927, 4519.05099929012, 4527.139843429738, 5312.229726689686, 5313.362396512443, 5319.38827034896, 5320.14877619579, 5367.141257398797, 4509.413776209138, 4558.293464513005, 4558.33904083906, 4559.635229181657, 4606.378138951199, 4690.0, 4775.6657034007985, 4791.544803243686, 4810.2063159578975, 4839.080574690797, 4982.153033984231, 4996.850094497171, 4999.250112483157, 4999.500049995117, 5049.570786483043, 5335.653182543122, 5350.357957045024, 5352.116900711339, 5359.14253719403, 5361.463853614608, 4801.975990120027, 4859.829905953275, 4899.4365647950945, 4904.386951631046, 4919.942212424346, 5164.664296820694, 5182.067227898863, 5187.0, 5190.480951904779, 5238.869028274324, 5076.289319495348, 5239.973800130975, 5258.553022993088, 5259.105951988095, 5261.105612045885, 5007.84976450707, 5021.6735912165595, 5048.242763585345, 5059.291699162194, 5069.9746501267255, 5056.84829455117, 5113.7698803553185, 5123.308353372247, 5128.307678463509, 5174.844754657216, 4929.088118698052, 4967.40391153064, 5078.746062696924, 5090.516400941905, 5149.845504634867, 5094.617903657315, 5117.718525481134, 5135.717535535432, 5144.254083157846, 5194.870128246824, 5025.070361983045, 5043.84868453947, 5058.4683335901955, 5064.67079639837, 5106.744662766772], "value": 4829.604492892579, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [535.8186196970346, 566.6996491859313, 581.6074149948784, 585.3970410477204, 599.8470390050536, 26203.868980655094, 29115.854420727894, 29258.12225633229, 29309.706902930964, 38910.610893891055, 40313.19373612526, 40604.59395406045, 40856.0, 40905.0, 40921.38617920734, 7773.689052437896, 7979.042514898191, 7981.082175549799, 7984.161663025386, 7986.960065199666, 7801.180876007994, 7870.2916737493615, 7896.091949425832, 7896.802579935519, 7898.605069746518, 7861.842763144734, 7893.486923349992, 7908.841823163533, 7921.881171782428, 7923.9207607924045, 7803.531788092732, 7854.214578542157, 7860.842783144334, 7861.646225919847, 7897.131315555284, 7881.0, 7934.484258523235, 7940.563269020259, 7942.007249093864, 7945.721899733481, 38431.0, 38588.80705596482, 38756.61243387558, 38769.0, 38781.612183878366, 2990.7308342249303, 3396.252824378633, 3673.5775385830702, 3792.9620703792884, 3840.6941639842307, 29611.0, 29755.404891902257, 29941.0, 30181.547276790865, 31265.687343126505, 3485.9477107843404, 3583.516225309602, 3589.46158076288, 3621.2395396966667, 3715.8699445519546, 238219.0, 238965.0, 238987.0, 239275.99999999825, 239295.0, 31924.36151277008, 52075.739621301655, 52200.738996304775, 52419.0, 52575.47424525706, 32460.83769581137, 34261.0, 34385.82807085948, 34386.0, 34393.31213375769, 11347.0, 11417.48621312043, 11429.199956003, 11434.085273178125, 11435.0, 9399.953000234955, 9472.857907131398, 9501.714948551555, 9508.334416590911, 9513.809723805485, 7948.801279968047, 8027.799305017421, 8049.597520123971, 8056.113827478973, 8127.918720812834, 3136.9372612547622, 3159.1944054266014, 3190.356264744786, 3200.4239236937574, 3210.2455922858107, 772.563501621582, 788.5268838696736, 822.6462621072997, 828.0808302783905, 908.1917093786535, 20460.69308960367, 20777.06503207359, 20957.68563471549, 21263.255786047415, 21325.146994120372, 868.6699054359303, 878.3280790195505, 888.3515034025069, 890.4835195586544, 894.4927796662645, 10763.94618026905, 10834.566617335377, 10985.505652245669, 11116.0, 11194.552217911192, 372200.0, 373554.0, 375810.0, 376978.0, 380421.0, 49848.750756246714, 50197.24704129441, 50295.99408011892, 50367.49632503701, 50458.0, 1083.9349639021684, 1161.7502237019082, 1168.3106966889577, 1186.371223251678, 1208.6132437620054, 28236.0, 28840.423191536473, 28891.42217155687, 28957.710422895503, 29038.564421534116, 42237.0, 42428.0, 42603.36094958516, 42688.0, 42798.572014280704, 286996.0, 287610.0, 288503.0, 289011.0, 289608.0, 22538.774612253666, 24468.14361497339, 25273.74726252714, 25462.23613291604, 25736.742632573434, 41371.793141034104, 41587.37618935779, 41916.0, 42402.15195696131, 42926.0, 35269.82365088158, 35964.0, 36319.0, 36364.454533181495, 36477.63522364743, 34986.82506587451, 35936.0, 35954.46068308925, 36231.818840905624, 36278.09304767296, 35416.29167416689, 36020.45969310515, 36276.0, 36427.27145457129, 36438.4534231992, 31509.684903151592, 32386.352272953936, 32487.837560812044, 32623.0, 33004.50493242555, 24446.633300500864, 24458.877705611358, 24805.379865503506, 25241.24276271715, 25400.0], "value": 15906.171713351097, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.42306137018295, 382.1840370808324, 383.2679581998382, 394.5029263128457, 395.8752992807267, 15742.449014284502, 16538.33846646134, 17098.743518847197, 17137.74293385597, 17165.39921102763, 11556.422178891044, 16178.0, 16925.06912119833, 16933.0, 17401.607871370292, 4711.222648263041, 4875.951240487585, 4913.1647619904525, 4920.557149856512, 4921.212605983025, 3489.825508724566, 5346.946530534703, 5645.125005624135, 5651.8304450866335, 5732.2261494698305, 5413.972930135364, 5645.520130788867, 5669.206311116426, 5673.8581535461535, 5717.514011309044, 5558.44415558443, 5577.274954255941, 5606.635568688022, 5626.212330273765, 5638.464345887155, 3378.6114596821435, 5081.136206844845, 5440.972795136038, 5478.972605136989, 5482.17767334903, 11426.143039272089, 11517.769644607144, 11557.653270401901, 16234.594135146597, 20648.587028259502, 1925.047101684675, 1939.660559402099, 2016.9092390842447, 2196.2971849008322, 2289.7252329720372, 8722.215000649909, 9318.068193180696, 9704.0, 11983.520659173622, 12638.368081595883, 1760.287083731091, 1825.561845260009, 1861.3857427049177, 1870.5697689531348, 1877.0802306869673, 57588.0, 61058.38941610616, 65213.0, 65676.6716166421, 68456.31543684549, 9740.902590974141, 9925.602975880884, 10102.393856368495, 10251.743706407251, 10262.538185781657, 13961.720765584834, 14062.22657753813, 14064.2967851607, 14066.367013484212, 14081.647958801112, 6748.392644662004, 6815.246119910188, 7008.649567521604, 7017.052697885822, 7086.50394472382, 6299.748010079637, 6321.336259692758, 6479.481641468671, 6504.5121615879, 6525.412712855866, 4339.7396156230725, 4471.485779135376, 4483.910321793546, 4519.344695019204, 4527.84152554659, 1525.3745964154643, 2716.660417447819, 3290.7696461247483, 3373.8481768320485, 3496.860125594948, 794.3843521270945, 870.2907130688469, 915.6383228624655, 923.3351986569659, 948.4783369146942, 15965.28156232949, 16058.0, 16776.51946391841, 16897.239624216938, 17650.205740741698, 832.256140001979, 843.3169133002287, 858.7295002074327, 873.7029410000504, 874.1739056591587, 9252.90747092534, 9496.43021418717, 9666.806663866684, 9676.951615241878, 9843.606255749834, 284621.0, 290696.5465172661, 295918.0, 408460.0, 410976.0, 80025.0, 86047.0, 87552.56223718968, 87591.0, 87856.0, 894.3605322194662, 918.0727465260136, 921.7833809054866, 946.5740416812462, 949.9002604726543, 15486.5354039379, 15716.292766825749, 15731.370745170065, 15924.124173170587, 16244.106574138063, 31612.209694757814, 36220.4566931491, 36700.44949325816, 37491.0, 38405.0, 46211.537884620724, 53483.7325813384, 53741.73129134329, 53777.0, 53942.19086713781, 7213.2065472799, 7722.3049925506975, 7784.532928024337, 7888.3689304856625, 8022.0, 9763.80472390534, 11533.1926765129, 11607.5937342196, 11696.298222106361, 11721.531138754355, 4713.08094921487, 4788.042391521779, 8400.915990840012, 9331.766705832408, 9839.0, 9041.864372034557, 9424.246060315296, 9657.420554766737, 9664.71005869825, 10658.307210031324, 9048.276137909083, 9248.121428464285, 9488.193503552275, 9568.521573921415, 10100.343477673927, 6743.763968261087, 6974.616396098263, 7191.2449192834, 7243.0, 7301.233370496234, 8492.0, 9065.637374504946, 9222.492762898106, 9247.167754902091, 9547.904520954702], "value": 8070.808365199058, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1186.4423720851196, 1369.383777300215, 1398.6992796548743, 1497.880169586433, 1716.6909956207885, 6868.725250989961, 7786.883196752046, 8026.9197308026905, 8094.91905080949, 8309.252167304941, 8129.39029572783, 8131.959340203305, 8201.7129400471, 8203.753887383373, 8204.876926846087, 1927.5662975830396, 2209.07218968033, 2302.412884714404, 2334.0, 2338.8362814602983, 2303.6544518322303, 2352.282553821084, 2353.8469999450067, 2356.7643235676383, 2357.292812156355, 2255.436140964759, 2264.7395549511853, 2272.6704627829035, 2317.4901521665206, 2319.5129022905126, 1847.1687740516686, 2144.9463763405884, 2245.618244898363, 2256.029907139937, 2256.6050941085246, 2282.4978504728933, 2302.6661134135456, 2305.216226483004, 2305.6426253930667, 2306.1582522379213, 8175.427720059598, 8227.54748488833, 8228.958855205745, 8229.83540329196, 8237.876431853527, 4722.268048452495, 4868.367112275417, 4872.683275587111, 4874.8050077997195, 4875.293082503017, 2844.7297506736827, 2871.3826527296524, 3000.369922316316, 3020.199647093522, 3024.561438591414, 974.9317547771659, 1002.904724051214, 1011.0900189829139, 1012.0790081026231, 1014.9188064954786, 10924.126069914388, 10967.0, 10978.780424391467, 10984.01143897053, 11013.394263315435, 4379.956200438019, 4459.888502787457, 4671.85984420468, 4674.0, 4748.477667456577, 8777.385583009129, 8824.691135810344, 8880.245179159714, 8880.51157186348, 8892.8666070009, 4867.196912509467, 4867.610591152699, 4872.342233798392, 4872.342233798392, 4872.78072486739, 3822.808859557011, 4015.8996025099605, 4436.157130145262, 4452.866414007585, 4516.23224051912, 3475.7740746851373, 3491.336646037245, 3568.411212149966, 3593.137646964762, 3598.9100272492874, 1058.142904247562, 1070.7430216747925, 1077.7628921637227, 1084.6258040975852, 1085.7719878825378, 781.1992707474884, 791.6556298010383, 881.6605606841284, 894.9373543851998, 920.636348642296, 5499.615026948155, 5499.890002199933, 5567.582431317667, 5607.915881261784, 5752.712364381848, 857.3826844671827, 895.2837729816121, 895.737010814748, 917.3807679816231, 918.2195134135931, 3541.557305336833, 3610.133567943727, 3668.137987572918, 3687.59436461989, 3723.8138093095235, 10014.949925250326, 10223.795524089477, 10241.84637230442, 10354.068133867988, 10357.844632330522, 10184.083432490963, 10223.59105635781, 10237.0, 10249.077583017564, 10260.743481413025, 7518.812029699192, 7547.886781698279, 7655.0813902332075, 7723.111842138113, 7725.884111738328, 5501.257330260363, 5566.415526369835, 5611.57913156523, 5620.634658747168, 5765.308163020464, 9280.767980800534, 9449.102335278138, 9533.809323813624, 9581.137697607248, 9589.904100958902, 9192.402493837879, 9547.4748888809, 9560.043995600663, 9571.952140239255, 9606.615735370508, 6322.462590679844, 6899.586024838325, 7125.35871771543, 7149.356557909813, 7261.092363454568, 7893.644785984759, 7932.0, 7967.84064318722, 7968.0, 7998.280154786097, 6856.554323968926, 7048.0, 7123.323284287984, 7137.714491420493, 7259.637018148966, 6887.414569761627, 7007.264237255219, 7097.0, 7102.893456598258, 7209.315115064061, 5882.323532793784, 6020.789272375446, 6058.0610005449225, 6061.787837425669, 6064.787732429344, 4702.882427939329, 4762.452317983338, 4770.117528257283, 4792.640551958687, 4870.488598697086, 4779.474257831566, 4795.736234507136, 4804.135255654016, 4841.588464980516, 4863.878403039952], "value": 4280.611836778378, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.7372672638169, 308.834773396233, 318.41729634768376, 320.36567596159597, 322.8208344368876, 4732.90534189316, 4864.148773964555, 4867.53758392953, 4931.679440836348, 4959.47066995832, 4647.976760116203, 4828.444728856182, 4830.275458681191, 4839.7338146401935, 4867.951320486794, 3840.961590384088, 3856.4408160816665, 3866.729328946975, 3870.283997460479, 3875.8837234883, 3630.782153070811, 3745.576680861269, 3876.8061596920184, 3885.8834234972855, 3886.9805650971707, 3845.9807700961455, 3859.459675645398, 3860.9034774130732, 3863.3625451800417, 3865.1496670732395, 3828.3109040372724, 3837.2709185254853, 3848.8652897148463, 3850.788206648633, 3870.7290489665734, 3444.50054742061, 3448.3620530201742, 3451.0, 3452.7928324300624, 3452.8100954447495, 4537.0, 4742.5731684148595, 4754.66717329787, 4756.833510827105, 4762.0, 4302.472622219098, 4362.389265502832, 4365.2142614329405, 4387.890302742457, 4410.669199810027, 5046.646734728571, 5052.539010777629, 5079.87300317495, 5084.567811736007, 5102.285680004802, 3605.6118394418336, 3721.0, 3730.6082861299715, 3741.8129093545485, 3755.342815007363, 5285.603579731535, 5324.946750532484, 5341.813036543741, 5342.626016178869, 5363.758630861659, 3839.788811615388, 3841.250956063541, 3843.5408132680254, 3846.480725102075, 3865.468118382464, 3786.5645450772963, 3940.665043471337, 3941.2708648900043, 3951.565327813938, 3980.402939559089, 4354.869353919387, 4372.256716358227, 4388.341748737715, 4392.341148827638, 4397.802098905557, 5015.746063484129, 5068.569171620454, 5071.13790655582, 5095.770690318945, 5100.617453690914, 1274.6813296675832, 1292.9030322725832, 1336.2650542201945, 1340.4035204333977, 1344.4420565465418, 869.9391042627082, 879.9528561012374, 889.6130183370254, 892.8125093730389, 895.7504281527363, 4030.911653853443, 4033.69747268956, 4055.655269302142, 4060.2082593894497, 4067.593240675909, 4590.954090459053, 4715.221988371949, 4725.763711814396, 4730.574248317599, 4732.266498692708, 3994.9201015980098, 4020.6582440492884, 4022.899427514277, 4044.8179831907637, 4058.2086493134148, 2503.4603718713224, 2576.922692319196, 2772.792040596923, 2790.8185967912427, 2806.396624725694, 4917.188663870421, 4931.5315045070665, 4946.183879659888, 4953.2074868020945, 4969.453360130383, 5339.81310654133, 5361.169018802092, 5385.515303622693, 5388.568914486832, 5409.107497262908, 4694.765261736899, 4910.975445122752, 4962.429320628174, 4988.850334489971, 5041.8487445376695, 5261.394939581997, 5283.815066472655, 5296.285001524745, 5309.203619457112, 5353.785848566014, 4823.807047718052, 5101.7194054327365, 5135.764468384894, 5153.510416510426, 5165.405978312542, 4873.904221184925, 4912.263160525949, 4917.655764096479, 4940.209566469348, 4950.084234416644, 3714.4985426967014, 3743.5694895087413, 3766.9246615066995, 3796.6583007529457, 3797.2974999625158, 4970.179920313108, 5275.973620132028, 5284.920726189187, 5294.258803767399, 5307.124324486571, 4355.4577679990225, 5052.8484145475695, 5055.34280543527, 5068.783491961976, 5108.412532558803, 5023.34696489454, 5031.924521132113, 5058.494150584912, 5081.822136225214, 5088.7201203932655, 5013.849584512471, 5023.799048038038, 5025.723585202702, 5030.446650868328, 5036.143855544639, 4879.902401952012, 5027.522385373383, 5133.820316288912, 5136.178211486144, 5146.8970620588125, 4975.776090075883, 4994.275830004556, 5022.271770593316, 5022.769421491612, 5052.494750524918], "value": 3831.431184374507, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [662.9834254143649, 704.5490885833068, 723.070853952671, 745.2212438002288, 753.3558807219827, 12640.936795316022, 13077.346132693363, 13297.401616927238, 13339.066265361429, 13352.59942201733, 13598.524051658194, 13676.111052781587, 13718.657033574165, 13751.0, 13820.239886806221, 627.2754968011953, 760.8630446519625, 778.9883151752742, 791.8535071011881, 795.387551585279, 780.8789637606152, 781.2109769133187, 784.725346128856, 785.1716439156687, 786.6184900323339, 832.5629044751494, 833.9749807505784, 834.0408530190269, 834.1533343656193, 835.2607941971369, 645.9612423254596, 781.316348195329, 808.8058865872207, 812.5043723328823, 827.4538804389105, 593.1962191230906, 593.2258402784365, 593.6675461741438, 597.8624916269282, 598.3298705449924, 12007.579734709243, 12052.698682532919, 12057.93971030148, 12063.457144428434, 12082.456289466994, 11829.526818927232, 12174.878251217464, 12349.444275007647, 12527.74722527749, 12538.0, 11787.115966302561, 11877.821832672516, 11879.762404751857, 12030.759384812254, 12049.15655904087, 4221.894452638709, 4259.925276720564, 4303.935440968388, 4329.220740266782, 4471.2398892188085, 51633.0, 53268.467315326736, 53336.199957000674, 57525.0, 57680.0, 10860.185486088574, 11158.386288754034, 11444.313341199555, 11537.250078744855, 11624.244424112574, 20527.178912843618, 20783.584328313653, 20800.791992079885, 20806.167753290003, 20832.270870519762, 7137.10786151731, 7402.777916662509, 7403.8149046274275, 7510.699572017058, 7741.458097933203, 3886.203328317688, 3943.4873466449176, 3989.3018721723583, 3994.7603143811466, 4093.9385909211387, 2988.3126880817535, 3099.7210251077513, 3103.658597554267, 3112.5019996800856, 3115.9844200778853, 497.8879752055831, 512.8615273876107, 521.5434115483205, 524.9133892907704, 539.2935254816203, 588.2646691635456, 591.973361198747, 618.4650277509921, 630.5302549600577, 644.687326646576, 8128.918710812816, 8221.136780638066, 8397.370197235232, 8464.195901389357, 8570.442921210102, 528.8069854503078, 564.2016546586616, 575.9596828221984, 579.1862433281246, 609.6068036116768, 3943.881683549498, 3971.62269584389, 4009.7594144351433, 4123.2165888481095, 4146.647534959562, 76774.0, 92347.0, 93657.531712341, 97138.514307428, 97865.0, 68182.65908670524, 69697.65151174212, 71584.64207679032, 77896.22103779003, 79855.60072199602, 45915.54084459179, 66191.0, 69240.0, 69517.0, 70934.0, 8586.441881277697, 8846.867296990678, 8942.418742781698, 9148.954255228682, 9249.429877411345, 32076.037718868472, 38208.23583528258, 38300.23399531938, 39024.41463378108, 39089.80455097706, 40950.59049409587, 46940.295895560914, 58343.70828145832, 65080.34919650743, 69066.0, 11512.53949841997, 12163.45264463119, 12237.571684990982, 12242.693932651755, 12999.1550549214, 13724.176549407068, 13812.585622431343, 13914.652133696738, 14142.080764750259, 14619.634509137357, 12493.312867792358, 12529.749405012031, 12796.424160912595, 13010.674733131747, 13114.60656180316, 12851.0, 13181.538646147339, 13674.31628418595, 13678.726425471634, 13728.0, 12127.332996684914, 12424.627261182179, 12705.682357941125, 12911.935440322739, 12947.417366218731, 9668.15571155069, 9710.184046626398, 9758.951205243928, 9784.706458806248, 9863.901360986298, 9807.166390856857, 9927.560503727063, 9978.850317245102, 10729.624463143753, 10874.347539147677], "value": 6680.592036848975, "unit": "score"}], "name": "dromaeo_css", "value": 6387.827333846528, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 114.0, 114.0, 115.0, 113.0, 113.0, 113.0, 114.0, 114.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 131.0, 138.0, 190.0, 128.0, 164.0, 154.0, 130.0, 203.0, 130.0], "value": 152.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 162.0, 159.0, 161.0, 161.0, 163.0, 163.0, 162.0, 160.0], "value": 161.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 77.0, 118.0, 78.0, 78.0, 76.0, 76.0, 99.0, 76.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 95.0, 118.0, 87.0, 86.0, 87.0, 119.0, 86.0, 85.0, 125.0], "value": 97.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 118.0, 116.0, 118.0, 118.0, 118.0, 117.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 147.0, 146.0, 147.0, 147.0, 146.0, 146.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 94.0, 96.0, 96.0, 95.0, 96.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 62.0, 62.0, 62.0, 63.0, 62.0, 61.0, 62.0], "value": 62.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0], "value": 65.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 82.0, 82.0, 85.0, 82.0, 86.0, 83.0, 112.0, 82.0], "value": 86.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 135.0, 136.0, 134.0, 138.0, 130.0, 130.0, 141.0, 143.0, 130.0], "value": 136.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 167.0, 171.0, 169.0, 165.0, 175.0, 163.0, 166.0, 168.0, 163.0], "value": 167.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 66.0, 67.0, 67.0, 67.0, 67.0, 66.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1551.5, "alertThreshold": 2.0}]} 08:39:21 INFO - Return code: 0 08:39:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:39:21 INFO - # TBPL SUCCESS # 08:39:21 INFO - Running post-action listener: _resource_record_post_action 08:39:21 INFO - ##### 08:39:21 INFO - ##### Finished run-tests step (success) 08:39:21 INFO - ##### 08:39:21 INFO - Running post-run listener: _resource_record_post_run 08:39:21 INFO - Total resource usage - Wall time: 1205s; CPU: 13.0%; Read bytes: 32768; Write bytes: 555696128; Read time: 24; Write time: 2132028 08:39:21 INFO - TinderboxPrint: CPU usage
13.3% 08:39:21 INFO - TinderboxPrint: I/O read bytes / time
32,768 / 24 08:39:21 INFO - TinderboxPrint: I/O write bytes / time
555,696,128 / 2,132,028 08:39:21 INFO - TinderboxPrint: CPU idle
8,331.6 (86.6%) 08:39:21 INFO - TinderboxPrint: CPU user
1,216.0 (12.6%) 08:39:21 INFO - TinderboxPrint: Swap in / out
0 / 0 08:39:21 INFO - install - Wall time: 17s; CPU: 22.0%; Read bytes: 0; Write bytes: 259510272; Read time: 0; Write time: 1460048 08:39:21 INFO - run-tests - Wall time: 1189s; CPU: 13.0%; Read bytes: 8192; Write bytes: 296185856; Read time: 12; Write time: 671980 08:39:21 INFO - Running post-run listener: _upload_blobber_files 08:39:21 INFO - Blob upload gear active. 08:39:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:39:21 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 08:39:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:39:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:39:22 INFO - (blobuploader) - INFO - Open directory for files ... 08:39:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:39:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:39:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:39:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:39:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:39:25 INFO - (blobuploader) - INFO - Done attempting. 08:39:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:39:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:39:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:39:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:39:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:39:26 INFO - (blobuploader) - INFO - Done attempting. 08:39:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:39:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:39:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:39:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:39:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:39:27 INFO - (blobuploader) - INFO - Done attempting. 08:39:27 INFO - (blobuploader) - INFO - Iteration through files over. 08:39:27 INFO - Return code: 0 08:39:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:39:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:39:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2196367d04148a0f01d7783f394ab36dc4c2f51d3b0e5b20e6721751b175e5cebdf27d488e8211e107a82d85d170aae752300a958eee8058296ce2b1bc47356c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e23209287b3b7e7b60d4f1df2be9daf31c90d3481ea52ba887a711828501ec65c5853878f5ddd55564d0143738c95290503f251e85cc7db454cdf7d3af2f4151", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bd3e22b793016cb218f2b119363c6d3aed3c19452ef144d5d276d36319bd1b1ae148e56b4cd0d9963ba481c69677b7389a89d38692656a290f55e2b46b1fb6ed"} 08:39:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:39:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:39:27 INFO - Contents: 08:39:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2196367d04148a0f01d7783f394ab36dc4c2f51d3b0e5b20e6721751b175e5cebdf27d488e8211e107a82d85d170aae752300a958eee8058296ce2b1bc47356c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e23209287b3b7e7b60d4f1df2be9daf31c90d3481ea52ba887a711828501ec65c5853878f5ddd55564d0143738c95290503f251e85cc7db454cdf7d3af2f4151", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bd3e22b793016cb218f2b119363c6d3aed3c19452ef144d5d276d36319bd1b1ae148e56b4cd0d9963ba481c69677b7389a89d38692656a290f55e2b46b1fb6ed"} 08:39:27 INFO - Running post-run listener: copy_logs_to_upload_dir 08:39:27 INFO - Copying logs to upload dir... 08:39:27 INFO - mkdir: /builds/slave/test/build/upload/logs 08:39:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.792375 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-06-28 08:39:27.485365) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-28 08:39:27.489048) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1467127119.136077-232950574 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2196367d04148a0f01d7783f394ab36dc4c2f51d3b0e5b20e6721751b175e5cebdf27d488e8211e107a82d85d170aae752300a958eee8058296ce2b1bc47356c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e23209287b3b7e7b60d4f1df2be9daf31c90d3481ea52ba887a711828501ec65c5853878f5ddd55564d0143738c95290503f251e85cc7db454cdf7d3af2f4151", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bd3e22b793016cb218f2b119363c6d3aed3c19452ef144d5d276d36319bd1b1ae148e56b4cd0d9963ba481c69677b7389a89d38692656a290f55e2b46b1fb6ed"} build_url:https://queue.taskcluster.net/v1/task/eLYp0e0RTkyeVRt5CPLjQA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011284 build_url: 'https://queue.taskcluster.net/v1/task/eLYp0e0RTkyeVRt5CPLjQA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2196367d04148a0f01d7783f394ab36dc4c2f51d3b0e5b20e6721751b175e5cebdf27d488e8211e107a82d85d170aae752300a958eee8058296ce2b1bc47356c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e23209287b3b7e7b60d4f1df2be9daf31c90d3481ea52ba887a711828501ec65c5853878f5ddd55564d0143738c95290503f251e85cc7db454cdf7d3af2f4151", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bd3e22b793016cb218f2b119363c6d3aed3c19452ef144d5d276d36319bd1b1ae148e56b4cd0d9963ba481c69677b7389a89d38692656a290f55e2b46b1fb6ed"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-28 08:39:27.514981) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 08:39:27.515311) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1467127119.136077-232950574 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004434 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-28 08:39:27.597482) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-28 08:39:27.597817) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-28 08:39:27.598119) ========= ========= Total master_lag: 0.17 =========