builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-032 starttime: 1466440539.86 results: success (0) buildid: 20160620065724 builduid: 1972fe5da8ca419c9a20e98698a337ee revision: dd0fbfbe790f880c80f126916e1c4e38436f9ad6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-20 09:35:39.861847) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-20 09:35:39.862595) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-20 09:35:39.863183) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-20 09:35:39.914436) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 09:35:39.914895) ========= 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=745a4373a2a16df560f216e000000264-1466439366.69894-678773580 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-20 09:35: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% 224M=0s 2016-06-20 09:35:40 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.201162 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 09:35:40.131625) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 09:35:40.131963) ========= 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=745a4373a2a16df560f216e000000264-1466439366.69894-678773580 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018349 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 09:35:40.183800) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 09:35:40.184651) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev dd0fbfbe790f880c80f126916e1c4e38436f9ad6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev dd0fbfbe790f880c80f126916e1c4e38436f9ad6 --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=745a4373a2a16df560f216e000000264-1466439366.69894-678773580 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-20 09:35:40,260 truncating revision to first 12 chars 2016-06-20 09:35:40,260 Setting DEBUG logging. 2016-06-20 09:35:40,260 attempt 1/10 2016-06-20 09:35:40,260 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/dd0fbfbe790f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-20 09:35:40,579 unpacking tar archive at: mozilla-aurora-dd0fbfbe790f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.728455 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 09:35:40.930587) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-20 09:35:40.930895) ========= script_repo_revision: dd0fbfbe790f880c80f126916e1c4e38436f9ad6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-20 09:35:40.931349) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-20 09:35:40.931674) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-20 09:35:40.940494) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-06-20 09:35:40.940806) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1466439366.69894-678773580 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:35:41 INFO - MultiFileLogger online at 20160620 09:35:41 in /builds/slave/test 09:35:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:35:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:35:41 INFO - {'append_to_log': False, 09:35:41 INFO - 'base_work_dir': '/builds/slave/test', 09:35:41 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:35:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:35:41 INFO - 'branch': 'Mozilla-Aurora', 09:35:41 INFO - 'buildbot_json_path': 'buildprops.json', 09:35:41 INFO - 'config_files': ('talos/linux_config.py',), 09:35:41 INFO - 'default_actions': ('clobber', 09:35:41 INFO - 'read-buildbot-config', 09:35:41 INFO - 'download-and-extract', 09:35:41 INFO - 'populate-webroot', 09:35:41 INFO - 'create-virtualenv', 09:35:41 INFO - 'install', 09:35:41 INFO - 'run-tests'), 09:35:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:35:41 INFO - 'download_minidump_stackwalk': True, 09:35:41 INFO - 'download_symbols': 'ondemand', 09:35:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:35:41 INFO - 'tooltool.py': '/tools/tooltool.py', 09:35:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:35:41 INFO - '/tools/misc-python/virtualenv.py')}, 09:35:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:35:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:35:41 INFO - 'installer_path': 'installer.exe', 09:35:41 INFO - 'log_level': 'info', 09:35:41 INFO - 'log_name': 'talos', 09:35:41 INFO - 'log_to_console': True, 09:35:41 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:35:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:35:41 INFO - 'opt_config_files': (), 09:35:41 INFO - 'pip_index': False, 09:35:41 INFO - 'sps_profile': False, 09:35:41 INFO - 'sps_profile_interval': 0, 09:35:41 INFO - 'suite': 'dromaeojs', 09:35:41 INFO - 'system_bits': '32', 09:35:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:35:41 INFO - 'title': 'talos-linux64-ix-032', 09:35:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:35:41 INFO - 'use_talos_json': True, 09:35:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:35:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:35:41 INFO - 'work_dir': 'build'} 09:35:41 INFO - ##### 09:35:41 INFO - ##### Running clobber step. 09:35:41 INFO - ##### 09:35:41 INFO - Running pre-action listener: _resource_record_pre_action 09:35:41 INFO - Running main action method: clobber 09:35:41 INFO - rmtree: /builds/slave/test/build 09:35:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:35:41 INFO - Running post-action listener: _resource_record_post_action 09:35:41 INFO - ##### 09:35:41 INFO - ##### Finished clobber step (success) 09:35:41 INFO - ##### 09:35:41 INFO - ##### 09:35:41 INFO - ##### Running read-buildbot-config step. 09:35:41 INFO - ##### 09:35:41 INFO - Running pre-action listener: _resource_record_pre_action 09:35:41 INFO - Running main action method: read_buildbot_config 09:35:41 INFO - Using buildbot properties: 09:35:41 INFO - { 09:35:41 INFO - "project": "", 09:35:41 INFO - "product": "firefox", 09:35:41 INFO - "script_repo_revision": "production", 09:35:41 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:35:41 INFO - "repository": "", 09:35:41 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:35:41 INFO - "buildid": "20160620065724", 09:35:41 INFO - "pgo_build": "True", 09:35:41 INFO - "basedir": "/builds/slave/test", 09:35:41 INFO - "buildnumber": 568, 09:35:41 INFO - "slavename": "talos-linux64-ix-032", 09:35:41 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:35:41 INFO - "platform": "ubuntu64_hw", 09:35:41 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:35:41 INFO - "branch": "mozilla-aurora", 09:35:41 INFO - "repo_path": "releases/mozilla-aurora", 09:35:41 INFO - "revision": "dd0fbfbe790f880c80f126916e1c4e38436f9ad6", 09:35:41 INFO - "stage_platform": "linux64-pgo", 09:35:41 INFO - "builduid": "1972fe5da8ca419c9a20e98698a337ee", 09:35:41 INFO - "slavebuilddir": "test" 09:35:41 INFO - } 09:35:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 09:35:41 INFO - Running post-action listener: _resource_record_post_action 09:35:41 INFO - ##### 09:35:41 INFO - ##### Finished read-buildbot-config step (success) 09:35:41 INFO - ##### 09:35:41 INFO - ##### 09:35:41 INFO - ##### Running download-and-extract step. 09:35:41 INFO - ##### 09:35:41 INFO - Running pre-action listener: _resource_record_pre_action 09:35:41 INFO - Running main action method: download_and_extract 09:35:41 INFO - mkdir: /builds/slave/test/build/tests 09:35: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')]} 09:35:41 INFO - https://queue.taskcluster.net/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:35:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 09:35:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 09:35:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 09:35:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:35:51 INFO - Downloaded 1361 bytes. 09:35:51 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 09:35:51 INFO - Using the following test package requirements: 09:35:51 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 09:35:51 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:35:51 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:35:51 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:35:51 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:35:51 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:35:51 INFO - u'jsshell-linux-x86_64.zip'], 09:35:51 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:35:51 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:35:51 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 09:35:51 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:35:51 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:35:51 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:35:51 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:35:51 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:35:51 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:35:51 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 09:35:51 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:35:51 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:35:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:35:51 INFO - https://queue.taskcluster.net/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:35:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:35:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:35:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:35:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:35:59 INFO - Downloaded 24368004 bytes. 09:35:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:35:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:36:01 INFO - Return code: 0 09:36:01 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:36: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')]} 09:36:01 INFO - https://queue.taskcluster.net/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:36:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:36:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:36:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 09:36:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:36:02 INFO - Downloaded 24368004 bytes. 09:36:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:36:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:36:03 INFO - Return code: 0 09:36:03 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')]} 09:36:03 INFO - https://queue.taskcluster.net/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:36:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 09:36:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 09:36:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 09:36:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:36:04 INFO - Downloaded 10833369 bytes. 09:36:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:36:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:36:04 INFO - Return code: 0 09:36: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')]} 09:36:04 INFO - https://queue.taskcluster.net/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:36:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:36:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:36:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:36:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:36:08 INFO - Downloaded 62811232 bytes. 09:36:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:36:08 INFO - mkdir: /builds/slave/test/properties 09:36:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:36:08 INFO - Writing to file /builds/slave/test/properties/build_url 09:36:08 INFO - Contents: 09:36:08 INFO - build_url:https://queue.taskcluster.net/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 09:36:09 INFO - Running post-action listener: _resource_record_post_action 09:36:09 INFO - Running post-action listener: set_extra_try_arguments 09:36:09 INFO - ##### 09:36:09 INFO - ##### Finished download-and-extract step (success) 09:36:09 INFO - ##### 09:36:09 INFO - ##### 09:36:09 INFO - ##### Running populate-webroot step. 09:36:09 INFO - ##### 09:36:09 INFO - Running pre-action listener: _resource_record_pre_action 09:36:09 INFO - Running main action method: populate_webroot 09:36:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:36:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:36:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:36:09 INFO - '--tppagecycles', 09:36:09 INFO - '7'], 09:36:09 INFO - 'tests': ['tsvgm']}}, 09:36:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:36:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:36:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:36:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:36:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:36:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:36:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:09 INFO - 'talos_options': ['--disable-e10s'], 09:36:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:36:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:36:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:09 INFO - 'talos_options': ['--disable-e10s'], 09:36:09 INFO - 'tests': ['damp', 'tps']}, 09:36:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:09 INFO - 'tests': ['damp', 'tps']}, 09:36:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:36:09 INFO - 'tests': ['dromaeo_dom']}, 09:36:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:36:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:36:09 INFO - 'tests': ['a11yr', 09:36:09 INFO - 'ts_paint', 09:36:09 INFO - 'tpaint', 09:36:09 INFO - 'sessionrestore', 09:36:09 INFO - 'sessionrestore_no_auto_restore', 09:36:09 INFO - 'tabpaint']}, 09:36:09 INFO - 'other-e10s': {'tests': ['a11yr', 09:36:09 INFO - 'ts_paint', 09:36:09 INFO - 'tpaint', 09:36:09 INFO - 'sessionrestore', 09:36:09 INFO - 'sessionrestore_no_auto_restore', 09:36:09 INFO - 'tabpaint']}, 09:36:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:36:09 INFO - 'ts_paint', 09:36:09 INFO - 'tpaint', 09:36:09 INFO - 'sessionrestore', 09:36:09 INFO - 'sessionrestore_no_auto_restore', 09:36:09 INFO - 'tabpaint']}, 09:36:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:36:09 INFO - 'ts_paint', 09:36:09 INFO - 'tpaint', 09:36:09 INFO - 'sessionrestore', 09:36:09 INFO - 'sessionrestore_no_auto_restore', 09:36:09 INFO - 'tabpaint']}, 09:36:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:36:09 INFO - 'tests': ['a11yr', 09:36:09 INFO - 'ts_paint', 09:36:09 INFO - 'tpaint', 09:36:09 INFO - 'sessionrestore', 09:36:09 INFO - 'sessionrestore_no_auto_restore', 09:36:09 INFO - 'tabpaint']}, 09:36:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:36:09 INFO - 'tests': ['a11yr', 09:36:09 INFO - 'ts_paint', 09:36:09 INFO - 'tpaint', 09:36:09 INFO - 'sessionrestore', 09:36:09 INFO - 'sessionrestore_no_auto_restore', 09:36:09 INFO - 'tabpaint']}, 09:36:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:36:09 INFO - 'tests': ['tsvgx', 09:36:09 INFO - 'tsvgr_opacity', 09:36:09 INFO - 'tart', 09:36:09 INFO - 'tscrollx', 09:36:09 INFO - 'cart']}, 09:36:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:36:09 INFO - 'tsvgr_opacity', 09:36:09 INFO - 'tart', 09:36:09 INFO - 'tscrollx', 09:36:09 INFO - 'cart']}, 09:36:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:09 INFO - 'talos_options': ['--disable-e10s'], 09:36:09 INFO - 'tests': ['tp5o']}, 09:36:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:09 INFO - 'tests': ['tp5o']}, 09:36:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:09 INFO - 'talos_options': ['--disable-e10s', 09:36:09 INFO - '--xperf_path', 09:36:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:36:09 INFO - 'tests': ['tp5n']}, 09:36:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:36:09 INFO - 'talos_options': ['--xperf_path', 09:36:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:36:09 INFO - 'tests': ['tp5n']}}, 09:36:09 INFO - 'talos.zip': {'path': '', 09:36:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:36:09 INFO - Running post-action listener: _resource_record_post_action 09:36:09 INFO - ##### 09:36:09 INFO - ##### Finished populate-webroot step (success) 09:36:09 INFO - ##### 09:36:09 INFO - ##### 09:36:09 INFO - ##### Running create-virtualenv step. 09:36:09 INFO - ##### 09:36:09 INFO - Running pre-action listener: _resource_record_pre_action 09:36:09 INFO - Running main action method: create_virtualenv 09:36:09 INFO - Creating virtualenv /builds/slave/test/build/venv 09:36:09 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 09:36:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:36:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:36:09 INFO - Using real prefix '/usr' 09:36:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:36:10 INFO - Installing distribute.............................................................................................................................................................................................done. 09:36:12 INFO - Installing pip.................done. 09:36:12 INFO - Return code: 0 09:36:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:36: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')]} 09:36:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36: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')]} 09:36:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6b96040f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22563b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e8990>, '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 0x2254470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2254900>, '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': '745a4373a2a16df560f216e000000264-1466439366.69894-678773580', '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 09:36: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', 'pip>=1.5'] in /builds/slave/test/build 09:36: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 pip>=1.5 09:36:12 INFO - Using env: {'DISPLAY': ':0', 09:36:12 INFO - 'HOME': '/home/cltbld', 09:36:12 INFO - 'LANG': 'en_US.UTF-8', 09:36:12 INFO - 'LANGUAGE': 'en_US:en', 09:36:12 INFO - 'LOGNAME': 'cltbld', 09:36:12 INFO - 'MAIL': '/var/mail/cltbld', 09:36:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:12 INFO - 'MOZ_NO_REMOTE': '1', 09:36:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:12 INFO - 'NO_EM_RESTART': '1', 09:36:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:12 INFO - 'PWD': '/builds/slave/test', 09:36:12 INFO - 'SHELL': '/bin/bash', 09:36:12 INFO - 'SHLVL': '1', 09:36:12 INFO - 'TERM': 'linux', 09:36:12 INFO - 'TMOUT': '86400', 09:36:12 INFO - 'USER': 'cltbld', 09:36:12 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1466439366.69894-678773580', 09:36:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:12 INFO - '_': '/tools/buildbot/bin/python'} 09:36:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:12 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 09:36:12 INFO - Cleaning up... 09:36:12 INFO - Return code: 0 09:36:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:36: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')]} 09:36:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36: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')]} 09:36:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36: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', '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 0x7f6b96040f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22563b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e8990>, '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 0x2254470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2254900>, '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': '745a4373a2a16df560f216e000000264-1466439366.69894-678773580', '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 09:36: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', 'psutil>=3.1.1'] in /builds/slave/test/build 09:36: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 psutil>=3.1.1 09:36:12 INFO - Using env: {'DISPLAY': ':0', 09:36:12 INFO - 'HOME': '/home/cltbld', 09:36:12 INFO - 'LANG': 'en_US.UTF-8', 09:36:12 INFO - 'LANGUAGE': 'en_US:en', 09:36:12 INFO - 'LOGNAME': 'cltbld', 09:36:12 INFO - 'MAIL': '/var/mail/cltbld', 09:36:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:12 INFO - 'MOZ_NO_REMOTE': '1', 09:36:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:12 INFO - 'NO_EM_RESTART': '1', 09:36:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:12 INFO - 'PWD': '/builds/slave/test', 09:36:12 INFO - 'SHELL': '/bin/bash', 09:36:12 INFO - 'SHLVL': '1', 09:36:12 INFO - 'TERM': 'linux', 09:36:12 INFO - 'TMOUT': '86400', 09:36:12 INFO - 'USER': 'cltbld', 09:36:12 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1466439366.69894-678773580', 09:36:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:12 INFO - '_': '/tools/buildbot/bin/python'} 09:36:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:12 INFO - Downloading/unpacking psutil>=3.1.1 09:36: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 09:36: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 09:36: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 09:36: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 09:36: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 09:36: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 09:36:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:36:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:36:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:36:14 INFO - Installing collected packages: psutil 09:36:14 INFO - Running setup.py install for psutil 09:36:14 INFO - building 'psutil._psutil_linux' extension 09:36:14 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 09:36:14 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 09:36:14 INFO - building 'psutil._psutil_posix' extension 09:36:14 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 09:36:15 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 09:36:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:36:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:36:15 INFO - Successfully installed psutil 09:36:15 INFO - Cleaning up... 09:36:15 INFO - Return code: 0 09:36:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:36:15 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')]} 09:36:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:15 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')]} 09:36:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:15 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 0x7f6b96040f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22563b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e8990>, '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 0x2254470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2254900>, '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': '745a4373a2a16df560f216e000000264-1466439366.69894-678773580', '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 09:36:15 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 09:36:15 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 09:36:15 INFO - Using env: {'DISPLAY': ':0', 09:36:15 INFO - 'HOME': '/home/cltbld', 09:36:15 INFO - 'LANG': 'en_US.UTF-8', 09:36:15 INFO - 'LANGUAGE': 'en_US:en', 09:36:15 INFO - 'LOGNAME': 'cltbld', 09:36:15 INFO - 'MAIL': '/var/mail/cltbld', 09:36:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:15 INFO - 'MOZ_NO_REMOTE': '1', 09:36:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:15 INFO - 'NO_EM_RESTART': '1', 09:36:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:15 INFO - 'PWD': '/builds/slave/test', 09:36:15 INFO - 'SHELL': '/bin/bash', 09:36:15 INFO - 'SHLVL': '1', 09:36:15 INFO - 'TERM': 'linux', 09:36:15 INFO - 'TMOUT': '86400', 09:36:15 INFO - 'USER': 'cltbld', 09:36:15 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1466439366.69894-678773580', 09:36:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:15 INFO - '_': '/tools/buildbot/bin/python'} 09:36:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:36:15 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 09:36:15 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 09:36:15 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 09:36:15 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 09:36:15 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 09:36:15 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 09:36:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:36:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:36:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:36:17 INFO - Installing collected packages: mozsystemmonitor 09:36:17 INFO - Running setup.py install for mozsystemmonitor 09:36:17 INFO - Successfully installed mozsystemmonitor 09:36:17 INFO - Cleaning up... 09:36:17 INFO - Return code: 0 09:36:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:36:17 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')]} 09:36:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:17 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')]} 09:36:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:17 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 0x7f6b96040f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22563b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e8990>, '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 0x2254470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2254900>, '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': '745a4373a2a16df560f216e000000264-1466439366.69894-678773580', '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 09:36:17 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 09:36:17 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 09:36:17 INFO - Using env: {'DISPLAY': ':0', 09:36:17 INFO - 'HOME': '/home/cltbld', 09:36:17 INFO - 'LANG': 'en_US.UTF-8', 09:36:17 INFO - 'LANGUAGE': 'en_US:en', 09:36:17 INFO - 'LOGNAME': 'cltbld', 09:36:17 INFO - 'MAIL': '/var/mail/cltbld', 09:36:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:17 INFO - 'MOZ_NO_REMOTE': '1', 09:36:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:17 INFO - 'NO_EM_RESTART': '1', 09:36:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:17 INFO - 'PWD': '/builds/slave/test', 09:36:17 INFO - 'SHELL': '/bin/bash', 09:36:17 INFO - 'SHLVL': '1', 09:36:17 INFO - 'TERM': 'linux', 09:36:17 INFO - 'TMOUT': '86400', 09:36:17 INFO - 'USER': 'cltbld', 09:36:17 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1466439366.69894-678773580', 09:36:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:17 INFO - '_': '/tools/buildbot/bin/python'} 09:36:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:17 INFO - Downloading/unpacking jsonschema==2.5.1 09:36:17 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 09:36:17 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 09:36:17 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 09:36:17 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 09:36:17 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 09:36:17 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 09:36:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:36:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:36:19 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 09:36:19 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 09:36:19 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 09:36:19 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 09:36:19 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 09:36:19 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 09:36:19 INFO - Downloading functools32-3.2.3-2.tar.gz 09:36:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:36:20 INFO - warning: no files found matching '*.txt' 09:36:20 INFO - no previously-included directories found matching 'build' 09:36:20 INFO - no previously-included directories found matching 'dist' 09:36:20 INFO - no previously-included directories found matching '.git*' 09:36:20 INFO - Installing collected packages: jsonschema, functools32 09:36:20 INFO - Running setup.py install for functools32 09:36:20 INFO - warning: no files found matching '*.txt' 09:36:20 INFO - no previously-included directories found matching 'build' 09:36:20 INFO - no previously-included directories found matching 'dist' 09:36:20 INFO - no previously-included directories found matching '.git*' 09:36:20 INFO - Successfully installed jsonschema functools32 09:36:20 INFO - Cleaning up... 09:36:20 INFO - Return code: 0 09:36:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:36:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f6b96040f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22563b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e8990>, '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 0x2254470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2254900>, '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': '745a4373a2a16df560f216e000000264-1466439366.69894-678773580', '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 09:36:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:36:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:36:20 INFO - Using env: {'DISPLAY': ':0', 09:36:20 INFO - 'HOME': '/home/cltbld', 09:36:20 INFO - 'LANG': 'en_US.UTF-8', 09:36:20 INFO - 'LANGUAGE': 'en_US:en', 09:36:20 INFO - 'LOGNAME': 'cltbld', 09:36:20 INFO - 'MAIL': '/var/mail/cltbld', 09:36:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:20 INFO - 'MOZ_NO_REMOTE': '1', 09:36:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:20 INFO - 'NO_EM_RESTART': '1', 09:36:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:20 INFO - 'PWD': '/builds/slave/test', 09:36:20 INFO - 'SHELL': '/bin/bash', 09:36:20 INFO - 'SHLVL': '1', 09:36:20 INFO - 'TERM': 'linux', 09:36:20 INFO - 'TMOUT': '86400', 09:36:20 INFO - 'USER': 'cltbld', 09:36:20 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1466439366.69894-678773580', 09:36:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:20 INFO - '_': '/tools/buildbot/bin/python'} 09:36:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:20 INFO - Downloading/unpacking blobuploader==1.2.4 09:36:20 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 09:36:20 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 09:36:20 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 09:36:20 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 09:36:20 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 09:36:20 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 09:36:22 INFO - Downloading blobuploader-1.2.4.tar.gz 09:36:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:36:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:36:22 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 09:36:22 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 09:36:22 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 09:36:22 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 09:36:22 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 09:36:22 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 09:36:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:36:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:36:22 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 09:36:22 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 09:36:22 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 09:36:22 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 09:36:22 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 09:36:22 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 09:36:23 INFO - Downloading docopt-0.6.1.tar.gz 09:36:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:36:23 INFO - Installing collected packages: blobuploader, requests, docopt 09:36:23 INFO - Running setup.py install for blobuploader 09:36:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:36:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:36:23 INFO - Running setup.py install for requests 09:36:23 INFO - Running setup.py install for docopt 09:36:23 INFO - Successfully installed blobuploader requests docopt 09:36:23 INFO - Cleaning up... 09:36:23 INFO - Return code: 0 09:36:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:36:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:23 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 0x7f6b96040f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22563b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e8990>, '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 0x2254470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2254900>, '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': '745a4373a2a16df560f216e000000264-1466439366.69894-678773580', '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 09:36:23 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 09:36:23 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 09:36:23 INFO - Using env: {'DISPLAY': ':0', 09:36:23 INFO - 'HOME': '/home/cltbld', 09:36:23 INFO - 'LANG': 'en_US.UTF-8', 09:36:23 INFO - 'LANGUAGE': 'en_US:en', 09:36:23 INFO - 'LOGNAME': 'cltbld', 09:36:23 INFO - 'MAIL': '/var/mail/cltbld', 09:36:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:23 INFO - 'MOZ_NO_REMOTE': '1', 09:36:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:23 INFO - 'NO_EM_RESTART': '1', 09:36:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:23 INFO - 'PWD': '/builds/slave/test', 09:36:23 INFO - 'SHELL': '/bin/bash', 09:36:23 INFO - 'SHLVL': '1', 09:36:23 INFO - 'TERM': 'linux', 09:36:23 INFO - 'TMOUT': '86400', 09:36:23 INFO - 'USER': 'cltbld', 09:36:23 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1466439366.69894-678773580', 09:36:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:23 INFO - '_': '/tools/buildbot/bin/python'} 09:36:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:36:23 INFO - Running setup.py (path:/tmp/pip-CjQ45R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:36:24 INFO - Running setup.py (path:/tmp/pip-DiCDSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:36:24 INFO - Running setup.py (path:/tmp/pip-qrOCHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:36:24 INFO - Running setup.py (path:/tmp/pip-RpQtyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:36:24 INFO - Running setup.py (path:/tmp/pip-cjVwVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:36:24 INFO - Running setup.py (path:/tmp/pip-MQSdgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:36:24 INFO - Running setup.py (path:/tmp/pip-vQ3S55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:36:24 INFO - Running setup.py (path:/tmp/pip-fB9jtt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:36:24 INFO - Running setup.py (path:/tmp/pip-aE3lCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:36:24 INFO - Running setup.py (path:/tmp/pip-aZqoWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:36:24 INFO - Running setup.py (path:/tmp/pip-RbKR0z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:36:24 INFO - Running setup.py (path:/tmp/pip-OZePm5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:36:24 INFO - Running setup.py (path:/tmp/pip-BqeKA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:36:24 INFO - Running setup.py (path:/tmp/pip-R6xzYC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:24 INFO - Running setup.py (path:/tmp/pip-uP9buZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:36:25 INFO - Running setup.py (path:/tmp/pip-rDIMZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:36:25 INFO - Running setup.py (path:/tmp/pip-ponsrs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:36:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:36:25 INFO - Running setup.py install for manifestparser 09:36:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:36:25 INFO - Running setup.py install for mozcrash 09:36:25 INFO - Running setup.py install for mozdebug 09:36:25 INFO - Running setup.py install for mozdevice 09:36:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:36:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:36:25 INFO - Running setup.py install for mozfile 09:36:25 INFO - Running setup.py install for mozhttpd 09:36:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:36:25 INFO - Running setup.py install for mozinfo 09:36:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:36:25 INFO - Running setup.py install for mozInstall 09:36:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:36:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:36:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:36:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:36:25 INFO - Running setup.py install for mozleak 09:36:26 INFO - Running setup.py install for mozlog 09:36:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:36:26 INFO - Running setup.py install for moznetwork 09:36:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:36:26 INFO - Running setup.py install for mozprocess 09:36:26 INFO - Running setup.py install for mozprofile 09:36:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:36:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:36:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:36:26 INFO - Running setup.py install for mozrunner 09:36:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:36:26 INFO - Running setup.py install for mozscreenshot 09:36:26 INFO - Running setup.py install for moztest 09:36:26 INFO - Running setup.py install for mozversion 09:36:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:36:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:36:26 INFO - Cleaning up... 09:36:26 INFO - Return code: 0 09:36:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:36:26 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')]} 09:36:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:26 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')]} 09:36:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:26 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 0x7f6b96040f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22563b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e8990>, '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 0x2254470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2254900>, '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': '745a4373a2a16df560f216e000000264-1466439366.69894-678773580', '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 09:36:26 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 09:36:26 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 09:36:26 INFO - Using env: {'DISPLAY': ':0', 09:36:26 INFO - 'HOME': '/home/cltbld', 09:36:26 INFO - 'LANG': 'en_US.UTF-8', 09:36:26 INFO - 'LANGUAGE': 'en_US:en', 09:36:26 INFO - 'LOGNAME': 'cltbld', 09:36:26 INFO - 'MAIL': '/var/mail/cltbld', 09:36:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:26 INFO - 'MOZ_NO_REMOTE': '1', 09:36:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:26 INFO - 'NO_EM_RESTART': '1', 09:36:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:26 INFO - 'PWD': '/builds/slave/test', 09:36:26 INFO - 'SHELL': '/bin/bash', 09:36:26 INFO - 'SHLVL': '1', 09:36:26 INFO - 'TERM': 'linux', 09:36:26 INFO - 'TMOUT': '86400', 09:36:26 INFO - 'USER': 'cltbld', 09:36:26 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1466439366.69894-678773580', 09:36:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:26 INFO - '_': '/tools/buildbot/bin/python'} 09:36:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:36:27 INFO - Running setup.py (path:/tmp/pip-y_xJfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:36:27 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)) 09:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:36:27 INFO - Running setup.py (path:/tmp/pip-ffcdyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:36:27 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)) 09:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:36:27 INFO - Running setup.py (path:/tmp/pip-AzPfWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:36:27 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)) 09:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:36:27 INFO - Running setup.py (path:/tmp/pip-4OJ7Bt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:36:27 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)) 09:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:36:27 INFO - Running setup.py (path:/tmp/pip-Ewvd6q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:36:27 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)) 09:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:36:27 INFO - Running setup.py (path:/tmp/pip-hO3PF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:36:27 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)) 09:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:36:27 INFO - Running setup.py (path:/tmp/pip-HccbSK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:36:27 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)) 09:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:36:27 INFO - Running setup.py (path:/tmp/pip-Xzeocw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:36:27 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)) 09:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:36:27 INFO - Running setup.py (path:/tmp/pip-NXSCzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:36:27 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)) 09:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:36:27 INFO - Running setup.py (path:/tmp/pip-obfrRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:36:27 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)) 09:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:36:27 INFO - Running setup.py (path:/tmp/pip-sBNvlf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:36:27 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)) 09:36:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:36:27 INFO - Running setup.py (path:/tmp/pip-GODRuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:36:28 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)) 09:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:36:28 INFO - Running setup.py (path:/tmp/pip-oDjD8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:36:28 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)) 09:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:36:28 INFO - Running setup.py (path:/tmp/pip-hH0STw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:36:28 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)) 09:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:28 INFO - Running setup.py (path:/tmp/pip-FbODRh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:36:28 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)) 09:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:36:28 INFO - Running setup.py (path:/tmp/pip-Yz2cY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:36:28 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)) 09:36:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:36:28 INFO - Running setup.py (path:/tmp/pip-8KcYim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:36:28 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)) 09:36:28 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)) 09:36:28 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)) 09:36:28 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)) 09:36:28 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)) 09:36:28 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)) 09:36:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:36:28 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 09:36:28 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 09:36:28 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 09:36:28 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 09:36:28 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 09:36:28 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 09:36:30 INFO - Downloading blessings-1.6.tar.gz 09:36:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:36:30 INFO - Installing collected packages: blessings 09:36:30 INFO - Running setup.py install for blessings 09:36:30 INFO - Successfully installed blessings 09:36:30 INFO - Cleaning up... 09:36:30 INFO - Return code: 0 09:36:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:36:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:36:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:36:30 INFO - Reading from file tmpfile_stdout 09:36:30 INFO - Current package versions: 09:36:30 INFO - argparse == 1.2.1 09:36:30 INFO - blessings == 1.6 09:36:30 INFO - blobuploader == 1.2.4 09:36:30 INFO - docopt == 0.6.1 09:36:30 INFO - functools32 == 3.2.3-2 09:36:30 INFO - jsonschema == 2.5.1 09:36:30 INFO - manifestparser == 1.1 09:36:30 INFO - mozInstall == 1.12 09:36:30 INFO - mozcrash == 0.17 09:36:30 INFO - mozdebug == 0.1 09:36:30 INFO - mozdevice == 0.48 09:36:30 INFO - mozfile == 1.2 09:36:30 INFO - mozhttpd == 0.7 09:36:30 INFO - mozinfo == 0.9 09:36:30 INFO - mozleak == 0.1 09:36:30 INFO - mozlog == 3.2 09:36:30 INFO - moznetwork == 0.27 09:36:30 INFO - mozprocess == 0.22 09:36:30 INFO - mozprofile == 0.28 09:36:30 INFO - mozrunner == 6.11 09:36:30 INFO - mozscreenshot == 0.1 09:36:30 INFO - mozsystemmonitor == 0.3 09:36:30 INFO - moztest == 0.7 09:36:30 INFO - mozversion == 1.4 09:36:30 INFO - psutil == 3.1.1 09:36:30 INFO - requests == 1.2.3 09:36:30 INFO - wsgiref == 0.1.2 09:36:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:36:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:30 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 0x7f6b96040f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22563b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e8990>, '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 0x2254470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2254900>, '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': '745a4373a2a16df560f216e000000264-1466439366.69894-678773580', '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 09:36:30 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 09:36:30 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 09:36:30 INFO - Using env: {'DISPLAY': ':0', 09:36:30 INFO - 'HOME': '/home/cltbld', 09:36:30 INFO - 'LANG': 'en_US.UTF-8', 09:36:30 INFO - 'LANGUAGE': 'en_US:en', 09:36:30 INFO - 'LOGNAME': 'cltbld', 09:36:30 INFO - 'MAIL': '/var/mail/cltbld', 09:36:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:30 INFO - 'MOZ_NO_REMOTE': '1', 09:36:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:30 INFO - 'NO_EM_RESTART': '1', 09:36:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:30 INFO - 'PWD': '/builds/slave/test', 09:36:30 INFO - 'SHELL': '/bin/bash', 09:36:30 INFO - 'SHLVL': '1', 09:36:30 INFO - 'TERM': 'linux', 09:36:30 INFO - 'TMOUT': '86400', 09:36:30 INFO - 'USER': 'cltbld', 09:36:30 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1466439366.69894-678773580', 09:36:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:30 INFO - '_': '/tools/buildbot/bin/python'} 09:36:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:30 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)) 09:36:30 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)) 09:36:30 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)) 09:36:30 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)) 09:36:30 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)) 09:36:30 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)) 09:36:30 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)) 09:36:30 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)) 09:36:30 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)) 09:36:30 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)) 09:36:30 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)) 09:36:30 INFO - Cleaning up... 09:36:30 INFO - Return code: 0 09:36:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:36:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:30 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 0x7f6b96040f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22563b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e8990>, '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 0x2254470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2254900>, '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': '745a4373a2a16df560f216e000000264-1466439366.69894-678773580', '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 09:36:30 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 09:36:30 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 09:36:30 INFO - Using env: {'DISPLAY': ':0', 09:36:30 INFO - 'HOME': '/home/cltbld', 09:36:30 INFO - 'LANG': 'en_US.UTF-8', 09:36:30 INFO - 'LANGUAGE': 'en_US:en', 09:36:30 INFO - 'LOGNAME': 'cltbld', 09:36:30 INFO - 'MAIL': '/var/mail/cltbld', 09:36:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:30 INFO - 'MOZ_NO_REMOTE': '1', 09:36:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:30 INFO - 'NO_EM_RESTART': '1', 09:36:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:30 INFO - 'PWD': '/builds/slave/test', 09:36:30 INFO - 'SHELL': '/bin/bash', 09:36:30 INFO - 'SHLVL': '1', 09:36:30 INFO - 'TERM': 'linux', 09:36:30 INFO - 'TMOUT': '86400', 09:36:30 INFO - 'USER': 'cltbld', 09:36:30 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1466439366.69894-678773580', 09:36:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:30 INFO - '_': '/tools/buildbot/bin/python'} 09:36:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:36:31 INFO - Cleaning up... 09:36:31 INFO - Return code: 0 09:36:31 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:36:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:36:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:36:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:36:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6b96040f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21888a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22563b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22e8990>, '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 0x2254470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2254900>, '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': '745a4373a2a16df560f216e000000264-1466439366.69894-678773580', '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 09:36:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:36:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:36:31 INFO - Using env: {'DISPLAY': ':0', 09:36:31 INFO - 'HOME': '/home/cltbld', 09:36:31 INFO - 'LANG': 'en_US.UTF-8', 09:36:31 INFO - 'LANGUAGE': 'en_US:en', 09:36:31 INFO - 'LOGNAME': 'cltbld', 09:36:31 INFO - 'MAIL': '/var/mail/cltbld', 09:36:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:31 INFO - 'MOZ_NO_REMOTE': '1', 09:36:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:31 INFO - 'NO_EM_RESTART': '1', 09:36:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:31 INFO - 'PWD': '/builds/slave/test', 09:36:31 INFO - 'SHELL': '/bin/bash', 09:36:31 INFO - 'SHLVL': '1', 09:36:31 INFO - 'TERM': 'linux', 09:36:31 INFO - 'TMOUT': '86400', 09:36:31 INFO - 'USER': 'cltbld', 09:36:31 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1466439366.69894-678773580', 09:36:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:31 INFO - '_': '/tools/buildbot/bin/python'} 09:36:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:36:31 INFO - Downloading/unpacking flake8 09:36:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:36:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:36:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:36:33 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:36:33 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:36:33 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 09:36:33 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 09:36:33 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 09:36:33 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 09:36:33 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 09:36:33 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 09:36:33 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:36:33 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:36:33 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 09:36:33 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 09:36:33 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 09:36:33 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 09:36:33 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 09:36:33 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 09:36:33 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:36:33 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 09:36:33 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 09:36:33 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 09:36:33 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 09:36:33 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 09:36:33 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 09:36:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:36:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:36:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:36:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:36:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:36:34 INFO - no previously-included directories found matching 'docs/_build' 09:36:34 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:36:34 INFO - Running setup.py install for pep8 09:36:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:36:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:36:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:36:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:36:34 INFO - no previously-included directories found matching 'docs/_build' 09:36:34 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:36:34 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:36:34 INFO - Cleaning up... 09:36:34 INFO - Return code: 0 09:36:34 INFO - Running post-action listener: _resource_record_post_action 09:36:34 INFO - Running post-action listener: _start_resource_monitoring 09:36:34 INFO - Starting resource monitoring. 09:36:34 INFO - ##### 09:36:34 INFO - ##### Finished create-virtualenv step (success) 09:36:34 INFO - ##### 09:36:34 INFO - ##### 09:36:34 INFO - ##### Running install step. 09:36:34 INFO - ##### 09:36:34 INFO - Running pre-action listener: _resource_record_pre_action 09:36:34 INFO - Running main action method: install 09:36:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:36:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:36:34 INFO - Reading from file tmpfile_stdout 09:36:34 INFO - Detecting whether we're running mozinstall >=1.0... 09:36:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:36:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:36:34 INFO - Reading from file tmpfile_stdout 09:36:34 INFO - Output received: 09:36:34 INFO - Usage: mozinstall [options] installer 09:36:34 INFO - Options: 09:36:34 INFO - -h, --help show this help message and exit 09:36:34 INFO - -d DEST, --destination=DEST 09:36:34 INFO - Directory to install application into. [default: 09:36:34 INFO - "/builds/slave/test"] 09:36:34 INFO - --app=APP Application being installed. [default: firefox] 09:36:34 INFO - mkdir: /builds/slave/test/build/application 09:36:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:36:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:36:50 INFO - Reading from file tmpfile_stdout 09:36:50 INFO - Output received: 09:36:50 INFO - /builds/slave/test/build/application/firefox/firefox 09:36:50 INFO - Running post-action listener: _resource_record_post_action 09:36:50 INFO - ##### 09:36:50 INFO - ##### Finished install step (success) 09:36:50 INFO - ##### 09:36:50 INFO - ##### 09:36:50 INFO - ##### Running run-tests step. 09:36:50 INFO - ##### 09:36:50 INFO - Running pre-action listener: _resource_record_pre_action 09:36:50 INFO - Running main action method: run_tests 09:36:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:36:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:36:50 INFO - Python 2.7.3 09:36:50 INFO - Return code: 0 09:36:50 INFO - grabbing minidump binary from tooltool 09:36:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:36:50 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 0x22e8990>, '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 0x2254470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2254900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:36:50 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 09:36:50 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 09:36:50 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 09:36:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:36:50 INFO - Return code: 0 09:36:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:36:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:36:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:36:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:36:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:36:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:36:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:36:51 INFO - Return code: 0 09:36:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 09:36:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-032 --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 09:36:51 INFO - Using env: {'DISPLAY': ':0', 09:36:51 INFO - 'HOME': '/home/cltbld', 09:36:51 INFO - 'LANG': 'en_US.UTF-8', 09:36:51 INFO - 'LANGUAGE': 'en_US:en', 09:36:51 INFO - 'LOGNAME': 'cltbld', 09:36:51 INFO - 'MAIL': '/var/mail/cltbld', 09:36:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:36:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:36:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:51 INFO - 'MOZ_NO_REMOTE': '1', 09:36:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:36:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:36:51 INFO - 'NO_EM_RESTART': '1', 09:36:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:36:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:51 INFO - 'PWD': '/builds/slave/test', 09:36:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:36:51 INFO - 'SHELL': '/bin/bash', 09:36:51 INFO - 'SHLVL': '1', 09:36:51 INFO - 'TERM': 'linux', 09:36:51 INFO - 'TMOUT': '86400', 09:36:51 INFO - 'USER': 'cltbld', 09:36:51 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1466439366.69894-678773580', 09:36:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:51 INFO - '_': '/tools/buildbot/bin/python'} 09:36:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 09:36:51 INFO - mozversion application_buildid: 20160620065724 09:36:51 INFO - mozversion application_changeset: dd0fbfbe790f880c80f126916e1c4e38436f9ad6 09:36:51 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:36:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:36:51 INFO - mozversion application_name: Firefox 09:36:51 INFO - mozversion application_remotingname: firefox-dev 09:36:51 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:36:51 INFO - mozversion application_vendor: Mozilla 09:36:51 INFO - mozversion application_version: 49.0a2 09:36:51 INFO - mozversion platform_buildid: 20160620065724 09:36:51 INFO - mozversion platform_changeset: dd0fbfbe790f880c80f126916e1c4e38436f9ad6 09:36:51 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:36:51 INFO - mozversion platform_version: 49.0a2 09:36:51 INFO - using testdate: 1466440611 09:36:51 INFO - actual date: 1466440611 09:36:51 INFO - starting webserver on 'localhost:48999' 09:36:51 INFO - SUITE-START | Running 2 tests 09:36:51 INFO - TEST-START | dromaeo_css 09:36:51 INFO - operating with platform_type : linux_ 09:36:51 INFO - Initialising browser for dromaeo_css test... 09:36:51 INFO - TEST-INFO | started process 16680 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAb59ZK/profile http://localhost:48999/getInfo.html) 09:36:56 INFO - PROCESS | 16680 | __metrics Screen width/height:1600/1200 09:36:56 INFO - PROCESS | 16680 | colorDepth:24 09:36:56 INFO - PROCESS | 16680 | Browser inner width/height: 1024/697 09:36:56 INFO - PROCESS | 16680 | __metrics 09:36:57 ERROR - PROCESS | 16680 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:36:58 INFO - PROCESS | 16680 | console.error: PushService: 09:36:58 INFO - PROCESS | 16680 | stateChangeProcessEnqueue: Error transitioning state 09:36:58 INFO - PROCESS | 16680 | UnknownError 09:36:58 INFO - TEST-INFO | 16680: exit 0 09:36:58 INFO - Browser initialized. 09:36:58 INFO - Running cycle 1/1 for dromaeo_css test... 09:36:58 INFO - TEST-INFO | started process 16770 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAb59ZK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:37:10 INFO - PROCESS | 16770 | RSS: Main: 149155840 09:37:10 INFO - PROCESS | 16770 | 09:38:01 INFO - PROCESS | 16770 | 1466440681642 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] 09:40:09 INFO - PROCESS | 16770 | Cycle 1(1): loaded http://localhost:48999/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48999/tests/dromaeo/cssquery-ext.html) 09:40:10 INFO - PROCESS | 16770 | RSS: Main: 241336320 09:40:10 INFO - PROCESS | 16770 | 09:43:12 INFO - PROCESS | 16770 | Cycle 1(1): loaded http://localhost:48999/tests/dromaeo/cssquery-ext.html (next: http://localhost:48999/tests/dromaeo/cssquery-jquery.html) 09:43:13 INFO - PROCESS | 16770 | RSS: Main: 225144832 09:43:13 INFO - PROCESS | 16770 | 09:46:15 INFO - PROCESS | 16770 | Cycle 1(1): loaded http://localhost:48999/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48999/tests/dromaeo/cssquery-mootools.html) 09:46:15 INFO - PROCESS | 16770 | RSS: Main: 228691968 09:46:15 INFO - PROCESS | 16770 | 09:49:17 INFO - PROCESS | 16770 | Cycle 1(1): loaded http://localhost:48999/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48999/tests/dromaeo/cssquery-prototype.html) 09:49:18 INFO - PROCESS | 16770 | RSS: Main: 225304576 09:49:18 INFO - PROCESS | 16770 | 09:52:20 INFO - PROCESS | 16770 | Cycle 1(1): loaded http://localhost:48999/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48999/tests/dromaeo/cssquery-yui.html) 09:52:21 INFO - PROCESS | 16770 | RSS: Main: 200998912 09:52:21 INFO - PROCESS | 16770 | 09:55:24 INFO - PROCESS | 16770 | Cycle 1(1): loaded http://localhost:48999/tests/dromaeo/cssquery-yui.html (next: http://localhost:48999/tests/dromaeo/cssquery-dojo.html) 09:55:24 INFO - PROCESS | 16770 | RSS: Main: 235896832 09:55:24 INFO - PROCESS | 16770 | 09:55:24 INFO - PROCESS | 16770 | __start_tp_report 09:55:24 INFO - PROCESS | 16770 | _x_x_mozilla_page_load 09:55:24 INFO - PROCESS | 16770 | _x_x_mozilla_page_load_details 09:55:24 INFO - PROCESS | 16770 | |i|pagename|runs| 09:55:24 INFO - PROCESS | 16770 | |0;dojo.html;3196.6483686794454;3230.8707651693903;3232.0788575256042;3234.239953610901;3275.377678241134;5045.243213517974;5086.160783470727;5103.004914041754;5110.054639891618;5115.079285728568;4974.552290293873;4976.203807390818;5001.674891132074;5016.44819069902;5036.597072234222;4481.5070342262325;4489;4544.61370783483;4560.840370587013;4574.519675434081;4537.047220083787;4555.111753208126;4586.518415566367;4596.862094137165;4599.540045995407;4579.335996280537;4590.724556526602;4610.216263235241;4622.39908811854;4635.281531362627;4668.976655116737;4698.342232087493;4698.788554515055;4716.882077948045;4722.268048452495;4486.304622783474;4494.303382975644;4511.639068874482;4617.5151609080895;4627.768611569441;4854.466008739036;4883.8779030524165;4912.877178070541;4915.090708218991;4919.286703428019;5323.254744335796;5347.51872331492;5359.088954877641;5379.838604841861;5381.246625472437;5364.007658583136;5387.434319396485;5393.6763794172475;5416.350037995427;5458.890822183574;5128.512791284822;5140.203268493389;5150.999999999963;5152.355955505562;5172.456892026321;5307.522322990872;5551.084071128219;5560.193771903132;5561.860953476195;5566.610337276433;5183.248428977852;5220.869478262998;5285;5294.12646913255;5298.788048478094;5345.438728933484;5356.651817631841;5365.973170134124;5369.167778994184;5373.220882972026;5187.584993200535;5302.045631786315;5345.946540534623;5346;5351.010063138332;5323.148296272578;5353.143497040455;5377.650452720561;5386.703731294738;5407.270018547446;4278.764667943231;4293.914121717548;4305.160493703697;4305.784710764449;4308.590683885026;3730.2539492101146;3824.6175382461533;3858.421236814444;3889.7082718795637;3902.375619900802;5177.301064356339;5195.636305458657;5201.375834899761;5213.218017297359;5233.110371236899;5408.864778380494;5409.1615799550345;5424.267723857229;5427.3758517770175;5453.4273901240595;4879.170541008037;4972.502749725071;5013.147764879979;5192.532672059533;5205.713685747321;4606.723596584148;4620.237660785932;4627.6529260305615;4635.049814787961;4649.907001860011;5492.505674489315;5499.917501237484;5503.889922201533;5515.25544051556;5516.420775818481;5075.264086707481;5223.3209682741;5225.16397376418;5247.05553000456;5281.973590132025;5546.251256080377;5546.4453554644215;5571.0807716726285;5575.303087114111;5578.748956296895;5188.728761453468;5238.738063096754;5241.082810508222;5243.5542978847225;5272.393674727456;5101.260317254051;5186.714730689849;5232.084385232645;5250.422453530032;5314.840554783363;5143.974280128576;5145.3568303962;5147.40804807452;5152.690838549699;5159.458256882973;5135.486451354835;5143.537081662519;5145.048166089286;5148.43367229612;5150.948490515047;5027.396712394536;5059.19052951526;5081.314022607048;5106.4893510648635;5127.2565478004735;5187.844364669066;5199.324087868553;5216.713080780593;5225.686458812484;5235.973820130875;4997.625178111729;5122.974385128051;5123.257127716386;5128.948710512847;5149.639525233198 09:55:24 INFO - PROCESS | 16770 | |1;ext.html;593.1251404178834;624.4567226512934;644.552036334747;647.3817504283409;657.076807086044;34874;45688.3146752799;46143;46249;46263.53736462635;48151.759241203836;48482;48535;48628.51371486284;48673;8252.46358986667;9172.08279172084;9326.440413575207;9326.673566425177;10873.728156796064;10753.569857205703;11212.04697600705;11213.607523736671;11215.495302711397;11223.38271395073;11299.378534180616;11331.773364532704;11345.546178152865;11347.489362978644;11351.262167959097;11209.607663731771;11249.83125253122;11267;11271.661850144468;11272.943635281854;11079.446027698663;11168.497417616227;11170.273932194463;11176.832347514794;11186.384748838811;44395.334069988974;44899;45090.77454612739;45161.77419112916;45261.32108018382;3524.6299138590334;3566.233259849145;3613.114786877232;3628.11111277736;3687.1335236219466;34792.82603586966;35193.29613407717;35225;35531.822340888386;35673;4538.795754191069;4618;4633.582977532038;4671.416072990876;4706.952930470686;417658;417672;418308;419170;419699;38103.2379352417;67963.32036679669;68795;68837.3116268841;68841;38419.231615367535;40521.392179117334;40551.18897622031;40580.797096014925;40597.79701101535;17347.306107755547;17366;17464.82535174632;17470.47588572345;17558.91220543889;13063.281519516519;14093;14101.858981410054;14141.71716565663;14619.342129604192;9318.90681093194;9328;9340.859887101698;9344.392614480037;9414.29392795543;3535.1869070113503;3558.2815957324656;3585.1933315004435;3605.170810713498;3630.3102410541915;1107.9723006924892;1108.9001989820954;1120.9831852522218;1150.4362862197463;1156.67613068341;34649.48025779615;35436.64563354385;35463.4680479793;35528;35602.28795424077;1135.250734515216;1200.1958687679275;1229.2133034857698;1295.792673172288;1302.7850404683122;18270.360537381126;18338.449846504627;18397.448076557725;18520.81479185218;18524.629507409776;632839;634305;636998;640609;641037;65090.674546626964;65608.67195664087;65716;65729.67135164293;65793.67103164454;1357.151780137414;1438.9352479138254;1460.3793387810351;1481.7703255995339;1505.744023516005;34635.82682086674;35851;36510;37023.44464832976;37151;55747.16379254396;56788.14817777654;56793.43206567881;56922.43077569337;56973;418517;423934;425762;425836;426255;27370.726292736817;29683.703162968093;30337.696623033487;30659;32873;51993;52839.20741188962;53029.204561932376;53454.73272633612;54463.45536544742;44311.67064988056;44785;44823.55176448324;45698.543014569426;46254.53745462637;43425;43487.334125471396;43685.34471982987;43794.34308485439;44485.33272000987;45261.32108018317;45834.312485313414;46106.76946615245;46318.53681463142;46518;37884.24231515409;39127.41308880312;39739.80130099331;39838.80080599694;40745.38881916833;30531.389372212874;30536.389272214874;31036;31041.379172415975;31103.84448077745 09:55:24 INFO - PROCESS | 16770 | |2;jquery.html;396.910695093604;406.3721550204935;411.14276733011667;414.7221361687669;422.64708968011706;13796.724065518709;18514.351997680085;20033.098510567022;20184.39446816596;20220.393388198358;15536.300866461008;19771.703424448646;20024.0989155488;20187.899060504715;20244.29144979926;5539.972300138494;5761.711914404284;5773.33606635236;5812.593118481708;5815.709214539277;5728.942710572904;5758.280214973108;5781.739821708034;5785.913211301834;5807.883842323151;4247.171801498709;6186.814395568118;6494.350564943515;6497.155369801919;6599.769008084718;6503.317151699051;6562.901556476657;6584.078229047937;6594.802155935329;6602.8679426411445;5600.551955843523;5706;5722.942770572283;5730;5730.742116604721;23474.765252347428;23928.162514311916;24461;24800.3799905002;24819.62770558443;2411.7588241175918;2479.85120892747;2489.1536877461567;2513.3716570857287;3101.1316831287277;14704.926475367554;14895.553133406016;15315.31081101342;15457.5362739118;15586.610334741723;2038.1236068490543;2099.139352865333;2108.862923909941;2127.6914847347048;2170.8806015669134;53597.732011340086;82831;82863;83541.58229208876;84019.57990210071;10932.344059356465;11321.207515473838;11432.256903301259;11436.199466037298;11490.770184596262;15415.537533874001;15525.922370388076;15547.533573992798;15568.143752093743;15628.92185539065;8818.86771698425;8831;8938.597763100546;8954.328425368123;9112.681056163003;8676.696315628922;8739.519326437106;8762.430442021248;8936.41913275635;8956.373053886296;6185.41238582334;6442.742290308335;6789.898151527731;6926.757563485254;7054.964725176341;1903.486058764126;1957.8854836776247;1975.5555000124857;1996.1217064491577;2018.8788672679686;1015.8273093573962;1046.1055797293357;1068.2949253492657;1114.7547539541288;1118.9440527973732;19514.804851951583;19534.218631254877;19968.73329903648;20465.795342046687;20671.58656826855;1027.356467878978;1034.3276870034388;1061.0503599278757;1088.4992903264429;1091.3888222595212;11181.888181118247;11652.41737913101;11667.299962002136;11674;11713.121515886343;495642;496428;496753;499451;501745;96480;96793;101505;102295;103571.48214258881;1044.796264728386;1105.2539535813294;1108.8613923259593;1122.910167186623;1124.5333186727416;17217.31130754756;18141;18406.539836504195;18406.815931841047;18733.71899421537;36584.268314633024;39965.20069598534;41253;41309.58690413057;42068.36897446602;55358;55664.44335556593;57056.14415783683;57368.42631573631;57481.712591438445;8811.691590794291;9161.404508706913;9234.538273086184;9378.812423751624;9384.390014649027;8537.701180458902;10000.149987251165;11194.888051119384;13323.13399629021;13376.398062087424;12007.399630018639;12352.567660131852;12470.376481176087;12478;12623.431945562656;12084.214526055779;12142.332171730639;12151.210171338835;12260.87739122633;12409.93795031019;12651.620451386472;12716.74566508646;12822.102452828212;12896.935515322739;12970.22178669283;9136.132067453582;9139.908600913906;9173.99086100515;9206.861897071416;9251.722448326562;9390.342676012613;12609.747805043664;12781.424835882593;12795.552155674506;12808.359582020676 09:55:24 INFO - PROCESS | 16770 | |3;mootools.html;1428.7785393264044;1437.4034775568139;1516.4843953055959;1679.9832001679988;1929.2475934385593;8318.91681083189;9374.109459601335;9601.663941762039;9608.663696770614;9815.75460613485;9422.057794220575;9577.185939195177;9592.088751568608;9610.903890961088;9651.806963860736;2194;2483.2177863972793;2549.2352294311727;2579.548578998677;2580.419405633736;2569.511792759379;2593.48130373925;2595.779358754508;2595.909143179989;2597.052075992259;2514.6102354135137;2584.198898341511;2585.1856665150417;2586.1982785336604;2587.133310341038;2119.544297975935;2388.3551441110803;2472.307753828931;2472.950540989188;2476.4675594747214;2474.1464194852747;2496.950060998788;2519.9748002519923;2523.9495210095697;2548.069954466625;9326.533673316308;9416.482093484856;9431.198348140348;9440;9447.480388578626;5670.234518340013;5778.393268706767;5778.479936805707;5781.19063331134;5785.595008349417;3523.224890524081;3610.1155216971747;3653.324135035027;3767.566729826078;3781.5462144542557;1126.4030064066058;1165.7668466306773;1192.2695102014573;1214.4777745569402;1231.4335405713382;13553.186808791505;13782.65543361424;13821.861781382258;13831.377588008563;13835.861641383557;4980;5005.124103281911;5049.192129259301;5129.3075434816565;5157.432682404932;11527.193096483166;11569.42152892352;11593;11606.94196529012;11616.709582260512;5702.059160238514;5705.087186050212;5705.62913410627;5708.914366284509;5715.514181294637;4556.749378784132;5064.746762661852;5210.739463026834;5222.529972302435;5276.683398996072;4433.866983990485;4442.20040392726;4464.441944756905;4498.3252512123445;4536.342230376643;1367.9726405471836;1392.4196037497513;1412.583287930063;1441.740486712381;1456.3810380588206;1075.7525769073156;1079.5304042741432;1083.869935607732;1084.6854412220368;1085.2511766880828;6410.64741439216;6434.2600600930555;6543.836404089936;6570.277269500351;6619.86760264792;481.5930538694797;489.5129346300454;496.9726665033458;504.2461520027582;510.8595136337537;4527.59251667345;4600.493945665974;4619.491855895849;4642.953570464252;4662.813487460531;11787.115966302561;11897.34564598938;11900.047996160287;11996.280223186637;12126.636200913987;11299;11651.59219427333;11682.70793230176;11712.531498740125;11935.641930742091;8801.867971980426;8902.59938302765;8918.687845925491;8946.284297256203;9010.59452324647;6581.07864898905;6699.464042876461;6874.759383421556;6878.7248510059035;6975.581465112109;10742.140628749525;10921.235513513977;11110.777784444428;11239.269447485887;11257.943710281395;11002.119830413387;11279.15406344511;11310.547578096784;11411.144164187554;11537.250078744855;7464.6267686614365;8239.917600823916;8321.95839020801;8455.32357411418;8484.151584841713;9329.953350233205;9389.248860091313;9456.527173641152;9522.85715714251;9541.618335266512;8268.37987150954;8436.11420800807;8455;8579.099194584478;8587.484750914966;8043.6782528698195;8089.3528517719615;8302.460340077876;8417.368697347601;8436.493810371398;7531.887021694789;7774.455788094779;7783.260590243918;7784;7793.766187014398;5461.886481359095;5516.420775818642;5533.363663178786;5597.244372009726;5643.774249030157;5539.224508568723;5659.207710920392;5702.743376547983;5754.395788442321;5949.048152295785 09:55:24 INFO - PROCESS | 16770 | |4;prototype.html;338.72901678657075;339.57553058676655;341.1267156080433;346.0397397222707;346.7988566631354;4808.903821923559;4837.726268653736;4848.951510484894;4850.514948505148;4893.241547560125;4783.162946484369;4801;4822.132016237076;4834.782434790434;4844.830430934918;3687.9631203688023;3737.9252414951684;3874.670652994485;3919.176972835708;3941.1329507508303;3815.179736356683;3869.729118961674;3882.417637354405;3888.863889763859;3902.161035377393;3698.9445158322646;3854.9807250963704;3860.1507668312925;3874.554426240976;3883.611638836122;3628.600853906068;3794.165283637595;3812.1232116613055;3827.4832897558754;3846.9038274043232;3391.812865497081;3408.7784294021058;3410.6759857813468;3411.164264755126;3417.8632854685784;4637.443506779175;4651.209294419923;4669.1090108506105;4678;4758.547937945889;4329.523752387235;4375.234333991571;4383.276759334706;4420.381146639473;4423.867283981485;4964.329815474901;4981.875453113665;4985.127602669519;5029.862308518865;5034.295198672189;3803.1442925341853;3812.3137835189655;3818.84724611018;3819.4843696100947;3819.6371344722206;5108.7445627718835;5211.879445919146;5218.817341393071;5259.999999999962;5276.261323414725;3849.7690138591215;3873.8256778444475;3880.5835869907846;3888.5528164260904;3889.086064774776;3918.9020274493364;3939.842406303716;3944.2111577684577;3944.8224829882724;3945.763254204757;4287.228298906164;4392.670549708785;4398.890027749268;4435.3125265583885;4441.511433742286;4972.527609877128;5035.546800787946;5044.688381020961;5045.7981680732355;5047.848564543069;1306.8956731561816;1326.8606796286444;1327.1373607155233;1342.718029213876;1347.7169794343297;881.5680316644927;913.4744975890279;919.9126083022102;925.2921515040999;926.5459924637014;4806.615470762401;4891.437484689235;5008.69947803133;5019.3976722793495;5039.823606173766;5180.4458662401885;5186.429492755794;5219.269302297681;5219.869503262449;5232.319798426181;4874.512548745168;4978.5768209701855;4986.875328116754;5014.949850501521;5056.469070747592;2715.932101697473;2722.2922040269673;2725.5502842031237;2735.931601709933;2739.1919383781833;5391.487808658249;5408.323959505062;5417.485338892799;5441.782328706887;5469.835904922858;5119.97600479898;5229.973850130801;5288.444713305124;5290.523852853262;5291.444398338119;5463.159157238214;5464.453554644504;5465.344158700981;5470;5474.753636086465;5146.279520867007;5171.793128274827;5177.637565370388;5210.765515551733;5236.842894713165;5034.949650503595;5169.870753231199;5175.378954525481;5180.290504133703;5188.299579556711;5453.727313634223;5471.2613797138465;5473.698946557977;5475.260839786578;5489.917651235155;3920.862769803043;3932.6264004919485;3933.9803300983313;3939.6848252140335;3945.5659877412886;5024.949750502595;5163.432022477599;5164.690118592896;5181.145111056784;5249.921251181312;4991.850244492671;5041.294218809443;5045.325977232073;5067.974660126823;5068.011737711111;5070.594352451869;5094.592432605456;5122.487751224848;5124.30821839047;5126.4617215191865;4901.754912254444;5032.119379108714;5046.899062018666;5047.83899703063;5049.57078648319;4917.286993385867;5034.496550344937;5106.514881086291;5112.79548818058;5126.0521002018995;5042.84871453857;5043.167877300204;5060.342155519907;5064.721440320818;5066.341375621146 09:55:24 INFO - PROCESS | 16770 | |5;yui.html;721.249900103892;751.4063889527271;841.1588411588411;860.6514361683519;880.6917578847405;15592.298346574404;16262.186890655465;17081.48755537336;17085.231164597593;17090;15496.302666380012;15505.302261398236;15549.689006219869;15573.84426155738;15650.686986260296;709.2977951827679;847.4195176304252;862.9870551941726;869.4739682492096;872.8777971084053;870.1124852650292;870.8127752533204;874.396666300254;874.9343799215085;875.0636818604096;918.701422037837;919.4483310014004;919.6827094652334;919.9402038867486;921.2629896083107;741.8627553902518;885.3006125161115;892.3976315986748;897.9640814367385;908.3006085314337;660.7885476647475;665.4543274514899;665.640554100785;666.4901350466897;666.8199586111771;15305.234738263041;15391.769123463157;15416;15453.304601292968;15456.690866182726;14616.269186540736;14912.478063267841;14956.62608434787;14972.47596334123;15175;13442.798358024638;13524.188548687012;13532.458701651922;13662.18026918388;13986.16083035021;4562.361269422284;4609.539046095397;4629.467611224719;4664.230401983668;4689.812407503729;75572.24427755707;75773.62113189399;75790;75975.62012189958;77893;11005.119590432747;11014.44927753609;11277.097832173406;11318.094552435785;11367.54529818798;21112;21223.257185998416;21247.68128478074;21274.36176914695;21329.253476128262;8399.328053755808;8435.536045517432;8640.481571105754;8750.256228220673;8835.469871807712;4506.662000349921;4533.252013417815;4566.223741963874;4628;4707.741074240949;3510.087377281874;3669.9266014680093;3673.430618254175;3681.9631803682155;3686.594474607845;611.4160976267653;613.8618810767631;665.0356982375574;668.8194187569329;710.4245561095529;613.9938600614026;683.2006552333781;779.5868189859384;824;839.4333824668324;11304.660860174208;11553.884461155281;11760.941515263667;11761.294322340687;11890.583829565923;657.273712547628;753.408574269196;764.4839733180081;801.1827935505752;831.9625616847256;4924.285978533093;5077.18764997606;5080.32349324722;5126.077306084941;5588.30146231721;78302.21697783063;81421;125378;128220;129811.35094324657;98173;105289.21921996662;106041.99999999846;106790;107763.46118269516;95065.5246723762;95635;95683;96654;96772;10557.102646275152;10778.08386287143;11146.442677865913;11194.66416007521;11341.26281791681;54532.454675454326;55259.44740552543;56092.15861761995;56910;56984.1452378223;79182.60408697919;83893;83965;85846.57076714576;86085;14018;14115.505957291445;14168.079074860101;14620.926895365455;14772.852271477148;15024.173670447793;15580.766288505909;15614.375424982873;15697.29362178682;16980.150992450574;10826.621068262573;12905.354732263537;13457.259850708306;14240.359183836545;14248.572542823731;11922.52309907594;13897.930510347383;14215.50245741394;14455.927720361331;14640.780388294397;11016.669499915015;15400.229988500307;15444.5366639001;15542.456014039455;15871.68256634884;12102.697432564257;12198.342184940615;12261.448234829633;12503.499860005863;12593;12582.119251652663;12643.810342845049;12832.230066196058;12866.228026318451;13033.934830325788 09:55:24 INFO - PROCESS | 16770 | __end_tp_report 09:55:24 INFO - PROCESS | 16770 | __start_cc_report 09:55:24 INFO - PROCESS | 16770 | _x_x_mozilla_cycle_collect,816 09:55:24 INFO - PROCESS | 16770 | __end_cc_report 09:55:24 INFO - PROCESS | 16770 | __startTimestamp1466441724267__endTimestamp 09:55:24 INFO - PROCESS | 16770 | 09:55:24 INFO - PROCESS | 16770 | ------- Summary: start ------- 09:55:24 INFO - PROCESS | 16770 | Number of tests: 6 09:55:24 INFO - PROCESS | 16770 | 09:55:24 INFO - PROCESS | 16770 | [#0] dojo.html Cycles:165 Average:4993.19 Median:5148.43 stddev:496.24 (9.6%) stddev-sans-first:477.32 09:55:24 INFO - PROCESS | 16770 | Values: 3196.6 3230.9 3232.1 3234.2 3275.4 5045.2 5086.2 5103.0 5110.1 5115.1 4974.6 4976.2 5001.7 5016.4 5036.6 4481.5 4489.0 4544.6 4560.8 4574.5 4537.0 4555.1 4586.5 4596.9 4599.5 4579.3 4590.7 4610.2 4622.4 4635.3 4669.0 4698.3 4698.8 4716.9 4722.3 4486.3 4494.3 4511.6 4617.5 4627.8 4854.5 4883.9 4912.9 4915.1 4919.3 5323.3 5347.5 5359.1 5379.8 5381.2 5364.0 5387.4 5393.7 5416.4 5458.9 5128.5 5140.2 5151.0 5152.4 5172.5 5307.5 5551.1 5560.2 5561.9 5566.6 5183.2 5220.9 5285.0 5294.1 5298.8 5345.4 5356.7 5366.0 5369.2 5373.2 5187.6 5302.0 5345.9 5346.0 5351.0 5323.1 5353.1 5377.7 5386.7 5407.3 4278.8 4293.9 4305.2 4305.8 4308.6 3730.3 3824.6 3858.4 3889.7 3902.4 5177.3 5195.6 5201.4 5213.2 5233.1 5408.9 5409.2 5424.3 5427.4 5453.4 4879.2 4972.5 5013.1 5192.5 5205.7 4606.7 4620.2 4627.7 4635.0 4649.9 5492.5 5499.9 5503.9 5515.3 5516.4 5075.3 5223.3 5225.2 5247.1 5282.0 5546.3 5546.4 5571.1 5575.3 5578.7 5188.7 5238.7 5241.1 5243.6 5272.4 5101.3 5186.7 5232.1 5250.4 5314.8 5144.0 5145.4 5147.4 5152.7 5159.5 5135.5 5143.5 5145.0 5148.4 5150.9 5027.4 5059.2 5081.3 5106.5 5127.3 5187.8 5199.3 5216.7 5225.7 5236.0 4997.6 5123.0 5123.3 5128.9 5149.6 09:55:24 INFO - PROCESS | 16770 | 09:55:24 INFO - PROCESS | 16770 | [#1] ext.html Cycles:180 Average:65752.54 Median:39433.61 stddev:133967.93 (339.7%) stddev-sans-first:134254.42 09:55:24 INFO - PROCESS | 16770 | Values: 593.1 624.5 644.6 647.4 657.1 34874.0 45688.3 46143.0 46249.0 46263.5 48151.8 48482.0 48535.0 48628.5 48673.0 8252.5 9172.1 9326.4 9326.7 10873.7 10753.6 11212.0 11213.6 11215.5 11223.4 11299.4 11331.8 11345.5 11347.5 11351.3 11209.6 11249.8 11267.0 11271.7 11272.9 11079.4 11168.5 11170.3 11176.8 11186.4 44395.3 44899.0 45090.8 45161.8 45261.3 3524.6 3566.2 3613.1 3628.1 3687.1 34792.8 35193.3 35225.0 35531.8 35673.0 4538.8 4618.0 4633.6 4671.4 4707.0 417658.0 417672.0 418308.0 419170.0 419699.0 38103.2 67963.3 68795.0 68837.3 68841.0 38419.2 40521.4 40551.2 40580.8 40597.8 17347.3 17366.0 17464.8 17470.5 17558.9 13063.3 14093.0 14101.9 14141.7 14619.3 9318.9 9328.0 9340.9 9344.4 9414.3 3535.2 3558.3 3585.2 3605.2 3630.3 1108.0 1108.9 1121.0 1150.4 1156.7 34649.5 35436.6 35463.5 35528.0 35602.3 1135.3 1200.2 1229.2 1295.8 1302.8 18270.4 18338.4 18397.4 18520.8 18524.6 632839.0 634305.0 636998.0 640609.0 641037.0 65090.7 65608.7 65716.0 65729.7 65793.7 1357.2 1438.9 1460.4 1481.8 1505.7 34635.8 35851.0 36510.0 37023.4 37151.0 55747.2 56788.1 56793.4 56922.4 56973.0 418517.0 423934.0 425762.0 425836.0 426255.0 27370.7 29683.7 30337.7 30659.0 32873.0 51993.0 52839.2 53029.2 53454.7 54463.5 44311.7 44785.0 44823.6 45698.5 46254.5 43425.0 43487.3 43685.3 43794.3 44485.3 45261.3 45834.3 46106.8 46318.5 46518.0 37884.2 39127.4 39739.8 39838.8 40745.4 30531.4 30536.4 31036.0 31041.4 31103.8 09:55:24 INFO - PROCESS | 16770 | 09:55:24 INFO - PROCESS | 16770 | [#2] jquery.html Cycles:180 Average:29717.99 Median:12822.82 stddev:82098.61 (640.3%) stddev-sans-first:82299.40 09:55:24 INFO - PROCESS | 16770 | Values: 396.9 406.4 411.1 414.7 422.6 13796.7 18514.4 20033.1 20184.4 20220.4 15536.3 19771.7 20024.1 20187.9 20244.3 5540.0 5761.7 5773.3 5812.6 5815.7 5728.9 5758.3 5781.7 5785.9 5807.9 4247.2 6186.8 6494.4 6497.2 6599.8 6503.3 6562.9 6584.1 6594.8 6602.9 5600.6 5706.0 5722.9 5730.0 5730.7 23474.8 23928.2 24461.0 24800.4 24819.6 2411.8 2479.9 2489.2 2513.4 3101.1 14704.9 14895.6 15315.3 15457.5 15586.6 2038.1 2099.1 2108.9 2127.7 2170.9 53597.7 82831.0 82863.0 83541.6 84019.6 10932.3 11321.2 11432.3 11436.2 11490.8 15415.5 15525.9 15547.5 15568.1 15628.9 8818.9 8831.0 8938.6 8954.3 9112.7 8676.7 8739.5 8762.4 8936.4 8956.4 6185.4 6442.7 6789.9 6926.8 7055.0 1903.5 1957.9 1975.6 1996.1 2018.9 1015.8 1046.1 1068.3 1114.8 1118.9 19514.8 19534.2 19968.7 20465.8 20671.6 1027.4 1034.3 1061.1 1088.5 1091.4 11181.9 11652.4 11667.3 11674.0 11713.1 495642.0 496428.0 496753.0 499451.0 501745.0 96480.0 96793.0 101505.0 102295.0 103571.5 1044.8 1105.3 1108.9 1122.9 1124.5 17217.3 18141.0 18406.5 18406.8 18733.7 36584.3 39965.2 41253.0 41309.6 42068.4 55358.0 55664.4 57056.1 57368.4 57481.7 8811.7 9161.4 9234.5 9378.8 9384.4 8537.7 10000.1 11194.9 13323.1 13376.4 12007.4 12352.6 12470.4 12478.0 12623.4 12084.2 12142.3 12151.2 12260.9 12409.9 12651.6 12716.7 12822.1 12896.9 12970.2 9136.1 9139.9 9174.0 9206.9 9251.7 9390.3 12609.7 12781.4 12795.6 12808.4 09:55:24 INFO - PROCESS | 16770 | 09:55:24 INFO - PROCESS | 16770 | [#3] mootools.html Cycles:180 Average:6330.61 Median:2572.20 stddev:3647.12 (141.8%) stddev-sans-first:3638.75 09:55:24 INFO - PROCESS | 16770 | Values: 1428.8 1437.4 1516.5 1680.0 1929.2 8318.9 9374.1 9601.7 9608.7 9815.8 9422.1 9577.2 9592.1 9610.9 9651.8 2194.0 2483.2 2549.2 2579.5 2580.4 2569.5 2593.5 2595.8 2595.9 2597.1 2514.6 2584.2 2585.2 2586.2 2587.1 2119.5 2388.4 2472.3 2473.0 2476.5 2474.1 2497.0 2520.0 2523.9 2548.1 9326.5 9416.5 9431.2 9440.0 9447.5 5670.2 5778.4 5778.5 5781.2 5785.6 3523.2 3610.1 3653.3 3767.6 3781.5 1126.4 1165.8 1192.3 1214.5 1231.4 13553.2 13782.7 13821.9 13831.4 13835.9 4980.0 5005.1 5049.2 5129.3 5157.4 11527.2 11569.4 11593.0 11606.9 11616.7 5702.1 5705.1 5705.6 5708.9 5715.5 4556.7 5064.7 5210.7 5222.5 5276.7 4433.9 4442.2 4464.4 4498.3 4536.3 1368.0 1392.4 1412.6 1441.7 1456.4 1075.8 1079.5 1083.9 1084.7 1085.3 6410.6 6434.3 6543.8 6570.3 6619.9 481.6 489.5 497.0 504.2 510.9 4527.6 4600.5 4619.5 4643.0 4662.8 11787.1 11897.3 11900.0 11996.3 12126.6 11299.0 11651.6 11682.7 11712.5 11935.6 8801.9 8902.6 8918.7 8946.3 9010.6 6581.1 6699.5 6874.8 6878.7 6975.6 10742.1 10921.2 11110.8 11239.3 11257.9 11002.1 11279.2 11310.5 11411.1 11537.3 7464.6 8239.9 8322.0 8455.3 8484.2 9330.0 9389.2 9456.5 9522.9 9541.6 8268.4 8436.1 8455.0 8579.1 8587.5 8043.7 8089.4 8302.5 8417.4 8436.5 7531.9 7774.5 7783.3 7784.0 7793.8 5461.9 5516.4 5533.4 5597.2 5643.8 5539.2 5659.2 5702.7 5754.4 5949.0 09:55:24 INFO - PROCESS | 16770 | 09:55:24 INFO - PROCESS | 16770 | [#4] prototype.html Cycles:180 Average:4319.47 Median:4968.43 stddev:1241.20 (25.0%) stddev-sans-first:1208.18 09:55:24 INFO - PROCESS | 16770 | Values: 338.7 339.6 341.1 346.0 346.8 4808.9 4837.7 4849.0 4850.5 4893.2 4783.2 4801.0 4822.1 4834.8 4844.8 3688.0 3737.9 3874.7 3919.2 3941.1 3815.2 3869.7 3882.4 3888.9 3902.2 3698.9 3855.0 3860.2 3874.6 3883.6 3628.6 3794.2 3812.1 3827.5 3846.9 3391.8 3408.8 3410.7 3411.2 3417.9 4637.4 4651.2 4669.1 4678.0 4758.5 4329.5 4375.2 4383.3 4420.4 4423.9 4964.3 4981.9 4985.1 5029.9 5034.3 3803.1 3812.3 3818.8 3819.5 3819.6 5108.7 5211.9 5218.8 5260.0 5276.3 3849.8 3873.8 3880.6 3888.6 3889.1 3918.9 3939.8 3944.2 3944.8 3945.8 4287.2 4392.7 4398.9 4435.3 4441.5 4972.5 5035.5 5044.7 5045.8 5047.8 1306.9 1326.9 1327.1 1342.7 1347.7 881.6 913.5 919.9 925.3 926.5 4806.6 4891.4 5008.7 5019.4 5039.8 5180.4 5186.4 5219.3 5219.9 5232.3 4874.5 4978.6 4986.9 5014.9 5056.5 2715.9 2722.3 2725.6 2735.9 2739.2 5391.5 5408.3 5417.5 5441.8 5469.8 5120.0 5230.0 5288.4 5290.5 5291.4 5463.2 5464.5 5465.3 5470.0 5474.8 5146.3 5171.8 5177.6 5210.8 5236.8 5034.9 5169.9 5175.4 5180.3 5188.3 5453.7 5471.3 5473.7 5475.3 5489.9 3920.9 3932.6 3934.0 3939.7 3945.6 5024.9 5163.4 5164.7 5181.1 5249.9 4991.9 5041.3 5045.3 5068.0 5068.0 5070.6 5094.6 5122.5 5124.3 5126.5 4901.8 5032.1 5046.9 5047.8 5049.6 4917.3 5034.5 5106.5 5112.8 5126.1 5042.8 5043.2 5060.3 5064.7 5066.3 09:55:24 INFO - PROCESS | 16770 | 09:55:24 INFO - PROCESS | 16770 | [#5] yui.html Cycles:180 Average:21866.81 Median:3684.28 stddev:31191.19 (846.6%) stddev-sans-first:31238.28 09:55:24 INFO - PROCESS | 16770 | Values: 721.2 751.4 841.2 860.7 880.7 15592.3 16262.2 17081.5 17085.2 17090.0 15496.3 15505.3 15549.7 15573.8 15650.7 709.3 847.4 863.0 869.5 872.9 870.1 870.8 874.4 874.9 875.1 918.7 919.4 919.7 919.9 921.3 741.9 885.3 892.4 898.0 908.3 660.8 665.5 665.6 666.5 666.8 15305.2 15391.8 15416.0 15453.3 15456.7 14616.3 14912.5 14956.6 14972.5 15175.0 13442.8 13524.2 13532.5 13662.2 13986.2 4562.4 4609.5 4629.5 4664.2 4689.8 75572.2 75773.6 75790.0 75975.6 77893.0 11005.1 11014.4 11277.1 11318.1 11367.5 21112.0 21223.3 21247.7 21274.4 21329.3 8399.3 8435.5 8640.5 8750.3 8835.5 4506.7 4533.3 4566.2 4628.0 4707.7 3510.1 3669.9 3673.4 3682.0 3686.6 611.4 613.9 665.0 668.8 710.4 614.0 683.2 779.6 824.0 839.4 11304.7 11553.9 11760.9 11761.3 11890.6 657.3 753.4 764.5 801.2 832.0 4924.3 5077.2 5080.3 5126.1 5588.3 78302.2 81421.0 125378.0 128220.0 129811.4 98173.0 105289.2 106042.0 106790.0 107763.5 95065.5 95635.0 95683.0 96654.0 96772.0 10557.1 10778.1 11146.4 11194.7 11341.3 54532.5 55259.4 56092.2 56910.0 56984.1 79182.6 83893.0 83965.0 85846.6 86085.0 14018.0 14115.5 14168.1 14620.9 14772.9 15024.2 15580.8 15614.4 15697.3 16980.2 10826.6 12905.4 13457.3 14240.4 14248.6 11922.5 13897.9 14215.5 14455.9 14640.8 11016.7 15400.2 15444.5 15542.5 15871.7 12102.7 12198.3 12261.4 12503.5 12593.0 12582.1 12643.8 12832.2 12866.2 13033.9 09:55:24 INFO - PROCESS | 16770 | -------- Summary: end -------- 09:55:24 INFO - PROCESS | 16770 | 09:55:24 INFO - TEST-INFO | 16770: exit 0 09:55:25 INFO - TEST-OK | dromaeo_css | took 1113469ms 09:55:25 INFO - TEST-START | kraken 09:55:25 INFO - operating with platform_type : linux_ 09:55:25 INFO - Initialising browser for kraken test... 09:55:25 INFO - TEST-INFO | started process 16964 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCVJpMK/profile http://localhost:48999/getInfo.html) 09:55:29 INFO - PROCESS | 16964 | __metrics Screen width/height:1600/1200 09:55:29 INFO - PROCESS | 16964 | colorDepth:24 09:55:29 INFO - PROCESS | 16964 | Browser inner width/height: 1024/697 09:55:29 INFO - PROCESS | 16964 | __metrics 09:55:30 ERROR - PROCESS | 16964 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:55:31 INFO - PROCESS | 16964 | console.error: PushService: 09:55:31 INFO - PROCESS | 16964 | stateChangeProcessEnqueue: Error transitioning state 09:55:31 INFO - PROCESS | 16964 | UnknownError 09:55:31 INFO - TEST-INFO | 16964: exit 0 09:55:31 INFO - Browser initialized. 09:55:31 INFO - Running cycle 1/1 for kraken test... 09:55:31 INFO - TEST-INFO | started process 17058 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCVJpMK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:55:43 INFO - PROCESS | 17058 | RSS: Main: 149696512 09:55:43 INFO - PROCESS | 17058 | 09:55:46 INFO - PROCESS | 17058 | Cycle 1(1): loaded http://localhost:48999/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48999/tests/kraken/driver.html?testName=audio-beat-detection) 09:55:46 INFO - PROCESS | 17058 | RSS: Main: 198889472 09:55:46 INFO - PROCESS | 17058 | 09:55:49 INFO - PROCESS | 17058 | Cycle 1(1): loaded http://localhost:48999/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48999/tests/kraken/driver.html?testName=audio-dft) 09:55:50 INFO - PROCESS | 17058 | RSS: Main: 196239360 09:55:50 INFO - PROCESS | 17058 | 09:55:52 INFO - PROCESS | 17058 | Cycle 1(1): loaded http://localhost:48999/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48999/tests/kraken/driver.html?testName=audio-fft) 09:55:53 INFO - PROCESS | 17058 | RSS: Main: 282603520 09:55:53 INFO - PROCESS | 17058 | 09:55:55 INFO - PROCESS | 17058 | Cycle 1(1): loaded http://localhost:48999/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48999/tests/kraken/driver.html?testName=audio-oscillator) 09:55:56 INFO - PROCESS | 17058 | RSS: Main: 191422464 09:55:56 INFO - PROCESS | 17058 | 09:55:58 INFO - PROCESS | 17058 | Cycle 1(1): loaded http://localhost:48999/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48999/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:55:59 INFO - PROCESS | 17058 | RSS: Main: 192995328 09:55:59 INFO - PROCESS | 17058 | 09:56:02 INFO - PROCESS | 17058 | Cycle 1(1): loaded http://localhost:48999/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48999/tests/kraken/driver.html?testName=imaging-darkroom) 09:56:02 INFO - PROCESS | 17058 | RSS: Main: 227639296 09:56:02 INFO - PROCESS | 17058 | 09:56:05 INFO - PROCESS | 17058 | Cycle 1(1): loaded http://localhost:48999/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48999/tests/kraken/driver.html?testName=imaging-desaturate) 09:56:06 INFO - PROCESS | 17058 | RSS: Main: 226918400 09:56:06 INFO - PROCESS | 17058 | 09:56:09 INFO - PROCESS | 17058 | Cycle 1(1): loaded http://localhost:48999/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48999/tests/kraken/driver.html?testName=json-parse-financial) 09:56:09 INFO - PROCESS | 17058 | RSS: Main: 226709504 09:56:09 INFO - PROCESS | 17058 | 09:56:11 INFO - PROCESS | 17058 | Cycle 1(1): loaded http://localhost:48999/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48999/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:56:12 INFO - PROCESS | 17058 | RSS: Main: 205438976 09:56:12 INFO - PROCESS | 17058 | 09:56:13 INFO - PROCESS | 17058 | Cycle 1(1): loaded http://localhost:48999/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48999/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:56:14 INFO - PROCESS | 17058 | RSS: Main: 206708736 09:56:14 INFO - PROCESS | 17058 | 09:56:16 INFO - PROCESS | 17058 | Cycle 1(1): loaded http://localhost:48999/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48999/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:56:17 INFO - PROCESS | 17058 | RSS: Main: 209956864 09:56:17 INFO - PROCESS | 17058 | 09:56:19 INFO - PROCESS | 17058 | Cycle 1(1): loaded http://localhost:48999/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48999/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:56:20 INFO - PROCESS | 17058 | RSS: Main: 214949888 09:56:20 INFO - PROCESS | 17058 | 09:56:22 INFO - PROCESS | 17058 | Cycle 1(1): loaded http://localhost:48999/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48999/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:56:23 INFO - PROCESS | 17058 | RSS: Main: 213454848 09:56:23 INFO - PROCESS | 17058 | 09:56:25 INFO - PROCESS | 17058 | Cycle 1(1): loaded http://localhost:48999/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48999/tests/kraken/driver.html?testName=ai-astar) 09:56:25 INFO - PROCESS | 17058 | RSS: Main: 255737856 09:56:25 INFO - PROCESS | 17058 | 09:56:25 INFO - PROCESS | 17058 | __start_tp_report 09:56:25 INFO - PROCESS | 17058 | _x_x_mozilla_page_load 09:56:25 INFO - PROCESS | 17058 | _x_x_mozilla_page_load_details 09:56:25 INFO - PROCESS | 17058 | |i|pagename|runs| 09:56:25 INFO - PROCESS | 17058 | |0;ai-astar;110;103;109;110;110;108;110;110;108;108 09:56:25 INFO - PROCESS | 17058 | |1;audio-beat-detection;158;123;126;173;132;126;157;128;156;131 09:56:25 INFO - PROCESS | 17058 | |2;audio-dft;160;161;159;162;159;160;159;161;159;158 09:56:25 INFO - PROCESS | 17058 | |3;audio-fft;73;84;76;111;76;76;72;74;98;74 09:56:25 INFO - PROCESS | 17058 | |4;audio-oscillator;84;84;119;85;84;85;114;84;86;120 09:56:25 INFO - PROCESS | 17058 | |5;imaging-gaussian-blur;117;116;116;116;117;117;116;117;116;117 09:56:25 INFO - PROCESS | 17058 | |6;imaging-darkroom;146;146;145;147;147;146;146;145;146;146 09:56:25 INFO - PROCESS | 17058 | |7;imaging-desaturate;95;94;95;96;95;95;95;95;95;95 09:56:25 INFO - PROCESS | 17058 | |8;json-parse-financial;55;57;56;57;56;56;57;57;62;56 09:56:25 INFO - PROCESS | 17058 | |9;json-stringify-tinderbox;60;60;59;60;59;59;61;60;60;60 09:56:25 INFO - PROCESS | 17058 | |10;stanford-crypto-aes;78;76;77;83;79;85;78;78;116;79 09:56:25 INFO - PROCESS | 17058 | |11;stanford-crypto-ccm;120;117;130;132;117;134;121;126;132;121 09:56:25 INFO - PROCESS | 17058 | |12;stanford-crypto-pbkdf2;158;158;161;158;156;166;155;159;157;155 09:56:25 INFO - PROCESS | 17058 | |13;stanford-crypto-sha256-iterative;65;62;62;63;61;62;63;61;62;61 09:56:25 INFO - PROCESS | 17058 | __end_tp_report 09:56:25 INFO - PROCESS | 17058 | __start_cc_report 09:56:25 INFO - PROCESS | 17058 | _x_x_mozilla_cycle_collect,1605 09:56:25 INFO - PROCESS | 17058 | __end_cc_report 09:56:25 INFO - PROCESS | 17058 | __startTimestamp1466441785510__endTimestamp 09:56:25 INFO - PROCESS | 17058 | 09:56:25 INFO - PROCESS | 17058 | ------- Summary: start ------- 09:56:25 INFO - PROCESS | 17058 | Number of tests: 14 09:56:25 INFO - PROCESS | 17058 | 09:56:25 INFO - PROCESS | 17058 | [#0] ai-astar Cycles:10 Average:108.60 Median:110.00 stddev:2.17 (2.0%) stddev-sans-first:2.24 09:56:25 INFO - PROCESS | 17058 | Values: 110.0 103.0 109.0 110.0 110.0 108.0 110.0 110.0 108.0 108.0 09:56:25 INFO - PROCESS | 17058 | 09:56:25 INFO - PROCESS | 17058 | [#1] audio-beat-detection Cycles:10 Average:141.00 Median:144.00 stddev:18.01 (12.5%) stddev-sans-first:18.02 09:56:25 INFO - PROCESS | 17058 | Values: 158.0 123.0 126.0 173.0 132.0 126.0 157.0 128.0 156.0 131.0 09:56:25 INFO - PROCESS | 17058 | 09:56:25 INFO - PROCESS | 17058 | [#2] audio-dft Cycles:10 Average:159.80 Median:160.00 stddev:1.23 (0.8%) stddev-sans-first:1.30 09:56:25 INFO - PROCESS | 17058 | Values: 160.0 161.0 159.0 162.0 159.0 160.0 159.0 161.0 159.0 158.0 09:56:25 INFO - PROCESS | 17058 | 09:56:25 INFO - PROCESS | 17058 | [#3] audio-fft Cycles:10 Average:81.40 Median:76.00 stddev:12.97 (17.1%) stddev-sans-first:13.40 09:56:25 INFO - PROCESS | 17058 | Values: 73.0 84.0 76.0 111.0 76.0 76.0 72.0 74.0 98.0 74.0 09:56:25 INFO - PROCESS | 17058 | 09:56:25 INFO - PROCESS | 17058 | [#4] audio-oscillator Cycles:10 Average:94.50 Median:84.00 stddev:16.07 (19.1%) stddev-sans-first:16.59 09:56:25 INFO - PROCESS | 17058 | Values: 84.0 84.0 119.0 85.0 84.0 85.0 114.0 84.0 86.0 120.0 09:56:25 INFO - PROCESS | 17058 | 09:56:25 INFO - PROCESS | 17058 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 09:56:25 INFO - PROCESS | 17058 | Values: 117.0 116.0 116.0 116.0 117.0 117.0 116.0 117.0 116.0 117.0 09:56:25 INFO - PROCESS | 17058 | 09:56:25 INFO - PROCESS | 17058 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 09:56:25 INFO - PROCESS | 17058 | Values: 146.0 146.0 145.0 147.0 147.0 146.0 146.0 145.0 146.0 146.0 09:56:25 INFO - PROCESS | 17058 | 09:56:25 INFO - PROCESS | 17058 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 09:56:25 INFO - PROCESS | 17058 | Values: 95.0 94.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 09:56:25 INFO - PROCESS | 17058 | 09:56:25 INFO - PROCESS | 17058 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:57.00 stddev:1.91 (3.4%) stddev-sans-first:1.90 09:56:25 INFO - PROCESS | 17058 | Values: 55.0 57.0 56.0 57.0 56.0 56.0 57.0 57.0 62.0 56.0 09:56:25 INFO - PROCESS | 17058 | 09:56:25 INFO - PROCESS | 17058 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 09:56:25 INFO - PROCESS | 17058 | Values: 60.0 60.0 59.0 60.0 59.0 59.0 61.0 60.0 60.0 60.0 09:56:25 INFO - PROCESS | 17058 | 09:56:25 INFO - PROCESS | 17058 | [#10] stanford-crypto-aes Cycles:10 Average:82.90 Median:78.50 stddev:11.95 (15.2%) stddev-sans-first:12.54 09:56:25 INFO - PROCESS | 17058 | Values: 78.0 76.0 77.0 83.0 79.0 85.0 78.0 78.0 116.0 79.0 09:56:25 INFO - PROCESS | 17058 | 09:56:25 INFO - PROCESS | 17058 | [#11] stanford-crypto-ccm Cycles:10 Average:125.00 Median:128.00 stddev:6.58 (5.1%) stddev-sans-first:6.73 09:56:25 INFO - PROCESS | 17058 | Values: 120.0 117.0 130.0 132.0 117.0 134.0 121.0 126.0 132.0 121.0 09:56:25 INFO - PROCESS | 17058 | 09:56:25 INFO - PROCESS | 17058 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.30 Median:158.00 stddev:3.27 (2.1%) stddev-sans-first:3.46 09:56:25 INFO - PROCESS | 17058 | Values: 158.0 158.0 161.0 158.0 156.0 166.0 155.0 159.0 157.0 155.0 09:56:25 INFO - PROCESS | 17058 | 09:56:25 INFO - PROCESS | 17058 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:62.00 stddev:1.23 (2.0%) stddev-sans-first:0.78 09:56:25 INFO - PROCESS | 17058 | Values: 65.0 62.0 62.0 63.0 61.0 62.0 63.0 61.0 62.0 61.0 09:56:25 INFO - PROCESS | 17058 | -------- Summary: end -------- 09:56:25 INFO - PROCESS | 17058 | 09:56:26 INFO - TEST-INFO | 17058: exit 0 09:56:26 INFO - TEST-OK | kraken | took 61559ms 09:56:26 INFO - SUITE-END | took 1175s 09:56:26 INFO - Completed test suite (00:19:35) 09:56:26 INFO - javascript benchmark 09:56:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3196.6483686794454, 3230.8707651693903, 3232.0788575256042, 3234.239953610901, 3275.377678241134, 5045.243213517974, 5086.160783470727, 5103.004914041754, 5110.054639891618, 5115.079285728568, 4974.552290293873, 4976.203807390818, 5001.674891132074, 5016.44819069902, 5036.597072234222, 4481.5070342262325, 4489.0, 4544.61370783483, 4560.840370587013, 4574.519675434081, 4537.047220083787, 4555.111753208126, 4586.518415566367, 4596.862094137165, 4599.540045995407, 4579.335996280537, 4590.724556526602, 4610.216263235241, 4622.39908811854, 4635.281531362627, 4668.976655116737, 4698.342232087493, 4698.788554515055, 4716.882077948045, 4722.268048452495, 4486.304622783474, 4494.303382975644, 4511.639068874482, 4617.5151609080895, 4627.768611569441, 4854.466008739036, 4883.8779030524165, 4912.877178070541, 4915.090708218991, 4919.286703428019, 5323.254744335796, 5347.51872331492, 5359.088954877641, 5379.838604841861, 5381.246625472437, 5364.007658583136, 5387.434319396485, 5393.6763794172475, 5416.350037995427, 5458.890822183574, 5128.512791284822, 5140.203268493389, 5150.999999999963, 5152.355955505562, 5172.456892026321, 5307.522322990872, 5551.084071128219, 5560.193771903132, 5561.860953476195, 5566.610337276433, 5183.248428977852, 5220.869478262998, 5285.0, 5294.12646913255, 5298.788048478094, 5345.438728933484, 5356.651817631841, 5365.973170134124, 5369.167778994184, 5373.220882972026, 5187.584993200535, 5302.045631786315, 5345.946540534623, 5346.0, 5351.010063138332, 5323.148296272578, 5353.143497040455, 5377.650452720561, 5386.703731294738, 5407.270018547446, 4278.764667943231, 4293.914121717548, 4305.160493703697, 4305.784710764449, 4308.590683885026, 3730.2539492101146, 3824.6175382461533, 3858.421236814444, 3889.7082718795637, 3902.375619900802, 5177.301064356339, 5195.636305458657, 5201.375834899761, 5213.218017297359, 5233.110371236899, 5408.864778380494, 5409.1615799550345, 5424.267723857229, 5427.3758517770175, 5453.4273901240595, 4879.170541008037, 4972.502749725071, 5013.147764879979, 5192.532672059533, 5205.713685747321, 4606.723596584148, 4620.237660785932, 4627.6529260305615, 4635.049814787961, 4649.907001860011, 5492.505674489315, 5499.917501237484, 5503.889922201533, 5515.25544051556, 5516.420775818481, 5075.264086707481, 5223.3209682741, 5225.16397376418, 5247.05553000456, 5281.973590132025, 5546.251256080377, 5546.4453554644215, 5571.0807716726285, 5575.303087114111, 5578.748956296895, 5188.728761453468, 5238.738063096754, 5241.082810508222, 5243.5542978847225, 5272.393674727456, 5101.260317254051, 5186.714730689849, 5232.084385232645, 5250.422453530032, 5314.840554783363, 5143.974280128576, 5145.3568303962, 5147.40804807452, 5152.690838549699, 5159.458256882973, 5135.486451354835, 5143.537081662519, 5145.048166089286, 5148.43367229612, 5150.948490515047, 5027.396712394536, 5059.19052951526, 5081.314022607048, 5106.4893510648635, 5127.2565478004735, 5187.844364669066, 5199.324087868553, 5216.713080780593, 5225.686458812484, 5235.973820130875, 4997.625178111729, 5122.974385128051, 5123.257127716386, 5128.948710512847, 5149.639525233198], "value": 4965.201230423136, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.1251404178834, 624.4567226512934, 644.552036334747, 647.3817504283409, 657.076807086044, 34874.0, 45688.3146752799, 46143.0, 46249.0, 46263.53736462635, 48151.759241203836, 48482.0, 48535.0, 48628.51371486284, 48673.0, 8252.46358986667, 9172.08279172084, 9326.440413575207, 9326.673566425177, 10873.728156796064, 10753.569857205703, 11212.04697600705, 11213.607523736671, 11215.495302711397, 11223.38271395073, 11299.378534180616, 11331.773364532704, 11345.546178152865, 11347.489362978644, 11351.262167959097, 11209.607663731771, 11249.83125253122, 11267.0, 11271.661850144468, 11272.943635281854, 11079.446027698663, 11168.497417616227, 11170.273932194463, 11176.832347514794, 11186.384748838811, 44395.334069988974, 44899.0, 45090.77454612739, 45161.77419112916, 45261.32108018382, 3524.6299138590334, 3566.233259849145, 3613.114786877232, 3628.11111277736, 3687.1335236219466, 34792.82603586966, 35193.29613407717, 35225.0, 35531.822340888386, 35673.0, 4538.795754191069, 4618.0, 4633.582977532038, 4671.416072990876, 4706.952930470686, 417658.0, 417672.0, 418308.0, 419170.0, 419699.0, 38103.2379352417, 67963.32036679669, 68795.0, 68837.3116268841, 68841.0, 38419.231615367535, 40521.392179117334, 40551.18897622031, 40580.797096014925, 40597.79701101535, 17347.306107755547, 17366.0, 17464.82535174632, 17470.47588572345, 17558.91220543889, 13063.281519516519, 14093.0, 14101.858981410054, 14141.71716565663, 14619.342129604192, 9318.90681093194, 9328.0, 9340.859887101698, 9344.392614480037, 9414.29392795543, 3535.1869070113503, 3558.2815957324656, 3585.1933315004435, 3605.170810713498, 3630.3102410541915, 1107.9723006924892, 1108.9001989820954, 1120.9831852522218, 1150.4362862197463, 1156.67613068341, 34649.48025779615, 35436.64563354385, 35463.4680479793, 35528.0, 35602.28795424077, 1135.250734515216, 1200.1958687679275, 1229.2133034857698, 1295.792673172288, 1302.7850404683122, 18270.360537381126, 18338.449846504627, 18397.448076557725, 18520.81479185218, 18524.629507409776, 632839.0, 634305.0, 636998.0, 640609.0, 641037.0, 65090.674546626964, 65608.67195664087, 65716.0, 65729.67135164293, 65793.67103164454, 1357.151780137414, 1438.9352479138254, 1460.3793387810351, 1481.7703255995339, 1505.744023516005, 34635.82682086674, 35851.0, 36510.0, 37023.44464832976, 37151.0, 55747.16379254396, 56788.14817777654, 56793.43206567881, 56922.43077569337, 56973.0, 418517.0, 423934.0, 425762.0, 425836.0, 426255.0, 27370.726292736817, 29683.703162968093, 30337.696623033487, 30659.0, 32873.0, 51993.0, 52839.20741188962, 53029.204561932376, 53454.73272633612, 54463.45536544742, 44311.67064988056, 44785.0, 44823.55176448324, 45698.543014569426, 46254.53745462637, 43425.0, 43487.334125471396, 43685.34471982987, 43794.34308485439, 44485.33272000987, 45261.32108018317, 45834.312485313414, 46106.76946615245, 46318.53681463142, 46518.0, 37884.24231515409, 39127.41308880312, 39739.80130099331, 39838.80080599694, 40745.38881916833, 30531.389372212874, 30536.389272214874, 31036.0, 31041.379172415975, 31103.84448077745], "value": 20984.94958058113, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.910695093604, 406.3721550204935, 411.14276733011667, 414.7221361687669, 422.64708968011706, 13796.724065518709, 18514.351997680085, 20033.098510567022, 20184.39446816596, 20220.393388198358, 15536.300866461008, 19771.703424448646, 20024.0989155488, 20187.899060504715, 20244.29144979926, 5539.972300138494, 5761.711914404284, 5773.33606635236, 5812.593118481708, 5815.709214539277, 5728.942710572904, 5758.280214973108, 5781.739821708034, 5785.913211301834, 5807.883842323151, 4247.171801498709, 6186.814395568118, 6494.350564943515, 6497.155369801919, 6599.769008084718, 6503.317151699051, 6562.901556476657, 6584.078229047937, 6594.802155935329, 6602.8679426411445, 5600.551955843523, 5706.0, 5722.942770572283, 5730.0, 5730.742116604721, 23474.765252347428, 23928.162514311916, 24461.0, 24800.3799905002, 24819.62770558443, 2411.7588241175918, 2479.85120892747, 2489.1536877461567, 2513.3716570857287, 3101.1316831287277, 14704.926475367554, 14895.553133406016, 15315.31081101342, 15457.5362739118, 15586.610334741723, 2038.1236068490543, 2099.139352865333, 2108.862923909941, 2127.6914847347048, 2170.8806015669134, 53597.732011340086, 82831.0, 82863.0, 83541.58229208876, 84019.57990210071, 10932.344059356465, 11321.207515473838, 11432.256903301259, 11436.199466037298, 11490.770184596262, 15415.537533874001, 15525.922370388076, 15547.533573992798, 15568.143752093743, 15628.92185539065, 8818.86771698425, 8831.0, 8938.597763100546, 8954.328425368123, 9112.681056163003, 8676.696315628922, 8739.519326437106, 8762.430442021248, 8936.41913275635, 8956.373053886296, 6185.41238582334, 6442.742290308335, 6789.898151527731, 6926.757563485254, 7054.964725176341, 1903.486058764126, 1957.8854836776247, 1975.5555000124857, 1996.1217064491577, 2018.8788672679686, 1015.8273093573962, 1046.1055797293357, 1068.2949253492657, 1114.7547539541288, 1118.9440527973732, 19514.804851951583, 19534.218631254877, 19968.73329903648, 20465.795342046687, 20671.58656826855, 1027.356467878978, 1034.3276870034388, 1061.0503599278757, 1088.4992903264429, 1091.3888222595212, 11181.888181118247, 11652.41737913101, 11667.299962002136, 11674.0, 11713.121515886343, 495642.0, 496428.0, 496753.0, 499451.0, 501745.0, 96480.0, 96793.0, 101505.0, 102295.0, 103571.48214258881, 1044.796264728386, 1105.2539535813294, 1108.8613923259593, 1122.910167186623, 1124.5333186727416, 17217.31130754756, 18141.0, 18406.539836504195, 18406.815931841047, 18733.71899421537, 36584.268314633024, 39965.20069598534, 41253.0, 41309.58690413057, 42068.36897446602, 55358.0, 55664.44335556593, 57056.14415783683, 57368.42631573631, 57481.712591438445, 8811.691590794291, 9161.404508706913, 9234.538273086184, 9378.812423751624, 9384.390014649027, 8537.701180458902, 10000.149987251165, 11194.888051119384, 13323.13399629021, 13376.398062087424, 12007.399630018639, 12352.567660131852, 12470.376481176087, 12478.0, 12623.431945562656, 12084.214526055779, 12142.332171730639, 12151.210171338835, 12260.87739122633, 12409.93795031019, 12651.620451386472, 12716.74566508646, 12822.102452828212, 12896.935515322739, 12970.22178669283, 9136.132067453582, 9139.908600913906, 9173.99086100515, 9206.861897071416, 9251.722448326562, 9390.342676012613, 12609.747805043664, 12781.424835882593, 12795.552155674506, 12808.359582020676], "value": 9759.708260840947, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1428.7785393264044, 1437.4034775568139, 1516.4843953055959, 1679.9832001679988, 1929.2475934385593, 8318.91681083189, 9374.109459601335, 9601.663941762039, 9608.663696770614, 9815.75460613485, 9422.057794220575, 9577.185939195177, 9592.088751568608, 9610.903890961088, 9651.806963860736, 2194.0, 2483.2177863972793, 2549.2352294311727, 2579.548578998677, 2580.419405633736, 2569.511792759379, 2593.48130373925, 2595.779358754508, 2595.909143179989, 2597.052075992259, 2514.6102354135137, 2584.198898341511, 2585.1856665150417, 2586.1982785336604, 2587.133310341038, 2119.544297975935, 2388.3551441110803, 2472.307753828931, 2472.950540989188, 2476.4675594747214, 2474.1464194852747, 2496.950060998788, 2519.9748002519923, 2523.9495210095697, 2548.069954466625, 9326.533673316308, 9416.482093484856, 9431.198348140348, 9440.0, 9447.480388578626, 5670.234518340013, 5778.393268706767, 5778.479936805707, 5781.19063331134, 5785.595008349417, 3523.224890524081, 3610.1155216971747, 3653.324135035027, 3767.566729826078, 3781.5462144542557, 1126.4030064066058, 1165.7668466306773, 1192.2695102014573, 1214.4777745569402, 1231.4335405713382, 13553.186808791505, 13782.65543361424, 13821.861781382258, 13831.377588008563, 13835.861641383557, 4980.0, 5005.124103281911, 5049.192129259301, 5129.3075434816565, 5157.432682404932, 11527.193096483166, 11569.42152892352, 11593.0, 11606.94196529012, 11616.709582260512, 5702.059160238514, 5705.087186050212, 5705.62913410627, 5708.914366284509, 5715.514181294637, 4556.749378784132, 5064.746762661852, 5210.739463026834, 5222.529972302435, 5276.683398996072, 4433.866983990485, 4442.20040392726, 4464.441944756905, 4498.3252512123445, 4536.342230376643, 1367.9726405471836, 1392.4196037497513, 1412.583287930063, 1441.740486712381, 1456.3810380588206, 1075.7525769073156, 1079.5304042741432, 1083.869935607732, 1084.6854412220368, 1085.2511766880828, 6410.64741439216, 6434.2600600930555, 6543.836404089936, 6570.277269500351, 6619.86760264792, 481.5930538694797, 489.5129346300454, 496.9726665033458, 504.2461520027582, 510.8595136337537, 4527.59251667345, 4600.493945665974, 4619.491855895849, 4642.953570464252, 4662.813487460531, 11787.115966302561, 11897.34564598938, 11900.047996160287, 11996.280223186637, 12126.636200913987, 11299.0, 11651.59219427333, 11682.70793230176, 11712.531498740125, 11935.641930742091, 8801.867971980426, 8902.59938302765, 8918.687845925491, 8946.284297256203, 9010.59452324647, 6581.07864898905, 6699.464042876461, 6874.759383421556, 6878.7248510059035, 6975.581465112109, 10742.140628749525, 10921.235513513977, 11110.777784444428, 11239.269447485887, 11257.943710281395, 11002.119830413387, 11279.15406344511, 11310.547578096784, 11411.144164187554, 11537.250078744855, 7464.6267686614365, 8239.917600823916, 8321.95839020801, 8455.32357411418, 8484.151584841713, 9329.953350233205, 9389.248860091313, 9456.527173641152, 9522.85715714251, 9541.618335266512, 8268.37987150954, 8436.11420800807, 8455.0, 8579.099194584478, 8587.484750914966, 8043.6782528698195, 8089.3528517719615, 8302.460340077876, 8417.368697347601, 8436.493810371398, 7531.887021694789, 7774.455788094779, 7783.260590243918, 7784.0, 7793.766187014398, 5461.886481359095, 5516.420775818642, 5533.363663178786, 5597.244372009726, 5643.774249030157, 5539.224508568723, 5659.207710920392, 5702.743376547983, 5754.395788442321, 5949.048152295785], "value": 4963.61067607469, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.72901678657075, 339.57553058676655, 341.1267156080433, 346.0397397222707, 346.7988566631354, 4808.903821923559, 4837.726268653736, 4848.951510484894, 4850.514948505148, 4893.241547560125, 4783.162946484369, 4801.0, 4822.132016237076, 4834.782434790434, 4844.830430934918, 3687.9631203688023, 3737.9252414951684, 3874.670652994485, 3919.176972835708, 3941.1329507508303, 3815.179736356683, 3869.729118961674, 3882.417637354405, 3888.863889763859, 3902.161035377393, 3698.9445158322646, 3854.9807250963704, 3860.1507668312925, 3874.554426240976, 3883.611638836122, 3628.600853906068, 3794.165283637595, 3812.1232116613055, 3827.4832897558754, 3846.9038274043232, 3391.812865497081, 3408.7784294021058, 3410.6759857813468, 3411.164264755126, 3417.8632854685784, 4637.443506779175, 4651.209294419923, 4669.1090108506105, 4678.0, 4758.547937945889, 4329.523752387235, 4375.234333991571, 4383.276759334706, 4420.381146639473, 4423.867283981485, 4964.329815474901, 4981.875453113665, 4985.127602669519, 5029.862308518865, 5034.295198672189, 3803.1442925341853, 3812.3137835189655, 3818.84724611018, 3819.4843696100947, 3819.6371344722206, 5108.7445627718835, 5211.879445919146, 5218.817341393071, 5259.999999999962, 5276.261323414725, 3849.7690138591215, 3873.8256778444475, 3880.5835869907846, 3888.5528164260904, 3889.086064774776, 3918.9020274493364, 3939.842406303716, 3944.2111577684577, 3944.8224829882724, 3945.763254204757, 4287.228298906164, 4392.670549708785, 4398.890027749268, 4435.3125265583885, 4441.511433742286, 4972.527609877128, 5035.546800787946, 5044.688381020961, 5045.7981680732355, 5047.848564543069, 1306.8956731561816, 1326.8606796286444, 1327.1373607155233, 1342.718029213876, 1347.7169794343297, 881.5680316644927, 913.4744975890279, 919.9126083022102, 925.2921515040999, 926.5459924637014, 4806.615470762401, 4891.437484689235, 5008.69947803133, 5019.3976722793495, 5039.823606173766, 5180.4458662401885, 5186.429492755794, 5219.269302297681, 5219.869503262449, 5232.319798426181, 4874.512548745168, 4978.5768209701855, 4986.875328116754, 5014.949850501521, 5056.469070747592, 2715.932101697473, 2722.2922040269673, 2725.5502842031237, 2735.931601709933, 2739.1919383781833, 5391.487808658249, 5408.323959505062, 5417.485338892799, 5441.782328706887, 5469.835904922858, 5119.97600479898, 5229.973850130801, 5288.444713305124, 5290.523852853262, 5291.444398338119, 5463.159157238214, 5464.453554644504, 5465.344158700981, 5470.0, 5474.753636086465, 5146.279520867007, 5171.793128274827, 5177.637565370388, 5210.765515551733, 5236.842894713165, 5034.949650503595, 5169.870753231199, 5175.378954525481, 5180.290504133703, 5188.299579556711, 5453.727313634223, 5471.2613797138465, 5473.698946557977, 5475.260839786578, 5489.917651235155, 3920.862769803043, 3932.6264004919485, 3933.9803300983313, 3939.6848252140335, 3945.5659877412886, 5024.949750502595, 5163.432022477599, 5164.690118592896, 5181.145111056784, 5249.921251181312, 4991.850244492671, 5041.294218809443, 5045.325977232073, 5067.974660126823, 5068.011737711111, 5070.594352451869, 5094.592432605456, 5122.487751224848, 5124.30821839047, 5126.4617215191865, 4901.754912254444, 5032.119379108714, 5046.899062018666, 5047.83899703063, 5049.57078648319, 4917.286993385867, 5034.496550344937, 5106.514881086291, 5112.79548818058, 5126.0521002018995, 5042.84871453857, 5043.167877300204, 5060.342155519907, 5064.721440320818, 5066.341375621146], "value": 3935.4744239326974, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.249900103892, 751.4063889527271, 841.1588411588411, 860.6514361683519, 880.6917578847405, 15592.298346574404, 16262.186890655465, 17081.48755537336, 17085.231164597593, 17090.0, 15496.302666380012, 15505.302261398236, 15549.689006219869, 15573.84426155738, 15650.686986260296, 709.2977951827679, 847.4195176304252, 862.9870551941726, 869.4739682492096, 872.8777971084053, 870.1124852650292, 870.8127752533204, 874.396666300254, 874.9343799215085, 875.0636818604096, 918.701422037837, 919.4483310014004, 919.6827094652334, 919.9402038867486, 921.2629896083107, 741.8627553902518, 885.3006125161115, 892.3976315986748, 897.9640814367385, 908.3006085314337, 660.7885476647475, 665.4543274514899, 665.640554100785, 666.4901350466897, 666.8199586111771, 15305.234738263041, 15391.769123463157, 15416.0, 15453.304601292968, 15456.690866182726, 14616.269186540736, 14912.478063267841, 14956.62608434787, 14972.47596334123, 15175.0, 13442.798358024638, 13524.188548687012, 13532.458701651922, 13662.18026918388, 13986.16083035021, 4562.361269422284, 4609.539046095397, 4629.467611224719, 4664.230401983668, 4689.812407503729, 75572.24427755707, 75773.62113189399, 75790.0, 75975.62012189958, 77893.0, 11005.119590432747, 11014.44927753609, 11277.097832173406, 11318.094552435785, 11367.54529818798, 21112.0, 21223.257185998416, 21247.68128478074, 21274.36176914695, 21329.253476128262, 8399.328053755808, 8435.536045517432, 8640.481571105754, 8750.256228220673, 8835.469871807712, 4506.662000349921, 4533.252013417815, 4566.223741963874, 4628.0, 4707.741074240949, 3510.087377281874, 3669.9266014680093, 3673.430618254175, 3681.9631803682155, 3686.594474607845, 611.4160976267653, 613.8618810767631, 665.0356982375574, 668.8194187569329, 710.4245561095529, 613.9938600614026, 683.2006552333781, 779.5868189859384, 824.0, 839.4333824668324, 11304.660860174208, 11553.884461155281, 11760.941515263667, 11761.294322340687, 11890.583829565923, 657.273712547628, 753.408574269196, 764.4839733180081, 801.1827935505752, 831.9625616847256, 4924.285978533093, 5077.18764997606, 5080.32349324722, 5126.077306084941, 5588.30146231721, 78302.21697783063, 81421.0, 125378.0, 128220.0, 129811.35094324657, 98173.0, 105289.21921996662, 106041.99999999846, 106790.0, 107763.46118269516, 95065.5246723762, 95635.0, 95683.0, 96654.0, 96772.0, 10557.102646275152, 10778.08386287143, 11146.442677865913, 11194.66416007521, 11341.26281791681, 54532.454675454326, 55259.44740552543, 56092.15861761995, 56910.0, 56984.1452378223, 79182.60408697919, 83893.0, 83965.0, 85846.57076714576, 86085.0, 14018.0, 14115.505957291445, 14168.079074860101, 14620.926895365455, 14772.852271477148, 15024.173670447793, 15580.766288505909, 15614.375424982873, 15697.29362178682, 16980.150992450574, 10826.621068262573, 12905.354732263537, 13457.259850708306, 14240.359183836545, 14248.572542823731, 11922.52309907594, 13897.930510347383, 14215.50245741394, 14455.927720361331, 14640.780388294397, 11016.669499915015, 15400.229988500307, 15444.5366639001, 15542.456014039455, 15871.68256634884, 12102.697432564257, 12198.342184940615, 12261.448234829633, 12503.499860005863, 12593.0, 12582.119251652663, 12643.810342845049, 12832.230066196058, 12866.228026318451, 13033.934830325788], "value": 8029.093028568133, "unit": "score"}], "name": "dromaeo_css", "value": 7364.313657894901, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 103.0, 109.0, 110.0, 110.0, 108.0, 110.0, 110.0, 108.0, 108.0], "value": 108.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 123.0, 126.0, 173.0, 132.0, 126.0, 157.0, 128.0, 156.0, 131.0], "value": 141.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 159.0, 162.0, 159.0, 160.0, 159.0, 161.0, 159.0, 158.0], "value": 159.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 84.0, 76.0, 111.0, 76.0, 76.0, 72.0, 74.0, 98.0, 74.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 119.0, 85.0, 84.0, 85.0, 114.0, 84.0, 86.0, 120.0], "value": 94.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 147.0, 147.0, 146.0, 146.0, 145.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 57.0, 56.0, 57.0, 56.0, 56.0, 57.0, 57.0, 62.0, 56.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 59.0, 60.0, 59.0, 59.0, 61.0, 60.0, 60.0, 60.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 76.0, 77.0, 83.0, 79.0, 85.0, 78.0, 78.0, 116.0, 79.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 117.0, 130.0, 132.0, 117.0, 134.0, 121.0, 126.0, 132.0, 121.0], "value": 125.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 158.0, 161.0, 158.0, 156.0, 166.0, 155.0, 159.0, 157.0, 155.0], "value": 158.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 62.0, 62.0, 63.0, 61.0, 62.0, 63.0, 61.0, 62.0, 61.0], "value": 62.2, "unit": "score"}], "name": "kraken", "value": 1487.9, "alertThreshold": 2.0}]} 09:56:26 INFO - Return code: 0 09:56:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:56:26 ERROR - # TBPL SUCCESS # 09:56:26 INFO - Running post-action listener: _resource_record_post_action 09:56:26 INFO - ##### 09:56:26 INFO - ##### Finished run-tests step (success) 09:56:26 INFO - ##### 09:56:26 INFO - Running post-run listener: _resource_record_post_run 09:56:26 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 266240; Write bytes: 500355072; Read time: 28; Write time: 1052916 09:56:26 INFO - TinderboxPrint: CPU usage
13.1% 09:56:26 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 28 09:56:26 INFO - TinderboxPrint: I/O write bytes / time
500,355,072 / 1,052,916 09:56:26 INFO - TinderboxPrint: CPU idle
8,263.2 (86.9%) 09:56:26 INFO - TinderboxPrint: CPU user
1,193.8 (12.5%) 09:56:26 INFO - TinderboxPrint: Swap in / out
0 / 0 09:56:26 INFO - install - Wall time: 16s; CPU: 21.0%; Read bytes: 0; Write bytes: 226811904; Read time: 0; Write time: 814140 09:56:26 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 266240; Write bytes: 248942592; Read time: 28; Write time: 230148 09:56:27 INFO - Running post-run listener: _upload_blobber_files 09:56:27 INFO - Blob upload gear active. 09:56:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:56:27 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 09:56:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:56:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:56:27 INFO - (blobuploader) - INFO - Open directory for files ... 09:56:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:56:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:56:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:56:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:56:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:56:31 INFO - (blobuploader) - INFO - Done attempting. 09:56:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:56:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:56:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:56:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:56:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:56:32 INFO - (blobuploader) - INFO - Done attempting. 09:56:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:56:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:56:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:56:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:56:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:56:33 INFO - (blobuploader) - INFO - Done attempting. 09:56:33 INFO - (blobuploader) - INFO - Iteration through files over. 09:56:33 INFO - Return code: 0 09:56:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:56:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:56:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f229765163096b7fd1c542c3fb1200c742c59474d6d85fad44ca21f6df20dde83a7c44bafc6cd3bdbd706911761013c00118d092bbd33872fe41563d1263ef4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/14323577f90b396c116db87b2e4b317ad3b2a3c69ebb3aab89335df6b53fef269ed5fbe556a6c9ba4d4651708db096b33beeff61b8e865cdd513c1034681d03b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a68ff93700b480eb547bc5dcc2d97f4b9c5bc3f07b5374e1191f6b2ad986ef4526366f72b1477d4099cb4495257b77d37f710c7519557987987b522e7aa67fd"} 09:56:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:56:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:56:33 INFO - Contents: 09:56:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f229765163096b7fd1c542c3fb1200c742c59474d6d85fad44ca21f6df20dde83a7c44bafc6cd3bdbd706911761013c00118d092bbd33872fe41563d1263ef4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/14323577f90b396c116db87b2e4b317ad3b2a3c69ebb3aab89335df6b53fef269ed5fbe556a6c9ba4d4651708db096b33beeff61b8e865cdd513c1034681d03b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a68ff93700b480eb547bc5dcc2d97f4b9c5bc3f07b5374e1191f6b2ad986ef4526366f72b1477d4099cb4495257b77d37f710c7519557987987b522e7aa67fd"} 09:56:33 INFO - Running post-run listener: copy_logs_to_upload_dir 09:56:33 INFO - Copying logs to upload dir... 09:56:33 INFO - mkdir: /builds/slave/test/build/upload/logs 09:56:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.331343 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-06-20 09:56:33.288706) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-20 09:56:33.291908) ========= 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=745a4373a2a16df560f216e000000264-1466439366.69894-678773580 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f229765163096b7fd1c542c3fb1200c742c59474d6d85fad44ca21f6df20dde83a7c44bafc6cd3bdbd706911761013c00118d092bbd33872fe41563d1263ef4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/14323577f90b396c116db87b2e4b317ad3b2a3c69ebb3aab89335df6b53fef269ed5fbe556a6c9ba4d4651708db096b33beeff61b8e865cdd513c1034681d03b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a68ff93700b480eb547bc5dcc2d97f4b9c5bc3f07b5374e1191f6b2ad986ef4526366f72b1477d4099cb4495257b77d37f710c7519557987987b522e7aa67fd"} build_url:https://queue.taskcluster.net/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011382 build_url: 'https://queue.taskcluster.net/v1/task/b69pL5EtT26j4AA7WSy0fg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f229765163096b7fd1c542c3fb1200c742c59474d6d85fad44ca21f6df20dde83a7c44bafc6cd3bdbd706911761013c00118d092bbd33872fe41563d1263ef4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/14323577f90b396c116db87b2e4b317ad3b2a3c69ebb3aab89335df6b53fef269ed5fbe556a6c9ba4d4651708db096b33beeff61b8e865cdd513c1034681d03b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3a68ff93700b480eb547bc5dcc2d97f4b9c5bc3f07b5374e1191f6b2ad986ef4526366f72b1477d4099cb4495257b77d37f710c7519557987987b522e7aa67fd"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-20 09:56:33.317266) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 09:56:33.317603) ========= 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=745a4373a2a16df560f216e000000264-1466439366.69894-678773580 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004572 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-20 09:56:33.402575) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-20 09:56:33.402907) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-20 09:56:33.403177) ========= ========= Total master_lag: 0.18 =========