builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-055 starttime: 1469142240.64 results: success (0) buildid: 20160721123119 builduid: 54f093940c824a1fb934e612997e9f38 revision: 588be0abb337b29fd764bf570ef2679e2ffabc21 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 16:04:00.638284) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 16:04:00.638881) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 16:04:00.639437) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 16:04:00.663504) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 16:04:00.663786) ========= 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=bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-21 16:04:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 222M=0s 2016-07-21 16:04:01 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.132682 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 16:04:00.810490) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 16:04:00.810816) ========= 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=bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018976 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 16:04:00.860261) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 16:04:00.860602) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 588be0abb337b29fd764bf570ef2679e2ffabc21 --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 588be0abb337b29fd764bf570ef2679e2ffabc21 --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=bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-21 16:04:01,398 truncating revision to first 12 chars 2016-07-21 16:04:01,398 Setting DEBUG logging. 2016-07-21 16:04:01,398 attempt 1/10 2016-07-21 16:04:01,398 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/588be0abb337?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-21 16:04:01,712 unpacking tar archive at: mozilla-aurora-588be0abb337/testing/mozharness/ program finished with exit code 0 elapsedTime=0.651975 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 16:04:01.526121) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 16:04:01.526460) ========= script_repo_revision: 588be0abb337b29fd764bf570ef2679e2ffabc21 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 16:04:01.526901) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 16:04:01.527195) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 16:04:01.537350) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-07-21 16:04:01.537654) ========= /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=bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:04:02 INFO - MultiFileLogger online at 20160721 16:04:02 in /builds/slave/test 16:04:02 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 16:04:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:04:02 INFO - {'append_to_log': False, 16:04:02 INFO - 'base_work_dir': '/builds/slave/test', 16:04:02 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 16:04:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:04:02 INFO - 'branch': 'Mozilla-Aurora', 16:04:02 INFO - 'buildbot_json_path': 'buildprops.json', 16:04:02 INFO - 'config_files': ('talos/linux_config.py',), 16:04:02 INFO - 'default_actions': ('clobber', 16:04:02 INFO - 'read-buildbot-config', 16:04:02 INFO - 'download-and-extract', 16:04:02 INFO - 'populate-webroot', 16:04:02 INFO - 'create-virtualenv', 16:04:02 INFO - 'install', 16:04:02 INFO - 'run-tests'), 16:04:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:04:02 INFO - 'download_minidump_stackwalk': True, 16:04:02 INFO - 'download_symbols': 'ondemand', 16:04:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:04:02 INFO - 'tooltool.py': '/tools/tooltool.py', 16:04:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:04:02 INFO - '/tools/misc-python/virtualenv.py')}, 16:04:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:04:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:04:02 INFO - 'installer_path': 'installer.exe', 16:04:02 INFO - 'log_level': 'info', 16:04:02 INFO - 'log_name': 'talos', 16:04:02 INFO - 'log_to_console': True, 16:04:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:04:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:04:02 INFO - 'opt_config_files': (), 16:04:02 INFO - 'pip_index': False, 16:04:02 INFO - 'sps_profile': False, 16:04:02 INFO - 'sps_profile_interval': 0, 16:04:02 INFO - 'suite': 'dromaeojs', 16:04:02 INFO - 'system_bits': '32', 16:04:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:04:02 INFO - 'title': 'talos-linux64-ix-055', 16:04:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:04:02 INFO - 'use_talos_json': True, 16:04:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:04:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:04:02 INFO - 'work_dir': 'build'} 16:04:02 INFO - ##### 16:04:02 INFO - ##### Running clobber step. 16:04:02 INFO - ##### 16:04:02 INFO - Running pre-action listener: _resource_record_pre_action 16:04:02 INFO - Running main action method: clobber 16:04:02 INFO - rmtree: /builds/slave/test/build 16:04:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:04:02 INFO - Running post-action listener: _resource_record_post_action 16:04:02 INFO - ##### 16:04:02 INFO - ##### Finished clobber step (success) 16:04:02 INFO - ##### 16:04:02 INFO - ##### 16:04:02 INFO - ##### Running read-buildbot-config step. 16:04:02 INFO - ##### 16:04:02 INFO - Running pre-action listener: _resource_record_pre_action 16:04:02 INFO - Running main action method: read_buildbot_config 16:04:02 INFO - Using buildbot properties: 16:04:02 INFO - { 16:04:02 INFO - "project": "", 16:04:02 INFO - "product": "firefox", 16:04:02 INFO - "script_repo_revision": "production", 16:04:02 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 16:04:02 INFO - "repository": "", 16:04:02 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 16:04:02 INFO - "buildid": "20160721123119", 16:04:02 INFO - "pgo_build": "True", 16:04:02 INFO - "basedir": "/builds/slave/test", 16:04:02 INFO - "buildnumber": 378, 16:04:02 INFO - "slavename": "talos-linux64-ix-055", 16:04:02 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:04:02 INFO - "platform": "ubuntu64_hw", 16:04:02 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 16:04:02 INFO - "branch": "mozilla-aurora", 16:04:02 INFO - "repo_path": "releases/mozilla-aurora", 16:04:02 INFO - "revision": "588be0abb337b29fd764bf570ef2679e2ffabc21", 16:04:02 INFO - "stage_platform": "linux64-pgo", 16:04:02 INFO - "builduid": "54f093940c824a1fb934e612997e9f38", 16:04:02 INFO - "slavebuilddir": "test" 16:04:02 INFO - } 16:04:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/C2tcmztBRRqISSzUHt9uzg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 16:04:02 INFO - Running post-action listener: _resource_record_post_action 16:04:02 INFO - ##### 16:04:02 INFO - ##### Finished read-buildbot-config step (success) 16:04:02 INFO - ##### 16:04:02 INFO - ##### 16:04:02 INFO - ##### Running download-and-extract step. 16:04:02 INFO - ##### 16:04:02 INFO - Running pre-action listener: _resource_record_pre_action 16:04:02 INFO - Running main action method: download_and_extract 16:04:02 INFO - mkdir: /builds/slave/test/build/tests 16:04:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:02 INFO - https://queue.taskcluster.net/v1/task/C2tcmztBRRqISSzUHt9uzg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:04:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C2tcmztBRRqISSzUHt9uzg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 16:04:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C2tcmztBRRqISSzUHt9uzg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 16:04:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C2tcmztBRRqISSzUHt9uzg/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 16:04:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C2tcmztBRRqISSzUHt9uzg/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 16:04:08 INFO - Downloaded 1361 bytes. 16:04:08 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 16:04:08 INFO - Using the following test package requirements: 16:04:08 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 16:04:08 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 16:04:08 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 16:04:08 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 16:04:08 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 16:04:08 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 16:04:08 INFO - u'jsshell-linux-x86_64.zip'], 16:04:08 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 16:04:08 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 16:04:08 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 16:04:08 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 16:04:08 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 16:04:08 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 16:04:08 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 16:04:08 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 16:04:08 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 16:04:08 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 16:04:08 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 16:04:08 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:04:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:08 INFO - https://queue.taskcluster.net/v1/task/C2tcmztBRRqISSzUHt9uzg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:04:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C2tcmztBRRqISSzUHt9uzg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 16:04:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C2tcmztBRRqISSzUHt9uzg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 16:04:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C2tcmztBRRqISSzUHt9uzg/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 16:04:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C2tcmztBRRqISSzUHt9uzg/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 16:04:23 INFO - Downloaded 24491039 bytes. 16:04:23 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'] 16:04:23 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 16:04:24 INFO - Return code: 0 16:04:24 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 16:04:24 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')]} 16:04:24 INFO - https://queue.taskcluster.net/v1/task/C2tcmztBRRqISSzUHt9uzg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:04:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C2tcmztBRRqISSzUHt9uzg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 16:04:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C2tcmztBRRqISSzUHt9uzg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 16:04:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C2tcmztBRRqISSzUHt9uzg/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 16:04:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C2tcmztBRRqISSzUHt9uzg/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 16:04:29 INFO - Downloaded 24491039 bytes. 16:04:29 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'] 16:04:29 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 16:04:31 INFO - Return code: 0 16:04: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')]} 16:04:31 INFO - https://queue.taskcluster.net/v1/task/C2tcmztBRRqISSzUHt9uzg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:04:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C2tcmztBRRqISSzUHt9uzg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 16:04:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C2tcmztBRRqISSzUHt9uzg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 16:04:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C2tcmztBRRqISSzUHt9uzg/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 16:04:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C2tcmztBRRqISSzUHt9uzg/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 16:04:33 INFO - Downloaded 10833369 bytes. 16:04:33 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'] 16:04:33 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 16:04:33 INFO - Return code: 0 16:04:33 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')]} 16:04:33 INFO - https://queue.taskcluster.net/v1/task/C2tcmztBRRqISSzUHt9uzg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:04:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C2tcmztBRRqISSzUHt9uzg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 16:04:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C2tcmztBRRqISSzUHt9uzg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 16:04:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C2tcmztBRRqISSzUHt9uzg/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 16:04:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C2tcmztBRRqISSzUHt9uzg/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 16:04:35 INFO - Downloaded 63635525 bytes. 16:04:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/C2tcmztBRRqISSzUHt9uzg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 16:04:35 INFO - mkdir: /builds/slave/test/properties 16:04:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:04:35 INFO - Writing to file /builds/slave/test/properties/build_url 16:04:35 INFO - Contents: 16:04:35 INFO - build_url:https://queue.taskcluster.net/v1/task/C2tcmztBRRqISSzUHt9uzg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 16:04:36 INFO - Running post-action listener: _resource_record_post_action 16:04:36 INFO - Running post-action listener: set_extra_try_arguments 16:04:36 INFO - ##### 16:04:36 INFO - ##### Finished download-and-extract step (success) 16:04:36 INFO - ##### 16:04:36 INFO - ##### 16:04:36 INFO - ##### Running populate-webroot step. 16:04:36 INFO - ##### 16:04:36 INFO - Running pre-action listener: _resource_record_pre_action 16:04:36 INFO - Running main action method: populate_webroot 16:04:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:04:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:04:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:04:36 INFO - '--tppagecycles', 16:04:36 INFO - '7'], 16:04:36 INFO - 'tests': ['tsvgm']}}, 16:04:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:04:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:04:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:04:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:04:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:04:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:04:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:04:36 INFO - 'talos_options': ['--disable-e10s'], 16:04:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:04:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:04:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:04:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:04:36 INFO - 'talos_options': ['--disable-e10s'], 16:04:36 INFO - 'tests': ['damp', 'tps']}, 16:04:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:04:36 INFO - 'tests': ['damp', 'tps']}, 16:04:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:04:36 INFO - 'tests': ['dromaeo_dom']}, 16:04:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:04:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:04:36 INFO - 'tests': ['a11yr', 16:04:36 INFO - 'ts_paint', 16:04:36 INFO - 'tpaint', 16:04:36 INFO - 'sessionrestore', 16:04:36 INFO - 'sessionrestore_no_auto_restore', 16:04:36 INFO - 'tabpaint']}, 16:04:36 INFO - 'other-e10s': {'tests': ['a11yr', 16:04:36 INFO - 'ts_paint', 16:04:36 INFO - 'tpaint', 16:04:36 INFO - 'sessionrestore', 16:04:36 INFO - 'sessionrestore_no_auto_restore', 16:04:36 INFO - 'tabpaint']}, 16:04:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:04:36 INFO - 'ts_paint', 16:04:36 INFO - 'tpaint', 16:04:36 INFO - 'sessionrestore', 16:04:36 INFO - 'sessionrestore_no_auto_restore', 16:04:36 INFO - 'tabpaint']}, 16:04:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:04:36 INFO - 'ts_paint', 16:04:36 INFO - 'tpaint', 16:04:36 INFO - 'sessionrestore', 16:04:36 INFO - 'sessionrestore_no_auto_restore', 16:04:36 INFO - 'tabpaint']}, 16:04:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:04:36 INFO - 'tests': ['a11yr', 16:04:36 INFO - 'ts_paint', 16:04:36 INFO - 'tpaint', 16:04:36 INFO - 'sessionrestore', 16:04:36 INFO - 'sessionrestore_no_auto_restore', 16:04:36 INFO - 'tabpaint']}, 16:04:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:04:36 INFO - 'tests': ['a11yr', 16:04:36 INFO - 'ts_paint', 16:04:36 INFO - 'tpaint', 16:04:36 INFO - 'sessionrestore', 16:04:36 INFO - 'sessionrestore_no_auto_restore', 16:04:36 INFO - 'tabpaint']}, 16:04:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:04:36 INFO - 'tests': ['tsvgx', 16:04:36 INFO - 'tsvgr_opacity', 16:04:36 INFO - 'tart', 16:04:36 INFO - 'tscrollx', 16:04:36 INFO - 'cart']}, 16:04:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:04:36 INFO - 'tsvgr_opacity', 16:04:36 INFO - 'tart', 16:04:36 INFO - 'tscrollx', 16:04:36 INFO - 'cart']}, 16:04:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:04:36 INFO - 'talos_options': ['--disable-e10s'], 16:04:36 INFO - 'tests': ['tp5o']}, 16:04:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:04:36 INFO - 'tests': ['tp5o']}, 16:04:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:04:36 INFO - 'talos_options': ['--disable-e10s', 16:04:36 INFO - '--xperf_path', 16:04:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:04:36 INFO - 'tests': ['tp5n']}, 16:04:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:04:36 INFO - 'talos_options': ['--xperf_path', 16:04:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:04:36 INFO - 'tests': ['tp5n']}}, 16:04:36 INFO - 'talos.zip': {'path': '', 16:04:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:04:36 INFO - Running post-action listener: _resource_record_post_action 16:04:36 INFO - ##### 16:04:36 INFO - ##### Finished populate-webroot step (success) 16:04:36 INFO - ##### 16:04:36 INFO - ##### 16:04:36 INFO - ##### Running create-virtualenv step. 16:04:36 INFO - ##### 16:04:36 INFO - Running pre-action listener: _resource_record_pre_action 16:04:36 INFO - Running main action method: create_virtualenv 16:04:36 INFO - Creating virtualenv /builds/slave/test/build/venv 16:04:36 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 16:04:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:04:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:04:36 INFO - Using real prefix '/usr' 16:04:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:04:36 INFO - Installing distribute.............................................................................................................................................................................................done. 16:04:38 INFO - Installing pip.................done. 16:04:38 INFO - Return code: 0 16:04:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5f44f1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12f82e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f2e20>, '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 0x12f63a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12f6830>, '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': 'bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066', '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 16:04:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:04:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:04:38 INFO - Using env: {'DISPLAY': ':0', 16:04:38 INFO - 'HOME': '/home/cltbld', 16:04:38 INFO - 'LANG': 'en_US.UTF-8', 16:04:38 INFO - 'LANGUAGE': 'en_US:en', 16:04:38 INFO - 'LOGNAME': 'cltbld', 16:04:38 INFO - 'MAIL': '/var/mail/cltbld', 16:04:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:38 INFO - 'MOZ_NO_REMOTE': '1', 16:04:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:38 INFO - 'NO_EM_RESTART': '1', 16:04:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:38 INFO - 'PWD': '/builds/slave/test', 16:04:38 INFO - 'SHELL': '/bin/bash', 16:04:38 INFO - 'SHLVL': '1', 16:04:38 INFO - 'TERM': 'linux', 16:04:38 INFO - 'TMOUT': '86400', 16:04:38 INFO - 'USER': 'cltbld', 16:04:38 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066', 16:04:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:38 INFO - '_': '/tools/buildbot/bin/python'} 16:04:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:38 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 16:04:38 INFO - Cleaning up... 16:04:38 INFO - Return code: 0 16:04:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f5f44f1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12f82e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f2e20>, '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 0x12f63a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12f6830>, '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': 'bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066', '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 16:04:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:04:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:04:38 INFO - Using env: {'DISPLAY': ':0', 16:04:38 INFO - 'HOME': '/home/cltbld', 16:04:38 INFO - 'LANG': 'en_US.UTF-8', 16:04:38 INFO - 'LANGUAGE': 'en_US:en', 16:04:38 INFO - 'LOGNAME': 'cltbld', 16:04:38 INFO - 'MAIL': '/var/mail/cltbld', 16:04:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:38 INFO - 'MOZ_NO_REMOTE': '1', 16:04:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:38 INFO - 'NO_EM_RESTART': '1', 16:04:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:38 INFO - 'PWD': '/builds/slave/test', 16:04:38 INFO - 'SHELL': '/bin/bash', 16:04:38 INFO - 'SHLVL': '1', 16:04:38 INFO - 'TERM': 'linux', 16:04:38 INFO - 'TMOUT': '86400', 16:04:38 INFO - 'USER': 'cltbld', 16:04:38 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066', 16:04:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:38 INFO - '_': '/tools/buildbot/bin/python'} 16:04:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:39 INFO - Downloading/unpacking psutil>=3.1.1 16:04:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:04:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:04:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:04:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:04:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:04:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:04:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:04:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:04:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:04:41 INFO - Installing collected packages: psutil 16:04:41 INFO - Running setup.py install for psutil 16:04:41 INFO - building 'psutil._psutil_linux' extension 16:04:41 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 16:04:41 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 16:04:41 INFO - building 'psutil._psutil_posix' extension 16:04:41 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 16:04:41 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 16:04:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:04:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:04:41 INFO - Successfully installed psutil 16:04:41 INFO - Cleaning up... 16:04:41 INFO - Return code: 0 16:04:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:04: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')]} 16:04:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04: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')]} 16:04:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:41 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 0x7f5f44f1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12f82e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f2e20>, '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 0x12f63a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12f6830>, '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': 'bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066', '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 16:04:41 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 16:04:41 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 16:04:41 INFO - Using env: {'DISPLAY': ':0', 16:04:41 INFO - 'HOME': '/home/cltbld', 16:04:41 INFO - 'LANG': 'en_US.UTF-8', 16:04:41 INFO - 'LANGUAGE': 'en_US:en', 16:04:41 INFO - 'LOGNAME': 'cltbld', 16:04:41 INFO - 'MAIL': '/var/mail/cltbld', 16:04:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:41 INFO - 'MOZ_NO_REMOTE': '1', 16:04:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:41 INFO - 'NO_EM_RESTART': '1', 16:04:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:41 INFO - 'PWD': '/builds/slave/test', 16:04:41 INFO - 'SHELL': '/bin/bash', 16:04:41 INFO - 'SHLVL': '1', 16:04:41 INFO - 'TERM': 'linux', 16:04:41 INFO - 'TMOUT': '86400', 16:04:41 INFO - 'USER': 'cltbld', 16:04:41 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066', 16:04:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:41 INFO - '_': '/tools/buildbot/bin/python'} 16:04:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:04:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:04:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:04:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:04:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:04:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:04:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:04:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:04:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:04:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:04:43 INFO - Installing collected packages: mozsystemmonitor 16:04:43 INFO - Running setup.py install for mozsystemmonitor 16:04:43 INFO - Successfully installed mozsystemmonitor 16:04:43 INFO - Cleaning up... 16:04:44 INFO - Return code: 0 16:04:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:44 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 0x7f5f44f1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12f82e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f2e20>, '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 0x12f63a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12f6830>, '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': 'bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066', '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 16:04:44 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 16:04:44 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 16:04:44 INFO - Using env: {'DISPLAY': ':0', 16:04:44 INFO - 'HOME': '/home/cltbld', 16:04:44 INFO - 'LANG': 'en_US.UTF-8', 16:04:44 INFO - 'LANGUAGE': 'en_US:en', 16:04:44 INFO - 'LOGNAME': 'cltbld', 16:04:44 INFO - 'MAIL': '/var/mail/cltbld', 16:04:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:44 INFO - 'MOZ_NO_REMOTE': '1', 16:04:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:44 INFO - 'NO_EM_RESTART': '1', 16:04:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:44 INFO - 'PWD': '/builds/slave/test', 16:04:44 INFO - 'SHELL': '/bin/bash', 16:04:44 INFO - 'SHLVL': '1', 16:04:44 INFO - 'TERM': 'linux', 16:04:44 INFO - 'TMOUT': '86400', 16:04:44 INFO - 'USER': 'cltbld', 16:04:44 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066', 16:04:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:44 INFO - '_': '/tools/buildbot/bin/python'} 16:04:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:44 INFO - Downloading/unpacking jsonschema==2.5.1 16:04:44 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 16:04:44 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 16:04:44 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 16:04:44 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 16:04:44 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 16:04:44 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 16:04:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:04:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:04:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:04:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:04:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:04:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:04:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:04:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:04:46 INFO - Downloading functools32-3.2.3-2.tar.gz 16:04:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:04:46 INFO - warning: no files found matching '*.txt' 16:04:46 INFO - no previously-included directories found matching 'build' 16:04:46 INFO - no previously-included directories found matching 'dist' 16:04:46 INFO - no previously-included directories found matching '.git*' 16:04:46 INFO - Installing collected packages: jsonschema, functools32 16:04:46 INFO - Running setup.py install for functools32 16:04:46 INFO - warning: no files found matching '*.txt' 16:04:46 INFO - no previously-included directories found matching 'build' 16:04:46 INFO - no previously-included directories found matching 'dist' 16:04:46 INFO - no previously-included directories found matching '.git*' 16:04:46 INFO - Successfully installed jsonschema functools32 16:04:46 INFO - Cleaning up... 16:04:46 INFO - Return code: 0 16:04:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:04:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:46 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 0x7f5f44f1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12f82e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f2e20>, '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 0x12f63a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12f6830>, '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': 'bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066', '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 16:04:46 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 16:04:46 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 16:04:46 INFO - Using env: {'DISPLAY': ':0', 16:04:46 INFO - 'HOME': '/home/cltbld', 16:04:46 INFO - 'LANG': 'en_US.UTF-8', 16:04:46 INFO - 'LANGUAGE': 'en_US:en', 16:04:46 INFO - 'LOGNAME': 'cltbld', 16:04:46 INFO - 'MAIL': '/var/mail/cltbld', 16:04:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:46 INFO - 'MOZ_NO_REMOTE': '1', 16:04:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:46 INFO - 'NO_EM_RESTART': '1', 16:04:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:46 INFO - 'PWD': '/builds/slave/test', 16:04:46 INFO - 'SHELL': '/bin/bash', 16:04:46 INFO - 'SHLVL': '1', 16:04:46 INFO - 'TERM': 'linux', 16:04:46 INFO - 'TMOUT': '86400', 16:04:46 INFO - 'USER': 'cltbld', 16:04:46 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066', 16:04:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:46 INFO - '_': '/tools/buildbot/bin/python'} 16:04:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:46 INFO - Downloading/unpacking blobuploader==1.2.4 16:04:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:04:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:04:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:04:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:04:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:04:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:04:48 INFO - Downloading blobuploader-1.2.4.tar.gz 16:04:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:04:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:04:48 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 16:04:48 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 16:04:48 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 16:04:48 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 16:04:48 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 16:04:48 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 16:04:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:04:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:04:49 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 16:04:49 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 16:04:49 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 16:04:49 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 16:04:49 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 16:04:49 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 16:04:49 INFO - Downloading docopt-0.6.1.tar.gz 16:04:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:04:49 INFO - Installing collected packages: blobuploader, requests, docopt 16:04:49 INFO - Running setup.py install for blobuploader 16:04:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:04:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:04:49 INFO - Running setup.py install for requests 16:04:49 INFO - Running setup.py install for docopt 16:04:49 INFO - Successfully installed blobuploader requests docopt 16:04:49 INFO - Cleaning up... 16:04:50 INFO - Return code: 0 16:04:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:04: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')]} 16:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04: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')]} 16:04:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:50 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 0x7f5f44f1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12f82e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f2e20>, '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 0x12f63a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12f6830>, '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': 'bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066', '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 16:04:50 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 16:04:50 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 16:04:50 INFO - Using env: {'DISPLAY': ':0', 16:04:50 INFO - 'HOME': '/home/cltbld', 16:04:50 INFO - 'LANG': 'en_US.UTF-8', 16:04:50 INFO - 'LANGUAGE': 'en_US:en', 16:04:50 INFO - 'LOGNAME': 'cltbld', 16:04:50 INFO - 'MAIL': '/var/mail/cltbld', 16:04:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:50 INFO - 'MOZ_NO_REMOTE': '1', 16:04:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:50 INFO - 'NO_EM_RESTART': '1', 16:04:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:50 INFO - 'PWD': '/builds/slave/test', 16:04:50 INFO - 'SHELL': '/bin/bash', 16:04:50 INFO - 'SHLVL': '1', 16:04:50 INFO - 'TERM': 'linux', 16:04:50 INFO - 'TMOUT': '86400', 16:04:50 INFO - 'USER': 'cltbld', 16:04:50 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066', 16:04:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:50 INFO - '_': '/tools/buildbot/bin/python'} 16:04:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:04:50 INFO - Running setup.py (path:/tmp/pip-Aik1Hq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:04:50 INFO - Running setup.py (path:/tmp/pip-S0vzvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:04:50 INFO - Running setup.py (path:/tmp/pip-PIvvVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:04:50 INFO - Running setup.py (path:/tmp/pip-mYKBzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:04:50 INFO - Running setup.py (path:/tmp/pip-wsKAUx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:04:50 INFO - Running setup.py (path:/tmp/pip-KF1cwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:04:50 INFO - Running setup.py (path:/tmp/pip-0dJ5bk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:04:50 INFO - Running setup.py (path:/tmp/pip-v5QyTl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:04:50 INFO - Running setup.py (path:/tmp/pip-wm3pIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:04:50 INFO - Running setup.py (path:/tmp/pip-ZKwlBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:04:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:04:50 INFO - Running setup.py (path:/tmp/pip-81_Mb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:04:51 INFO - Running setup.py (path:/tmp/pip-mA6mUP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:04:51 INFO - Running setup.py (path:/tmp/pip-CcM232-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:04:51 INFO - Running setup.py (path:/tmp/pip-BdoGNI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:04:51 INFO - Running setup.py (path:/tmp/pip-9I4HKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:04:51 INFO - Running setup.py (path:/tmp/pip-oN1pCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:04:51 INFO - Running setup.py (path:/tmp/pip-rniV7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:04:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:04:51 INFO - Running setup.py install for manifestparser 16:04:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:04:51 INFO - Running setup.py install for mozcrash 16:04:51 INFO - Running setup.py install for mozdebug 16:04:51 INFO - Running setup.py install for mozdevice 16:04:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:04:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:04:51 INFO - Running setup.py install for mozfile 16:04:51 INFO - Running setup.py install for mozhttpd 16:04:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:04:52 INFO - Running setup.py install for mozinfo 16:04:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:04:52 INFO - Running setup.py install for mozInstall 16:04:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:04:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:04:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:04:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:04:52 INFO - Running setup.py install for mozleak 16:04:52 INFO - Running setup.py install for mozlog 16:04:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:04:52 INFO - Running setup.py install for moznetwork 16:04:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:04:52 INFO - Running setup.py install for mozprocess 16:04:52 INFO - Running setup.py install for mozprofile 16:04:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:04:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:04:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:04:52 INFO - Running setup.py install for mozrunner 16:04:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:04:52 INFO - Running setup.py install for mozscreenshot 16:04:53 INFO - Running setup.py install for moztest 16:04:53 INFO - Running setup.py install for mozversion 16:04:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:04:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:04:53 INFO - Cleaning up... 16:04:53 INFO - Return code: 0 16:04:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:04:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:04:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:53 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 0x7f5f44f1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12f82e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f2e20>, '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 0x12f63a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12f6830>, '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': 'bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066', '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 16:04:53 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 16:04:53 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 16:04:53 INFO - Using env: {'DISPLAY': ':0', 16:04:53 INFO - 'HOME': '/home/cltbld', 16:04:53 INFO - 'LANG': 'en_US.UTF-8', 16:04:53 INFO - 'LANGUAGE': 'en_US:en', 16:04:53 INFO - 'LOGNAME': 'cltbld', 16:04:53 INFO - 'MAIL': '/var/mail/cltbld', 16:04:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:53 INFO - 'MOZ_NO_REMOTE': '1', 16:04:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:53 INFO - 'NO_EM_RESTART': '1', 16:04:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:53 INFO - 'PWD': '/builds/slave/test', 16:04:53 INFO - 'SHELL': '/bin/bash', 16:04:53 INFO - 'SHLVL': '1', 16:04:53 INFO - 'TERM': 'linux', 16:04:53 INFO - 'TMOUT': '86400', 16:04:53 INFO - 'USER': 'cltbld', 16:04:53 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066', 16:04:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:53 INFO - '_': '/tools/buildbot/bin/python'} 16:04:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:04:53 INFO - Running setup.py (path:/tmp/pip-8eC4ZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:04:53 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)) 16:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:04:53 INFO - Running setup.py (path:/tmp/pip-XKnpk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:04:53 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)) 16:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:04:53 INFO - Running setup.py (path:/tmp/pip-yKSrLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:04:53 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)) 16:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:04:53 INFO - Running setup.py (path:/tmp/pip-pyCnlv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:04:53 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)) 16:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:04:53 INFO - Running setup.py (path:/tmp/pip-zZgir3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:04:53 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)) 16:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:04:53 INFO - Running setup.py (path:/tmp/pip-dJVM5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:04:53 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)) 16:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:04:53 INFO - Running setup.py (path:/tmp/pip-iDTlbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:04:53 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)) 16:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:04:53 INFO - Running setup.py (path:/tmp/pip-UATNQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:04:54 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)) 16:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:04:54 INFO - Running setup.py (path:/tmp/pip-DUVvEi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:04:54 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)) 16:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:04:54 INFO - Running setup.py (path:/tmp/pip-Wk8XRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:04:54 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)) 16:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:04:54 INFO - Running setup.py (path:/tmp/pip-L8NPmq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:04:54 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)) 16:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:04:54 INFO - Running setup.py (path:/tmp/pip-6XchUP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:04:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 16:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:04:54 INFO - Running setup.py (path:/tmp/pip-9DgIAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:04:54 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)) 16:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:04:54 INFO - Running setup.py (path:/tmp/pip-nQSF4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:04:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 16:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:04:54 INFO - Running setup.py (path:/tmp/pip-vPS494-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:04:54 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)) 16:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:04:54 INFO - Running setup.py (path:/tmp/pip-jCbccW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:04:54 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)) 16:04:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:04:54 INFO - Running setup.py (path:/tmp/pip-YgrmYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:04:54 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)) 16:04:54 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)) 16:04:54 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)) 16:04:54 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)) 16:04:54 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)) 16:04:54 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)) 16:04:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:04:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:04:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:04:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:04:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:04:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:04:56 INFO - Downloading blessings-1.6.tar.gz 16:04:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:04:56 INFO - Installing collected packages: blessings 16:04:56 INFO - Running setup.py install for blessings 16:04:56 INFO - Successfully installed blessings 16:04:56 INFO - Cleaning up... 16:04:56 INFO - Return code: 0 16:04:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:04:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:04:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:04:57 INFO - Reading from file tmpfile_stdout 16:04:57 INFO - Current package versions: 16:04:57 INFO - argparse == 1.2.1 16:04:57 INFO - blessings == 1.6 16:04:57 INFO - blobuploader == 1.2.4 16:04:57 INFO - docopt == 0.6.1 16:04:57 INFO - functools32 == 3.2.3-2 16:04:57 INFO - jsonschema == 2.5.1 16:04:57 INFO - manifestparser == 1.1 16:04:57 INFO - mozInstall == 1.12 16:04:57 INFO - mozcrash == 0.17 16:04:57 INFO - mozdebug == 0.1 16:04:57 INFO - mozdevice == 0.48 16:04:57 INFO - mozfile == 1.2 16:04:57 INFO - mozhttpd == 0.7 16:04:57 INFO - mozinfo == 0.9 16:04:57 INFO - mozleak == 0.1 16:04:57 INFO - mozlog == 3.2 16:04:57 INFO - moznetwork == 0.27 16:04:57 INFO - mozprocess == 0.23 16:04:57 INFO - mozprofile == 0.28 16:04:57 INFO - mozrunner == 6.12 16:04:57 INFO - mozscreenshot == 0.1 16:04:57 INFO - mozsystemmonitor == 0.3 16:04:57 INFO - moztest == 0.7 16:04:57 INFO - mozversion == 1.4 16:04:57 INFO - psutil == 3.1.1 16:04:57 INFO - requests == 1.2.3 16:04:57 INFO - wsgiref == 0.1.2 16:04:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:04:57 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')]} 16:04:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:57 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')]} 16:04:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:57 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 0x7f5f44f1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12f82e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f2e20>, '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 0x12f63a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12f6830>, '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': 'bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066', '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 16:04:57 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 16:04:57 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 16:04:57 INFO - Using env: {'DISPLAY': ':0', 16:04:57 INFO - 'HOME': '/home/cltbld', 16:04:57 INFO - 'LANG': 'en_US.UTF-8', 16:04:57 INFO - 'LANGUAGE': 'en_US:en', 16:04:57 INFO - 'LOGNAME': 'cltbld', 16:04:57 INFO - 'MAIL': '/var/mail/cltbld', 16:04:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:57 INFO - 'MOZ_NO_REMOTE': '1', 16:04:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:57 INFO - 'NO_EM_RESTART': '1', 16:04:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:57 INFO - 'PWD': '/builds/slave/test', 16:04:57 INFO - 'SHELL': '/bin/bash', 16:04:57 INFO - 'SHLVL': '1', 16:04:57 INFO - 'TERM': 'linux', 16:04:57 INFO - 'TMOUT': '86400', 16:04:57 INFO - 'USER': 'cltbld', 16:04:57 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066', 16:04:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:57 INFO - '_': '/tools/buildbot/bin/python'} 16:04:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:57 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)) 16:04:57 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)) 16:04:57 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)) 16:04:57 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)) 16:04:57 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)) 16:04:57 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)) 16:04:57 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)) 16:04:57 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)) 16:04:57 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)) 16:04:57 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)) 16:04:57 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)) 16:04:57 INFO - Cleaning up... 16:04:57 INFO - Return code: 0 16:04:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:04:57 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')]} 16:04:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:57 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')]} 16:04:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:57 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 0x7f5f44f1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12f82e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f2e20>, '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 0x12f63a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12f6830>, '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': 'bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066', '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 16:04:57 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 16:04:57 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 16:04:57 INFO - Using env: {'DISPLAY': ':0', 16:04:57 INFO - 'HOME': '/home/cltbld', 16:04:57 INFO - 'LANG': 'en_US.UTF-8', 16:04:57 INFO - 'LANGUAGE': 'en_US:en', 16:04:57 INFO - 'LOGNAME': 'cltbld', 16:04:57 INFO - 'MAIL': '/var/mail/cltbld', 16:04:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:57 INFO - 'MOZ_NO_REMOTE': '1', 16:04:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:57 INFO - 'NO_EM_RESTART': '1', 16:04:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:57 INFO - 'PWD': '/builds/slave/test', 16:04:57 INFO - 'SHELL': '/bin/bash', 16:04:57 INFO - 'SHLVL': '1', 16:04:57 INFO - 'TERM': 'linux', 16:04:57 INFO - 'TMOUT': '86400', 16:04:57 INFO - 'USER': 'cltbld', 16:04:57 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066', 16:04:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:57 INFO - '_': '/tools/buildbot/bin/python'} 16:04:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:04:57 INFO - Cleaning up... 16:04:57 INFO - Return code: 0 16:04:57 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:04:57 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')]} 16:04:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:04:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:57 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')]} 16:04:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:04:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:04:57 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 0x7f5f44f1af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x122a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12f82e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12f2e20>, '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 0x12f63a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12f6830>, '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': 'bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066', '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 16:04:57 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 16:04:57 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 16:04:57 INFO - Using env: {'DISPLAY': ':0', 16:04:57 INFO - 'HOME': '/home/cltbld', 16:04:57 INFO - 'LANG': 'en_US.UTF-8', 16:04:57 INFO - 'LANGUAGE': 'en_US:en', 16:04:57 INFO - 'LOGNAME': 'cltbld', 16:04:57 INFO - 'MAIL': '/var/mail/cltbld', 16:04:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:04:57 INFO - 'MOZ_NO_REMOTE': '1', 16:04:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:04:57 INFO - 'NO_EM_RESTART': '1', 16:04:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:04:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:04:57 INFO - 'PWD': '/builds/slave/test', 16:04:57 INFO - 'SHELL': '/bin/bash', 16:04:57 INFO - 'SHLVL': '1', 16:04:57 INFO - 'TERM': 'linux', 16:04:57 INFO - 'TMOUT': '86400', 16:04:57 INFO - 'USER': 'cltbld', 16:04:57 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066', 16:04:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:04:57 INFO - '_': '/tools/buildbot/bin/python'} 16:04:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:04:57 INFO - Downloading/unpacking flake8 16:04:57 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 16:04:57 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 16:04:57 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 16:04:57 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 16:04:57 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 16:04:57 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 16:04:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:04:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:04:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:04:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:04:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:04:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:04:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:04:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:04:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:04:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:04:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:04:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:04:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:05:00 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:05:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:05:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:05:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:05:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:05:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:05:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:05:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:05:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:05:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:05:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:05:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:05:00 INFO - no previously-included directories found matching 'docs/_build' 16:05:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:05:00 INFO - Running setup.py install for pep8 16:05:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:05:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:05:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:05:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:05:00 INFO - no previously-included directories found matching 'docs/_build' 16:05:00 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:05:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:05:00 INFO - Cleaning up... 16:05:00 INFO - Return code: 0 16:05:00 INFO - Running post-action listener: _resource_record_post_action 16:05:00 INFO - Running post-action listener: _start_resource_monitoring 16:05:00 INFO - Starting resource monitoring. 16:05:00 INFO - ##### 16:05:00 INFO - ##### Finished create-virtualenv step (success) 16:05:00 INFO - ##### 16:05:00 INFO - ##### 16:05:00 INFO - ##### Running install step. 16:05:00 INFO - ##### 16:05:00 INFO - Running pre-action listener: _resource_record_pre_action 16:05:00 INFO - Running main action method: install 16:05:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:05:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:05:00 INFO - Reading from file tmpfile_stdout 16:05:00 INFO - Detecting whether we're running mozinstall >=1.0... 16:05:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:05:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:05:00 INFO - Reading from file tmpfile_stdout 16:05:00 INFO - Output received: 16:05:00 INFO - Usage: mozinstall [options] installer 16:05:00 INFO - Options: 16:05:00 INFO - -h, --help show this help message and exit 16:05:00 INFO - -d DEST, --destination=DEST 16:05:00 INFO - Directory to install application into. [default: 16:05:00 INFO - "/builds/slave/test"] 16:05:00 INFO - --app=APP Application being installed. [default: firefox] 16:05:00 INFO - mkdir: /builds/slave/test/build/application 16:05:00 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'] 16:05:00 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 16:05:16 INFO - Reading from file tmpfile_stdout 16:05:16 INFO - Output received: 16:05:16 INFO - /builds/slave/test/build/application/firefox/firefox 16:05:16 INFO - Running post-action listener: _resource_record_post_action 16:05:16 INFO - ##### 16:05:16 INFO - ##### Finished install step (success) 16:05:16 INFO - ##### 16:05:16 INFO - ##### 16:05:16 INFO - ##### Running run-tests step. 16:05:16 INFO - ##### 16:05:16 INFO - Running pre-action listener: _resource_record_pre_action 16:05:16 INFO - Running main action method: run_tests 16:05:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:05:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:05:16 INFO - Python 2.7.3 16:05:16 INFO - Return code: 0 16:05:16 INFO - grabbing minidump binary from tooltool 16:05:16 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')]} 16:05:16 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 0x12f2e20>, '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 0x12f63a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12f6830>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:05:16 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 16:05:16 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 16:05:16 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 16:05:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:05:16 INFO - Return code: 0 16:05:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:05:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:05:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:05:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:05:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:05:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:05:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:05:17 INFO - Return code: 0 16:05:17 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/C2tcmztBRRqISSzUHt9uzg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 16:05:17 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/C2tcmztBRRqISSzUHt9uzg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-055 --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 16:05:17 INFO - Using env: {'DISPLAY': ':0', 16:05:17 INFO - 'HOME': '/home/cltbld', 16:05:17 INFO - 'LANG': 'en_US.UTF-8', 16:05:17 INFO - 'LANGUAGE': 'en_US:en', 16:05:17 INFO - 'LOGNAME': 'cltbld', 16:05:17 INFO - 'MAIL': '/var/mail/cltbld', 16:05:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:05:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:05:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:05:17 INFO - 'MOZ_NO_REMOTE': '1', 16:05:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:05:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:05:17 INFO - 'NO_EM_RESTART': '1', 16:05:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:05:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:05:17 INFO - 'PWD': '/builds/slave/test', 16:05:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:05:17 INFO - 'SHELL': '/bin/bash', 16:05:17 INFO - 'SHLVL': '1', 16:05:17 INFO - 'TERM': 'linux', 16:05:17 INFO - 'TMOUT': '86400', 16:05:17 INFO - 'USER': 'cltbld', 16:05:17 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066', 16:05:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:05:17 INFO - '_': '/tools/buildbot/bin/python'} 16:05:17 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/C2tcmztBRRqISSzUHt9uzg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 16:05:18 INFO - mozversion application_buildid: 20160721123119 16:05:18 INFO - mozversion application_changeset: 588be0abb337b29fd764bf570ef2679e2ffabc21 16:05:18 INFO - mozversion application_display_name: FirefoxDeveloperEdition 16:05:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:05:18 INFO - mozversion application_name: Firefox 16:05:18 INFO - mozversion application_remotingname: firefox-dev 16:05:18 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:05:18 INFO - mozversion application_vendor: Mozilla 16:05:18 INFO - mozversion application_version: 49.0a2 16:05:18 INFO - mozversion platform_buildid: 20160721123119 16:05:18 INFO - mozversion platform_changeset: 588be0abb337b29fd764bf570ef2679e2ffabc21 16:05:18 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:05:18 INFO - mozversion platform_version: 49.0a2 16:05:18 INFO - using testdate: 1469142318 16:05:18 INFO - actual date: 1469142318 16:05:18 INFO - starting webserver on 'localhost:43149' 16:05:18 INFO - SUITE-START | Running 2 tests 16:05:18 INFO - TEST-START | dromaeo_css 16:05:18 INFO - operating with platform_type : linux_ 16:05:18 INFO - Initialising browser for dromaeo_css test... 16:05:18 INFO - TEST-INFO | started process 14216 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2s1kn6/profile http://localhost:43149/getInfo.html) 16:05:21 INFO - PROCESS | 14216 | __metrics Screen width/height:1600/1200 16:05:21 INFO - PROCESS | 14216 | colorDepth:24 16:05:21 INFO - PROCESS | 14216 | Browser inner width/height: 1024/697 16:05:21 INFO - PROCESS | 14216 | __metrics 16:05:22 INFO - PROCESS | 14216 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:05:22 ERROR - PROCESS | 14216 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:05:23 INFO - TEST-INFO | 14216: exit 0 16:05:23 INFO - Browser initialized. 16:05:23 INFO - Running cycle 1/1 for dromaeo_css test... 16:05:23 INFO - TEST-INFO | started process 14309 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2s1kn6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:05:35 INFO - PROCESS | 14309 | RSS: Main: 144855040 16:05:35 INFO - PROCESS | 14309 | 16:06:26 INFO - PROCESS | 14309 | 1469142386505 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:08:34 INFO - PROCESS | 14309 | Cycle 1(1): loaded http://localhost:43149/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43149/tests/dromaeo/cssquery-ext.html) 16:08:35 INFO - PROCESS | 14309 | RSS: Main: 259289088 16:08:35 INFO - PROCESS | 14309 | 16:11:38 INFO - PROCESS | 14309 | Cycle 1(1): loaded http://localhost:43149/tests/dromaeo/cssquery-ext.html (next: http://localhost:43149/tests/dromaeo/cssquery-jquery.html) 16:11:38 INFO - PROCESS | 14309 | RSS: Main: 231280640 16:11:38 INFO - PROCESS | 14309 | 16:14:40 INFO - PROCESS | 14309 | Cycle 1(1): loaded http://localhost:43149/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43149/tests/dromaeo/cssquery-mootools.html) 16:14:41 INFO - PROCESS | 14309 | RSS: Main: 228200448 16:14:41 INFO - PROCESS | 14309 | 16:17:43 INFO - PROCESS | 14309 | Cycle 1(1): loaded http://localhost:43149/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43149/tests/dromaeo/cssquery-prototype.html) 16:17:44 INFO - PROCESS | 14309 | RSS: Main: 225320960 16:17:44 INFO - PROCESS | 14309 | 16:20:46 INFO - PROCESS | 14309 | Cycle 1(1): loaded http://localhost:43149/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43149/tests/dromaeo/cssquery-yui.html) 16:20:47 INFO - PROCESS | 14309 | RSS: Main: 201834496 16:20:47 INFO - PROCESS | 14309 | 16:23:49 INFO - PROCESS | 14309 | Cycle 1(1): loaded http://localhost:43149/tests/dromaeo/cssquery-yui.html (next: http://localhost:43149/tests/dromaeo/cssquery-dojo.html) 16:23:49 INFO - PROCESS | 14309 | RSS: Main: 260636672 16:23:49 INFO - PROCESS | 14309 | 16:23:49 INFO - PROCESS | 14309 | __start_tp_report 16:23:49 INFO - PROCESS | 14309 | _x_x_mozilla_page_load 16:23:49 INFO - PROCESS | 14309 | _x_x_mozilla_page_load_details 16:23:49 INFO - PROCESS | 14309 | |i|pagename|runs| 16:23:49 INFO - PROCESS | 14309 | |0;dojo.html;3315.0717799016284;3354.3459025490033;3379.6620337966197;3396.3037577296627;3416.1288871337806;5521.171824226363;5560.082586373253;5560.36055853577;5567.9977604031255;5599.32808063032;5577.3028371453565;5619.662820230789;5620.409856965021;5626.324841019084;5664.235328230688;4920.704757714549;4968.677035992667;5043.747812609364;5068;5109.156989096795;5006.499350065001;5027.84916452507;5055.115354812911;5059.013492368971;5116.053530096944;5062.26597143414;5128.948710512903;5140.742962851861;5147.073526765181;5163.922541161885;4877.8780530486665;5070.062038522885;5079.780852595388;5112.258722485238;5144.588432925376;4965.652404331699;5038.168702164138;5058.013687330972;5078.21287700407;5095.847124586268;5528.668279903219;5533.335999680024;5556.888862222733;5559.249501317311;5568.6658800472105;5823.301203855522;5871.500922421599;6032.336442991267;6063.939360606381;6078.452939235446;5902.380250073724;6008.639481631072;6023.759049638009;6034.577579569432;6040.848978775522;5563.165525171216;5597.608167428281;5598.664080155204;5600.803971860965;5600.887982240332;5844.824655260349;5994.940050599525;6042.848928776728;6058.545609079249;6061.359084335204;5890.49930755882;5914.787468568933;5922.141289512996;5928.851778705481;5929.881402371929;5976.850578735566;5989.940100599026;6008.579399442085;6010.939890601126;6014.18808460861;5715.228444159985;5862.677552734641;5903;5910.34986151523;5976.581639285208;5955.493783028491;5999.520038396918;5999.910001349983;6011.609245399122;6011.699415029231;4625.537446255347;4634.930476042862;4640.048789998043;4640.721556706609;4673.906521869543;4045.7370270932297;4106.876793696194;4114.423980642712;4135.60711732385;4169.374593810952;5816.069428891357;5831.708414579254;5840;5842.357340692521;5849.970750146307;5840.5035571976005;6060.303065147476;6064.423879731418;6072.666003369769;6116.143739876421;5696.914546281809;5705.543556515552;5739.71301434935;5789.363170051291;5826.009578371687;5201.973990130025;5216.191490319006;5218.608604354613;5225.477452254744;5226.738663066832;6040.335563088057;6161.630302181973;6314.55798094129;6321.715522801393;6329.113924050636;5938.435848594377;6026.578139530191;6029.397060293935;6033.336333003278;6067.302260239952;6263.342349053284;6303.275123360689;6316.968415157895;6317.96841015792;6319.431251187415;5655.660660360391;5754.625949313281;5801.5938884279385;5907.793227237198;5938.673372964528;5948.434898684618;5997.16039754426;6002.819915402545;6003.339632640319;6011.458968692838;5848.064309710426;5862.384449632727;5863.501602363676;5870.0607902735355;5873.324567674772;5598.720063996702;5762.596618236683;5801.709914504174;5814.970925145347;5819.418058194146;5758.568107391879;5775.364709881994;5785.103308987113;5785.392533783892;5789.015867302653;5876.588638795243;5886.470217680429;5888.028475301528;5926.911096333644;5929.199558059607;5555.694436805853;5569;5696.829095127153;5740.569457290803;5771.163181338767 16:23:49 INFO - PROCESS | 14309 | |1;ext.html;604.1722839709596;632.7216024949022;643.2281262485019;652.6345246661871;655.4363247607054;33940.66059339406;48093.27860082097;48939.26591101136;48964.020719585635;49013.01973960519;52514.21228681563;53015.46984530154;53045.469545304535;53070.20394694083;53104;8410.36922230832;9225.584848681809;9387.530623468834;9390.295727820407;10992.505337259843;10686.251962362638;11138.610148644799;11149.108071354272;11153;11158.944205278962;11186.272892262017;11189.216754827165;11207.21549491532;11217.719557011058;11240.381779002151;8485.193906578834;8677.739667809976;8682.26200772935;10942.398168100752;11068.948449897287;10741.194410419172;11135.164862635254;11137.220394572383;11147.554097836159;11151.721206969809;46798;47327.76336118297;47347.526524734654;47393.76303118497;47570;3954.5254569451563;3957.940630890539;3968.523777146732;4304.913901721948;4438;35475.467867982;35557;35996.82001589975;36092.27815443676;36139;4810.759462026919;4817.879553011167;4843.733594652293;4868.707877527359;5036.974815125901;394874;396255.9999999971;396458;397920;398513;58063.41936580664;65057.34942650607;65457;65761;65919.67040164769;64202.35797642057;69507;69569.65215173892;69610.30389696038;69786.30213697797;19273;19412.902935485232;19434.12546435414;19488.902555487417;19510.122044508033;12766;14975.400983960737;15228.847711522965;15254.618634534225;15366.539003829903;18730.25078996825;18770.436886893414;18779.53051173704;18795.718064229048;18821.62356752857;6297.034251671639;6371.362863713592;6383.4574061204385;6544.54188206821;6615.63614001225;982.1995074014656;1061.3843970497094;1115.983260251097;1116.1349953785882;1116.7598966222301;35368.46947295741;35700.642993570254;35711.28577428489;35849.5331963054;35994.64005359965;1116.385987706758;1176.6411244570488;1208.462234305743;1247.1706315300405;1277.9329260067761;18187.727184092248;18237.81762182361;18280.268789248283;18433;18447.72328415075;625753;629803;630547;631228;632087;66730.33269667339;66844;66875;66894.66552667205;67193;1374.9037567370187;1406.5288128477075;1409.9154050756988;1483.9629009274854;1501.902376345534;32263.35473290474;36127;36516;36816.26367472689;36834.8158259207;56869.43130568641;57091.42908570861;57457.71271143617;57543.136852948075;57588;459490;460333;461058;461376;463606;39663;46437.303440447744;46626.53373466358;46800.531994679615;47228.52771472379;54597;54654.726726367706;54824.725876370365;54978.45021549733;55414.72292638511;45462;46119.3082103762;46331.53668463407;46415.0547203478;46806.76596616995;45224.32163517616;45608.10641924793;46197.76901115473;46671;46949;45460.318095227936;46313.76843115763;46349.76825115853;47434.76282618565;47569;39498.80250598844;40152.59847401606;40340.59659403368;40673.79663101665;40858.38712419375;30301.545476818308;30338.54492182575;30612.387752244384;30859.228519287197;30863.537046944763 16:23:49 INFO - PROCESS | 14309 | |2;jquery.html;396.70278190317646;409.8729393887893;413.51618606230716;418.6838936602866;423.3734073571115;18637.813621863777;19540.609187816237;20346.491337716565;20514.89742551287;20538.281160159397;11920.105992050587;12259.754804903896;16845.578860528494;20218.494537636565;20233.696494552594;5128.56407205387;5366.973165134169;5452.454754524536;5729.6562206267545;5733.799317023905;3990.321645320288;5494.560435165177;5647.858803529924;5697.4587414195585;5765.106408506688;5805.187273781674;5830.7667693292215;5834.708264586775;5842.240508733858;5844.620099693528;5676.659400435987;5828.825135245928;5893.6463812171405;5905.261842269738;5907.320658124328;3863.16941857502;5242.082635538765;5475.561955043587;5487.067198576212;5737.684427356494;22729.431764205863;23318.766812332;23501.294961151194;23535.4116147096;23717.288481345586;2206.1396055538353;2265.9320220393415;2481.491294284667;2509.723930367658;2559.4497183105723;9639.951800240953;9876.370748061721;9976.600935962553;10083.142932850717;10169.135623472013;1996.940091797234;2019.5355068334366;2023.706562548437;2033.6949457581334;2131.6802479628027;88455.55772221163;89511.55244223868;90007.54996225043;91932.54033729856;92024.07975920221;11350.545978160946;11537.769244615061;11597.594084207181;11618.6514404568;11843.88156118445;16469.83530164683;16620.501384958472;16624.750628740578;16650.583735406468;16768.496945091665;8278.71024514139;8366.665333386718;8575.442596231349;8579.442336248125;8597.914020859711;8603.655853765904;8667.523286219319;8902.688405905892;8947.910520894839;9034.593443295067;4496.842610508616;4613.192691279013;4639.048994956026;4808.951910480921;4964.1809101498575;1838.6648955826292;1880.1633273193297;1899.2877670873422;1973.4868934077244;2079.386580958621;962.9277804164716;1052.2949623752104;1075.3655343347307;1076.4079756134095;1118.3345909184186;18828.246870125316;19087.045647717558;19577.216911323674;19694.90152549228;19775.110120044632;1024.2778840917135;1043.6712435582767;1056.6301794372062;1068.5779117248644;1087.521490544158;10647.414392208348;11104.167187460973;11169.162312826571;11219;11327.830082548768;480459.8274898205;487136;492116;503150;504750;71103.64448177829;90589;91055.54472227597;91199;91378.54310728537;1052.9105026072716;1077;1078.4230436716318;1097.4074000040048;1100.4057808783168;17128.52639519654;17149.828501714823;17486.300547978448;17638.64722705513;17710.64578708393;37652.435213471275;39202;40161.38950968178;40958.180836383704;44287.11425771531;52490.475095248556;52967.735161323944;54387.72806135944;59882.70058649679;60975.39024609697;8079.959600201962;8325.500469971821;8470.745877623682;8550.444221125606;8594.753760704787;8588.742337729878;12476.750464990831;13367.866321337051;13401.06192566511;13497.730045399234;11438.828417573577;11442.885571144181;11446.82829757571;11475.081993440672;12276.508939642314;11602.94198529002;11770.058395328524;11772.411379431165;11835.408229588658;11944.104192185794;11894.167408281337;12019.75960480768;12111.757764844477;12255.264684118982;12350.197237179555;8454.208025055408;8464.365172612203;8502.107278735644;8518.148185181431;8522.659093636184;8672.913270867211;11115.888841111486;11876.109291802977;11918.52325906954;12074.39628018578 16:23:49 INFO - PROCESS | 14309 | |3;mootools.html;1400.2158791077004;1576.6688995310983;1613.5240104169272;1819.126819126819;1926.9421917342484;9205.171534561889;9557.952210238947;9581.041895810424;9868.802623947517;9870.45712485813;9604.279679024066;9811.803763924718;9821.705348839547;9829.164521015722;9836.704898853028;2157.1587081038406;2421.987890060547;2468;2493.763092506209;2498.375406148463;2459.5818710819203;2469.5184439034397;2482.429041320497;2483.9751602483925;2484.503099380131;2472.6043832986725;2488.1042824583137;2491.813114016447;2493.9376515587073;2494.775470207681;2161.4056134563043;2359.5988681924027;2427.053449154831;2429.344077099191;2438.4025913651267;2394.5450364430876;2402.60357041088;2403.531311394279;2404.987975060131;2442.1940759549307;9732.95133524335;9756.121949024622;9767.072128147815;9772.560234789451;9803.215742740564;5514.420985796473;5657.717114144318;5669.23465332179;5669.376368599451;5675.744591493392;3621.1671315597296;3735.589085200626;3764.9435258471144;3792.051987003249;3792.2415516896453;1190.0182349561646;1218.5125949620135;1240.875912408761;1248.5630029489605;1274.5666473398996;13364.331783410791;13844.515441959484;13873.722525549534;14328.211948342838;14341.996060275786;4822.9758851205515;4860.489648586848;4899.093667671492;4979.775910084055;5016.02187573427;10887.891121088687;10888.945555272174;10893.128549716004;10894.891051089387;10901.727456813485;5076.0863044652315;5829.5044921181325;5830.329512106162;5831.125331200269;5838.970805146032;4889.853304400874;4890.364252647133;4984.651074424755;5383.111786555174;5397.460253974571;4164.583541645811;4167.249895018926;4237.957620423818;4250.55369186231;4276.72201306914;1195.9043276537857;1201.303244118409;1205.3310412721023;1233.4696080685212;1234.5802427174624;986.1814693804125;986.4278718343344;1001.8447140693204;1008.1732978957331;1009.3741880034426;6324.968375158095;6378.84052898672;6454.451371633464;6696.83257918556;6868.793936181923;446.9664775141877;449.76162633803483;451.17435093778306;453.4898239480584;479.1519011349961;4300.6129448349175;4372.366006928977;4431.7562534060935;4470.329450582439;4500.009997800478;11641.53433862636;12010.339431331186;12468.688282793002;12515.061370397256;12526.373681315898;12098;12222.816657750142;12345.567905123278;12361.381930903419;12373.505059797688;8930.821383572293;9078.773030674154;9082.273418126533;9087.81824363509;9090.27277817773;6263.498920086291;6308;6394.936050639621;6476.579022363531;6560.573562718408;10688.625898093529;10915;11094.611688590861;11422.543098276308;11435.828462573234;11154.88845111571;11317.094632429551;11570.710732231762;11591.304521728724;11624.82562761576;8506.447080939719;9106.726798196065;9315.953420232856;9318.207952323857;9491.525423728648;9610.807783844424;9619.326647134634;9652.710418687451;9706.611735530782;9708.466034367895;8602.311815054656;8789.868151977598;8821.823563528822;8849.4247873888;8958.910410895807;8657.91342086571;8707.651693932172;8710.69512567057;8719.912800872164;8781.780455488664;7930.563818990011;8055;8055.113937466756;8105.837883242184;8107.067687216046;5526.751296191762;5569.972150139223;5617.438256174513;5644.802431915027;5698.344690360662;5553.666779993213;5633.295838020247;5746.9712651436475;5779.190913272229;5933.792317269048 16:23:49 INFO - PROCESS | 14309 | |4;prototype.html;317.8778910446126;323.5890419167657;329.6242283796474;331.9850606722697;333.44980781710177;5184.455632158616;5339.22581225723;5413.404525502192;5432.483914028171;5434.565234781218;5322.547583455401;5388.164834450657;5389.487998640133;5397.62216644835;5398.2982212312345;4226.260404429228;4241.066965267636;4242.596953289432;4258.572613610243;4298.226319262531;3922.5292964844116;4007.4790277263915;4208.45290112285;4269.274223382033;4273.29490634045;4157.5634558371385;4213.810378532973;4221.873343799691;4226.429432026668;4227.7463352198965;4200.600942910419;4208.85269015583;4210.894727631803;4215.241256573816;4252.383404406344;3634.83643236055;3708.42519409492;3722.9441558376648;3737.177820879402;3746.5129533160516;5122.052420302256;5161.845144645629;5172.689638621695;5184.559312458446;5189.662671926313;4800.7119572825395;4804.567588917014;4832.903341933177;4837.903241935177;4841.951580484185;5392.379876314196;5606.411326810708;5666.838298148869;5666.971665141689;5672.886542269172;4054.6148115929236;4057.9797101014306;4080.5919408059253;4090;4107.219628270619;5832;5848.97075514624;5851.23933888596;5858.707064646751;5899.32157801854;4069.409935559327;4171.520275168334;4192.580741925783;4196.601322874322;4203.768792716369;4181.310083836133;4183.6653067754505;4205.663546916239;4211.578842115764;4211.789410529523;4786.880327991758;4792.736399497991;4805.111054454867;4830;4836.492168322276;5585;5642.689652069093;5659.858503537363;5718.714064296769;5767.826965191051;1439.3163247457583;1444.2706433251142;1445.161806152429;1451.9473381798111;1452.9030581910802;891.4428482198626;911.9088091190961;931.5714771205186;932.7434955387187;936.283742936654;4986.376702912136;4991.450940396553;5018.8707540803025;5023.673461225008;5046.848594542096;5185.429602743695;5188.662736922088;5221.399539052982;5229.849433124707;5242.370915490089;4693.436787585511;4940.2342636891335;4988.925166122511;5081.74591270435;5111.744412779421;2754.476649436601;2775.930601734932;2775.9583606245924;2783.9443211135663;2788.1914244869167;5377.623566350396;5408.4050754416985;5427.6200665953;5439.592030597721;5441.945580544223;5011.198208286657;5222.164453687392;5227.320448341691;5254.71419932323;5300.337457817773;5347.545458636059;5378.462153784669;5434.722840132566;5461.590380721462;5493.615446918758;5157.699072593618;5224.947750522598;5233.371995360581;5242.659227150223;5269.707677160448;4857.955539559011;5064.2403639452905;5097.6941383517105;5130.846074617767;5149.815542425188;5408.75660595282;5412.485813847678;5442.755076021668;5451.727413629382;5503.367112782082;3952.980235098806;3965.563787983262;4066.755994640331;4073.2057248837377;4132.710710250253;5837.5329973601165;5848.795292164754;5848.912266315923;5876.236089308362;5879.235699359056;5547.805826796042;5743.712814359349;5756.481916627524;5764.394738552559;5769;5695.686737229492;5720.914186287126;5729.484346408676;5753.338366087786;5776.913346299725;5636.577256705681;5636.928983493123;5640;5658.660480371191;5701.372848986691;5653.349864765604;5660.716784265199;5758.452946970031;5772.509336706427;5828.94171058284;5680.715964201856;5701.173329867229;5704.401037890962;5737.196792449144;5738.5696072795545 16:23:49 INFO - PROCESS | 14309 | |5;yui.html;794.3526026288578;803.7749430159554;814.8166662500935;880.7533890510657;915.6017132547342;15836.683266334681;17190.742138867896;17197.484075477725;17219.05295208763;17226.91386543067;15434;15592.220388980562;15604.219789010533;15610.84389156108;15629.452969146083;727.2182403915785;745.5303159009816;748.4311922938579;749.2582343479943;855.1875718067861;852.2798235491;881.1320848963766;882.025361975018;889.0709208876707;889.5107690770083;917.5595714057257;924.1820988425259;925.5326060339519;928.6842473558988;928.7631653928239;754.781113477091;774.8101715079786;920.1764420843341;921.7833809054866;923.5520772425335;576.7894718427785;656.4912193050424;668.168130677305;672.4653900149393;672.9831754206135;15941.601459963478;16007.759883601755;16035.679286414323;16048.679026419406;16080.59798505035;14630.487932922297;15172.696546069017;15480.922595387063;15516.534503964898;15696.372145114181;12789.907229686503;12906.161099528594;12940.611781646565;13141.474341026347;13761.38073786672;4707.152712511746;4724.102420540086;4739.336492890998;4793.400824896888;4809.350737650442;77343.22656773416;77637.22362776357;77871.83192252064;78603.60698196529;80163;12117.515299387926;12125.220020087752;12202.694932626579;12207.328596927076;12230.266184028987;22141.114355425925;22189.556208875732;22198.667019994715;22203.222887199194;22246.666300005512;8418.789530261793;8794.42836215644;8952;9094.408863423856;9205.953970230106;4836.250381190921;5104.846854594368;5126.743662816844;5194.7922083116255;5262.789488420421;3523.489094081344;3614.548181477315;3615.8011309378235;3624.8550057997913;3626.2022355082368;644.1143427786793;645.9870802583922;655.0370954696589;655.0665301944709;665.3978149774462;600.5825950964038;634.4543692424511;670.735059651435;671.9047951838472;701.1761180612759;10330;10382.169426446017;10453.634122805664;10598.841017384746;10641.201909856793;680.5950459476603;717.5156769180886;726.9709211631475;752.3717695724022;754.4040208235433;4939.6542242042715;4966.925496117561;5027.421846487628;5115.18157094863;5122.564582010496;105493.83518494458;108623;109396;115494;115542.42228788802;92122.53938730263;97654.51172744232;98445.50777246068;98984;100143.49928250312;84639.15360846312;86980;87480;87962.56018719866;88088;9622.47076410804;9625.470599117116;9798.461084640414;9856.802863942825;9867.901320986986;51498.74250628873;51851;52376.73811630918;53264.2010369837;53418.73290633522;69041.65479172773;74522;75588.62205688936;75873;76139.23860761321;11867.881321186678;12197.573084941985;12411.131220814455;12607.873921261036;12818;13633.727325453237;13854.930725346712;14029.579112626638;14121.929390352983;14437;9692.41845489273;12368.628941131781;12890.613281601567;12918.095733298578;14965.476208332657;14811.777823332442;14897.180655064076;14992.175430351435;15275.007124536869;15664.216789160268;13667.726645467234;13952.720945581235;14352.138871667734;14387.352569134206;14765.926170369079;12595;12669.873301266869;12917.677058073248;13116;13198.670033249246;11739;12431.31627760482;12536.874631253935;12728.554500592434;12802.807957880825 16:23:49 INFO - PROCESS | 14309 | __end_tp_report 16:23:49 INFO - PROCESS | 14309 | __start_cc_report 16:23:49 INFO - PROCESS | 14309 | _x_x_mozilla_cycle_collect,965 16:23:49 INFO - PROCESS | 14309 | __end_cc_report 16:23:49 INFO - PROCESS | 14309 | __startTimestamp1469143429899__endTimestamp 16:23:49 INFO - PROCESS | 14309 | 16:23:49 INFO - PROCESS | 14309 | ------- Summary: start ------- 16:23:49 INFO - PROCESS | 14309 | Number of tests: 6 16:23:49 INFO - PROCESS | 14309 | 16:23:49 INFO - PROCESS | 14309 | [#0] dojo.html Cycles:165 Average:5566.43 Median:5789.02 stddev:616.66 (10.7%) stddev-sans-first:592.72 16:23:49 INFO - PROCESS | 14309 | Values: 3315.1 3354.3 3379.7 3396.3 3416.1 5521.2 5560.1 5560.4 5568.0 5599.3 5577.3 5619.7 5620.4 5626.3 5664.2 4920.7 4968.7 5043.7 5068.0 5109.2 5006.5 5027.8 5055.1 5059.0 5116.1 5062.3 5128.9 5140.7 5147.1 5163.9 4877.9 5070.1 5079.8 5112.3 5144.6 4965.7 5038.2 5058.0 5078.2 5095.8 5528.7 5533.3 5556.9 5559.2 5568.7 5823.3 5871.5 6032.3 6063.9 6078.5 5902.4 6008.6 6023.8 6034.6 6040.8 5563.2 5597.6 5598.7 5600.8 5600.9 5844.8 5994.9 6042.8 6058.5 6061.4 5890.5 5914.8 5922.1 5928.9 5929.9 5976.9 5989.9 6008.6 6010.9 6014.2 5715.2 5862.7 5903.0 5910.3 5976.6 5955.5 5999.5 5999.9 6011.6 6011.7 4625.5 4634.9 4640.0 4640.7 4673.9 4045.7 4106.9 4114.4 4135.6 4169.4 5816.1 5831.7 5840.0 5842.4 5850.0 5840.5 6060.3 6064.4 6072.7 6116.1 5696.9 5705.5 5739.7 5789.4 5826.0 5202.0 5216.2 5218.6 5225.5 5226.7 6040.3 6161.6 6314.6 6321.7 6329.1 5938.4 6026.6 6029.4 6033.3 6067.3 6263.3 6303.3 6317.0 6318.0 6319.4 5655.7 5754.6 5801.6 5907.8 5938.7 5948.4 5997.2 6002.8 6003.3 6011.5 5848.1 5862.4 5863.5 5870.1 5873.3 5598.7 5762.6 5801.7 5815.0 5819.4 5758.6 5775.4 5785.1 5785.4 5789.0 5876.6 5886.5 5888.0 5926.9 5929.2 5555.7 5569.0 5696.8 5740.6 5771.2 16:23:49 INFO - PROCESS | 14309 | 16:23:49 INFO - PROCESS | 14309 | [#1] ext.html Cycles:180 Average:68097.97 Median:45461.16 stddev:134074.70 (294.9%) stddev-sans-first:134355.04 16:23:49 INFO - PROCESS | 14309 | Values: 604.2 632.7 643.2 652.6 655.4 33940.7 48093.3 48939.3 48964.0 49013.0 52514.2 53015.5 53045.5 53070.2 53104.0 8410.4 9225.6 9387.5 9390.3 10992.5 10686.3 11138.6 11149.1 11153.0 11158.9 11186.3 11189.2 11207.2 11217.7 11240.4 8485.2 8677.7 8682.3 10942.4 11068.9 10741.2 11135.2 11137.2 11147.6 11151.7 46798.0 47327.8 47347.5 47393.8 47570.0 3954.5 3957.9 3968.5 4304.9 4438.0 35475.5 35557.0 35996.8 36092.3 36139.0 4810.8 4817.9 4843.7 4868.7 5037.0 394874.0 396256.0 396458.0 397920.0 398513.0 58063.4 65057.3 65457.0 65761.0 65919.7 64202.4 69507.0 69569.7 69610.3 69786.3 19273.0 19412.9 19434.1 19488.9 19510.1 12766.0 14975.4 15228.8 15254.6 15366.5 18730.3 18770.4 18779.5 18795.7 18821.6 6297.0 6371.4 6383.5 6544.5 6615.6 982.2 1061.4 1116.0 1116.1 1116.8 35368.5 35700.6 35711.3 35849.5 35994.6 1116.4 1176.6 1208.5 1247.2 1277.9 18187.7 18237.8 18280.3 18433.0 18447.7 625753.0 629803.0 630547.0 631228.0 632087.0 66730.3 66844.0 66875.0 66894.7 67193.0 1374.9 1406.5 1409.9 1484.0 1501.9 32263.4 36127.0 36516.0 36816.3 36834.8 56869.4 57091.4 57457.7 57543.1 57588.0 459490.0 460333.0 461058.0 461376.0 463606.0 39663.0 46437.3 46626.5 46800.5 47228.5 54597.0 54654.7 54824.7 54978.5 55414.7 45462.0 46119.3 46331.5 46415.1 46806.8 45224.3 45608.1 46197.8 46671.0 46949.0 45460.3 46313.8 46349.8 47434.8 47569.0 39498.8 40152.6 40340.6 40673.8 40858.4 30301.5 30338.5 30612.4 30859.2 30863.5 16:23:49 INFO - PROCESS | 14309 | 16:23:49 INFO - PROCESS | 14309 | [#2] jquery.html Cycles:180 Average:29129.80 Median:13099.39 stddev:81440.61 (621.7%) stddev-sans-first:81640.49 16:23:49 INFO - PROCESS | 14309 | Values: 396.7 409.9 413.5 418.7 423.4 18637.8 19540.6 20346.5 20514.9 20538.3 11920.1 12259.8 16845.6 20218.5 20233.7 5128.6 5367.0 5452.5 5729.7 5733.8 3990.3 5494.6 5647.9 5697.5 5765.1 5805.2 5830.8 5834.7 5842.2 5844.6 5676.7 5828.8 5893.6 5905.3 5907.3 3863.2 5242.1 5475.6 5487.1 5737.7 22729.4 23318.8 23501.3 23535.4 23717.3 2206.1 2265.9 2481.5 2509.7 2559.4 9640.0 9876.4 9976.6 10083.1 10169.1 1996.9 2019.5 2023.7 2033.7 2131.7 88455.6 89511.6 90007.5 91932.5 92024.1 11350.5 11537.8 11597.6 11618.7 11843.9 16469.8 16620.5 16624.8 16650.6 16768.5 8278.7 8366.7 8575.4 8579.4 8597.9 8603.7 8667.5 8902.7 8947.9 9034.6 4496.8 4613.2 4639.0 4809.0 4964.2 1838.7 1880.2 1899.3 1973.5 2079.4 962.9 1052.3 1075.4 1076.4 1118.3 18828.2 19087.0 19577.2 19694.9 19775.1 1024.3 1043.7 1056.6 1068.6 1087.5 10647.4 11104.2 11169.2 11219.0 11327.8 480459.8 487136.0 492116.0 503150.0 504750.0 71103.6 90589.0 91055.5 91199.0 91378.5 1052.9 1077.0 1078.4 1097.4 1100.4 17128.5 17149.8 17486.3 17638.6 17710.6 37652.4 39202.0 40161.4 40958.2 44287.1 52490.5 52967.7 54387.7 59882.7 60975.4 8080.0 8325.5 8470.7 8550.4 8594.8 8588.7 12476.8 13367.9 13401.1 13497.7 11438.8 11442.9 11446.8 11475.1 12276.5 11602.9 11770.1 11772.4 11835.4 11944.1 11894.2 12019.8 12111.8 12255.3 12350.2 8454.2 8464.4 8502.1 8518.1 8522.7 8672.9 11115.9 11876.1 11918.5 12074.4 16:23:49 INFO - PROCESS | 14309 | 16:23:49 INFO - PROCESS | 14309 | [#3] mootools.html Cycles:180 Average:6395.45 Median:452.33 stddev:3766.10 (832.6%) stddev-sans-first:3757.96 16:23:49 INFO - PROCESS | 14309 | Values: 1400.2 1576.7 1613.5 1819.1 1926.9 9205.2 9558.0 9581.0 9868.8 9870.5 9604.3 9811.8 9821.7 9829.2 9836.7 2157.2 2422.0 2468.0 2493.8 2498.4 2459.6 2469.5 2482.4 2484.0 2484.5 2472.6 2488.1 2491.8 2493.9 2494.8 2161.4 2359.6 2427.1 2429.3 2438.4 2394.5 2402.6 2403.5 2405.0 2442.2 9733.0 9756.1 9767.1 9772.6 9803.2 5514.4 5657.7 5669.2 5669.4 5675.7 3621.2 3735.6 3764.9 3792.1 3792.2 1190.0 1218.5 1240.9 1248.6 1274.6 13364.3 13844.5 13873.7 14328.2 14342.0 4823.0 4860.5 4899.1 4979.8 5016.0 10887.9 10888.9 10893.1 10894.9 10901.7 5076.1 5829.5 5830.3 5831.1 5839.0 4889.9 4890.4 4984.7 5383.1 5397.5 4164.6 4167.2 4238.0 4250.6 4276.7 1195.9 1201.3 1205.3 1233.5 1234.6 986.2 986.4 1001.8 1008.2 1009.4 6325.0 6378.8 6454.5 6696.8 6868.8 447.0 449.8 451.2 453.5 479.2 4300.6 4372.4 4431.8 4470.3 4500.0 11641.5 12010.3 12468.7 12515.1 12526.4 12098.0 12222.8 12345.6 12361.4 12373.5 8930.8 9078.8 9082.3 9087.8 9090.3 6263.5 6308.0 6394.9 6476.6 6560.6 10688.6 10915.0 11094.6 11422.5 11435.8 11154.9 11317.1 11570.7 11591.3 11624.8 8506.4 9106.7 9316.0 9318.2 9491.5 9610.8 9619.3 9652.7 9706.6 9708.5 8602.3 8789.9 8821.8 8849.4 8958.9 8657.9 8707.7 8710.7 8719.9 8781.8 7930.6 8055.0 8055.1 8105.8 8107.1 5526.8 5570.0 5617.4 5644.8 5698.3 5553.7 5633.3 5747.0 5779.2 5933.8 16:23:49 INFO - PROCESS | 14309 | 16:23:49 INFO - PROCESS | 14309 | [#4] prototype.html Cycles:180 Average:4625.83 Median:5184.51 stddev:1340.48 (25.9%) stddev-sans-first:1304.66 16:23:49 INFO - PROCESS | 14309 | Values: 317.9 323.6 329.6 332.0 333.4 5184.5 5339.2 5413.4 5432.5 5434.6 5322.5 5388.2 5389.5 5397.6 5398.3 4226.3 4241.1 4242.6 4258.6 4298.2 3922.5 4007.5 4208.5 4269.3 4273.3 4157.6 4213.8 4221.9 4226.4 4227.7 4200.6 4208.9 4210.9 4215.2 4252.4 3634.8 3708.4 3722.9 3737.2 3746.5 5122.1 5161.8 5172.7 5184.6 5189.7 4800.7 4804.6 4832.9 4837.9 4842.0 5392.4 5606.4 5666.8 5667.0 5672.9 4054.6 4058.0 4080.6 4090.0 4107.2 5832.0 5849.0 5851.2 5858.7 5899.3 4069.4 4171.5 4192.6 4196.6 4203.8 4181.3 4183.7 4205.7 4211.6 4211.8 4786.9 4792.7 4805.1 4830.0 4836.5 5585.0 5642.7 5659.9 5718.7 5767.8 1439.3 1444.3 1445.2 1451.9 1452.9 891.4 911.9 931.6 932.7 936.3 4986.4 4991.5 5018.9 5023.7 5046.8 5185.4 5188.7 5221.4 5229.8 5242.4 4693.4 4940.2 4988.9 5081.7 5111.7 2754.5 2775.9 2776.0 2783.9 2788.2 5377.6 5408.4 5427.6 5439.6 5441.9 5011.2 5222.2 5227.3 5254.7 5300.3 5347.5 5378.5 5434.7 5461.6 5493.6 5157.7 5224.9 5233.4 5242.7 5269.7 4858.0 5064.2 5097.7 5130.8 5149.8 5408.8 5412.5 5442.8 5451.7 5503.4 3953.0 3965.6 4066.8 4073.2 4132.7 5837.5 5848.8 5848.9 5876.2 5879.2 5547.8 5743.7 5756.5 5764.4 5769.0 5695.7 5720.9 5729.5 5753.3 5776.9 5636.6 5636.9 5640.0 5658.7 5701.4 5653.3 5660.7 5758.5 5772.5 5828.9 5680.7 5701.2 5704.4 5737.2 5738.6 16:23:49 INFO - PROCESS | 14309 | 16:23:49 INFO - PROCESS | 14309 | [#5] yui.html Cycles:180 Average:21111.13 Median:4887.95 stddev:29539.43 (604.3%) stddev-sans-first:29582.91 16:23:49 INFO - PROCESS | 14309 | Values: 794.4 803.8 814.8 880.8 915.6 15836.7 17190.7 17197.5 17219.1 17226.9 15434.0 15592.2 15604.2 15610.8 15629.5 727.2 745.5 748.4 749.3 855.2 852.3 881.1 882.0 889.1 889.5 917.6 924.2 925.5 928.7 928.8 754.8 774.8 920.2 921.8 923.6 576.8 656.5 668.2 672.5 673.0 15941.6 16007.8 16035.7 16048.7 16080.6 14630.5 15172.7 15480.9 15516.5 15696.4 12789.9 12906.2 12940.6 13141.5 13761.4 4707.2 4724.1 4739.3 4793.4 4809.4 77343.2 77637.2 77871.8 78603.6 80163.0 12117.5 12125.2 12202.7 12207.3 12230.3 22141.1 22189.6 22198.7 22203.2 22246.7 8418.8 8794.4 8952.0 9094.4 9206.0 4836.3 5104.8 5126.7 5194.8 5262.8 3523.5 3614.5 3615.8 3624.9 3626.2 644.1 646.0 655.0 655.1 665.4 600.6 634.5 670.7 671.9 701.2 10330.0 10382.2 10453.6 10598.8 10641.2 680.6 717.5 727.0 752.4 754.4 4939.7 4966.9 5027.4 5115.2 5122.6 105493.8 108623.0 109396.0 115494.0 115542.4 92122.5 97654.5 98445.5 98984.0 100143.5 84639.2 86980.0 87480.0 87962.6 88088.0 9622.5 9625.5 9798.5 9856.8 9867.9 51498.7 51851.0 52376.7 53264.2 53418.7 69041.7 74522.0 75588.6 75873.0 76139.2 11867.9 12197.6 12411.1 12607.9 12818.0 13633.7 13854.9 14029.6 14121.9 14437.0 9692.4 12368.6 12890.6 12918.1 14965.5 14811.8 14897.2 14992.2 15275.0 15664.2 13667.7 13952.7 14352.1 14387.4 14765.9 12595.0 12669.9 12917.7 13116.0 13198.7 11739.0 12431.3 12536.9 12728.6 12802.8 16:23:49 INFO - PROCESS | 14309 | -------- Summary: end -------- 16:23:49 INFO - PROCESS | 14309 | 16:23:50 INFO - TEST-INFO | 14309: exit 0 16:23:50 INFO - TEST-OK | dromaeo_css | took 1112593ms 16:23:50 INFO - TEST-START | kraken 16:23:50 INFO - operating with platform_type : linux_ 16:23:50 INFO - Initialising browser for kraken test... 16:23:50 INFO - TEST-INFO | started process 14501 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgNTaYm/profile http://localhost:43149/getInfo.html) 16:23:54 INFO - PROCESS | 14501 | __metrics Screen width/height:1600/1200 16:23:54 INFO - PROCESS | 14501 | colorDepth:24 16:23:54 INFO - PROCESS | 14501 | Browser inner width/height: 1024/697 16:23:54 INFO - PROCESS | 14501 | __metrics 16:23:55 INFO - PROCESS | 14501 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:23:55 ERROR - PROCESS | 14501 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:23:56 INFO - TEST-INFO | 14501: exit 0 16:23:56 INFO - Browser initialized. 16:23:56 INFO - Running cycle 1/1 for kraken test... 16:23:56 INFO - TEST-INFO | started process 14592 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgNTaYm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:24:08 INFO - PROCESS | 14592 | RSS: Main: 145850368 16:24:08 INFO - PROCESS | 14592 | 16:24:10 INFO - PROCESS | 14592 | Cycle 1(1): loaded http://localhost:43149/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43149/tests/kraken/driver.html?testName=audio-beat-detection) 16:24:11 INFO - PROCESS | 14592 | RSS: Main: 194736128 16:24:11 INFO - PROCESS | 14592 | 16:24:13 INFO - PROCESS | 14592 | Cycle 1(1): loaded http://localhost:43149/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43149/tests/kraken/driver.html?testName=audio-dft) 16:24:14 INFO - PROCESS | 14592 | RSS: Main: 192978944 16:24:14 INFO - PROCESS | 14592 | 16:24:17 INFO - PROCESS | 14592 | Cycle 1(1): loaded http://localhost:43149/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43149/tests/kraken/driver.html?testName=audio-fft) 16:24:18 INFO - PROCESS | 14592 | RSS: Main: 279564288 16:24:18 INFO - PROCESS | 14592 | 16:24:20 INFO - PROCESS | 14592 | Cycle 1(1): loaded http://localhost:43149/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43149/tests/kraken/driver.html?testName=audio-oscillator) 16:24:20 INFO - PROCESS | 14592 | RSS: Main: 188358656 16:24:20 INFO - PROCESS | 14592 | 16:24:22 INFO - PROCESS | 14592 | Cycle 1(1): loaded http://localhost:43149/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43149/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:24:23 INFO - PROCESS | 14592 | RSS: Main: 188092416 16:24:23 INFO - PROCESS | 14592 | 16:24:26 INFO - PROCESS | 14592 | Cycle 1(1): loaded http://localhost:43149/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43149/tests/kraken/driver.html?testName=imaging-darkroom) 16:24:27 INFO - PROCESS | 14592 | RSS: Main: 223948800 16:24:27 INFO - PROCESS | 14592 | 16:24:30 INFO - PROCESS | 14592 | Cycle 1(1): loaded http://localhost:43149/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43149/tests/kraken/driver.html?testName=imaging-desaturate) 16:24:31 INFO - PROCESS | 14592 | RSS: Main: 223793152 16:24:31 INFO - PROCESS | 14592 | 16:24:33 INFO - PROCESS | 14592 | Cycle 1(1): loaded http://localhost:43149/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43149/tests/kraken/driver.html?testName=json-parse-financial) 16:24:34 INFO - PROCESS | 14592 | RSS: Main: 223346688 16:24:34 INFO - PROCESS | 14592 | 16:24:35 INFO - PROCESS | 14592 | Cycle 1(1): loaded http://localhost:43149/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43149/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:24:36 INFO - PROCESS | 14592 | RSS: Main: 201252864 16:24:36 INFO - PROCESS | 14592 | 16:24:38 INFO - PROCESS | 14592 | Cycle 1(1): loaded http://localhost:43149/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43149/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:24:38 INFO - PROCESS | 14592 | RSS: Main: 202960896 16:24:38 INFO - PROCESS | 14592 | 16:24:40 INFO - PROCESS | 14592 | Cycle 1(1): loaded http://localhost:43149/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43149/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:24:41 INFO - PROCESS | 14592 | RSS: Main: 208371712 16:24:41 INFO - PROCESS | 14592 | 16:24:43 INFO - PROCESS | 14592 | Cycle 1(1): loaded http://localhost:43149/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43149/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:24:44 INFO - PROCESS | 14592 | RSS: Main: 214663168 16:24:44 INFO - PROCESS | 14592 | 16:24:47 INFO - PROCESS | 14592 | Cycle 1(1): loaded http://localhost:43149/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43149/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:24:47 INFO - PROCESS | 14592 | RSS: Main: 214568960 16:24:47 INFO - PROCESS | 14592 | 16:24:49 INFO - PROCESS | 14592 | Cycle 1(1): loaded http://localhost:43149/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43149/tests/kraken/driver.html?testName=ai-astar) 16:24:49 INFO - PROCESS | 14592 | RSS: Main: 256790528 16:24:49 INFO - PROCESS | 14592 | 16:24:49 INFO - PROCESS | 14592 | __start_tp_report 16:24:49 INFO - PROCESS | 14592 | _x_x_mozilla_page_load 16:24:49 INFO - PROCESS | 14592 | _x_x_mozilla_page_load_details 16:24:49 INFO - PROCESS | 14592 | |i|pagename|runs| 16:24:49 INFO - PROCESS | 14592 | |0;ai-astar;111;104;109;109;109;109;109;109;108;108 16:24:49 INFO - PROCESS | 14592 | |1;audio-beat-detection;155;122;125;172;123;126;149;125;165;124 16:24:49 INFO - PROCESS | 14592 | |2;audio-dft;160;161;160;161;161;159;163;162;161;160 16:24:49 INFO - PROCESS | 14592 | |3;audio-fft;74;76;75;134;76;74;75;74;99;75 16:24:49 INFO - PROCESS | 14592 | |4;audio-oscillator;84;85;107;84;85;85;118;84;85;131 16:24:49 INFO - PROCESS | 14592 | |5;imaging-gaussian-blur;117;118;117;116;117;117;117;117;117;117 16:24:49 INFO - PROCESS | 14592 | |6;imaging-darkroom;146;147;146;146;147;146;147;145;146;148 16:24:49 INFO - PROCESS | 14592 | |7;imaging-desaturate;94;95;95;95;95;94;95;95;95;95 16:24:49 INFO - PROCESS | 14592 | |8;json-parse-financial;50;49;58;51;50;51;50;52;51;50 16:24:49 INFO - PROCESS | 14592 | |9;json-stringify-tinderbox;60;60;61;60;61;60;60;60;61;61 16:24:49 INFO - PROCESS | 14592 | |10;stanford-crypto-aes;76;78;75;76;76;77;77;79;112;76 16:24:49 INFO - PROCESS | 14592 | |11;stanford-crypto-ccm;118;118;118;116;119;114;117;123;121;119 16:24:49 INFO - PROCESS | 14592 | |12;stanford-crypto-pbkdf2;155;155;155;157;155;155;156;156;155;153 16:24:49 INFO - PROCESS | 14592 | |13;stanford-crypto-sha256-iterative;63;62;63;63;62;62;62;60;66;64 16:24:49 INFO - PROCESS | 14592 | __end_tp_report 16:24:49 INFO - PROCESS | 14592 | __start_cc_report 16:24:49 INFO - PROCESS | 14592 | _x_x_mozilla_cycle_collect,1607 16:24:49 INFO - PROCESS | 14592 | __end_cc_report 16:24:49 INFO - PROCESS | 14592 | __startTimestamp1469143489664__endTimestamp 16:24:49 INFO - PROCESS | 14592 | 16:24:49 INFO - PROCESS | 14592 | ------- Summary: start ------- 16:24:49 INFO - PROCESS | 14592 | Number of tests: 14 16:24:49 INFO - PROCESS | 14592 | 16:24:49 INFO - PROCESS | 14592 | [#0] ai-astar Cycles:10 Average:108.50 Median:109.00 stddev:1.78 (1.6%) stddev-sans-first:1.64 16:24:49 INFO - PROCESS | 14592 | Values: 111.0 104.0 109.0 109.0 109.0 109.0 109.0 109.0 108.0 108.0 16:24:49 INFO - PROCESS | 14592 | 16:24:49 INFO - PROCESS | 14592 | [#1] audio-beat-detection Cycles:10 Average:138.60 Median:137.50 stddev:19.58 (14.2%) stddev-sans-first:19.85 16:24:49 INFO - PROCESS | 14592 | Values: 155.0 122.0 125.0 172.0 123.0 126.0 149.0 125.0 165.0 124.0 16:24:49 INFO - PROCESS | 14592 | 16:24:49 INFO - PROCESS | 14592 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:1.14 (0.7%) stddev-sans-first:1.17 16:24:49 INFO - PROCESS | 14592 | Values: 160.0 161.0 160.0 161.0 161.0 159.0 163.0 162.0 161.0 160.0 16:24:49 INFO - PROCESS | 14592 | 16:24:49 INFO - PROCESS | 14592 | [#3] audio-fft Cycles:10 Average:83.20 Median:75.00 stddev:19.41 (25.9%) stddev-sans-first:20.30 16:24:49 INFO - PROCESS | 14592 | Values: 74.0 76.0 75.0 134.0 76.0 74.0 75.0 74.0 99.0 75.0 16:24:49 INFO - PROCESS | 14592 | 16:24:49 INFO - PROCESS | 14592 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:84.50 stddev:17.42 (20.6%) stddev-sans-first:18.03 16:24:49 INFO - PROCESS | 14592 | Values: 84.0 85.0 107.0 84.0 85.0 85.0 118.0 84.0 85.0 131.0 16:24:49 INFO - PROCESS | 14592 | 16:24:49 INFO - PROCESS | 14592 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 16:24:49 INFO - PROCESS | 14592 | Values: 117.0 118.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 16:24:49 INFO - PROCESS | 14592 | 16:24:49 INFO - PROCESS | 14592 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.84 (0.6%) stddev-sans-first:0.88 16:24:49 INFO - PROCESS | 14592 | Values: 146.0 147.0 146.0 146.0 147.0 146.0 147.0 145.0 146.0 148.0 16:24:49 INFO - PROCESS | 14592 | 16:24:49 INFO - PROCESS | 14592 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 16:24:49 INFO - PROCESS | 14592 | Values: 94.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 16:24:49 INFO - PROCESS | 14592 | 16:24:49 INFO - PROCESS | 14592 | [#8] json-parse-financial Cycles:10 Average:51.20 Median:51.00 stddev:2.53 (5.0%) stddev-sans-first:2.65 16:24:49 INFO - PROCESS | 14592 | Values: 50.0 49.0 58.0 51.0 50.0 51.0 50.0 52.0 51.0 50.0 16:24:49 INFO - PROCESS | 14592 | 16:24:49 INFO - PROCESS | 14592 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:60.50 stddev:0.52 (0.9%) stddev-sans-first:0.53 16:24:49 INFO - PROCESS | 14592 | Values: 60.0 60.0 61.0 60.0 61.0 60.0 60.0 60.0 61.0 61.0 16:24:49 INFO - PROCESS | 14592 | 16:24:49 INFO - PROCESS | 14592 | [#10] stanford-crypto-aes Cycles:10 Average:80.20 Median:76.50 stddev:11.23 (14.7%) stddev-sans-first:11.81 16:24:49 INFO - PROCESS | 14592 | Values: 76.0 78.0 75.0 76.0 76.0 77.0 77.0 79.0 112.0 76.0 16:24:49 INFO - PROCESS | 14592 | 16:24:49 INFO - PROCESS | 14592 | [#11] stanford-crypto-ccm Cycles:10 Average:118.30 Median:118.50 stddev:2.50 (2.1%) stddev-sans-first:2.65 16:24:49 INFO - PROCESS | 14592 | Values: 118.0 118.0 118.0 116.0 119.0 114.0 117.0 123.0 121.0 119.0 16:24:49 INFO - PROCESS | 14592 | 16:24:49 INFO - PROCESS | 14592 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.20 Median:155.00 stddev:1.03 (0.7%) stddev-sans-first:1.09 16:24:49 INFO - PROCESS | 14592 | Values: 155.0 155.0 155.0 157.0 155.0 155.0 156.0 156.0 155.0 153.0 16:24:49 INFO - PROCESS | 14592 | 16:24:49 INFO - PROCESS | 14592 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:63.00 stddev:1.57 (2.5%) stddev-sans-first:1.66 16:24:49 INFO - PROCESS | 14592 | Values: 63.0 62.0 63.0 63.0 62.0 62.0 62.0 60.0 66.0 64.0 16:24:49 INFO - PROCESS | 14592 | -------- Summary: end -------- 16:24:49 INFO - PROCESS | 14592 | 16:24:50 INFO - TEST-INFO | 14592: exit 0 16:24:50 INFO - TEST-OK | kraken | took 60024ms 16:24:50 INFO - SUITE-END | took 1172s 16:24:50 INFO - Completed test suite (00:19:32) 16:24:50 INFO - javascript benchmark 16:24:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3315.0717799016284, 3354.3459025490033, 3379.6620337966197, 3396.3037577296627, 3416.1288871337806, 5521.171824226363, 5560.082586373253, 5560.36055853577, 5567.9977604031255, 5599.32808063032, 5577.3028371453565, 5619.662820230789, 5620.409856965021, 5626.324841019084, 5664.235328230688, 4920.704757714549, 4968.677035992667, 5043.747812609364, 5068.0, 5109.156989096795, 5006.499350065001, 5027.84916452507, 5055.115354812911, 5059.013492368971, 5116.053530096944, 5062.26597143414, 5128.948710512903, 5140.742962851861, 5147.073526765181, 5163.922541161885, 4877.8780530486665, 5070.062038522885, 5079.780852595388, 5112.258722485238, 5144.588432925376, 4965.652404331699, 5038.168702164138, 5058.013687330972, 5078.21287700407, 5095.847124586268, 5528.668279903219, 5533.335999680024, 5556.888862222733, 5559.249501317311, 5568.6658800472105, 5823.301203855522, 5871.500922421599, 6032.336442991267, 6063.939360606381, 6078.452939235446, 5902.380250073724, 6008.639481631072, 6023.759049638009, 6034.577579569432, 6040.848978775522, 5563.165525171216, 5597.608167428281, 5598.664080155204, 5600.803971860965, 5600.887982240332, 5844.824655260349, 5994.940050599525, 6042.848928776728, 6058.545609079249, 6061.359084335204, 5890.49930755882, 5914.787468568933, 5922.141289512996, 5928.851778705481, 5929.881402371929, 5976.850578735566, 5989.940100599026, 6008.579399442085, 6010.939890601126, 6014.18808460861, 5715.228444159985, 5862.677552734641, 5903.0, 5910.34986151523, 5976.581639285208, 5955.493783028491, 5999.520038396918, 5999.910001349983, 6011.609245399122, 6011.699415029231, 4625.537446255347, 4634.930476042862, 4640.048789998043, 4640.721556706609, 4673.906521869543, 4045.7370270932297, 4106.876793696194, 4114.423980642712, 4135.60711732385, 4169.374593810952, 5816.069428891357, 5831.708414579254, 5840.0, 5842.357340692521, 5849.970750146307, 5840.5035571976005, 6060.303065147476, 6064.423879731418, 6072.666003369769, 6116.143739876421, 5696.914546281809, 5705.543556515552, 5739.71301434935, 5789.363170051291, 5826.009578371687, 5201.973990130025, 5216.191490319006, 5218.608604354613, 5225.477452254744, 5226.738663066832, 6040.335563088057, 6161.630302181973, 6314.55798094129, 6321.715522801393, 6329.113924050636, 5938.435848594377, 6026.578139530191, 6029.397060293935, 6033.336333003278, 6067.302260239952, 6263.342349053284, 6303.275123360689, 6316.968415157895, 6317.96841015792, 6319.431251187415, 5655.660660360391, 5754.625949313281, 5801.5938884279385, 5907.793227237198, 5938.673372964528, 5948.434898684618, 5997.16039754426, 6002.819915402545, 6003.339632640319, 6011.458968692838, 5848.064309710426, 5862.384449632727, 5863.501602363676, 5870.0607902735355, 5873.324567674772, 5598.720063996702, 5762.596618236683, 5801.709914504174, 5814.970925145347, 5819.418058194146, 5758.568107391879, 5775.364709881994, 5785.103308987113, 5785.392533783892, 5789.015867302653, 5876.588638795243, 5886.470217680429, 5888.028475301528, 5926.911096333644, 5929.199558059607, 5555.694436805853, 5569.0, 5696.829095127153, 5740.569457290803, 5771.163181338767], "value": 5526.667846084006, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.1722839709596, 632.7216024949022, 643.2281262485019, 652.6345246661871, 655.4363247607054, 33940.66059339406, 48093.27860082097, 48939.26591101136, 48964.020719585635, 49013.01973960519, 52514.21228681563, 53015.46984530154, 53045.469545304535, 53070.20394694083, 53104.0, 8410.36922230832, 9225.584848681809, 9387.530623468834, 9390.295727820407, 10992.505337259843, 10686.251962362638, 11138.610148644799, 11149.108071354272, 11153.0, 11158.944205278962, 11186.272892262017, 11189.216754827165, 11207.21549491532, 11217.719557011058, 11240.381779002151, 8485.193906578834, 8677.739667809976, 8682.26200772935, 10942.398168100752, 11068.948449897287, 10741.194410419172, 11135.164862635254, 11137.220394572383, 11147.554097836159, 11151.721206969809, 46798.0, 47327.76336118297, 47347.526524734654, 47393.76303118497, 47570.0, 3954.5254569451563, 3957.940630890539, 3968.523777146732, 4304.913901721948, 4438.0, 35475.467867982, 35557.0, 35996.82001589975, 36092.27815443676, 36139.0, 4810.759462026919, 4817.879553011167, 4843.733594652293, 4868.707877527359, 5036.974815125901, 394874.0, 396255.9999999971, 396458.0, 397920.0, 398513.0, 58063.41936580664, 65057.34942650607, 65457.0, 65761.0, 65919.67040164769, 64202.35797642057, 69507.0, 69569.65215173892, 69610.30389696038, 69786.30213697797, 19273.0, 19412.902935485232, 19434.12546435414, 19488.902555487417, 19510.122044508033, 12766.0, 14975.400983960737, 15228.847711522965, 15254.618634534225, 15366.539003829903, 18730.25078996825, 18770.436886893414, 18779.53051173704, 18795.718064229048, 18821.62356752857, 6297.034251671639, 6371.362863713592, 6383.4574061204385, 6544.54188206821, 6615.63614001225, 982.1995074014656, 1061.3843970497094, 1115.983260251097, 1116.1349953785882, 1116.7598966222301, 35368.46947295741, 35700.642993570254, 35711.28577428489, 35849.5331963054, 35994.64005359965, 1116.385987706758, 1176.6411244570488, 1208.462234305743, 1247.1706315300405, 1277.9329260067761, 18187.727184092248, 18237.81762182361, 18280.268789248283, 18433.0, 18447.72328415075, 625753.0, 629803.0, 630547.0, 631228.0, 632087.0, 66730.33269667339, 66844.0, 66875.0, 66894.66552667205, 67193.0, 1374.9037567370187, 1406.5288128477075, 1409.9154050756988, 1483.9629009274854, 1501.902376345534, 32263.35473290474, 36127.0, 36516.0, 36816.26367472689, 36834.8158259207, 56869.43130568641, 57091.42908570861, 57457.71271143617, 57543.136852948075, 57588.0, 459490.0, 460333.0, 461058.0, 461376.0, 463606.0, 39663.0, 46437.303440447744, 46626.53373466358, 46800.531994679615, 47228.52771472379, 54597.0, 54654.726726367706, 54824.725876370365, 54978.45021549733, 55414.72292638511, 45462.0, 46119.3082103762, 46331.53668463407, 46415.0547203478, 46806.76596616995, 45224.32163517616, 45608.10641924793, 46197.76901115473, 46671.0, 46949.0, 45460.318095227936, 46313.76843115763, 46349.76825115853, 47434.76282618565, 47569.0, 39498.80250598844, 40152.59847401606, 40340.59659403368, 40673.79663101665, 40858.38712419375, 30301.545476818308, 30338.54492182575, 30612.387752244384, 30859.228519287197, 30863.537046944763], "value": 22631.24173252179, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.70278190317646, 409.8729393887893, 413.51618606230716, 418.6838936602866, 423.3734073571115, 18637.813621863777, 19540.609187816237, 20346.491337716565, 20514.89742551287, 20538.281160159397, 11920.105992050587, 12259.754804903896, 16845.578860528494, 20218.494537636565, 20233.696494552594, 5128.56407205387, 5366.973165134169, 5452.454754524536, 5729.6562206267545, 5733.799317023905, 3990.321645320288, 5494.560435165177, 5647.858803529924, 5697.4587414195585, 5765.106408506688, 5805.187273781674, 5830.7667693292215, 5834.708264586775, 5842.240508733858, 5844.620099693528, 5676.659400435987, 5828.825135245928, 5893.6463812171405, 5905.261842269738, 5907.320658124328, 3863.16941857502, 5242.082635538765, 5475.561955043587, 5487.067198576212, 5737.684427356494, 22729.431764205863, 23318.766812332, 23501.294961151194, 23535.4116147096, 23717.288481345586, 2206.1396055538353, 2265.9320220393415, 2481.491294284667, 2509.723930367658, 2559.4497183105723, 9639.951800240953, 9876.370748061721, 9976.600935962553, 10083.142932850717, 10169.135623472013, 1996.940091797234, 2019.5355068334366, 2023.706562548437, 2033.6949457581334, 2131.6802479628027, 88455.55772221163, 89511.55244223868, 90007.54996225043, 91932.54033729856, 92024.07975920221, 11350.545978160946, 11537.769244615061, 11597.594084207181, 11618.6514404568, 11843.88156118445, 16469.83530164683, 16620.501384958472, 16624.750628740578, 16650.583735406468, 16768.496945091665, 8278.71024514139, 8366.665333386718, 8575.442596231349, 8579.442336248125, 8597.914020859711, 8603.655853765904, 8667.523286219319, 8902.688405905892, 8947.910520894839, 9034.593443295067, 4496.842610508616, 4613.192691279013, 4639.048994956026, 4808.951910480921, 4964.1809101498575, 1838.6648955826292, 1880.1633273193297, 1899.2877670873422, 1973.4868934077244, 2079.386580958621, 962.9277804164716, 1052.2949623752104, 1075.3655343347307, 1076.4079756134095, 1118.3345909184186, 18828.246870125316, 19087.045647717558, 19577.216911323674, 19694.90152549228, 19775.110120044632, 1024.2778840917135, 1043.6712435582767, 1056.6301794372062, 1068.5779117248644, 1087.521490544158, 10647.414392208348, 11104.167187460973, 11169.162312826571, 11219.0, 11327.830082548768, 480459.8274898205, 487136.0, 492116.0, 503150.0, 504750.0, 71103.64448177829, 90589.0, 91055.54472227597, 91199.0, 91378.54310728537, 1052.9105026072716, 1077.0, 1078.4230436716318, 1097.4074000040048, 1100.4057808783168, 17128.52639519654, 17149.828501714823, 17486.300547978448, 17638.64722705513, 17710.64578708393, 37652.435213471275, 39202.0, 40161.38950968178, 40958.180836383704, 44287.11425771531, 52490.475095248556, 52967.735161323944, 54387.72806135944, 59882.70058649679, 60975.39024609697, 8079.959600201962, 8325.500469971821, 8470.745877623682, 8550.444221125606, 8594.753760704787, 8588.742337729878, 12476.750464990831, 13367.866321337051, 13401.06192566511, 13497.730045399234, 11438.828417573577, 11442.885571144181, 11446.82829757571, 11475.081993440672, 12276.508939642314, 11602.94198529002, 11770.058395328524, 11772.411379431165, 11835.408229588658, 11944.104192185794, 11894.167408281337, 12019.75960480768, 12111.757764844477, 12255.264684118982, 12350.197237179555, 8454.208025055408, 8464.365172612203, 8502.107278735644, 8518.148185181431, 8522.659093636184, 8672.913270867211, 11115.888841111486, 11876.109291802977, 11918.52325906954, 12074.39628018578], "value": 9275.587921729955, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1400.2158791077004, 1576.6688995310983, 1613.5240104169272, 1819.126819126819, 1926.9421917342484, 9205.171534561889, 9557.952210238947, 9581.041895810424, 9868.802623947517, 9870.45712485813, 9604.279679024066, 9811.803763924718, 9821.705348839547, 9829.164521015722, 9836.704898853028, 2157.1587081038406, 2421.987890060547, 2468.0, 2493.763092506209, 2498.375406148463, 2459.5818710819203, 2469.5184439034397, 2482.429041320497, 2483.9751602483925, 2484.503099380131, 2472.6043832986725, 2488.1042824583137, 2491.813114016447, 2493.9376515587073, 2494.775470207681, 2161.4056134563043, 2359.5988681924027, 2427.053449154831, 2429.344077099191, 2438.4025913651267, 2394.5450364430876, 2402.60357041088, 2403.531311394279, 2404.987975060131, 2442.1940759549307, 9732.95133524335, 9756.121949024622, 9767.072128147815, 9772.560234789451, 9803.215742740564, 5514.420985796473, 5657.717114144318, 5669.23465332179, 5669.376368599451, 5675.744591493392, 3621.1671315597296, 3735.589085200626, 3764.9435258471144, 3792.051987003249, 3792.2415516896453, 1190.0182349561646, 1218.5125949620135, 1240.875912408761, 1248.5630029489605, 1274.5666473398996, 13364.331783410791, 13844.515441959484, 13873.722525549534, 14328.211948342838, 14341.996060275786, 4822.9758851205515, 4860.489648586848, 4899.093667671492, 4979.775910084055, 5016.02187573427, 10887.891121088687, 10888.945555272174, 10893.128549716004, 10894.891051089387, 10901.727456813485, 5076.0863044652315, 5829.5044921181325, 5830.329512106162, 5831.125331200269, 5838.970805146032, 4889.853304400874, 4890.364252647133, 4984.651074424755, 5383.111786555174, 5397.460253974571, 4164.583541645811, 4167.249895018926, 4237.957620423818, 4250.55369186231, 4276.72201306914, 1195.9043276537857, 1201.303244118409, 1205.3310412721023, 1233.4696080685212, 1234.5802427174624, 986.1814693804125, 986.4278718343344, 1001.8447140693204, 1008.1732978957331, 1009.3741880034426, 6324.968375158095, 6378.84052898672, 6454.451371633464, 6696.83257918556, 6868.793936181923, 446.9664775141877, 449.76162633803483, 451.17435093778306, 453.4898239480584, 479.1519011349961, 4300.6129448349175, 4372.366006928977, 4431.7562534060935, 4470.329450582439, 4500.009997800478, 11641.53433862636, 12010.339431331186, 12468.688282793002, 12515.061370397256, 12526.373681315898, 12098.0, 12222.816657750142, 12345.567905123278, 12361.381930903419, 12373.505059797688, 8930.821383572293, 9078.773030674154, 9082.273418126533, 9087.81824363509, 9090.27277817773, 6263.498920086291, 6308.0, 6394.936050639621, 6476.579022363531, 6560.573562718408, 10688.625898093529, 10915.0, 11094.611688590861, 11422.543098276308, 11435.828462573234, 11154.88845111571, 11317.094632429551, 11570.710732231762, 11591.304521728724, 11624.82562761576, 8506.447080939719, 9106.726798196065, 9315.953420232856, 9318.207952323857, 9491.525423728648, 9610.807783844424, 9619.326647134634, 9652.710418687451, 9706.611735530782, 9708.466034367895, 8602.311815054656, 8789.868151977598, 8821.823563528822, 8849.4247873888, 8958.910410895807, 8657.91342086571, 8707.651693932172, 8710.69512567057, 8719.912800872164, 8781.780455488664, 7930.563818990011, 8055.0, 8055.113937466756, 8105.837883242184, 8107.067687216046, 5526.751296191762, 5569.972150139223, 5617.438256174513, 5644.802431915027, 5698.344690360662, 5553.666779993213, 5633.295838020247, 5746.9712651436475, 5779.190913272229, 5933.792317269048], "value": 4942.677236710223, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.8778910446126, 323.5890419167657, 329.6242283796474, 331.9850606722697, 333.44980781710177, 5184.455632158616, 5339.22581225723, 5413.404525502192, 5432.483914028171, 5434.565234781218, 5322.547583455401, 5388.164834450657, 5389.487998640133, 5397.62216644835, 5398.2982212312345, 4226.260404429228, 4241.066965267636, 4242.596953289432, 4258.572613610243, 4298.226319262531, 3922.5292964844116, 4007.4790277263915, 4208.45290112285, 4269.274223382033, 4273.29490634045, 4157.5634558371385, 4213.810378532973, 4221.873343799691, 4226.429432026668, 4227.7463352198965, 4200.600942910419, 4208.85269015583, 4210.894727631803, 4215.241256573816, 4252.383404406344, 3634.83643236055, 3708.42519409492, 3722.9441558376648, 3737.177820879402, 3746.5129533160516, 5122.052420302256, 5161.845144645629, 5172.689638621695, 5184.559312458446, 5189.662671926313, 4800.7119572825395, 4804.567588917014, 4832.903341933177, 4837.903241935177, 4841.951580484185, 5392.379876314196, 5606.411326810708, 5666.838298148869, 5666.971665141689, 5672.886542269172, 4054.6148115929236, 4057.9797101014306, 4080.5919408059253, 4090.0, 4107.219628270619, 5832.0, 5848.97075514624, 5851.23933888596, 5858.707064646751, 5899.32157801854, 4069.409935559327, 4171.520275168334, 4192.580741925783, 4196.601322874322, 4203.768792716369, 4181.310083836133, 4183.6653067754505, 4205.663546916239, 4211.578842115764, 4211.789410529523, 4786.880327991758, 4792.736399497991, 4805.111054454867, 4830.0, 4836.492168322276, 5585.0, 5642.689652069093, 5659.858503537363, 5718.714064296769, 5767.826965191051, 1439.3163247457583, 1444.2706433251142, 1445.161806152429, 1451.9473381798111, 1452.9030581910802, 891.4428482198626, 911.9088091190961, 931.5714771205186, 932.7434955387187, 936.283742936654, 4986.376702912136, 4991.450940396553, 5018.8707540803025, 5023.673461225008, 5046.848594542096, 5185.429602743695, 5188.662736922088, 5221.399539052982, 5229.849433124707, 5242.370915490089, 4693.436787585511, 4940.2342636891335, 4988.925166122511, 5081.74591270435, 5111.744412779421, 2754.476649436601, 2775.930601734932, 2775.9583606245924, 2783.9443211135663, 2788.1914244869167, 5377.623566350396, 5408.4050754416985, 5427.6200665953, 5439.592030597721, 5441.945580544223, 5011.198208286657, 5222.164453687392, 5227.320448341691, 5254.71419932323, 5300.337457817773, 5347.545458636059, 5378.462153784669, 5434.722840132566, 5461.590380721462, 5493.615446918758, 5157.699072593618, 5224.947750522598, 5233.371995360581, 5242.659227150223, 5269.707677160448, 4857.955539559011, 5064.2403639452905, 5097.6941383517105, 5130.846074617767, 5149.815542425188, 5408.75660595282, 5412.485813847678, 5442.755076021668, 5451.727413629382, 5503.367112782082, 3952.980235098806, 3965.563787983262, 4066.755994640331, 4073.2057248837377, 4132.710710250253, 5837.5329973601165, 5848.795292164754, 5848.912266315923, 5876.236089308362, 5879.235699359056, 5547.805826796042, 5743.712814359349, 5756.481916627524, 5764.394738552559, 5769.0, 5695.686737229492, 5720.914186287126, 5729.484346408676, 5753.338366087786, 5776.913346299725, 5636.577256705681, 5636.928983493123, 5640.0, 5658.660480371191, 5701.372848986691, 5653.349864765604, 5660.716784265199, 5758.452946970031, 5772.509336706427, 5828.94171058284, 5680.715964201856, 5701.173329867229, 5704.401037890962, 5737.196792449144, 5738.5696072795545], "value": 4196.320789879179, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [794.3526026288578, 803.7749430159554, 814.8166662500935, 880.7533890510657, 915.6017132547342, 15836.683266334681, 17190.742138867896, 17197.484075477725, 17219.05295208763, 17226.91386543067, 15434.0, 15592.220388980562, 15604.219789010533, 15610.84389156108, 15629.452969146083, 727.2182403915785, 745.5303159009816, 748.4311922938579, 749.2582343479943, 855.1875718067861, 852.2798235491, 881.1320848963766, 882.025361975018, 889.0709208876707, 889.5107690770083, 917.5595714057257, 924.1820988425259, 925.5326060339519, 928.6842473558988, 928.7631653928239, 754.781113477091, 774.8101715079786, 920.1764420843341, 921.7833809054866, 923.5520772425335, 576.7894718427785, 656.4912193050424, 668.168130677305, 672.4653900149393, 672.9831754206135, 15941.601459963478, 16007.759883601755, 16035.679286414323, 16048.679026419406, 16080.59798505035, 14630.487932922297, 15172.696546069017, 15480.922595387063, 15516.534503964898, 15696.372145114181, 12789.907229686503, 12906.161099528594, 12940.611781646565, 13141.474341026347, 13761.38073786672, 4707.152712511746, 4724.102420540086, 4739.336492890998, 4793.400824896888, 4809.350737650442, 77343.22656773416, 77637.22362776357, 77871.83192252064, 78603.60698196529, 80163.0, 12117.515299387926, 12125.220020087752, 12202.694932626579, 12207.328596927076, 12230.266184028987, 22141.114355425925, 22189.556208875732, 22198.667019994715, 22203.222887199194, 22246.666300005512, 8418.789530261793, 8794.42836215644, 8952.0, 9094.408863423856, 9205.953970230106, 4836.250381190921, 5104.846854594368, 5126.743662816844, 5194.7922083116255, 5262.789488420421, 3523.489094081344, 3614.548181477315, 3615.8011309378235, 3624.8550057997913, 3626.2022355082368, 644.1143427786793, 645.9870802583922, 655.0370954696589, 655.0665301944709, 665.3978149774462, 600.5825950964038, 634.4543692424511, 670.735059651435, 671.9047951838472, 701.1761180612759, 10330.0, 10382.169426446017, 10453.634122805664, 10598.841017384746, 10641.201909856793, 680.5950459476603, 717.5156769180886, 726.9709211631475, 752.3717695724022, 754.4040208235433, 4939.6542242042715, 4966.925496117561, 5027.421846487628, 5115.18157094863, 5122.564582010496, 105493.83518494458, 108623.0, 109396.0, 115494.0, 115542.42228788802, 92122.53938730263, 97654.51172744232, 98445.50777246068, 98984.0, 100143.49928250312, 84639.15360846312, 86980.0, 87480.0, 87962.56018719866, 88088.0, 9622.47076410804, 9625.470599117116, 9798.461084640414, 9856.802863942825, 9867.901320986986, 51498.74250628873, 51851.0, 52376.73811630918, 53264.2010369837, 53418.73290633522, 69041.65479172773, 74522.0, 75588.62205688936, 75873.0, 76139.23860761321, 11867.881321186678, 12197.573084941985, 12411.131220814455, 12607.873921261036, 12818.0, 13633.727325453237, 13854.930725346712, 14029.579112626638, 14121.929390352983, 14437.0, 9692.41845489273, 12368.628941131781, 12890.613281601567, 12918.095733298578, 14965.476208332657, 14811.777823332442, 14897.180655064076, 14992.175430351435, 15275.007124536869, 15664.216789160268, 13667.726645467234, 13952.720945581235, 14352.138871667734, 14387.352569134206, 14765.926170369079, 12595.0, 12669.873301266869, 12917.677058073248, 13116.0, 13198.670033249246, 11739.0, 12431.31627760482, 12536.874631253935, 12728.554500592434, 12802.807957880825], "value": 7872.303808988408, "unit": "score"}], "name": "dromaeo_css", "value": 7578.48171655289, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 104.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 108.0, 108.0], "value": 108.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 122.0, 125.0, 172.0, 123.0, 126.0, 149.0, 125.0, 165.0, 124.0], "value": 138.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 161.0, 161.0, 159.0, 163.0, 162.0, 161.0, 160.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 134.0, 76.0, 74.0, 75.0, 74.0, 99.0, 75.0], "value": 83.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 107.0, 84.0, 85.0, 85.0, 118.0, 84.0, 85.0, 131.0], "value": 94.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 147.0, 146.0, 147.0, 145.0, 146.0, 148.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 58.0, 51.0, 50.0, 51.0, 50.0, 52.0, 51.0, 50.0], "value": 51.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 61.0, 60.0, 61.0, 60.0, 60.0, 60.0, 61.0, 61.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 75.0, 76.0, 76.0, 77.0, 77.0, 79.0, 112.0, 76.0], "value": 80.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 118.0, 116.0, 119.0, 114.0, 117.0, 123.0, 121.0, 119.0], "value": 118.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 155.0, 155.0, 157.0, 155.0, 155.0, 156.0, 156.0, 155.0, 153.0], "value": 155.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 63.0, 63.0, 62.0, 62.0, 62.0, 60.0, 66.0, 64.0], "value": 62.7, "unit": "score"}], "name": "kraken", "value": 1472.1000000000001, "alertThreshold": 2.0}]} 16:24:50 INFO - Return code: 0 16:24:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:24:50 ERROR - # TBPL SUCCESS # 16:24:50 INFO - Running post-action listener: _resource_record_post_action 16:24:50 INFO - ##### 16:24:50 INFO - ##### Finished run-tests step (success) 16:24:50 INFO - ##### 16:24:50 INFO - Running post-run listener: _resource_record_post_run 16:24:51 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 506343424; Read time: 0; Write time: 316592 16:24:51 INFO - TinderboxPrint: CPU usage
13.1% 16:24:51 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 16:24:51 INFO - TinderboxPrint: I/O write bytes / time
506,343,424 / 316,592 16:24:51 INFO - TinderboxPrint: CPU idle
8,250.1 (86.9%) 16:24:51 INFO - TinderboxPrint: CPU user
1,203.5 (12.7%) 16:24:51 INFO - TinderboxPrint: Swap in / out
0 / 0 16:24:51 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 178327552; Read time: 0; Write time: 163196 16:24:51 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 247222272; Read time: 0; Write time: 125168 16:24:51 INFO - Running post-run listener: _upload_blobber_files 16:24:51 INFO - Blob upload gear active. 16:24:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:24:51 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:24:51 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'] 16:24:51 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 16:24:52 INFO - (blobuploader) - INFO - Open directory for files ... 16:24:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:24:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:24:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:24:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:24:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:24:52 INFO - (blobuploader) - INFO - Done attempting. 16:24:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:24:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:24:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:24:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:24:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:24:53 INFO - (blobuploader) - INFO - Done attempting. 16:24:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:24:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:24:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:24:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:24:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:24:57 INFO - (blobuploader) - INFO - Done attempting. 16:24:57 INFO - (blobuploader) - INFO - Iteration through files over. 16:24:57 INFO - Return code: 0 16:24:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:24:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:24:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bc06caf9a785a48e4015bfd8c50e45f310dc3849bdae576c6bf7c53495b2b604c31065a709fae6f5f340926e8747df7784b008942a0d4b6376738a303ff5c02b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dac3904b07ff829f8a7902fddc1a7dc89f4b010db1a8f7d8769067edb2aef5896dd96d5c2b51f0bd44ca42d78a24e3409b44ab126911e4fbe78bc8baba8b8460", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c4e2ac2edf61d19207992e5e815243fa0f330cd4f7cebc49e5d59b48f021ff77410a967d5e1c95f223c973bd839333c35fee4060bd1df22b5294f408018e2352"} 16:24:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:24:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:24:57 INFO - Contents: 16:24:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bc06caf9a785a48e4015bfd8c50e45f310dc3849bdae576c6bf7c53495b2b604c31065a709fae6f5f340926e8747df7784b008942a0d4b6376738a303ff5c02b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dac3904b07ff829f8a7902fddc1a7dc89f4b010db1a8f7d8769067edb2aef5896dd96d5c2b51f0bd44ca42d78a24e3409b44ab126911e4fbe78bc8baba8b8460", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c4e2ac2edf61d19207992e5e815243fa0f330cd4f7cebc49e5d59b48f021ff77410a967d5e1c95f223c973bd839333c35fee4060bd1df22b5294f408018e2352"} 16:24:57 INFO - Running post-run listener: copy_logs_to_upload_dir 16:24:57 INFO - Copying logs to upload dir... 16:24:57 INFO - mkdir: /builds/slave/test/build/upload/logs 16:24:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.561029 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-07-21 16:24:57.106525) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 16:24:57.109658) ========= 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=bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066 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/bc06caf9a785a48e4015bfd8c50e45f310dc3849bdae576c6bf7c53495b2b604c31065a709fae6f5f340926e8747df7784b008942a0d4b6376738a303ff5c02b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dac3904b07ff829f8a7902fddc1a7dc89f4b010db1a8f7d8769067edb2aef5896dd96d5c2b51f0bd44ca42d78a24e3409b44ab126911e4fbe78bc8baba8b8460", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c4e2ac2edf61d19207992e5e815243fa0f330cd4f7cebc49e5d59b48f021ff77410a967d5e1c95f223c973bd839333c35fee4060bd1df22b5294f408018e2352"} build_url:https://queue.taskcluster.net/v1/task/C2tcmztBRRqISSzUHt9uzg/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011324 build_url: 'https://queue.taskcluster.net/v1/task/C2tcmztBRRqISSzUHt9uzg/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/bc06caf9a785a48e4015bfd8c50e45f310dc3849bdae576c6bf7c53495b2b604c31065a709fae6f5f340926e8747df7784b008942a0d4b6376738a303ff5c02b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dac3904b07ff829f8a7902fddc1a7dc89f4b010db1a8f7d8769067edb2aef5896dd96d5c2b51f0bd44ca42d78a24e3409b44ab126911e4fbe78bc8baba8b8460", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c4e2ac2edf61d19207992e5e815243fa0f330cd4f7cebc49e5d59b48f021ff77410a967d5e1c95f223c973bd839333c35fee4060bd1df22b5294f408018e2352"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 16:24:57.134843) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 16:24:57.135161) ========= 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=bb9b54edd4a927dc735d112f000001d9-1469139925.11257-615127066 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004426 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 16:24:57.218001) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 16:24:57.218316) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 16:24:57.218602) ========= ========= Total master_lag: 0.16 =========