builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-021 starttime: 1465970065.38 results: success (0) buildid: 20160614221925 builduid: 4ae920de601647388cf625adaa9de991 revision: 161aea49941f2a90c46a2b6b834590f72b1e04a6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-14 22:54:25.380981) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-14 22:54:25.382752) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-14 22:54:25.383065) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-14 22:54:25.415208) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 22:54:25.415456) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-14 22:54:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 46.6M=0s 2016-06-14 22:54:25 (46.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.104805 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 22:54:25.534320) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 22:54:25.534726) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017141 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 22:54:25.585257) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-14 22:54:25.585671) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 161aea49941f2a90c46a2b6b834590f72b1e04a6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 161aea49941f2a90c46a2b6b834590f72b1e04a6 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-14 22:54:25,946 truncating revision to first 12 chars 2016-06-14 22:54:25,946 Setting DEBUG logging. 2016-06-14 22:54:25,946 attempt 1/10 2016-06-14 22:54:25,946 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/161aea49941f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-14 22:54:27,210 unpacking tar archive at: fx-team-161aea49941f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.598106 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-14 22:54:27.209839) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-14 22:54:27.210169) ========= script_repo_revision: 161aea49941f2a90c46a2b6b834590f72b1e04a6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-14 22:54:27.210612) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-14 22:54:27.211004) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-14 22:54:27.221654) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-06-14 22:54:27.221944) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:54:27 INFO - MultiFileLogger online at 20160614 22:54:27 in /builds/slave/test 22:54:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 22:54:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:54:27 INFO - {'append_to_log': False, 22:54:27 INFO - 'base_work_dir': '/builds/slave/test', 22:54:27 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 22:54:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:54:27 INFO - 'branch': 'Fx-Team-Non-PGO', 22:54:27 INFO - 'buildbot_json_path': 'buildprops.json', 22:54:27 INFO - 'config_files': ('talos/linux_config.py',), 22:54:27 INFO - 'default_actions': ('clobber', 22:54:27 INFO - 'read-buildbot-config', 22:54:27 INFO - 'download-and-extract', 22:54:27 INFO - 'populate-webroot', 22:54:27 INFO - 'create-virtualenv', 22:54:27 INFO - 'install', 22:54:27 INFO - 'run-tests'), 22:54:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:54:27 INFO - 'download_minidump_stackwalk': True, 22:54:27 INFO - 'download_symbols': 'ondemand', 22:54:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:54:27 INFO - 'tooltool.py': '/tools/tooltool.py', 22:54:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:54:27 INFO - '/tools/misc-python/virtualenv.py')}, 22:54:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:54:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:54:27 INFO - 'installer_path': 'installer.exe', 22:54:27 INFO - 'log_level': 'info', 22:54:27 INFO - 'log_name': 'talos', 22:54:27 INFO - 'log_to_console': True, 22:54:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:54:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:54:27 INFO - 'opt_config_files': (), 22:54:27 INFO - 'pip_index': False, 22:54:27 INFO - 'sps_profile': False, 22:54:27 INFO - 'sps_profile_interval': 0, 22:54:27 INFO - 'suite': 'dromaeojs', 22:54:27 INFO - 'system_bits': '32', 22:54:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:54:27 INFO - 'title': 'talos-linux64-ix-021', 22:54:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:54:27 INFO - 'use_talos_json': True, 22:54:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:54:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:54:27 INFO - 'work_dir': 'build'} 22:54:27 INFO - ##### 22:54:27 INFO - ##### Running clobber step. 22:54:27 INFO - ##### 22:54:27 INFO - Running pre-action listener: _resource_record_pre_action 22:54:27 INFO - Running main action method: clobber 22:54:27 INFO - rmtree: /builds/slave/test/build 22:54:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:54:28 INFO - Running post-action listener: _resource_record_post_action 22:54:28 INFO - ##### 22:54:28 INFO - ##### Finished clobber step (success) 22:54:28 INFO - ##### 22:54:28 INFO - ##### 22:54:28 INFO - ##### Running read-buildbot-config step. 22:54:28 INFO - ##### 22:54:28 INFO - Running pre-action listener: _resource_record_pre_action 22:54:28 INFO - Running main action method: read_buildbot_config 22:54:28 INFO - Using buildbot properties: 22:54:28 INFO - { 22:54:28 INFO - "project": "", 22:54:28 INFO - "product": "firefox", 22:54:28 INFO - "script_repo_revision": "production", 22:54:28 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 22:54:28 INFO - "repository": "", 22:54:28 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 22:54:28 INFO - "buildid": "20160614221925", 22:54:28 INFO - "pgo_build": "False", 22:54:28 INFO - "basedir": "/builds/slave/test", 22:54:28 INFO - "buildnumber": 1861, 22:54:28 INFO - "slavename": "talos-linux64-ix-021", 22:54:28 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:54:28 INFO - "platform": "ubuntu64_hw", 22:54:28 INFO - "scheduler": "tests-fx-team-linux64-talos", 22:54:28 INFO - "branch": "fx-team", 22:54:28 INFO - "repo_path": "integration/fx-team", 22:54:28 INFO - "revision": "161aea49941f2a90c46a2b6b834590f72b1e04a6", 22:54:28 INFO - "stage_platform": "linux64", 22:54:28 INFO - "builduid": "4ae920de601647388cf625adaa9de991", 22:54:28 INFO - "slavebuilddir": "test" 22:54:28 INFO - } 22:54:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 22:54:28 INFO - Running post-action listener: _resource_record_post_action 22:54:28 INFO - ##### 22:54:28 INFO - ##### Finished read-buildbot-config step (success) 22:54:28 INFO - ##### 22:54:28 INFO - ##### 22:54:28 INFO - ##### Running download-and-extract step. 22:54:28 INFO - ##### 22:54:28 INFO - Running pre-action listener: _resource_record_pre_action 22:54:28 INFO - Running main action method: download_and_extract 22:54:28 INFO - mkdir: /builds/slave/test/build/tests 22:54:28 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')]} 22:54:28 INFO - https://queue.taskcluster.net/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:54:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:54:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:54:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:54:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:54:31 INFO - Downloaded 1361 bytes. 22:54:31 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:54:31 INFO - Using the following test package requirements: 22:54:31 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:54:31 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:54:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:54:31 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:54:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:54:31 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:54:31 INFO - u'jsshell-linux-x86_64.zip'], 22:54:31 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:54:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:54:31 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:54:31 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:54:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:54:31 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:54:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:54:31 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:54:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:54:31 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:54:31 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:54:31 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:31 INFO - https://queue.taskcluster.net/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:54:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:54:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:54:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:54:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:54:33 INFO - Downloaded 22653673 bytes. 22:54:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:54:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:54:34 INFO - Return code: 0 22:54:34 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:54:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:34 INFO - https://queue.taskcluster.net/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:54:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:54:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:54:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:54:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:54:36 INFO - Downloaded 22653673 bytes. 22:54:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:54:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:54:38 INFO - Return code: 0 22:54: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')]} 22:54:38 INFO - https://queue.taskcluster.net/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:54:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:54:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:54:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:54:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:54:39 INFO - Downloaded 14405384 bytes. 22:54:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:54:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:54:39 INFO - Return code: 0 22:54:39 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')]} 22:54:39 INFO - https://queue.taskcluster.net/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:54:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:54:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:54:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:54:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:54:43 INFO - Downloaded 55397261 bytes. 22:54:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:54:43 INFO - mkdir: /builds/slave/test/properties 22:54:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:54:43 INFO - Writing to file /builds/slave/test/properties/build_url 22:54:43 INFO - Contents: 22:54:43 INFO - build_url:https://queue.taskcluster.net/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:54:44 INFO - Running post-action listener: _resource_record_post_action 22:54:44 INFO - Running post-action listener: set_extra_try_arguments 22:54:44 INFO - ##### 22:54:44 INFO - ##### Finished download-and-extract step (success) 22:54:44 INFO - ##### 22:54:44 INFO - ##### 22:54:44 INFO - ##### Running populate-webroot step. 22:54:44 INFO - ##### 22:54:44 INFO - Running pre-action listener: _resource_record_pre_action 22:54:44 INFO - Running main action method: populate_webroot 22:54:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:54:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:54:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:54:44 INFO - '--tppagecycles', 22:54:44 INFO - '7'], 22:54:44 INFO - 'tests': ['tsvgm']}}, 22:54:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:54:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:54:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:54:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:54:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:54:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:54:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:54:44 INFO - 'talos_options': ['--disable-e10s'], 22:54:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:54:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:54:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:54:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:54:44 INFO - 'talos_options': ['--disable-e10s'], 22:54:44 INFO - 'tests': ['damp', 'tps']}, 22:54:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:54:44 INFO - 'tests': ['damp', 'tps']}, 22:54:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:54:44 INFO - 'tests': ['dromaeo_dom']}, 22:54:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:54:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:54:44 INFO - 'tests': ['basic_compositor_video']}, 22:54:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:54:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:54:44 INFO - 'tests': ['a11yr', 22:54:44 INFO - 'ts_paint', 22:54:44 INFO - 'tpaint', 22:54:44 INFO - 'sessionrestore', 22:54:44 INFO - 'sessionrestore_no_auto_restore', 22:54:44 INFO - 'tabpaint']}, 22:54:44 INFO - 'other-e10s': {'tests': ['a11yr', 22:54:44 INFO - 'ts_paint', 22:54:44 INFO - 'tpaint', 22:54:44 INFO - 'sessionrestore', 22:54:44 INFO - 'sessionrestore_no_auto_restore', 22:54:44 INFO - 'tabpaint']}, 22:54:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:54:44 INFO - 'ts_paint', 22:54:44 INFO - 'tpaint', 22:54:44 INFO - 'sessionrestore', 22:54:44 INFO - 'sessionrestore_no_auto_restore', 22:54:44 INFO - 'tabpaint']}, 22:54:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:54:44 INFO - 'ts_paint', 22:54:44 INFO - 'tpaint', 22:54:44 INFO - 'sessionrestore', 22:54:44 INFO - 'sessionrestore_no_auto_restore', 22:54:44 INFO - 'tabpaint']}, 22:54:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:54:44 INFO - 'tests': ['a11yr', 22:54:44 INFO - 'ts_paint', 22:54:44 INFO - 'tpaint', 22:54:44 INFO - 'sessionrestore', 22:54:44 INFO - 'sessionrestore_no_auto_restore', 22:54:44 INFO - 'tabpaint']}, 22:54:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:54:44 INFO - 'tests': ['a11yr', 22:54:44 INFO - 'ts_paint', 22:54:44 INFO - 'tpaint', 22:54:44 INFO - 'sessionrestore', 22:54:44 INFO - 'sessionrestore_no_auto_restore', 22:54:44 INFO - 'tabpaint']}, 22:54:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:54:44 INFO - 'tests': ['tsvgx', 22:54:44 INFO - 'tsvgr_opacity', 22:54:44 INFO - 'tart', 22:54:44 INFO - 'tscrollx', 22:54:44 INFO - 'cart']}, 22:54:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:54:44 INFO - 'tsvgr_opacity', 22:54:44 INFO - 'tart', 22:54:44 INFO - 'tscrollx', 22:54:44 INFO - 'cart']}, 22:54:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:54:44 INFO - 'talos_options': ['--disable-e10s'], 22:54:44 INFO - 'tests': ['tp5o']}, 22:54:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:54:44 INFO - 'tests': ['tp5o']}, 22:54:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:54:44 INFO - 'talos_options': ['--disable-e10s', 22:54:44 INFO - '--xperf_path', 22:54:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:54:44 INFO - 'tests': ['tp5n']}, 22:54:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:54:44 INFO - 'talos_options': ['--xperf_path', 22:54:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:54:44 INFO - 'tests': ['tp5n']}}, 22:54:44 INFO - 'talos.zip': {'path': '', 22:54:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:54:44 INFO - Running post-action listener: _resource_record_post_action 22:54:44 INFO - ##### 22:54:44 INFO - ##### Finished populate-webroot step (success) 22:54:44 INFO - ##### 22:54:44 INFO - ##### 22:54:44 INFO - ##### Running create-virtualenv step. 22:54:44 INFO - ##### 22:54:44 INFO - Running pre-action listener: _resource_record_pre_action 22:54:44 INFO - Running main action method: create_virtualenv 22:54:44 INFO - Creating virtualenv /builds/slave/test/build/venv 22:54:44 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 22:54:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:54:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:54:44 INFO - Using real prefix '/usr' 22:54:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:54:44 INFO - Installing distribute.............................................................................................................................................................................................done. 22:54:46 INFO - Installing pip.................done. 22:54:46 INFO - Return code: 0 22:54:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:54: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')]} 22:54:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54: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')]} 22:54:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f227fbdff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2299350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23281c0>, '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 0x23290c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2329550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604', '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 22:54: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', 'pip>=1.5'] in /builds/slave/test/build 22:54: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 pip>=1.5 22:54:46 INFO - Using env: {'DISPLAY': ':0', 22:54:46 INFO - 'HOME': '/home/cltbld', 22:54:46 INFO - 'LANG': 'en_US.UTF-8', 22:54:46 INFO - 'LANGUAGE': 'en_US:en', 22:54:46 INFO - 'LOGNAME': 'cltbld', 22:54:46 INFO - 'MAIL': '/var/mail/cltbld', 22:54:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:46 INFO - 'MOZ_NO_REMOTE': '1', 22:54:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:54:46 INFO - 'NO_EM_RESTART': '1', 22:54:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:54:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:46 INFO - 'PWD': '/builds/slave/test', 22:54:46 INFO - 'SHELL': '/bin/bash', 22:54:46 INFO - 'SHLVL': '1', 22:54:46 INFO - 'TERM': 'linux', 22:54:46 INFO - 'TMOUT': '86400', 22:54:46 INFO - 'USER': 'cltbld', 22:54:46 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604', 22:54:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:46 INFO - '_': '/tools/buildbot/bin/python'} 22:54:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:47 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 22:54:47 INFO - Cleaning up... 22:54:47 INFO - Return code: 0 22:54:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:54:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:47 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 0x7f227fbdff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2299350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23281c0>, '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 0x23290c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2329550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604', '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 22:54:47 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 22:54:47 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 22:54:47 INFO - Using env: {'DISPLAY': ':0', 22:54:47 INFO - 'HOME': '/home/cltbld', 22:54:47 INFO - 'LANG': 'en_US.UTF-8', 22:54:47 INFO - 'LANGUAGE': 'en_US:en', 22:54:47 INFO - 'LOGNAME': 'cltbld', 22:54:47 INFO - 'MAIL': '/var/mail/cltbld', 22:54:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:47 INFO - 'MOZ_NO_REMOTE': '1', 22:54:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:54:47 INFO - 'NO_EM_RESTART': '1', 22:54:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:54:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:47 INFO - 'PWD': '/builds/slave/test', 22:54:47 INFO - 'SHELL': '/bin/bash', 22:54:47 INFO - 'SHLVL': '1', 22:54:47 INFO - 'TERM': 'linux', 22:54:47 INFO - 'TMOUT': '86400', 22:54:47 INFO - 'USER': 'cltbld', 22:54:47 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604', 22:54:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:47 INFO - '_': '/tools/buildbot/bin/python'} 22:54:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:47 INFO - Downloading/unpacking psutil>=3.1.1 22:54:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:54:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:54:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:54:49 INFO - Installing collected packages: psutil 22:54:49 INFO - Running setup.py install for psutil 22:54:49 INFO - building 'psutil._psutil_linux' extension 22:54:49 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 22:54:49 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 22:54:49 INFO - building 'psutil._psutil_posix' extension 22:54:49 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 22:54:49 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 22:54:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:54:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:54:49 INFO - Successfully installed psutil 22:54:49 INFO - Cleaning up... 22:54:49 INFO - Return code: 0 22:54:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:54:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f227fbdff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2299350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23281c0>, '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 0x23290c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2329550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604', '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 22:54:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:54:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:54:49 INFO - Using env: {'DISPLAY': ':0', 22:54:49 INFO - 'HOME': '/home/cltbld', 22:54:49 INFO - 'LANG': 'en_US.UTF-8', 22:54:49 INFO - 'LANGUAGE': 'en_US:en', 22:54:49 INFO - 'LOGNAME': 'cltbld', 22:54:49 INFO - 'MAIL': '/var/mail/cltbld', 22:54:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:49 INFO - 'MOZ_NO_REMOTE': '1', 22:54:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:54:49 INFO - 'NO_EM_RESTART': '1', 22:54:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:54:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:49 INFO - 'PWD': '/builds/slave/test', 22:54:49 INFO - 'SHELL': '/bin/bash', 22:54:49 INFO - 'SHLVL': '1', 22:54:49 INFO - 'TERM': 'linux', 22:54:49 INFO - 'TMOUT': '86400', 22:54:49 INFO - 'USER': 'cltbld', 22:54:49 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604', 22:54:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:49 INFO - '_': '/tools/buildbot/bin/python'} 22:54:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:54:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:54:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:54:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:54:52 INFO - Installing collected packages: mozsystemmonitor 22:54:52 INFO - Running setup.py install for mozsystemmonitor 22:54:52 INFO - Successfully installed mozsystemmonitor 22:54:52 INFO - Cleaning up... 22:54:52 INFO - Return code: 0 22:54:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:54:52 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')]} 22:54:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:52 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')]} 22:54:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:52 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 0x7f227fbdff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2299350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23281c0>, '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 0x23290c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2329550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604', '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 22:54:52 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 22:54:52 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 22:54:52 INFO - Using env: {'DISPLAY': ':0', 22:54:52 INFO - 'HOME': '/home/cltbld', 22:54:52 INFO - 'LANG': 'en_US.UTF-8', 22:54:52 INFO - 'LANGUAGE': 'en_US:en', 22:54:52 INFO - 'LOGNAME': 'cltbld', 22:54:52 INFO - 'MAIL': '/var/mail/cltbld', 22:54:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:52 INFO - 'MOZ_NO_REMOTE': '1', 22:54:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:54:52 INFO - 'NO_EM_RESTART': '1', 22:54:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:54:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:52 INFO - 'PWD': '/builds/slave/test', 22:54:52 INFO - 'SHELL': '/bin/bash', 22:54:52 INFO - 'SHLVL': '1', 22:54:52 INFO - 'TERM': 'linux', 22:54:52 INFO - 'TMOUT': '86400', 22:54:52 INFO - 'USER': 'cltbld', 22:54:52 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604', 22:54:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:52 INFO - '_': '/tools/buildbot/bin/python'} 22:54:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:52 INFO - Downloading/unpacking jsonschema==2.5.1 22:54:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:54:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:54:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:54 INFO - Downloading functools32-3.2.3-2.tar.gz 22:54:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:54:54 INFO - warning: no files found matching '*.txt' 22:54:54 INFO - no previously-included directories found matching 'build' 22:54:54 INFO - no previously-included directories found matching 'dist' 22:54:54 INFO - no previously-included directories found matching '.git*' 22:54:54 INFO - Installing collected packages: jsonschema, functools32 22:54:54 INFO - Running setup.py install for functools32 22:54:54 INFO - warning: no files found matching '*.txt' 22:54:54 INFO - no previously-included directories found matching 'build' 22:54:54 INFO - no previously-included directories found matching 'dist' 22:54:54 INFO - no previously-included directories found matching '.git*' 22:54:54 INFO - Successfully installed jsonschema functools32 22:54:54 INFO - Cleaning up... 22:54:54 INFO - Return code: 0 22:54:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:54:54 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')]} 22:54:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:54 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')]} 22:54:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:54 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 0x7f227fbdff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2299350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23281c0>, '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 0x23290c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2329550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604', '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 22:54:54 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 22:54:54 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 22:54:54 INFO - Using env: {'DISPLAY': ':0', 22:54:54 INFO - 'HOME': '/home/cltbld', 22:54:54 INFO - 'LANG': 'en_US.UTF-8', 22:54:54 INFO - 'LANGUAGE': 'en_US:en', 22:54:54 INFO - 'LOGNAME': 'cltbld', 22:54:54 INFO - 'MAIL': '/var/mail/cltbld', 22:54:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:54 INFO - 'MOZ_NO_REMOTE': '1', 22:54:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:54:54 INFO - 'NO_EM_RESTART': '1', 22:54:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:54:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:54 INFO - 'PWD': '/builds/slave/test', 22:54:54 INFO - 'SHELL': '/bin/bash', 22:54:54 INFO - 'SHLVL': '1', 22:54:54 INFO - 'TERM': 'linux', 22:54:54 INFO - 'TMOUT': '86400', 22:54:54 INFO - 'USER': 'cltbld', 22:54:54 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604', 22:54:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:54 INFO - '_': '/tools/buildbot/bin/python'} 22:54:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:55 INFO - Downloading/unpacking blobuploader==1.2.4 22:54:55 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 22:54:55 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 22:54:55 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 22:54:55 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 22:54:55 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 22:54:55 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 22:54:56 INFO - Downloading blobuploader-1.2.4.tar.gz 22:54:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:54:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:54:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:54:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:54:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:54:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:54:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:54:57 INFO - Downloading docopt-0.6.1.tar.gz 22:54:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:54:57 INFO - Installing collected packages: blobuploader, requests, docopt 22:54:57 INFO - Running setup.py install for blobuploader 22:54:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:54:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:54:57 INFO - Running setup.py install for requests 22:54:58 INFO - Running setup.py install for docopt 22:54:58 INFO - Successfully installed blobuploader requests docopt 22:54:58 INFO - Cleaning up... 22:54:58 INFO - Return code: 0 22:54:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:54:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:54:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:54:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:54:58 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 0x7f227fbdff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2299350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23281c0>, '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 0x23290c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2329550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604', '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 22:54:58 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 22:54:58 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 22:54:58 INFO - Using env: {'DISPLAY': ':0', 22:54:58 INFO - 'HOME': '/home/cltbld', 22:54:58 INFO - 'LANG': 'en_US.UTF-8', 22:54:58 INFO - 'LANGUAGE': 'en_US:en', 22:54:58 INFO - 'LOGNAME': 'cltbld', 22:54:58 INFO - 'MAIL': '/var/mail/cltbld', 22:54:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:58 INFO - 'MOZ_NO_REMOTE': '1', 22:54:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:54:58 INFO - 'NO_EM_RESTART': '1', 22:54:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:54:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:58 INFO - 'PWD': '/builds/slave/test', 22:54:58 INFO - 'SHELL': '/bin/bash', 22:54:58 INFO - 'SHLVL': '1', 22:54:58 INFO - 'TERM': 'linux', 22:54:58 INFO - 'TMOUT': '86400', 22:54:58 INFO - 'USER': 'cltbld', 22:54:58 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604', 22:54:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:58 INFO - '_': '/tools/buildbot/bin/python'} 22:54:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:54:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:54:58 INFO - Running setup.py (path:/tmp/pip-PqTVVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:54:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:54:58 INFO - Running setup.py (path:/tmp/pip-oP0_3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:54:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:54:58 INFO - Running setup.py (path:/tmp/pip-C13Ecc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:54:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:54:58 INFO - Running setup.py (path:/tmp/pip-Yz0rT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:54:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:54:58 INFO - Running setup.py (path:/tmp/pip-wyju6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:54:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:54:58 INFO - Running setup.py (path:/tmp/pip-DrPdwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:54:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:54:58 INFO - Running setup.py (path:/tmp/pip-gBYnzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:54:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:54:59 INFO - Running setup.py (path:/tmp/pip-9pYbBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:54:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:54:59 INFO - Running setup.py (path:/tmp/pip-MUsJRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:54:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:54:59 INFO - Running setup.py (path:/tmp/pip-DrMsUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:54:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:54:59 INFO - Running setup.py (path:/tmp/pip-3DiYui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:54:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:54:59 INFO - Running setup.py (path:/tmp/pip-cDK1KC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:54:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:54:59 INFO - Running setup.py (path:/tmp/pip-CGkWR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:54:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:54:59 INFO - Running setup.py (path:/tmp/pip-IPY_b4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:54:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:54:59 INFO - Running setup.py (path:/tmp/pip-GgFNjs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:54:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:54:59 INFO - Running setup.py (path:/tmp/pip-MH3wo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:54:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:54:59 INFO - Running setup.py (path:/tmp/pip-VdQwMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:54:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:54:59 INFO - Running setup.py install for manifestparser 22:54:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:54:59 INFO - Running setup.py install for mozcrash 22:54:59 INFO - Running setup.py install for mozdebug 22:55:00 INFO - Running setup.py install for mozdevice 22:55:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:55:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:55:00 INFO - Running setup.py install for mozfile 22:55:00 INFO - Running setup.py install for mozhttpd 22:55:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:55:00 INFO - Running setup.py install for mozinfo 22:55:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:55:00 INFO - Running setup.py install for mozInstall 22:55:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:55:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:55:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:55:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:55:00 INFO - Running setup.py install for mozleak 22:55:00 INFO - Running setup.py install for mozlog 22:55:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:55:00 INFO - Running setup.py install for moznetwork 22:55:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:55:00 INFO - Running setup.py install for mozprocess 22:55:00 INFO - Running setup.py install for mozprofile 22:55:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:55:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:55:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:55:01 INFO - Running setup.py install for mozrunner 22:55:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:55:01 INFO - Running setup.py install for mozscreenshot 22:55:01 INFO - Running setup.py install for moztest 22:55:01 INFO - Running setup.py install for mozversion 22:55:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:55:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:55:01 INFO - Cleaning up... 22:55:01 INFO - Return code: 0 22:55:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:55:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:55:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:55:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:55:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:55:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:01 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 0x7f227fbdff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2299350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23281c0>, '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 0x23290c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2329550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604', '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 22:55:01 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 22:55:01 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 22:55:01 INFO - Using env: {'DISPLAY': ':0', 22:55:01 INFO - 'HOME': '/home/cltbld', 22:55:01 INFO - 'LANG': 'en_US.UTF-8', 22:55:01 INFO - 'LANGUAGE': 'en_US:en', 22:55:01 INFO - 'LOGNAME': 'cltbld', 22:55:01 INFO - 'MAIL': '/var/mail/cltbld', 22:55:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:55:01 INFO - 'MOZ_NO_REMOTE': '1', 22:55:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:55:01 INFO - 'NO_EM_RESTART': '1', 22:55:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:55:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:55:01 INFO - 'PWD': '/builds/slave/test', 22:55:01 INFO - 'SHELL': '/bin/bash', 22:55:01 INFO - 'SHLVL': '1', 22:55:01 INFO - 'TERM': 'linux', 22:55:01 INFO - 'TMOUT': '86400', 22:55:01 INFO - 'USER': 'cltbld', 22:55:01 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604', 22:55:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:55:01 INFO - '_': '/tools/buildbot/bin/python'} 22:55:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:55:01 INFO - Running setup.py (path:/tmp/pip-GQha0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:55:01 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)) 22:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:55:01 INFO - Running setup.py (path:/tmp/pip-UM8Nkc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:55:01 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)) 22:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:55:01 INFO - Running setup.py (path:/tmp/pip-Pr1jvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:55:01 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)) 22:55:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:55:01 INFO - Running setup.py (path:/tmp/pip-WTv1_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:55:02 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)) 22:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:55:02 INFO - Running setup.py (path:/tmp/pip-9YtYd3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:55:02 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)) 22:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:55:02 INFO - Running setup.py (path:/tmp/pip-xBVDls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:55:02 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)) 22:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:55:02 INFO - Running setup.py (path:/tmp/pip-sduxg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:55:02 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)) 22:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:55:02 INFO - Running setup.py (path:/tmp/pip-0Uugr6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:55:02 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)) 22:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:55:02 INFO - Running setup.py (path:/tmp/pip-slIZs8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:55:02 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)) 22:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:55:02 INFO - Running setup.py (path:/tmp/pip-uTaOED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:55:02 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)) 22:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:55:02 INFO - Running setup.py (path:/tmp/pip-4D1hHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:55:02 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)) 22:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:55:02 INFO - Running setup.py (path:/tmp/pip-ok4Hvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:55:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:55:02 INFO - Running setup.py (path:/tmp/pip-okmtyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:55:02 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)) 22:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:55:02 INFO - Running setup.py (path:/tmp/pip-oJlETt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:55:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:55:02 INFO - Running setup.py (path:/tmp/pip-BRm6XZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:55:02 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)) 22:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:55:02 INFO - Running setup.py (path:/tmp/pip-fA6cd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:55:02 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)) 22:55:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:55:02 INFO - Running setup.py (path:/tmp/pip-ZgGqpz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:55:02 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)) 22:55:02 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)) 22:55:02 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)) 22:55:02 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)) 22:55:02 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)) 22:55:02 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)) 22:55:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:55:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:55:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:55:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:55:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:55:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:55:04 INFO - Downloading blessings-1.6.tar.gz 22:55:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:55:04 INFO - Installing collected packages: blessings 22:55:04 INFO - Running setup.py install for blessings 22:55:05 INFO - Successfully installed blessings 22:55:05 INFO - Cleaning up... 22:55:05 INFO - Return code: 0 22:55:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:55:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:55:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:55:05 INFO - Reading from file tmpfile_stdout 22:55:05 INFO - Current package versions: 22:55:05 INFO - argparse == 1.2.1 22:55:05 INFO - blessings == 1.6 22:55:05 INFO - blobuploader == 1.2.4 22:55:05 INFO - docopt == 0.6.1 22:55:05 INFO - functools32 == 3.2.3-2 22:55:05 INFO - jsonschema == 2.5.1 22:55:05 INFO - manifestparser == 1.1 22:55:05 INFO - mozInstall == 1.12 22:55:05 INFO - mozcrash == 0.17 22:55:05 INFO - mozdebug == 0.1 22:55:05 INFO - mozdevice == 0.48 22:55:05 INFO - mozfile == 1.2 22:55:05 INFO - mozhttpd == 0.7 22:55:05 INFO - mozinfo == 0.9 22:55:05 INFO - mozleak == 0.1 22:55:05 INFO - mozlog == 3.2 22:55:05 INFO - moznetwork == 0.27 22:55:05 INFO - mozprocess == 0.22 22:55:05 INFO - mozprofile == 0.28 22:55:05 INFO - mozrunner == 6.11 22:55:05 INFO - mozscreenshot == 0.1 22:55:05 INFO - mozsystemmonitor == 0.3 22:55:05 INFO - moztest == 0.7 22:55:05 INFO - mozversion == 1.4 22:55:05 INFO - psutil == 3.1.1 22:55:05 INFO - requests == 1.2.3 22:55:05 INFO - wsgiref == 0.1.2 22:55:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:55: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')]} 22:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:55:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55: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')]} 22:55:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:55:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:05 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 0x7f227fbdff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2299350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23281c0>, '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 0x23290c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2329550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604', '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 22:55:05 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 22:55:05 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 22:55:05 INFO - Using env: {'DISPLAY': ':0', 22:55:05 INFO - 'HOME': '/home/cltbld', 22:55:05 INFO - 'LANG': 'en_US.UTF-8', 22:55:05 INFO - 'LANGUAGE': 'en_US:en', 22:55:05 INFO - 'LOGNAME': 'cltbld', 22:55:05 INFO - 'MAIL': '/var/mail/cltbld', 22:55:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:55:05 INFO - 'MOZ_NO_REMOTE': '1', 22:55:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:55:05 INFO - 'NO_EM_RESTART': '1', 22:55:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:55:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:55:05 INFO - 'PWD': '/builds/slave/test', 22:55:05 INFO - 'SHELL': '/bin/bash', 22:55:05 INFO - 'SHLVL': '1', 22:55:05 INFO - 'TERM': 'linux', 22:55:05 INFO - 'TMOUT': '86400', 22:55:05 INFO - 'USER': 'cltbld', 22:55:05 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604', 22:55:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:55:05 INFO - '_': '/tools/buildbot/bin/python'} 22:55:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:55:05 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)) 22:55:05 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)) 22:55:05 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)) 22:55:05 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)) 22:55:05 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)) 22:55:05 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)) 22:55:05 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)) 22:55:05 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)) 22:55:05 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)) 22:55:05 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)) 22:55:05 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)) 22:55:05 INFO - Cleaning up... 22:55:05 INFO - Return code: 0 22:55:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:55: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')]} 22:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:55:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55: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')]} 22:55:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:55:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:05 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 0x7f227fbdff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2299350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23281c0>, '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 0x23290c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2329550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604', '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 22:55:05 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 22:55:05 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 22:55:05 INFO - Using env: {'DISPLAY': ':0', 22:55:05 INFO - 'HOME': '/home/cltbld', 22:55:05 INFO - 'LANG': 'en_US.UTF-8', 22:55:05 INFO - 'LANGUAGE': 'en_US:en', 22:55:05 INFO - 'LOGNAME': 'cltbld', 22:55:05 INFO - 'MAIL': '/var/mail/cltbld', 22:55:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:55:05 INFO - 'MOZ_NO_REMOTE': '1', 22:55:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:55:05 INFO - 'NO_EM_RESTART': '1', 22:55:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:55:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:55:05 INFO - 'PWD': '/builds/slave/test', 22:55:05 INFO - 'SHELL': '/bin/bash', 22:55:05 INFO - 'SHLVL': '1', 22:55:05 INFO - 'TERM': 'linux', 22:55:05 INFO - 'TMOUT': '86400', 22:55:05 INFO - 'USER': 'cltbld', 22:55:05 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604', 22:55:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:55:05 INFO - '_': '/tools/buildbot/bin/python'} 22:55:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:55:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:55:05 INFO - Cleaning up... 22:55:05 INFO - Return code: 0 22:55:05 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:55: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')]} 22:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:55:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55: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')]} 22:55:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:55:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:55:05 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 0x7f227fbdff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21ce8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2299350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23281c0>, '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 0x23290c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2329550>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604', '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 22:55:05 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 22:55:05 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 22:55:05 INFO - Using env: {'DISPLAY': ':0', 22:55:05 INFO - 'HOME': '/home/cltbld', 22:55:05 INFO - 'LANG': 'en_US.UTF-8', 22:55:05 INFO - 'LANGUAGE': 'en_US:en', 22:55:05 INFO - 'LOGNAME': 'cltbld', 22:55:05 INFO - 'MAIL': '/var/mail/cltbld', 22:55:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:55:05 INFO - 'MOZ_NO_REMOTE': '1', 22:55:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:55:05 INFO - 'NO_EM_RESTART': '1', 22:55:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:55:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:55:05 INFO - 'PWD': '/builds/slave/test', 22:55:05 INFO - 'SHELL': '/bin/bash', 22:55:05 INFO - 'SHLVL': '1', 22:55:05 INFO - 'TERM': 'linux', 22:55:05 INFO - 'TMOUT': '86400', 22:55:05 INFO - 'USER': 'cltbld', 22:55:05 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604', 22:55:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:55:05 INFO - '_': '/tools/buildbot/bin/python'} 22:55:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:55:06 INFO - Downloading/unpacking flake8 22:55:06 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 22:55:06 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 22:55:06 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 22:55:06 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 22:55:06 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 22:55:06 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 22:55:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:55:07 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:55:07 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 22:55:07 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 22:55:07 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 22:55:07 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 22:55:07 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 22:55:07 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 22:55:08 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:55:08 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:55:08 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 22:55:08 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 22:55:08 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 22:55:08 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 22:55:08 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 22:55:08 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 22:55:08 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:55:08 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 22:55:08 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 22:55:08 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 22:55:08 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 22:55:08 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 22:55:08 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 22:55:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:55:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:55:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:55:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:55:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:55:08 INFO - no previously-included directories found matching 'docs/_build' 22:55:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:55:08 INFO - Running setup.py install for pep8 22:55:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:55:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:55:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:55:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:55:08 INFO - no previously-included directories found matching 'docs/_build' 22:55:08 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:55:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:55:08 INFO - Cleaning up... 22:55:08 INFO - Return code: 0 22:55:08 INFO - Running post-action listener: _resource_record_post_action 22:55:08 INFO - Running post-action listener: _start_resource_monitoring 22:55:08 INFO - Starting resource monitoring. 22:55:08 INFO - ##### 22:55:08 INFO - ##### Finished create-virtualenv step (success) 22:55:08 INFO - ##### 22:55:08 INFO - ##### 22:55:08 INFO - ##### Running install step. 22:55:08 INFO - ##### 22:55:08 INFO - Running pre-action listener: _resource_record_pre_action 22:55:08 INFO - Running main action method: install 22:55:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:55:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:55:09 INFO - Reading from file tmpfile_stdout 22:55:09 INFO - Detecting whether we're running mozinstall >=1.0... 22:55:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:55:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:55:09 INFO - Reading from file tmpfile_stdout 22:55:09 INFO - Output received: 22:55:09 INFO - Usage: mozinstall [options] installer 22:55:09 INFO - Options: 22:55:09 INFO - -h, --help show this help message and exit 22:55:09 INFO - -d DEST, --destination=DEST 22:55:09 INFO - Directory to install application into. [default: 22:55:09 INFO - "/builds/slave/test"] 22:55:09 INFO - --app=APP Application being installed. [default: firefox] 22:55:09 INFO - mkdir: /builds/slave/test/build/application 22:55:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:55:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:55:25 INFO - Reading from file tmpfile_stdout 22:55:25 INFO - Output received: 22:55:25 INFO - /builds/slave/test/build/application/firefox/firefox 22:55:25 INFO - Running post-action listener: _resource_record_post_action 22:55:25 INFO - ##### 22:55:25 INFO - ##### Finished install step (success) 22:55:25 INFO - ##### 22:55:25 INFO - ##### 22:55:25 INFO - ##### Running run-tests step. 22:55:25 INFO - ##### 22:55:25 INFO - Running pre-action listener: _resource_record_pre_action 22:55:25 INFO - Running main action method: run_tests 22:55:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:55:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:55:25 INFO - Python 2.7.3 22:55:25 INFO - Return code: 0 22:55:25 INFO - grabbing minidump binary from tooltool 22:55:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:55:25 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 0x23281c0>, '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 0x23290c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2329550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:55:25 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 22:55:25 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 22:55:25 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 22:55:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:55:25 INFO - Return code: 0 22:55:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:55:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:55:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:55:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:55:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:55:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:55:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:55:26 INFO - Return code: 0 22:55:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:55:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-021 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:55:26 INFO - Using env: {'DISPLAY': ':0', 22:55:26 INFO - 'HOME': '/home/cltbld', 22:55:26 INFO - 'LANG': 'en_US.UTF-8', 22:55:26 INFO - 'LANGUAGE': 'en_US:en', 22:55:26 INFO - 'LOGNAME': 'cltbld', 22:55:26 INFO - 'MAIL': '/var/mail/cltbld', 22:55:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:55:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:55:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:55:26 INFO - 'MOZ_NO_REMOTE': '1', 22:55:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:55:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:55:26 INFO - 'NO_EM_RESTART': '1', 22:55:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:55:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:55:26 INFO - 'PWD': '/builds/slave/test', 22:55:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:55:26 INFO - 'SHELL': '/bin/bash', 22:55:26 INFO - 'SHLVL': '1', 22:55:26 INFO - 'TERM': 'linux', 22:55:26 INFO - 'TMOUT': '86400', 22:55:26 INFO - 'USER': 'cltbld', 22:55:26 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604', 22:55:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:55:26 INFO - '_': '/tools/buildbot/bin/python'} 22:55:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:55:26 INFO - mozversion application_buildid: 20160614221925 22:55:26 INFO - mozversion application_changeset: 161aea49941f2a90c46a2b6b834590f72b1e04a6 22:55:26 INFO - mozversion application_display_name: Nightly 22:55:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:55:26 INFO - mozversion application_name: Firefox 22:55:26 INFO - mozversion application_remotingname: firefox 22:55:26 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:55:26 INFO - mozversion application_vendor: Mozilla 22:55:26 INFO - mozversion application_version: 50.0a1 22:55:26 INFO - mozversion platform_buildid: 20160614221925 22:55:26 INFO - mozversion platform_changeset: 161aea49941f2a90c46a2b6b834590f72b1e04a6 22:55:26 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:55:26 INFO - mozversion platform_version: 50.0a1 22:55:26 INFO - using testdate: 1465970126 22:55:26 INFO - actual date: 1465970126 22:55:26 INFO - starting webserver on 'localhost:47878' 22:55:26 INFO - SUITE-START | Running 2 tests 22:55:26 INFO - TEST-START | dromaeo_css 22:55:26 INFO - operating with platform_type : linux_ 22:55:26 INFO - Initialising browser for dromaeo_css test... 22:55:26 INFO - TEST-INFO | started process 5867 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpu0XY57/profile http://localhost:47878/getInfo.html) 22:55:32 INFO - PROCESS | 5867 | __metrics Screen width/height:1600/1200 22:55:32 INFO - PROCESS | 5867 | colorDepth:24 22:55:32 INFO - PROCESS | 5867 | Browser inner width/height: 1024/697 22:55:32 INFO - PROCESS | 5867 | __metrics 22:55:33 INFO - PROCESS | 5867 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:55:33 INFO - PROCESS | 5867 | console.error: PushService: 22:55:33 INFO - PROCESS | 5867 | stateChangeProcessEnqueue: Error transitioning state 22:55:33 INFO - PROCESS | 5867 | UnknownError 22:55:34 INFO - TEST-INFO | 5867: exit 0 22:55:34 INFO - Browser initialized. 22:55:34 INFO - Running cycle 1/1 for dromaeo_css test... 22:55:34 INFO - TEST-INFO | started process 5948 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpu0XY57/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:55:46 INFO - PROCESS | 5948 | RSS: Main: 174702592 22:55:46 INFO - PROCESS | 5948 | 22:56:37 INFO - PROCESS | 5948 | 1465970197879 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] 22:58:50 INFO - PROCESS | 5948 | Cycle 1(1): loaded http://localhost:47878/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47878/tests/dromaeo/cssquery-ext.html) 22:58:51 INFO - PROCESS | 5948 | RSS: Main: 267534336 22:58:51 INFO - PROCESS | 5948 | 23:01:54 INFO - PROCESS | 5948 | Cycle 1(1): loaded http://localhost:47878/tests/dromaeo/cssquery-ext.html (next: http://localhost:47878/tests/dromaeo/cssquery-jquery.html) 23:01:55 INFO - PROCESS | 5948 | RSS: Main: 258809856 23:01:55 INFO - PROCESS | 5948 | 23:04:57 INFO - PROCESS | 5948 | Cycle 1(1): loaded http://localhost:47878/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47878/tests/dromaeo/cssquery-mootools.html) 23:04:57 INFO - PROCESS | 5948 | RSS: Main: 259428352 23:04:57 INFO - PROCESS | 5948 | 23:08:00 INFO - PROCESS | 5948 | Cycle 1(1): loaded http://localhost:47878/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47878/tests/dromaeo/cssquery-prototype.html) 23:08:00 INFO - PROCESS | 5948 | RSS: Main: 262762496 23:08:00 INFO - PROCESS | 5948 | 23:11:03 INFO - PROCESS | 5948 | Cycle 1(1): loaded http://localhost:47878/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47878/tests/dromaeo/cssquery-yui.html) 23:11:04 INFO - PROCESS | 5948 | RSS: Main: 237060096 23:11:04 INFO - PROCESS | 5948 | 23:14:07 INFO - PROCESS | 5948 | Cycle 1(1): loaded http://localhost:47878/tests/dromaeo/cssquery-yui.html (next: http://localhost:47878/tests/dromaeo/cssquery-dojo.html) 23:14:07 INFO - PROCESS | 5948 | RSS: Main: 308817920 23:14:07 INFO - PROCESS | 5948 | 23:14:07 INFO - PROCESS | 5948 | __start_tp_report 23:14:07 INFO - PROCESS | 5948 | _x_x_mozilla_page_load 23:14:07 INFO - PROCESS | 5948 | _x_x_mozilla_page_load_details 23:14:07 INFO - PROCESS | 5948 | |i|pagename|runs| 23:14:07 INFO - PROCESS | 5948 | |0;dojo.html;2627.2643659775263;2632.644592979947;2667.239836646555;2668.9332766680836;2677.6385187999613;4580.244259697146;4661.836835710751;4716.386869706933;4739.336492890994;4814.590759785418;4685.882852928678;4706.176419126657;4709.952900470994;4766.52334766524;4785.21044027735;4298.204832106063;4327.935080973788;4348.847790327339;4383.013821890065;4537.2740361542155;4433.600975912167;4434.512203657595;4473.575010374026;4477.395551600541;4501.819927202924;4245.066085461193;4308.418363520947;4381.07997320563;4418.4256046714045;4455.866324010285;4329.913401731979;4341.153475072362;4357.694961352707;4377.102693947765;4391.6047555719815;4352.303631418989;4381.298992161239;4409.228385032607;4410.75740834254;4426.136903303857;4661.137689527414;4669.369635099252;4726.0784147091335;4787.066522028206;4833.202521583935;5065.442801291855;5130.461301563357;5137.7944882204665;5141.460146684619;5150.768215430315;5129.5383415492415;5158.509941555546;5168.198929165986;5172.715500647463;5172.8965420691375;4655.9291362986705;4690.202665546865;4715.669903106749;4750.311204875274;4808.447028591721;5215.426303106655;5235.293235413246;5268.051750684837;5275.103232450492;5286.47135286476;4987.725675087832;5022.547970682656;5037.924431133536;5044.9495505045215;5051.217061355495;4907.214845624754;4954.727489988013;5038.269450929594;5041.269015992661;5118.564921981673;5011.599072074226;5013.573846223112;5017.724025178651;5021.84934451967;5032.748362581856;4970.15507363749;4973;4975.5522003019905;4987.301777751118;5007.24891266313;4203.07532342884;4247.660187185018;4251;4292.806823692941;4338.82644694215;3763.8118094095184;3794.4498047782918;3807.562130354989;3810.3903375459795;3810.580836108026;4309.6552275818485;4350.2387082260475;4360.084382279757;4366.716163449366;4370.737755734666;5162.096633089194;5163.66436181647;5166.9224961625605;5175.715335656575;5214.139666954986;4232.449781528443;4368.803403846835;4375.956240437618;4377.846775362847;4423.646108311327;4529.5243999380245;4549.681522293474;4576.336431217454;4580.496145424001;4587.495375508692;4783.569478746929;4784.40194975628;4789.568938795525;4791.928121078117;4844.4186697596515;4859.319695242598;5095.974520127375;5127.17965125593;5135.127028405105;5138.3833939927445;4796.616270698406;4819.903601928012;4840.588549973291;4842.201036828884;4894.681845680019;5050.090983622837;5051.722155281348;5061.797528098835;5075.746212689277;5106.693598384109;4942.579880710036;5037.798488060436;5140.897182056263;5158.561522270649;5207.479252074762;4943.975280123576;4974.925376119289;4989.9251511228085;4992.600591952708;5032.773525191302;4993;5004.499550044967;5010.624203184849;5012.924806127838;5054;4725.10223057618;4814.422269327703;4839.588634965927;4889.535494128052;4953.529414705597;5023.974880125576;5045.091883460866;5046.823361182488;5047.87380315495;5064.645474816727;4978.153713868724;4988.700677959334;4990.8003679853855;4993.051320249141;4995.900081998412 23:14:07 INFO - PROCESS | 5948 | |1;ext.html;542.4846395923871;564.1875698993449;569.4334137533153;579.0705917003207;593.6408472873911;27324.86337568312;29306.413871722554;29330.999999999975;29369.70630293699;40075.59924400755;36979;41205.90955678321;41308.58691413085;41319;41444.37833432501;7831.138574756772;7963.960180199091;7968.8804667929735;7969.163237860028;7971.322437592811;7851.293383595504;7940.404469664798;7943.086545047336;7945.046594408679;7949.324307433875;7933.761987140395;7946.6821327146845;7976.840463190732;7984.720534781284;7987.361011119126;7928.088269848955;7954.602269886483;7957.562334071624;7958.4429089964015;7964.283214510722;7730.884036739453;7800;7802.180771019075;7806.219378062206;7823.256790604941;38903.610963890285;39297.80351098255;39305.60694393048;39349.80325098356;39370.606293937264;3110.237991692027;3244.2213868671583;3260.7228385587255;3368.1747971747077;3488.982555087234;29462.26344341387;29749.405011899857;29921.401571968658;30243.546346804815;31166.22084447884;3459.3081383723356;3531.523244362004;3544.8759293424605;3566.982165089184;3618.095476130967;238730;238955;238960.80519597465;239103;240033;28083.71916280811;51705.48294517082;51969.220461693105;52078.21882671763;52091.218631720556;32551.18622034468;32629.347413051604;32642.183945401554;32690.673093269237;32714;13713;13928.651783705489;13958.930205348908;13966.092204006707;13969.092009019381;10807.891921080845;10821.458927053616;10863.728406789736;10877.4771531984;10881.238313317992;7664.65509052094;7712.845743085107;7766.728164514215;7770.53376797394;7772.106207786064;2978.240548660078;3011.713887180714;3027.3188532579993;3063.6783137770212;3075.2158199658943;888.9909952204295;910.3627460777482;928.5125309212717;960.1071003966239;969.185883857563;28062.579061314096;28164;28556.286092847844;28865.711342886723;28901.85549072283;798.7204478432618;971.689059500967;979.5249304087512;1025.0825511167614;1037.818381783185;14599.19704416246;15057.17185554805;15186.924065379824;15299.672720913339;15346.53960381168;400639;402137;403939;403941;404851;50276.24585631218;50324.748376257885;50567;50800.491995080316;50926.49073509291;1067.1302888146136;1176.1825531255865;1186.8279099530691;1202.1164444133556;1210.6791700199453;28185.295367615974;29305.267368315963;29609;29843;30133.3973320528;41920.79039604782;42002.78998604987;42508.78745606252;42509.36235956401;42517.787411062745;286131;288376;288442;288513;288997;22198.223062192745;22822.543549128593;24641.876790615934;25451.23646290614;25451.36371590651;41464;41487;41839;42619.360709588764;42997.140057198056;35243;36230.55809698074;36313.273734524635;36991.445128323634;37421.43867842039;36001.63998360088;36230.09424764402;36477.27045459129;36506;36701;35698.8215058923;36471.63528364682;36745.448818267214;37204.813975929945;40030.1993960125;29894.103176904726;30443.695563044086;30747;30841.8457907718;31186.532202017443;25652.8717356412;25982.22053338327;26111.347216318984;26259.21222363332;26298.73701262963 23:14:07 INFO - PROCESS | 5948 | |2;jquery.html;357.1891805601286;358.3460185162079;362.92015756533556;372.13293027246516;376.27003612990796;15839.920800395996;16330.83669163308;17259.56851078722;17271.481855544323;17294.91352543239;14265.643358916032;16688.165591720397;17471.563210919732;17513.91243043783;17521.03634300113;5105.5149760772665;5204.271402003723;5219.138842091051;5250.7112108834;5259.6055295852775;3709.202521457886;3889.766614003169;5215.947840521603;5832.912506312408;6147.661878596676;6127.111568822518;6141.3244543100445;6160.1375807387;6200.81397558074;6204.410580994799;5867;6064.151018857364;6071.423214794588;6072.180255665473;6077.453029227391;3332.7667063305335;4999.75001249936;5321.122014867542;5325.760340784674;5332.33345831771;20278.188872445084;20807.167713291452;20849.791502084936;20860.47848803777;20909.68635470454;1775.9200835962413;1890.9149088290922;1977.1399441243104;2031.7358743363416;2108.441263065289;9101.7269481915;9148.359614826964;9681.273904457194;9689.41863488193;13621.863781362159;1670.4070055130376;1756.0780590190125;1757.156564848867;1813.150586401876;1874.0161415256962;67338.3266167337;68817;69390.65304673495;69945.3005469949;71619.99999999948;9863.260255480867;9900.702978910644;9968.903420623725;10106.797864042677;10132.949335253277;13858.792118118236;13939.860601393857;13945.302734863217;13967.580972570839;13989.160650360807;7157.4274058075225;7193.748218812317;7289.927100729031;7293.307135822089;7418.183999760022;6722.092517510171;6748.831279218059;6751.358620931003;6877.243503214642;6880.58716477013;4428.402165707653;4433.179861725591;4450.858422493157;4457.1308594823695;4459.866204013885;1651.5045486354045;1709.8717596180336;1773.9911300443673;1826.1234607388528;1838.9080545972915;846.6951897316961;848.9915100848913;913.6299798581585;924.7456949338986;959.3284700709531;16530;16792;17335;17344.219510122075;17817.376391826485;826.9875951860727;851.3614788908319;872.1322284327008;875.1598465473216;925.000998921177;9413.858792118124;9916.603335866628;9983.201343892471;10008.799824003625;10098.646547370954;290104;295476;296229;399416;401844;54284.728576356865;54435.45564544304;81559.18440815635;85096.14903850883;88511.55744221238;886.4902680958475;937.3813283233034;952.5427794658464;952.828490871636;953.0041107043156;16028.67942641164;16047.358105676109;16156.434524791575;16305.755413668567;16635.50093497197;36599.634003660685;36679.449808253434;36804.631953680124;37335.81332093322;38484;47015.29477057915;48187.7705466901;48498.75750621224;49035;50050.249246262065;7466.290702383265;7731.226877312224;7945.920540794518;7965.800854978672;7978.6037443446;8628.913710863062;11030.172737044913;11764.35296058725;11896.524139034342;12014.699632509257;9998.150157236716;10417.895821041693;10606.946965265124;10767.6231331903;10916.781664366827;9945.801083978424;10033.247506437192;10073.798524029626;10266.743331416475;10561.947190264;10169.237307201842;10329.431881246604;10474.947625261824;10549.419781912069;10632.734181645521;7812.023497062867;7845.176256493214;7872.330851877655;7905.4466187368635;8034.718784842503;9513.33406661527;9916.256280778825;9947.104760571583;10135.189184865341;10237.795244095225 23:14:07 INFO - PROCESS | 5948 | |3;mootools.html;1219.3293688471338;1367.364175658319;1413.6112569043512;1510.9244537773116;1717.45900041487;6943.201531823842;7763.534187948727;8020.959895200516;8039.517628942261;8222.259996600305;7924.603769811515;8080.3939704522245;8090.6763729450895;8099.676012959475;8101;2177.912883484659;2210.801027907488;2220.600291947449;2221.2669818959785;2221.466847956487;2130.7762684918016;2168.793964573371;2235.284708893155;2238.2613737466595;2239.9328020159423;2138.721966144407;2155.9029843656995;2168.934931952044;2171.4462811983003;2200.570888676709;2117.3965419855344;2122.946926326839;2143.9249626263004;2146.6672665736837;2149.57008598281;2139.7860213978634;2169.0781417897488;2173.1307477009163;2175.945601359963;2181.19295860531;7677.577733224671;7894.723684671067;8023.398245131581;8066.274035336789;8106.594670266522;4472.932906006413;4574.428196475441;4574.565416285448;4591.6556258280425;4593.379893714339;2921.2842853500815;3147.7481801456056;3202.759793015533;3214.582104326446;3219.114743445548;949.2074118111316;963.6530848894392;967.5017366056486;970.3498655900563;993.8558908958161;10674.78650426987;10719.892801071968;10794.082502987256;10862.945685271523;10869.83695244572;4097.938530922039;4099.077707515785;4217.283061879488;4220.514640816284;4226.513950895686;8615.095414981337;8696.782580435412;8708.95645521781;8714.172153645393;8720.563971801384;4552.453705555355;4601.240795268744;4614.400127983341;4649.86050418488;4652.092841895797;3763.5295588051495;3774.169682669808;4156.729812562174;4201.516825565038;4257.680673949404;3472.374972504923;3523.1016090896655;3565.23347480293;3586.9103272418397;3593.6765691087926;901.693424235763;910.4946754551288;922.520289449496;936.283742936654;1031.9587216511256;850.8808766772656;861.5778268648443;874.4796845876693;902.7111324376136;913.36064754672;5491.121420572769;5600.663960162403;5649.209110724501;5708.543316534668;5885.499732522783;894.789724414762;895.7536677413764;908.4004556992486;923.6213152607532;942.2697409507687;3751.7431660393527;3837.654611085016;3843.385058390644;3898.181381909774;3909.9609003909595;9749.317547771589;10024.398536087858;10035.69892903214;10110.292279540508;10139.442330671884;9596.568154433067;9985.101340879355;9993.850092248622;9995.150412215044;9996.050375214343;7503.737369192161;7514.211007844207;7549.0941087069905;7558.99999999989;7653.0816302042995;5514.09017512106;5519.751611177587;5559.4162612926675;5629.099344104923;5642.125470552071;9184.678536251466;9374.765630859283;9398.953005235204;9404.200642945427;9407.2003879671;9188.081191880758;9363.344565880596;9630.036996300314;9640.469774162488;9659.372140810825;5259.47405259471;5701.710612986228;6078.848028799315;6082.300535438481;6216;7294.96352518234;7340.853182936418;7352.926470735438;7371.078615173104;7379.225181356095;6422.6467544285515;6476.740930362733;6601.53789234749;6649.434798042221;6684.63234522106;6503;6506.837329066622;6549.803505894831;6578.407943285128;6602.10871532337;6207.906881396692;6255.37446255389;6310.49516038725;6313.021481670348;6322.841928951629;4463.196624607602;4464.598186163261;4670.1827180244;4700.106979673851;4745.786439610157;4734.479207287127;4735.384400027974;4775.116603428377;4829.73436460998;4834.854954351375 23:14:07 INFO - PROCESS | 5948 | |4;prototype.html;284.7833718153457;289.87535359795294;290.5975224314325;306.38263898245026;307.20740489536996;4645.442546894377;4778.9283160752575;4818.638866955193;4830.661853670244;4836.8065277388905;4632.768361581916;4742.644301677371;4753.643476739241;4758.357621721067;4758.833440829571;3755.699544036484;3757.736958412919;3771.924561508782;3779.6787273081823;3789.5263092113487;3589.569251689788;3619.5475565554307;3717.646823551758;3773.1699026214187;3804.9619503805025;3720.627937206285;3735.9626403736024;3741.3639681254117;3756.417755247941;3766.7928263945473;3709.4992176056294;3756;3762.887113386603;3774.7168962327937;3774.75464094833;3297.4394352960053;3300.273939733255;3306.933861322784;3310.735141188723;3324.1024923270584;4372.497162826284;4677.6257899367965;4696.178168820443;4723.724594359538;4727.896086241624;4283.550227226127;4288.270993931039;4296.892577685551;4306.1053136619785;4306.4832220133785;5055.646104772667;5071.137906555894;5074.238864170404;5077.390713114413;5087.440381558025;3461.6192218855904;3621.529201203853;3628.5827129880136;3632.6730594246646;3642.289753498069;5236.266922630834;5255.159174532057;5273.734303767106;5276.604254680914;5287.4448182941005;3694.131879008431;3717.423799311111;3719.776813391205;3727.2545490901384;3734.3651579231596;3706.5737440194184;3810.6951443884423;3813.923721525554;3818.0836599215986;3824.1778017725765;4214.978925105354;4222.59885310895;4234.25900467417;4249.426327445817;4278;4999.225120106389;5011.473795251519;5018.222175562793;5022.698638081727;5028.974855125774;1227.5580790915262;1258.8615252322238;1298.5779621622821;1299.0971274963808;1300.843898732158;834.5805786557509;839.2237180607913;846.9068402475723;849.0533055642913;855.9957200213959;3849;3897.012523612978;3933.3116704576582;3940.2513522430645;3953.367461206193;4539.931901021488;4560.338750881104;4600.930986035212;4612.95387046132;4622.768861556976;3953.2093581283857;3960.32674445345;3966.5636779954184;3969.543502497192;3974.2250261198787;2358.481134150484;2426.7916196261317;2427.5630386530593;2451.7373552620415;2477.8513289202706;4274.7435153890865;4305.590968857953;4319.596131257354;4339.435873336446;4370.929122365041;4690.5693763401805;4692.6245900327895;4712.363830882787;4714;4715.74063426508;4073.918521629551;4339.479262488521;4358.694891357574;4427.424434823452;4436.447243464761;4664.970737729099;4682.953170468388;4696.2955556666775;4696.319033740157;4701.435827700561;4563.566461186182;4591.517890621437;4597.563231493003;4600.65495087863;4607.354970304227;4385.956140438682;4421.425214722066;4434.17967675981;4518.322251662277;4900;3570.2859428115003;3580.534530511017;3588.12091037687;3597.352476554245;3624.6012938577264;5034.320366750589;5219.2953951216095;5229.424763275961;5278.601170689693;5290.6032047597355;4977.4773648767805;5065.075271396846;5065.974670126626;5079.314292570603;5079.847604571869;5004.649674522894;5020.623453240948;5036.496350364934;5053.2420136979745;5114.974425127851;4825.445073816556;4954.727489988085;4967.875803104951;4989.800407983786;5004.1492946198505;5071.416787069535;5103.515166059218;5108.56577190943;5111.156659151198;5112.3865136183895;4994.800207991785;5003.374578177728;5003.77483013258;5014;5027.572656324253 23:14:07 INFO - PROCESS | 5948 | |5;yui.html;621.7388696747363;645.9903101453476;710.4884483172115;712.4015826705566;759.1914610939349;12616.369181540933;13318.667033324171;13329.266890321029;13378.59864204073;14180.787288190686;13882.4447022119;14193.503227387044;14194.148351098942;14218.857811421884;14223.857761422383;779.7660701789468;789.001912580586;790.6679194738224;791.9406044546682;793.3653077537975;621.2048577820398;755.2824816424419;777.4363586399871;782.8121250899771;784.051297929506;817.5789468423767;818.2390376949435;818.8976377952756;819.7458787775809;820.7865954851721;631.9526035547352;813.3127507256359;814.2305521282361;814.861473549498;815.0911733417256;580.8112363481843;596.84780381003;597.2892258212756;598.186466405688;598.7126179433897;13125.803112953314;13164.736705265937;13171.538996135088;13190.142640728418;13216.603501894959;11377.658670239905;11863.466144023454;11932.70168245792;12299.262044277371;12536.81194782079;11766.294022358687;12068;12153.210041347284;12187.39063046853;12357.505699771998;3318.867245310209;3360.1935535471557;3452.188735647121;3543.0433782878736;4099.241640296555;62055.68972155156;62093;62294.377056229765;62423.375766242214;62722.37277627211;10280.434576098387;10502.002309780559;10542.525586348633;10554.841677374692;10719.19606029551;18806;18979.24083036663;18994.335198267996;19580.70628940567;19590.21639134447;6475.364814512466;6515.869682606416;6912.032315475837;6926.445884329241;6948.861022779617;3550.715942724576;3610.891673249807;3614.530111085542;3666.2484190740834;3706.814659267026;2929.39947310805;3031.1361262040214;3032.69673032695;3034.3779525197283;3037;491.87948952506866;492.32059757534375;494.78971437139074;504.74762618690653;508.2274942088006;498.8303976647957;547.6248769592842;587.1017343464421;588.2323567744049;597.3638075449633;7390.889136662954;7485.288897554724;7733.922660773433;7734.961325193451;7918.683252669829;498.21531088534977;570.292836882263;605.2041565341561;614.18927016338;621.2762132116125;3464.3590935676475;3633.94549081764;3676.7426280160666;3699.7780133192095;3765.273168335972;66822.66588667025;76092;90668;91551;92026.53986730023;48339.75830120827;72438.63780681063;72835;74083.40708075192;74466.62766686131;66597.33402666009;68889.65555172293;71230.15079396767;71640.28359716441;73591.26408735951;8095.433319667803;8242.216989386;8302;8447.95776021116;8709.12908709124;30271.394572108875;41343.7932810334;42041.78979105085;43941.78029109834;44330;38982;39816.40275395929;56781.71609141928;57694.42305576891;61230;10331.845022324822;10370.688879333633;10535.104516116216;10561.155107591221;10774.084202842532;11836.467358968695;12284.385780711107;12387.62837114888;12783.93608031991;12923.935380323039;11398.601048963246;11432.942835286103;11726.765464690829;11853.881461185278;12316.692082698004;11158.386288754196;11364.261323013978;11392.316461012366;11736.119791015537;11746;10818.29681070728;11757.118216133995;11782.410879456163;11794.587189448328;11795.29228246308;9709.70870873875;9807.607695692092;9956.900430995598;10294.227932904912;10312.381257124296;10010.349327293703;10126.746831329276;10316;10751.462426878781;10759.838602420814 23:14:07 INFO - PROCESS | 5948 | __end_tp_report 23:14:07 INFO - PROCESS | 5948 | __start_cc_report 23:14:07 INFO - PROCESS | 5948 | _x_x_mozilla_cycle_collect,907 23:14:07 INFO - PROCESS | 5948 | __end_cc_report 23:14:07 INFO - PROCESS | 5948 | __startTimestamp1465971247494__endTimestamp 23:14:07 INFO - PROCESS | 5948 | 23:14:07 INFO - PROCESS | 5948 | ------- Summary: start ------- 23:14:07 INFO - PROCESS | 5948 | Number of tests: 6 23:14:07 INFO - PROCESS | 5948 | 23:14:07 INFO - PROCESS | 5948 | [#0] dojo.html Cycles:165 Average:4707.93 Median:4833.20 stddev:499.54 (10.3%) stddev-sans-first:473.65 23:14:07 INFO - PROCESS | 5948 | Values: 2627.3 2632.6 2667.2 2668.9 2677.6 4580.2 4661.8 4716.4 4739.3 4814.6 4685.9 4706.2 4710.0 4766.5 4785.2 4298.2 4327.9 4348.8 4383.0 4537.3 4433.6 4434.5 4473.6 4477.4 4501.8 4245.1 4308.4 4381.1 4418.4 4455.9 4329.9 4341.2 4357.7 4377.1 4391.6 4352.3 4381.3 4409.2 4410.8 4426.1 4661.1 4669.4 4726.1 4787.1 4833.2 5065.4 5130.5 5137.8 5141.5 5150.8 5129.5 5158.5 5168.2 5172.7 5172.9 4655.9 4690.2 4715.7 4750.3 4808.4 5215.4 5235.3 5268.1 5275.1 5286.5 4987.7 5022.5 5037.9 5044.9 5051.2 4907.2 4954.7 5038.3 5041.3 5118.6 5011.6 5013.6 5017.7 5021.8 5032.7 4970.2 4973.0 4975.6 4987.3 5007.2 4203.1 4247.7 4251.0 4292.8 4338.8 3763.8 3794.4 3807.6 3810.4 3810.6 4309.7 4350.2 4360.1 4366.7 4370.7 5162.1 5163.7 5166.9 5175.7 5214.1 4232.4 4368.8 4376.0 4377.8 4423.6 4529.5 4549.7 4576.3 4580.5 4587.5 4783.6 4784.4 4789.6 4791.9 4844.4 4859.3 5096.0 5127.2 5135.1 5138.4 4796.6 4819.9 4840.6 4842.2 4894.7 5050.1 5051.7 5061.8 5075.7 5106.7 4942.6 5037.8 5140.9 5158.6 5207.5 4944.0 4974.9 4989.9 4992.6 5032.8 4993.0 5004.5 5010.6 5012.9 5054.0 4725.1 4814.4 4839.6 4889.5 4953.5 5024.0 5045.1 5046.8 5047.9 5064.6 4978.2 4988.7 4990.8 4993.1 4995.9 23:14:07 INFO - PROCESS | 5948 | 23:14:07 INFO - PROCESS | 5948 | [#1] ext.html Cycles:180 Average:45429.66 Median:36230.33 stddev:83871.28 (231.5%) stddev-sans-first:84038.84 23:14:07 INFO - PROCESS | 5948 | Values: 542.5 564.2 569.4 579.1 593.6 27324.9 29306.4 29331.0 29369.7 40075.6 36979.0 41205.9 41308.6 41319.0 41444.4 7831.1 7964.0 7968.9 7969.2 7971.3 7851.3 7940.4 7943.1 7945.0 7949.3 7933.8 7946.7 7976.8 7984.7 7987.4 7928.1 7954.6 7957.6 7958.4 7964.3 7730.9 7800.0 7802.2 7806.2 7823.3 38903.6 39297.8 39305.6 39349.8 39370.6 3110.2 3244.2 3260.7 3368.2 3489.0 29462.3 29749.4 29921.4 30243.5 31166.2 3459.3 3531.5 3544.9 3567.0 3618.1 238730.0 238955.0 238960.8 239103.0 240033.0 28083.7 51705.5 51969.2 52078.2 52091.2 32551.2 32629.3 32642.2 32690.7 32714.0 13713.0 13928.7 13958.9 13966.1 13969.1 10807.9 10821.5 10863.7 10877.5 10881.2 7664.7 7712.8 7766.7 7770.5 7772.1 2978.2 3011.7 3027.3 3063.7 3075.2 889.0 910.4 928.5 960.1 969.2 28062.6 28164.0 28556.3 28865.7 28901.9 798.7 971.7 979.5 1025.1 1037.8 14599.2 15057.2 15186.9 15299.7 15346.5 400639.0 402137.0 403939.0 403941.0 404851.0 50276.2 50324.7 50567.0 50800.5 50926.5 1067.1 1176.2 1186.8 1202.1 1210.7 28185.3 29305.3 29609.0 29843.0 30133.4 41920.8 42002.8 42508.8 42509.4 42517.8 286131.0 288376.0 288442.0 288513.0 288997.0 22198.2 22822.5 24641.9 25451.2 25451.4 41464.0 41487.0 41839.0 42619.4 42997.1 35243.0 36230.6 36313.3 36991.4 37421.4 36001.6 36230.1 36477.3 36506.0 36701.0 35698.8 36471.6 36745.4 37204.8 40030.2 29894.1 30443.7 30747.0 30841.8 31186.5 25652.9 25982.2 26111.3 26259.2 26298.7 23:14:07 INFO - PROCESS | 5948 | 23:14:07 INFO - PROCESS | 5948 | [#2] jquery.html Cycles:180 Average:22734.11 Median:4458.50 stddev:56562.34 (1268.6%) stddev-sans-first:56696.06 23:14:07 INFO - PROCESS | 5948 | Values: 357.2 358.3 362.9 372.1 376.3 15839.9 16330.8 17259.6 17271.5 17294.9 14265.6 16688.2 17471.6 17513.9 17521.0 5105.5 5204.3 5219.1 5250.7 5259.6 3709.2 3889.8 5215.9 5832.9 6147.7 6127.1 6141.3 6160.1 6200.8 6204.4 5867.0 6064.2 6071.4 6072.2 6077.5 3332.8 4999.8 5321.1 5325.8 5332.3 20278.2 20807.2 20849.8 20860.5 20909.7 1775.9 1890.9 1977.1 2031.7 2108.4 9101.7 9148.4 9681.3 9689.4 13621.9 1670.4 1756.1 1757.2 1813.2 1874.0 67338.3 68817.0 69390.7 69945.3 71620.0 9863.3 9900.7 9968.9 10106.8 10132.9 13858.8 13939.9 13945.3 13967.6 13989.2 7157.4 7193.7 7289.9 7293.3 7418.2 6722.1 6748.8 6751.4 6877.2 6880.6 4428.4 4433.2 4450.9 4457.1 4459.9 1651.5 1709.9 1774.0 1826.1 1838.9 846.7 849.0 913.6 924.7 959.3 16530.0 16792.0 17335.0 17344.2 17817.4 827.0 851.4 872.1 875.2 925.0 9413.9 9916.6 9983.2 10008.8 10098.6 290104.0 295476.0 296229.0 399416.0 401844.0 54284.7 54435.5 81559.2 85096.1 88511.6 886.5 937.4 952.5 952.8 953.0 16028.7 16047.4 16156.4 16305.8 16635.5 36599.6 36679.4 36804.6 37335.8 38484.0 47015.3 48187.8 48498.8 49035.0 50050.2 7466.3 7731.2 7945.9 7965.8 7978.6 8628.9 11030.2 11764.4 11896.5 12014.7 9998.2 10417.9 10606.9 10767.6 10916.8 9945.8 10033.2 10073.8 10266.7 10561.9 10169.2 10329.4 10474.9 10549.4 10632.7 7812.0 7845.2 7872.3 7905.4 8034.7 9513.3 9916.3 9947.1 10135.2 10237.8 23:14:07 INFO - PROCESS | 5948 | 23:14:07 INFO - PROCESS | 5948 | [#3] mootools.html Cycles:180 Average:5189.15 Median:5614.88 stddev:2936.76 (52.3%) stddev-sans-first:2929.84 23:14:07 INFO - PROCESS | 5948 | Values: 1219.3 1367.4 1413.6 1510.9 1717.5 6943.2 7763.5 8021.0 8039.5 8222.3 7924.6 8080.4 8090.7 8099.7 8101.0 2177.9 2210.8 2220.6 2221.3 2221.5 2130.8 2168.8 2235.3 2238.3 2239.9 2138.7 2155.9 2168.9 2171.4 2200.6 2117.4 2122.9 2143.9 2146.7 2149.6 2139.8 2169.1 2173.1 2175.9 2181.2 7677.6 7894.7 8023.4 8066.3 8106.6 4472.9 4574.4 4574.6 4591.7 4593.4 2921.3 3147.7 3202.8 3214.6 3219.1 949.2 963.7 967.5 970.3 993.9 10674.8 10719.9 10794.1 10862.9 10869.8 4097.9 4099.1 4217.3 4220.5 4226.5 8615.1 8696.8 8709.0 8714.2 8720.6 4552.5 4601.2 4614.4 4649.9 4652.1 3763.5 3774.2 4156.7 4201.5 4257.7 3472.4 3523.1 3565.2 3586.9 3593.7 901.7 910.5 922.5 936.3 1032.0 850.9 861.6 874.5 902.7 913.4 5491.1 5600.7 5649.2 5708.5 5885.5 894.8 895.8 908.4 923.6 942.3 3751.7 3837.7 3843.4 3898.2 3910.0 9749.3 10024.4 10035.7 10110.3 10139.4 9596.6 9985.1 9993.9 9995.2 9996.1 7503.7 7514.2 7549.1 7559.0 7653.1 5514.1 5519.8 5559.4 5629.1 5642.1 9184.7 9374.8 9399.0 9404.2 9407.2 9188.1 9363.3 9630.0 9640.5 9659.4 5259.5 5701.7 6078.8 6082.3 6216.0 7295.0 7340.9 7352.9 7371.1 7379.2 6422.6 6476.7 6601.5 6649.4 6684.6 6503.0 6506.8 6549.8 6578.4 6602.1 6207.9 6255.4 6310.5 6313.0 6322.8 4463.2 4464.6 4670.2 4700.1 4745.8 4734.5 4735.4 4775.1 4829.7 4834.9 23:14:07 INFO - PROCESS | 5948 | 23:14:07 INFO - PROCESS | 5948 | [#4] prototype.html Cycles:180 Average:4052.03 Median:4424.42 stddev:1172.64 (26.5%) stddev-sans-first:1141.32 23:14:07 INFO - PROCESS | 5948 | Values: 284.8 289.9 290.6 306.4 307.2 4645.4 4778.9 4818.6 4830.7 4836.8 4632.8 4742.6 4753.6 4758.4 4758.8 3755.7 3757.7 3771.9 3779.7 3789.5 3589.6 3619.5 3717.6 3773.2 3805.0 3720.6 3736.0 3741.4 3756.4 3766.8 3709.5 3756.0 3762.9 3774.7 3774.8 3297.4 3300.3 3306.9 3310.7 3324.1 4372.5 4677.6 4696.2 4723.7 4727.9 4283.6 4288.3 4296.9 4306.1 4306.5 5055.6 5071.1 5074.2 5077.4 5087.4 3461.6 3621.5 3628.6 3632.7 3642.3 5236.3 5255.2 5273.7 5276.6 5287.4 3694.1 3717.4 3719.8 3727.3 3734.4 3706.6 3810.7 3813.9 3818.1 3824.2 4215.0 4222.6 4234.3 4249.4 4278.0 4999.2 5011.5 5018.2 5022.7 5029.0 1227.6 1258.9 1298.6 1299.1 1300.8 834.6 839.2 846.9 849.1 856.0 3849.0 3897.0 3933.3 3940.3 3953.4 4539.9 4560.3 4600.9 4613.0 4622.8 3953.2 3960.3 3966.6 3969.5 3974.2 2358.5 2426.8 2427.6 2451.7 2477.9 4274.7 4305.6 4319.6 4339.4 4370.9 4690.6 4692.6 4712.4 4714.0 4715.7 4073.9 4339.5 4358.7 4427.4 4436.4 4665.0 4683.0 4696.3 4696.3 4701.4 4563.6 4591.5 4597.6 4600.7 4607.4 4386.0 4421.4 4434.2 4518.3 4900.0 3570.3 3580.5 3588.1 3597.4 3624.6 5034.3 5219.3 5229.4 5278.6 5290.6 4977.5 5065.1 5066.0 5079.3 5079.8 5004.6 5020.6 5036.5 5053.2 5115.0 4825.4 4954.7 4967.9 4989.8 5004.1 5071.4 5103.5 5108.6 5111.2 5112.4 4994.8 5003.4 5003.8 5014.0 5027.6 23:14:07 INFO - PROCESS | 5948 | 23:14:07 INFO - PROCESS | 5948 | [#5] yui.html Cycles:180 Average:16422.55 Median:41692.79 stddev:22267.65 (53.4%) stddev-sans-first:22298.51 23:14:07 INFO - PROCESS | 5948 | Values: 621.7 646.0 710.5 712.4 759.2 12616.4 13318.7 13329.3 13378.6 14180.8 13882.4 14193.5 14194.1 14218.9 14223.9 779.8 789.0 790.7 791.9 793.4 621.2 755.3 777.4 782.8 784.1 817.6 818.2 818.9 819.7 820.8 632.0 813.3 814.2 814.9 815.1 580.8 596.8 597.3 598.2 598.7 13125.8 13164.7 13171.5 13190.1 13216.6 11377.7 11863.5 11932.7 12299.3 12536.8 11766.3 12068.0 12153.2 12187.4 12357.5 3318.9 3360.2 3452.2 3543.0 4099.2 62055.7 62093.0 62294.4 62423.4 62722.4 10280.4 10502.0 10542.5 10554.8 10719.2 18806.0 18979.2 18994.3 19580.7 19590.2 6475.4 6515.9 6912.0 6926.4 6948.9 3550.7 3610.9 3614.5 3666.2 3706.8 2929.4 3031.1 3032.7 3034.4 3037.0 491.9 492.3 494.8 504.7 508.2 498.8 547.6 587.1 588.2 597.4 7390.9 7485.3 7733.9 7735.0 7918.7 498.2 570.3 605.2 614.2 621.3 3464.4 3633.9 3676.7 3699.8 3765.3 66822.7 76092.0 90668.0 91551.0 92026.5 48339.8 72438.6 72835.0 74083.4 74466.6 66597.3 68889.7 71230.2 71640.3 73591.3 8095.4 8242.2 8302.0 8448.0 8709.1 30271.4 41343.8 42041.8 43941.8 44330.0 38982.0 39816.4 56781.7 57694.4 61230.0 10331.8 10370.7 10535.1 10561.2 10774.1 11836.5 12284.4 12387.6 12783.9 12923.9 11398.6 11432.9 11726.8 11853.9 12316.7 11158.4 11364.3 11392.3 11736.1 11746.0 10818.3 11757.1 11782.4 11794.6 11795.3 9709.7 9807.6 9956.9 10294.2 10312.4 10010.3 10126.7 10316.0 10751.5 10759.8 23:14:07 INFO - PROCESS | 5948 | -------- Summary: end -------- 23:14:07 INFO - PROCESS | 5948 | 23:14:08 INFO - TEST-INFO | 5948: exit 0 23:14:08 INFO - TEST-OK | dromaeo_css | took 1121798ms 23:14:08 INFO - TEST-START | kraken 23:14:08 INFO - operating with platform_type : linux_ 23:14:08 INFO - Initialising browser for kraken test... 23:14:08 INFO - TEST-INFO | started process 6125 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRtP7QE/profile http://localhost:47878/getInfo.html) 23:14:13 INFO - PROCESS | 6125 | __metrics Screen width/height:1600/1200 23:14:13 INFO - PROCESS | 6125 | colorDepth:24 23:14:13 INFO - PROCESS | 6125 | Browser inner width/height: 1024/697 23:14:13 INFO - PROCESS | 6125 | __metrics 23:14:14 INFO - PROCESS | 6125 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:14:15 INFO - PROCESS | 6125 | console.error: PushService: 23:14:15 INFO - PROCESS | 6125 | stateChangeProcessEnqueue: Error transitioning state 23:14:15 INFO - PROCESS | 6125 | UnknownError 23:14:15 INFO - TEST-INFO | 6125: exit 0 23:14:15 INFO - Browser initialized. 23:14:15 INFO - Running cycle 1/1 for kraken test... 23:14:15 INFO - TEST-INFO | started process 6205 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRtP7QE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:14:27 INFO - PROCESS | 6205 | RSS: Main: 179261440 23:14:27 INFO - PROCESS | 6205 | 23:14:30 INFO - PROCESS | 6205 | Cycle 1(1): loaded http://localhost:47878/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47878/tests/kraken/driver.html?testName=audio-beat-detection) 23:14:31 INFO - PROCESS | 6205 | RSS: Main: 237596672 23:14:31 INFO - PROCESS | 6205 | 23:14:34 INFO - PROCESS | 6205 | Cycle 1(1): loaded http://localhost:47878/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47878/tests/kraken/driver.html?testName=audio-dft) 23:14:35 INFO - PROCESS | 6205 | RSS: Main: 226119680 23:14:35 INFO - PROCESS | 6205 | 23:14:38 INFO - PROCESS | 6205 | Cycle 1(1): loaded http://localhost:47878/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47878/tests/kraken/driver.html?testName=audio-fft) 23:14:38 INFO - PROCESS | 6205 | RSS: Main: 293076992 23:14:38 INFO - PROCESS | 6205 | 23:14:41 INFO - PROCESS | 6205 | Cycle 1(1): loaded http://localhost:47878/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47878/tests/kraken/driver.html?testName=audio-oscillator) 23:14:41 INFO - PROCESS | 6205 | RSS: Main: 202911744 23:14:41 INFO - PROCESS | 6205 | 23:14:43 INFO - PROCESS | 6205 | Cycle 1(1): loaded http://localhost:47878/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47878/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:14:44 INFO - PROCESS | 6205 | RSS: Main: 206671872 23:14:44 INFO - PROCESS | 6205 | 23:14:47 INFO - PROCESS | 6205 | Cycle 1(1): loaded http://localhost:47878/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47878/tests/kraken/driver.html?testName=imaging-darkroom) 23:14:48 INFO - PROCESS | 6205 | RSS: Main: 242921472 23:14:48 INFO - PROCESS | 6205 | 23:14:51 INFO - PROCESS | 6205 | Cycle 1(1): loaded http://localhost:47878/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47878/tests/kraken/driver.html?testName=imaging-desaturate) 23:14:52 INFO - PROCESS | 6205 | RSS: Main: 238751744 23:14:52 INFO - PROCESS | 6205 | 23:14:55 INFO - PROCESS | 6205 | Cycle 1(1): loaded http://localhost:47878/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47878/tests/kraken/driver.html?testName=json-parse-financial) 23:14:56 INFO - PROCESS | 6205 | RSS: Main: 241864704 23:14:56 INFO - PROCESS | 6205 | 23:14:57 INFO - PROCESS | 6205 | Cycle 1(1): loaded http://localhost:47878/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47878/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:14:58 INFO - PROCESS | 6205 | RSS: Main: 229212160 23:14:58 INFO - PROCESS | 6205 | 23:15:00 INFO - PROCESS | 6205 | Cycle 1(1): loaded http://localhost:47878/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47878/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:15:00 INFO - PROCESS | 6205 | RSS: Main: 231133184 23:15:00 INFO - PROCESS | 6205 | 23:15:03 INFO - PROCESS | 6205 | Cycle 1(1): loaded http://localhost:47878/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47878/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:15:03 INFO - PROCESS | 6205 | RSS: Main: 240484352 23:15:03 INFO - PROCESS | 6205 | 23:15:06 INFO - PROCESS | 6205 | Cycle 1(1): loaded http://localhost:47878/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47878/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:15:07 INFO - PROCESS | 6205 | RSS: Main: 242528256 23:15:07 INFO - PROCESS | 6205 | 23:15:10 INFO - PROCESS | 6205 | Cycle 1(1): loaded http://localhost:47878/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47878/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:15:10 INFO - PROCESS | 6205 | RSS: Main: 241811456 23:15:10 INFO - PROCESS | 6205 | 23:15:12 INFO - PROCESS | 6205 | Cycle 1(1): loaded http://localhost:47878/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47878/tests/kraken/driver.html?testName=ai-astar) 23:15:12 INFO - PROCESS | 6205 | RSS: Main: 286126080 23:15:12 INFO - PROCESS | 6205 | 23:15:12 INFO - PROCESS | 6205 | __start_tp_report 23:15:12 INFO - PROCESS | 6205 | _x_x_mozilla_page_load 23:15:12 INFO - PROCESS | 6205 | _x_x_mozilla_page_load_details 23:15:12 INFO - PROCESS | 6205 | |i|pagename|runs| 23:15:12 INFO - PROCESS | 6205 | |0;ai-astar;112;107;113;114;114;114;115;112;115;112 23:15:12 INFO - PROCESS | 6205 | |1;audio-beat-detection;167;138;138;198;129;157;158;133;189;137 23:15:12 INFO - PROCESS | 6205 | |2;audio-dft;161;161;161;170;162;161;162;162;162;160 23:15:12 INFO - PROCESS | 6205 | |3;audio-fft;77;79;76;106;76;77;77;75;101;77 23:15:12 INFO - PROCESS | 6205 | |4;audio-oscillator;88;87;130;86;86;86;117;85;86;123 23:15:12 INFO - PROCESS | 6205 | |5;imaging-gaussian-blur;117;117;117;117;117;117;116;116;117;117 23:15:12 INFO - PROCESS | 6205 | |6;imaging-darkroom;147;147;147;146;147;146;146;147;146;147 23:15:12 INFO - PROCESS | 6205 | |7;imaging-desaturate;94;94;95;94;94;95;95;95;95;95 23:15:12 INFO - PROCESS | 6205 | |8;json-parse-financial;63;63;64;63;65;63;63;62;62;63 23:15:12 INFO - PROCESS | 6205 | |9;json-stringify-tinderbox;66;66;66;67;68;67;65;66;66;67 23:15:12 INFO - PROCESS | 6205 | |10;stanford-crypto-aes;83;82;88;87;86;85;84;85;117;86 23:15:12 INFO - PROCESS | 6205 | |11;stanford-crypto-ccm;132;135;145;133;131;143;131;143;156;133 23:15:12 INFO - PROCESS | 6205 | |12;stanford-crypto-pbkdf2;175;179;175;169;174;172;172;173;169;170 23:15:12 INFO - PROCESS | 6205 | |13;stanford-crypto-sha256-iterative;68;67;66;70;66;66;68;68;65;70 23:15:12 INFO - PROCESS | 6205 | __end_tp_report 23:15:12 INFO - PROCESS | 6205 | __start_cc_report 23:15:12 INFO - PROCESS | 6205 | _x_x_mozilla_cycle_collect,1923 23:15:12 INFO - PROCESS | 6205 | __end_cc_report 23:15:12 INFO - PROCESS | 6205 | __startTimestamp1465971312761__endTimestamp 23:15:12 INFO - PROCESS | 6205 | 23:15:12 INFO - PROCESS | 6205 | ------- Summary: start ------- 23:15:12 INFO - PROCESS | 6205 | Number of tests: 14 23:15:12 INFO - PROCESS | 6205 | 23:15:12 INFO - PROCESS | 6205 | [#0] ai-astar Cycles:10 Average:112.80 Median:114.00 stddev:2.35 (2.1%) stddev-sans-first:2.47 23:15:12 INFO - PROCESS | 6205 | Values: 112.0 107.0 113.0 114.0 114.0 114.0 115.0 112.0 115.0 112.0 23:15:12 INFO - PROCESS | 6205 | 23:15:12 INFO - PROCESS | 6205 | [#1] audio-beat-detection Cycles:10 Average:154.40 Median:157.50 stddev:24.08 (15.3%) stddev-sans-first:25.11 23:15:12 INFO - PROCESS | 6205 | Values: 167.0 138.0 138.0 198.0 129.0 157.0 158.0 133.0 189.0 137.0 23:15:12 INFO - PROCESS | 6205 | 23:15:12 INFO - PROCESS | 6205 | [#2] audio-dft Cycles:10 Average:162.20 Median:162.00 stddev:2.82 (1.7%) stddev-sans-first:2.96 23:15:12 INFO - PROCESS | 6205 | Values: 161.0 161.0 161.0 170.0 162.0 161.0 162.0 162.0 162.0 160.0 23:15:12 INFO - PROCESS | 6205 | 23:15:12 INFO - PROCESS | 6205 | [#3] audio-fft Cycles:10 Average:82.10 Median:77.00 stddev:11.39 (14.8%) stddev-sans-first:11.93 23:15:12 INFO - PROCESS | 6205 | Values: 77.0 79.0 76.0 106.0 76.0 77.0 77.0 75.0 101.0 77.0 23:15:12 INFO - PROCESS | 6205 | 23:15:12 INFO - PROCESS | 6205 | [#4] audio-oscillator Cycles:10 Average:97.40 Median:86.00 stddev:18.17 (21.1%) stddev-sans-first:18.95 23:15:12 INFO - PROCESS | 6205 | Values: 88.0 87.0 130.0 86.0 86.0 86.0 117.0 85.0 86.0 123.0 23:15:12 INFO - PROCESS | 6205 | 23:15:12 INFO - PROCESS | 6205 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 23:15:12 INFO - PROCESS | 6205 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 116.0 116.0 117.0 117.0 23:15:12 INFO - PROCESS | 6205 | 23:15:12 INFO - PROCESS | 6205 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 23:15:12 INFO - PROCESS | 6205 | Values: 147.0 147.0 147.0 146.0 147.0 146.0 146.0 147.0 146.0 147.0 23:15:12 INFO - PROCESS | 6205 | 23:15:12 INFO - PROCESS | 6205 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 23:15:12 INFO - PROCESS | 6205 | Values: 94.0 94.0 95.0 94.0 94.0 95.0 95.0 95.0 95.0 95.0 23:15:12 INFO - PROCESS | 6205 | 23:15:12 INFO - PROCESS | 6205 | [#8] json-parse-financial Cycles:10 Average:63.10 Median:63.00 stddev:0.88 (1.4%) stddev-sans-first:0.93 23:15:12 INFO - PROCESS | 6205 | Values: 63.0 63.0 64.0 63.0 65.0 63.0 63.0 62.0 62.0 63.0 23:15:12 INFO - PROCESS | 6205 | 23:15:12 INFO - PROCESS | 6205 | [#9] json-stringify-tinderbox Cycles:10 Average:66.40 Median:66.50 stddev:0.84 (1.3%) stddev-sans-first:0.88 23:15:12 INFO - PROCESS | 6205 | Values: 66.0 66.0 66.0 67.0 68.0 67.0 65.0 66.0 66.0 67.0 23:15:12 INFO - PROCESS | 6205 | 23:15:12 INFO - PROCESS | 6205 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:85.50 stddev:10.24 (12.0%) stddev-sans-first:10.68 23:15:12 INFO - PROCESS | 6205 | Values: 83.0 82.0 88.0 87.0 86.0 85.0 84.0 85.0 117.0 86.0 23:15:12 INFO - PROCESS | 6205 | 23:15:12 INFO - PROCESS | 6205 | [#11] stanford-crypto-ccm Cycles:10 Average:138.20 Median:139.00 stddev:8.27 (5.9%) stddev-sans-first:8.46 23:15:12 INFO - PROCESS | 6205 | Values: 132.0 135.0 145.0 133.0 131.0 143.0 131.0 143.0 156.0 133.0 23:15:12 INFO - PROCESS | 6205 | 23:15:12 INFO - PROCESS | 6205 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.80 Median:173.50 stddev:3.12 (1.8%) stddev-sans-first:3.21 23:15:12 INFO - PROCESS | 6205 | Values: 175.0 179.0 175.0 169.0 174.0 172.0 172.0 173.0 169.0 170.0 23:15:12 INFO - PROCESS | 6205 | 23:15:12 INFO - PROCESS | 6205 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:1.71 (2.5%) stddev-sans-first:1.80 23:15:12 INFO - PROCESS | 6205 | Values: 68.0 67.0 66.0 70.0 66.0 66.0 68.0 68.0 65.0 70.0 23:15:12 INFO - PROCESS | 6205 | -------- Summary: end -------- 23:15:12 INFO - PROCESS | 6205 | 23:15:13 INFO - TEST-INFO | 6205: exit 0 23:15:13 INFO - TEST-OK | kraken | took 65543ms 23:15:13 INFO - SUITE-END | took 1187s 23:15:14 INFO - Completed test suite (00:19:47) 23:15:14 INFO - javascript benchmark 23:15:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2627.2643659775263, 2632.644592979947, 2667.239836646555, 2668.9332766680836, 2677.6385187999613, 4580.244259697146, 4661.836835710751, 4716.386869706933, 4739.336492890994, 4814.590759785418, 4685.882852928678, 4706.176419126657, 4709.952900470994, 4766.52334766524, 4785.21044027735, 4298.204832106063, 4327.935080973788, 4348.847790327339, 4383.013821890065, 4537.2740361542155, 4433.600975912167, 4434.512203657595, 4473.575010374026, 4477.395551600541, 4501.819927202924, 4245.066085461193, 4308.418363520947, 4381.07997320563, 4418.4256046714045, 4455.866324010285, 4329.913401731979, 4341.153475072362, 4357.694961352707, 4377.102693947765, 4391.6047555719815, 4352.303631418989, 4381.298992161239, 4409.228385032607, 4410.75740834254, 4426.136903303857, 4661.137689527414, 4669.369635099252, 4726.0784147091335, 4787.066522028206, 4833.202521583935, 5065.442801291855, 5130.461301563357, 5137.7944882204665, 5141.460146684619, 5150.768215430315, 5129.5383415492415, 5158.509941555546, 5168.198929165986, 5172.715500647463, 5172.8965420691375, 4655.9291362986705, 4690.202665546865, 4715.669903106749, 4750.311204875274, 4808.447028591721, 5215.426303106655, 5235.293235413246, 5268.051750684837, 5275.103232450492, 5286.47135286476, 4987.725675087832, 5022.547970682656, 5037.924431133536, 5044.9495505045215, 5051.217061355495, 4907.214845624754, 4954.727489988013, 5038.269450929594, 5041.269015992661, 5118.564921981673, 5011.599072074226, 5013.573846223112, 5017.724025178651, 5021.84934451967, 5032.748362581856, 4970.15507363749, 4973.0, 4975.5522003019905, 4987.301777751118, 5007.24891266313, 4203.07532342884, 4247.660187185018, 4251.0, 4292.806823692941, 4338.82644694215, 3763.8118094095184, 3794.4498047782918, 3807.562130354989, 3810.3903375459795, 3810.580836108026, 4309.6552275818485, 4350.2387082260475, 4360.084382279757, 4366.716163449366, 4370.737755734666, 5162.096633089194, 5163.66436181647, 5166.9224961625605, 5175.715335656575, 5214.139666954986, 4232.449781528443, 4368.803403846835, 4375.956240437618, 4377.846775362847, 4423.646108311327, 4529.5243999380245, 4549.681522293474, 4576.336431217454, 4580.496145424001, 4587.495375508692, 4783.569478746929, 4784.40194975628, 4789.568938795525, 4791.928121078117, 4844.4186697596515, 4859.319695242598, 5095.974520127375, 5127.17965125593, 5135.127028405105, 5138.3833939927445, 4796.616270698406, 4819.903601928012, 4840.588549973291, 4842.201036828884, 4894.681845680019, 5050.090983622837, 5051.722155281348, 5061.797528098835, 5075.746212689277, 5106.693598384109, 4942.579880710036, 5037.798488060436, 5140.897182056263, 5158.561522270649, 5207.479252074762, 4943.975280123576, 4974.925376119289, 4989.9251511228085, 4992.600591952708, 5032.773525191302, 4993.0, 5004.499550044967, 5010.624203184849, 5012.924806127838, 5054.0, 4725.10223057618, 4814.422269327703, 4839.588634965927, 4889.535494128052, 4953.529414705597, 5023.974880125576, 5045.091883460866, 5046.823361182488, 5047.87380315495, 5064.645474816727, 4978.153713868724, 4988.700677959334, 4990.8003679853855, 4993.051320249141, 4995.900081998412], "value": 4675.78648598997, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [542.4846395923871, 564.1875698993449, 569.4334137533153, 579.0705917003207, 593.6408472873911, 27324.86337568312, 29306.413871722554, 29330.999999999975, 29369.70630293699, 40075.59924400755, 36979.0, 41205.90955678321, 41308.58691413085, 41319.0, 41444.37833432501, 7831.138574756772, 7963.960180199091, 7968.8804667929735, 7969.163237860028, 7971.322437592811, 7851.293383595504, 7940.404469664798, 7943.086545047336, 7945.046594408679, 7949.324307433875, 7933.761987140395, 7946.6821327146845, 7976.840463190732, 7984.720534781284, 7987.361011119126, 7928.088269848955, 7954.602269886483, 7957.562334071624, 7958.4429089964015, 7964.283214510722, 7730.884036739453, 7800.0, 7802.180771019075, 7806.219378062206, 7823.256790604941, 38903.610963890285, 39297.80351098255, 39305.60694393048, 39349.80325098356, 39370.606293937264, 3110.237991692027, 3244.2213868671583, 3260.7228385587255, 3368.1747971747077, 3488.982555087234, 29462.26344341387, 29749.405011899857, 29921.401571968658, 30243.546346804815, 31166.22084447884, 3459.3081383723356, 3531.523244362004, 3544.8759293424605, 3566.982165089184, 3618.095476130967, 238730.0, 238955.0, 238960.80519597465, 239103.0, 240033.0, 28083.71916280811, 51705.48294517082, 51969.220461693105, 52078.21882671763, 52091.218631720556, 32551.18622034468, 32629.347413051604, 32642.183945401554, 32690.673093269237, 32714.0, 13713.0, 13928.651783705489, 13958.930205348908, 13966.092204006707, 13969.092009019381, 10807.891921080845, 10821.458927053616, 10863.728406789736, 10877.4771531984, 10881.238313317992, 7664.65509052094, 7712.845743085107, 7766.728164514215, 7770.53376797394, 7772.106207786064, 2978.240548660078, 3011.713887180714, 3027.3188532579993, 3063.6783137770212, 3075.2158199658943, 888.9909952204295, 910.3627460777482, 928.5125309212717, 960.1071003966239, 969.185883857563, 28062.579061314096, 28164.0, 28556.286092847844, 28865.711342886723, 28901.85549072283, 798.7204478432618, 971.689059500967, 979.5249304087512, 1025.0825511167614, 1037.818381783185, 14599.19704416246, 15057.17185554805, 15186.924065379824, 15299.672720913339, 15346.53960381168, 400639.0, 402137.0, 403939.0, 403941.0, 404851.0, 50276.24585631218, 50324.748376257885, 50567.0, 50800.491995080316, 50926.49073509291, 1067.1302888146136, 1176.1825531255865, 1186.8279099530691, 1202.1164444133556, 1210.6791700199453, 28185.295367615974, 29305.267368315963, 29609.0, 29843.0, 30133.3973320528, 41920.79039604782, 42002.78998604987, 42508.78745606252, 42509.36235956401, 42517.787411062745, 286131.0, 288376.0, 288442.0, 288513.0, 288997.0, 22198.223062192745, 22822.543549128593, 24641.876790615934, 25451.23646290614, 25451.36371590651, 41464.0, 41487.0, 41839.0, 42619.360709588764, 42997.140057198056, 35243.0, 36230.55809698074, 36313.273734524635, 36991.445128323634, 37421.43867842039, 36001.63998360088, 36230.09424764402, 36477.27045459129, 36506.0, 36701.0, 35698.8215058923, 36471.63528364682, 36745.448818267214, 37204.813975929945, 40030.1993960125, 29894.103176904726, 30443.695563044086, 30747.0, 30841.8457907718, 31186.532202017443, 25652.8717356412, 25982.22053338327, 26111.347216318984, 26259.21222363332, 26298.73701262963], "value": 16389.374279329673, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.1891805601286, 358.3460185162079, 362.92015756533556, 372.13293027246516, 376.27003612990796, 15839.920800395996, 16330.83669163308, 17259.56851078722, 17271.481855544323, 17294.91352543239, 14265.643358916032, 16688.165591720397, 17471.563210919732, 17513.91243043783, 17521.03634300113, 5105.5149760772665, 5204.271402003723, 5219.138842091051, 5250.7112108834, 5259.6055295852775, 3709.202521457886, 3889.766614003169, 5215.947840521603, 5832.912506312408, 6147.661878596676, 6127.111568822518, 6141.3244543100445, 6160.1375807387, 6200.81397558074, 6204.410580994799, 5867.0, 6064.151018857364, 6071.423214794588, 6072.180255665473, 6077.453029227391, 3332.7667063305335, 4999.75001249936, 5321.122014867542, 5325.760340784674, 5332.33345831771, 20278.188872445084, 20807.167713291452, 20849.791502084936, 20860.47848803777, 20909.68635470454, 1775.9200835962413, 1890.9149088290922, 1977.1399441243104, 2031.7358743363416, 2108.441263065289, 9101.7269481915, 9148.359614826964, 9681.273904457194, 9689.41863488193, 13621.863781362159, 1670.4070055130376, 1756.0780590190125, 1757.156564848867, 1813.150586401876, 1874.0161415256962, 67338.3266167337, 68817.0, 69390.65304673495, 69945.3005469949, 71619.99999999948, 9863.260255480867, 9900.702978910644, 9968.903420623725, 10106.797864042677, 10132.949335253277, 13858.792118118236, 13939.860601393857, 13945.302734863217, 13967.580972570839, 13989.160650360807, 7157.4274058075225, 7193.748218812317, 7289.927100729031, 7293.307135822089, 7418.183999760022, 6722.092517510171, 6748.831279218059, 6751.358620931003, 6877.243503214642, 6880.58716477013, 4428.402165707653, 4433.179861725591, 4450.858422493157, 4457.1308594823695, 4459.866204013885, 1651.5045486354045, 1709.8717596180336, 1773.9911300443673, 1826.1234607388528, 1838.9080545972915, 846.6951897316961, 848.9915100848913, 913.6299798581585, 924.7456949338986, 959.3284700709531, 16530.0, 16792.0, 17335.0, 17344.219510122075, 17817.376391826485, 826.9875951860727, 851.3614788908319, 872.1322284327008, 875.1598465473216, 925.000998921177, 9413.858792118124, 9916.603335866628, 9983.201343892471, 10008.799824003625, 10098.646547370954, 290104.0, 295476.0, 296229.0, 399416.0, 401844.0, 54284.728576356865, 54435.45564544304, 81559.18440815635, 85096.14903850883, 88511.55744221238, 886.4902680958475, 937.3813283233034, 952.5427794658464, 952.828490871636, 953.0041107043156, 16028.67942641164, 16047.358105676109, 16156.434524791575, 16305.755413668567, 16635.50093497197, 36599.634003660685, 36679.449808253434, 36804.631953680124, 37335.81332093322, 38484.0, 47015.29477057915, 48187.7705466901, 48498.75750621224, 49035.0, 50050.249246262065, 7466.290702383265, 7731.226877312224, 7945.920540794518, 7965.800854978672, 7978.6037443446, 8628.913710863062, 11030.172737044913, 11764.35296058725, 11896.524139034342, 12014.699632509257, 9998.150157236716, 10417.895821041693, 10606.946965265124, 10767.6231331903, 10916.781664366827, 9945.801083978424, 10033.247506437192, 10073.798524029626, 10266.743331416475, 10561.947190264, 10169.237307201842, 10329.431881246604, 10474.947625261824, 10549.419781912069, 10632.734181645521, 7812.023497062867, 7845.176256493214, 7872.330851877655, 7905.4466187368635, 8034.718784842503, 9513.33406661527, 9916.256280778825, 9947.104760571583, 10135.189184865341, 10237.795244095225], "value": 8230.238566073813, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1219.3293688471338, 1367.364175658319, 1413.6112569043512, 1510.9244537773116, 1717.45900041487, 6943.201531823842, 7763.534187948727, 8020.959895200516, 8039.517628942261, 8222.259996600305, 7924.603769811515, 8080.3939704522245, 8090.6763729450895, 8099.676012959475, 8101.0, 2177.912883484659, 2210.801027907488, 2220.600291947449, 2221.2669818959785, 2221.466847956487, 2130.7762684918016, 2168.793964573371, 2235.284708893155, 2238.2613737466595, 2239.9328020159423, 2138.721966144407, 2155.9029843656995, 2168.934931952044, 2171.4462811983003, 2200.570888676709, 2117.3965419855344, 2122.946926326839, 2143.9249626263004, 2146.6672665736837, 2149.57008598281, 2139.7860213978634, 2169.0781417897488, 2173.1307477009163, 2175.945601359963, 2181.19295860531, 7677.577733224671, 7894.723684671067, 8023.398245131581, 8066.274035336789, 8106.594670266522, 4472.932906006413, 4574.428196475441, 4574.565416285448, 4591.6556258280425, 4593.379893714339, 2921.2842853500815, 3147.7481801456056, 3202.759793015533, 3214.582104326446, 3219.114743445548, 949.2074118111316, 963.6530848894392, 967.5017366056486, 970.3498655900563, 993.8558908958161, 10674.78650426987, 10719.892801071968, 10794.082502987256, 10862.945685271523, 10869.83695244572, 4097.938530922039, 4099.077707515785, 4217.283061879488, 4220.514640816284, 4226.513950895686, 8615.095414981337, 8696.782580435412, 8708.95645521781, 8714.172153645393, 8720.563971801384, 4552.453705555355, 4601.240795268744, 4614.400127983341, 4649.86050418488, 4652.092841895797, 3763.5295588051495, 3774.169682669808, 4156.729812562174, 4201.516825565038, 4257.680673949404, 3472.374972504923, 3523.1016090896655, 3565.23347480293, 3586.9103272418397, 3593.6765691087926, 901.693424235763, 910.4946754551288, 922.520289449496, 936.283742936654, 1031.9587216511256, 850.8808766772656, 861.5778268648443, 874.4796845876693, 902.7111324376136, 913.36064754672, 5491.121420572769, 5600.663960162403, 5649.209110724501, 5708.543316534668, 5885.499732522783, 894.789724414762, 895.7536677413764, 908.4004556992486, 923.6213152607532, 942.2697409507687, 3751.7431660393527, 3837.654611085016, 3843.385058390644, 3898.181381909774, 3909.9609003909595, 9749.317547771589, 10024.398536087858, 10035.69892903214, 10110.292279540508, 10139.442330671884, 9596.568154433067, 9985.101340879355, 9993.850092248622, 9995.150412215044, 9996.050375214343, 7503.737369192161, 7514.211007844207, 7549.0941087069905, 7558.99999999989, 7653.0816302042995, 5514.09017512106, 5519.751611177587, 5559.4162612926675, 5629.099344104923, 5642.125470552071, 9184.678536251466, 9374.765630859283, 9398.953005235204, 9404.200642945427, 9407.2003879671, 9188.081191880758, 9363.344565880596, 9630.036996300314, 9640.469774162488, 9659.372140810825, 5259.47405259471, 5701.710612986228, 6078.848028799315, 6082.300535438481, 6216.0, 7294.96352518234, 7340.853182936418, 7352.926470735438, 7371.078615173104, 7379.225181356095, 6422.6467544285515, 6476.740930362733, 6601.53789234749, 6649.434798042221, 6684.63234522106, 6503.0, 6506.837329066622, 6549.803505894831, 6578.407943285128, 6602.10871532337, 6207.906881396692, 6255.37446255389, 6310.49516038725, 6313.021481670348, 6322.841928951629, 4463.196624607602, 4464.598186163261, 4670.1827180244, 4700.106979673851, 4745.786439610157, 4734.479207287127, 4735.384400027974, 4775.116603428377, 4829.73436460998, 4834.854954351375], "value": 4163.280292689186, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [284.7833718153457, 289.87535359795294, 290.5975224314325, 306.38263898245026, 307.20740489536996, 4645.442546894377, 4778.9283160752575, 4818.638866955193, 4830.661853670244, 4836.8065277388905, 4632.768361581916, 4742.644301677371, 4753.643476739241, 4758.357621721067, 4758.833440829571, 3755.699544036484, 3757.736958412919, 3771.924561508782, 3779.6787273081823, 3789.5263092113487, 3589.569251689788, 3619.5475565554307, 3717.646823551758, 3773.1699026214187, 3804.9619503805025, 3720.627937206285, 3735.9626403736024, 3741.3639681254117, 3756.417755247941, 3766.7928263945473, 3709.4992176056294, 3756.0, 3762.887113386603, 3774.7168962327937, 3774.75464094833, 3297.4394352960053, 3300.273939733255, 3306.933861322784, 3310.735141188723, 3324.1024923270584, 4372.497162826284, 4677.6257899367965, 4696.178168820443, 4723.724594359538, 4727.896086241624, 4283.550227226127, 4288.270993931039, 4296.892577685551, 4306.1053136619785, 4306.4832220133785, 5055.646104772667, 5071.137906555894, 5074.238864170404, 5077.390713114413, 5087.440381558025, 3461.6192218855904, 3621.529201203853, 3628.5827129880136, 3632.6730594246646, 3642.289753498069, 5236.266922630834, 5255.159174532057, 5273.734303767106, 5276.604254680914, 5287.4448182941005, 3694.131879008431, 3717.423799311111, 3719.776813391205, 3727.2545490901384, 3734.3651579231596, 3706.5737440194184, 3810.6951443884423, 3813.923721525554, 3818.0836599215986, 3824.1778017725765, 4214.978925105354, 4222.59885310895, 4234.25900467417, 4249.426327445817, 4278.0, 4999.225120106389, 5011.473795251519, 5018.222175562793, 5022.698638081727, 5028.974855125774, 1227.5580790915262, 1258.8615252322238, 1298.5779621622821, 1299.0971274963808, 1300.843898732158, 834.5805786557509, 839.2237180607913, 846.9068402475723, 849.0533055642913, 855.9957200213959, 3849.0, 3897.012523612978, 3933.3116704576582, 3940.2513522430645, 3953.367461206193, 4539.931901021488, 4560.338750881104, 4600.930986035212, 4612.95387046132, 4622.768861556976, 3953.2093581283857, 3960.32674445345, 3966.5636779954184, 3969.543502497192, 3974.2250261198787, 2358.481134150484, 2426.7916196261317, 2427.5630386530593, 2451.7373552620415, 2477.8513289202706, 4274.7435153890865, 4305.590968857953, 4319.596131257354, 4339.435873336446, 4370.929122365041, 4690.5693763401805, 4692.6245900327895, 4712.363830882787, 4714.0, 4715.74063426508, 4073.918521629551, 4339.479262488521, 4358.694891357574, 4427.424434823452, 4436.447243464761, 4664.970737729099, 4682.953170468388, 4696.2955556666775, 4696.319033740157, 4701.435827700561, 4563.566461186182, 4591.517890621437, 4597.563231493003, 4600.65495087863, 4607.354970304227, 4385.956140438682, 4421.425214722066, 4434.17967675981, 4518.322251662277, 4900.0, 3570.2859428115003, 3580.534530511017, 3588.12091037687, 3597.352476554245, 3624.6012938577264, 5034.320366750589, 5219.2953951216095, 5229.424763275961, 5278.601170689693, 5290.6032047597355, 4977.4773648767805, 5065.075271396846, 5065.974670126626, 5079.314292570603, 5079.847604571869, 5004.649674522894, 5020.623453240948, 5036.496350364934, 5053.2420136979745, 5114.974425127851, 4825.445073816556, 4954.727489988085, 4967.875803104951, 4989.800407983786, 5004.1492946198505, 5071.416787069535, 5103.515166059218, 5108.56577190943, 5111.156659151198, 5112.3865136183895, 4994.800207991785, 5003.374578177728, 5003.77483013258, 5014.0, 5027.572656324253], "value": 3683.202768971277, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [621.7388696747363, 645.9903101453476, 710.4884483172115, 712.4015826705566, 759.1914610939349, 12616.369181540933, 13318.667033324171, 13329.266890321029, 13378.59864204073, 14180.787288190686, 13882.4447022119, 14193.503227387044, 14194.148351098942, 14218.857811421884, 14223.857761422383, 779.7660701789468, 789.001912580586, 790.6679194738224, 791.9406044546682, 793.3653077537975, 621.2048577820398, 755.2824816424419, 777.4363586399871, 782.8121250899771, 784.051297929506, 817.5789468423767, 818.2390376949435, 818.8976377952756, 819.7458787775809, 820.7865954851721, 631.9526035547352, 813.3127507256359, 814.2305521282361, 814.861473549498, 815.0911733417256, 580.8112363481843, 596.84780381003, 597.2892258212756, 598.186466405688, 598.7126179433897, 13125.803112953314, 13164.736705265937, 13171.538996135088, 13190.142640728418, 13216.603501894959, 11377.658670239905, 11863.466144023454, 11932.70168245792, 12299.262044277371, 12536.81194782079, 11766.294022358687, 12068.0, 12153.210041347284, 12187.39063046853, 12357.505699771998, 3318.867245310209, 3360.1935535471557, 3452.188735647121, 3543.0433782878736, 4099.241640296555, 62055.68972155156, 62093.0, 62294.377056229765, 62423.375766242214, 62722.37277627211, 10280.434576098387, 10502.002309780559, 10542.525586348633, 10554.841677374692, 10719.19606029551, 18806.0, 18979.24083036663, 18994.335198267996, 19580.70628940567, 19590.21639134447, 6475.364814512466, 6515.869682606416, 6912.032315475837, 6926.445884329241, 6948.861022779617, 3550.715942724576, 3610.891673249807, 3614.530111085542, 3666.2484190740834, 3706.814659267026, 2929.39947310805, 3031.1361262040214, 3032.69673032695, 3034.3779525197283, 3037.0, 491.87948952506866, 492.32059757534375, 494.78971437139074, 504.74762618690653, 508.2274942088006, 498.8303976647957, 547.6248769592842, 587.1017343464421, 588.2323567744049, 597.3638075449633, 7390.889136662954, 7485.288897554724, 7733.922660773433, 7734.961325193451, 7918.683252669829, 498.21531088534977, 570.292836882263, 605.2041565341561, 614.18927016338, 621.2762132116125, 3464.3590935676475, 3633.94549081764, 3676.7426280160666, 3699.7780133192095, 3765.273168335972, 66822.66588667025, 76092.0, 90668.0, 91551.0, 92026.53986730023, 48339.75830120827, 72438.63780681063, 72835.0, 74083.40708075192, 74466.62766686131, 66597.33402666009, 68889.65555172293, 71230.15079396767, 71640.28359716441, 73591.26408735951, 8095.433319667803, 8242.216989386, 8302.0, 8447.95776021116, 8709.12908709124, 30271.394572108875, 41343.7932810334, 42041.78979105085, 43941.78029109834, 44330.0, 38982.0, 39816.40275395929, 56781.71609141928, 57694.42305576891, 61230.0, 10331.845022324822, 10370.688879333633, 10535.104516116216, 10561.155107591221, 10774.084202842532, 11836.467358968695, 12284.385780711107, 12387.62837114888, 12783.93608031991, 12923.935380323039, 11398.601048963246, 11432.942835286103, 11726.765464690829, 11853.881461185278, 12316.692082698004, 11158.386288754196, 11364.261323013978, 11392.316461012366, 11736.119791015537, 11746.0, 10818.29681070728, 11757.118216133995, 11782.410879456163, 11794.587189448328, 11795.29228246308, 9709.70870873875, 9807.607695692092, 9956.900430995598, 10294.227932904912, 10312.381257124296, 10010.349327293703, 10126.746831329276, 10316.0, 10751.462426878781, 10759.838602420814], "value": 6429.656947873908, "unit": "score"}], "name": "dromaeo_css", "value": 6294.401075520601, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 107.0, 113.0, 114.0, 114.0, 114.0, 115.0, 112.0, 115.0, 112.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 138.0, 138.0, 198.0, 129.0, 157.0, 158.0, 133.0, 189.0, 137.0], "value": 154.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 170.0, 162.0, 161.0, 162.0, 162.0, 162.0, 160.0], "value": 162.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 79.0, 76.0, 106.0, 76.0, 77.0, 77.0, 75.0, 101.0, 77.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 130.0, 86.0, 86.0, 86.0, 117.0, 85.0, 86.0, 123.0], "value": 97.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 146.0, 147.0, 146.0, 146.0, 147.0, 146.0, 147.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 64.0, 63.0, 65.0, 63.0, 63.0, 62.0, 62.0, 63.0], "value": 63.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 67.0, 68.0, 67.0, 65.0, 66.0, 66.0, 67.0], "value": 66.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 88.0, 87.0, 86.0, 85.0, 84.0, 85.0, 117.0, 86.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 135.0, 145.0, 133.0, 131.0, 143.0, 131.0, 143.0, 156.0, 133.0], "value": 138.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 179.0, 175.0, 169.0, 174.0, 172.0, 172.0, 173.0, 169.0, 170.0], "value": 172.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 70.0, 66.0, 66.0, 68.0, 68.0, 65.0, 70.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1563.1000000000001, "alertThreshold": 2.0}]} 23:15:14 INFO - Return code: 0 23:15:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:15:14 INFO - # TBPL SUCCESS # 23:15:14 INFO - Running post-action listener: _resource_record_post_action 23:15:14 INFO - ##### 23:15:14 INFO - ##### Finished run-tests step (success) 23:15:14 INFO - ##### 23:15:14 INFO - Running post-run listener: _resource_record_post_run 23:15:14 INFO - Total resource usage - Wall time: 1205s; CPU: 13.0%; Read bytes: 8192; Write bytes: 539889664; Read time: 708; Write time: 1690444 23:15:14 INFO - TinderboxPrint: CPU usage
13.3% 23:15:14 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 708 23:15:14 INFO - TinderboxPrint: I/O write bytes / time
539,889,664 / 1,690,444 23:15:14 INFO - TinderboxPrint: CPU idle
8,332.3 (86.6%) 23:15:14 INFO - TinderboxPrint: CPU user
1,220.6 (12.7%) 23:15:14 INFO - TinderboxPrint: Swap in / out
0 / 0 23:15:14 INFO - install - Wall time: 17s; CPU: 21.0%; Read bytes: 4096; Write bytes: 245686272; Read time: 80; Write time: 1156508 23:15:14 INFO - run-tests - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 294203392; Read time: 628; Write time: 533936 23:15:15 INFO - Running post-run listener: _upload_blobber_files 23:15:15 INFO - Blob upload gear active. 23:15:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:15:15 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 23:15:15 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', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:15:15 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 Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:15:15 INFO - (blobuploader) - INFO - Open directory for files ... 23:15:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:15:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:15:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:15:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:15:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:15:19 INFO - (blobuploader) - INFO - Done attempting. 23:15:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:15:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:15:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:15:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:15:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:15:20 INFO - (blobuploader) - INFO - Done attempting. 23:15:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:15:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:15:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:15:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:15:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:15:20 INFO - (blobuploader) - INFO - Done attempting. 23:15:20 INFO - (blobuploader) - INFO - Iteration through files over. 23:15:20 INFO - Return code: 0 23:15:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:15:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:15:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/8255044509f8e7b40299c59f36b7ed7bd980cd1c42e13e4bfcec197e883a0ce5e04a01bfc7d58aaf4c37cfc63b8fdb6ab424432c660e7bc8b14563af28392eb7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6c9e487519e51e41ddf226fe7b0e82b986ad6b807a3a408fd0e602373a5ff605875b3b54c7eda9065702c17f7092a60ce3f2148931d697a2ccf08a21ac31f5b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/661a1ca76dc5e40dabd637391230cf676b84fe5dc728d343d63562c0379785c7020be37f1c06b0cadc23532e9b27a7dcf6e576137d888f29dce03e98caa4c998"} 23:15:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:15:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:15:20 INFO - Contents: 23:15:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/8255044509f8e7b40299c59f36b7ed7bd980cd1c42e13e4bfcec197e883a0ce5e04a01bfc7d58aaf4c37cfc63b8fdb6ab424432c660e7bc8b14563af28392eb7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6c9e487519e51e41ddf226fe7b0e82b986ad6b807a3a408fd0e602373a5ff605875b3b54c7eda9065702c17f7092a60ce3f2148931d697a2ccf08a21ac31f5b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/661a1ca76dc5e40dabd637391230cf676b84fe5dc728d343d63562c0379785c7020be37f1c06b0cadc23532e9b27a7dcf6e576137d888f29dce03e98caa4c998"} 23:15:20 INFO - Running post-run listener: copy_logs_to_upload_dir 23:15:20 INFO - Copying logs to upload dir... 23:15:20 INFO - mkdir: /builds/slave/test/build/upload/logs 23:15:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.457882 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-06-14 23:15:20.712978) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-14 23:15:20.717195) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/8255044509f8e7b40299c59f36b7ed7bd980cd1c42e13e4bfcec197e883a0ce5e04a01bfc7d58aaf4c37cfc63b8fdb6ab424432c660e7bc8b14563af28392eb7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6c9e487519e51e41ddf226fe7b0e82b986ad6b807a3a408fd0e602373a5ff605875b3b54c7eda9065702c17f7092a60ce3f2148931d697a2ccf08a21ac31f5b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/661a1ca76dc5e40dabd637391230cf676b84fe5dc728d343d63562c0379785c7020be37f1c06b0cadc23532e9b27a7dcf6e576137d888f29dce03e98caa4c998"} build_url:https://queue.taskcluster.net/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011336 build_url: 'https://queue.taskcluster.net/v1/task/NdYyA0FXTfKBTFZ-EuVEhw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/8255044509f8e7b40299c59f36b7ed7bd980cd1c42e13e4bfcec197e883a0ce5e04a01bfc7d58aaf4c37cfc63b8fdb6ab424432c660e7bc8b14563af28392eb7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6c9e487519e51e41ddf226fe7b0e82b986ad6b807a3a408fd0e602373a5ff605875b3b54c7eda9065702c17f7092a60ce3f2148931d697a2ccf08a21ac31f5b3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/661a1ca76dc5e40dabd637391230cf676b84fe5dc728d343d63562c0379785c7020be37f1c06b0cadc23532e9b27a7dcf6e576137d888f29dce03e98caa4c998"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-14 23:15:20.745555) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 23:15:20.746104) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=df17445c3590ff7897617a0e00000225-1465969820.579620-2106468604 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004461 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-14 23:15:20.829010) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-14 23:15:20.829536) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-14 23:15:20.830009) ========= ========= Total master_lag: 0.20 =========