builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-032 starttime: 1469287317.15 results: success (0) buildid: 20160723054326 builduid: fd29ec6e413e4a5aa639f37ab869831e revision: b95e8981e41800c3b202ea73d096e3e3e184d70d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-23 08:21:57.148531) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-23 08:21:57.149154) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-23 08:21:57.149540) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-23 08:21:57.270350) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-23 08:21:57.270661) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1469283740.78057-297003153 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-23 08:21:57-- 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% 38.3M=0s 2016-07-23 08:21:57 (38.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.201925 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-23 08:21:57.497036) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-23 08:21:57.497552) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1469283740.78057-297003153 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017545 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-23 08:21:57.550572) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-23 08:21:57.550998) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b95e8981e41800c3b202ea73d096e3e3e184d70d --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 b95e8981e41800c3b202ea73d096e3e3e184d70d --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1469283740.78057-297003153 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-23 08:21:57,774 truncating revision to first 12 chars 2016-07-23 08:21:57,774 Setting DEBUG logging. 2016-07-23 08:21:57,774 attempt 1/10 2016-07-23 08:21:57,775 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b95e8981e418?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-23 08:21:58,089 unpacking tar archive at: mozilla-aurora-b95e8981e418/testing/mozharness/ program finished with exit code 0 elapsedTime=0.676506 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-23 08:21:58.245297) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-23 08:21:58.245636) ========= script_repo_revision: b95e8981e41800c3b202ea73d096e3e3e184d70d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-23 08:21:58.246067) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-23 08:21:58.246427) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-23 08:21:58.259107) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-07-23 08:21:58.259439) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1469283740.78057-297003153 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:21:58 INFO - MultiFileLogger online at 20160723 08:21:58 in /builds/slave/test 08:21:58 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 08:21:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:21:58 INFO - {'append_to_log': False, 08:21:58 INFO - 'base_work_dir': '/builds/slave/test', 08:21:58 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:21:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:21:58 INFO - 'branch': 'Mozilla-Aurora', 08:21:58 INFO - 'buildbot_json_path': 'buildprops.json', 08:21:58 INFO - 'config_files': ('talos/linux_config.py',), 08:21:58 INFO - 'default_actions': ('clobber', 08:21:58 INFO - 'read-buildbot-config', 08:21:58 INFO - 'download-and-extract', 08:21:58 INFO - 'populate-webroot', 08:21:58 INFO - 'create-virtualenv', 08:21:58 INFO - 'install', 08:21:58 INFO - 'run-tests'), 08:21:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:21:58 INFO - 'download_minidump_stackwalk': True, 08:21:58 INFO - 'download_symbols': 'ondemand', 08:21:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:21:58 INFO - 'tooltool.py': '/tools/tooltool.py', 08:21:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:21:58 INFO - '/tools/misc-python/virtualenv.py')}, 08:21:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:21:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:21:58 INFO - 'installer_path': 'installer.exe', 08:21:58 INFO - 'log_level': 'info', 08:21:58 INFO - 'log_name': 'talos', 08:21:58 INFO - 'log_to_console': True, 08:21:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:21:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:21:58 INFO - 'opt_config_files': (), 08:21:58 INFO - 'pip_index': False, 08:21:58 INFO - 'sps_profile': False, 08:21:58 INFO - 'sps_profile_interval': 0, 08:21:58 INFO - 'suite': 'dromaeojs', 08:21:58 INFO - 'system_bits': '32', 08:21:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:21:58 INFO - 'title': 'talos-linux64-ix-032', 08:21:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:21:58 INFO - 'use_talos_json': True, 08:21:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:21:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:21:58 INFO - 'work_dir': 'build'} 08:21:58 INFO - ##### 08:21:58 INFO - ##### Running clobber step. 08:21:58 INFO - ##### 08:21:58 INFO - Running pre-action listener: _resource_record_pre_action 08:21:58 INFO - Running main action method: clobber 08:21:58 INFO - rmtree: /builds/slave/test/build 08:21:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:21:59 INFO - Running post-action listener: _resource_record_post_action 08:21:59 INFO - ##### 08:21:59 INFO - ##### Finished clobber step (success) 08:21:59 INFO - ##### 08:21:59 INFO - ##### 08:21:59 INFO - ##### Running read-buildbot-config step. 08:21:59 INFO - ##### 08:21:59 INFO - Running pre-action listener: _resource_record_pre_action 08:21:59 INFO - Running main action method: read_buildbot_config 08:21:59 INFO - Using buildbot properties: 08:21:59 INFO - { 08:21:59 INFO - "project": "", 08:21:59 INFO - "product": "firefox", 08:21:59 INFO - "script_repo_revision": "production", 08:21:59 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 08:21:59 INFO - "repository": "", 08:21:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 08:21:59 INFO - "buildid": "20160723054326", 08:21:59 INFO - "pgo_build": "True", 08:21:59 INFO - "basedir": "/builds/slave/test", 08:21:59 INFO - "buildnumber": 624, 08:21:59 INFO - "slavename": "talos-linux64-ix-032", 08:21:59 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:21:59 INFO - "platform": "ubuntu64_hw", 08:21:59 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 08:21:59 INFO - "branch": "mozilla-aurora", 08:21:59 INFO - "repo_path": "releases/mozilla-aurora", 08:21:59 INFO - "revision": "b95e8981e41800c3b202ea73d096e3e3e184d70d", 08:21:59 INFO - "stage_platform": "linux64-pgo", 08:21:59 INFO - "builduid": "fd29ec6e413e4a5aa639f37ab869831e", 08:21:59 INFO - "slavebuilddir": "test" 08:21:59 INFO - } 08:21:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 08:21:59 INFO - Running post-action listener: _resource_record_post_action 08:21:59 INFO - ##### 08:21:59 INFO - ##### Finished read-buildbot-config step (success) 08:21:59 INFO - ##### 08:21:59 INFO - ##### 08:21:59 INFO - ##### Running download-and-extract step. 08:21:59 INFO - ##### 08:21:59 INFO - Running pre-action listener: _resource_record_pre_action 08:21:59 INFO - Running main action method: download_and_extract 08:21:59 INFO - mkdir: /builds/slave/test/build/tests 08:21:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:21:59 INFO - https://queue.taskcluster.net/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:21:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 08:21:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 08:21:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/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 08:21:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/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 08:22:10 INFO - Downloaded 1361 bytes. 08:22:10 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 08:22:10 INFO - Using the following test package requirements: 08:22:10 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 08:22:10 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:22:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 08:22:10 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:22:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 08:22:10 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:22:10 INFO - u'jsshell-linux-x86_64.zip'], 08:22:10 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:22:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 08:22:10 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 08:22:10 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:22:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 08:22:10 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:22:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 08:22:10 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:22:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 08:22:10 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 08:22:10 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 08:22:10 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:22:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:10 INFO - https://queue.taskcluster.net/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:22:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:22:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:22:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/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 08:22:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/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 08:22:17 INFO - Downloaded 24494577 bytes. 08:22:17 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'] 08:22:17 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 08:22:18 INFO - Return code: 0 08:22:18 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 08:22:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:18 INFO - https://queue.taskcluster.net/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:22:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:22:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 08:22:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/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 08:22:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/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 08:22:20 INFO - Downloaded 24494577 bytes. 08:22:20 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'] 08:22:20 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 08:22:21 INFO - Return code: 0 08:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:21 INFO - https://queue.taskcluster.net/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:22:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 08:22:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 08:22:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/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 08:22:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/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 08:22:22 INFO - Downloaded 10833369 bytes. 08:22:22 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'] 08:22:22 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 08:22:22 INFO - Return code: 0 08:22:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:22 INFO - https://queue.taskcluster.net/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:22:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:22:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:22:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/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 08:22:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/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 08:22:24 INFO - Downloaded 63638667 bytes. 08:22:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:22:24 INFO - mkdir: /builds/slave/test/properties 08:22:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:22:24 INFO - Writing to file /builds/slave/test/properties/build_url 08:22:24 INFO - Contents: 08:22:24 INFO - build_url:https://queue.taskcluster.net/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 08:22:24 INFO - Running post-action listener: _resource_record_post_action 08:22:24 INFO - Running post-action listener: set_extra_try_arguments 08:22:24 INFO - ##### 08:22:24 INFO - ##### Finished download-and-extract step (success) 08:22:24 INFO - ##### 08:22:24 INFO - ##### 08:22:24 INFO - ##### Running populate-webroot step. 08:22:24 INFO - ##### 08:22:24 INFO - Running pre-action listener: _resource_record_pre_action 08:22:24 INFO - Running main action method: populate_webroot 08:22:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:22:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:22:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:22:24 INFO - '--tppagecycles', 08:22:24 INFO - '7'], 08:22:24 INFO - 'tests': ['tsvgm']}}, 08:22:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:22:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:22:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:22:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:22:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:22:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:22:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:24 INFO - 'talos_options': ['--disable-e10s'], 08:22:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:22:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:22:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:24 INFO - 'talos_options': ['--disable-e10s'], 08:22:24 INFO - 'tests': ['damp', 'tps']}, 08:22:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:24 INFO - 'tests': ['damp', 'tps']}, 08:22:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:22:24 INFO - 'tests': ['dromaeo_dom']}, 08:22:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:22:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:22:24 INFO - 'tests': ['a11yr', 08:22:24 INFO - 'ts_paint', 08:22:24 INFO - 'tpaint', 08:22:24 INFO - 'sessionrestore', 08:22:24 INFO - 'sessionrestore_no_auto_restore', 08:22:24 INFO - 'tabpaint']}, 08:22:24 INFO - 'other-e10s': {'tests': ['a11yr', 08:22:24 INFO - 'ts_paint', 08:22:24 INFO - 'tpaint', 08:22:24 INFO - 'sessionrestore', 08:22:24 INFO - 'sessionrestore_no_auto_restore', 08:22:24 INFO - 'tabpaint']}, 08:22:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:22:24 INFO - 'ts_paint', 08:22:24 INFO - 'tpaint', 08:22:24 INFO - 'sessionrestore', 08:22:24 INFO - 'sessionrestore_no_auto_restore', 08:22:24 INFO - 'tabpaint']}, 08:22:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:22:24 INFO - 'ts_paint', 08:22:24 INFO - 'tpaint', 08:22:24 INFO - 'sessionrestore', 08:22:24 INFO - 'sessionrestore_no_auto_restore', 08:22:24 INFO - 'tabpaint']}, 08:22:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:22:24 INFO - 'tests': ['a11yr', 08:22:24 INFO - 'ts_paint', 08:22:24 INFO - 'tpaint', 08:22:24 INFO - 'sessionrestore', 08:22:24 INFO - 'sessionrestore_no_auto_restore', 08:22:24 INFO - 'tabpaint']}, 08:22:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:22:24 INFO - 'tests': ['a11yr', 08:22:24 INFO - 'ts_paint', 08:22:24 INFO - 'tpaint', 08:22:24 INFO - 'sessionrestore', 08:22:24 INFO - 'sessionrestore_no_auto_restore', 08:22:24 INFO - 'tabpaint']}, 08:22:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:22:24 INFO - 'tests': ['tsvgx', 08:22:24 INFO - 'tsvgr_opacity', 08:22:24 INFO - 'tart', 08:22:24 INFO - 'tscrollx', 08:22:24 INFO - 'cart']}, 08:22:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:22:24 INFO - 'tsvgr_opacity', 08:22:24 INFO - 'tart', 08:22:24 INFO - 'tscrollx', 08:22:24 INFO - 'cart']}, 08:22:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:24 INFO - 'talos_options': ['--disable-e10s'], 08:22:24 INFO - 'tests': ['tp5o']}, 08:22:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:24 INFO - 'tests': ['tp5o']}, 08:22:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:24 INFO - 'talos_options': ['--disable-e10s', 08:22:24 INFO - '--xperf_path', 08:22:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:22:24 INFO - 'tests': ['tp5n']}, 08:22:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:24 INFO - 'talos_options': ['--xperf_path', 08:22:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:22:24 INFO - 'tests': ['tp5n']}}, 08:22:24 INFO - 'talos.zip': {'path': '', 08:22:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:22:24 INFO - Running post-action listener: _resource_record_post_action 08:22:24 INFO - ##### 08:22:24 INFO - ##### Finished populate-webroot step (success) 08:22:24 INFO - ##### 08:22:24 INFO - ##### 08:22:24 INFO - ##### Running create-virtualenv step. 08:22:24 INFO - ##### 08:22:24 INFO - Running pre-action listener: _resource_record_pre_action 08:22:24 INFO - Running main action method: create_virtualenv 08:22:24 INFO - Creating virtualenv /builds/slave/test/build/venv 08:22:24 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:22:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:22:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:22:24 INFO - Using real prefix '/usr' 08:22:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:22:25 INFO - Installing distribute.............................................................................................................................................................................................done. 08:22:27 INFO - Installing pip.................done. 08:22:27 INFO - Return code: 0 08:22:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:22:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:27 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 0x7ffb2d1bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe2b450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeba2c0>, '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 0xebb1c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xebb650>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469283740.78057-297003153', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:22:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:22:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:22:27 INFO - Using env: {'DISPLAY': ':0', 08:22:27 INFO - 'HOME': '/home/cltbld', 08:22:27 INFO - 'LANG': 'en_US.UTF-8', 08:22:27 INFO - 'LANGUAGE': 'en_US:en', 08:22:27 INFO - 'LOGNAME': 'cltbld', 08:22:27 INFO - 'MAIL': '/var/mail/cltbld', 08:22:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:27 INFO - 'MOZ_NO_REMOTE': '1', 08:22:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:22:27 INFO - 'NO_EM_RESTART': '1', 08:22:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:22:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:27 INFO - 'PWD': '/builds/slave/test', 08:22:27 INFO - 'SHELL': '/bin/bash', 08:22:27 INFO - 'SHLVL': '1', 08:22:27 INFO - 'TERM': 'linux', 08:22:27 INFO - 'TMOUT': '86400', 08:22:27 INFO - 'USER': 'cltbld', 08:22:27 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469283740.78057-297003153', 08:22:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:27 INFO - '_': '/tools/buildbot/bin/python'} 08:22:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:27 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:22:27 INFO - Cleaning up... 08:22:27 INFO - Return code: 0 08:22:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:22:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:27 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 0x7ffb2d1bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe2b450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeba2c0>, '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 0xebb1c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xebb650>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469283740.78057-297003153', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:22:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:22:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:22:27 INFO - Using env: {'DISPLAY': ':0', 08:22:27 INFO - 'HOME': '/home/cltbld', 08:22:27 INFO - 'LANG': 'en_US.UTF-8', 08:22:27 INFO - 'LANGUAGE': 'en_US:en', 08:22:27 INFO - 'LOGNAME': 'cltbld', 08:22:27 INFO - 'MAIL': '/var/mail/cltbld', 08:22:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:27 INFO - 'MOZ_NO_REMOTE': '1', 08:22:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:22:27 INFO - 'NO_EM_RESTART': '1', 08:22:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:22:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:27 INFO - 'PWD': '/builds/slave/test', 08:22:27 INFO - 'SHELL': '/bin/bash', 08:22:27 INFO - 'SHLVL': '1', 08:22:27 INFO - 'TERM': 'linux', 08:22:27 INFO - 'TMOUT': '86400', 08:22:27 INFO - 'USER': 'cltbld', 08:22:27 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469283740.78057-297003153', 08:22:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:27 INFO - '_': '/tools/buildbot/bin/python'} 08:22:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:27 INFO - Downloading/unpacking psutil>=3.1.1 08:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:22:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:22:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:22:29 INFO - Installing collected packages: psutil 08:22:29 INFO - Running setup.py install for psutil 08:22:29 INFO - building 'psutil._psutil_linux' extension 08:22:29 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:22:30 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 08:22:30 INFO - building 'psutil._psutil_posix' extension 08:22:30 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 08:22:30 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 08:22:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:22:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:22:30 INFO - Successfully installed psutil 08:22:30 INFO - Cleaning up... 08:22:30 INFO - Return code: 0 08:22:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:22:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb2d1bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe2b450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeba2c0>, '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 0xebb1c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xebb650>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469283740.78057-297003153', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:22:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:22:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:22:30 INFO - Using env: {'DISPLAY': ':0', 08:22:30 INFO - 'HOME': '/home/cltbld', 08:22:30 INFO - 'LANG': 'en_US.UTF-8', 08:22:30 INFO - 'LANGUAGE': 'en_US:en', 08:22:30 INFO - 'LOGNAME': 'cltbld', 08:22:30 INFO - 'MAIL': '/var/mail/cltbld', 08:22:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:30 INFO - 'MOZ_NO_REMOTE': '1', 08:22:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:22:30 INFO - 'NO_EM_RESTART': '1', 08:22:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:22:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:30 INFO - 'PWD': '/builds/slave/test', 08:22:30 INFO - 'SHELL': '/bin/bash', 08:22:30 INFO - 'SHLVL': '1', 08:22:30 INFO - 'TERM': 'linux', 08:22:30 INFO - 'TMOUT': '86400', 08:22:30 INFO - 'USER': 'cltbld', 08:22:30 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469283740.78057-297003153', 08:22:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:30 INFO - '_': '/tools/buildbot/bin/python'} 08:22:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:22:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:22:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:22:32 INFO - Installing collected packages: mozsystemmonitor 08:22:32 INFO - Running setup.py install for mozsystemmonitor 08:22:32 INFO - Successfully installed mozsystemmonitor 08:22:32 INFO - Cleaning up... 08:22:32 INFO - Return code: 0 08:22:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:22:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:32 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 0x7ffb2d1bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe2b450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeba2c0>, '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 0xebb1c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xebb650>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469283740.78057-297003153', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:22:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:22:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:22:32 INFO - Using env: {'DISPLAY': ':0', 08:22:32 INFO - 'HOME': '/home/cltbld', 08:22:32 INFO - 'LANG': 'en_US.UTF-8', 08:22:32 INFO - 'LANGUAGE': 'en_US:en', 08:22:32 INFO - 'LOGNAME': 'cltbld', 08:22:32 INFO - 'MAIL': '/var/mail/cltbld', 08:22:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:32 INFO - 'MOZ_NO_REMOTE': '1', 08:22:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:22:32 INFO - 'NO_EM_RESTART': '1', 08:22:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:22:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:32 INFO - 'PWD': '/builds/slave/test', 08:22:32 INFO - 'SHELL': '/bin/bash', 08:22:32 INFO - 'SHLVL': '1', 08:22:32 INFO - 'TERM': 'linux', 08:22:32 INFO - 'TMOUT': '86400', 08:22:32 INFO - 'USER': 'cltbld', 08:22:32 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469283740.78057-297003153', 08:22:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:32 INFO - '_': '/tools/buildbot/bin/python'} 08:22:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:32 INFO - Downloading/unpacking jsonschema==2.5.1 08:22:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:22:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:22:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:35 INFO - Downloading functools32-3.2.3-2.tar.gz 08:22:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:22:35 INFO - warning: no files found matching '*.txt' 08:22:35 INFO - no previously-included directories found matching 'build' 08:22:35 INFO - no previously-included directories found matching 'dist' 08:22:35 INFO - no previously-included directories found matching '.git*' 08:22:35 INFO - Installing collected packages: jsonschema, functools32 08:22:35 INFO - Running setup.py install for functools32 08:22:35 INFO - warning: no files found matching '*.txt' 08:22:35 INFO - no previously-included directories found matching 'build' 08:22:35 INFO - no previously-included directories found matching 'dist' 08:22:35 INFO - no previously-included directories found matching '.git*' 08:22:35 INFO - Successfully installed jsonschema functools32 08:22:35 INFO - Cleaning up... 08:22:35 INFO - Return code: 0 08:22:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:22:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:35 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 0x7ffb2d1bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe2b450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeba2c0>, '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 0xebb1c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xebb650>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469283740.78057-297003153', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:22:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:22:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:22:35 INFO - Using env: {'DISPLAY': ':0', 08:22:35 INFO - 'HOME': '/home/cltbld', 08:22:35 INFO - 'LANG': 'en_US.UTF-8', 08:22:35 INFO - 'LANGUAGE': 'en_US:en', 08:22:35 INFO - 'LOGNAME': 'cltbld', 08:22:35 INFO - 'MAIL': '/var/mail/cltbld', 08:22:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:35 INFO - 'MOZ_NO_REMOTE': '1', 08:22:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:22:35 INFO - 'NO_EM_RESTART': '1', 08:22:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:22:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:35 INFO - 'PWD': '/builds/slave/test', 08:22:35 INFO - 'SHELL': '/bin/bash', 08:22:35 INFO - 'SHLVL': '1', 08:22:35 INFO - 'TERM': 'linux', 08:22:35 INFO - 'TMOUT': '86400', 08:22:35 INFO - 'USER': 'cltbld', 08:22:35 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469283740.78057-297003153', 08:22:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:35 INFO - '_': '/tools/buildbot/bin/python'} 08:22:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:35 INFO - Downloading/unpacking blobuploader==1.2.4 08:22:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:37 INFO - Downloading blobuploader-1.2.4.tar.gz 08:22:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:22:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:22:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:22:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:22:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:38 INFO - Downloading docopt-0.6.1.tar.gz 08:22:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:22:38 INFO - Installing collected packages: blobuploader, requests, docopt 08:22:38 INFO - Running setup.py install for blobuploader 08:22:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:22:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:22:38 INFO - Running setup.py install for requests 08:22:38 INFO - Running setup.py install for docopt 08:22:38 INFO - Successfully installed blobuploader requests docopt 08:22:38 INFO - Cleaning up... 08:22:38 INFO - Return code: 0 08:22:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:22: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')]} 08:22:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22: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')]} 08:22:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:38 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 0x7ffb2d1bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe2b450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeba2c0>, '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 0xebb1c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xebb650>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469283740.78057-297003153', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:22:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:22:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:22:38 INFO - Using env: {'DISPLAY': ':0', 08:22:38 INFO - 'HOME': '/home/cltbld', 08:22:38 INFO - 'LANG': 'en_US.UTF-8', 08:22:38 INFO - 'LANGUAGE': 'en_US:en', 08:22:38 INFO - 'LOGNAME': 'cltbld', 08:22:38 INFO - 'MAIL': '/var/mail/cltbld', 08:22:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:38 INFO - 'MOZ_NO_REMOTE': '1', 08:22:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:22:38 INFO - 'NO_EM_RESTART': '1', 08:22:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:22:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:38 INFO - 'PWD': '/builds/slave/test', 08:22:38 INFO - 'SHELL': '/bin/bash', 08:22:38 INFO - 'SHLVL': '1', 08:22:38 INFO - 'TERM': 'linux', 08:22:38 INFO - 'TMOUT': '86400', 08:22:38 INFO - 'USER': 'cltbld', 08:22:38 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469283740.78057-297003153', 08:22:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:38 INFO - '_': '/tools/buildbot/bin/python'} 08:22:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:22:39 INFO - Running setup.py (path:/tmp/pip-vQQxtQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:22:39 INFO - Running setup.py (path:/tmp/pip-rHojkw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:22:39 INFO - Running setup.py (path:/tmp/pip-tdGATO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:22:39 INFO - Running setup.py (path:/tmp/pip-4FIMUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:22:39 INFO - Running setup.py (path:/tmp/pip-GxyONo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:22:39 INFO - Running setup.py (path:/tmp/pip-p6Poyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:22:39 INFO - Running setup.py (path:/tmp/pip-qnufHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:22:39 INFO - Running setup.py (path:/tmp/pip-rv2vfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:22:39 INFO - Running setup.py (path:/tmp/pip-1acY5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:22:39 INFO - Running setup.py (path:/tmp/pip-EdAt_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:22:39 INFO - Running setup.py (path:/tmp/pip-UEnpUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:22:39 INFO - Running setup.py (path:/tmp/pip-NS7ur0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:22:39 INFO - Running setup.py (path:/tmp/pip-g_m9MS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:22:39 INFO - Running setup.py (path:/tmp/pip-RC7XqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:22:40 INFO - Running setup.py (path:/tmp/pip-q_51zA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:22:40 INFO - Running setup.py (path:/tmp/pip-bwOhHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:22:40 INFO - Running setup.py (path:/tmp/pip-mGgdu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:22:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:22:40 INFO - Running setup.py install for manifestparser 08:22:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:22:40 INFO - Running setup.py install for mozcrash 08:22:40 INFO - Running setup.py install for mozdebug 08:22:40 INFO - Running setup.py install for mozdevice 08:22:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:22:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:22:40 INFO - Running setup.py install for mozfile 08:22:40 INFO - Running setup.py install for mozhttpd 08:22:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:22:40 INFO - Running setup.py install for mozinfo 08:22:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:22:40 INFO - Running setup.py install for mozInstall 08:22:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:22:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:22:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:22:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:22:41 INFO - Running setup.py install for mozleak 08:22:41 INFO - Running setup.py install for mozlog 08:22:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:22:41 INFO - Running setup.py install for moznetwork 08:22:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:22:41 INFO - Running setup.py install for mozprocess 08:22:41 INFO - Running setup.py install for mozprofile 08:22:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:22:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:22:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:22:41 INFO - Running setup.py install for mozrunner 08:22:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:22:41 INFO - Running setup.py install for mozscreenshot 08:22:41 INFO - Running setup.py install for moztest 08:22:41 INFO - Running setup.py install for mozversion 08:22:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:22:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:22:41 INFO - Cleaning up... 08:22:42 INFO - Return code: 0 08:22:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:22:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:42 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 0x7ffb2d1bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe2b450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeba2c0>, '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 0xebb1c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xebb650>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469283740.78057-297003153', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:22:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:22:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:22:42 INFO - Using env: {'DISPLAY': ':0', 08:22:42 INFO - 'HOME': '/home/cltbld', 08:22:42 INFO - 'LANG': 'en_US.UTF-8', 08:22:42 INFO - 'LANGUAGE': 'en_US:en', 08:22:42 INFO - 'LOGNAME': 'cltbld', 08:22:42 INFO - 'MAIL': '/var/mail/cltbld', 08:22:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:42 INFO - 'MOZ_NO_REMOTE': '1', 08:22:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:22:42 INFO - 'NO_EM_RESTART': '1', 08:22:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:22:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:42 INFO - 'PWD': '/builds/slave/test', 08:22:42 INFO - 'SHELL': '/bin/bash', 08:22:42 INFO - 'SHLVL': '1', 08:22:42 INFO - 'TERM': 'linux', 08:22:42 INFO - 'TMOUT': '86400', 08:22:42 INFO - 'USER': 'cltbld', 08:22:42 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469283740.78057-297003153', 08:22:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:42 INFO - '_': '/tools/buildbot/bin/python'} 08:22:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:22:42 INFO - Running setup.py (path:/tmp/pip-2dCrGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:22:42 INFO - Running setup.py (path:/tmp/pip-XmEnMJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:22:42 INFO - Running setup.py (path:/tmp/pip-48fe3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:22:42 INFO - Running setup.py (path:/tmp/pip-H3wdbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:22:42 INFO - Running setup.py (path:/tmp/pip-tWpTkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:22:42 INFO - Running setup.py (path:/tmp/pip-JMOqay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:22:42 INFO - Running setup.py (path:/tmp/pip-SjYpDS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:22:42 INFO - Running setup.py (path:/tmp/pip-775046-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:22:42 INFO - Running setup.py (path:/tmp/pip-42XdYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:22:42 INFO - Running setup.py (path:/tmp/pip-lmoTDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:22:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:22:42 INFO - Running setup.py (path:/tmp/pip-wfddog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:22:43 INFO - Running setup.py (path:/tmp/pip-W9ukmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:22:43 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)) 08:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:22:43 INFO - Running setup.py (path:/tmp/pip-ZnIPGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:22:43 INFO - Running setup.py (path:/tmp/pip-Xd8CX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:22:43 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)) 08:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:22:43 INFO - Running setup.py (path:/tmp/pip-sPjSQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:22:43 INFO - Running setup.py (path:/tmp/pip-nw_D9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:22:43 INFO - Running setup.py (path:/tmp/pip-U0G9BU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:22:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:22:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:22:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:45 INFO - Downloading blessings-1.6.tar.gz 08:22:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:22:45 INFO - Installing collected packages: blessings 08:22:45 INFO - Running setup.py install for blessings 08:22:45 INFO - Successfully installed blessings 08:22:45 INFO - Cleaning up... 08:22:45 INFO - Return code: 0 08:22:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:22:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:22:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:22:45 INFO - Reading from file tmpfile_stdout 08:22:45 INFO - Current package versions: 08:22:45 INFO - argparse == 1.2.1 08:22:45 INFO - blessings == 1.6 08:22:45 INFO - blobuploader == 1.2.4 08:22:45 INFO - docopt == 0.6.1 08:22:45 INFO - functools32 == 3.2.3-2 08:22:45 INFO - jsonschema == 2.5.1 08:22:45 INFO - manifestparser == 1.1 08:22:45 INFO - mozInstall == 1.12 08:22:45 INFO - mozcrash == 0.17 08:22:45 INFO - mozdebug == 0.1 08:22:45 INFO - mozdevice == 0.48 08:22:45 INFO - mozfile == 1.2 08:22:45 INFO - mozhttpd == 0.7 08:22:45 INFO - mozinfo == 0.9 08:22:45 INFO - mozleak == 0.1 08:22:45 INFO - mozlog == 3.2 08:22:45 INFO - moznetwork == 0.27 08:22:45 INFO - mozprocess == 0.23 08:22:45 INFO - mozprofile == 0.28 08:22:45 INFO - mozrunner == 6.12 08:22:45 INFO - mozscreenshot == 0.1 08:22:45 INFO - mozsystemmonitor == 0.3 08:22:45 INFO - moztest == 0.7 08:22:45 INFO - mozversion == 1.4 08:22:45 INFO - psutil == 3.1.1 08:22:45 INFO - requests == 1.2.3 08:22:45 INFO - wsgiref == 0.1.2 08:22:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:22:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:22:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:45 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 0x7ffb2d1bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe2b450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeba2c0>, '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 0xebb1c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xebb650>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469283740.78057-297003153', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:22:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:22:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:22:45 INFO - Using env: {'DISPLAY': ':0', 08:22:45 INFO - 'HOME': '/home/cltbld', 08:22:45 INFO - 'LANG': 'en_US.UTF-8', 08:22:45 INFO - 'LANGUAGE': 'en_US:en', 08:22:45 INFO - 'LOGNAME': 'cltbld', 08:22:45 INFO - 'MAIL': '/var/mail/cltbld', 08:22:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:45 INFO - 'MOZ_NO_REMOTE': '1', 08:22:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:22:45 INFO - 'NO_EM_RESTART': '1', 08:22:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:22:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:45 INFO - 'PWD': '/builds/slave/test', 08:22:45 INFO - 'SHELL': '/bin/bash', 08:22:45 INFO - 'SHLVL': '1', 08:22:45 INFO - 'TERM': 'linux', 08:22:45 INFO - 'TMOUT': '86400', 08:22:45 INFO - 'USER': 'cltbld', 08:22:45 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469283740.78057-297003153', 08:22:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:45 INFO - '_': '/tools/buildbot/bin/python'} 08:22:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:22:46 INFO - Cleaning up... 08:22:46 INFO - Return code: 0 08:22:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:22: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')]} 08:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22: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')]} 08:22:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb2d1bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe2b450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeba2c0>, '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 0xebb1c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xebb650>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469283740.78057-297003153', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:22: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', 'jsonschema'] in /builds/slave/test/build 08:22: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 jsonschema 08:22:46 INFO - Using env: {'DISPLAY': ':0', 08:22:46 INFO - 'HOME': '/home/cltbld', 08:22:46 INFO - 'LANG': 'en_US.UTF-8', 08:22:46 INFO - 'LANGUAGE': 'en_US:en', 08:22:46 INFO - 'LOGNAME': 'cltbld', 08:22:46 INFO - 'MAIL': '/var/mail/cltbld', 08:22:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:46 INFO - 'MOZ_NO_REMOTE': '1', 08:22:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:22:46 INFO - 'NO_EM_RESTART': '1', 08:22:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:22:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:46 INFO - 'PWD': '/builds/slave/test', 08:22:46 INFO - 'SHELL': '/bin/bash', 08:22:46 INFO - 'SHLVL': '1', 08:22:46 INFO - 'TERM': 'linux', 08:22:46 INFO - 'TMOUT': '86400', 08:22:46 INFO - 'USER': 'cltbld', 08:22:46 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469283740.78057-297003153', 08:22:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:46 INFO - '_': '/tools/buildbot/bin/python'} 08:22:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:22:46 INFO - Cleaning up... 08:22:46 INFO - Return code: 0 08:22:46 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:22: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')]} 08:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22: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')]} 08:22:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb2d1bff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd5f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe2b450>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xeba2c0>, '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 0xebb1c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xebb650>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469283740.78057-297003153', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:22: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', 'flake8'] in /builds/slave/test/build 08:22: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 flake8 08:22:46 INFO - Using env: {'DISPLAY': ':0', 08:22:46 INFO - 'HOME': '/home/cltbld', 08:22:46 INFO - 'LANG': 'en_US.UTF-8', 08:22:46 INFO - 'LANGUAGE': 'en_US:en', 08:22:46 INFO - 'LOGNAME': 'cltbld', 08:22:46 INFO - 'MAIL': '/var/mail/cltbld', 08:22:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:46 INFO - 'MOZ_NO_REMOTE': '1', 08:22:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:22:46 INFO - 'NO_EM_RESTART': '1', 08:22:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:22:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:46 INFO - 'PWD': '/builds/slave/test', 08:22:46 INFO - 'SHELL': '/bin/bash', 08:22:46 INFO - 'SHLVL': '1', 08:22:46 INFO - 'TERM': 'linux', 08:22:46 INFO - 'TMOUT': '86400', 08:22:46 INFO - 'USER': 'cltbld', 08:22:46 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469283740.78057-297003153', 08:22:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:46 INFO - '_': '/tools/buildbot/bin/python'} 08:22:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:46 INFO - Downloading/unpacking flake8 08:22: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 08:22: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 08:22: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 08:22: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 08:22: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 08:22: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 08:22:48 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:22:48 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:22: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 08:22: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 08:22: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 08:22: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 08:22: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 08:22: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 08:22:48 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:22:48 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:22: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 08:22: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 08:22: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 08:22: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 08:22: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 08:22: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 08:22:48 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:22: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 08:22: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 08:22: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 08:22: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 08:22: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 08:22: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 08:22:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:22:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:22:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:22:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:22:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:22:49 INFO - no previously-included directories found matching 'docs/_build' 08:22:49 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:22:49 INFO - Running setup.py install for pep8 08:22:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:22:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:22:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:22:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:22:49 INFO - no previously-included directories found matching 'docs/_build' 08:22:49 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:22:49 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:22:49 INFO - Cleaning up... 08:22:49 INFO - Return code: 0 08:22:49 INFO - Running post-action listener: _resource_record_post_action 08:22:49 INFO - Running post-action listener: _start_resource_monitoring 08:22:49 INFO - Starting resource monitoring. 08:22:49 INFO - ##### 08:22:49 INFO - ##### Finished create-virtualenv step (success) 08:22:49 INFO - ##### 08:22:49 INFO - ##### 08:22:49 INFO - ##### Running install step. 08:22:49 INFO - ##### 08:22:49 INFO - Running pre-action listener: _resource_record_pre_action 08:22:49 INFO - Running main action method: install 08:22:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:22:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:22:49 INFO - Reading from file tmpfile_stdout 08:22:49 INFO - Detecting whether we're running mozinstall >=1.0... 08:22:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:22:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:22:49 INFO - Reading from file tmpfile_stdout 08:22:49 INFO - Output received: 08:22:49 INFO - Usage: mozinstall [options] installer 08:22:49 INFO - Options: 08:22:49 INFO - -h, --help show this help message and exit 08:22:49 INFO - -d DEST, --destination=DEST 08:22:49 INFO - Directory to install application into. [default: 08:22:49 INFO - "/builds/slave/test"] 08:22:49 INFO - --app=APP Application being installed. [default: firefox] 08:22:49 INFO - mkdir: /builds/slave/test/build/application 08:22:49 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'] 08:22:49 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 08:23:05 INFO - Reading from file tmpfile_stdout 08:23:05 INFO - Output received: 08:23:05 INFO - /builds/slave/test/build/application/firefox/firefox 08:23:05 INFO - Running post-action listener: _resource_record_post_action 08:23:05 INFO - ##### 08:23:05 INFO - ##### Finished install step (success) 08:23:05 INFO - ##### 08:23:05 INFO - ##### 08:23:05 INFO - ##### Running run-tests step. 08:23:05 INFO - ##### 08:23:05 INFO - Running pre-action listener: _resource_record_pre_action 08:23:05 INFO - Running main action method: run_tests 08:23:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:23:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:23:05 INFO - Python 2.7.3 08:23:05 INFO - Return code: 0 08:23:05 INFO - grabbing minidump binary from tooltool 08:23:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:23:05 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 0xeba2c0>, '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 0xebb1c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xebb650>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:23:05 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:23:05 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 08:23:05 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:23:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:23:05 INFO - Return code: 0 08:23:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:23:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:23:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:23:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:23:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:23:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:23:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:23:06 INFO - Return code: 0 08:23:06 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/XlnQ4j8nRdaPGTwvGRwT0g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:23:06 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/XlnQ4j8nRdaPGTwvGRwT0g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-032 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:23:06 INFO - Using env: {'DISPLAY': ':0', 08:23:06 INFO - 'HOME': '/home/cltbld', 08:23:06 INFO - 'LANG': 'en_US.UTF-8', 08:23:06 INFO - 'LANGUAGE': 'en_US:en', 08:23:06 INFO - 'LOGNAME': 'cltbld', 08:23:06 INFO - 'MAIL': '/var/mail/cltbld', 08:23:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:23:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:23:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:06 INFO - 'MOZ_NO_REMOTE': '1', 08:23:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:23:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:23:06 INFO - 'NO_EM_RESTART': '1', 08:23:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:23:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:06 INFO - 'PWD': '/builds/slave/test', 08:23:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:23:06 INFO - 'SHELL': '/bin/bash', 08:23:06 INFO - 'SHLVL': '1', 08:23:06 INFO - 'TERM': 'linux', 08:23:06 INFO - 'TMOUT': '86400', 08:23:06 INFO - 'USER': 'cltbld', 08:23:06 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1469283740.78057-297003153', 08:23:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:06 INFO - '_': '/tools/buildbot/bin/python'} 08:23:06 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/XlnQ4j8nRdaPGTwvGRwT0g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:23:06 INFO - mozversion application_buildid: 20160723054326 08:23:06 INFO - mozversion application_changeset: b95e8981e41800c3b202ea73d096e3e3e184d70d 08:23:06 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:23:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:23:06 INFO - mozversion application_name: Firefox 08:23:06 INFO - mozversion application_remotingname: firefox-dev 08:23:06 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:23:06 INFO - mozversion application_vendor: Mozilla 08:23:06 INFO - mozversion application_version: 49.0a2 08:23:06 INFO - mozversion platform_buildid: 20160723054326 08:23:06 INFO - mozversion platform_changeset: b95e8981e41800c3b202ea73d096e3e3e184d70d 08:23:06 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:23:06 INFO - mozversion platform_version: 49.0a2 08:23:06 INFO - using testdate: 1469287386 08:23:06 INFO - actual date: 1469287386 08:23:06 INFO - starting webserver on 'localhost:60998' 08:23:06 INFO - SUITE-START | Running 2 tests 08:23:06 INFO - TEST-START | dromaeo_css 08:23:06 INFO - operating with platform_type : linux_ 08:23:06 INFO - Initialising browser for dromaeo_css test... 08:23:06 INFO - TEST-INFO | started process 5897 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpE0PEOC/profile http://localhost:60998/getInfo.html) 08:23:11 INFO - PROCESS | 5897 | __metrics Screen width/height:1600/1200 08:23:11 INFO - PROCESS | 5897 | colorDepth:24 08:23:11 INFO - PROCESS | 5897 | Browser inner width/height: 1024/697 08:23:11 INFO - PROCESS | 5897 | __metrics 08:23:13 INFO - PROCESS | 5897 | 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" 08:23:13 ERROR - PROCESS | 5897 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:23:14 INFO - TEST-INFO | 5897: exit 0 08:23:14 INFO - Browser initialized. 08:23:14 INFO - Running cycle 1/1 for dromaeo_css test... 08:23:14 INFO - TEST-INFO | started process 5990 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpE0PEOC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:23:26 INFO - PROCESS | 5990 | RSS: Main: 145551360 08:23:26 INFO - PROCESS | 5990 | 08:24:17 INFO - PROCESS | 5990 | 1469287457552 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:26:25 INFO - PROCESS | 5990 | Cycle 1(1): loaded http://localhost:60998/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60998/tests/dromaeo/cssquery-ext.html) 08:26:26 INFO - PROCESS | 5990 | RSS: Main: 255672320 08:26:26 INFO - PROCESS | 5990 | 08:29:29 INFO - PROCESS | 5990 | Cycle 1(1): loaded http://localhost:60998/tests/dromaeo/cssquery-ext.html (next: http://localhost:60998/tests/dromaeo/cssquery-jquery.html) 08:29:29 INFO - PROCESS | 5990 | RSS: Main: 224870400 08:29:29 INFO - PROCESS | 5990 | 08:32:31 INFO - PROCESS | 5990 | Cycle 1(1): loaded http://localhost:60998/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60998/tests/dromaeo/cssquery-mootools.html) 08:32:32 INFO - PROCESS | 5990 | RSS: Main: 225021952 08:32:32 INFO - PROCESS | 5990 | 08:35:34 INFO - PROCESS | 5990 | Cycle 1(1): loaded http://localhost:60998/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60998/tests/dromaeo/cssquery-prototype.html) 08:35:34 INFO - PROCESS | 5990 | RSS: Main: 222109696 08:35:34 INFO - PROCESS | 5990 | 08:38:37 INFO - PROCESS | 5990 | Cycle 1(1): loaded http://localhost:60998/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60998/tests/dromaeo/cssquery-yui.html) 08:38:38 INFO - PROCESS | 5990 | RSS: Main: 216010752 08:38:38 INFO - PROCESS | 5990 | 08:41:40 INFO - PROCESS | 5990 | Cycle 1(1): loaded http://localhost:60998/tests/dromaeo/cssquery-yui.html (next: http://localhost:60998/tests/dromaeo/cssquery-dojo.html) 08:41:41 INFO - PROCESS | 5990 | RSS: Main: 255905792 08:41:41 INFO - PROCESS | 5990 | 08:41:41 INFO - PROCESS | 5990 | __start_tp_report 08:41:41 INFO - PROCESS | 5990 | _x_x_mozilla_page_load 08:41:41 INFO - PROCESS | 5990 | _x_x_mozilla_page_load_details 08:41:41 INFO - PROCESS | 5990 | |i|pagename|runs| 08:41:41 INFO - PROCESS | 5990 | |0;dojo.html;3345.514900339451;3357.429237029705;3363.7309015278774;3397.048826328629;3405.3529829332424;5516.448355164482;5608.270924779778;5622.156676498517;5626.859328516789;5640.661560306385;5554.50009499145;5607.327120745516;5615.101583746601;5616.550675945925;5631.475749221163;4958.578520825734;4998.5501304882555;5083.491650834914;5089.083964886319;5123.129068058421;4984.700917944935;5022.221555658861;5083.517065878754;5088.313077734514;5096.4648711885275;4988.526090021461;5058.443571207183;5064.544191022807;5113.284140220372;5126.282320475137;5175.482451754832;5186.19613959835;5186.6369354144845;5192.55863251624;5223.947760522384;4928.704277743311;4982.153033984231;4999.725015124167;5082.720450375228;5101.362329708786;5429.56563474921;5432.266644003088;5448.700321482317;5494.890102197934;5511.22842802008;6038.33578306386;6041.8187454376;6058.0610005449225;6058.363871793486;6061.272647282311;5989.251343582052;6018.939810601925;6048.092786082079;6080.61535387841;6113.358097399753;5641.943580564182;5688.658680479184;5697.4587414195585;5698.17376480408;5700.059490184156;5955.076963070731;6185.288691800501;6197.442230199214;6205.875882482325;6208.037754148114;5844.240248767718;5900.291964964232;5904.76380944766;5938.435848594377;6003.369646187088;6031.306399763996;6045.274567051982;6060.575759696779;6072;6077.270727512727;5809.535237181015;5860.208871802337;6004.699765011732;6020.789272375533;6023.578349515492;5895.793647222326;5940.287165540163;5949.791757288474;5962.701864906738;5980.910286345708;4558.475775285885;4572.542745725401;4584.885377865513;4585.770711464414;4606.746628935441;4027.275090483741;4034.616711412411;4080.367543030835;4115.58844115592;4150.584941505886;5740.1102829061565;5763.106718458645;5764.827055188351;5765.769369225184;5792.855178620568;6001.849953751192;6051.788187413419;6054.878902422015;6064.848378790565;6085.36103709113;5552.611317207757;5694.829155125353;5719.742611582489;5734.541236701054;5737.254156959569;5136.5890728741615;5145.614078944094;5149.613778966593;5156.74216289184;5169.560587350117;6328.493720502349;6330.177076979963;6340.112384266206;6353.332900045429;6356.8410789729705;5789.797357092481;5900.793472228452;5966;5985.25184351956;6059.515238780975;6227.097070924781;6241.656708880873;6254.843628909313;6256.593321434092;6264.154339164154;5861.472467477947;5895.4399332063385;5901.0558310670085;5909.409059094056;5939;5877.265341832271;5961.195238642727;6008.489278411384;6025.066114752045;6072.301685306246;5872.735726892215;5886.911696324644;5909.202257695328;5914.704264786829;5918.763249469973;5702.800401985911;5868.677222752619;5891.234139562001;5918.763249469973;5933.2880054393745;5790.10253410722;5798.188253644409;5827.504662103599;5885.558583106199;5886.6173698709445;5968.313643931004;6021.126936594257;6023.1567580537885;6033.366496517802;6034.487068599043;5662.150677398258;5766.481016708516;5855.94144058554;5869.442402971711;5872.647641141545 08:41:41 INFO - PROCESS | 5990 | |1;ext.html;618.9504839612831;643.7392855893363;665.1452883045288;670.1756839087918;677.0217036382427;32873.83563082184;36172.63827361725;46045.53954460459;46822;46871.531284687146;48615.27077093846;49022.50977490224;49085.50914490854;49121;49182.26226606595;8299.58502074897;8857.822843543108;9070.04764499728;9073.047330030351;10617.150627949784;10506.212034097434;10828.40443775592;10904;10924.890751092467;10931.344119352865;10952.835707464394;10996.560137594486;11005.559777608885;11008.174386921013;11009.724756881062;8321.66713331469;8541.487510749375;10850.077743391821;10877.456127193649;11077.889221107765;10472.947635261851;10878.401687907162;10878.891211087866;10882.347059176474;10885.727856803564;45772.54227457716;46327;46355.30467042997;46382.76808615969;46442.53557464416;3758.4550240215017;3850.6534411902785;3896.4544963705102;4063.207674503473;4380.759058251795;35564;35693.28613427743;35842.46236306457;35871.641283587094;36145.27709445823;4653.926131300122;4723.881902952454;4732.645051621143;4792.904141917177;4792.9760351198365;360617;364002;364380;365316;365887;69940.6502967482;70176.6491167541;70821.64589177021;70849.6457517709;70968.64515677388;40394.59605403908;41201.58798412037;41255.58744412578;43579;74847.25152748512;18862.43412697621;18908.71636925447;18963.715544266848;18970.62058758817;18978.52553686169;13671.521496747766;13890.58328250154;14233.146011239358;14293.356798944073;14613.196274204809;22359.66460503094;22360.322975776882;22529.662055069188;22586.887065564566;22709.886450567643;7971;8238.464499807493;8336.499430102518;8378.622961966727;8392.496450213006;1091.792559413709;1096.1614365010773;1106.4744246482855;1125.2910666280354;1169.5555688838188;33776.324473510394;33894.32211355759;34309.31381372359;34394.31211375759;34589.481157782655;1206.3907726598188;1221.3404761428906;1246.7132559511363;1272.2088177231901;1276.9489220431078;17347.3061077558;17617.471475855746;17742.822571774374;18213.90893045526;18243.17905694247;574340;580687;583846;584051;585241;60874;61963.38036619666;62199.378006220264;62366.68816655887;62396.37603623996;1356.9050166488448;1443.487561915526;1468.4273133477739;1489.858463445971;1498.2658497336445;34151.829240853636;34555.65444345625;35179;35292.823535882155;35718;52991.735041324544;53363.19955200597;53411;53578;53761.19358209552;419719;422878;423374;425574;425941;26960.730392696605;29704.70295297106;30815.537766933066;30967.535486967263;31337.529937050505;51636.48363516317;51949.22076168785;52545;53549.73225133849;56387;43890.34164487599;44212.2787516889;44827.551724482335;44911.77544112258;45694;42831.785841070596;42972.57027429811;43993;44737.55262447464;45203.32195017012;43454.34818477789;44365.77817110894;44443;45152.77423612861;45365;39370;39911.800440997606;40008.3998740025;40337.39493907535;40623;29997.550036749904;30849.75554155754;31159.221019474695;31219.59511822019;31756.364872701954 08:41:41 INFO - PROCESS | 5990 | |2;jquery.html;433.13051036880665;435.3208993969408;438.8025388575141;440.3570786651489;450.61022215783356;18559.814401855976;19470.221191152355;20178;20209;20309.289174878882;19022.429327120175;19982.300619478323;20273.189072437122;20295.898520507413;20324.796752032475;5696.08862581987;5900.203472531196;5927.703614819264;5931.6441013539325;5931.851703707399;5818.06910894257;5838.2702162229725;5855.590108692372;5857.3849745776715;5868;5682.886342273152;5789.623674461168;5797.536197104221;5809.941900581003;5814.941850581504;5631.521320687746;5792.884142317151;5900.822975310769;5949.8810023799715;6053.818385448443;5576.832695019156;5765.336986246593;5784.334801497839;5792.131180322943;5858.033424484997;14474.42102315906;14621.195834229113;14649.340779664833;14721.631959200999;18616;2310.4801419680607;2379.4170428245016;2511.949761004788;2548.4011257354505;2862.5276829323343;9650.08324209199;10503.264771466;10558.894411055868;10616.363018218932;10689;1943.6112777444569;2095.7170781944396;2216.5345277491747;2217.5010622609784;2219.9223027194134;60869;71593.99999999948;74214.25785742127;77091.22908770952;78028;11581.305121692725;11793.646190614296;11884.465199066062;11916.285022898654;12048.698782530331;16478.505644830675;16698.916505417394;16703.081330526937;16710.749338759928;16724.91637541829;7346.412287017026;8554.443961142506;8895.24390426808;8909.643614255487;8923.286137109015;8734.344924130588;8743.038265790885;8804.559772011375;8813.383063185644;8830.602622881986;5057.216131499624;5091.261767043758;5117.078925793318;5117.130087885069;5210.583153347723;1949.2885096939515;2071.927482538104;2089.8026020189136;2103.1797598936428;2142.1966762464076;980.2256217588168;1043.1654676258963;1072.8766191887876;1096.3860238266595;1132.660201939415;19434.70847937282;19436.22255109808;19505.512362190773;19755.111020004133;19763.640181168947;1019;1067.8886287791938;1088.50473034769;1122.0967121467297;1131.1516362727955;10764.192685548614;11046.889531104585;11070.225084244186;11086.833697494543;11200.383978881076;542732;555885;556712;559546;568187;101374;101969.49015254877;103440;106481;108159.45920270348;984.5274268351232;1052.6157952347337;1118.2954738514845;1132.4281237974935;1141.7145713571608;18307.54231144232;18313.267469301078;18455;18608.34870779516;18685.06574671234;43734.56265437305;44278.55721442744;46212;46546.836329090685;47766.04467910692;63012;64029.0395644075;64289;64307.35692643201;66005;7778.533288002512;8140.633671484916;8141.552214628252;8364.581770911553;8924.643014279356;9144.63421463134;9408.670696525589;9498.19265362452;9530.142287194185;9926.255530835071;8667.609957552051;8728.170823771732;8800.427972181787;8887.600057997277;9286.257099432165;8328.333733301442;8393.531132051989;8819.162179592928;8996.685116020908;9141.497217653094;9323.440593564408;9333.57998890038;9449.102335278138;9562.426254424758;9753.122219000325;13410.9329453356;13820.723585528434;13828.239446830521;13854.30728463593;13854.930725346308;12901.677458063623;13009.869901301245;13074.28091454979;13105.606831795061;13112.606621801362 08:41:41 INFO - PROCESS | 5990 | |3;mootools.html;1375.3948262764388;1540.8151021877372;1579.1078040906887;1729.8529624981875;1898.2407037185123;8565.443246188994;8929.464232146067;8943.463392196472;9282.025387334334;9282.118198771106;8840.77898052549;9014.278857691384;9025.097490250972;9036.231920286784;9038.59326330315;2101.5901899129676;2390.055927908475;2451.9509609807837;2467.111839737693;2499.600063989762;2441.5849305618;2461.692288463942;2466.3834041489627;2468.4816188600416;2470.357706996176;2415.2271273192587;2430.368104292888;2432.0393444589376;2433.124075332879;2435.512897420523;2072.5440403111293;2404.6152615581423;2477.046337160188;2482.689663792026;2491.190363131989;2380.9880950595134;2386.7135943686694;2398.616221404567;2410.9035638574437;2435.135526887947;9126.543672816333;9174.633014679404;9191.080891910822;9215.400998935047;9283.11810378013;5320.14877619579;5432.891342173174;5433.510984011458;5483.917741233885;5492.148716948879;3360.8319584020946;3479.5128681984543;3548.1484443733575;3555.8577656893694;3565.0196196046036;1222.6148763139672;1232.9075319350995;1250.1249125612107;2262.2082271205113;2284.348960546253;12855.807162892565;12925.482980680761;12968.416421260972;12970.416331265116;12976.221426714334;4706.1999460091865;4720.197566413649;4763.904721905543;4793.520647935248;4852.44196917359;10230.23273254509;10269.794604107876;10307.020833020852;10319.432431216355;10324.174066074696;4617;5439.836804895859;5454.154606036071;5496.587755918322;5501.477359650827;4410.4927933287445;4466.062126953311;4467.419235499365;4949.653524253267;5092.159793634083;4052.708638360176;4071.7353372030725;4123.134141830249;4133.55325636025;4137.586241375839;1192.1297452859462;1231.1874163052341;1233.858106317767;1250.4310538704867;1250.8248845161684;986.2947992185622;1088.874779400379;1090.1987039525943;1102.7132945434084;1125.6960620632547;6211.472024877936;6262.1546091278005;6326.9683651581445;6327.462165715873;6343.651099189497;490.20341944340447;491.91391506486224;495.2299174783175;508.55501436243287;508.96437249392187;4336.489366975686;4469.9553004469535;4476.798544065525;4494.056248187917;4543.250363689954;11184.94407527957;11622.418879056013;11653.41732913351;11692;11696.766064678659;11294.152938529644;11585.420728963687;11645.243059201124;11651.067914566815;11657.125715571367;8606.13938606147;8711.825763484821;8725.389222754473;8780;8804.823903521894;6288.591241569284;6321.96839015802;6328.778492752732;6352.714127864349;6463.515236357198;10378.42918639482;10725.08836748885;10727.463626818471;10803.567857285934;10838.349699018085;10716.303440276357;10838;10852.403117828595;11070.335779853234;11071.944640276746;6703.3296670332575;6918.273581274095;6921.792346229419;7803.141654417896;7916.08964969036;8205.384596155192;8344.207300306462;8543.957280213559;8615.6984505542;8877.511736854534;7862.606869656609;7991.24083212094;8034.156413576724;8114.945057142534;8175.959120204361;7827.139014708264;7886.763397098096;7959.323457506177;7992.6403311849945;8012.679492820222;7045.964770176116;7139.178994415501;7180.461465390013;7189.1013623297085;7191.388731957633;5467.9453205467435;5529.30883639545;5529.336479622471;5705.600607957404;5767.913481297868;5766.077427611562;5813.854653633523;5823.650580965155;5846.269216347957;5947.256592925884 08:41:41 INFO - PROCESS | 5990 | |4;prototype.html;327.30774412118376;346.59448445318975;348.59737003760665;358.03688079067314;360.81778701755616;5513.857101452174;5527.778888844451;5543.946650136471;5545.251391062206;5561.416051314614;5192.792288308473;5213;5373.462653734625;5416.485433883775;5448.291722076135;4271.765052922088;4272.060146767706;4295.463067116611;4309.956900430987;4319.50325712544;4284.885929658296;4302.053548219386;4315.525292217853;4322.616762635942;4340.631046361063;3960.2871483133026;4060.8984775380554;4069.4302797608357;4251;4277.3156294992805;4182.414461975326;4216.978915105436;4224.598663127029;4241.151769646083;4242.490901091874;3730.403135498307;3741.5510138783247;3749.4375843623675;3766.5291838520184;3777.527809023872;5072.746362681851;5245.213218017289;5247.029299579552;5251.08106081438;5259.631825772198;4894.192458244385;4903.975480122613;4910.386201724785;4922.507749225048;4923.532264434873;5618.943810561883;5687.8339940312135;5691.886162276731;5692.203091567184;5708.686022268774;4128.348660535809;4207.779743874273;4222.324428091521;4225.19721252961;4240.279152544075;5896.941030589725;5899.262592175978;5901.704914754288;5909.6158749681135;5915.645061296293;4304.289792184254;4306.267934451151;4314.331278651751;4324.891877703019;4329.935050974238;4049.493813273341;4274.444322238089;4297.935530967038;4300.0969796343115;4397.098594788061;4828.275758636232;4840.419149702058;4855.393075865517;4868.391451068616;4889.902201955941;5673.574481913873;5676.2337084493065;5693.658380497101;5723.627964182315;5733.942660573341;1402.0956483068364;1419.5599364196926;1431.8997670163196;1474.5944865161953;1483.1916605450056;987.3779518903045;999.6607937426458;1007.5893748751806;1010.2724341376182;1025.620520407454;5511.87006663633;5513.669179849222;5518.24087956022;5549.611527193058;5565.526930210896;5760.654360738369;5773.566982476247;5774.873899589624;5791.594588378857;5829.300483941869;5353.304070470814;5556.388797232301;5623.887522249532;5624.272227969432;5689.9146512802345;3121.798107728541;3135.498320268746;3136.2002689314077;3147.4420162019946;3153.369326134782;6135.754569817157;6141.293751218578;6157.84605384869;6162;6163.630182189083;5676.858078548069;6002.579819412599;6047.183630209865;6049;6069.666168360694;6131.84670383244;6175.3824617538785;6182.258129024456;6193.969030154911;6265.749370025148;5662.603617746719;5982.162497250475;5994.400559943971;5994.460498555151;6033.456988871023;5783.94216057834;5821.4573138117585;5836.416358364129;5897.469227769521;5908.793192238251;5876.794312199053;6192.59748116389;6200.348963358782;6201.81394558164;6211.9689401552705;4272.850450234227;4357.150355680738;4388.670849686222;4391.714538554984;4396.428464299621;5926.2295901532525;5946.494547963299;5952.464278214981;5961.612495187799;5964.850878728066;5593.972030139823;5651.858703532444;5756.769729210785;5771.278590176228;5785.768569257351;5709.8287051388525;5724.341700704473;5736.971315143398;5767.480926716616;5770.480656740746;5548.583856210719;5561.249231353877;5656.5757568181725;5692.601517893708;5712.771489140554;5786.218860453785;5805.506531944832;5821.825345239649;5824.912626310524;5849.824505264849;5638.85902852432;5642.915356269577;5656.717164141859;5673.631213971079;5690.5163061140265 08:41:41 INFO - PROCESS | 5990 | |5;yui.html;775.8991331126953;793.1473665809253;827.5283088639478;845.602566793607;860.7159637319685;15217.086974781521;16380.42668506601;16677.08276044817;16683.332666693346;16702.331906723746;14847.628809279773;15308.770368444455;15320;15325.310361033753;15326.693466130699;713.6289129652578;856.3363393370125;870.78665726897;890.6214858685065;893.4371346051978;886.0165216609558;887.6538150121457;893.7631527645179;894.0121166111451;894.1058941058941;940.9482478463682;943.127606963556;943.1558754914347;943.3820847345;943.485800238868;753.4386881773087;879.9912000880005;907.850204716221;908.6456282049973;911.8541033434618;671.0940230688581;671.228087699145;671.4024518178825;671.5634837355709;671.6977360187886;16134.596635084099;16166.191690415431;16186.35254589815;16190.109543975077;16200.513984580364;15518.844811551853;15553.300101495346;15868.444604438904;15923;16007.039577625379;13881.722365552632;14230.359633816503;14269.215193164371;14527.85472145286;14657.780133298009;3982.0841206522373;3996.0609256824914;4080.2655522006025;4117.691173161995;4201.7268877522865;75519;76664.61667691682;77268.22731772666;77286.22713772846;79881.60059199667;12206.877931220573;12288;12430;12527.248365097941;12529.812052819216;21979.670304945437;21992.230271940407;22013.779862201493;22031.889840550695;22036.669449958263;8766;8800.383973121949;8818.91181088181;9034.774130646787;9148.588313525774;5082.567981721521;5150.639455238097;5180.922286165635;5226.242194881721;5250.5012023858435;3841.2509560635967;3912.7847968361443;3915.647591716759;3921.784301863425;3927.0378757204667;648.3225029843825;690.6719308328604;697.2191145916504;713.9535930164523;754.5887491317245;683.6205905722272;739.0097269658684;776.7203806629609;797.924197201265;814.9877751833727;10058.849117263248;10181.083702466814;10203.591856325664;10445.582176712998;10650.57397704083;666.5434177588368;761.4251240313528;796.1600511460465;812.5287333346645;824.2375802382772;5248.895022099537;5259.421463638997;5323.707196104174;5394.811181608625;5445.754941027663;77728.61135694286;78048;90054;118327;133089.33455332855;105811;112395;113069;113307.43346283215;113944;98290;98385;100473;102298.48850755848;102534;9701.514924253901;9723.65967191145;9781.04012183845;9971.219570692;10281.177505799667;45018;56979;57071.71464142653;57505.71247143905;59206;63657;76282;77477;77973;80667;13790.586282411543;13893.583192503836;13895.235762033186;13999.230042347768;14318.642033948818;16065.357385704909;16139.380147055233;16322.67354652924;16363.100029498491;16527.586810329838;15282.847171528143;15357.3089210988;15556.299966501761;15798.36806527726;16295.185240738152;14695.191764453059;14859.257037147883;15091.094534327976;15497.30262138229;15794.9210253948;13845.030847840555;14319.284035798377;14821.92589037048;15044.473443429428;15365.078095314317;10967.561297548327;11168.609098681507;11206.215564910379;11352.886471135183;11871.406429678655;12619.645848289167;12693.619191424272;12751.107422480338;12849.293288868828;12886.806697899352 08:41:41 INFO - PROCESS | 5990 | __end_tp_report 08:41:41 INFO - PROCESS | 5990 | __start_cc_report 08:41:41 INFO - PROCESS | 5990 | _x_x_mozilla_cycle_collect,900 08:41:41 INFO - PROCESS | 5990 | __end_cc_report 08:41:41 INFO - PROCESS | 5990 | __startTimestamp1469288501103__endTimestamp 08:41:41 INFO - PROCESS | 5990 | 08:41:41 INFO - PROCESS | 5990 | ------- Summary: start ------- 08:41:41 INFO - PROCESS | 5990 | Number of tests: 6 08:41:41 INFO - PROCESS | 5990 | 08:41:41 INFO - PROCESS | 5990 | [#0] dojo.html Cycles:165 Average:5587.83 Median:5827.50 stddev:635.32 (10.9%) stddev-sans-first:612.43 08:41:41 INFO - PROCESS | 5990 | Values: 3345.5 3357.4 3363.7 3397.0 3405.4 5516.4 5608.3 5622.2 5626.9 5640.7 5554.5 5607.3 5615.1 5616.6 5631.5 4958.6 4998.6 5083.5 5089.1 5123.1 4984.7 5022.2 5083.5 5088.3 5096.5 4988.5 5058.4 5064.5 5113.3 5126.3 5175.5 5186.2 5186.6 5192.6 5223.9 4928.7 4982.2 4999.7 5082.7 5101.4 5429.6 5432.3 5448.7 5494.9 5511.2 6038.3 6041.8 6058.1 6058.4 6061.3 5989.3 6018.9 6048.1 6080.6 6113.4 5641.9 5688.7 5697.5 5698.2 5700.1 5955.1 6185.3 6197.4 6205.9 6208.0 5844.2 5900.3 5904.8 5938.4 6003.4 6031.3 6045.3 6060.6 6072.0 6077.3 5809.5 5860.2 6004.7 6020.8 6023.6 5895.8 5940.3 5949.8 5962.7 5980.9 4558.5 4572.5 4584.9 4585.8 4606.7 4027.3 4034.6 4080.4 4115.6 4150.6 5740.1 5763.1 5764.8 5765.8 5792.9 6001.8 6051.8 6054.9 6064.8 6085.4 5552.6 5694.8 5719.7 5734.5 5737.3 5136.6 5145.6 5149.6 5156.7 5169.6 6328.5 6330.2 6340.1 6353.3 6356.8 5789.8 5900.8 5966.0 5985.3 6059.5 6227.1 6241.7 6254.8 6256.6 6264.2 5861.5 5895.4 5901.1 5909.4 5939.0 5877.3 5961.2 6008.5 6025.1 6072.3 5872.7 5886.9 5909.2 5914.7 5918.8 5702.8 5868.7 5891.2 5918.8 5933.3 5790.1 5798.2 5827.5 5885.6 5886.6 5968.3 6021.1 6023.2 6033.4 6034.5 5662.2 5766.5 5855.9 5869.4 5872.6 08:41:41 INFO - PROCESS | 5990 | 08:41:41 INFO - PROCESS | 5990 | [#1] ext.html Cycles:180 Average:63317.01 Median:39960.10 stddev:123303.88 (308.6%) stddev-sans-first:123559.92 08:41:41 INFO - PROCESS | 5990 | Values: 619.0 643.7 665.1 670.2 677.0 32873.8 36172.6 46045.5 46822.0 46871.5 48615.3 49022.5 49085.5 49121.0 49182.3 8299.6 8857.8 9070.0 9073.0 10617.2 10506.2 10828.4 10904.0 10924.9 10931.3 10952.8 10996.6 11005.6 11008.2 11009.7 8321.7 8541.5 10850.1 10877.5 11077.9 10472.9 10878.4 10878.9 10882.3 10885.7 45772.5 46327.0 46355.3 46382.8 46442.5 3758.5 3850.7 3896.5 4063.2 4380.8 35564.0 35693.3 35842.5 35871.6 36145.3 4653.9 4723.9 4732.6 4792.9 4793.0 360617.0 364002.0 364380.0 365316.0 365887.0 69940.7 70176.6 70821.6 70849.6 70968.6 40394.6 41201.6 41255.6 43579.0 74847.3 18862.4 18908.7 18963.7 18970.6 18978.5 13671.5 13890.6 14233.1 14293.4 14613.2 22359.7 22360.3 22529.7 22586.9 22709.9 7971.0 8238.5 8336.5 8378.6 8392.5 1091.8 1096.2 1106.5 1125.3 1169.6 33776.3 33894.3 34309.3 34394.3 34589.5 1206.4 1221.3 1246.7 1272.2 1276.9 17347.3 17617.5 17742.8 18213.9 18243.2 574340.0 580687.0 583846.0 584051.0 585241.0 60874.0 61963.4 62199.4 62366.7 62396.4 1356.9 1443.5 1468.4 1489.9 1498.3 34151.8 34555.7 35179.0 35292.8 35718.0 52991.7 53363.2 53411.0 53578.0 53761.2 419719.0 422878.0 423374.0 425574.0 425941.0 26960.7 29704.7 30815.5 30967.5 31337.5 51636.5 51949.2 52545.0 53549.7 56387.0 43890.3 44212.3 44827.6 44911.8 45694.0 42831.8 42972.6 43993.0 44737.6 45203.3 43454.3 44365.8 44443.0 45152.8 45365.0 39370.0 39911.8 40008.4 40337.4 40623.0 29997.6 30849.8 31159.2 31219.6 31756.4 08:41:41 INFO - PROCESS | 5990 | 08:41:41 INFO - PROCESS | 5990 | [#2] jquery.html Cycles:180 Average:31112.67 Median:439.58 stddev:91687.24 (20857.9%) stddev-sans-first:91915.51 08:41:41 INFO - PROCESS | 5990 | Values: 433.1 435.3 438.8 440.4 450.6 18559.8 19470.2 20178.0 20209.0 20309.3 19022.4 19982.3 20273.2 20295.9 20324.8 5696.1 5900.2 5927.7 5931.6 5931.9 5818.1 5838.3 5855.6 5857.4 5868.0 5682.9 5789.6 5797.5 5809.9 5814.9 5631.5 5792.9 5900.8 5949.9 6053.8 5576.8 5765.3 5784.3 5792.1 5858.0 14474.4 14621.2 14649.3 14721.6 18616.0 2310.5 2379.4 2511.9 2548.4 2862.5 9650.1 10503.3 10558.9 10616.4 10689.0 1943.6 2095.7 2216.5 2217.5 2219.9 60869.0 71594.0 74214.3 77091.2 78028.0 11581.3 11793.6 11884.5 11916.3 12048.7 16478.5 16698.9 16703.1 16710.7 16724.9 7346.4 8554.4 8895.2 8909.6 8923.3 8734.3 8743.0 8804.6 8813.4 8830.6 5057.2 5091.3 5117.1 5117.1 5210.6 1949.3 2071.9 2089.8 2103.2 2142.2 980.2 1043.2 1072.9 1096.4 1132.7 19434.7 19436.2 19505.5 19755.1 19763.6 1019.0 1067.9 1088.5 1122.1 1131.2 10764.2 11046.9 11070.2 11086.8 11200.4 542732.0 555885.0 556712.0 559546.0 568187.0 101374.0 101969.5 103440.0 106481.0 108159.5 984.5 1052.6 1118.3 1132.4 1141.7 18307.5 18313.3 18455.0 18608.3 18685.1 43734.6 44278.6 46212.0 46546.8 47766.0 63012.0 64029.0 64289.0 64307.4 66005.0 7778.5 8140.6 8141.6 8364.6 8924.6 9144.6 9408.7 9498.2 9530.1 9926.3 8667.6 8728.2 8800.4 8887.6 9286.3 8328.3 8393.5 8819.2 8996.7 9141.5 9323.4 9333.6 9449.1 9562.4 9753.1 13410.9 13820.7 13828.2 13854.3 13854.9 12901.7 13009.9 13074.3 13105.6 13112.6 08:41:41 INFO - PROCESS | 5990 | 08:41:41 INFO - PROCESS | 5990 | [#3] mootools.html Cycles:180 Average:6034.87 Median:4518.65 stddev:3444.72 (76.2%) stddev-sans-first:3436.59 08:41:41 INFO - PROCESS | 5990 | Values: 1375.4 1540.8 1579.1 1729.9 1898.2 8565.4 8929.5 8943.5 9282.0 9282.1 8840.8 9014.3 9025.1 9036.2 9038.6 2101.6 2390.1 2452.0 2467.1 2499.6 2441.6 2461.7 2466.4 2468.5 2470.4 2415.2 2430.4 2432.0 2433.1 2435.5 2072.5 2404.6 2477.0 2482.7 2491.2 2381.0 2386.7 2398.6 2410.9 2435.1 9126.5 9174.6 9191.1 9215.4 9283.1 5320.1 5432.9 5433.5 5483.9 5492.1 3360.8 3479.5 3548.1 3555.9 3565.0 1222.6 1232.9 1250.1 2262.2 2284.3 12855.8 12925.5 12968.4 12970.4 12976.2 4706.2 4720.2 4763.9 4793.5 4852.4 10230.2 10269.8 10307.0 10319.4 10324.2 4617.0 5439.8 5454.2 5496.6 5501.5 4410.5 4466.1 4467.4 4949.7 5092.2 4052.7 4071.7 4123.1 4133.6 4137.6 1192.1 1231.2 1233.9 1250.4 1250.8 986.3 1088.9 1090.2 1102.7 1125.7 6211.5 6262.2 6327.0 6327.5 6343.7 490.2 491.9 495.2 508.6 509.0 4336.5 4470.0 4476.8 4494.1 4543.3 11184.9 11622.4 11653.4 11692.0 11696.8 11294.2 11585.4 11645.2 11651.1 11657.1 8606.1 8711.8 8725.4 8780.0 8804.8 6288.6 6322.0 6328.8 6352.7 6463.5 10378.4 10725.1 10727.5 10803.6 10838.3 10716.3 10838.0 10852.4 11070.3 11071.9 6703.3 6918.3 6921.8 7803.1 7916.1 8205.4 8344.2 8544.0 8615.7 8877.5 7862.6 7991.2 8034.2 8114.9 8176.0 7827.1 7886.8 7959.3 7992.6 8012.7 7046.0 7139.2 7180.5 7189.1 7191.4 5467.9 5529.3 5529.3 5705.6 5767.9 5766.1 5813.9 5823.7 5846.3 5947.3 08:41:41 INFO - PROCESS | 5990 | 08:41:41 INFO - PROCESS | 5990 | [#4] prototype.html Cycles:180 Average:4848.78 Median:5546.92 stddev:1422.90 (25.7%) stddev-sans-first:1385.83 08:41:41 INFO - PROCESS | 5990 | Values: 327.3 346.6 348.6 358.0 360.8 5513.9 5527.8 5543.9 5545.3 5561.4 5192.8 5213.0 5373.5 5416.5 5448.3 4271.8 4272.1 4295.5 4310.0 4319.5 4284.9 4302.1 4315.5 4322.6 4340.6 3960.3 4060.9 4069.4 4251.0 4277.3 4182.4 4217.0 4224.6 4241.2 4242.5 3730.4 3741.6 3749.4 3766.5 3777.5 5072.7 5245.2 5247.0 5251.1 5259.6 4894.2 4904.0 4910.4 4922.5 4923.5 5618.9 5687.8 5691.9 5692.2 5708.7 4128.3 4207.8 4222.3 4225.2 4240.3 5896.9 5899.3 5901.7 5909.6 5915.6 4304.3 4306.3 4314.3 4324.9 4329.9 4049.5 4274.4 4297.9 4300.1 4397.1 4828.3 4840.4 4855.4 4868.4 4889.9 5673.6 5676.2 5693.7 5723.6 5733.9 1402.1 1419.6 1431.9 1474.6 1483.2 987.4 999.7 1007.6 1010.3 1025.6 5511.9 5513.7 5518.2 5549.6 5565.5 5760.7 5773.6 5774.9 5791.6 5829.3 5353.3 5556.4 5623.9 5624.3 5689.9 3121.8 3135.5 3136.2 3147.4 3153.4 6135.8 6141.3 6157.8 6162.0 6163.6 5676.9 6002.6 6047.2 6049.0 6069.7 6131.8 6175.4 6182.3 6194.0 6265.7 5662.6 5982.2 5994.4 5994.5 6033.5 5783.9 5821.5 5836.4 5897.5 5908.8 5876.8 6192.6 6200.3 6201.8 6212.0 4272.9 4357.2 4388.7 4391.7 4396.4 5926.2 5946.5 5952.5 5961.6 5964.9 5594.0 5651.9 5756.8 5771.3 5785.8 5709.8 5724.3 5737.0 5767.5 5770.5 5548.6 5561.2 5656.6 5692.6 5712.8 5786.2 5805.5 5821.8 5824.9 5849.8 5638.9 5642.9 5656.7 5673.6 5690.5 08:41:41 INFO - PROCESS | 5990 | 08:41:41 INFO - PROCESS | 5990 | [#5] yui.html Cycles:180 Average:21839.32 Median:3954.56 stddev:30910.40 (781.6%) stddev-sans-first:30956.65 08:41:41 INFO - PROCESS | 5990 | Values: 775.9 793.1 827.5 845.6 860.7 15217.1 16380.4 16677.1 16683.3 16702.3 14847.6 15308.8 15320.0 15325.3 15326.7 713.6 856.3 870.8 890.6 893.4 886.0 887.7 893.8 894.0 894.1 940.9 943.1 943.2 943.4 943.5 753.4 880.0 907.9 908.6 911.9 671.1 671.2 671.4 671.6 671.7 16134.6 16166.2 16186.4 16190.1 16200.5 15518.8 15553.3 15868.4 15923.0 16007.0 13881.7 14230.4 14269.2 14527.9 14657.8 3982.1 3996.1 4080.3 4117.7 4201.7 75519.0 76664.6 77268.2 77286.2 79881.6 12206.9 12288.0 12430.0 12527.2 12529.8 21979.7 21992.2 22013.8 22031.9 22036.7 8766.0 8800.4 8818.9 9034.8 9148.6 5082.6 5150.6 5180.9 5226.2 5250.5 3841.3 3912.8 3915.6 3921.8 3927.0 648.3 690.7 697.2 714.0 754.6 683.6 739.0 776.7 797.9 815.0 10058.8 10181.1 10203.6 10445.6 10650.6 666.5 761.4 796.2 812.5 824.2 5248.9 5259.4 5323.7 5394.8 5445.8 77728.6 78048.0 90054.0 118327.0 133089.3 105811.0 112395.0 113069.0 113307.4 113944.0 98290.0 98385.0 100473.0 102298.5 102534.0 9701.5 9723.7 9781.0 9971.2 10281.2 45018.0 56979.0 57071.7 57505.7 59206.0 63657.0 76282.0 77477.0 77973.0 80667.0 13790.6 13893.6 13895.2 13999.2 14318.6 16065.4 16139.4 16322.7 16363.1 16527.6 15282.8 15357.3 15556.3 15798.4 16295.2 14695.2 14859.3 15091.1 15497.3 15794.9 13845.0 14319.3 14821.9 15044.5 15365.1 10967.6 11168.6 11206.2 11352.9 11871.4 12619.6 12693.6 12751.1 12849.3 12886.8 08:41:41 INFO - PROCESS | 5990 | -------- Summary: end -------- 08:41:41 INFO - PROCESS | 5990 | 08:41:41 INFO - TEST-INFO | 5990: exit 0 08:41:41 INFO - TEST-OK | dromaeo_css | took 1114933ms 08:41:41 INFO - TEST-START | kraken 08:41:41 INFO - operating with platform_type : linux_ 08:41:41 INFO - Initialising browser for kraken test... 08:41:41 INFO - TEST-INFO | started process 6180 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeUB4Uw/profile http://localhost:60998/getInfo.html) 08:41:46 INFO - PROCESS | 6180 | __metrics Screen width/height:1600/1200 08:41:46 INFO - PROCESS | 6180 | colorDepth:24 08:41:46 INFO - PROCESS | 6180 | Browser inner width/height: 1024/697 08:41:46 INFO - PROCESS | 6180 | __metrics 08:41:47 INFO - PROCESS | 6180 | 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" 08:41:47 ERROR - PROCESS | 6180 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:41:48 INFO - TEST-INFO | 6180: exit 0 08:41:48 INFO - Browser initialized. 08:41:48 INFO - Running cycle 1/1 for kraken test... 08:41:48 INFO - TEST-INFO | started process 6275 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeUB4Uw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:42:00 INFO - PROCESS | 6275 | RSS: Main: 145580032 08:42:00 INFO - PROCESS | 6275 | 08:42:02 INFO - PROCESS | 6275 | Cycle 1(1): loaded http://localhost:60998/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60998/tests/kraken/driver.html?testName=audio-beat-detection) 08:42:03 INFO - PROCESS | 6275 | RSS: Main: 195153920 08:42:03 INFO - PROCESS | 6275 | 08:42:06 INFO - PROCESS | 6275 | Cycle 1(1): loaded http://localhost:60998/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60998/tests/kraken/driver.html?testName=audio-dft) 08:42:06 INFO - PROCESS | 6275 | RSS: Main: 191643648 08:42:06 INFO - PROCESS | 6275 | 08:42:09 INFO - PROCESS | 6275 | Cycle 1(1): loaded http://localhost:60998/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60998/tests/kraken/driver.html?testName=audio-fft) 08:42:10 INFO - PROCESS | 6275 | RSS: Main: 278794240 08:42:10 INFO - PROCESS | 6275 | 08:42:12 INFO - PROCESS | 6275 | Cycle 1(1): loaded http://localhost:60998/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60998/tests/kraken/driver.html?testName=audio-oscillator) 08:42:12 INFO - PROCESS | 6275 | RSS: Main: 187457536 08:42:12 INFO - PROCESS | 6275 | 08:42:14 INFO - PROCESS | 6275 | Cycle 1(1): loaded http://localhost:60998/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60998/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:42:15 INFO - PROCESS | 6275 | RSS: Main: 187969536 08:42:15 INFO - PROCESS | 6275 | 08:42:18 INFO - PROCESS | 6275 | Cycle 1(1): loaded http://localhost:60998/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60998/tests/kraken/driver.html?testName=imaging-darkroom) 08:42:19 INFO - PROCESS | 6275 | RSS: Main: 223375360 08:42:19 INFO - PROCESS | 6275 | 08:42:22 INFO - PROCESS | 6275 | Cycle 1(1): loaded http://localhost:60998/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60998/tests/kraken/driver.html?testName=imaging-desaturate) 08:42:23 INFO - PROCESS | 6275 | RSS: Main: 224022528 08:42:23 INFO - PROCESS | 6275 | 08:42:25 INFO - PROCESS | 6275 | Cycle 1(1): loaded http://localhost:60998/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60998/tests/kraken/driver.html?testName=json-parse-financial) 08:42:26 INFO - PROCESS | 6275 | RSS: Main: 223625216 08:42:26 INFO - PROCESS | 6275 | 08:42:27 INFO - PROCESS | 6275 | Cycle 1(1): loaded http://localhost:60998/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60998/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:42:28 INFO - PROCESS | 6275 | RSS: Main: 201150464 08:42:28 INFO - PROCESS | 6275 | 08:42:30 INFO - PROCESS | 6275 | Cycle 1(1): loaded http://localhost:60998/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60998/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:42:30 INFO - PROCESS | 6275 | RSS: Main: 202289152 08:42:30 INFO - PROCESS | 6275 | 08:42:33 INFO - PROCESS | 6275 | Cycle 1(1): loaded http://localhost:60998/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60998/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:42:33 INFO - PROCESS | 6275 | RSS: Main: 210362368 08:42:33 INFO - PROCESS | 6275 | 08:42:36 INFO - PROCESS | 6275 | Cycle 1(1): loaded http://localhost:60998/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60998/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:42:36 INFO - PROCESS | 6275 | RSS: Main: 214011904 08:42:36 INFO - PROCESS | 6275 | 08:42:39 INFO - PROCESS | 6275 | Cycle 1(1): loaded http://localhost:60998/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60998/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:42:40 INFO - PROCESS | 6275 | RSS: Main: 213504000 08:42:40 INFO - PROCESS | 6275 | 08:42:41 INFO - PROCESS | 6275 | Cycle 1(1): loaded http://localhost:60998/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60998/tests/kraken/driver.html?testName=ai-astar) 08:42:41 INFO - PROCESS | 6275 | RSS: Main: 255746048 08:42:41 INFO - PROCESS | 6275 | 08:42:41 INFO - PROCESS | 6275 | __start_tp_report 08:42:41 INFO - PROCESS | 6275 | _x_x_mozilla_page_load 08:42:41 INFO - PROCESS | 6275 | _x_x_mozilla_page_load_details 08:42:41 INFO - PROCESS | 6275 | |i|pagename|runs| 08:42:41 INFO - PROCESS | 6275 | |0;ai-astar;111;106;110;108;110;109;109;109;109;111 08:42:41 INFO - PROCESS | 6275 | |1;audio-beat-detection;151;129;135;174;124;125;153;130;183;123 08:42:41 INFO - PROCESS | 6275 | |2;audio-dft;160;160;160;161;162;160;158;161;160;159 08:42:41 INFO - PROCESS | 6275 | |3;audio-fft;73;74;75;104;76;77;75;75;97;75 08:42:41 INFO - PROCESS | 6275 | |4;audio-oscillator;84;84;116;83;84;85;119;84;84;121 08:42:41 INFO - PROCESS | 6275 | |5;imaging-gaussian-blur;117;116;117;117;117;116;117;116;117;117 08:42:41 INFO - PROCESS | 6275 | |6;imaging-darkroom;146;147;145;146;146;146;146;145;145;145 08:42:41 INFO - PROCESS | 6275 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 08:42:41 INFO - PROCESS | 6275 | |8;json-parse-financial;49;49;51;52;51;51;51;50;52;50 08:42:41 INFO - PROCESS | 6275 | |9;json-stringify-tinderbox;59;61;61;60;60;61;59;60;61;60 08:42:41 INFO - PROCESS | 6275 | |10;stanford-crypto-aes;76;76;76;75;82;80;76;76;108;78 08:42:41 INFO - PROCESS | 6275 | |11;stanford-crypto-ccm;132;132;126;116;117;117;122;118;127;118 08:42:41 INFO - PROCESS | 6275 | |12;stanford-crypto-pbkdf2;158;152;154;155;162;157;159;155;156;162 08:42:41 INFO - PROCESS | 6275 | |13;stanford-crypto-sha256-iterative;62;62;62;60;63;60;61;64;59;59 08:42:41 INFO - PROCESS | 6275 | __end_tp_report 08:42:41 INFO - PROCESS | 6275 | __start_cc_report 08:42:41 INFO - PROCESS | 6275 | _x_x_mozilla_cycle_collect,1613 08:42:41 INFO - PROCESS | 6275 | __end_cc_report 08:42:41 INFO - PROCESS | 6275 | __startTimestamp1469288561957__endTimestamp 08:42:41 INFO - PROCESS | 6275 | 08:42:41 INFO - PROCESS | 6275 | ------- Summary: start ------- 08:42:41 INFO - PROCESS | 6275 | Number of tests: 14 08:42:41 INFO - PROCESS | 6275 | 08:42:41 INFO - PROCESS | 6275 | [#0] ai-astar Cycles:10 Average:109.20 Median:109.50 stddev:1.48 (1.3%) stddev-sans-first:1.41 08:42:41 INFO - PROCESS | 6275 | Values: 111.0 106.0 110.0 108.0 110.0 109.0 109.0 109.0 109.0 111.0 08:42:41 INFO - PROCESS | 6275 | 08:42:41 INFO - PROCESS | 6275 | [#1] audio-beat-detection Cycles:10 Average:142.70 Median:143.00 stddev:21.70 (15.2%) stddev-sans-first:22.81 08:42:41 INFO - PROCESS | 6275 | Values: 151.0 129.0 135.0 174.0 124.0 125.0 153.0 130.0 183.0 123.0 08:42:41 INFO - PROCESS | 6275 | 08:42:41 INFO - PROCESS | 6275 | [#2] audio-dft Cycles:10 Average:160.10 Median:160.00 stddev:1.10 (0.7%) stddev-sans-first:1.17 08:42:41 INFO - PROCESS | 6275 | Values: 160.0 160.0 160.0 161.0 162.0 160.0 158.0 161.0 160.0 159.0 08:42:41 INFO - PROCESS | 6275 | 08:42:41 INFO - PROCESS | 6275 | [#3] audio-fft Cycles:10 Average:80.10 Median:75.00 stddev:10.93 (14.6%) stddev-sans-first:11.29 08:42:41 INFO - PROCESS | 6275 | Values: 73.0 74.0 75.0 104.0 76.0 77.0 75.0 75.0 97.0 75.0 08:42:41 INFO - PROCESS | 6275 | 08:42:41 INFO - PROCESS | 6275 | [#4] audio-oscillator Cycles:10 Average:94.40 Median:84.00 stddev:16.79 (20.0%) stddev-sans-first:17.39 08:42:41 INFO - PROCESS | 6275 | Values: 84.0 84.0 116.0 83.0 84.0 85.0 119.0 84.0 84.0 121.0 08:42:41 INFO - PROCESS | 6275 | 08:42:41 INFO - PROCESS | 6275 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 08:42:41 INFO - PROCESS | 6275 | Values: 117.0 116.0 117.0 117.0 117.0 116.0 117.0 116.0 117.0 117.0 08:42:41 INFO - PROCESS | 6275 | 08:42:41 INFO - PROCESS | 6275 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 08:42:41 INFO - PROCESS | 6275 | Values: 146.0 147.0 145.0 146.0 146.0 146.0 146.0 145.0 145.0 145.0 08:42:41 INFO - PROCESS | 6275 | 08:42:41 INFO - PROCESS | 6275 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 08:42:41 INFO - PROCESS | 6275 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 08:42:41 INFO - PROCESS | 6275 | 08:42:41 INFO - PROCESS | 6275 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:51.00 stddev:1.07 (2.1%) stddev-sans-first:0.97 08:42:41 INFO - PROCESS | 6275 | Values: 49.0 49.0 51.0 52.0 51.0 51.0 51.0 50.0 52.0 50.0 08:42:41 INFO - PROCESS | 6275 | 08:42:41 INFO - PROCESS | 6275 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:60.50 stddev:0.79 (1.3%) stddev-sans-first:0.71 08:42:41 INFO - PROCESS | 6275 | Values: 59.0 61.0 61.0 60.0 60.0 61.0 59.0 60.0 61.0 60.0 08:42:41 INFO - PROCESS | 6275 | 08:42:41 INFO - PROCESS | 6275 | [#10] stanford-crypto-aes Cycles:10 Average:80.30 Median:76.00 stddev:9.98 (13.1%) stddev-sans-first:10.46 08:42:41 INFO - PROCESS | 6275 | Values: 76.0 76.0 76.0 75.0 82.0 80.0 76.0 76.0 108.0 78.0 08:42:41 INFO - PROCESS | 6275 | 08:42:41 INFO - PROCESS | 6275 | [#11] stanford-crypto-ccm Cycles:10 Average:122.50 Median:124.00 stddev:6.29 (5.1%) stddev-sans-first:5.66 08:42:41 INFO - PROCESS | 6275 | Values: 132.0 132.0 126.0 116.0 117.0 117.0 122.0 118.0 127.0 118.0 08:42:41 INFO - PROCESS | 6275 | 08:42:41 INFO - PROCESS | 6275 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.00 Median:157.50 stddev:3.30 (2.1%) stddev-sans-first:3.48 08:42:41 INFO - PROCESS | 6275 | Values: 158.0 152.0 154.0 155.0 162.0 157.0 159.0 155.0 156.0 162.0 08:42:41 INFO - PROCESS | 6275 | 08:42:41 INFO - PROCESS | 6275 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.20 Median:62.00 stddev:1.69 (2.7%) stddev-sans-first:1.76 08:42:41 INFO - PROCESS | 6275 | Values: 62.0 62.0 62.0 60.0 63.0 60.0 61.0 64.0 59.0 59.0 08:42:41 INFO - PROCESS | 6275 | -------- Summary: end -------- 08:42:41 INFO - PROCESS | 6275 | 08:42:42 INFO - TEST-INFO | 6275: exit 0 08:42:42 INFO - TEST-OK | kraken | took 61090ms 08:42:42 INFO - SUITE-END | took 1176s 08:42:43 INFO - Completed test suite (00:19:36) 08:42:43 INFO - javascript benchmark 08:42:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3345.514900339451, 3357.429237029705, 3363.7309015278774, 3397.048826328629, 3405.3529829332424, 5516.448355164482, 5608.270924779778, 5622.156676498517, 5626.859328516789, 5640.661560306385, 5554.50009499145, 5607.327120745516, 5615.101583746601, 5616.550675945925, 5631.475749221163, 4958.578520825734, 4998.5501304882555, 5083.491650834914, 5089.083964886319, 5123.129068058421, 4984.700917944935, 5022.221555658861, 5083.517065878754, 5088.313077734514, 5096.4648711885275, 4988.526090021461, 5058.443571207183, 5064.544191022807, 5113.284140220372, 5126.282320475137, 5175.482451754832, 5186.19613959835, 5186.6369354144845, 5192.55863251624, 5223.947760522384, 4928.704277743311, 4982.153033984231, 4999.725015124167, 5082.720450375228, 5101.362329708786, 5429.56563474921, 5432.266644003088, 5448.700321482317, 5494.890102197934, 5511.22842802008, 6038.33578306386, 6041.8187454376, 6058.0610005449225, 6058.363871793486, 6061.272647282311, 5989.251343582052, 6018.939810601925, 6048.092786082079, 6080.61535387841, 6113.358097399753, 5641.943580564182, 5688.658680479184, 5697.4587414195585, 5698.17376480408, 5700.059490184156, 5955.076963070731, 6185.288691800501, 6197.442230199214, 6205.875882482325, 6208.037754148114, 5844.240248767718, 5900.291964964232, 5904.76380944766, 5938.435848594377, 6003.369646187088, 6031.306399763996, 6045.274567051982, 6060.575759696779, 6072.0, 6077.270727512727, 5809.535237181015, 5860.208871802337, 6004.699765011732, 6020.789272375533, 6023.578349515492, 5895.793647222326, 5940.287165540163, 5949.791757288474, 5962.701864906738, 5980.910286345708, 4558.475775285885, 4572.542745725401, 4584.885377865513, 4585.770711464414, 4606.746628935441, 4027.275090483741, 4034.616711412411, 4080.367543030835, 4115.58844115592, 4150.584941505886, 5740.1102829061565, 5763.106718458645, 5764.827055188351, 5765.769369225184, 5792.855178620568, 6001.849953751192, 6051.788187413419, 6054.878902422015, 6064.848378790565, 6085.36103709113, 5552.611317207757, 5694.829155125353, 5719.742611582489, 5734.541236701054, 5737.254156959569, 5136.5890728741615, 5145.614078944094, 5149.613778966593, 5156.74216289184, 5169.560587350117, 6328.493720502349, 6330.177076979963, 6340.112384266206, 6353.332900045429, 6356.8410789729705, 5789.797357092481, 5900.793472228452, 5966.0, 5985.25184351956, 6059.515238780975, 6227.097070924781, 6241.656708880873, 6254.843628909313, 6256.593321434092, 6264.154339164154, 5861.472467477947, 5895.4399332063385, 5901.0558310670085, 5909.409059094056, 5939.0, 5877.265341832271, 5961.195238642727, 6008.489278411384, 6025.066114752045, 6072.301685306246, 5872.735726892215, 5886.911696324644, 5909.202257695328, 5914.704264786829, 5918.763249469973, 5702.800401985911, 5868.677222752619, 5891.234139562001, 5918.763249469973, 5933.2880054393745, 5790.10253410722, 5798.188253644409, 5827.504662103599, 5885.558583106199, 5886.6173698709445, 5968.313643931004, 6021.126936594257, 6023.1567580537885, 6033.366496517802, 6034.487068599043, 5662.150677398258, 5766.481016708516, 5855.94144058554, 5869.442402971711, 5872.647641141545], "value": 5545.74534784942, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [618.9504839612831, 643.7392855893363, 665.1452883045288, 670.1756839087918, 677.0217036382427, 32873.83563082184, 36172.63827361725, 46045.53954460459, 46822.0, 46871.531284687146, 48615.27077093846, 49022.50977490224, 49085.50914490854, 49121.0, 49182.26226606595, 8299.58502074897, 8857.822843543108, 9070.04764499728, 9073.047330030351, 10617.150627949784, 10506.212034097434, 10828.40443775592, 10904.0, 10924.890751092467, 10931.344119352865, 10952.835707464394, 10996.560137594486, 11005.559777608885, 11008.174386921013, 11009.724756881062, 8321.66713331469, 8541.487510749375, 10850.077743391821, 10877.456127193649, 11077.889221107765, 10472.947635261851, 10878.401687907162, 10878.891211087866, 10882.347059176474, 10885.727856803564, 45772.54227457716, 46327.0, 46355.30467042997, 46382.76808615969, 46442.53557464416, 3758.4550240215017, 3850.6534411902785, 3896.4544963705102, 4063.207674503473, 4380.759058251795, 35564.0, 35693.28613427743, 35842.46236306457, 35871.641283587094, 36145.27709445823, 4653.926131300122, 4723.881902952454, 4732.645051621143, 4792.904141917177, 4792.9760351198365, 360617.0, 364002.0, 364380.0, 365316.0, 365887.0, 69940.6502967482, 70176.6491167541, 70821.64589177021, 70849.6457517709, 70968.64515677388, 40394.59605403908, 41201.58798412037, 41255.58744412578, 43579.0, 74847.25152748512, 18862.43412697621, 18908.71636925447, 18963.715544266848, 18970.62058758817, 18978.52553686169, 13671.521496747766, 13890.58328250154, 14233.146011239358, 14293.356798944073, 14613.196274204809, 22359.66460503094, 22360.322975776882, 22529.662055069188, 22586.887065564566, 22709.886450567643, 7971.0, 8238.464499807493, 8336.499430102518, 8378.622961966727, 8392.496450213006, 1091.792559413709, 1096.1614365010773, 1106.4744246482855, 1125.2910666280354, 1169.5555688838188, 33776.324473510394, 33894.32211355759, 34309.31381372359, 34394.31211375759, 34589.481157782655, 1206.3907726598188, 1221.3404761428906, 1246.7132559511363, 1272.2088177231901, 1276.9489220431078, 17347.3061077558, 17617.471475855746, 17742.822571774374, 18213.90893045526, 18243.17905694247, 574340.0, 580687.0, 583846.0, 584051.0, 585241.0, 60874.0, 61963.38036619666, 62199.378006220264, 62366.68816655887, 62396.37603623996, 1356.9050166488448, 1443.487561915526, 1468.4273133477739, 1489.858463445971, 1498.2658497336445, 34151.829240853636, 34555.65444345625, 35179.0, 35292.823535882155, 35718.0, 52991.735041324544, 53363.19955200597, 53411.0, 53578.0, 53761.19358209552, 419719.0, 422878.0, 423374.0, 425574.0, 425941.0, 26960.730392696605, 29704.70295297106, 30815.537766933066, 30967.535486967263, 31337.529937050505, 51636.48363516317, 51949.22076168785, 52545.0, 53549.73225133849, 56387.0, 43890.34164487599, 44212.2787516889, 44827.551724482335, 44911.77544112258, 45694.0, 42831.785841070596, 42972.57027429811, 43993.0, 44737.55262447464, 45203.32195017012, 43454.34818477789, 44365.77817110894, 44443.0, 45152.77423612861, 45365.0, 39370.0, 39911.800440997606, 40008.3998740025, 40337.39493907535, 40623.0, 29997.550036749904, 30849.75554155754, 31159.221019474695, 31219.59511822019, 31756.364872701954], "value": 21917.57776625791, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [433.13051036880665, 435.3208993969408, 438.8025388575141, 440.3570786651489, 450.61022215783356, 18559.814401855976, 19470.221191152355, 20178.0, 20209.0, 20309.289174878882, 19022.429327120175, 19982.300619478323, 20273.189072437122, 20295.898520507413, 20324.796752032475, 5696.08862581987, 5900.203472531196, 5927.703614819264, 5931.6441013539325, 5931.851703707399, 5818.06910894257, 5838.2702162229725, 5855.590108692372, 5857.3849745776715, 5868.0, 5682.886342273152, 5789.623674461168, 5797.536197104221, 5809.941900581003, 5814.941850581504, 5631.521320687746, 5792.884142317151, 5900.822975310769, 5949.8810023799715, 6053.818385448443, 5576.832695019156, 5765.336986246593, 5784.334801497839, 5792.131180322943, 5858.033424484997, 14474.42102315906, 14621.195834229113, 14649.340779664833, 14721.631959200999, 18616.0, 2310.4801419680607, 2379.4170428245016, 2511.949761004788, 2548.4011257354505, 2862.5276829323343, 9650.08324209199, 10503.264771466, 10558.894411055868, 10616.363018218932, 10689.0, 1943.6112777444569, 2095.7170781944396, 2216.5345277491747, 2217.5010622609784, 2219.9223027194134, 60869.0, 71593.99999999948, 74214.25785742127, 77091.22908770952, 78028.0, 11581.305121692725, 11793.646190614296, 11884.465199066062, 11916.285022898654, 12048.698782530331, 16478.505644830675, 16698.916505417394, 16703.081330526937, 16710.749338759928, 16724.91637541829, 7346.412287017026, 8554.443961142506, 8895.24390426808, 8909.643614255487, 8923.286137109015, 8734.344924130588, 8743.038265790885, 8804.559772011375, 8813.383063185644, 8830.602622881986, 5057.216131499624, 5091.261767043758, 5117.078925793318, 5117.130087885069, 5210.583153347723, 1949.2885096939515, 2071.927482538104, 2089.8026020189136, 2103.1797598936428, 2142.1966762464076, 980.2256217588168, 1043.1654676258963, 1072.8766191887876, 1096.3860238266595, 1132.660201939415, 19434.70847937282, 19436.22255109808, 19505.512362190773, 19755.111020004133, 19763.640181168947, 1019.0, 1067.8886287791938, 1088.50473034769, 1122.0967121467297, 1131.1516362727955, 10764.192685548614, 11046.889531104585, 11070.225084244186, 11086.833697494543, 11200.383978881076, 542732.0, 555885.0, 556712.0, 559546.0, 568187.0, 101374.0, 101969.49015254877, 103440.0, 106481.0, 108159.45920270348, 984.5274268351232, 1052.6157952347337, 1118.2954738514845, 1132.4281237974935, 1141.7145713571608, 18307.54231144232, 18313.267469301078, 18455.0, 18608.34870779516, 18685.06574671234, 43734.56265437305, 44278.55721442744, 46212.0, 46546.836329090685, 47766.04467910692, 63012.0, 64029.0395644075, 64289.0, 64307.35692643201, 66005.0, 7778.533288002512, 8140.633671484916, 8141.552214628252, 8364.581770911553, 8924.643014279356, 9144.63421463134, 9408.670696525589, 9498.19265362452, 9530.142287194185, 9926.255530835071, 8667.609957552051, 8728.170823771732, 8800.427972181787, 8887.600057997277, 9286.257099432165, 8328.333733301442, 8393.531132051989, 8819.162179592928, 8996.685116020908, 9141.497217653094, 9323.440593564408, 9333.57998890038, 9449.102335278138, 9562.426254424758, 9753.122219000325, 13410.9329453356, 13820.723585528434, 13828.239446830521, 13854.30728463593, 13854.930725346308, 12901.677458063623, 13009.869901301245, 13074.28091454979, 13105.606831795061, 13112.606621801362], "value": 9384.758339602937, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1375.3948262764388, 1540.8151021877372, 1579.1078040906887, 1729.8529624981875, 1898.2407037185123, 8565.443246188994, 8929.464232146067, 8943.463392196472, 9282.025387334334, 9282.118198771106, 8840.77898052549, 9014.278857691384, 9025.097490250972, 9036.231920286784, 9038.59326330315, 2101.5901899129676, 2390.055927908475, 2451.9509609807837, 2467.111839737693, 2499.600063989762, 2441.5849305618, 2461.692288463942, 2466.3834041489627, 2468.4816188600416, 2470.357706996176, 2415.2271273192587, 2430.368104292888, 2432.0393444589376, 2433.124075332879, 2435.512897420523, 2072.5440403111293, 2404.6152615581423, 2477.046337160188, 2482.689663792026, 2491.190363131989, 2380.9880950595134, 2386.7135943686694, 2398.616221404567, 2410.9035638574437, 2435.135526887947, 9126.543672816333, 9174.633014679404, 9191.080891910822, 9215.400998935047, 9283.11810378013, 5320.14877619579, 5432.891342173174, 5433.510984011458, 5483.917741233885, 5492.148716948879, 3360.8319584020946, 3479.5128681984543, 3548.1484443733575, 3555.8577656893694, 3565.0196196046036, 1222.6148763139672, 1232.9075319350995, 1250.1249125612107, 2262.2082271205113, 2284.348960546253, 12855.807162892565, 12925.482980680761, 12968.416421260972, 12970.416331265116, 12976.221426714334, 4706.1999460091865, 4720.197566413649, 4763.904721905543, 4793.520647935248, 4852.44196917359, 10230.23273254509, 10269.794604107876, 10307.020833020852, 10319.432431216355, 10324.174066074696, 4617.0, 5439.836804895859, 5454.154606036071, 5496.587755918322, 5501.477359650827, 4410.4927933287445, 4466.062126953311, 4467.419235499365, 4949.653524253267, 5092.159793634083, 4052.708638360176, 4071.7353372030725, 4123.134141830249, 4133.55325636025, 4137.586241375839, 1192.1297452859462, 1231.1874163052341, 1233.858106317767, 1250.4310538704867, 1250.8248845161684, 986.2947992185622, 1088.874779400379, 1090.1987039525943, 1102.7132945434084, 1125.6960620632547, 6211.472024877936, 6262.1546091278005, 6326.9683651581445, 6327.462165715873, 6343.651099189497, 490.20341944340447, 491.91391506486224, 495.2299174783175, 508.55501436243287, 508.96437249392187, 4336.489366975686, 4469.9553004469535, 4476.798544065525, 4494.056248187917, 4543.250363689954, 11184.94407527957, 11622.418879056013, 11653.41732913351, 11692.0, 11696.766064678659, 11294.152938529644, 11585.420728963687, 11645.243059201124, 11651.067914566815, 11657.125715571367, 8606.13938606147, 8711.825763484821, 8725.389222754473, 8780.0, 8804.823903521894, 6288.591241569284, 6321.96839015802, 6328.778492752732, 6352.714127864349, 6463.515236357198, 10378.42918639482, 10725.08836748885, 10727.463626818471, 10803.567857285934, 10838.349699018085, 10716.303440276357, 10838.0, 10852.403117828595, 11070.335779853234, 11071.944640276746, 6703.3296670332575, 6918.273581274095, 6921.792346229419, 7803.141654417896, 7916.08964969036, 8205.384596155192, 8344.207300306462, 8543.957280213559, 8615.6984505542, 8877.511736854534, 7862.606869656609, 7991.24083212094, 8034.156413576724, 8114.945057142534, 8175.959120204361, 7827.139014708264, 7886.763397098096, 7959.323457506177, 7992.6403311849945, 8012.679492820222, 7045.964770176116, 7139.178994415501, 7180.461465390013, 7189.1013623297085, 7191.388731957633, 5467.9453205467435, 5529.30883639545, 5529.336479622471, 5705.600607957404, 5767.913481297868, 5766.077427611562, 5813.854653633523, 5823.650580965155, 5846.269216347957, 5947.256592925884], "value": 4784.642159687236, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.30774412118376, 346.59448445318975, 348.59737003760665, 358.03688079067314, 360.81778701755616, 5513.857101452174, 5527.778888844451, 5543.946650136471, 5545.251391062206, 5561.416051314614, 5192.792288308473, 5213.0, 5373.462653734625, 5416.485433883775, 5448.291722076135, 4271.765052922088, 4272.060146767706, 4295.463067116611, 4309.956900430987, 4319.50325712544, 4284.885929658296, 4302.053548219386, 4315.525292217853, 4322.616762635942, 4340.631046361063, 3960.2871483133026, 4060.8984775380554, 4069.4302797608357, 4251.0, 4277.3156294992805, 4182.414461975326, 4216.978915105436, 4224.598663127029, 4241.151769646083, 4242.490901091874, 3730.403135498307, 3741.5510138783247, 3749.4375843623675, 3766.5291838520184, 3777.527809023872, 5072.746362681851, 5245.213218017289, 5247.029299579552, 5251.08106081438, 5259.631825772198, 4894.192458244385, 4903.975480122613, 4910.386201724785, 4922.507749225048, 4923.532264434873, 5618.943810561883, 5687.8339940312135, 5691.886162276731, 5692.203091567184, 5708.686022268774, 4128.348660535809, 4207.779743874273, 4222.324428091521, 4225.19721252961, 4240.279152544075, 5896.941030589725, 5899.262592175978, 5901.704914754288, 5909.6158749681135, 5915.645061296293, 4304.289792184254, 4306.267934451151, 4314.331278651751, 4324.891877703019, 4329.935050974238, 4049.493813273341, 4274.444322238089, 4297.935530967038, 4300.0969796343115, 4397.098594788061, 4828.275758636232, 4840.419149702058, 4855.393075865517, 4868.391451068616, 4889.902201955941, 5673.574481913873, 5676.2337084493065, 5693.658380497101, 5723.627964182315, 5733.942660573341, 1402.0956483068364, 1419.5599364196926, 1431.8997670163196, 1474.5944865161953, 1483.1916605450056, 987.3779518903045, 999.6607937426458, 1007.5893748751806, 1010.2724341376182, 1025.620520407454, 5511.87006663633, 5513.669179849222, 5518.24087956022, 5549.611527193058, 5565.526930210896, 5760.654360738369, 5773.566982476247, 5774.873899589624, 5791.594588378857, 5829.300483941869, 5353.304070470814, 5556.388797232301, 5623.887522249532, 5624.272227969432, 5689.9146512802345, 3121.798107728541, 3135.498320268746, 3136.2002689314077, 3147.4420162019946, 3153.369326134782, 6135.754569817157, 6141.293751218578, 6157.84605384869, 6162.0, 6163.630182189083, 5676.858078548069, 6002.579819412599, 6047.183630209865, 6049.0, 6069.666168360694, 6131.84670383244, 6175.3824617538785, 6182.258129024456, 6193.969030154911, 6265.749370025148, 5662.603617746719, 5982.162497250475, 5994.400559943971, 5994.460498555151, 6033.456988871023, 5783.94216057834, 5821.4573138117585, 5836.416358364129, 5897.469227769521, 5908.793192238251, 5876.794312199053, 6192.59748116389, 6200.348963358782, 6201.81394558164, 6211.9689401552705, 4272.850450234227, 4357.150355680738, 4388.670849686222, 4391.714538554984, 4396.428464299621, 5926.2295901532525, 5946.494547963299, 5952.464278214981, 5961.612495187799, 5964.850878728066, 5593.972030139823, 5651.858703532444, 5756.769729210785, 5771.278590176228, 5785.768569257351, 5709.8287051388525, 5724.341700704473, 5736.971315143398, 5767.480926716616, 5770.480656740746, 5548.583856210719, 5561.249231353877, 5656.5757568181725, 5692.601517893708, 5712.771489140554, 5786.218860453785, 5805.506531944832, 5821.825345239649, 5824.912626310524, 5849.824505264849, 5638.85902852432, 5642.915356269577, 5656.717164141859, 5673.631213971079, 5690.5163061140265], "value": 4397.0232112452695, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [775.8991331126953, 793.1473665809253, 827.5283088639478, 845.602566793607, 860.7159637319685, 15217.086974781521, 16380.42668506601, 16677.08276044817, 16683.332666693346, 16702.331906723746, 14847.628809279773, 15308.770368444455, 15320.0, 15325.310361033753, 15326.693466130699, 713.6289129652578, 856.3363393370125, 870.78665726897, 890.6214858685065, 893.4371346051978, 886.0165216609558, 887.6538150121457, 893.7631527645179, 894.0121166111451, 894.1058941058941, 940.9482478463682, 943.127606963556, 943.1558754914347, 943.3820847345, 943.485800238868, 753.4386881773087, 879.9912000880005, 907.850204716221, 908.6456282049973, 911.8541033434618, 671.0940230688581, 671.228087699145, 671.4024518178825, 671.5634837355709, 671.6977360187886, 16134.596635084099, 16166.191690415431, 16186.35254589815, 16190.109543975077, 16200.513984580364, 15518.844811551853, 15553.300101495346, 15868.444604438904, 15923.0, 16007.039577625379, 13881.722365552632, 14230.359633816503, 14269.215193164371, 14527.85472145286, 14657.780133298009, 3982.0841206522373, 3996.0609256824914, 4080.2655522006025, 4117.691173161995, 4201.7268877522865, 75519.0, 76664.61667691682, 77268.22731772666, 77286.22713772846, 79881.60059199667, 12206.877931220573, 12288.0, 12430.0, 12527.248365097941, 12529.812052819216, 21979.670304945437, 21992.230271940407, 22013.779862201493, 22031.889840550695, 22036.669449958263, 8766.0, 8800.383973121949, 8818.91181088181, 9034.774130646787, 9148.588313525774, 5082.567981721521, 5150.639455238097, 5180.922286165635, 5226.242194881721, 5250.5012023858435, 3841.2509560635967, 3912.7847968361443, 3915.647591716759, 3921.784301863425, 3927.0378757204667, 648.3225029843825, 690.6719308328604, 697.2191145916504, 713.9535930164523, 754.5887491317245, 683.6205905722272, 739.0097269658684, 776.7203806629609, 797.924197201265, 814.9877751833727, 10058.849117263248, 10181.083702466814, 10203.591856325664, 10445.582176712998, 10650.57397704083, 666.5434177588368, 761.4251240313528, 796.1600511460465, 812.5287333346645, 824.2375802382772, 5248.895022099537, 5259.421463638997, 5323.707196104174, 5394.811181608625, 5445.754941027663, 77728.61135694286, 78048.0, 90054.0, 118327.0, 133089.33455332855, 105811.0, 112395.0, 113069.0, 113307.43346283215, 113944.0, 98290.0, 98385.0, 100473.0, 102298.48850755848, 102534.0, 9701.514924253901, 9723.65967191145, 9781.04012183845, 9971.219570692, 10281.177505799667, 45018.0, 56979.0, 57071.71464142653, 57505.71247143905, 59206.0, 63657.0, 76282.0, 77477.0, 77973.0, 80667.0, 13790.586282411543, 13893.583192503836, 13895.235762033186, 13999.230042347768, 14318.642033948818, 16065.357385704909, 16139.380147055233, 16322.67354652924, 16363.100029498491, 16527.586810329838, 15282.847171528143, 15357.3089210988, 15556.299966501761, 15798.36806527726, 16295.185240738152, 14695.191764453059, 14859.257037147883, 15091.094534327976, 15497.30262138229, 15794.9210253948, 13845.030847840555, 14319.284035798377, 14821.92589037048, 15044.473443429428, 15365.078095314317, 10967.561297548327, 11168.609098681507, 11206.215564910379, 11352.886471135183, 11871.406429678655, 12619.645848289167, 12693.619191424272, 12751.107422480338, 12849.293288868828, 12886.806697899352], "value": 8128.960478907327, "unit": "score"}], "name": "dromaeo_css", "value": 7615.714725253998, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 106.0, 110.0, 108.0, 110.0, 109.0, 109.0, 109.0, 109.0, 111.0], "value": 109.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 129.0, 135.0, 174.0, 124.0, 125.0, 153.0, 130.0, 183.0, 123.0], "value": 142.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 161.0, 162.0, 160.0, 158.0, 161.0, 160.0, 159.0], "value": 160.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 75.0, 104.0, 76.0, 77.0, 75.0, 75.0, 97.0, 75.0], "value": 80.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 116.0, 83.0, 84.0, 85.0, 119.0, 84.0, 84.0, 121.0], "value": 94.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 145.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 51.0, 52.0, 51.0, 51.0, 51.0, 50.0, 52.0, 50.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 61.0, 60.0, 60.0, 61.0, 59.0, 60.0, 61.0, 60.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 75.0, 82.0, 80.0, 76.0, 76.0, 108.0, 78.0], "value": 80.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 132.0, 126.0, 116.0, 117.0, 117.0, 122.0, 118.0, 127.0, 118.0], "value": 122.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 152.0, 154.0, 155.0, 162.0, 157.0, 159.0, 155.0, 156.0, 162.0], "value": 157.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 60.0, 63.0, 60.0, 61.0, 64.0, 59.0, 59.0], "value": 61.2, "unit": "score"}], "name": "kraken", "value": 1475.7, "alertThreshold": 2.0}]} 08:42:43 INFO - Return code: 0 08:42:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:42:43 ERROR - # TBPL SUCCESS # 08:42:43 INFO - Running post-action listener: _resource_record_post_action 08:42:43 INFO - ##### 08:42:43 INFO - ##### Finished run-tests step (success) 08:42:43 INFO - ##### 08:42:43 INFO - Running post-run listener: _resource_record_post_run 08:42:43 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 504229888; Read time: 20; Write time: 1202492 08:42:43 INFO - TinderboxPrint: CPU usage
13.1% 08:42:43 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 08:42:43 INFO - TinderboxPrint: I/O write bytes / time
504,229,888 / 1,202,492 08:42:43 INFO - TinderboxPrint: CPU idle
8,269.7 (86.8%) 08:42:43 INFO - TinderboxPrint: CPU user
1,194.6 (12.5%) 08:42:43 INFO - TinderboxPrint: Swap in / out
0 / 0 08:42:43 INFO - install - Wall time: 16s; CPU: 20.0%; Read bytes: 0; Write bytes: 252628992; Read time: 0; Write time: 870364 08:42:43 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 4096; Write bytes: 251600896; Read time: 20; Write time: 332128 08:42:43 INFO - Running post-run listener: _upload_blobber_files 08:42:43 INFO - Blob upload gear active. 08:42:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:42:43 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:42:43 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'] 08:42:43 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 08:42:44 INFO - (blobuploader) - INFO - Open directory for files ... 08:42:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:42:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:42:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:42:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:42:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:42:56 INFO - (blobuploader) - INFO - Done attempting. 08:42:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:42:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:42:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:42:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:42:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:42:57 INFO - (blobuploader) - INFO - Done attempting. 08:42:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:42:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:42:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:42:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:42:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:42:58 INFO - (blobuploader) - INFO - Done attempting. 08:42:58 INFO - (blobuploader) - INFO - Iteration through files over. 08:42:58 INFO - Return code: 0 08:42:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:42:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:42:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab3d9d9abc0fcd30b52f75e88a6971a1fb8af7aa63e1e89b7e35f5082582ee0df4fb9a5689fff57b1e27983973893ae8ce7a904f9d7fed86b3eb4b9c7c4b7f8a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7b9086a7cf259b900a21024b09fb81274460577d1991941bd6d9f03e693a760be2ee38a912e5a205f383f4542538c552e824bf9b0aeb37f175038b38319c759e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f21f8f71a2a74034ab31fd4ff0d1c2123f06eb77090daa9924de8bb5c11e27c4c94d6150d52d3c25d0f7f564fc96e04d10785e6d89581616953889f6d47a1cae"} 08:42:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:42:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:42:58 INFO - Contents: 08:42:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ab3d9d9abc0fcd30b52f75e88a6971a1fb8af7aa63e1e89b7e35f5082582ee0df4fb9a5689fff57b1e27983973893ae8ce7a904f9d7fed86b3eb4b9c7c4b7f8a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7b9086a7cf259b900a21024b09fb81274460577d1991941bd6d9f03e693a760be2ee38a912e5a205f383f4542538c552e824bf9b0aeb37f175038b38319c759e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f21f8f71a2a74034ab31fd4ff0d1c2123f06eb77090daa9924de8bb5c11e27c4c94d6150d52d3c25d0f7f564fc96e04d10785e6d89581616953889f6d47a1cae"} 08:42:58 INFO - Running post-run listener: copy_logs_to_upload_dir 08:42:58 INFO - Copying logs to upload dir... 08:42:58 INFO - mkdir: /builds/slave/test/build/upload/logs 08:42:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.700413 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-07-23 08:42:57.981832) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-23 08:42:57.985827) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1469283740.78057-297003153 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/ab3d9d9abc0fcd30b52f75e88a6971a1fb8af7aa63e1e89b7e35f5082582ee0df4fb9a5689fff57b1e27983973893ae8ce7a904f9d7fed86b3eb4b9c7c4b7f8a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7b9086a7cf259b900a21024b09fb81274460577d1991941bd6d9f03e693a760be2ee38a912e5a205f383f4542538c552e824bf9b0aeb37f175038b38319c759e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f21f8f71a2a74034ab31fd4ff0d1c2123f06eb77090daa9924de8bb5c11e27c4c94d6150d52d3c25d0f7f564fc96e04d10785e6d89581616953889f6d47a1cae"} build_url:https://queue.taskcluster.net/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011245 build_url: 'https://queue.taskcluster.net/v1/task/XlnQ4j8nRdaPGTwvGRwT0g/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/ab3d9d9abc0fcd30b52f75e88a6971a1fb8af7aa63e1e89b7e35f5082582ee0df4fb9a5689fff57b1e27983973893ae8ce7a904f9d7fed86b3eb4b9c7c4b7f8a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7b9086a7cf259b900a21024b09fb81274460577d1991941bd6d9f03e693a760be2ee38a912e5a205f383f4542538c552e824bf9b0aeb37f175038b38319c759e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f21f8f71a2a74034ab31fd4ff0d1c2123f06eb77090daa9924de8bb5c11e27c4c94d6150d52d3c25d0f7f564fc96e04d10785e6d89581616953889f6d47a1cae"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-23 08:42:58.014770) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-23 08:42:58.015540) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1469283740.78057-297003153 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004432 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-23 08:42:58.098845) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-23 08:42:58.099195) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-23 08:42:58.099536) ========= ========= Total master_lag: 0.20 =========