builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-011 starttime: 1465258387.9 results: success (0) buildid: 20160606143009 builduid: 8127c73dd1ad473c89c4a483a6065172 revision: 883b0a89b3212b6b55b7e6fde69d0438c39899ff ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 17:13:07.905376) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 17:13:07.906335) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 17:13:07.907037) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 17:13:07.924417) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 17:13:07.924739) ========= 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=5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-06 17:13:08-- 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.4M=0s 2016-06-06 17:13:08 (46.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.100705 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 17:13:08.042773) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 17:13:08.043530) ========= 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=5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015117 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 17:13:08.096538) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 17:13:08.097381) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 883b0a89b3212b6b55b7e6fde69d0438c39899ff --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 883b0a89b3212b6b55b7e6fde69d0438c39899ff --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=5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-06 17:13:08,318 truncating revision to first 12 chars 2016-06-06 17:13:08,318 Setting DEBUG logging. 2016-06-06 17:13:08,318 attempt 1/10 2016-06-06 17:13:08,318 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/883b0a89b321?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-06 17:13:08,663 unpacking tar archive at: fx-team-883b0a89b321/testing/mozharness/ program finished with exit code 0 elapsedTime=0.739006 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 17:13:08.857135) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 17:13:08.858022) ========= script_repo_revision: 883b0a89b3212b6b55b7e6fde69d0438c39899ff ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 17:13:08.859022) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 17:13:08.859747) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 17:13:08.870816) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-06-06 17:13:08.871126) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:13:09 INFO - MultiFileLogger online at 20160606 17:13:09 in /builds/slave/test 17:13:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 17:13:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:13:09 INFO - {'append_to_log': False, 17:13:09 INFO - 'base_work_dir': '/builds/slave/test', 17:13:09 INFO - 'blob_upload_branch': 'Fx-Team', 17:13:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:13:09 INFO - 'branch': 'Fx-Team', 17:13:09 INFO - 'buildbot_json_path': 'buildprops.json', 17:13:09 INFO - 'config_files': ('talos/linux_config.py',), 17:13:09 INFO - 'default_actions': ('clobber', 17:13:09 INFO - 'read-buildbot-config', 17:13:09 INFO - 'download-and-extract', 17:13:09 INFO - 'populate-webroot', 17:13:09 INFO - 'create-virtualenv', 17:13:09 INFO - 'install', 17:13:09 INFO - 'run-tests'), 17:13:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:13:09 INFO - 'download_minidump_stackwalk': True, 17:13:09 INFO - 'download_symbols': 'ondemand', 17:13:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:13:09 INFO - 'tooltool.py': '/tools/tooltool.py', 17:13:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:13:09 INFO - '/tools/misc-python/virtualenv.py')}, 17:13:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:13:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:13:09 INFO - 'installer_path': 'installer.exe', 17:13:09 INFO - 'log_level': 'info', 17:13:09 INFO - 'log_name': 'talos', 17:13:09 INFO - 'log_to_console': True, 17:13:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:13:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:13:09 INFO - 'opt_config_files': (), 17:13:09 INFO - 'pip_index': False, 17:13:09 INFO - 'sps_profile': False, 17:13:09 INFO - 'sps_profile_interval': 0, 17:13:09 INFO - 'suite': 'dromaeojs', 17:13:09 INFO - 'system_bits': '32', 17:13:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:13:09 INFO - 'title': 'talos-linux64-ix-011', 17:13:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:13:09 INFO - 'use_talos_json': True, 17:13:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:13:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:13:09 INFO - 'work_dir': 'build'} 17:13:09 INFO - ##### 17:13:09 INFO - ##### Running clobber step. 17:13:09 INFO - ##### 17:13:09 INFO - Running pre-action listener: _resource_record_pre_action 17:13:09 INFO - Running main action method: clobber 17:13:09 INFO - rmtree: /builds/slave/test/build 17:13:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:13:09 INFO - Running post-action listener: _resource_record_post_action 17:13:09 INFO - ##### 17:13:09 INFO - ##### Finished clobber step (success) 17:13:09 INFO - ##### 17:13:09 INFO - ##### 17:13:09 INFO - ##### Running read-buildbot-config step. 17:13:09 INFO - ##### 17:13:09 INFO - Running pre-action listener: _resource_record_pre_action 17:13:09 INFO - Running main action method: read_buildbot_config 17:13:09 INFO - Using buildbot properties: 17:13:09 INFO - { 17:13:09 INFO - "project": "", 17:13:09 INFO - "product": "firefox", 17:13:09 INFO - "script_repo_revision": "production", 17:13:09 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 17:13:09 INFO - "repository": "", 17:13:09 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 17:13:09 INFO - "buildid": "20160606143009", 17:13:09 INFO - "pgo_build": "True", 17:13:09 INFO - "basedir": "/builds/slave/test", 17:13:09 INFO - "buildnumber": 1228, 17:13:09 INFO - "slavename": "talos-linux64-ix-011", 17:13:09 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:13:09 INFO - "platform": "ubuntu64_hw", 17:13:09 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 17:13:09 INFO - "branch": "fx-team", 17:13:09 INFO - "repo_path": "integration/fx-team", 17:13:09 INFO - "revision": "883b0a89b3212b6b55b7e6fde69d0438c39899ff", 17:13:09 INFO - "stage_platform": "linux64-pgo", 17:13:09 INFO - "builduid": "8127c73dd1ad473c89c4a483a6065172", 17:13:09 INFO - "slavebuilddir": "test" 17:13:09 INFO - } 17:13:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 17:13:09 INFO - Running post-action listener: _resource_record_post_action 17:13:09 INFO - ##### 17:13:09 INFO - ##### Finished read-buildbot-config step (success) 17:13:09 INFO - ##### 17:13:09 INFO - ##### 17:13:09 INFO - ##### Running download-and-extract step. 17:13:09 INFO - ##### 17:13:09 INFO - Running pre-action listener: _resource_record_pre_action 17:13:09 INFO - Running main action method: download_and_extract 17:13:09 INFO - mkdir: /builds/slave/test/build/tests 17:13:09 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')]} 17:13:09 INFO - https://queue.taskcluster.net/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 17:13:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:13:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:13:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:13:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:13:10 INFO - Downloaded 1361 bytes. 17:13:10 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 17:13:10 INFO - Using the following test package requirements: 17:13:10 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 17:13:10 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:13:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:13:10 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:13:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 17:13:10 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:13:10 INFO - u'jsshell-linux-x86_64.zip'], 17:13:10 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:13:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:13:10 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 17:13:10 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:13:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:13:10 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:13:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:13:10 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:13:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:13:10 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 17:13:10 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:13:10 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:13:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:13:10 INFO - https://queue.taskcluster.net/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:13:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:13:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:13:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:13:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:13:11 INFO - Downloaded 24443829 bytes. 17:13:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:13:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:13:13 INFO - Return code: 0 17:13:13 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:13:13 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')]} 17:13:13 INFO - https://queue.taskcluster.net/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:13:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:13:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:13:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 17:13:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:13:14 INFO - Downloaded 24443829 bytes. 17:13:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:13:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:13:15 INFO - Return code: 0 17:13:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:13:15 INFO - https://queue.taskcluster.net/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:13:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 17:13:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 17:13:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 17:13:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:13:16 INFO - Downloaded 10833369 bytes. 17:13:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:13:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 17:13:16 INFO - Return code: 0 17:13:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:13:16 INFO - https://queue.taskcluster.net/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:13:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:13:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:13:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:13:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:13:18 INFO - Downloaded 59193531 bytes. 17:13:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:13:18 INFO - mkdir: /builds/slave/test/properties 17:13:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:13:18 INFO - Writing to file /builds/slave/test/properties/build_url 17:13:18 INFO - Contents: 17:13:18 INFO - build_url:https://queue.taskcluster.net/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 17:13:19 INFO - Running post-action listener: _resource_record_post_action 17:13:19 INFO - Running post-action listener: set_extra_try_arguments 17:13:19 INFO - ##### 17:13:19 INFO - ##### Finished download-and-extract step (success) 17:13:19 INFO - ##### 17:13:19 INFO - ##### 17:13:19 INFO - ##### Running populate-webroot step. 17:13:19 INFO - ##### 17:13:19 INFO - Running pre-action listener: _resource_record_pre_action 17:13:19 INFO - Running main action method: populate_webroot 17:13:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:13:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:13:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:13:19 INFO - '--tppagecycles', 17:13:19 INFO - '7'], 17:13:19 INFO - 'tests': ['tsvgm']}}, 17:13:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:13:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:13:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:13:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:13:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:13:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:13:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:13:19 INFO - 'talos_options': ['--disable-e10s'], 17:13:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:13:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:13:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:13:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:13:19 INFO - 'talos_options': ['--disable-e10s'], 17:13:19 INFO - 'tests': ['damp', 'tps']}, 17:13:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:13:19 INFO - 'tests': ['damp', 'tps']}, 17:13:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:13:19 INFO - 'tests': ['dromaeo_dom']}, 17:13:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:13:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:13:19 INFO - 'tests': ['a11yr', 17:13:19 INFO - 'ts_paint', 17:13:19 INFO - 'tpaint', 17:13:19 INFO - 'sessionrestore', 17:13:19 INFO - 'sessionrestore_no_auto_restore', 17:13:19 INFO - 'tabpaint']}, 17:13:19 INFO - 'other-e10s': {'tests': ['a11yr', 17:13:19 INFO - 'ts_paint', 17:13:19 INFO - 'tpaint', 17:13:19 INFO - 'sessionrestore', 17:13:19 INFO - 'sessionrestore_no_auto_restore', 17:13:19 INFO - 'tabpaint']}, 17:13:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:13:19 INFO - 'ts_paint', 17:13:19 INFO - 'tpaint', 17:13:19 INFO - 'sessionrestore', 17:13:19 INFO - 'sessionrestore_no_auto_restore', 17:13:19 INFO - 'tabpaint']}, 17:13:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:13:19 INFO - 'ts_paint', 17:13:19 INFO - 'tpaint', 17:13:19 INFO - 'sessionrestore', 17:13:19 INFO - 'sessionrestore_no_auto_restore', 17:13:19 INFO - 'tabpaint']}, 17:13:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:13:19 INFO - 'tests': ['a11yr', 17:13:19 INFO - 'ts_paint', 17:13:19 INFO - 'tpaint', 17:13:19 INFO - 'sessionrestore', 17:13:19 INFO - 'sessionrestore_no_auto_restore', 17:13:19 INFO - 'tabpaint']}, 17:13:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:13:19 INFO - 'tests': ['a11yr', 17:13:19 INFO - 'ts_paint', 17:13:19 INFO - 'tpaint', 17:13:19 INFO - 'sessionrestore', 17:13:19 INFO - 'sessionrestore_no_auto_restore', 17:13:19 INFO - 'tabpaint']}, 17:13:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:13:19 INFO - 'tests': ['tsvgx', 17:13:19 INFO - 'tsvgr_opacity', 17:13:19 INFO - 'tart', 17:13:19 INFO - 'tscrollx', 17:13:19 INFO - 'cart']}, 17:13:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:13:19 INFO - 'tsvgr_opacity', 17:13:19 INFO - 'tart', 17:13:19 INFO - 'tscrollx', 17:13:19 INFO - 'cart']}, 17:13:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:13:19 INFO - 'talos_options': ['--disable-e10s'], 17:13:19 INFO - 'tests': ['tp5o']}, 17:13:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:13:19 INFO - 'tests': ['tp5o']}, 17:13:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:13:19 INFO - 'talos_options': ['--disable-e10s', 17:13:19 INFO - '--xperf_path', 17:13:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:13:19 INFO - 'tests': ['tp5n']}, 17:13:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:13:19 INFO - 'talos_options': ['--xperf_path', 17:13:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:13:19 INFO - 'tests': ['tp5n']}}, 17:13:19 INFO - 'talos.zip': {'path': '', 17:13:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:13:19 INFO - Running post-action listener: _resource_record_post_action 17:13:19 INFO - ##### 17:13:19 INFO - ##### Finished populate-webroot step (success) 17:13:19 INFO - ##### 17:13:19 INFO - ##### 17:13:19 INFO - ##### Running create-virtualenv step. 17:13:19 INFO - ##### 17:13:19 INFO - Running pre-action listener: _resource_record_pre_action 17:13:19 INFO - Running main action method: create_virtualenv 17:13:19 INFO - Creating virtualenv /builds/slave/test/build/venv 17:13:19 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 17:13:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:13:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:13:19 INFO - Using real prefix '/usr' 17:13:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:13:19 INFO - Installing distribute.............................................................................................................................................................................................done. 17:13:21 INFO - Installing pip.................done. 17:13:21 INFO - Return code: 0 17:13:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:13:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:13:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:13:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:21 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 0x7fd024730f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4f1c0>, '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 0x1b500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b50550>, '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': '5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153', '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 17:13:21 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 17:13:21 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 17:13:21 INFO - Using env: {'DISPLAY': ':0', 17:13:21 INFO - 'HOME': '/home/cltbld', 17:13:21 INFO - 'LANG': 'en_US.UTF-8', 17:13:21 INFO - 'LANGUAGE': 'en_US:en', 17:13:21 INFO - 'LOGNAME': 'cltbld', 17:13:21 INFO - 'MAIL': '/var/mail/cltbld', 17:13:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:21 INFO - 'MOZ_NO_REMOTE': '1', 17:13:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:21 INFO - 'NO_EM_RESTART': '1', 17:13:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:21 INFO - 'PWD': '/builds/slave/test', 17:13:21 INFO - 'SHELL': '/bin/bash', 17:13:21 INFO - 'SHLVL': '1', 17:13:21 INFO - 'TERM': 'linux', 17:13:21 INFO - 'TMOUT': '86400', 17:13:21 INFO - 'USER': 'cltbld', 17:13:21 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153', 17:13:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:21 INFO - '_': '/tools/buildbot/bin/python'} 17:13:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:21 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 17:13:21 INFO - Cleaning up... 17:13:22 INFO - Return code: 0 17:13:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:13:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:13:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:22 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 0x7fd024730f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4f1c0>, '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 0x1b500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b50550>, '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': '5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153', '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 17:13:22 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 17:13:22 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 17:13:22 INFO - Using env: {'DISPLAY': ':0', 17:13:22 INFO - 'HOME': '/home/cltbld', 17:13:22 INFO - 'LANG': 'en_US.UTF-8', 17:13:22 INFO - 'LANGUAGE': 'en_US:en', 17:13:22 INFO - 'LOGNAME': 'cltbld', 17:13:22 INFO - 'MAIL': '/var/mail/cltbld', 17:13:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:22 INFO - 'MOZ_NO_REMOTE': '1', 17:13:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:22 INFO - 'NO_EM_RESTART': '1', 17:13:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:22 INFO - 'PWD': '/builds/slave/test', 17:13:22 INFO - 'SHELL': '/bin/bash', 17:13:22 INFO - 'SHLVL': '1', 17:13:22 INFO - 'TERM': 'linux', 17:13:22 INFO - 'TMOUT': '86400', 17:13:22 INFO - 'USER': 'cltbld', 17:13:22 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153', 17:13:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:22 INFO - '_': '/tools/buildbot/bin/python'} 17:13:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:22 INFO - Downloading/unpacking psutil>=3.1.1 17:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:13:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:13:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:13:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:13:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:13:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:13:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:13:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:13:24 INFO - Installing collected packages: psutil 17:13:24 INFO - Running setup.py install for psutil 17:13:24 INFO - building 'psutil._psutil_linux' extension 17:13:24 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 17:13:24 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 17:13:24 INFO - building 'psutil._psutil_posix' extension 17:13:24 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 17:13:24 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 17:13:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:13:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:13:24 INFO - Successfully installed psutil 17:13:24 INFO - Cleaning up... 17:13:24 INFO - Return code: 0 17:13:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:13:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:24 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 0x7fd024730f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4f1c0>, '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 0x1b500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b50550>, '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': '5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153', '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 17:13:24 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 17:13:24 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 17:13:24 INFO - Using env: {'DISPLAY': ':0', 17:13:24 INFO - 'HOME': '/home/cltbld', 17:13:24 INFO - 'LANG': 'en_US.UTF-8', 17:13:24 INFO - 'LANGUAGE': 'en_US:en', 17:13:24 INFO - 'LOGNAME': 'cltbld', 17:13:24 INFO - 'MAIL': '/var/mail/cltbld', 17:13:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:24 INFO - 'MOZ_NO_REMOTE': '1', 17:13:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:24 INFO - 'NO_EM_RESTART': '1', 17:13:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:24 INFO - 'PWD': '/builds/slave/test', 17:13:24 INFO - 'SHELL': '/bin/bash', 17:13:24 INFO - 'SHLVL': '1', 17:13:24 INFO - 'TERM': 'linux', 17:13:24 INFO - 'TMOUT': '86400', 17:13:24 INFO - 'USER': 'cltbld', 17:13:24 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153', 17:13:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:24 INFO - '_': '/tools/buildbot/bin/python'} 17:13:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:13:24 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 17:13:24 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 17:13:24 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 17:13:24 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 17:13:24 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 17:13:24 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 17:13:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:13:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:13:27 INFO - Installing collected packages: mozsystemmonitor 17:13:27 INFO - Running setup.py install for mozsystemmonitor 17:13:27 INFO - Successfully installed mozsystemmonitor 17:13:27 INFO - Cleaning up... 17:13:27 INFO - Return code: 0 17:13:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:13:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:13:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fd024730f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4f1c0>, '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 0x1b500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b50550>, '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': '5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153', '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 17:13:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:13:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:13:27 INFO - Using env: {'DISPLAY': ':0', 17:13:27 INFO - 'HOME': '/home/cltbld', 17:13:27 INFO - 'LANG': 'en_US.UTF-8', 17:13:27 INFO - 'LANGUAGE': 'en_US:en', 17:13:27 INFO - 'LOGNAME': 'cltbld', 17:13:27 INFO - 'MAIL': '/var/mail/cltbld', 17:13:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:27 INFO - 'MOZ_NO_REMOTE': '1', 17:13:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:27 INFO - 'NO_EM_RESTART': '1', 17:13:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:27 INFO - 'PWD': '/builds/slave/test', 17:13:27 INFO - 'SHELL': '/bin/bash', 17:13:27 INFO - 'SHLVL': '1', 17:13:27 INFO - 'TERM': 'linux', 17:13:27 INFO - 'TMOUT': '86400', 17:13:27 INFO - 'USER': 'cltbld', 17:13:27 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153', 17:13:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:27 INFO - '_': '/tools/buildbot/bin/python'} 17:13:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:27 INFO - Downloading/unpacking jsonschema==2.5.1 17:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:13:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:13:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:13:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:13:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:13:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:13:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:13:29 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 17:13:29 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 17:13:29 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 17:13:29 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 17:13:29 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 17:13:29 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 17:13:29 INFO - Downloading functools32-3.2.3-2.tar.gz 17:13:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:13:29 INFO - warning: no files found matching '*.txt' 17:13:29 INFO - no previously-included directories found matching 'build' 17:13:29 INFO - no previously-included directories found matching 'dist' 17:13:29 INFO - no previously-included directories found matching '.git*' 17:13:29 INFO - Installing collected packages: jsonschema, functools32 17:13:29 INFO - Running setup.py install for functools32 17:13:29 INFO - warning: no files found matching '*.txt' 17:13:29 INFO - no previously-included directories found matching 'build' 17:13:29 INFO - no previously-included directories found matching 'dist' 17:13:29 INFO - no previously-included directories found matching '.git*' 17:13:29 INFO - Successfully installed jsonschema functools32 17:13:29 INFO - Cleaning up... 17:13:29 INFO - Return code: 0 17:13:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:13:29 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')]} 17:13:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:29 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')]} 17:13:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:29 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 0x7fd024730f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4f1c0>, '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 0x1b500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b50550>, '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': '5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153', '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 17:13:29 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 17:13:29 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 17:13:29 INFO - Using env: {'DISPLAY': ':0', 17:13:29 INFO - 'HOME': '/home/cltbld', 17:13:29 INFO - 'LANG': 'en_US.UTF-8', 17:13:29 INFO - 'LANGUAGE': 'en_US:en', 17:13:29 INFO - 'LOGNAME': 'cltbld', 17:13:29 INFO - 'MAIL': '/var/mail/cltbld', 17:13:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:29 INFO - 'MOZ_NO_REMOTE': '1', 17:13:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:29 INFO - 'NO_EM_RESTART': '1', 17:13:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:29 INFO - 'PWD': '/builds/slave/test', 17:13:29 INFO - 'SHELL': '/bin/bash', 17:13:29 INFO - 'SHLVL': '1', 17:13:29 INFO - 'TERM': 'linux', 17:13:29 INFO - 'TMOUT': '86400', 17:13:29 INFO - 'USER': 'cltbld', 17:13:29 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153', 17:13:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:29 INFO - '_': '/tools/buildbot/bin/python'} 17:13:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:29 INFO - Downloading/unpacking blobuploader==1.2.4 17:13:29 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 17:13:29 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 17:13:29 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 17:13:29 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 17:13:29 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 17:13:29 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 17:13:31 INFO - Downloading blobuploader-1.2.4.tar.gz 17:13:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:13:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:13:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:13:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:13:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:13:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:13:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:13:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:13:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:13:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:13:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:13:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:13:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:13:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:13:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:13:32 INFO - Downloading docopt-0.6.1.tar.gz 17:13:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:13:32 INFO - Installing collected packages: blobuploader, requests, docopt 17:13:32 INFO - Running setup.py install for blobuploader 17:13:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:13:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:13:32 INFO - Running setup.py install for requests 17:13:33 INFO - Running setup.py install for docopt 17:13:33 INFO - Successfully installed blobuploader requests docopt 17:13:33 INFO - Cleaning up... 17:13:33 INFO - Return code: 0 17:13:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:13:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:13:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:13:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:33 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 0x7fd024730f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4f1c0>, '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 0x1b500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b50550>, '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': '5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153', '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 17:13:33 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 17:13:33 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 17:13:33 INFO - Using env: {'DISPLAY': ':0', 17:13:33 INFO - 'HOME': '/home/cltbld', 17:13:33 INFO - 'LANG': 'en_US.UTF-8', 17:13:33 INFO - 'LANGUAGE': 'en_US:en', 17:13:33 INFO - 'LOGNAME': 'cltbld', 17:13:33 INFO - 'MAIL': '/var/mail/cltbld', 17:13:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:33 INFO - 'MOZ_NO_REMOTE': '1', 17:13:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:33 INFO - 'NO_EM_RESTART': '1', 17:13:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:33 INFO - 'PWD': '/builds/slave/test', 17:13:33 INFO - 'SHELL': '/bin/bash', 17:13:33 INFO - 'SHLVL': '1', 17:13:33 INFO - 'TERM': 'linux', 17:13:33 INFO - 'TMOUT': '86400', 17:13:33 INFO - 'USER': 'cltbld', 17:13:33 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153', 17:13:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:33 INFO - '_': '/tools/buildbot/bin/python'} 17:13:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:13:33 INFO - Running setup.py (path:/tmp/pip-M5sVYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:13:33 INFO - Running setup.py (path:/tmp/pip-UPTZXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:13:33 INFO - Running setup.py (path:/tmp/pip-SFPsxz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:13:33 INFO - Running setup.py (path:/tmp/pip-WZZ14H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:13:33 INFO - Running setup.py (path:/tmp/pip-Su4pTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:13:33 INFO - Running setup.py (path:/tmp/pip-j7ogzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:13:33 INFO - Running setup.py (path:/tmp/pip-L9Pcsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:13:33 INFO - Running setup.py (path:/tmp/pip-aDvlwO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:13:34 INFO - Running setup.py (path:/tmp/pip-HfOPJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:13:34 INFO - Running setup.py (path:/tmp/pip-CbhOko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:13:34 INFO - Running setup.py (path:/tmp/pip-9B_ZfA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:13:34 INFO - Running setup.py (path:/tmp/pip-gattXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:13:34 INFO - Running setup.py (path:/tmp/pip-hewBhM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:13:34 INFO - Running setup.py (path:/tmp/pip-Fy9ZWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:13:34 INFO - Running setup.py (path:/tmp/pip-d7cTSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:13:34 INFO - Running setup.py (path:/tmp/pip-aNPW_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:13:34 INFO - Running setup.py (path:/tmp/pip-EfarGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:13:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:13:34 INFO - Running setup.py install for manifestparser 17:13:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:13:34 INFO - Running setup.py install for mozcrash 17:13:34 INFO - Running setup.py install for mozdebug 17:13:34 INFO - Running setup.py install for mozdevice 17:13:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:13:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:13:35 INFO - Running setup.py install for mozfile 17:13:35 INFO - Running setup.py install for mozhttpd 17:13:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:13:35 INFO - Running setup.py install for mozinfo 17:13:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:13:35 INFO - Running setup.py install for mozInstall 17:13:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:13:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:13:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:13:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:13:35 INFO - Running setup.py install for mozleak 17:13:35 INFO - Running setup.py install for mozlog 17:13:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:13:35 INFO - Running setup.py install for moznetwork 17:13:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:13:35 INFO - Running setup.py install for mozprocess 17:13:35 INFO - Running setup.py install for mozprofile 17:13:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:13:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:13:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:13:35 INFO - Running setup.py install for mozrunner 17:13:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:13:36 INFO - Running setup.py install for mozscreenshot 17:13:36 INFO - Running setup.py install for moztest 17:13:36 INFO - Running setup.py install for mozversion 17:13:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:13:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:13:36 INFO - Cleaning up... 17:13:36 INFO - Return code: 0 17:13:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:13:36 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')]} 17:13:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:36 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')]} 17:13:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:36 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 0x7fd024730f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4f1c0>, '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 0x1b500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b50550>, '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': '5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153', '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 17:13:36 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 17:13:36 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 17:13:36 INFO - Using env: {'DISPLAY': ':0', 17:13:36 INFO - 'HOME': '/home/cltbld', 17:13:36 INFO - 'LANG': 'en_US.UTF-8', 17:13:36 INFO - 'LANGUAGE': 'en_US:en', 17:13:36 INFO - 'LOGNAME': 'cltbld', 17:13:36 INFO - 'MAIL': '/var/mail/cltbld', 17:13:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:36 INFO - 'MOZ_NO_REMOTE': '1', 17:13:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:36 INFO - 'NO_EM_RESTART': '1', 17:13:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:36 INFO - 'PWD': '/builds/slave/test', 17:13:36 INFO - 'SHELL': '/bin/bash', 17:13:36 INFO - 'SHLVL': '1', 17:13:36 INFO - 'TERM': 'linux', 17:13:36 INFO - 'TMOUT': '86400', 17:13:36 INFO - 'USER': 'cltbld', 17:13:36 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153', 17:13:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:36 INFO - '_': '/tools/buildbot/bin/python'} 17:13:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:13:36 INFO - Running setup.py (path:/tmp/pip-uZmXsP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:13:36 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)) 17:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:13:36 INFO - Running setup.py (path:/tmp/pip-DehKZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:13:36 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)) 17:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:13:36 INFO - Running setup.py (path:/tmp/pip-LxMP5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:13:36 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)) 17:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:13:36 INFO - Running setup.py (path:/tmp/pip-BvnsHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:13:36 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)) 17:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:13:36 INFO - Running setup.py (path:/tmp/pip-lQHlDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:13:37 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)) 17:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:13:37 INFO - Running setup.py (path:/tmp/pip-SR8vSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:13:37 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)) 17:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:13:37 INFO - Running setup.py (path:/tmp/pip-VP5get-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:13:37 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)) 17:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:13:37 INFO - Running setup.py (path:/tmp/pip-pmI1sA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:13:37 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)) 17:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:13:37 INFO - Running setup.py (path:/tmp/pip-Ev1Z0J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:13:37 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)) 17:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:13:37 INFO - Running setup.py (path:/tmp/pip-1v1B6s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:13:37 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)) 17:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:13:37 INFO - Running setup.py (path:/tmp/pip-V24Mio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:13:37 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)) 17:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:13:37 INFO - Running setup.py (path:/tmp/pip-NbmPr6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:13:37 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)) 17:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:13:37 INFO - Running setup.py (path:/tmp/pip-2ZW6yl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:13:37 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)) 17:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:13:37 INFO - Running setup.py (path:/tmp/pip-EnQGcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:13:37 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)) 17:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:13:37 INFO - Running setup.py (path:/tmp/pip-0caCUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:13:37 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)) 17:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:13:37 INFO - Running setup.py (path:/tmp/pip-FUPVLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:13:37 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)) 17:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:13:37 INFO - Running setup.py (path:/tmp/pip-9bhdaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:13:37 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)) 17:13:37 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)) 17:13:37 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)) 17:13:37 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)) 17:13:37 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)) 17:13:37 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)) 17:13:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:13:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:13:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:13:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:13:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:13:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:13:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:13:39 INFO - Downloading blessings-1.6.tar.gz 17:13:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:13:39 INFO - Installing collected packages: blessings 17:13:39 INFO - Running setup.py install for blessings 17:13:40 INFO - Successfully installed blessings 17:13:40 INFO - Cleaning up... 17:13:40 INFO - Return code: 0 17:13:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:13:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:13:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:13:40 INFO - Reading from file tmpfile_stdout 17:13:40 INFO - Current package versions: 17:13:40 INFO - argparse == 1.2.1 17:13:40 INFO - blessings == 1.6 17:13:40 INFO - blobuploader == 1.2.4 17:13:40 INFO - docopt == 0.6.1 17:13:40 INFO - functools32 == 3.2.3-2 17:13:40 INFO - jsonschema == 2.5.1 17:13:40 INFO - manifestparser == 1.1 17:13:40 INFO - mozInstall == 1.12 17:13:40 INFO - mozcrash == 0.17 17:13:40 INFO - mozdebug == 0.1 17:13:40 INFO - mozdevice == 0.48 17:13:40 INFO - mozfile == 1.2 17:13:40 INFO - mozhttpd == 0.7 17:13:40 INFO - mozinfo == 0.9 17:13:40 INFO - mozleak == 0.1 17:13:40 INFO - mozlog == 3.2 17:13:40 INFO - moznetwork == 0.27 17:13:40 INFO - mozprocess == 0.22 17:13:40 INFO - mozprofile == 0.28 17:13:40 INFO - mozrunner == 6.11 17:13:40 INFO - mozscreenshot == 0.1 17:13:40 INFO - mozsystemmonitor == 0.3 17:13:40 INFO - moztest == 0.7 17:13:40 INFO - mozversion == 1.4 17:13:40 INFO - psutil == 3.1.1 17:13:40 INFO - requests == 1.2.3 17:13:40 INFO - wsgiref == 0.1.2 17:13:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:13:40 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')]} 17:13:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:40 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')]} 17:13:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:40 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 0x7fd024730f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4f1c0>, '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 0x1b500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b50550>, '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': '5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153', '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 17:13:40 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 17:13:40 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 17:13:40 INFO - Using env: {'DISPLAY': ':0', 17:13:40 INFO - 'HOME': '/home/cltbld', 17:13:40 INFO - 'LANG': 'en_US.UTF-8', 17:13:40 INFO - 'LANGUAGE': 'en_US:en', 17:13:40 INFO - 'LOGNAME': 'cltbld', 17:13:40 INFO - 'MAIL': '/var/mail/cltbld', 17:13:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:40 INFO - 'MOZ_NO_REMOTE': '1', 17:13:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:40 INFO - 'NO_EM_RESTART': '1', 17:13:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:40 INFO - 'PWD': '/builds/slave/test', 17:13:40 INFO - 'SHELL': '/bin/bash', 17:13:40 INFO - 'SHLVL': '1', 17:13:40 INFO - 'TERM': 'linux', 17:13:40 INFO - 'TMOUT': '86400', 17:13:40 INFO - 'USER': 'cltbld', 17:13:40 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153', 17:13:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:40 INFO - '_': '/tools/buildbot/bin/python'} 17:13:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:40 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)) 17:13:40 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)) 17:13:40 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)) 17:13:40 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)) 17:13:40 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)) 17:13:40 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)) 17:13:40 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)) 17:13:40 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)) 17:13:40 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)) 17:13:40 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)) 17:13:40 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)) 17:13:40 INFO - Cleaning up... 17:13:40 INFO - Return code: 0 17:13:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:13:40 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')]} 17:13:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:40 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')]} 17:13:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:40 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 0x7fd024730f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4f1c0>, '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 0x1b500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b50550>, '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': '5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153', '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 17:13:40 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 17:13:40 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 17:13:40 INFO - Using env: {'DISPLAY': ':0', 17:13:40 INFO - 'HOME': '/home/cltbld', 17:13:40 INFO - 'LANG': 'en_US.UTF-8', 17:13:40 INFO - 'LANGUAGE': 'en_US:en', 17:13:40 INFO - 'LOGNAME': 'cltbld', 17:13:40 INFO - 'MAIL': '/var/mail/cltbld', 17:13:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:40 INFO - 'MOZ_NO_REMOTE': '1', 17:13:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:40 INFO - 'NO_EM_RESTART': '1', 17:13:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:40 INFO - 'PWD': '/builds/slave/test', 17:13:40 INFO - 'SHELL': '/bin/bash', 17:13:40 INFO - 'SHLVL': '1', 17:13:40 INFO - 'TERM': 'linux', 17:13:40 INFO - 'TMOUT': '86400', 17:13:40 INFO - 'USER': 'cltbld', 17:13:40 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153', 17:13:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:40 INFO - '_': '/tools/buildbot/bin/python'} 17:13:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:13:40 INFO - Cleaning up... 17:13:40 INFO - Return code: 0 17:13:40 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:13:40 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')]} 17:13:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:40 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')]} 17:13:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:40 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 0x7fd024730f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b4f1c0>, '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 0x1b500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b50550>, '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': '5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153', '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 17:13:40 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 17:13:40 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 17:13:40 INFO - Using env: {'DISPLAY': ':0', 17:13:40 INFO - 'HOME': '/home/cltbld', 17:13:40 INFO - 'LANG': 'en_US.UTF-8', 17:13:40 INFO - 'LANGUAGE': 'en_US:en', 17:13:40 INFO - 'LOGNAME': 'cltbld', 17:13:40 INFO - 'MAIL': '/var/mail/cltbld', 17:13:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:40 INFO - 'MOZ_NO_REMOTE': '1', 17:13:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:40 INFO - 'NO_EM_RESTART': '1', 17:13:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:40 INFO - 'PWD': '/builds/slave/test', 17:13:40 INFO - 'SHELL': '/bin/bash', 17:13:40 INFO - 'SHLVL': '1', 17:13:40 INFO - 'TERM': 'linux', 17:13:40 INFO - 'TMOUT': '86400', 17:13:40 INFO - 'USER': 'cltbld', 17:13:40 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153', 17:13:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:40 INFO - '_': '/tools/buildbot/bin/python'} 17:13:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:40 INFO - Downloading/unpacking flake8 17:13:40 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 17:13:40 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 17:13:40 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 17:13:40 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 17:13:40 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 17:13:40 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 17:13:42 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:13:42 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:13:42 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 17:13:42 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 17:13:42 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 17:13:42 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 17:13:42 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 17:13:42 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 17:13:43 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:13:43 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:13:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:13:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:13:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:13:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:13:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:13:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:13:43 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:13:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:13:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:13:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:13:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:13:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:13:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:13:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:13:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:13:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:13:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:13:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:13:43 INFO - no previously-included directories found matching 'docs/_build' 17:13:43 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:13:43 INFO - Running setup.py install for pep8 17:13:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:13:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:13:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:13:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:13:43 INFO - no previously-included directories found matching 'docs/_build' 17:13:43 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:13:43 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:13:43 INFO - Cleaning up... 17:13:43 INFO - Return code: 0 17:13:43 INFO - Running post-action listener: _resource_record_post_action 17:13:43 INFO - Running post-action listener: _start_resource_monitoring 17:13:43 INFO - Starting resource monitoring. 17:13:43 INFO - ##### 17:13:43 INFO - ##### Finished create-virtualenv step (success) 17:13:43 INFO - ##### 17:13:43 INFO - ##### 17:13:43 INFO - ##### Running install step. 17:13:43 INFO - ##### 17:13:43 INFO - Running pre-action listener: _resource_record_pre_action 17:13:43 INFO - Running main action method: install 17:13:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:13:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:13:44 INFO - Reading from file tmpfile_stdout 17:13:44 INFO - Detecting whether we're running mozinstall >=1.0... 17:13:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:13:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:13:44 INFO - Reading from file tmpfile_stdout 17:13:44 INFO - Output received: 17:13:44 INFO - Usage: mozinstall [options] installer 17:13:44 INFO - Options: 17:13:44 INFO - -h, --help show this help message and exit 17:13:44 INFO - -d DEST, --destination=DEST 17:13:44 INFO - Directory to install application into. [default: 17:13:44 INFO - "/builds/slave/test"] 17:13:44 INFO - --app=APP Application being installed. [default: firefox] 17:13:44 INFO - mkdir: /builds/slave/test/build/application 17:13:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:13:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:14:00 INFO - Reading from file tmpfile_stdout 17:14:00 INFO - Output received: 17:14:00 INFO - /builds/slave/test/build/application/firefox/firefox 17:14:00 INFO - Running post-action listener: _resource_record_post_action 17:14:00 INFO - ##### 17:14:00 INFO - ##### Finished install step (success) 17:14:00 INFO - ##### 17:14:00 INFO - ##### 17:14:00 INFO - ##### Running run-tests step. 17:14:00 INFO - ##### 17:14:00 INFO - Running pre-action listener: _resource_record_pre_action 17:14:00 INFO - Running main action method: run_tests 17:14:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:14:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:14:00 INFO - Python 2.7.3 17:14:00 INFO - Return code: 0 17:14:00 INFO - grabbing minidump binary from tooltool 17:14:00 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')]} 17:14:00 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 0x1b4f1c0>, '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 0x1b500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b50550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:14:00 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 17:14:00 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 17:14:00 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 17:14:01 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:14:01 INFO - Return code: 0 17:14:01 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:14:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:14:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:14:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:14:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:14:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:14:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:14:01 INFO - Return code: 0 17:14:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 17:14:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-011 --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 17:14:01 INFO - Using env: {'DISPLAY': ':0', 17:14:01 INFO - 'HOME': '/home/cltbld', 17:14:01 INFO - 'LANG': 'en_US.UTF-8', 17:14:01 INFO - 'LANGUAGE': 'en_US:en', 17:14:01 INFO - 'LOGNAME': 'cltbld', 17:14:01 INFO - 'MAIL': '/var/mail/cltbld', 17:14:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:14:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:14:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:01 INFO - 'MOZ_NO_REMOTE': '1', 17:14:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:14:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:14:01 INFO - 'NO_EM_RESTART': '1', 17:14:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:14:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:01 INFO - 'PWD': '/builds/slave/test', 17:14:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:14:01 INFO - 'SHELL': '/bin/bash', 17:14:01 INFO - 'SHLVL': '1', 17:14:01 INFO - 'TERM': 'linux', 17:14:01 INFO - 'TMOUT': '86400', 17:14:01 INFO - 'USER': 'cltbld', 17:14:01 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153', 17:14:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:01 INFO - '_': '/tools/buildbot/bin/python'} 17:14:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 17:14:02 INFO - mozversion application_buildid: 20160606143009 17:14:02 INFO - mozversion application_changeset: 883b0a89b3212b6b55b7e6fde69d0438c39899ff 17:14:02 INFO - mozversion application_display_name: Nightly 17:14:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:14:02 INFO - mozversion application_name: Firefox 17:14:02 INFO - mozversion application_remotingname: firefox 17:14:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:14:02 INFO - mozversion application_vendor: Mozilla 17:14:02 INFO - mozversion application_version: 49.0a1 17:14:02 INFO - mozversion platform_buildid: 20160606143009 17:14:02 INFO - mozversion platform_changeset: 883b0a89b3212b6b55b7e6fde69d0438c39899ff 17:14:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:14:02 INFO - mozversion platform_version: 49.0a1 17:14:02 INFO - using testdate: 1465258442 17:14:02 INFO - actual date: 1465258442 17:14:02 INFO - starting webserver on 'localhost:35768' 17:14:02 INFO - SUITE-START | Running 2 tests 17:14:02 INFO - TEST-START | dromaeo_css 17:14:02 INFO - operating with platform_type : linux_ 17:14:02 INFO - Initialising browser for dromaeo_css test... 17:14:02 INFO - TEST-INFO | started process 3539 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5aWOsJ/profile http://localhost:35768/getInfo.html) 17:14:07 INFO - PROCESS | 3539 | __metrics Screen width/height:1600/1200 17:14:07 INFO - PROCESS | 3539 | colorDepth:24 17:14:07 INFO - PROCESS | 3539 | Browser inner width/height: 1024/697 17:14:07 INFO - PROCESS | 3539 | __metrics 17:14:09 ERROR - PROCESS | 3539 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:14:09 INFO - PROCESS | 3539 | console.error: PushService: 17:14:09 INFO - PROCESS | 3539 | stateChangeProcessEnqueue: Error transitioning state 17:14:09 INFO - PROCESS | 3539 | UnknownError 17:14:10 INFO - TEST-INFO | 3539: exit 0 17:14:10 INFO - Browser initialized. 17:14:10 INFO - Running cycle 1/1 for dromaeo_css test... 17:14:10 INFO - TEST-INFO | started process 3624 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5aWOsJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:14:21 INFO - PROCESS | 3624 | RSS: Main: 160497664 17:14:21 INFO - PROCESS | 3624 | 17:15:12 INFO - PROCESS | 3624 | 1465258512916 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] 17:17:21 INFO - PROCESS | 3624 | Cycle 1(1): loaded http://localhost:35768/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35768/tests/dromaeo/cssquery-ext.html) 17:17:22 INFO - PROCESS | 3624 | RSS: Main: 241860608 17:17:22 INFO - PROCESS | 3624 | 17:20:24 INFO - PROCESS | 3624 | Cycle 1(1): loaded http://localhost:35768/tests/dromaeo/cssquery-ext.html (next: http://localhost:35768/tests/dromaeo/cssquery-jquery.html) 17:20:25 INFO - PROCESS | 3624 | RSS: Main: 239296512 17:20:25 INFO - PROCESS | 3624 | 17:23:27 INFO - PROCESS | 3624 | Cycle 1(1): loaded http://localhost:35768/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35768/tests/dromaeo/cssquery-mootools.html) 17:23:27 INFO - PROCESS | 3624 | RSS: Main: 245989376 17:23:27 INFO - PROCESS | 3624 | 17:26:29 INFO - PROCESS | 3624 | Cycle 1(1): loaded http://localhost:35768/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35768/tests/dromaeo/cssquery-prototype.html) 17:26:30 INFO - PROCESS | 3624 | RSS: Main: 240226304 17:26:30 INFO - PROCESS | 3624 | 17:29:32 INFO - PROCESS | 3624 | Cycle 1(1): loaded http://localhost:35768/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35768/tests/dromaeo/cssquery-yui.html) 17:29:33 INFO - PROCESS | 3624 | RSS: Main: 209408000 17:29:33 INFO - PROCESS | 3624 | 17:32:36 INFO - PROCESS | 3624 | Cycle 1(1): loaded http://localhost:35768/tests/dromaeo/cssquery-yui.html (next: http://localhost:35768/tests/dromaeo/cssquery-dojo.html) 17:32:36 INFO - PROCESS | 3624 | RSS: Main: 274395136 17:32:36 INFO - PROCESS | 3624 | 17:32:36 INFO - PROCESS | 3624 | __start_tp_report 17:32:36 INFO - PROCESS | 3624 | _x_x_mozilla_page_load 17:32:36 INFO - PROCESS | 3624 | _x_x_mozilla_page_load_details 17:32:36 INFO - PROCESS | 3624 | |i|pagename|runs| 17:32:36 INFO - PROCESS | 3624 | |0;dojo.html;3137.9686203137976;3144.8113113213208;3158.557801907732;3169.1443310306217;3197.7601679874;5270.92093618596;5360.169173778066;5371.274877891484;5407.59443041772;5431.049566325892;5307.310049693534;5365.7585408656605;5401.972990135044;5421.241026256327;5486.506214440709;4838.935434204478;4903.632227582928;4911.656184067099;4929.531694489017;4993.800247990076;4927.728974906378;4959.876003099934;4977.029479251547;4996.750162491879;4997.950020499785;4812.157872372339;4831.347768051321;4848.7090774553635;4917.950820491785;4941.357623508939;4783.880402989918;4832.275158726184;4836.733979631119;4849.175640141185;4937.432195297551;4831.468538460802;4846.248831431134;4862.3922009748785;4872.952315252238;4911.778969946361;5190.8183213587345;5191.195364718475;5255.500727430888;5267.789288428458;5351.277577527023;5750.597458177929;5751.942480575224;5754.625949313281;5775.191473193756;5777.19119323295;5761.135829625548;5777.19119323295;5812.331581868097;5820.941790582082;5828.271466066742;5314.388845282765;5343.893122137535;5394.1099718546;5396.352437707461;5480.177973303957;5952;5956.344802071769;5965.015772397506;5965.55258355625;5988.490978266809;5562.221289019541;5740.6268592541355;5754.251947246831;5754.56840736938;5756.7121643917635;5615.213870058195;5654.660720356709;5661.830145095653;5685.602007859493;5689.487946084872;5658.943410565924;5662.915056274159;5663.490285874291;5665.575081868877;5667.263255776723;5578.163275508625;5665.49010589049;5672.064109421982;5679.801206957736;5727.226824378656;4390.473143222834;4397.36238245459;4420;4446.866594002185;4468.03937153507;3938.468306778548;3946.664533514626;3955.5451123120633;3957.2085582883537;3976.6421022108148;5302.681839089667;5360.8659783504945;5397.244385785914;5478.55440826054;5483.424240454775;5802.187693722883;5834.912476312774;5862.208601838697;5866.73599688015;5918.792842250587;5464.207689884945;5479.506844384025;5482.451754824486;5485.506304432541;5486.423925487846;4870.634702397264;4946.9010619787405;4947.975260123676;5017.974910125426;5073.213651883964;5980.790672326448;5981.312149102821;5986.2517185351835;6040.305364883006;6046.455818976313;5799.043157878987;5803.883922321615;5810.622309549865;5811.56413269015;5813.534917206529;5795.768169273182;5840.620359676607;5923.911141332797;5979.760809567742;5993.880122397441;5645.717714114196;5686.829395118153;5690.2887139107615;5699.715014249188;5723.227364305765;5780.826575202583;5788.7395067222915;5789.421057894177;5791.189233507228;5801.912971305518;5436.347638283273;5609.663420194802;5612.803551875665;5617.719114044363;5672.659640421422;5675.460831221028;5690.800821971211;5695.544356451391;5696.060150075357;5704.229928959538;5565.749541270734;5579.386267510652;5580.190872323572;5583.916241256303;5584.692841893735;5503.834884953458;5642.576806739429;5682.488576028178;5716.742746576331;5808.5353171747;5529.198266251451;5556.833295001156;5570.053090974463;5598;5617.101263797773 17:32:36 INFO - PROCESS | 3624 | |1;ext.html;593.7832691067758;620.9720562574684;621.8010236724248;643.5045015338188;654.9443297319727;29661;43840.34239486406;44382.33426498597;44507;44508.109837803226;45685.7715711421;46263.07473850521;46281.53718462814;46316.3052554212;46342.76828615852;8690.087540808236;8851.867221991675;8871.512066836322;8871.600777964975;8878;6621.073049773036;6625.63559004257;6625.734970601195;6626.13860198176;6751.257361690235;6667.533272670915;6688.665566721669;6692.297308782557;6693.799186024403;6897.448204143681;6658.101156343929;6659.533832631718;6661.766838160641;6662.5003124765835;6877;8752.824943501095;8818.382713210078;8859.645614175426;8864.113588641148;8865.33509986747;41747.37378939318;42104.3684344732;42216.57783422157;42220.36669449961;42246.1550768986;3358.009387230773;3471.409860323751;3795.9240815183543;3889.4943657324648;4074.3888416737727;32297.677023229702;32764.836175819208;32892.50661240083;33110.33779324424;33114.6688533114;4248.4052232687445;4255.63827074699;4296.806643701009;4299.570042995707;4317.07182955666;363806;365598;365600;366374;368451;57707.711461443265;57896.71051644715;58130.709346453;58243;58288.12567811486;60771;61046.38953610407;61279;61311.69344153251;61391.38608613946;16854.241559129867;17047.573810654834;17066.829331706773;17071.658566828595;17109.315627375014;13252.668683282802;13318;13323.267220302976;13325.66685832862;13345.666358340924;14517.419303227753;16200.351985920432;16313.42902998413;16329.591760206089;16373.672526549402;5553.805616803393;6267.435930766253;6274.686265686697;6338.524610654219;6862.451003919675;1059.5431281987267;1062.4369084385135;1121.7363919478917;1163.3078318400537;1221.712897469094;32112.197195070312;32557.837210813796;32574.348513029607;32629.836850815595;33127.503087453704;1094.2231015978584;1110.5113749949996;1137.70988397958;1175.341808587194;1224.8897599216114;16772.412965546147;16968.23642936071;16991;17103.914480427517;17373.305067797148;530404;532252;535046;535171;535234;57871.71064144652;57968.42031579714;58066.70966645224;58180.54548636318;58226.41773582295;1286.298967062953;1377.9242141682103;1387.2855479428104;1397.0500059959122;1404.4943820224712;31838.522422163223;33285.16787080342;33312.50031249582;33440.832795835864;33908.321833563685;50397.24404133868;50779.74610126926;51044.23433648424;51052.23421648604;51062.74468627633;407380;407639;408604;409295;409713;35667.46498802468;41312.38031429591;42118.57881421147;42404.787976059924;43762;47852.5214747862;48426;48518.272225917346;49530.25704614506;49665.751671242855;40725.18549628931;41458.37812432876;42004.78997604993;42231.577684223994;42773.786131069144;40177.59822401738;40255;40620.593794062865;40771.45977865729;41155.38266925939;40162.79918600388;40978.79510602428;41067.383989240785;41195.7940210297;41354.793226033675;34842.82578587091;36249;36596.81701591475;36607.63392366149;37259.81370093132;27882.721172788013;27913.720862791924;27996.58005129884;28137.155885323475;28215 17:32:36 INFO - PROCESS | 3624 | |2;jquery.html;390.58402801016916;401.8995251187203;409.87498812862077;413.6897327004746;423.916892340071;18069.638607227847;19566.31517896872;19587.118579663897;19727.21091156352;20445.386638400872;18661.626767464644;18927.716084258747;19393.612127757435;19524.804751952477;19604.31384901529;5588.497035266825;5618.494335509803;5653.378128405872;5716.599838011341;5725.427457254262;5643.125315576092;5679.460451257145;5684.37471878093;5692.943070569303;5699.430056994309;5541.612087153901;5578.497935185853;5653.830385088454;5674.801381951633;5692.8861422771515;5548.805791797308;5620.241267428885;5648.124540696199;5653.745581448845;5660.490555849972;4971.7514124294;5078.7968481260705;5110.10573149701;5110.131277682803;5117.53942145205;19521.609567808708;22143.889280553656;22169.667454988026;22899.19852805144;22980.425489362733;2279.3959600705825;2331.6269396896414;2353.7646235376496;2626.763591276775;3091.0108765195146;13227;14121.293935303194;14239;14683.92658036703;14759.262036898219;1787.5084351803227;1873.297513432463;1922;1939.9612007759906;2011.9094640741203;53360;61420;80625.59687201586;81166.18833811645;82500.58749706214;9897.60409583623;10383.532741026673;10599.152067834555;10733.946330268454;10899.618513351994;14995.850041499663;14996.400143994337;15017.24913754308;15148.242587870562;15157.242137893061;8056.7180148694515;8110;8179.5501247430775;8267.173282671685;8294.668213271401;7825.217478252129;7879.369650427952;7985.161558036439;8038.758837234892;8055.758327250074;6408.198975128109;6447.967760161263;6472.967635161794;6673.699683514254;6719.731210751515;1826.3881599664262;1891.4420246027455;1956.4521933858543;1962.3720409468833;1965.8034196580513;869.5434896679294;956.407027642852;1044.7597052677775;1053.5943661690305;1069.0378659206622;18804;19470.22119115248;19719.112639931238;19787.208511659373;19949.800501995083;1000.8398656215116;1017.1303535477208;1027.1320733979776;1031.9329243599143;1032.4528000159928;10936.781264374667;11397.601083962187;11399.772004560029;11406.942965285121;11449.59926402572;517209;517506;525740;526486;529062.1922013519;59095.70452147712;83828.58085709532;84663.57668211743;85143.14856851476;85871.71192432118;1009.2958841053352;1046.0951277145366;1053.5943661690305;1084.766775143348;1096.5339730614617;17543.473695789147;18247;18255.1785169665;18287.45137645873;18352.17415216292;38268.61731382651;38955.2208955825;39575.40636890391;39900;40321.1935761289;52864.735676321376;52899;53149;53170.73414632902;53371.73314133404;7555.9244407555225;7679.961600191963;7732.149463559116;8397.496150231005;8514.787130321543;8951.910480895369;10847.511861966073;12719.42762575705;12722.61832145037;13307.600771976857;6193.659348735863;9052.54737263121;11095.556177753122;11324.660260192208;11328;11789.646310610695;11987.160898737353;12127.878721212675;12186.878131218575;12317.568885088978;11554.133439991867;11762.176647634584;11775.52897884075;11858.16992810495;12363.876361236273;8704.347173962105;8757;8860.64557417696;8913.598888049924;9088.364094462795;11684.357360344922;11703.2978021319;11797.351145686725;11925.165238433226;11946.34295113777 17:32:36 INFO - PROCESS | 3624 | |3;mootools.html;1320.2870449957024;1469.441612187369;1510.6298956755593;1646.2015922277694;1777.6533575952694;7957.482763620361;8274.255317021474;8277.503349799008;8463.28062114721;8520.786980325494;8209.261166495015;8391.832163356745;8400;8406.7057652982;8413.495190288588;2063.814256716895;2300.539892021598;2305.2738387407912;2325.267540724666;2370.7747763962484;2389.283215035491;2389.581823180936;2391.6173412254043;2393.0547433763654;2398.256540472459;2336.5560543496767;2364.5034542746043;2402.627592723131;2404.9639255411093;2406.0255596483453;2052.7536695596473;2245.62947113726;2298.1841446286494;2311.838171328008;2318.5131122464304;2318.188633978111;2320.851178666554;2327.3832434404894;2328;2338.485533182697;8659.437136586102;8711.651533938635;8719.825603487958;8758.781030474225;8768.956155219246;5208.270842082074;5328.573714102862;5330.706811125387;5334.546563542065;5338.519533241988;3245.334706385185;3379.290349026707;3398.6771256730817;3432.63957284484;3437.5874895012757;1173.1904985559936;1216.367488905773;1230.9967376588143;1237.4988129807443;1297.468038104382;12100.213486123463;12149.331786751725;12149.331786751725;12164.51341946321;12167.695807604881;4609.216433206363;4679.859604211879;4773.37946067009;4773.856784296477;4787.54518320759;10254.128399086012;10259.7435064124;10285.331453455501;10285.588576457008;10299.53652085658;5267.130923397596;5268.841934741964;5272.314599102092;5273.709945953009;5274.736263186902;4355.629771469397;4784.665073444826;4887.755612219375;4897.926531102036;4945.7774400152075;3907.159960608483;3975.1254723960683;4027.6777857771895;4068.2270368629866;4074.9185016299507;1305.797601371789;1306.372940988312;1308.3262120008012;1324.5562736483193;1324.9403776830065;977.7360112769512;987.3631507677651;991.980160396788;996.9252306077074;1006.6778630838207;6046.274447066292;6076.361981991916;6085.57400981927;6103.542234332443;6196.008638617799;472.7683435116768;476.44732110751363;485.0250995499002;486.0667518364748;486.13225392673905;4062.370332598452;4130.769726184206;4145.875623731293;4174.91650166995;4200.978995105066;10565.471726413649;11012.449377531091;11035.668929932115;11038.11695064393;11041.33751974868;10721.678349649523;10751.946240268748;10823.080038196684;10861.022507974163;10864.456777161111;8094.757157285291;8129.268365846984;8133.308668763103;8213.54825484604;8216.876746848802;5798.275215598051;5909.763609455574;5919.200907877554;5979;6035.547333949883;9705.708828735149;10174.542145603318;10314.535845887103;10515.421651809223;10544.998225168596;10240.795184096127;10443.216758742972;10458.058774710313;10549.945005499696;10592.629257975934;7552.66013029426;7792.064952205772;7846.489978151402;7874.84250314979;8035.276825085536;8676.349273804364;8709.651613935624;8730.47617142948;8753.168448997336;8757.47455152693;7315.30504602062;7581.241875812595;7684.8463030740195;7802.687892484237;7965.681372745025;7493.962530187314;7670.309672129535;7696.114946780968;7884.566348850868;7938.087119981276;6961.338672826073;6996.440284777307;7126.750563730245;7127.85744285101;7133.500654954103;5151.459096794782;5202.557782588522;5294.990186138683;5346.599005074713;5350.839474815762;5146.485351464824;5255.658382205144;5305.204219366971;5319.414864364994;5488.313960754906 17:32:36 INFO - PROCESS | 3624 | |4;prototype.html;318.81827358405707;320.3273126434488;326.29520236289613;332.92342761164946;333.3666034534386;5047.697138171712;5282.230452798317;5385.3268341457315;5399.190121481774;5443.319585051869;5228.73856307185;5320.920186197211;5337.226102215186;5365.168398898168;5372.838814835562;4199.963017936291;4209.7474151551005;4219.746815191083;4226.450561427026;4268.97865510672;3934.4688467057013;3978.826246257361;4108.50697916249;4224.9931282640655;4258;4072.9592704073025;4106;4115.506139263278;4118.341065429532;4151.688623353246;4064.8170832312617;4109.712320137592;4117.567655396185;4127.669786417079;4153.979230103861;3439.94840077399;3641;3644.0343309022865;3666.5150614001373;3676.669099781006;5041.873953151164;5087.643864929457;5088.338515992934;5092.719900405476;5101.2603172539775;4691.929621055687;4703.95296047042;4708.764561771932;4732.645051621108;4732.739699316537;5343.969291669179;5459.754311056012;5503.587230957694;5525.751341189656;5541;4008.2183974125055;4013.3979903014488;4014.5182578090526;4019.537753158366;4031.7379370341127;5754.913676294859;5767.855803604902;5775.826725198251;5799.18811366409;5818.563607729395;3999.1002024544246;4103.363978583325;4152.335626299838;4154.50145982484;4157.625813676723;4131.35963925592;4152.501699795984;4157.625813676784;4166.270902592034;4202.222588821078;4712.293156026623;4718.787654555554;4729.172394830891;4731.148393289172;4739.478657347689;5458.645188062763;5568.749406276727;5568.860778480489;5585.58108141891;5612.607117501817;1420.4034305591676;1420.5596265157835;1422.679897023182;1423.4804296431728;1426.3937826423728;911.0388540090136;921.189353369031;941.4069136443997;951.3055469507299;956.2111258212003;4755.239161734106;4791.873909631234;4795.20879054959;4816.662833601685;4843.885906241584;5055.620828437882;5060.7722652480725;5095.31213286209;5116.386033675983;5135.228346220548;4684.4378674559275;4813.267223799429;4841.1361625773625;4844.685095468783;4885.65800393969;2697.759030845833;2790.260580946051;2793.268173732299;2796.1891051595217;2805.635267415223;5145.691258524501;5202.271681964528;5227.60792940531;5297.8808476609665;5312.92030619541;4730.624172640362;5169.86410871309;5176.8964620707375;5186.481351864783;5204.42751297357;5199.934013527218;5206.895862082737;5258.238490105781;5284.260203571503;5309.230161626542;5080.237964305384;5081.4918508148885;5146.794128234828;5157;5179.637425380338;4726.787294571821;4982.950170498249;5073.822416215415;5075.619328550448;5085.135526960352;5298.357509172169;5328.893422131613;5332.253484512249;5352.330958630171;5364.624476286623;3791.128040550744;3797.7341586089815;3971.225611005826;4079.6129316032693;4113.197926404322;5713.400092990176;5751.051076572318;5753.741081651252;5754.884902302014;5756.625819321731;5454.2091396746455;5552.472515111058;5589.301337332833;5606.18710286998;5618.157276408571;5520.475554822286;5530.778768849362;5574.0736113540715;5589.888202235851;5599.132134519156;5436.483534064258;5451.727413629382;5456.863578410572;5491.533219676213;5504.0643090675485;5426.593005524681;5442.210879422382;5512.84230311639;5602.635828671123;5621.409751975983;5371.838844834661;5397.217403476396;5401.675899446046;5420.512153906167;5454.618176727591 17:32:36 INFO - PROCESS | 3624 | |5;yui.html;720.9243029481904;769.1961899814695;792.5284455748829;810.6109067647529;814.1654803826074;15310.157941313224;16806.243719032642;16821;16823.49529514115;16853.241604127812;14477.63805904853;14627.926860365684;14629.707405851903;14650.487232946822;14653.194074325907;655.7639249870055;765.2271206081841;790.2571582712261;790.344014467993;792.7502836606451;783.6081959020489;788.0897563314353;790.8299715561153;791.3155120820478;792.0455850699893;689.3244620272136;831.9625616847256;835.9205875441854;838.8825564420955;839.1859895900997;804.0833449867156;821.0721884270765;823.4565186976627;823.6911158315631;824.0646865807322;578.4533615733134;579.9942000580024;580.0892598620168;580.3558050563871;581.8050952930731;14010.22943738094;14187.503437379746;14187.929060354736;14253.429862805475;14267.571972840831;13628.250446225453;13921.164730116125;13985.930070349583;13996.370163342572;14004.789928151085;11035.172362072797;11483.196176267664;11604.071674266039;11640.825387619192;11748.353840538766;4565.863024109249;4569.908601827978;4600.102979918917;4613.195909108981;4632.235681112612;77238.6138069306;77886.54794164411;79152.20847791506;79489;79815.99999999942;11310.94344528272;11490.310581365145;11574.826377604342;11601.651950441501;11963.222390544586;20917;20959;21038.368848934555;21045.89477052605;21067.8946605266;7845.764627061198;7899.249571290718;8328.458650187718;8566.271866891384;8590.742277731679;4498.797554110073;4668.626509879202;4687.9765601171775;4693.48371679115;4716.622670186377;3351.111955331839;3435.248023508029;3458.360203362386;3459.3081383723356;3460.6712362326043;603.2730559675581;610;616.1527899138684;617.6695467924638;625.680902739597;648.3126991848958;714.1358955663601;727.0693512304259;730.521508411991;733.1817093607882;9713.320067595341;10103.292769506212;10166.745831354126;10169.389836609678;10214.693559193236;624.3506752976944;639.1722719078804;691.5746815708303;700.8983697363956;713.6075158662715;4622.214223581999;4818.855434336975;4819.397575303087;4819.542143496362;4836;104521;106058;106726;115236.42381788038;116837;61465.38534614686;79771.60114199392;86552.56723716341;93345.53327233321;93915;60121;84431;84776;85033.14966850376;85920.14079859246;8898.911010890068;9050.230730387992;9054.320925930713;9166.17504424605;9207.309451791009;48823;49388.50611493839;49824.00351993012;50003.74998125132;50762.49237507725;67420;67943.66028169828;73034.63482682552;74740.62629687034;75567.62216188884;11326.37704926237;11332.886671133183;11544.307341559534;11572.884271157182;11664.183507154417;13110.147840390344;13131.34343282813;14873.553793386216;14926.701465970402;15096.16971066602;14206.289685515889;14646.414143434144;14657.473641302691;14749.557513274618;14829.110253384832;13904.443822246996;14637.63405914818;14757.852421475647;14778;15158.848411516185;12962.675933101747;13678.794818077937;13855.792163117763;14455.277236137941;14494.660963718195;10771.515281812493;11343.376114313462;11905.867085922557;12290.324032177958;12362.505499779909;10999.780004400027;11485.82771258415;11537.711557211136;12140.514379425078;12273.140880138662 17:32:36 INFO - PROCESS | 3624 | __end_tp_report 17:32:36 INFO - PROCESS | 3624 | __start_cc_report 17:32:36 INFO - PROCESS | 3624 | _x_x_mozilla_cycle_collect,1039 17:32:36 INFO - PROCESS | 3624 | __end_cc_report 17:32:36 INFO - PROCESS | 3624 | __startTimestamp1465259556365__endTimestamp 17:32:36 INFO - PROCESS | 3624 | 17:32:36 INFO - PROCESS | 3624 | ------- Summary: start ------- 17:32:36 INFO - PROCESS | 3624 | Number of tests: 6 17:32:36 INFO - PROCESS | 3624 | 17:32:36 INFO - PROCESS | 3624 | [#0] dojo.html Cycles:165 Average:5353.45 Median:5578.16 stddev:603.90 (10.8%) stddev-sans-first:580.20 17:32:36 INFO - PROCESS | 3624 | Values: 3138.0 3144.8 3158.6 3169.1 3197.8 5270.9 5360.2 5371.3 5407.6 5431.0 5307.3 5365.8 5402.0 5421.2 5486.5 4838.9 4903.6 4911.7 4929.5 4993.8 4927.7 4959.9 4977.0 4996.8 4998.0 4812.2 4831.3 4848.7 4918.0 4941.4 4783.9 4832.3 4836.7 4849.2 4937.4 4831.5 4846.2 4862.4 4873.0 4911.8 5190.8 5191.2 5255.5 5267.8 5351.3 5750.6 5751.9 5754.6 5775.2 5777.2 5761.1 5777.2 5812.3 5820.9 5828.3 5314.4 5343.9 5394.1 5396.4 5480.2 5952.0 5956.3 5965.0 5965.6 5988.5 5562.2 5740.6 5754.3 5754.6 5756.7 5615.2 5654.7 5661.8 5685.6 5689.5 5658.9 5662.9 5663.5 5665.6 5667.3 5578.2 5665.5 5672.1 5679.8 5727.2 4390.5 4397.4 4420.0 4446.9 4468.0 3938.5 3946.7 3955.5 3957.2 3976.6 5302.7 5360.9 5397.2 5478.6 5483.4 5802.2 5834.9 5862.2 5866.7 5918.8 5464.2 5479.5 5482.5 5485.5 5486.4 4870.6 4946.9 4948.0 5018.0 5073.2 5980.8 5981.3 5986.3 6040.3 6046.5 5799.0 5803.9 5810.6 5811.6 5813.5 5795.8 5840.6 5923.9 5979.8 5993.9 5645.7 5686.8 5690.3 5699.7 5723.2 5780.8 5788.7 5789.4 5791.2 5801.9 5436.3 5609.7 5612.8 5617.7 5672.7 5675.5 5690.8 5695.5 5696.1 5704.2 5565.7 5579.4 5580.2 5583.9 5584.7 5503.8 5642.6 5682.5 5716.7 5808.5 5529.2 5556.8 5570.1 5598.0 5617.1 17:32:36 INFO - PROCESS | 3624 | 17:32:36 INFO - PROCESS | 3624 | [#1] ext.html Cycles:180 Average:60047.33 Median:41876.08 stddev:117037.14 (279.5%) stddev-sans-first:117280.33 17:32:36 INFO - PROCESS | 3624 | Values: 593.8 621.0 621.8 643.5 654.9 29661.0 43840.3 44382.3 44507.0 44508.1 45685.8 46263.1 46281.5 46316.3 46342.8 8690.1 8851.9 8871.5 8871.6 8878.0 6621.1 6625.6 6625.7 6626.1 6751.3 6667.5 6688.7 6692.3 6693.8 6897.4 6658.1 6659.5 6661.8 6662.5 6877.0 8752.8 8818.4 8859.6 8864.1 8865.3 41747.4 42104.4 42216.6 42220.4 42246.2 3358.0 3471.4 3795.9 3889.5 4074.4 32297.7 32764.8 32892.5 33110.3 33114.7 4248.4 4255.6 4296.8 4299.6 4317.1 363806.0 365598.0 365600.0 366374.0 368451.0 57707.7 57896.7 58130.7 58243.0 58288.1 60771.0 61046.4 61279.0 61311.7 61391.4 16854.2 17047.6 17066.8 17071.7 17109.3 13252.7 13318.0 13323.3 13325.7 13345.7 14517.4 16200.4 16313.4 16329.6 16373.7 5553.8 6267.4 6274.7 6338.5 6862.5 1059.5 1062.4 1121.7 1163.3 1221.7 32112.2 32557.8 32574.3 32629.8 33127.5 1094.2 1110.5 1137.7 1175.3 1224.9 16772.4 16968.2 16991.0 17103.9 17373.3 530404.0 532252.0 535046.0 535171.0 535234.0 57871.7 57968.4 58066.7 58180.5 58226.4 1286.3 1377.9 1387.3 1397.1 1404.5 31838.5 33285.2 33312.5 33440.8 33908.3 50397.2 50779.7 51044.2 51052.2 51062.7 407380.0 407639.0 408604.0 409295.0 409713.0 35667.5 41312.4 42118.6 42404.8 43762.0 47852.5 48426.0 48518.3 49530.3 49665.8 40725.2 41458.4 42004.8 42231.6 42773.8 40177.6 40255.0 40620.6 40771.5 41155.4 40162.8 40978.8 41067.4 41195.8 41354.8 34842.8 36249.0 36596.8 36607.6 37259.8 27882.7 27913.7 27996.6 28137.2 28215.0 17:32:36 INFO - PROCESS | 3624 | 17:32:36 INFO - PROCESS | 3624 | [#2] jquery.html Cycles:180 Average:29193.33 Median:2342.70 stddev:85708.12 (3658.5%) stddev-sans-first:85921.26 17:32:36 INFO - PROCESS | 3624 | Values: 390.6 401.9 409.9 413.7 423.9 18069.6 19566.3 19587.1 19727.2 20445.4 18661.6 18927.7 19393.6 19524.8 19604.3 5588.5 5618.5 5653.4 5716.6 5725.4 5643.1 5679.5 5684.4 5692.9 5699.4 5541.6 5578.5 5653.8 5674.8 5692.9 5548.8 5620.2 5648.1 5653.7 5660.5 4971.8 5078.8 5110.1 5110.1 5117.5 19521.6 22143.9 22169.7 22899.2 22980.4 2279.4 2331.6 2353.8 2626.8 3091.0 13227.0 14121.3 14239.0 14683.9 14759.3 1787.5 1873.3 1922.0 1940.0 2011.9 53360.0 61420.0 80625.6 81166.2 82500.6 9897.6 10383.5 10599.2 10733.9 10899.6 14995.9 14996.4 15017.2 15148.2 15157.2 8056.7 8110.0 8179.6 8267.2 8294.7 7825.2 7879.4 7985.2 8038.8 8055.8 6408.2 6448.0 6473.0 6673.7 6719.7 1826.4 1891.4 1956.5 1962.4 1965.8 869.5 956.4 1044.8 1053.6 1069.0 18804.0 19470.2 19719.1 19787.2 19949.8 1000.8 1017.1 1027.1 1031.9 1032.5 10936.8 11397.6 11399.8 11406.9 11449.6 517209.0 517506.0 525740.0 526486.0 529062.2 59095.7 83828.6 84663.6 85143.1 85871.7 1009.3 1046.1 1053.6 1084.8 1096.5 17543.5 18247.0 18255.2 18287.5 18352.2 38268.6 38955.2 39575.4 39900.0 40321.2 52864.7 52899.0 53149.0 53170.7 53371.7 7555.9 7680.0 7732.1 8397.5 8514.8 8951.9 10847.5 12719.4 12722.6 13307.6 6193.7 9052.5 11095.6 11324.7 11328.0 11789.6 11987.2 12127.9 12186.9 12317.6 11554.1 11762.2 11775.5 11858.2 12363.9 8704.3 8757.0 8860.6 8913.6 9088.4 11684.4 11703.3 11797.4 11925.2 11946.3 17:32:36 INFO - PROCESS | 3624 | 17:32:36 INFO - PROCESS | 3624 | [#3] mootools.html Cycles:180 Average:5801.02 Median:4773.62 stddev:3293.13 (69.0%) stddev-sans-first:3285.15 17:32:36 INFO - PROCESS | 3624 | Values: 1320.3 1469.4 1510.6 1646.2 1777.7 7957.5 8274.3 8277.5 8463.3 8520.8 8209.3 8391.8 8400.0 8406.7 8413.5 2063.8 2300.5 2305.3 2325.3 2370.8 2389.3 2389.6 2391.6 2393.1 2398.3 2336.6 2364.5 2402.6 2405.0 2406.0 2052.8 2245.6 2298.2 2311.8 2318.5 2318.2 2320.9 2327.4 2328.0 2338.5 8659.4 8711.7 8719.8 8758.8 8769.0 5208.3 5328.6 5330.7 5334.5 5338.5 3245.3 3379.3 3398.7 3432.6 3437.6 1173.2 1216.4 1231.0 1237.5 1297.5 12100.2 12149.3 12149.3 12164.5 12167.7 4609.2 4679.9 4773.4 4773.9 4787.5 10254.1 10259.7 10285.3 10285.6 10299.5 5267.1 5268.8 5272.3 5273.7 5274.7 4355.6 4784.7 4887.8 4897.9 4945.8 3907.2 3975.1 4027.7 4068.2 4074.9 1305.8 1306.4 1308.3 1324.6 1324.9 977.7 987.4 992.0 996.9 1006.7 6046.3 6076.4 6085.6 6103.5 6196.0 472.8 476.4 485.0 486.1 486.1 4062.4 4130.8 4145.9 4174.9 4201.0 10565.5 11012.4 11035.7 11038.1 11041.3 10721.7 10751.9 10823.1 10861.0 10864.5 8094.8 8129.3 8133.3 8213.5 8216.9 5798.3 5909.8 5919.2 5979.0 6035.5 9705.7 10174.5 10314.5 10515.4 10545.0 10240.8 10443.2 10458.1 10549.9 10592.6 7552.7 7792.1 7846.5 7874.8 8035.3 8676.3 8709.7 8730.5 8753.2 8757.5 7315.3 7581.2 7684.8 7802.7 7965.7 7494.0 7670.3 7696.1 7884.6 7938.1 6961.3 6996.4 7126.8 7127.9 7133.5 5151.5 5202.6 5295.0 5346.6 5350.8 5146.5 5255.7 5305.2 5319.4 5488.3 17:32:36 INFO - PROCESS | 3624 | 17:32:36 INFO - PROCESS | 3624 | [#4] prototype.html Cycles:180 Average:4523.97 Median:5083.31 stddev:1297.46 (25.5%) stddev-sans-first:1262.12 17:32:36 INFO - PROCESS | 3624 | Values: 318.8 320.3 326.3 332.9 333.4 5047.7 5282.2 5385.3 5399.2 5443.3 5228.7 5320.9 5337.2 5365.2 5372.8 4200.0 4209.7 4219.7 4226.5 4269.0 3934.5 3978.8 4108.5 4225.0 4258.0 4073.0 4106.0 4115.5 4118.3 4151.7 4064.8 4109.7 4117.6 4127.7 4154.0 3439.9 3641.0 3644.0 3666.5 3676.7 5041.9 5087.6 5088.3 5092.7 5101.3 4691.9 4704.0 4708.8 4732.6 4732.7 5344.0 5459.8 5503.6 5525.8 5541.0 4008.2 4013.4 4014.5 4019.5 4031.7 5754.9 5767.9 5775.8 5799.2 5818.6 3999.1 4103.4 4152.3 4154.5 4157.6 4131.4 4152.5 4157.6 4166.3 4202.2 4712.3 4718.8 4729.2 4731.1 4739.5 5458.6 5568.7 5568.9 5585.6 5612.6 1420.4 1420.6 1422.7 1423.5 1426.4 911.0 921.2 941.4 951.3 956.2 4755.2 4791.9 4795.2 4816.7 4843.9 5055.6 5060.8 5095.3 5116.4 5135.2 4684.4 4813.3 4841.1 4844.7 4885.7 2697.8 2790.3 2793.3 2796.2 2805.6 5145.7 5202.3 5227.6 5297.9 5312.9 4730.6 5169.9 5176.9 5186.5 5204.4 5199.9 5206.9 5258.2 5284.3 5309.2 5080.2 5081.5 5146.8 5157.0 5179.6 4726.8 4983.0 5073.8 5075.6 5085.1 5298.4 5328.9 5332.3 5352.3 5364.6 3791.1 3797.7 3971.2 4079.6 4113.2 5713.4 5751.1 5753.7 5754.9 5756.6 5454.2 5552.5 5589.3 5606.2 5618.2 5520.5 5530.8 5574.1 5589.9 5599.1 5436.5 5451.7 5456.9 5491.5 5504.1 5426.6 5442.2 5512.8 5602.6 5621.4 5371.8 5397.2 5401.7 5420.5 5454.6 17:32:36 INFO - PROCESS | 3624 | 17:32:36 INFO - PROCESS | 3624 | [#5] yui.html Cycles:180 Average:20004.54 Median:4650.43 stddev:28076.92 (603.7%) stddev-sans-first:28118.34 17:32:36 INFO - PROCESS | 3624 | Values: 720.9 769.2 792.5 810.6 814.2 15310.2 16806.2 16821.0 16823.5 16853.2 14477.6 14627.9 14629.7 14650.5 14653.2 655.8 765.2 790.3 790.3 792.8 783.6 788.1 790.8 791.3 792.0 689.3 832.0 835.9 838.9 839.2 804.1 821.1 823.5 823.7 824.1 578.5 580.0 580.1 580.4 581.8 14010.2 14187.5 14187.9 14253.4 14267.6 13628.3 13921.2 13985.9 13996.4 14004.8 11035.2 11483.2 11604.1 11640.8 11748.4 4565.9 4569.9 4600.1 4613.2 4632.2 77238.6 77886.5 79152.2 79489.0 79816.0 11310.9 11490.3 11574.8 11601.7 11963.2 20917.0 20959.0 21038.4 21045.9 21067.9 7845.8 7899.2 8328.5 8566.3 8590.7 4498.8 4668.6 4688.0 4693.5 4716.6 3351.1 3435.2 3458.4 3459.3 3460.7 603.3 610.0 616.2 617.7 625.7 648.3 714.1 727.1 730.5 733.2 9713.3 10103.3 10166.7 10169.4 10214.7 624.4 639.2 691.6 700.9 713.6 4622.2 4818.9 4819.4 4819.5 4836.0 104521.0 106058.0 106726.0 115236.4 116837.0 61465.4 79771.6 86552.6 93345.5 93915.0 60121.0 84431.0 84776.0 85033.1 85920.1 8898.9 9050.2 9054.3 9166.2 9207.3 48823.0 49388.5 49824.0 50003.7 50762.5 67420.0 67943.7 73034.6 74740.6 75567.6 11326.4 11332.9 11544.3 11572.9 11664.2 13110.1 13131.3 14873.6 14926.7 15096.2 14206.3 14646.4 14657.5 14749.6 14829.1 13904.4 14637.6 14757.9 14778.0 15158.8 12962.7 13678.8 13855.8 14455.3 14494.7 10771.5 11343.4 11905.9 12290.3 12362.5 10999.8 11485.8 11537.7 12140.5 12273.1 17:32:36 INFO - PROCESS | 3624 | -------- Summary: end -------- 17:32:36 INFO - PROCESS | 3624 | 17:32:37 INFO - TEST-INFO | 3624: exit 0 17:32:37 INFO - TEST-OK | dromaeo_css | took 1114946ms 17:32:37 INFO - TEST-START | kraken 17:32:37 INFO - operating with platform_type : linux_ 17:32:37 INFO - Initialising browser for kraken test... 17:32:37 INFO - TEST-INFO | started process 3803 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp85AH2y/profile http://localhost:35768/getInfo.html) 17:32:42 INFO - PROCESS | 3803 | __metrics Screen width/height:1600/1200 17:32:42 INFO - PROCESS | 3803 | colorDepth:24 17:32:42 INFO - PROCESS | 3803 | Browser inner width/height: 1024/697 17:32:42 INFO - PROCESS | 3803 | __metrics 17:32:44 ERROR - PROCESS | 3803 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:32:44 INFO - PROCESS | 3803 | console.error: PushService: 17:32:44 INFO - PROCESS | 3803 | stateChangeProcessEnqueue: Error transitioning state 17:32:44 INFO - PROCESS | 3803 | UnknownError 17:32:45 INFO - TEST-INFO | 3803: exit 0 17:32:45 INFO - Browser initialized. 17:32:45 INFO - Running cycle 1/1 for kraken test... 17:32:45 INFO - TEST-INFO | started process 3886 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp85AH2y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:32:56 INFO - PROCESS | 3886 | RSS: Main: 160436224 17:32:56 INFO - PROCESS | 3886 | 17:32:59 INFO - PROCESS | 3886 | Cycle 1(1): loaded http://localhost:35768/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35768/tests/kraken/driver.html?testName=audio-beat-detection) 17:33:00 INFO - PROCESS | 3886 | RSS: Main: 218583040 17:33:00 INFO - PROCESS | 3886 | 17:33:02 INFO - PROCESS | 3886 | Cycle 1(1): loaded http://localhost:35768/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35768/tests/kraken/driver.html?testName=audio-dft) 17:33:03 INFO - PROCESS | 3886 | RSS: Main: 204754944 17:33:03 INFO - PROCESS | 3886 | 17:33:06 INFO - PROCESS | 3886 | Cycle 1(1): loaded http://localhost:35768/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35768/tests/kraken/driver.html?testName=audio-fft) 17:33:07 INFO - PROCESS | 3886 | RSS: Main: 272363520 17:33:07 INFO - PROCESS | 3886 | 17:33:09 INFO - PROCESS | 3886 | Cycle 1(1): loaded http://localhost:35768/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35768/tests/kraken/driver.html?testName=audio-oscillator) 17:33:10 INFO - PROCESS | 3886 | RSS: Main: 183287808 17:33:10 INFO - PROCESS | 3886 | 17:33:12 INFO - PROCESS | 3886 | Cycle 1(1): loaded http://localhost:35768/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35768/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:33:12 INFO - PROCESS | 3886 | RSS: Main: 187670528 17:33:12 INFO - PROCESS | 3886 | 17:33:15 INFO - PROCESS | 3886 | Cycle 1(1): loaded http://localhost:35768/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35768/tests/kraken/driver.html?testName=imaging-darkroom) 17:33:16 INFO - PROCESS | 3886 | RSS: Main: 221155328 17:33:16 INFO - PROCESS | 3886 | 17:33:19 INFO - PROCESS | 3886 | Cycle 1(1): loaded http://localhost:35768/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35768/tests/kraken/driver.html?testName=imaging-desaturate) 17:33:20 INFO - PROCESS | 3886 | RSS: Main: 224395264 17:33:20 INFO - PROCESS | 3886 | 17:33:23 INFO - PROCESS | 3886 | Cycle 1(1): loaded http://localhost:35768/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35768/tests/kraken/driver.html?testName=json-parse-financial) 17:33:23 INFO - PROCESS | 3886 | RSS: Main: 223711232 17:33:23 INFO - PROCESS | 3886 | 17:33:25 INFO - PROCESS | 3886 | Cycle 1(1): loaded http://localhost:35768/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35768/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:33:25 INFO - PROCESS | 3886 | RSS: Main: 209514496 17:33:25 INFO - PROCESS | 3886 | 17:33:27 INFO - PROCESS | 3886 | Cycle 1(1): loaded http://localhost:35768/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35768/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:33:28 INFO - PROCESS | 3886 | RSS: Main: 213405696 17:33:28 INFO - PROCESS | 3886 | 17:33:30 INFO - PROCESS | 3886 | Cycle 1(1): loaded http://localhost:35768/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35768/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:33:30 INFO - PROCESS | 3886 | RSS: Main: 216555520 17:33:30 INFO - PROCESS | 3886 | 17:33:33 INFO - PROCESS | 3886 | Cycle 1(1): loaded http://localhost:35768/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35768/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:33:33 INFO - PROCESS | 3886 | RSS: Main: 222199808 17:33:33 INFO - PROCESS | 3886 | 17:33:36 INFO - PROCESS | 3886 | Cycle 1(1): loaded http://localhost:35768/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35768/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:33:37 INFO - PROCESS | 3886 | RSS: Main: 221032448 17:33:37 INFO - PROCESS | 3886 | 17:33:38 INFO - PROCESS | 3886 | Cycle 1(1): loaded http://localhost:35768/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35768/tests/kraken/driver.html?testName=ai-astar) 17:33:39 INFO - PROCESS | 3886 | RSS: Main: 262586368 17:33:39 INFO - PROCESS | 3886 | 17:33:39 INFO - PROCESS | 3886 | __start_tp_report 17:33:39 INFO - PROCESS | 3886 | _x_x_mozilla_page_load 17:33:39 INFO - PROCESS | 3886 | _x_x_mozilla_page_load_details 17:33:39 INFO - PROCESS | 3886 | |i|pagename|runs| 17:33:39 INFO - PROCESS | 3886 | |0;ai-astar;112;106;111;111;111;111;111;111;111;112 17:33:39 INFO - PROCESS | 3886 | |1;audio-beat-detection;154;126;128;184;128;126;151;124;158;126 17:33:39 INFO - PROCESS | 3886 | |2;audio-dft;161;160;159;161;160;160;161;164;162;160 17:33:39 INFO - PROCESS | 3886 | |3;audio-fft;75;75;75;139;76;76;74;75;97;74 17:33:39 INFO - PROCESS | 3886 | |4;audio-oscillator;83;84;112;85;86;84;122;85;85;120 17:33:39 INFO - PROCESS | 3886 | |5;imaging-gaussian-blur;117;116;117;117;117;116;116;116;116;117 17:33:39 INFO - PROCESS | 3886 | |6;imaging-darkroom;145;145;145;145;146;145;145;145;145;145 17:33:39 INFO - PROCESS | 3886 | |7;imaging-desaturate;95;94;95;94;95;95;95;94;95;94 17:33:39 INFO - PROCESS | 3886 | |8;json-parse-financial;54;54;54;54;55;55;55;55;55;54 17:33:39 INFO - PROCESS | 3886 | |9;json-stringify-tinderbox;63;63;62;62;62;62;63;62;62;62 17:33:39 INFO - PROCESS | 3886 | |10;stanford-crypto-aes;79;78;78;79;79;80;80;79;110;83 17:33:39 INFO - PROCESS | 3886 | |11;stanford-crypto-ccm;125;122;126;117;124;127;119;123;125;123 17:33:39 INFO - PROCESS | 3886 | |12;stanford-crypto-pbkdf2;172;159;159;159;159;163;166;159;169;159 17:33:39 INFO - PROCESS | 3886 | |13;stanford-crypto-sha256-iterative;63;62;63;63;62;61;62;62;63;61 17:33:39 INFO - PROCESS | 3886 | __end_tp_report 17:33:39 INFO - PROCESS | 3886 | __start_cc_report 17:33:39 INFO - PROCESS | 3886 | _x_x_mozilla_cycle_collect,1631 17:33:39 INFO - PROCESS | 3886 | __end_cc_report 17:33:39 INFO - PROCESS | 3886 | __startTimestamp1465259619168__endTimestamp 17:33:39 INFO - PROCESS | 3886 | 17:33:39 INFO - PROCESS | 3886 | ------- Summary: start ------- 17:33:39 INFO - PROCESS | 3886 | Number of tests: 14 17:33:39 INFO - PROCESS | 3886 | 17:33:39 INFO - PROCESS | 3886 | [#0] ai-astar Cycles:10 Average:110.70 Median:111.00 stddev:1.70 (1.5%) stddev-sans-first:1.74 17:33:39 INFO - PROCESS | 3886 | Values: 112.0 106.0 111.0 111.0 111.0 111.0 111.0 111.0 111.0 112.0 17:33:39 INFO - PROCESS | 3886 | 17:33:39 INFO - PROCESS | 3886 | [#1] audio-beat-detection Cycles:10 Average:140.50 Median:139.50 stddev:20.29 (14.5%) stddev-sans-first:20.93 17:33:39 INFO - PROCESS | 3886 | Values: 154.0 126.0 128.0 184.0 128.0 126.0 151.0 124.0 158.0 126.0 17:33:39 INFO - PROCESS | 3886 | 17:33:39 INFO - PROCESS | 3886 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:1.40 (0.9%) stddev-sans-first:1.48 17:33:39 INFO - PROCESS | 3886 | Values: 161.0 160.0 159.0 161.0 160.0 160.0 161.0 164.0 162.0 160.0 17:33:39 INFO - PROCESS | 3886 | 17:33:39 INFO - PROCESS | 3886 | [#3] audio-fft Cycles:10 Average:83.60 Median:75.00 stddev:20.67 (27.6%) stddev-sans-first:21.69 17:33:39 INFO - PROCESS | 3886 | Values: 75.0 75.0 75.0 139.0 76.0 76.0 74.0 75.0 97.0 74.0 17:33:39 INFO - PROCESS | 3886 | 17:33:39 INFO - PROCESS | 3886 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:84.50 stddev:16.36 (19.4%) stddev-sans-first:16.80 17:33:39 INFO - PROCESS | 3886 | Values: 83.0 84.0 112.0 85.0 86.0 84.0 122.0 85.0 85.0 120.0 17:33:39 INFO - PROCESS | 3886 | 17:33:39 INFO - PROCESS | 3886 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 17:33:39 INFO - PROCESS | 3886 | Values: 117.0 116.0 117.0 117.0 117.0 116.0 116.0 116.0 116.0 117.0 17:33:39 INFO - PROCESS | 3886 | 17:33:39 INFO - PROCESS | 3886 | [#6] imaging-darkroom Cycles:10 Average:145.10 Median:145.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 17:33:39 INFO - PROCESS | 3886 | Values: 145.0 145.0 145.0 145.0 146.0 145.0 145.0 145.0 145.0 145.0 17:33:39 INFO - PROCESS | 3886 | 17:33:39 INFO - PROCESS | 3886 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 17:33:39 INFO - PROCESS | 3886 | Values: 95.0 94.0 95.0 94.0 95.0 95.0 95.0 94.0 95.0 94.0 17:33:39 INFO - PROCESS | 3886 | 17:33:39 INFO - PROCESS | 3886 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:0.53 (1.0%) stddev-sans-first:0.53 17:33:39 INFO - PROCESS | 3886 | Values: 54.0 54.0 54.0 54.0 55.0 55.0 55.0 55.0 55.0 54.0 17:33:39 INFO - PROCESS | 3886 | 17:33:39 INFO - PROCESS | 3886 | [#9] json-stringify-tinderbox Cycles:10 Average:62.30 Median:62.00 stddev:0.48 (0.8%) stddev-sans-first:0.44 17:33:39 INFO - PROCESS | 3886 | Values: 63.0 63.0 62.0 62.0 62.0 62.0 63.0 62.0 62.0 62.0 17:33:39 INFO - PROCESS | 3886 | 17:33:39 INFO - PROCESS | 3886 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:79.00 stddev:9.77 (12.4%) stddev-sans-first:10.28 17:33:39 INFO - PROCESS | 3886 | Values: 79.0 78.0 78.0 79.0 79.0 80.0 80.0 79.0 110.0 83.0 17:33:39 INFO - PROCESS | 3886 | 17:33:39 INFO - PROCESS | 3886 | [#11] stanford-crypto-ccm Cycles:10 Average:123.10 Median:124.50 stddev:3.11 (2.5%) stddev-sans-first:3.22 17:33:39 INFO - PROCESS | 3886 | Values: 125.0 122.0 126.0 117.0 124.0 127.0 119.0 123.0 125.0 123.0 17:33:39 INFO - PROCESS | 3886 | 17:33:39 INFO - PROCESS | 3886 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.40 Median:161.00 stddev:4.93 (3.1%) stddev-sans-first:3.81 17:33:39 INFO - PROCESS | 3886 | Values: 172.0 159.0 159.0 159.0 159.0 163.0 166.0 159.0 169.0 159.0 17:33:39 INFO - PROCESS | 3886 | 17:33:39 INFO - PROCESS | 3886 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:62.50 stddev:0.79 (1.3%) stddev-sans-first:0.78 17:33:39 INFO - PROCESS | 3886 | Values: 63.0 62.0 63.0 63.0 62.0 61.0 62.0 62.0 63.0 61.0 17:33:39 INFO - PROCESS | 3886 | -------- Summary: end -------- 17:33:39 INFO - PROCESS | 3886 | 17:33:40 INFO - TEST-INFO | 3886: exit 0 17:33:40 INFO - TEST-OK | kraken | took 63032ms 17:33:40 INFO - SUITE-END | took 1177s 17:33:40 INFO - Completed test suite (00:19:38) 17:33:40 INFO - javascript benchmark 17:33:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3137.9686203137976, 3144.8113113213208, 3158.557801907732, 3169.1443310306217, 3197.7601679874, 5270.92093618596, 5360.169173778066, 5371.274877891484, 5407.59443041772, 5431.049566325892, 5307.310049693534, 5365.7585408656605, 5401.972990135044, 5421.241026256327, 5486.506214440709, 4838.935434204478, 4903.632227582928, 4911.656184067099, 4929.531694489017, 4993.800247990076, 4927.728974906378, 4959.876003099934, 4977.029479251547, 4996.750162491879, 4997.950020499785, 4812.157872372339, 4831.347768051321, 4848.7090774553635, 4917.950820491785, 4941.357623508939, 4783.880402989918, 4832.275158726184, 4836.733979631119, 4849.175640141185, 4937.432195297551, 4831.468538460802, 4846.248831431134, 4862.3922009748785, 4872.952315252238, 4911.778969946361, 5190.8183213587345, 5191.195364718475, 5255.500727430888, 5267.789288428458, 5351.277577527023, 5750.597458177929, 5751.942480575224, 5754.625949313281, 5775.191473193756, 5777.19119323295, 5761.135829625548, 5777.19119323295, 5812.331581868097, 5820.941790582082, 5828.271466066742, 5314.388845282765, 5343.893122137535, 5394.1099718546, 5396.352437707461, 5480.177973303957, 5952.0, 5956.344802071769, 5965.015772397506, 5965.55258355625, 5988.490978266809, 5562.221289019541, 5740.6268592541355, 5754.251947246831, 5754.56840736938, 5756.7121643917635, 5615.213870058195, 5654.660720356709, 5661.830145095653, 5685.602007859493, 5689.487946084872, 5658.943410565924, 5662.915056274159, 5663.490285874291, 5665.575081868877, 5667.263255776723, 5578.163275508625, 5665.49010589049, 5672.064109421982, 5679.801206957736, 5727.226824378656, 4390.473143222834, 4397.36238245459, 4420.0, 4446.866594002185, 4468.03937153507, 3938.468306778548, 3946.664533514626, 3955.5451123120633, 3957.2085582883537, 3976.6421022108148, 5302.681839089667, 5360.8659783504945, 5397.244385785914, 5478.55440826054, 5483.424240454775, 5802.187693722883, 5834.912476312774, 5862.208601838697, 5866.73599688015, 5918.792842250587, 5464.207689884945, 5479.506844384025, 5482.451754824486, 5485.506304432541, 5486.423925487846, 4870.634702397264, 4946.9010619787405, 4947.975260123676, 5017.974910125426, 5073.213651883964, 5980.790672326448, 5981.312149102821, 5986.2517185351835, 6040.305364883006, 6046.455818976313, 5799.043157878987, 5803.883922321615, 5810.622309549865, 5811.56413269015, 5813.534917206529, 5795.768169273182, 5840.620359676607, 5923.911141332797, 5979.760809567742, 5993.880122397441, 5645.717714114196, 5686.829395118153, 5690.2887139107615, 5699.715014249188, 5723.227364305765, 5780.826575202583, 5788.7395067222915, 5789.421057894177, 5791.189233507228, 5801.912971305518, 5436.347638283273, 5609.663420194802, 5612.803551875665, 5617.719114044363, 5672.659640421422, 5675.460831221028, 5690.800821971211, 5695.544356451391, 5696.060150075357, 5704.229928959538, 5565.749541270734, 5579.386267510652, 5580.190872323572, 5583.916241256303, 5584.692841893735, 5503.834884953458, 5642.576806739429, 5682.488576028178, 5716.742746576331, 5808.5353171747, 5529.198266251451, 5556.833295001156, 5570.053090974463, 5598.0, 5617.101263797773], "value": 5313.261458495822, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.7832691067758, 620.9720562574684, 621.8010236724248, 643.5045015338188, 654.9443297319727, 29661.0, 43840.34239486406, 44382.33426498597, 44507.0, 44508.109837803226, 45685.7715711421, 46263.07473850521, 46281.53718462814, 46316.3052554212, 46342.76828615852, 8690.087540808236, 8851.867221991675, 8871.512066836322, 8871.600777964975, 8878.0, 6621.073049773036, 6625.63559004257, 6625.734970601195, 6626.13860198176, 6751.257361690235, 6667.533272670915, 6688.665566721669, 6692.297308782557, 6693.799186024403, 6897.448204143681, 6658.101156343929, 6659.533832631718, 6661.766838160641, 6662.5003124765835, 6877.0, 8752.824943501095, 8818.382713210078, 8859.645614175426, 8864.113588641148, 8865.33509986747, 41747.37378939318, 42104.3684344732, 42216.57783422157, 42220.36669449961, 42246.1550768986, 3358.009387230773, 3471.409860323751, 3795.9240815183543, 3889.4943657324648, 4074.3888416737727, 32297.677023229702, 32764.836175819208, 32892.50661240083, 33110.33779324424, 33114.6688533114, 4248.4052232687445, 4255.63827074699, 4296.806643701009, 4299.570042995707, 4317.07182955666, 363806.0, 365598.0, 365600.0, 366374.0, 368451.0, 57707.711461443265, 57896.71051644715, 58130.709346453, 58243.0, 58288.12567811486, 60771.0, 61046.38953610407, 61279.0, 61311.69344153251, 61391.38608613946, 16854.241559129867, 17047.573810654834, 17066.829331706773, 17071.658566828595, 17109.315627375014, 13252.668683282802, 13318.0, 13323.267220302976, 13325.66685832862, 13345.666358340924, 14517.419303227753, 16200.351985920432, 16313.42902998413, 16329.591760206089, 16373.672526549402, 5553.805616803393, 6267.435930766253, 6274.686265686697, 6338.524610654219, 6862.451003919675, 1059.5431281987267, 1062.4369084385135, 1121.7363919478917, 1163.3078318400537, 1221.712897469094, 32112.197195070312, 32557.837210813796, 32574.348513029607, 32629.836850815595, 33127.503087453704, 1094.2231015978584, 1110.5113749949996, 1137.70988397958, 1175.341808587194, 1224.8897599216114, 16772.412965546147, 16968.23642936071, 16991.0, 17103.914480427517, 17373.305067797148, 530404.0, 532252.0, 535046.0, 535171.0, 535234.0, 57871.71064144652, 57968.42031579714, 58066.70966645224, 58180.54548636318, 58226.41773582295, 1286.298967062953, 1377.9242141682103, 1387.2855479428104, 1397.0500059959122, 1404.4943820224712, 31838.522422163223, 33285.16787080342, 33312.50031249582, 33440.832795835864, 33908.321833563685, 50397.24404133868, 50779.74610126926, 51044.23433648424, 51052.23421648604, 51062.74468627633, 407380.0, 407639.0, 408604.0, 409295.0, 409713.0, 35667.46498802468, 41312.38031429591, 42118.57881421147, 42404.787976059924, 43762.0, 47852.5214747862, 48426.0, 48518.272225917346, 49530.25704614506, 49665.751671242855, 40725.18549628931, 41458.37812432876, 42004.78997604993, 42231.577684223994, 42773.786131069144, 40177.59822401738, 40255.0, 40620.593794062865, 40771.45977865729, 41155.38266925939, 40162.79918600388, 40978.79510602428, 41067.383989240785, 41195.7940210297, 41354.793226033675, 34842.82578587091, 36249.0, 36596.81701591475, 36607.63392366149, 37259.81370093132, 27882.721172788013, 27913.720862791924, 27996.58005129884, 28137.155885323475, 28215.0], "value": 19869.954538010632, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.58402801016916, 401.8995251187203, 409.87498812862077, 413.6897327004746, 423.916892340071, 18069.638607227847, 19566.31517896872, 19587.118579663897, 19727.21091156352, 20445.386638400872, 18661.626767464644, 18927.716084258747, 19393.612127757435, 19524.804751952477, 19604.31384901529, 5588.497035266825, 5618.494335509803, 5653.378128405872, 5716.599838011341, 5725.427457254262, 5643.125315576092, 5679.460451257145, 5684.37471878093, 5692.943070569303, 5699.430056994309, 5541.612087153901, 5578.497935185853, 5653.830385088454, 5674.801381951633, 5692.8861422771515, 5548.805791797308, 5620.241267428885, 5648.124540696199, 5653.745581448845, 5660.490555849972, 4971.7514124294, 5078.7968481260705, 5110.10573149701, 5110.131277682803, 5117.53942145205, 19521.609567808708, 22143.889280553656, 22169.667454988026, 22899.19852805144, 22980.425489362733, 2279.3959600705825, 2331.6269396896414, 2353.7646235376496, 2626.763591276775, 3091.0108765195146, 13227.0, 14121.293935303194, 14239.0, 14683.92658036703, 14759.262036898219, 1787.5084351803227, 1873.297513432463, 1922.0, 1939.9612007759906, 2011.9094640741203, 53360.0, 61420.0, 80625.59687201586, 81166.18833811645, 82500.58749706214, 9897.60409583623, 10383.532741026673, 10599.152067834555, 10733.946330268454, 10899.618513351994, 14995.850041499663, 14996.400143994337, 15017.24913754308, 15148.242587870562, 15157.242137893061, 8056.7180148694515, 8110.0, 8179.5501247430775, 8267.173282671685, 8294.668213271401, 7825.217478252129, 7879.369650427952, 7985.161558036439, 8038.758837234892, 8055.758327250074, 6408.198975128109, 6447.967760161263, 6472.967635161794, 6673.699683514254, 6719.731210751515, 1826.3881599664262, 1891.4420246027455, 1956.4521933858543, 1962.3720409468833, 1965.8034196580513, 869.5434896679294, 956.407027642852, 1044.7597052677775, 1053.5943661690305, 1069.0378659206622, 18804.0, 19470.22119115248, 19719.112639931238, 19787.208511659373, 19949.800501995083, 1000.8398656215116, 1017.1303535477208, 1027.1320733979776, 1031.9329243599143, 1032.4528000159928, 10936.781264374667, 11397.601083962187, 11399.772004560029, 11406.942965285121, 11449.59926402572, 517209.0, 517506.0, 525740.0, 526486.0, 529062.1922013519, 59095.70452147712, 83828.58085709532, 84663.57668211743, 85143.14856851476, 85871.71192432118, 1009.2958841053352, 1046.0951277145366, 1053.5943661690305, 1084.766775143348, 1096.5339730614617, 17543.473695789147, 18247.0, 18255.1785169665, 18287.45137645873, 18352.17415216292, 38268.61731382651, 38955.2208955825, 39575.40636890391, 39900.0, 40321.1935761289, 52864.735676321376, 52899.0, 53149.0, 53170.73414632902, 53371.73314133404, 7555.9244407555225, 7679.961600191963, 7732.149463559116, 8397.496150231005, 8514.787130321543, 8951.910480895369, 10847.511861966073, 12719.42762575705, 12722.61832145037, 13307.600771976857, 6193.659348735863, 9052.54737263121, 11095.556177753122, 11324.660260192208, 11328.0, 11789.646310610695, 11987.160898737353, 12127.878721212675, 12186.878131218575, 12317.568885088978, 11554.133439991867, 11762.176647634584, 11775.52897884075, 11858.16992810495, 12363.876361236273, 8704.347173962105, 8757.0, 8860.64557417696, 8913.598888049924, 9088.364094462795, 11684.357360344922, 11703.2978021319, 11797.351145686725, 11925.165238433226, 11946.34295113777], "value": 9238.631980576485, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1320.2870449957024, 1469.441612187369, 1510.6298956755593, 1646.2015922277694, 1777.6533575952694, 7957.482763620361, 8274.255317021474, 8277.503349799008, 8463.28062114721, 8520.786980325494, 8209.261166495015, 8391.832163356745, 8400.0, 8406.7057652982, 8413.495190288588, 2063.814256716895, 2300.539892021598, 2305.2738387407912, 2325.267540724666, 2370.7747763962484, 2389.283215035491, 2389.581823180936, 2391.6173412254043, 2393.0547433763654, 2398.256540472459, 2336.5560543496767, 2364.5034542746043, 2402.627592723131, 2404.9639255411093, 2406.0255596483453, 2052.7536695596473, 2245.62947113726, 2298.1841446286494, 2311.838171328008, 2318.5131122464304, 2318.188633978111, 2320.851178666554, 2327.3832434404894, 2328.0, 2338.485533182697, 8659.437136586102, 8711.651533938635, 8719.825603487958, 8758.781030474225, 8768.956155219246, 5208.270842082074, 5328.573714102862, 5330.706811125387, 5334.546563542065, 5338.519533241988, 3245.334706385185, 3379.290349026707, 3398.6771256730817, 3432.63957284484, 3437.5874895012757, 1173.1904985559936, 1216.367488905773, 1230.9967376588143, 1237.4988129807443, 1297.468038104382, 12100.213486123463, 12149.331786751725, 12149.331786751725, 12164.51341946321, 12167.695807604881, 4609.216433206363, 4679.859604211879, 4773.37946067009, 4773.856784296477, 4787.54518320759, 10254.128399086012, 10259.7435064124, 10285.331453455501, 10285.588576457008, 10299.53652085658, 5267.130923397596, 5268.841934741964, 5272.314599102092, 5273.709945953009, 5274.736263186902, 4355.629771469397, 4784.665073444826, 4887.755612219375, 4897.926531102036, 4945.7774400152075, 3907.159960608483, 3975.1254723960683, 4027.6777857771895, 4068.2270368629866, 4074.9185016299507, 1305.797601371789, 1306.372940988312, 1308.3262120008012, 1324.5562736483193, 1324.9403776830065, 977.7360112769512, 987.3631507677651, 991.980160396788, 996.9252306077074, 1006.6778630838207, 6046.274447066292, 6076.361981991916, 6085.57400981927, 6103.542234332443, 6196.008638617799, 472.7683435116768, 476.44732110751363, 485.0250995499002, 486.0667518364748, 486.13225392673905, 4062.370332598452, 4130.769726184206, 4145.875623731293, 4174.91650166995, 4200.978995105066, 10565.471726413649, 11012.449377531091, 11035.668929932115, 11038.11695064393, 11041.33751974868, 10721.678349649523, 10751.946240268748, 10823.080038196684, 10861.022507974163, 10864.456777161111, 8094.757157285291, 8129.268365846984, 8133.308668763103, 8213.54825484604, 8216.876746848802, 5798.275215598051, 5909.763609455574, 5919.200907877554, 5979.0, 6035.547333949883, 9705.708828735149, 10174.542145603318, 10314.535845887103, 10515.421651809223, 10544.998225168596, 10240.795184096127, 10443.216758742972, 10458.058774710313, 10549.945005499696, 10592.629257975934, 7552.66013029426, 7792.064952205772, 7846.489978151402, 7874.84250314979, 8035.276825085536, 8676.349273804364, 8709.651613935624, 8730.47617142948, 8753.168448997336, 8757.47455152693, 7315.30504602062, 7581.241875812595, 7684.8463030740195, 7802.687892484237, 7965.681372745025, 7493.962530187314, 7670.309672129535, 7696.114946780968, 7884.566348850868, 7938.087119981276, 6961.338672826073, 6996.440284777307, 7126.750563730245, 7127.85744285101, 7133.500654954103, 5151.459096794782, 5202.557782588522, 5294.990186138683, 5346.599005074713, 5350.839474815762, 5146.485351464824, 5255.658382205144, 5305.204219366971, 5319.414864364994, 5488.313960754906], "value": 4586.231913604462, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.81827358405707, 320.3273126434488, 326.29520236289613, 332.92342761164946, 333.3666034534386, 5047.697138171712, 5282.230452798317, 5385.3268341457315, 5399.190121481774, 5443.319585051869, 5228.73856307185, 5320.920186197211, 5337.226102215186, 5365.168398898168, 5372.838814835562, 4199.963017936291, 4209.7474151551005, 4219.746815191083, 4226.450561427026, 4268.97865510672, 3934.4688467057013, 3978.826246257361, 4108.50697916249, 4224.9931282640655, 4258.0, 4072.9592704073025, 4106.0, 4115.506139263278, 4118.341065429532, 4151.688623353246, 4064.8170832312617, 4109.712320137592, 4117.567655396185, 4127.669786417079, 4153.979230103861, 3439.94840077399, 3641.0, 3644.0343309022865, 3666.5150614001373, 3676.669099781006, 5041.873953151164, 5087.643864929457, 5088.338515992934, 5092.719900405476, 5101.2603172539775, 4691.929621055687, 4703.95296047042, 4708.764561771932, 4732.645051621108, 4732.739699316537, 5343.969291669179, 5459.754311056012, 5503.587230957694, 5525.751341189656, 5541.0, 4008.2183974125055, 4013.3979903014488, 4014.5182578090526, 4019.537753158366, 4031.7379370341127, 5754.913676294859, 5767.855803604902, 5775.826725198251, 5799.18811366409, 5818.563607729395, 3999.1002024544246, 4103.363978583325, 4152.335626299838, 4154.50145982484, 4157.625813676723, 4131.35963925592, 4152.501699795984, 4157.625813676784, 4166.270902592034, 4202.222588821078, 4712.293156026623, 4718.787654555554, 4729.172394830891, 4731.148393289172, 4739.478657347689, 5458.645188062763, 5568.749406276727, 5568.860778480489, 5585.58108141891, 5612.607117501817, 1420.4034305591676, 1420.5596265157835, 1422.679897023182, 1423.4804296431728, 1426.3937826423728, 911.0388540090136, 921.189353369031, 941.4069136443997, 951.3055469507299, 956.2111258212003, 4755.239161734106, 4791.873909631234, 4795.20879054959, 4816.662833601685, 4843.885906241584, 5055.620828437882, 5060.7722652480725, 5095.31213286209, 5116.386033675983, 5135.228346220548, 4684.4378674559275, 4813.267223799429, 4841.1361625773625, 4844.685095468783, 4885.65800393969, 2697.759030845833, 2790.260580946051, 2793.268173732299, 2796.1891051595217, 2805.635267415223, 5145.691258524501, 5202.271681964528, 5227.60792940531, 5297.8808476609665, 5312.92030619541, 4730.624172640362, 5169.86410871309, 5176.8964620707375, 5186.481351864783, 5204.42751297357, 5199.934013527218, 5206.895862082737, 5258.238490105781, 5284.260203571503, 5309.230161626542, 5080.237964305384, 5081.4918508148885, 5146.794128234828, 5157.0, 5179.637425380338, 4726.787294571821, 4982.950170498249, 5073.822416215415, 5075.619328550448, 5085.135526960352, 5298.357509172169, 5328.893422131613, 5332.253484512249, 5352.330958630171, 5364.624476286623, 3791.128040550744, 3797.7341586089815, 3971.225611005826, 4079.6129316032693, 4113.197926404322, 5713.400092990176, 5751.051076572318, 5753.741081651252, 5754.884902302014, 5756.625819321731, 5454.2091396746455, 5552.472515111058, 5589.301337332833, 5606.18710286998, 5618.157276408571, 5520.475554822286, 5530.778768849362, 5574.0736113540715, 5589.888202235851, 5599.132134519156, 5436.483534064258, 5451.727413629382, 5456.863578410572, 5491.533219676213, 5504.0643090675485, 5426.593005524681, 5442.210879422382, 5512.84230311639, 5602.635828671123, 5621.409751975983, 5371.838844834661, 5397.217403476396, 5401.675899446046, 5420.512153906167, 5454.618176727591], "value": 4112.740576410606, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [720.9243029481904, 769.1961899814695, 792.5284455748829, 810.6109067647529, 814.1654803826074, 15310.157941313224, 16806.243719032642, 16821.0, 16823.49529514115, 16853.241604127812, 14477.63805904853, 14627.926860365684, 14629.707405851903, 14650.487232946822, 14653.194074325907, 655.7639249870055, 765.2271206081841, 790.2571582712261, 790.344014467993, 792.7502836606451, 783.6081959020489, 788.0897563314353, 790.8299715561153, 791.3155120820478, 792.0455850699893, 689.3244620272136, 831.9625616847256, 835.9205875441854, 838.8825564420955, 839.1859895900997, 804.0833449867156, 821.0721884270765, 823.4565186976627, 823.6911158315631, 824.0646865807322, 578.4533615733134, 579.9942000580024, 580.0892598620168, 580.3558050563871, 581.8050952930731, 14010.22943738094, 14187.503437379746, 14187.929060354736, 14253.429862805475, 14267.571972840831, 13628.250446225453, 13921.164730116125, 13985.930070349583, 13996.370163342572, 14004.789928151085, 11035.172362072797, 11483.196176267664, 11604.071674266039, 11640.825387619192, 11748.353840538766, 4565.863024109249, 4569.908601827978, 4600.102979918917, 4613.195909108981, 4632.235681112612, 77238.6138069306, 77886.54794164411, 79152.20847791506, 79489.0, 79815.99999999942, 11310.94344528272, 11490.310581365145, 11574.826377604342, 11601.651950441501, 11963.222390544586, 20917.0, 20959.0, 21038.368848934555, 21045.89477052605, 21067.8946605266, 7845.764627061198, 7899.249571290718, 8328.458650187718, 8566.271866891384, 8590.742277731679, 4498.797554110073, 4668.626509879202, 4687.9765601171775, 4693.48371679115, 4716.622670186377, 3351.111955331839, 3435.248023508029, 3458.360203362386, 3459.3081383723356, 3460.6712362326043, 603.2730559675581, 610.0, 616.1527899138684, 617.6695467924638, 625.680902739597, 648.3126991848958, 714.1358955663601, 727.0693512304259, 730.521508411991, 733.1817093607882, 9713.320067595341, 10103.292769506212, 10166.745831354126, 10169.389836609678, 10214.693559193236, 624.3506752976944, 639.1722719078804, 691.5746815708303, 700.8983697363956, 713.6075158662715, 4622.214223581999, 4818.855434336975, 4819.397575303087, 4819.542143496362, 4836.0, 104521.0, 106058.0, 106726.0, 115236.42381788038, 116837.0, 61465.38534614686, 79771.60114199392, 86552.56723716341, 93345.53327233321, 93915.0, 60121.0, 84431.0, 84776.0, 85033.14966850376, 85920.14079859246, 8898.911010890068, 9050.230730387992, 9054.320925930713, 9166.17504424605, 9207.309451791009, 48823.0, 49388.50611493839, 49824.00351993012, 50003.74998125132, 50762.49237507725, 67420.0, 67943.66028169828, 73034.63482682552, 74740.62629687034, 75567.62216188884, 11326.37704926237, 11332.886671133183, 11544.307341559534, 11572.884271157182, 11664.183507154417, 13110.147840390344, 13131.34343282813, 14873.553793386216, 14926.701465970402, 15096.16971066602, 14206.289685515889, 14646.414143434144, 14657.473641302691, 14749.557513274618, 14829.110253384832, 13904.443822246996, 14637.63405914818, 14757.852421475647, 14778.0, 15158.848411516185, 12962.675933101747, 13678.794818077937, 13855.792163117763, 14455.277236137941, 14494.660963718195, 10771.515281812493, 11343.376114313462, 11905.867085922557, 12290.324032177958, 12362.505499779909, 10999.780004400027, 11485.82771258415, 11537.711557211136, 12140.514379425078, 12273.140880138662], "value": 7456.360927292441, "unit": "score"}], "name": "dromaeo_css", "value": 7181.597227140428, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 111.0, 111.0, 111.0, 111.0, 111.0, 111.0, 111.0, 112.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 126.0, 128.0, 184.0, 128.0, 126.0, 151.0, 124.0, 158.0, 126.0], "value": 140.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 159.0, 161.0, 160.0, 160.0, 161.0, 164.0, 162.0, 160.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 139.0, 76.0, 76.0, 74.0, 75.0, 97.0, 74.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 112.0, 85.0, 86.0, 84.0, 122.0, 85.0, 85.0, 120.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0], "value": 145.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 54.0, 55.0, 55.0, 55.0, 55.0, 55.0, 54.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 62.0, 62.0, 62.0, 62.0, 63.0, 62.0, 62.0, 62.0], "value": 62.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 78.0, 79.0, 79.0, 80.0, 80.0, 79.0, 110.0, 83.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 122.0, 126.0, 117.0, 124.0, 127.0, 119.0, 123.0, 125.0, 123.0], "value": 123.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 159.0, 159.0, 159.0, 159.0, 163.0, 166.0, 159.0, 169.0, 159.0], "value": 162.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 63.0, 63.0, 62.0, 61.0, 62.0, 62.0, 63.0, 61.0], "value": 62.2, "unit": "score"}], "name": "kraken", "value": 1493.4, "alertThreshold": 2.0}]} 17:33:40 INFO - Return code: 0 17:33:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:33:40 ERROR - # TBPL SUCCESS # 17:33:40 INFO - Running post-action listener: _resource_record_post_action 17:33:40 INFO - ##### 17:33:40 INFO - ##### Finished run-tests step (success) 17:33:40 INFO - ##### 17:33:40 INFO - Running post-run listener: _resource_record_post_run 17:33:40 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 548659200; Read time: 8; Write time: 1338300 17:33:40 INFO - TinderboxPrint: CPU usage
13.3% 17:33:40 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 17:33:40 INFO - TinderboxPrint: I/O write bytes / time
548,659,200 / 1,338,300 17:33:40 INFO - TinderboxPrint: CPU idle
8,272.5 (86.7%) 17:33:40 INFO - TinderboxPrint: CPU user
1,215.0 (12.7%) 17:33:40 INFO - TinderboxPrint: Swap in / out
0 / 0 17:33:40 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 257622016; Read time: 0; Write time: 540884 17:33:40 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290902016; Read time: 8; Write time: 797396 17:33:41 INFO - Running post-run listener: _upload_blobber_files 17:33:41 INFO - Blob upload gear active. 17:33:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:33:41 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 17:33:41 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:33:41 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:33:41 INFO - (blobuploader) - INFO - Open directory for files ... 17:33:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:33:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:33:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:33:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:33:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:33:42 INFO - (blobuploader) - INFO - Done attempting. 17:33:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:33:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:33:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:33:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:33:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:33:43 INFO - (blobuploader) - INFO - Done attempting. 17:33:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:33:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:33:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:33:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:33:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:33:47 INFO - (blobuploader) - INFO - Done attempting. 17:33:47 INFO - (blobuploader) - INFO - Iteration through files over. 17:33:47 INFO - Return code: 0 17:33:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:33:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:33:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/af4b69b5303a1c507c3b3783825ebd3ae759e8999aa58164f95e701a1d8c3e5c1170528b1985fe724d5d053872edb534326872796ab15b7c4499d672f0c6fd7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e8578800a71eba603d355173f818f3488251498174e78993541e6895c3e56d872c28b68a2c418818aca78263ee1be2d7e122321518f1a97dc122fb992e596f0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/79053aa2f99152635d5977d42c4d62d6dac329bfd37ab574f68de616b0a8a09ffbff096d269165327ba013a12feffb306ec811a11eb6c8e1c2ab6c7ac0c0fafd"} 17:33:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:33:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:33:47 INFO - Contents: 17:33:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/af4b69b5303a1c507c3b3783825ebd3ae759e8999aa58164f95e701a1d8c3e5c1170528b1985fe724d5d053872edb534326872796ab15b7c4499d672f0c6fd7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e8578800a71eba603d355173f818f3488251498174e78993541e6895c3e56d872c28b68a2c418818aca78263ee1be2d7e122321518f1a97dc122fb992e596f0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/79053aa2f99152635d5977d42c4d62d6dac329bfd37ab574f68de616b0a8a09ffbff096d269165327ba013a12feffb306ec811a11eb6c8e1c2ab6c7ac0c0fafd"} 17:33:47 INFO - Running post-run listener: copy_logs_to_upload_dir 17:33:47 INFO - Copying logs to upload dir... 17:33:47 INFO - mkdir: /builds/slave/test/build/upload/logs 17:33:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.219612 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-06-06 17:33:47.116927) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 17:33:47.120141) ========= 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=5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153 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/sha512/af4b69b5303a1c507c3b3783825ebd3ae759e8999aa58164f95e701a1d8c3e5c1170528b1985fe724d5d053872edb534326872796ab15b7c4499d672f0c6fd7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e8578800a71eba603d355173f818f3488251498174e78993541e6895c3e56d872c28b68a2c418818aca78263ee1be2d7e122321518f1a97dc122fb992e596f0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/79053aa2f99152635d5977d42c4d62d6dac329bfd37ab574f68de616b0a8a09ffbff096d269165327ba013a12feffb306ec811a11eb6c8e1c2ab6c7ac0c0fafd"} build_url:https://queue.taskcluster.net/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011290 build_url: 'https://queue.taskcluster.net/v1/task/BuYhmW1uR86CzUKJnQOqXA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/af4b69b5303a1c507c3b3783825ebd3ae759e8999aa58164f95e701a1d8c3e5c1170528b1985fe724d5d053872edb534326872796ab15b7c4499d672f0c6fd7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e8578800a71eba603d355173f818f3488251498174e78993541e6895c3e56d872c28b68a2c418818aca78263ee1be2d7e122321518f1a97dc122fb992e596f0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/79053aa2f99152635d5977d42c4d62d6dac329bfd37ab574f68de616b0a8a09ffbff096d269165327ba013a12feffb306ec811a11eb6c8e1c2ab6c7ac0c0fafd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 17:33:47.149944) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 17:33:47.150280) ========= 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=5cf47e208febbcd81da23b2e0000026f-1465258387.239120-612272153 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004488 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 17:33:47.234917) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 17:33:47.235602) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 17:33:47.236256) ========= ========= Total master_lag: 0.20 =========