builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-038 starttime: 1465809685.4 results: success (0) buildid: 20160613010526 builduid: 0d0762ee465548c986c1f26b7be28940 revision: fbc8f897e016fe44393bb2e3c064071598b8f586 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-13 02:21:25.398000) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-13 02:21:25.398647) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-13 02:21:25.398956) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-13 02:21:25.415782) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-13 02:21:25.416068) ========= 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=8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-13 02:21:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 51.0M=0s 2016-06-13 02:21:25 (51.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098587 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-13 02:21:25.529101) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-13 02:21:25.529437) ========= 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=8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018201 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-13 02:21:25.579081) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-13 02:21:25.579412) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fbc8f897e016fe44393bb2e3c064071598b8f586 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev fbc8f897e016fe44393bb2e3c064071598b8f586 --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=8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-13 02:21:25,725 truncating revision to first 12 chars 2016-06-13 02:21:25,725 Setting DEBUG logging. 2016-06-13 02:21:25,725 attempt 1/10 2016-06-13 02:21:25,725 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fbc8f897e016?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-13 02:21:26,637 unpacking tar archive at: mozilla-central-fbc8f897e016/testing/mozharness/ program finished with exit code 0 elapsedTime=1.254848 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-13 02:21:26.847307) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-13 02:21:26.847636) ========= script_repo_revision: fbc8f897e016fe44393bb2e3c064071598b8f586 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-13 02:21:26.848100) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-13 02:21:26.848374) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-13 02:21:26.857681) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-06-13 02:21:26.858043) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:21:27 INFO - MultiFileLogger online at 20160613 02:21:27 in /builds/slave/test 02:21:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 02:21:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:21:27 INFO - {'append_to_log': False, 02:21:27 INFO - 'base_work_dir': '/builds/slave/test', 02:21:27 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 02:21:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:21:27 INFO - 'branch': 'Firefox-Non-PGO', 02:21:27 INFO - 'buildbot_json_path': 'buildprops.json', 02:21:27 INFO - 'config_files': ('talos/linux_config.py',), 02:21:27 INFO - 'default_actions': ('clobber', 02:21:27 INFO - 'read-buildbot-config', 02:21:27 INFO - 'download-and-extract', 02:21:27 INFO - 'populate-webroot', 02:21:27 INFO - 'create-virtualenv', 02:21:27 INFO - 'install', 02:21:27 INFO - 'run-tests'), 02:21:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:21:27 INFO - 'download_minidump_stackwalk': True, 02:21:27 INFO - 'download_symbols': 'ondemand', 02:21:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:21:27 INFO - 'tooltool.py': '/tools/tooltool.py', 02:21:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:21:27 INFO - '/tools/misc-python/virtualenv.py')}, 02:21:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:21:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:21:27 INFO - 'installer_path': 'installer.exe', 02:21:27 INFO - 'log_level': 'info', 02:21:27 INFO - 'log_name': 'talos', 02:21:27 INFO - 'log_to_console': True, 02:21:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:21:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:21:27 INFO - 'opt_config_files': (), 02:21:27 INFO - 'pip_index': False, 02:21:27 INFO - 'sps_profile': False, 02:21:27 INFO - 'sps_profile_interval': 0, 02:21:27 INFO - 'suite': 'dromaeojs', 02:21:27 INFO - 'system_bits': '32', 02:21:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:21:27 INFO - 'title': 'talos-linux64-ix-038', 02:21:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:21:27 INFO - 'use_talos_json': True, 02:21:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:21:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:21:27 INFO - 'work_dir': 'build'} 02:21:27 INFO - ##### 02:21:27 INFO - ##### Running clobber step. 02:21:27 INFO - ##### 02:21:27 INFO - Running pre-action listener: _resource_record_pre_action 02:21:27 INFO - Running main action method: clobber 02:21:27 INFO - rmtree: /builds/slave/test/build 02:21:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:21:27 INFO - Running post-action listener: _resource_record_post_action 02:21:27 INFO - ##### 02:21:27 INFO - ##### Finished clobber step (success) 02:21:27 INFO - ##### 02:21:27 INFO - ##### 02:21:27 INFO - ##### Running read-buildbot-config step. 02:21:27 INFO - ##### 02:21:27 INFO - Running pre-action listener: _resource_record_pre_action 02:21:27 INFO - Running main action method: read_buildbot_config 02:21:27 INFO - Using buildbot properties: 02:21:27 INFO - { 02:21:27 INFO - "project": "", 02:21:27 INFO - "product": "firefox", 02:21:27 INFO - "script_repo_revision": "production", 02:21:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 02:21:27 INFO - "repository": "", 02:21:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 02:21:27 INFO - "buildid": "20160613010526", 02:21:27 INFO - "pgo_build": "False", 02:21:27 INFO - "basedir": "/builds/slave/test", 02:21:27 INFO - "buildnumber": 112, 02:21:27 INFO - "slavename": "talos-linux64-ix-038", 02:21:27 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 02:21:27 INFO - "platform": "ubuntu64_hw", 02:21:27 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 02:21:27 INFO - "branch": "mozilla-central", 02:21:27 INFO - "repo_path": "mozilla-central", 02:21:27 INFO - "revision": "fbc8f897e016fe44393bb2e3c064071598b8f586", 02:21:27 INFO - "stage_platform": "linux64", 02:21:27 INFO - "builduid": "0d0762ee465548c986c1f26b7be28940", 02:21:27 INFO - "slavebuilddir": "test" 02:21:27 INFO - } 02:21:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 02:21:27 INFO - Running post-action listener: _resource_record_post_action 02:21:27 INFO - ##### 02:21:27 INFO - ##### Finished read-buildbot-config step (success) 02:21:27 INFO - ##### 02:21:27 INFO - ##### 02:21:27 INFO - ##### Running download-and-extract step. 02:21:27 INFO - ##### 02:21:27 INFO - Running pre-action listener: _resource_record_pre_action 02:21:27 INFO - Running main action method: download_and_extract 02:21:27 INFO - mkdir: /builds/slave/test/build/tests 02:21: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')]} 02:21:27 INFO - https://queue.taskcluster.net/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 02:21:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 02:21:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 02:21:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 02:21:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 02:21:28 INFO - Downloaded 1361 bytes. 02:21:28 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 02:21:28 INFO - Using the following test package requirements: 02:21:28 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 02:21:28 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 02:21:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 02:21:28 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 02:21:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 02:21:28 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 02:21:28 INFO - u'jsshell-linux-x86_64.zip'], 02:21:28 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 02:21:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 02:21:28 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 02:21:28 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 02:21:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 02:21:28 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 02:21:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 02:21:28 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 02:21:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 02:21:28 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 02:21:28 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 02:21:28 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 02:21:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:21:28 INFO - https://queue.taskcluster.net/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 02:21:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 02:21:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 02:21:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 02:21:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:21:31 INFO - Downloaded 22651531 bytes. 02:21:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:21:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 02:21:32 INFO - Return code: 0 02:21:32 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 02:21:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:21:32 INFO - https://queue.taskcluster.net/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 02:21:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 02:21:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 02:21:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 02:21:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:21:33 INFO - Downloaded 22651531 bytes. 02:21:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:21:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 02:21:34 INFO - Return code: 0 02:21:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:21:34 INFO - https://queue.taskcluster.net/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 02:21:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 02:21:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 02:21:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 02:21:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 02:21:36 INFO - Downloaded 14405384 bytes. 02:21:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:21:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 02:21:36 INFO - Return code: 0 02:21: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')]} 02:21:36 INFO - https://queue.taskcluster.net/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 02:21:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 02:21:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 02:21:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 02:21:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 02:21:39 INFO - Downloaded 55405089 bytes. 02:21:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 02:21:39 INFO - mkdir: /builds/slave/test/properties 02:21:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:21:39 INFO - Writing to file /builds/slave/test/properties/build_url 02:21:39 INFO - Contents: 02:21:39 INFO - build_url:https://queue.taskcluster.net/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 02:21:40 INFO - Running post-action listener: _resource_record_post_action 02:21:40 INFO - Running post-action listener: set_extra_try_arguments 02:21:40 INFO - ##### 02:21:40 INFO - ##### Finished download-and-extract step (success) 02:21:40 INFO - ##### 02:21:40 INFO - ##### 02:21:40 INFO - ##### Running populate-webroot step. 02:21:40 INFO - ##### 02:21:40 INFO - Running pre-action listener: _resource_record_pre_action 02:21:40 INFO - Running main action method: populate_webroot 02:21:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:21:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:21:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:21:40 INFO - '--tppagecycles', 02:21:40 INFO - '7'], 02:21:40 INFO - 'tests': ['tsvgm']}}, 02:21:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:21:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:21:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:21:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:21:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:21:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:21:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:21:40 INFO - 'talos_options': ['--disable-e10s'], 02:21:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:21:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:21:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:21:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:21:40 INFO - 'talos_options': ['--disable-e10s'], 02:21:40 INFO - 'tests': ['damp', 'tps']}, 02:21:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:21:40 INFO - 'tests': ['damp', 'tps']}, 02:21:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:21:40 INFO - 'tests': ['dromaeo_dom']}, 02:21:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:21:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:21:40 INFO - 'tests': ['basic_compositor_video']}, 02:21:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:21:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:21:40 INFO - 'tests': ['a11yr', 02:21:40 INFO - 'ts_paint', 02:21:40 INFO - 'tpaint', 02:21:40 INFO - 'sessionrestore', 02:21:40 INFO - 'sessionrestore_no_auto_restore', 02:21:40 INFO - 'tabpaint']}, 02:21:40 INFO - 'other-e10s': {'tests': ['a11yr', 02:21:40 INFO - 'ts_paint', 02:21:40 INFO - 'tpaint', 02:21:40 INFO - 'sessionrestore', 02:21:40 INFO - 'sessionrestore_no_auto_restore', 02:21:40 INFO - 'tabpaint']}, 02:21:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:21:40 INFO - 'ts_paint', 02:21:40 INFO - 'tpaint', 02:21:40 INFO - 'sessionrestore', 02:21:40 INFO - 'sessionrestore_no_auto_restore', 02:21:40 INFO - 'tabpaint']}, 02:21:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:21:40 INFO - 'ts_paint', 02:21:40 INFO - 'tpaint', 02:21:40 INFO - 'sessionrestore', 02:21:40 INFO - 'sessionrestore_no_auto_restore', 02:21:40 INFO - 'tabpaint']}, 02:21:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:21:40 INFO - 'tests': ['a11yr', 02:21:40 INFO - 'ts_paint', 02:21:40 INFO - 'tpaint', 02:21:40 INFO - 'sessionrestore', 02:21:40 INFO - 'sessionrestore_no_auto_restore', 02:21:40 INFO - 'tabpaint']}, 02:21:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:21:40 INFO - 'tests': ['a11yr', 02:21:40 INFO - 'ts_paint', 02:21:40 INFO - 'tpaint', 02:21:40 INFO - 'sessionrestore', 02:21:40 INFO - 'sessionrestore_no_auto_restore', 02:21:40 INFO - 'tabpaint']}, 02:21:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:21:40 INFO - 'tests': ['tsvgx', 02:21:40 INFO - 'tsvgr_opacity', 02:21:40 INFO - 'tart', 02:21:40 INFO - 'tscrollx', 02:21:40 INFO - 'cart']}, 02:21:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:21:40 INFO - 'tsvgr_opacity', 02:21:40 INFO - 'tart', 02:21:40 INFO - 'tscrollx', 02:21:40 INFO - 'cart']}, 02:21:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:21:40 INFO - 'talos_options': ['--disable-e10s'], 02:21:40 INFO - 'tests': ['tp5o']}, 02:21:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:21:40 INFO - 'tests': ['tp5o']}, 02:21:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:21:40 INFO - 'talos_options': ['--disable-e10s', 02:21:40 INFO - '--xperf_path', 02:21:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:21:40 INFO - 'tests': ['tp5n']}, 02:21:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:21:40 INFO - 'talos_options': ['--xperf_path', 02:21:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:21:40 INFO - 'tests': ['tp5n']}}, 02:21:40 INFO - 'talos.zip': {'path': '', 02:21:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:21:40 INFO - Running post-action listener: _resource_record_post_action 02:21:40 INFO - ##### 02:21:40 INFO - ##### Finished populate-webroot step (success) 02:21:40 INFO - ##### 02:21:40 INFO - ##### 02:21:40 INFO - ##### Running create-virtualenv step. 02:21:40 INFO - ##### 02:21:40 INFO - Running pre-action listener: _resource_record_pre_action 02:21:40 INFO - Running main action method: create_virtualenv 02:21:40 INFO - Creating virtualenv /builds/slave/test/build/venv 02:21:40 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 02:21:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:21:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:21:40 INFO - Using real prefix '/usr' 02:21:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:21:40 INFO - Installing distribute.............................................................................................................................................................................................done. 02:21:42 INFO - Installing pip.................done. 02:21:42 INFO - Return code: 0 02:21:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:21:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:21:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:21:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:21:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21:42 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 0x7fbcfd624f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215c1c0>, '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 0x215d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x215d550>, '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': '8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713', '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 02:21:42 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 02:21:42 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 02:21:42 INFO - Using env: {'DISPLAY': ':0', 02:21:42 INFO - 'HOME': '/home/cltbld', 02:21:42 INFO - 'LANG': 'en_US.UTF-8', 02:21:42 INFO - 'LANGUAGE': 'en_US:en', 02:21:42 INFO - 'LOGNAME': 'cltbld', 02:21:42 INFO - 'MAIL': '/var/mail/cltbld', 02:21:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:21:42 INFO - 'MOZ_NO_REMOTE': '1', 02:21:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:21:42 INFO - 'NO_EM_RESTART': '1', 02:21:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:21:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:21:42 INFO - 'PWD': '/builds/slave/test', 02:21:42 INFO - 'SHELL': '/bin/bash', 02:21:42 INFO - 'SHLVL': '1', 02:21:42 INFO - 'TERM': 'linux', 02:21:42 INFO - 'TMOUT': '86400', 02:21:42 INFO - 'USER': 'cltbld', 02:21:42 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713', 02:21:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:21:42 INFO - '_': '/tools/buildbot/bin/python'} 02:21:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:21:42 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 02:21:42 INFO - Cleaning up... 02:21:42 INFO - Return code: 0 02:21:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:21:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:21:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:21:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:21:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21:42 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 0x7fbcfd624f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215c1c0>, '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 0x215d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x215d550>, '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': '8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713', '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 02:21:42 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 02:21:42 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 02:21:42 INFO - Using env: {'DISPLAY': ':0', 02:21:42 INFO - 'HOME': '/home/cltbld', 02:21:42 INFO - 'LANG': 'en_US.UTF-8', 02:21:42 INFO - 'LANGUAGE': 'en_US:en', 02:21:42 INFO - 'LOGNAME': 'cltbld', 02:21:42 INFO - 'MAIL': '/var/mail/cltbld', 02:21:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:21:42 INFO - 'MOZ_NO_REMOTE': '1', 02:21:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:21:42 INFO - 'NO_EM_RESTART': '1', 02:21:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:21:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:21:42 INFO - 'PWD': '/builds/slave/test', 02:21:42 INFO - 'SHELL': '/bin/bash', 02:21:42 INFO - 'SHLVL': '1', 02:21:42 INFO - 'TERM': 'linux', 02:21:42 INFO - 'TMOUT': '86400', 02:21:42 INFO - 'USER': 'cltbld', 02:21:42 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713', 02:21:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:21:42 INFO - '_': '/tools/buildbot/bin/python'} 02:21:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:21:43 INFO - Downloading/unpacking psutil>=3.1.1 02:21: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 02:21: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 02:21: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 02:21: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 02:21: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 02:21: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 02:21:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:21:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:21:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:21:45 INFO - Installing collected packages: psutil 02:21:45 INFO - Running setup.py install for psutil 02:21:45 INFO - building 'psutil._psutil_linux' extension 02:21:45 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 02:21:45 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 02:21:45 INFO - building 'psutil._psutil_posix' extension 02:21:45 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 02:21:45 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 02:21:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:21:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:21:45 INFO - Successfully installed psutil 02:21:45 INFO - Cleaning up... 02:21:45 INFO - Return code: 0 02:21:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:21:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:21:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:21:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21:45 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 0x7fbcfd624f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215c1c0>, '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 0x215d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x215d550>, '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': '8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713', '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 02:21:45 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 02:21:45 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 02:21:45 INFO - Using env: {'DISPLAY': ':0', 02:21:45 INFO - 'HOME': '/home/cltbld', 02:21:45 INFO - 'LANG': 'en_US.UTF-8', 02:21:45 INFO - 'LANGUAGE': 'en_US:en', 02:21:45 INFO - 'LOGNAME': 'cltbld', 02:21:45 INFO - 'MAIL': '/var/mail/cltbld', 02:21:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:21:45 INFO - 'MOZ_NO_REMOTE': '1', 02:21:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:21:45 INFO - 'NO_EM_RESTART': '1', 02:21:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:21:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:21:45 INFO - 'PWD': '/builds/slave/test', 02:21:45 INFO - 'SHELL': '/bin/bash', 02:21:45 INFO - 'SHLVL': '1', 02:21:45 INFO - 'TERM': 'linux', 02:21:45 INFO - 'TMOUT': '86400', 02:21:45 INFO - 'USER': 'cltbld', 02:21:45 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713', 02:21:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:21:45 INFO - '_': '/tools/buildbot/bin/python'} 02:21:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:21:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:21:45 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 02:21:45 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 02:21:45 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 02:21:45 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 02:21:45 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 02:21:45 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 02:21:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:21:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:21:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:21:47 INFO - Installing collected packages: mozsystemmonitor 02:21:47 INFO - Running setup.py install for mozsystemmonitor 02:21:47 INFO - Successfully installed mozsystemmonitor 02:21:47 INFO - Cleaning up... 02:21:47 INFO - Return code: 0 02:21:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:21:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:21:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:21:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:21:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fbcfd624f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215c1c0>, '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 0x215d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x215d550>, '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': '8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713', '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 02:21:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:21:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:21:47 INFO - Using env: {'DISPLAY': ':0', 02:21:47 INFO - 'HOME': '/home/cltbld', 02:21:47 INFO - 'LANG': 'en_US.UTF-8', 02:21:47 INFO - 'LANGUAGE': 'en_US:en', 02:21:47 INFO - 'LOGNAME': 'cltbld', 02:21:47 INFO - 'MAIL': '/var/mail/cltbld', 02:21:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:21:47 INFO - 'MOZ_NO_REMOTE': '1', 02:21:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:21:47 INFO - 'NO_EM_RESTART': '1', 02:21:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:21:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:21:47 INFO - 'PWD': '/builds/slave/test', 02:21:47 INFO - 'SHELL': '/bin/bash', 02:21:47 INFO - 'SHLVL': '1', 02:21:47 INFO - 'TERM': 'linux', 02:21:47 INFO - 'TMOUT': '86400', 02:21:47 INFO - 'USER': 'cltbld', 02:21:47 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713', 02:21:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:21:47 INFO - '_': '/tools/buildbot/bin/python'} 02:21:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:21:48 INFO - Downloading/unpacking jsonschema==2.5.1 02:21:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:21:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:21:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:21:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:21:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:21:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:21:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:21:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:21:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:21:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:21:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:21:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:21:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:21:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:21:50 INFO - Downloading functools32-3.2.3-2.tar.gz 02:21:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:21:50 INFO - warning: no files found matching '*.txt' 02:21:50 INFO - no previously-included directories found matching 'build' 02:21:50 INFO - no previously-included directories found matching 'dist' 02:21:50 INFO - no previously-included directories found matching '.git*' 02:21:50 INFO - Installing collected packages: jsonschema, functools32 02:21:50 INFO - Running setup.py install for functools32 02:21:50 INFO - warning: no files found matching '*.txt' 02:21:50 INFO - no previously-included directories found matching 'build' 02:21:50 INFO - no previously-included directories found matching 'dist' 02:21:50 INFO - no previously-included directories found matching '.git*' 02:21:50 INFO - Successfully installed jsonschema functools32 02:21:50 INFO - Cleaning up... 02:21:50 INFO - Return code: 0 02:21:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:21:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:21:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:21:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:21:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:21:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21:50 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 0x7fbcfd624f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215c1c0>, '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 0x215d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x215d550>, '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': '8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713', '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 02:21:50 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 02:21:50 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 02:21:50 INFO - Using env: {'DISPLAY': ':0', 02:21:50 INFO - 'HOME': '/home/cltbld', 02:21:50 INFO - 'LANG': 'en_US.UTF-8', 02:21:50 INFO - 'LANGUAGE': 'en_US:en', 02:21:50 INFO - 'LOGNAME': 'cltbld', 02:21:50 INFO - 'MAIL': '/var/mail/cltbld', 02:21:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:21:50 INFO - 'MOZ_NO_REMOTE': '1', 02:21:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:21:50 INFO - 'NO_EM_RESTART': '1', 02:21:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:21:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:21:50 INFO - 'PWD': '/builds/slave/test', 02:21:50 INFO - 'SHELL': '/bin/bash', 02:21:50 INFO - 'SHLVL': '1', 02:21:50 INFO - 'TERM': 'linux', 02:21:50 INFO - 'TMOUT': '86400', 02:21:50 INFO - 'USER': 'cltbld', 02:21:50 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713', 02:21:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:21:50 INFO - '_': '/tools/buildbot/bin/python'} 02:21:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:21:50 INFO - Downloading/unpacking blobuploader==1.2.4 02:21:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:21:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:21:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:21:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:21:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:21:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:21:52 INFO - Downloading blobuploader-1.2.4.tar.gz 02:21:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:21:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:21:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:21:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:21:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:21:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:21:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:21:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:21:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:21:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:21:53 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 02:21:53 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 02:21:53 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 02:21:53 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 02:21:53 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 02:21:53 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 02:21:53 INFO - Downloading docopt-0.6.1.tar.gz 02:21:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:21:53 INFO - Installing collected packages: blobuploader, requests, docopt 02:21:53 INFO - Running setup.py install for blobuploader 02:21:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:21:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:21:53 INFO - Running setup.py install for requests 02:21:53 INFO - Running setup.py install for docopt 02:21:53 INFO - Successfully installed blobuploader requests docopt 02:21:53 INFO - Cleaning up... 02:21:54 INFO - Return code: 0 02:21:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:21:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:21:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:21:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:21:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:21:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21:54 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 0x7fbcfd624f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215c1c0>, '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 0x215d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x215d550>, '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': '8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713', '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 02:21:54 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 02:21:54 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 02:21:54 INFO - Using env: {'DISPLAY': ':0', 02:21:54 INFO - 'HOME': '/home/cltbld', 02:21:54 INFO - 'LANG': 'en_US.UTF-8', 02:21:54 INFO - 'LANGUAGE': 'en_US:en', 02:21:54 INFO - 'LOGNAME': 'cltbld', 02:21:54 INFO - 'MAIL': '/var/mail/cltbld', 02:21:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:21:54 INFO - 'MOZ_NO_REMOTE': '1', 02:21:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:21:54 INFO - 'NO_EM_RESTART': '1', 02:21:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:21:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:21:54 INFO - 'PWD': '/builds/slave/test', 02:21:54 INFO - 'SHELL': '/bin/bash', 02:21:54 INFO - 'SHLVL': '1', 02:21:54 INFO - 'TERM': 'linux', 02:21:54 INFO - 'TMOUT': '86400', 02:21:54 INFO - 'USER': 'cltbld', 02:21:54 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713', 02:21:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:21:54 INFO - '_': '/tools/buildbot/bin/python'} 02:21:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:21:54 INFO - Running setup.py (path:/tmp/pip-lqbRFe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:21:54 INFO - Running setup.py (path:/tmp/pip-a2EvsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:21:54 INFO - Running setup.py (path:/tmp/pip-nG7Zyh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:21:54 INFO - Running setup.py (path:/tmp/pip-xL68WL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:21:54 INFO - Running setup.py (path:/tmp/pip-j8KDDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:21:54 INFO - Running setup.py (path:/tmp/pip-Mrz8VI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:21:54 INFO - Running setup.py (path:/tmp/pip-q2QpJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:21:54 INFO - Running setup.py (path:/tmp/pip-Zf8eXX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:21:54 INFO - Running setup.py (path:/tmp/pip-jXMjRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:21:54 INFO - Running setup.py (path:/tmp/pip-Bs4lD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:21:54 INFO - Running setup.py (path:/tmp/pip-EBJVng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:21:55 INFO - Running setup.py (path:/tmp/pip-IGaeaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:21:55 INFO - Running setup.py (path:/tmp/pip-z7eiNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:21:55 INFO - Running setup.py (path:/tmp/pip-lvxEiJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:21:55 INFO - Running setup.py (path:/tmp/pip-npOj5g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:21:55 INFO - Running setup.py (path:/tmp/pip-sVXYTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:21:55 INFO - Running setup.py (path:/tmp/pip-MokiKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:21:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:21:55 INFO - Running setup.py install for manifestparser 02:21:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:21:55 INFO - Running setup.py install for mozcrash 02:21:55 INFO - Running setup.py install for mozdebug 02:21:55 INFO - Running setup.py install for mozdevice 02:21:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:21:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:21:55 INFO - Running setup.py install for mozfile 02:21:55 INFO - Running setup.py install for mozhttpd 02:21:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:21:56 INFO - Running setup.py install for mozinfo 02:21:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:21:56 INFO - Running setup.py install for mozInstall 02:21:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:21:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:21:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:21:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:21:56 INFO - Running setup.py install for mozleak 02:21:56 INFO - Running setup.py install for mozlog 02:21:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:21:56 INFO - Running setup.py install for moznetwork 02:21:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:21:56 INFO - Running setup.py install for mozprocess 02:21:56 INFO - Running setup.py install for mozprofile 02:21:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:21:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:21:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:21:56 INFO - Running setup.py install for mozrunner 02:21:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:21:56 INFO - Running setup.py install for mozscreenshot 02:21:57 INFO - Running setup.py install for moztest 02:21:57 INFO - Running setup.py install for mozversion 02:21:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:21:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:21:57 INFO - Cleaning up... 02:21:57 INFO - Return code: 0 02:21:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:21:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:21:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:21:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:21:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:21:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:21:57 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 0x7fbcfd624f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215c1c0>, '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 0x215d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x215d550>, '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': '8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713', '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 02:21:57 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 02:21:57 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 02:21:57 INFO - Using env: {'DISPLAY': ':0', 02:21:57 INFO - 'HOME': '/home/cltbld', 02:21:57 INFO - 'LANG': 'en_US.UTF-8', 02:21:57 INFO - 'LANGUAGE': 'en_US:en', 02:21:57 INFO - 'LOGNAME': 'cltbld', 02:21:57 INFO - 'MAIL': '/var/mail/cltbld', 02:21:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:21:57 INFO - 'MOZ_NO_REMOTE': '1', 02:21:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:21:57 INFO - 'NO_EM_RESTART': '1', 02:21:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:21:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:21:57 INFO - 'PWD': '/builds/slave/test', 02:21:57 INFO - 'SHELL': '/bin/bash', 02:21:57 INFO - 'SHLVL': '1', 02:21:57 INFO - 'TERM': 'linux', 02:21:57 INFO - 'TMOUT': '86400', 02:21:57 INFO - 'USER': 'cltbld', 02:21:57 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713', 02:21:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:21:57 INFO - '_': '/tools/buildbot/bin/python'} 02:21:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:21:57 INFO - Running setup.py (path:/tmp/pip-H8FMpq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:21:57 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)) 02:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:21:57 INFO - Running setup.py (path:/tmp/pip-xl5gyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:21:57 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)) 02:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:21:57 INFO - Running setup.py (path:/tmp/pip-VEkWYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:21:57 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)) 02:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:21:57 INFO - Running setup.py (path:/tmp/pip-jLrhwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:21:57 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)) 02:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:21:57 INFO - Running setup.py (path:/tmp/pip-hFsGx8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:21:57 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)) 02:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:21:57 INFO - Running setup.py (path:/tmp/pip-sOfq6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:21:57 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)) 02:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:21:57 INFO - Running setup.py (path:/tmp/pip-iuU04G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:21:57 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)) 02:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:21:57 INFO - Running setup.py (path:/tmp/pip-tdvugk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:21:58 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)) 02:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:21:58 INFO - Running setup.py (path:/tmp/pip-37Wr9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:21:58 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)) 02:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:21:58 INFO - Running setup.py (path:/tmp/pip-m51rWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:21:58 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)) 02:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:21:58 INFO - Running setup.py (path:/tmp/pip-viGPaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:21:58 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)) 02:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:21:58 INFO - Running setup.py (path:/tmp/pip-XHzglm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:21:58 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)) 02:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:21:58 INFO - Running setup.py (path:/tmp/pip-eM1RDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:21:58 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)) 02:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:21:58 INFO - Running setup.py (path:/tmp/pip-iHRDl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:21:58 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)) 02:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:21:58 INFO - Running setup.py (path:/tmp/pip-xTekBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:21:58 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)) 02:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:21:58 INFO - Running setup.py (path:/tmp/pip-RvpCfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:21:58 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)) 02:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:21:58 INFO - Running setup.py (path:/tmp/pip-Y_I4EZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:21:58 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)) 02:21:58 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)) 02:21:58 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)) 02:21:58 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)) 02:21:58 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)) 02:21:58 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)) 02:21:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:21:58 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 02:21:58 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 02:21:58 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 02:21:58 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 02:21:58 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 02:21:58 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 02:22:00 INFO - Downloading blessings-1.6.tar.gz 02:22:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:22:00 INFO - Installing collected packages: blessings 02:22:00 INFO - Running setup.py install for blessings 02:22:00 INFO - Successfully installed blessings 02:22:00 INFO - Cleaning up... 02:22:00 INFO - Return code: 0 02:22:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:22:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:22:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:22:01 INFO - Reading from file tmpfile_stdout 02:22:01 INFO - Current package versions: 02:22:01 INFO - argparse == 1.2.1 02:22:01 INFO - blessings == 1.6 02:22:01 INFO - blobuploader == 1.2.4 02:22:01 INFO - docopt == 0.6.1 02:22:01 INFO - functools32 == 3.2.3-2 02:22:01 INFO - jsonschema == 2.5.1 02:22:01 INFO - manifestparser == 1.1 02:22:01 INFO - mozInstall == 1.12 02:22:01 INFO - mozcrash == 0.17 02:22:01 INFO - mozdebug == 0.1 02:22:01 INFO - mozdevice == 0.48 02:22:01 INFO - mozfile == 1.2 02:22:01 INFO - mozhttpd == 0.7 02:22:01 INFO - mozinfo == 0.9 02:22:01 INFO - mozleak == 0.1 02:22:01 INFO - mozlog == 3.2 02:22:01 INFO - moznetwork == 0.27 02:22:01 INFO - mozprocess == 0.22 02:22:01 INFO - mozprofile == 0.28 02:22:01 INFO - mozrunner == 6.11 02:22:01 INFO - mozscreenshot == 0.1 02:22:01 INFO - mozsystemmonitor == 0.3 02:22:01 INFO - moztest == 0.7 02:22:01 INFO - mozversion == 1.4 02:22:01 INFO - psutil == 3.1.1 02:22:01 INFO - requests == 1.2.3 02:22:01 INFO - wsgiref == 0.1.2 02:22:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:22:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:01 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 0x7fbcfd624f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215c1c0>, '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 0x215d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x215d550>, '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': '8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713', '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 02:22:01 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 02:22:01 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 02:22:01 INFO - Using env: {'DISPLAY': ':0', 02:22:01 INFO - 'HOME': '/home/cltbld', 02:22:01 INFO - 'LANG': 'en_US.UTF-8', 02:22:01 INFO - 'LANGUAGE': 'en_US:en', 02:22:01 INFO - 'LOGNAME': 'cltbld', 02:22:01 INFO - 'MAIL': '/var/mail/cltbld', 02:22:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:01 INFO - 'MOZ_NO_REMOTE': '1', 02:22:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:22:01 INFO - 'NO_EM_RESTART': '1', 02:22:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:22:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:01 INFO - 'PWD': '/builds/slave/test', 02:22:01 INFO - 'SHELL': '/bin/bash', 02:22:01 INFO - 'SHLVL': '1', 02:22:01 INFO - 'TERM': 'linux', 02:22:01 INFO - 'TMOUT': '86400', 02:22:01 INFO - 'USER': 'cltbld', 02:22:01 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713', 02:22:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:01 INFO - '_': '/tools/buildbot/bin/python'} 02:22:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:01 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)) 02:22:01 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)) 02:22:01 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)) 02:22:01 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)) 02:22:01 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)) 02:22:01 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)) 02:22:01 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)) 02:22:01 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)) 02:22:01 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)) 02:22:01 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)) 02:22:01 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)) 02:22:01 INFO - Cleaning up... 02:22:01 INFO - Return code: 0 02:22:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:22:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:01 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 0x7fbcfd624f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215c1c0>, '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 0x215d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x215d550>, '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': '8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713', '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 02:22:01 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 02:22:01 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 02:22:01 INFO - Using env: {'DISPLAY': ':0', 02:22:01 INFO - 'HOME': '/home/cltbld', 02:22:01 INFO - 'LANG': 'en_US.UTF-8', 02:22:01 INFO - 'LANGUAGE': 'en_US:en', 02:22:01 INFO - 'LOGNAME': 'cltbld', 02:22:01 INFO - 'MAIL': '/var/mail/cltbld', 02:22:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:01 INFO - 'MOZ_NO_REMOTE': '1', 02:22:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:22:01 INFO - 'NO_EM_RESTART': '1', 02:22:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:22:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:01 INFO - 'PWD': '/builds/slave/test', 02:22:01 INFO - 'SHELL': '/bin/bash', 02:22:01 INFO - 'SHLVL': '1', 02:22:01 INFO - 'TERM': 'linux', 02:22:01 INFO - 'TMOUT': '86400', 02:22:01 INFO - 'USER': 'cltbld', 02:22:01 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713', 02:22:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:01 INFO - '_': '/tools/buildbot/bin/python'} 02:22:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:22:01 INFO - Cleaning up... 02:22:01 INFO - Return code: 0 02:22:01 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:22:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:22:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:22:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:22:01 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 0x7fbcfd624f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20028a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x215c1c0>, '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 0x215d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x215d550>, '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': '8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713', '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 02:22:01 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 02:22:01 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 02:22:01 INFO - Using env: {'DISPLAY': ':0', 02:22:01 INFO - 'HOME': '/home/cltbld', 02:22:01 INFO - 'LANG': 'en_US.UTF-8', 02:22:01 INFO - 'LANGUAGE': 'en_US:en', 02:22:01 INFO - 'LOGNAME': 'cltbld', 02:22:01 INFO - 'MAIL': '/var/mail/cltbld', 02:22:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:01 INFO - 'MOZ_NO_REMOTE': '1', 02:22:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:22:01 INFO - 'NO_EM_RESTART': '1', 02:22:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:22:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:01 INFO - 'PWD': '/builds/slave/test', 02:22:01 INFO - 'SHELL': '/bin/bash', 02:22:01 INFO - 'SHLVL': '1', 02:22:01 INFO - 'TERM': 'linux', 02:22:01 INFO - 'TMOUT': '86400', 02:22:01 INFO - 'USER': 'cltbld', 02:22:01 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713', 02:22:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:01 INFO - '_': '/tools/buildbot/bin/python'} 02:22:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:22:01 INFO - Downloading/unpacking flake8 02:22:01 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 02:22:01 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 02:22:01 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 02:22:01 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 02:22:01 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 02:22:01 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 02:22:03 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:22:03 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:03 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:22:03 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:22:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:22:04 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:22:04 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 02:22:04 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 02:22:04 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 02:22:04 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 02:22:04 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 02:22:04 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 02:22:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:22:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:22:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:22:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:22:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:22:04 INFO - no previously-included directories found matching 'docs/_build' 02:22:04 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:22:04 INFO - Running setup.py install for pep8 02:22:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:22:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:22:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:22:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:22:04 INFO - no previously-included directories found matching 'docs/_build' 02:22:04 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:22:04 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:22:04 INFO - Cleaning up... 02:22:04 INFO - Return code: 0 02:22:04 INFO - Running post-action listener: _resource_record_post_action 02:22:04 INFO - Running post-action listener: _start_resource_monitoring 02:22:04 INFO - Starting resource monitoring. 02:22:04 INFO - ##### 02:22:04 INFO - ##### Finished create-virtualenv step (success) 02:22:04 INFO - ##### 02:22:04 INFO - ##### 02:22:04 INFO - ##### Running install step. 02:22:04 INFO - ##### 02:22:04 INFO - Running pre-action listener: _resource_record_pre_action 02:22:04 INFO - Running main action method: install 02:22:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:22:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:22:04 INFO - Reading from file tmpfile_stdout 02:22:04 INFO - Detecting whether we're running mozinstall >=1.0... 02:22:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:22:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:22:04 INFO - Reading from file tmpfile_stdout 02:22:04 INFO - Output received: 02:22:04 INFO - Usage: mozinstall [options] installer 02:22:04 INFO - Options: 02:22:04 INFO - -h, --help show this help message and exit 02:22:04 INFO - -d DEST, --destination=DEST 02:22:04 INFO - Directory to install application into. [default: 02:22:04 INFO - "/builds/slave/test"] 02:22:04 INFO - --app=APP Application being installed. [default: firefox] 02:22:04 INFO - mkdir: /builds/slave/test/build/application 02:22:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 02:22:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 02:22:21 INFO - Reading from file tmpfile_stdout 02:22:21 INFO - Output received: 02:22:21 INFO - /builds/slave/test/build/application/firefox/firefox 02:22:21 INFO - Running post-action listener: _resource_record_post_action 02:22:21 INFO - ##### 02:22:21 INFO - ##### Finished install step (success) 02:22:21 INFO - ##### 02:22:21 INFO - ##### 02:22:21 INFO - ##### Running run-tests step. 02:22:21 INFO - ##### 02:22:21 INFO - Running pre-action listener: _resource_record_pre_action 02:22:21 INFO - Running main action method: run_tests 02:22:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:22:21 INFO - Python 2.7.3 02:22:21 INFO - Return code: 0 02:22:21 INFO - grabbing minidump binary from tooltool 02:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:21 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 0x215c1c0>, '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 0x215d0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x215d550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:22:21 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 02:22:21 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 02:22:21 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 02:22:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:22:21 INFO - Return code: 0 02:22:21 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:22:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:22:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:22:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:22:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:22:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:22:22 INFO - Return code: 0 02:22:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 02:22:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-038 --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 02:22:22 INFO - Using env: {'DISPLAY': ':0', 02:22:22 INFO - 'HOME': '/home/cltbld', 02:22:22 INFO - 'LANG': 'en_US.UTF-8', 02:22:22 INFO - 'LANGUAGE': 'en_US:en', 02:22:22 INFO - 'LOGNAME': 'cltbld', 02:22:22 INFO - 'MAIL': '/var/mail/cltbld', 02:22:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:22:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:22:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:22:22 INFO - 'MOZ_NO_REMOTE': '1', 02:22:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:22:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:22:22 INFO - 'NO_EM_RESTART': '1', 02:22:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:22:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:22:22 INFO - 'PWD': '/builds/slave/test', 02:22:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:22:22 INFO - 'SHELL': '/bin/bash', 02:22:22 INFO - 'SHLVL': '1', 02:22:22 INFO - 'TERM': 'linux', 02:22:22 INFO - 'TMOUT': '86400', 02:22:22 INFO - 'USER': 'cltbld', 02:22:22 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713', 02:22:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:22:22 INFO - '_': '/tools/buildbot/bin/python'} 02:22:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 02:22:22 INFO - mozversion application_buildid: 20160613010526 02:22:22 INFO - mozversion application_changeset: fbc8f897e016fe44393bb2e3c064071598b8f586 02:22:22 INFO - mozversion application_display_name: Nightly 02:22:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:22:22 INFO - mozversion application_name: Firefox 02:22:22 INFO - mozversion application_remotingname: firefox 02:22:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:22:22 INFO - mozversion application_vendor: Mozilla 02:22:22 INFO - mozversion application_version: 50.0a1 02:22:22 INFO - mozversion platform_buildid: 20160613010526 02:22:22 INFO - mozversion platform_changeset: fbc8f897e016fe44393bb2e3c064071598b8f586 02:22:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:22:22 INFO - mozversion platform_version: 50.0a1 02:22:22 INFO - using testdate: 1465809742 02:22:22 INFO - actual date: 1465809742 02:22:22 INFO - starting webserver on 'localhost:60317' 02:22:22 INFO - SUITE-START | Running 2 tests 02:22:22 INFO - TEST-START | dromaeo_css 02:22:22 INFO - operating with platform_type : linux_ 02:22:22 INFO - Initialising browser for dromaeo_css test... 02:22:22 INFO - TEST-INFO | started process 24372 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp9Ub8I/profile http://localhost:60317/getInfo.html) 02:22:28 INFO - PROCESS | 24372 | __metrics Screen width/height:1600/1200 02:22:28 INFO - PROCESS | 24372 | colorDepth:24 02:22:28 INFO - PROCESS | 24372 | Browser inner width/height: 1024/697 02:22:28 INFO - PROCESS | 24372 | __metrics 02:22:29 INFO - PROCESS | 24372 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:22:29 INFO - PROCESS | 24372 | console.error: PushService: 02:22:29 INFO - PROCESS | 24372 | stateChangeProcessEnqueue: Error transitioning state 02:22:29 INFO - PROCESS | 24372 | UnknownError 02:22:30 INFO - TEST-INFO | 24372: exit 0 02:22:30 INFO - Browser initialized. 02:22:30 INFO - Running cycle 1/1 for dromaeo_css test... 02:22:30 INFO - TEST-INFO | started process 24453 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp9Ub8I/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:22:42 INFO - PROCESS | 24453 | RSS: Main: 175579136 02:22:42 INFO - PROCESS | 24453 | 02:23:33 INFO - PROCESS | 24453 | 1465809813749 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] 02:25:46 INFO - PROCESS | 24453 | Cycle 1(1): loaded http://localhost:60317/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60317/tests/dromaeo/cssquery-ext.html) 02:25:48 INFO - PROCESS | 24453 | RSS: Main: 257941504 02:25:48 INFO - PROCESS | 24453 | 02:28:50 INFO - PROCESS | 24453 | Cycle 1(1): loaded http://localhost:60317/tests/dromaeo/cssquery-ext.html (next: http://localhost:60317/tests/dromaeo/cssquery-jquery.html) 02:28:51 INFO - PROCESS | 24453 | RSS: Main: 253521920 02:28:51 INFO - PROCESS | 24453 | 02:31:53 INFO - PROCESS | 24453 | Cycle 1(1): loaded http://localhost:60317/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60317/tests/dromaeo/cssquery-mootools.html) 02:31:54 INFO - PROCESS | 24453 | RSS: Main: 250544128 02:31:54 INFO - PROCESS | 24453 | 02:34:56 INFO - PROCESS | 24453 | Cycle 1(1): loaded http://localhost:60317/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60317/tests/dromaeo/cssquery-prototype.html) 02:34:56 INFO - PROCESS | 24453 | RSS: Main: 253923328 02:34:56 INFO - PROCESS | 24453 | 02:38:00 INFO - PROCESS | 24453 | Cycle 1(1): loaded http://localhost:60317/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60317/tests/dromaeo/cssquery-yui.html) 02:38:00 INFO - PROCESS | 24453 | RSS: Main: 237953024 02:38:00 INFO - PROCESS | 24453 | 02:41:03 INFO - PROCESS | 24453 | Cycle 1(1): loaded http://localhost:60317/tests/dromaeo/cssquery-yui.html (next: http://localhost:60317/tests/dromaeo/cssquery-dojo.html) 02:41:03 INFO - PROCESS | 24453 | RSS: Main: 271208448 02:41:03 INFO - PROCESS | 24453 | 02:41:03 INFO - PROCESS | 24453 | __start_tp_report 02:41:03 INFO - PROCESS | 24453 | _x_x_mozilla_page_load 02:41:03 INFO - PROCESS | 24453 | _x_x_mozilla_page_load_details 02:41:03 INFO - PROCESS | 24453 | |i|pagename|runs| 02:41:03 INFO - PROCESS | 24453 | |0;dojo.html;2669.919902402928;2678.3973605938654;2711.3763834318106;2711.593261010848;2722.823016503927;4753.47711751707;4810.44679861816;4858.975705121474;4935.111679897622;5002.199648056312;4843.806247750086;4860.2709593560985;4860.927086093711;4895.853124406273;4902.779374928128;4413.205622987869;4441.156180325728;4470.955290447103;4532.864014079566;4581.67928245023;4512.932306015412;4515.05183911379;4543.204939135655;4548.022175232324;4581.7709114544305;4267.637250833683;4396.956030439686;4398.538153493869;4444.422225110748;4462.821487140511;4440;4453.487848897385;4477.283634618478;4483.08096840147;4522.841700540465;4422.469303683546;4426.203283408985;4426.756528390937;4455.955440445587;4479.776011199427;4678.7660616969015;4774.498677638832;4848.200046992242;4852.053849499349;4891.9755401222765;5217.50433708797;5241.73791310433;5250.081235783722;5257.5005374489365;5270.947290527084;5046.697198168122;5300.443453437372;5307.681539107627;5309.601779866487;5318.335208098988;4810.879728006757;4864.221724524059;4870.220764677634;4874.098291816025;4897.094037603055;5388.353397592314;5388.622796404214;5391.784328626811;5391.81128660495;5393.487618676219;4919.631027672939;5120.052790233819;5120.564751996122;5121.385433747974;5125.794968201305;5258.605604579672;5266.947330526722;5288.68267903927;5289.735513224324;5341.385740639798;5218.765155568009;5232.162853943427;5233.487925795564;5234.685918844882;5292.576593872481;4970.502949705001;5015.498450155028;5091.770870310845;5213.061648903234;5221.765020574083;4331.805068771912;4380.102079073846;4395.120975804852;4421.977890110593;4422.71252368595;3826.196498735241;3837.2901013312626;3840.251151025579;3879.5344558652578;3880.708946828999;4377.16833801583;4512.503624601291;4514.796834142471;4522.027764030683;4571.314302854532;5231.476852314814;5265.552428043659;5266.1574148136115;5277.947220527745;5293.7353132344;4547.909041819145;4553.840615578439;4556.977215113903;4566.315052742115;4601.378813860153;4505.504394516601;4593.127305811885;4670.462896766915;4685.1800934836265;4757.286407038902;4856.854294371175;4892.975535122301;4903.362562866947;4910.165271903857;4950.257461380822;5203.92194117081;5218.373795144607;5224.425313215467;5225.007248622902;5274.525292723673;4781.856544303677;4891.877703057452;4899.118158731462;4900.901981960412;4909.828156014522;5158.819441319536;5175.404828444777;5176.689398636094;5184.637075404686;5223.347081614798;5229.6339256251695;5241.9737901310245;5248.501392367719;5250.606204534599;5297.629165958346;4891.657583969088;5049.444561098203;5090.923636145387;5101.183810590288;5128.410232823273;5084.771185296597;5088.771005304696;5104.872378190426;5104.897902042013;5157.277981082727;5038.2190760432195;5045.419776725724;5099.949000509948;5100.285959965682;5105.33630628016;4902.92645610309;5101.974490127525;5146.562542183956;5173.974130129476;5178.948210517847;5002.449730529712;5028.446870844131;5030.195168772979;5039.899202016012;5089.338386009795 02:41:03 INFO - PROCESS | 24453 | |1;ext.html;567.4759742002167;578.2944807335052;581.819635912867;584.8333225030867;593.4451288045677;27227;39380.80309598451;40388.39417408738;40485.19029619406;44179.11641767163;39802;40206.99999999993;40231.798841005824;40297.7985110074;40321.79839100801;7406.444516661245;7784.766457006284;7935.523868567876;9895.752606184868;10255.589776408935;10072.395656260647;10192.082712555868;10192.439415832163;10203.591856325775;10207.948960255188;10254.794904101913;10283.845742313872;10293.433861137635;10304.124149447269;10312.381257124596;7583.469157159001;9989.300748947613;10267.43529105899;10268.127209187229;10620.946895265473;10070.446125463097;10152.08631223186;10152.746181345525;10160.034796694303;10163.745906352326;38690.806545967374;39073.60926390728;39114.608853911384;39134.804325978184;39235;3177.936441271185;3187.6334221564584;3227.4190645683766;3311.850966706504;3462.601800792916;29779.25551861199;30399.696003039906;30430.956904309613;30643.693563064306;30667.11710816563;3249.69127932846;3291.8024918504966;3301.5047742838765;3310.420676381648;3358.395488812013;247847;249456;249649;250021;251918;30925.69074309228;32521;32574.348513029607;54384.728076359366;54948;33851.4922276166;33972.15069623279;33985.32029359399;34033.48949765755;34253.486197707054;11057;13413.731725365438;13543.458261669422;13545.255010974492;13549.187048777105;10915.181361397927;11122;11158.107351411867;11367.715807104723;11370.94314528422;7904.881426778603;7917.802054948557;7925.484843485155;7998.440109192301;8005.239502247277;3034.226272300549;3040.665526792051;3050.816950982948;3067.8005929614505;3091.211741006074;846.9026062002825;881.8104107616894;908.9590968406437;962.6967505235829;999.6201443451442;27480.450390992068;28806;28926;29303.706962930097;29360.706392936223;919.434547753127;957.9089986451276;986.2947992185622;1011.2163073617858;1037.600523798328;14255.287235638176;14874.107553546823;14886.734627556563;14948.177850218124;14958.252087395587;398766;400383;402179;403572;403825;50757.23864142041;51814.740926295126;51850.96298074017;51936;51944.74027629838;1068.8770791358938;1197.8921897029309;1206.4209179593668;1238.0652607281445;1248.4694005047818;28211.57682634721;29637.25906852346;29762;29835.25411864634;30429;44352.556474434845;44509.33236001527;45063.54936450725;45130.54869451263;45164.77417612891;303337;304933;304938;305250;305368;22284.220052298093;24214;25759.227223183334;25778.09776657808;26996;42620.36069459023;42799.7860010698;42806.14387712291;43348.56651433445;43845.34231986459;37147.25705485929;37388.25223495569;37461.62538374581;37825.62174378331;38103.38017888296;37140;37152;37196;37395.6260437403;37654.246915061;36398.45402319021;36719.81640091889;37509.062273443385;38538.229235414576;38587.807060965635;32833.34333313368;34010.65989340075;34317.14207144841;34787.434565445124;35391;26395.076172334644;26433.867830660725;26490;26738.59892101581;26785.464290714466 02:41:03 INFO - PROCESS | 24453 | |2;jquery.html;378.10012171032946;378.95452545694513;379.1715102501034;386.8510623409988;391.74340806771573;15688.764668529968;16193.838061619395;17072.658546829087;17077.914610426946;17097.23062462189;15479;16126.516204513853;17089;17090;17110.572235694115;4575.931361029588;4804.231322988323;4809.7835597398025;4812.181929072049;4812.181929072066;3521.1021189596618;3659.1035196376793;5274.868128296785;5561.082421400484;5582.246396736429;5572.832815015536;5595.244641973342;5628.662280263176;5639.323281206241;5640.605157638967;5827.533797296227;6163.3528479509905;6163.815085547396;6175.505959523227;6176.4441200292185;3339.231976645361;5000.324956130912;5346.03771321162;5351.25082488452;5459.208414779874;12475.50097996079;12555.05837062224;12657.556985505555;14044.508442204473;20521.794782052137;1898.563330433994;1974.664307067802;2077.491014701393;2077.501399664085;2512.798976081909;8525.74422767318;8862.822743545157;9153.954230228806;9252.352335336462;9792.902070979342;1775.5628832754;1840.9815901840943;1844.6034102667857;1847.5196448923377;1858.1545396844404;62800;63104.684476577786;64682;66561.334386656;67072.66463667699;9731.172850307803;9807.75480612976;10192.1336686381;10216.948915255376;10244.129249013768;13899.235542045284;13945.511907083204;13971.930140349437;14012.579622611338;14015.439382424793;6947.131608548932;7248.9275107249305;7260.745873894494;7447.143578488435;7456.440766942501;6649.867002659919;6813.693383797741;6825.283345248777;6839.076724642209;7167.92832071683;4353.172897149531;4460.085682435093;4481.058977614673;4538.750368729751;4558.703684260512;1726.680564095621;1782.117851663409;1845.178895391538;1854.2582966813113;1869.1869036968963;760.2739383888484;807.3460496997451;819.7049062337554;866.3675516872593;908.3732224764891;16530.02217997468;16632.168391580373;16952.57618559521;17065.14674266282;17117.14414279281;827.4538804389194;849.8172892828071;877.863931090682;880.372089313399;881.7310720230263;9298.488583127857;9707.805843883083;9715.902840971641;9832.557534910946;9896.604135834486;284391;289412;402970;405984;407414;77404.22595774083;79189.60405197938;80052.59973700094;80635.59682201552;81373;895.5029958373166;937.1378331934636;947.1570302430843;950.0404591362774;956.7608097975506;15364.00133991287;15389.69220615559;15656.295466703798;16071.27679254413;16083.276252568896;26577.33556661099;27070.72929270682;36053.27893442169;36760.81619591885;37289.44065839069;49103.75448122736;49888.75055624699;50327.245091322926;50497.45522902956;51088;7599.013089446262;7685.654145563351;7779.144294127755;7854.332381747616;7976.6410511525955;8816.86774698393;11364.374959377314;11399.886001139883;11709.180357375242;11922.403879806148;9593.28050396209;9664.178544823768;9666.226701863974;9823.16503097216;9842.803143937224;9704.417734935927;10242.692719218436;10417.791644167226;10630.574777008833;10858.837117443403;9698.85451718239;10197.13324367437;10662.73343166396;10686.625968091079;10918.399488028235;6781.38967492928;6814.386705196556;7169.928300717134;7330.413566914527;7961.72133975308;7714.3442807362;9480.573374198315;9924.801503970024;9979.650712225037;10048.82973565962 02:41:03 INFO - PROCESS | 24453 | |3;mootools.html;1229.9508019679213;1288.5876519513756;1367.994524024842;1372.7254549090178;1668.0408764960143;6837.453003759693;7583.469157158994;7772.844543109134;7811.6875324987;8033.277005069543;7808.8047798805055;7949.523028618272;7955.124936257022;7984.720534781284;7986.161453047419;2249.5838269920037;2278.5556816420803;2280.5438912217624;2280.931572052841;2283.668868014137;2208.8785116818567;2232.363776323748;2262.954740905181;2268.1721171772265;2268.330842401487;2213.1700612270397;2218.190360518407;2219.511707424364;2222.1777942161425;2251.864888106719;2179.7820217978233;2189.091527016294;2194.594000109985;2198.703175071369;2199.087378737822;2161.502854343494;2178.6405243134864;2201.2955854126685;2203.4160947348964;2208.2712704807377;7735.342495887856;7926.445148839583;7973;8038.437309388345;8038.598070096472;4639.6752227344095;4791.47293797682;4875.58557522611;4876.609871210295;4879.048585525824;2843.1896909380725;2994.4609970205356;3052.4047810676925;3053.954190687119;3059.632844058705;886.2776836877988;889.0798024045147;904.841652710773;911.9589618467119;917.9173874351274;10885.18361122919;11142.164337674707;11158.665240042812;11160.218784685076;11166.441677916071;4320.503142138631;4423.889402764892;4481.3726078349055;4489.069700029019;4515.864524064284;9553.283503737248;9566.712998610054;9567.138957493858;9567.856482152773;9568.4737339447;4740.5733483986605;4742.644301677319;4743.217369134054;4745.501722319176;4748.430188377348;3809.2952803730846;4207.6423504002505;4280.59334363235;4345.891352716144;4360.629346505519;3771.453139294787;3783.281176576442;3897.4543563901075;3924.7841368724994;3951.05174758056;1227.374039239994;1235.518147922311;1246.4515613130193;1246.9812952805714;1254.0937774582583;576.2426448758687;744.3896005275622;786.3080489169495;830.7217082277384;837.2213841127809;5127.307813445212;5301.231321458367;5348.304720386325;5360.517553420133;5489.533389661923;833.3874602167402;843.2073850580434;892.4600616626848;903.1284810158229;906.9637214511346;3593.317269718745;3635.5092062571216;3673.6142705016123;3744.457053727249;3763.303788799081;9699.466529340812;9936.205103591696;9968.700938971842;10017.198624110199;10056.396616203052;9923.354981926152;9941.900580994243;9944.950275248577;9946.801063978824;9951.950240248752;7004.859902802017;7319.268073192745;7327.706891724271;7417.221191774894;7583.824507201392;5196.792128314976;5202.765875535534;5244.921326180187;5308.442613525524;5351.732413379237;9071.138241867013;9139.086091390807;9216.2626989842;9284.396514226553;9297.349185557217;8920.018797932353;9279.536023198678;9489.66786162481;9498.382605130644;9541.475218862752;6567.2447668518735;6750.797476075725;6779.6271205084195;6791.049253104668;6925.065116209247;7745.728899488491;7799.805004874924;7832.530048197126;7869.3311068559815;7872.212778722082;5278.261043453843;6567.507436942153;6792.218894827158;6800.591964482147;6878.58728476293;6889.655517224019;6904.965475172592;7049.7885063448175;7085.787426377217;7165.140183178052;6290.402411770874;6348.269948955929;6353.428191462605;6357.332480089709;6358.682065896779;4400.207962566768;4591.8392856249875;4608.976955115203;4646.233371493666;4673.088747694167;4467.195904737178;4559.316102584638;4563.520830312902;4565.6119229865835;4581.015081757305 02:41:03 INFO - PROCESS | 24453 | |4;prototype.html;308.82088388734536;314.4355881193259;314.7434840604907;319.3819958380535;319.5733695516485;4924.679895806775;4928.137575924216;4966.379202599675;4997.175466048102;5048.617426324362;4818.08456393285;4888.68223565468;4899.432181701857;4900.632452566063;4913.434954980178;3647.708183345326;3789.848406063768;3800.37293846515;3801.688417495973;3836.136869204435;3741.2143449875557;3775.5658099318516;3851.1527463957887;3931.115499012728;3937.2912875682364;3839.4240863870364;3859.9421008684894;3862.2082473093087;3864.8840534784003;3870.2453021660785;3865.1689886674358;3877.108265099043;3898.7075969302273;3898.961010389888;3925.9596207005166;3246.383187194449;3335.399628066947;3354.8993530194134;3362.411577973845;3365.562476878015;4797.856064318076;4798.880027999293;4812.171374877559;4820.879478013043;4829.661923665345;4434.401355816955;4448.466184057901;4476.761619190404;4490.146872094291;4509.301058335963;4946.455889852114;5109.310243117169;5112.744362781846;5126.205438157054;5256.947430525723;3720.6651401374006;3746.1758413149064;3748.962510374916;3754.155315054091;3760.417135344026;5133.997740881059;5207.255569384223;5353.598480113968;5360.16917377807;5402.297701298806;3758.6241375862196;3766.133789228438;3767.453719210699;3776.055986003499;3799.4490798834013;3821.6178382162116;3826.330392181357;3831.6934645228316;3849.480320156799;3851.1527463957887;4221.514525829508;4351.3472979053395;4365.847195348148;4371.628411584988;4383.298672212431;5119.360079990001;5149.124648809711;5150.48495150482;5156;5162.328897243335;1328.2827273272517;1342.3489607540334;1345.5828693105168;1351.7698894006407;1355.1124013770996;845.9661613535512;883.7083762358535;893.2585953658448;914.849049906758;916.5692124701379;4058.5332686740812;4067.91864162721;4068.3694027425795;4133.689973252018;4135.91728165435;4638.8608341749805;4702.388689470347;4706.552877476634;4719.457262414866;4755.524447555217;4085.4484644572603;4095.5904409558807;4115.609017143366;4131.492005418001;4152.937705934413;2427;2477.764612361823;2493.6882889638796;2520.676644761478;2583.263769825628;4378.78106094694;4450.153186427597;4457;4458.621017213508;4474.485434175046;4783.206297638386;4819.012102518976;4826;4835.564799168092;4863.975680121577;4482.21561226784;4655.255159174516;5063.594912406998;5073.518015788494;5084.491550844886;5276.498732620395;5311.362636483646;5345.812896548602;5370.865728356823;5395.622306438669;5184.403793563638;5190.584753219809;5246.816361427332;5249.133892907627;5259.973700131628;4859.246816743411;4991.775370108281;4994.825181118644;4997.175466048061;5001.299558150246;3734.981325093466;3786.2995345861104;3799.6770274526975;3821.885343439701;3824.2351529693615;5307.174312288953;5372.817980044384;5384.165454354581;5392.568594512508;5398.62209645321;5097.547199048254;5122.771594356467;5126.4360920297995;5135.92296115566;5136.614753893398;5094.5160209780015;5134.332536770196;5138.434772175133;5192.221166825007;5227.686338819683;4886.609071274218;5107.361579802525;5131.230315452712;5141.974290128675;5171;5207;5210.869728256824;5220.138677118233;5235.450277720937;5237.135872581134;5088.5674717647935;5127.974360128175;5128.410232823273;5147.485251474823;5168.276441298146 02:41:03 INFO - PROCESS | 24453 | |5;yui.html;657.6956843060713;686;691.8443350246192;695.572223082804;718.6649683043963;11974.940125299372;12969.481220751182;12972.675683107927;12978.93510532446;12993.61019169424;12710.872891271085;12974.091813573054;13003.739925201491;13004.47982080718;13022.609321720363;619.8791235709016;752.789219018673;765.7894079128242;774.0633833062002;774.7791879314397;780.2899361580962;783.0994356490025;785.5718633344841;786.0920636664662;789.2462698123278;826.7933016745814;835.4068611285963;836.1972506393869;837.0499483086679;840.8906842110548;657.8585604095094;815.2744057788573;833.3166803221361;834.929031032363;835.7409203147043;610.7160170520684;610.9877802443971;611.4802417944755;612.5742608886825;613.2977740487162;12893.484260629657;12896.290704011275;12899.67750806228;12928.418221180069;12930.418131184026;11717.882821171765;12112.81830772539;12162.939185304105;12229.449674764657;12236.204646697935;9798.118169364792;10281.742956426075;10793.136549076055;10845.51195196218;11049.005589496985;4118.464599602063;4185.309423945046;4206.3690446432975;4206.894827629303;4343.2399330117105;61038.389616104156;62297.37702623006;62344.37655623431;62505.68747156281;62536;9915.504224788732;10012.199024078056;10161.4919254037;10165.847512287322;10307.175425965905;17429.04140272272;17467.38864139749;17471.12644367802;17475.300987960338;17482.213300401512;6361.777337793247;6560.475161987029;6589.472842172615;6641.1366522352755;6680.63256520896;3584.9641503585153;3659.688926441229;3662.8717994870053;3687.815609219528;3708.42519409492;2912.6067980822318;2998.3553535989868;3008.217863355543;3010.518317069302;3021.7280444760077;510.9448987840098;534.0680512505702;548.9204065410493;549.0254797733975;549.1954286969609;355.94660800880075;452.8686680862579;527.6332948600763;571.9742611582489;577.1631134854393;6968.825779355455;7605.619719014027;7640.69437222516;7670.578118203441;7680.846383072419;541.2611784913564;575.3670961941831;584.6871923520896;595.1727099331932;595.3570144244206;3598.4242521196484;3691.1141326081547;3713.517242758424;3808.6572208501366;3873.9418908716393;87447;88907.55546222356;88950;89728;89874;77305;79052;79121.20878791138;79267.60366198132;80239.59880200679;67741.32258677449;68898.5215960971;71271.6436417825;72030.63984680148;72321;7206.819829504304;7264;7433.10802703679;7455.776326710207;7478.5138965967035;40056.39915401213;40496.392554111124;40844.18311633691;40880;42305.365419519345;50789.74605126951;58982.70508647429;59358.109628354745;59409.40590594039;59771.70114149575;8912.688055918012;9141.680041198526;9204.171624553823;9604.519774011133;9900.900990989998;9957.800843983225;10045.748856278358;10272.178225741773;10305.330153540295;10480.790384192425;9920.454375009444;10091.747706307107;10145.03622155894;10293.022162894513;10494.265401421828;8597.785055373666;8732.126787321471;9108;9447.244220462484;9563.09150630689;9432.52837358143;9527.428354298765;9719.319647624598;9764.267679924176;10028.899711002796;8138.633761480866;8176.305014073871;8315.584220788814;8398.496090234605;8714.997775255926;8155.510669359857;8378.204070613281;8464.830703385775;8522.531260780468;8808.075152108937 02:41:03 INFO - PROCESS | 24453 | __end_tp_report 02:41:03 INFO - PROCESS | 24453 | __start_cc_report 02:41:03 INFO - PROCESS | 24453 | _x_x_mozilla_cycle_collect,1114 02:41:03 INFO - PROCESS | 24453 | __end_cc_report 02:41:03 INFO - PROCESS | 24453 | __startTimestamp1465810863868__endTimestamp 02:41:03 INFO - PROCESS | 24453 | 02:41:03 INFO - PROCESS | 24453 | ------- Summary: start ------- 02:41:03 INFO - PROCESS | 24453 | Number of tests: 6 02:41:03 INFO - PROCESS | 24453 | 02:41:03 INFO - PROCESS | 24453 | [#0] dojo.html Cycles:165 Average:4821.42 Median:4909.83 stddev:518.71 (10.6%) stddev-sans-first:492.07 02:41:03 INFO - PROCESS | 24453 | Values: 2669.9 2678.4 2711.4 2711.6 2722.8 4753.5 4810.4 4859.0 4935.1 5002.2 4843.8 4860.3 4860.9 4895.9 4902.8 4413.2 4441.2 4471.0 4532.9 4581.7 4512.9 4515.1 4543.2 4548.0 4581.8 4267.6 4397.0 4398.5 4444.4 4462.8 4440.0 4453.5 4477.3 4483.1 4522.8 4422.5 4426.2 4426.8 4456.0 4479.8 4678.8 4774.5 4848.2 4852.1 4892.0 5217.5 5241.7 5250.1 5257.5 5270.9 5046.7 5300.4 5307.7 5309.6 5318.3 4810.9 4864.2 4870.2 4874.1 4897.1 5388.4 5388.6 5391.8 5391.8 5393.5 4919.6 5120.1 5120.6 5121.4 5125.8 5258.6 5266.9 5288.7 5289.7 5341.4 5218.8 5232.2 5233.5 5234.7 5292.6 4970.5 5015.5 5091.8 5213.1 5221.8 4331.8 4380.1 4395.1 4422.0 4422.7 3826.2 3837.3 3840.3 3879.5 3880.7 4377.2 4512.5 4514.8 4522.0 4571.3 5231.5 5265.6 5266.2 5277.9 5293.7 4547.9 4553.8 4557.0 4566.3 4601.4 4505.5 4593.1 4670.5 4685.2 4757.3 4856.9 4893.0 4903.4 4910.2 4950.3 5203.9 5218.4 5224.4 5225.0 5274.5 4781.9 4891.9 4899.1 4900.9 4909.8 5158.8 5175.4 5176.7 5184.6 5223.3 5229.6 5242.0 5248.5 5250.6 5297.6 4891.7 5049.4 5090.9 5101.2 5128.4 5084.8 5088.8 5104.9 5104.9 5157.3 5038.2 5045.4 5099.9 5100.3 5105.3 4902.9 5102.0 5146.6 5174.0 5178.9 5002.4 5028.4 5030.2 5039.9 5089.3 02:41:03 INFO - PROCESS | 24453 | 02:41:03 INFO - PROCESS | 24453 | [#1] ext.html Cycles:180 Average:46799.96 Median:3296.65 stddev:85616.67 (2597.1%) stddev-sans-first:85786.47 02:41:03 INFO - PROCESS | 24453 | Values: 567.5 578.3 581.8 584.8 593.4 27227.0 39380.8 40388.4 40485.2 44179.1 39802.0 40207.0 40231.8 40297.8 40321.8 7406.4 7784.8 7935.5 9895.8 10255.6 10072.4 10192.1 10192.4 10203.6 10207.9 10254.8 10283.8 10293.4 10304.1 10312.4 7583.5 9989.3 10267.4 10268.1 10620.9 10070.4 10152.1 10152.7 10160.0 10163.7 38690.8 39073.6 39114.6 39134.8 39235.0 3177.9 3187.6 3227.4 3311.9 3462.6 29779.3 30399.7 30431.0 30643.7 30667.1 3249.7 3291.8 3301.5 3310.4 3358.4 247847.0 249456.0 249649.0 250021.0 251918.0 30925.7 32521.0 32574.3 54384.7 54948.0 33851.5 33972.2 33985.3 34033.5 34253.5 11057.0 13413.7 13543.5 13545.3 13549.2 10915.2 11122.0 11158.1 11367.7 11370.9 7904.9 7917.8 7925.5 7998.4 8005.2 3034.2 3040.7 3050.8 3067.8 3091.2 846.9 881.8 909.0 962.7 999.6 27480.5 28806.0 28926.0 29303.7 29360.7 919.4 957.9 986.3 1011.2 1037.6 14255.3 14874.1 14886.7 14948.2 14958.3 398766.0 400383.0 402179.0 403572.0 403825.0 50757.2 51814.7 51851.0 51936.0 51944.7 1068.9 1197.9 1206.4 1238.1 1248.5 28211.6 29637.3 29762.0 29835.3 30429.0 44352.6 44509.3 45063.5 45130.5 45164.8 303337.0 304933.0 304938.0 305250.0 305368.0 22284.2 24214.0 25759.2 25778.1 26996.0 42620.4 42799.8 42806.1 43348.6 43845.3 37147.3 37388.3 37461.6 37825.6 38103.4 37140.0 37152.0 37196.0 37395.6 37654.2 36398.5 36719.8 37509.1 38538.2 38587.8 32833.3 34010.7 34317.1 34787.4 35391.0 26395.1 26433.9 26490.0 26738.6 26785.5 02:41:03 INFO - PROCESS | 24453 | 02:41:03 INFO - PROCESS | 24453 | [#2] jquery.html Cycles:180 Average:23001.38 Median:27444.54 stddev:60117.70 (219.1%) stddev-sans-first:60262.35 02:41:03 INFO - PROCESS | 24453 | Values: 378.1 379.0 379.2 386.9 391.7 15688.8 16193.8 17072.7 17077.9 17097.2 15479.0 16126.5 17089.0 17090.0 17110.6 4575.9 4804.2 4809.8 4812.2 4812.2 3521.1 3659.1 5274.9 5561.1 5582.2 5572.8 5595.2 5628.7 5639.3 5640.6 5827.5 6163.4 6163.8 6175.5 6176.4 3339.2 5000.3 5346.0 5351.3 5459.2 12475.5 12555.1 12657.6 14044.5 20521.8 1898.6 1974.7 2077.5 2077.5 2512.8 8525.7 8862.8 9154.0 9252.4 9792.9 1775.6 1841.0 1844.6 1847.5 1858.2 62800.0 63104.7 64682.0 66561.3 67072.7 9731.2 9807.8 10192.1 10216.9 10244.1 13899.2 13945.5 13971.9 14012.6 14015.4 6947.1 7248.9 7260.7 7447.1 7456.4 6649.9 6813.7 6825.3 6839.1 7167.9 4353.2 4460.1 4481.1 4538.8 4558.7 1726.7 1782.1 1845.2 1854.3 1869.2 760.3 807.3 819.7 866.4 908.4 16530.0 16632.2 16952.6 17065.1 17117.1 827.5 849.8 877.9 880.4 881.7 9298.5 9707.8 9715.9 9832.6 9896.6 284391.0 289412.0 402970.0 405984.0 407414.0 77404.2 79189.6 80052.6 80635.6 81373.0 895.5 937.1 947.2 950.0 956.8 15364.0 15389.7 15656.3 16071.3 16083.3 26577.3 27070.7 36053.3 36760.8 37289.4 49103.8 49888.8 50327.2 50497.5 51088.0 7599.0 7685.7 7779.1 7854.3 7976.6 8816.9 11364.4 11399.9 11709.2 11922.4 9593.3 9664.2 9666.2 9823.2 9842.8 9704.4 10242.7 10417.8 10630.6 10858.8 9698.9 10197.1 10662.7 10686.6 10918.4 6781.4 6814.4 7169.9 7330.4 7961.7 7714.3 9480.6 9924.8 9979.7 10048.8 02:41:03 INFO - PROCESS | 24453 | 02:41:03 INFO - PROCESS | 24453 | [#3] mootools.html Cycles:180 Average:5252.25 Median:5261.59 stddev:2974.10 (56.5%) stddev-sans-first:2967.08 02:41:03 INFO - PROCESS | 24453 | Values: 1230.0 1288.6 1368.0 1372.7 1668.0 6837.5 7583.5 7772.8 7811.7 8033.3 7808.8 7949.5 7955.1 7984.7 7986.2 2249.6 2278.6 2280.5 2280.9 2283.7 2208.9 2232.4 2263.0 2268.2 2268.3 2213.2 2218.2 2219.5 2222.2 2251.9 2179.8 2189.1 2194.6 2198.7 2199.1 2161.5 2178.6 2201.3 2203.4 2208.3 7735.3 7926.4 7973.0 8038.4 8038.6 4639.7 4791.5 4875.6 4876.6 4879.0 2843.2 2994.5 3052.4 3054.0 3059.6 886.3 889.1 904.8 912.0 917.9 10885.2 11142.2 11158.7 11160.2 11166.4 4320.5 4423.9 4481.4 4489.1 4515.9 9553.3 9566.7 9567.1 9567.9 9568.5 4740.6 4742.6 4743.2 4745.5 4748.4 3809.3 4207.6 4280.6 4345.9 4360.6 3771.5 3783.3 3897.5 3924.8 3951.1 1227.4 1235.5 1246.5 1247.0 1254.1 576.2 744.4 786.3 830.7 837.2 5127.3 5301.2 5348.3 5360.5 5489.5 833.4 843.2 892.5 903.1 907.0 3593.3 3635.5 3673.6 3744.5 3763.3 9699.5 9936.2 9968.7 10017.2 10056.4 9923.4 9941.9 9945.0 9946.8 9952.0 7004.9 7319.3 7327.7 7417.2 7583.8 5196.8 5202.8 5244.9 5308.4 5351.7 9071.1 9139.1 9216.3 9284.4 9297.3 8920.0 9279.5 9489.7 9498.4 9541.5 6567.2 6750.8 6779.6 6791.0 6925.1 7745.7 7799.8 7832.5 7869.3 7872.2 5278.3 6567.5 6792.2 6800.6 6878.6 6889.7 6905.0 7049.8 7085.8 7165.1 6290.4 6348.3 6353.4 6357.3 6358.7 4400.2 4591.8 4609.0 4646.2 4673.1 4467.2 4559.3 4563.5 4565.6 4581.0 02:41:03 INFO - PROCESS | 24453 | 02:41:03 INFO - PROCESS | 24453 | [#4] prototype.html Cycles:180 Average:4211.70 Median:4737.49 stddev:1223.36 (25.8%) stddev-sans-first:1191.21 02:41:03 INFO - PROCESS | 24453 | Values: 308.8 314.4 314.7 319.4 319.6 4924.7 4928.1 4966.4 4997.2 5048.6 4818.1 4888.7 4899.4 4900.6 4913.4 3647.7 3789.8 3800.4 3801.7 3836.1 3741.2 3775.6 3851.2 3931.1 3937.3 3839.4 3859.9 3862.2 3864.9 3870.2 3865.2 3877.1 3898.7 3899.0 3926.0 3246.4 3335.4 3354.9 3362.4 3365.6 4797.9 4798.9 4812.2 4820.9 4829.7 4434.4 4448.5 4476.8 4490.1 4509.3 4946.5 5109.3 5112.7 5126.2 5256.9 3720.7 3746.2 3749.0 3754.2 3760.4 5134.0 5207.3 5353.6 5360.2 5402.3 3758.6 3766.1 3767.5 3776.1 3799.4 3821.6 3826.3 3831.7 3849.5 3851.2 4221.5 4351.3 4365.8 4371.6 4383.3 5119.4 5149.1 5150.5 5156.0 5162.3 1328.3 1342.3 1345.6 1351.8 1355.1 846.0 883.7 893.3 914.8 916.6 4058.5 4067.9 4068.4 4133.7 4135.9 4638.9 4702.4 4706.6 4719.5 4755.5 4085.4 4095.6 4115.6 4131.5 4152.9 2427.0 2477.8 2493.7 2520.7 2583.3 4378.8 4450.2 4457.0 4458.6 4474.5 4783.2 4819.0 4826.0 4835.6 4864.0 4482.2 4655.3 5063.6 5073.5 5084.5 5276.5 5311.4 5345.8 5370.9 5395.6 5184.4 5190.6 5246.8 5249.1 5260.0 4859.2 4991.8 4994.8 4997.2 5001.3 3735.0 3786.3 3799.7 3821.9 3824.2 5307.2 5372.8 5384.2 5392.6 5398.6 5097.5 5122.8 5126.4 5135.9 5136.6 5094.5 5134.3 5138.4 5192.2 5227.7 4886.6 5107.4 5131.2 5142.0 5171.0 5207.0 5210.9 5220.1 5235.5 5237.1 5088.6 5128.0 5128.4 5147.5 5168.3 02:41:03 INFO - PROCESS | 24453 | 02:41:03 INFO - PROCESS | 24453 | [#5] yui.html Cycles:180 Average:16541.03 Median:612.03 stddev:23718.40 (3875.4%) stddev-sans-first:23754.95 02:41:03 INFO - PROCESS | 24453 | Values: 657.7 686.0 691.8 695.6 718.7 11974.9 12969.5 12972.7 12978.9 12993.6 12710.9 12974.1 13003.7 13004.5 13022.6 619.9 752.8 765.8 774.1 774.8 780.3 783.1 785.6 786.1 789.2 826.8 835.4 836.2 837.0 840.9 657.9 815.3 833.3 834.9 835.7 610.7 611.0 611.5 612.6 613.3 12893.5 12896.3 12899.7 12928.4 12930.4 11717.9 12112.8 12162.9 12229.4 12236.2 9798.1 10281.7 10793.1 10845.5 11049.0 4118.5 4185.3 4206.4 4206.9 4343.2 61038.4 62297.4 62344.4 62505.7 62536.0 9915.5 10012.2 10161.5 10165.8 10307.2 17429.0 17467.4 17471.1 17475.3 17482.2 6361.8 6560.5 6589.5 6641.1 6680.6 3585.0 3659.7 3662.9 3687.8 3708.4 2912.6 2998.4 3008.2 3010.5 3021.7 510.9 534.1 548.9 549.0 549.2 355.9 452.9 527.6 572.0 577.2 6968.8 7605.6 7640.7 7670.6 7680.8 541.3 575.4 584.7 595.2 595.4 3598.4 3691.1 3713.5 3808.7 3873.9 87447.0 88907.6 88950.0 89728.0 89874.0 77305.0 79052.0 79121.2 79267.6 80239.6 67741.3 68898.5 71271.6 72030.6 72321.0 7206.8 7264.0 7433.1 7455.8 7478.5 40056.4 40496.4 40844.2 40880.0 42305.4 50789.7 58982.7 59358.1 59409.4 59771.7 8912.7 9141.7 9204.2 9604.5 9900.9 9957.8 10045.7 10272.2 10305.3 10480.8 9920.5 10091.7 10145.0 10293.0 10494.3 8597.8 8732.1 9108.0 9447.2 9563.1 9432.5 9527.4 9719.3 9764.3 10028.9 8138.6 8176.3 8315.6 8398.5 8715.0 8155.5 8378.2 8464.8 8522.5 8808.1 02:41:03 INFO - PROCESS | 24453 | -------- Summary: end -------- 02:41:03 INFO - PROCESS | 24453 | 02:41:04 INFO - TEST-INFO | 24453: exit 0 02:41:04 INFO - TEST-OK | dromaeo_css | took 1122223ms 02:41:04 INFO - TEST-START | kraken 02:41:04 INFO - operating with platform_type : linux_ 02:41:04 INFO - Initialising browser for kraken test... 02:41:04 INFO - TEST-INFO | started process 24631 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpConcDr/profile http://localhost:60317/getInfo.html) 02:41:09 INFO - PROCESS | 24631 | __metrics Screen width/height:1600/1200 02:41:09 INFO - PROCESS | 24631 | colorDepth:24 02:41:09 INFO - PROCESS | 24631 | Browser inner width/height: 1024/697 02:41:09 INFO - PROCESS | 24631 | __metrics 02:41:10 INFO - PROCESS | 24631 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:41:10 INFO - PROCESS | 24631 | console.error: PushService: 02:41:10 INFO - PROCESS | 24631 | stateChangeProcessEnqueue: Error transitioning state 02:41:10 INFO - PROCESS | 24631 | UnknownError 02:41:11 INFO - TEST-INFO | 24631: exit 0 02:41:11 INFO - Browser initialized. 02:41:11 INFO - Running cycle 1/1 for kraken test... 02:41:11 INFO - TEST-INFO | started process 24711 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpConcDr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:41:23 INFO - PROCESS | 24711 | RSS: Main: 175525888 02:41:23 INFO - PROCESS | 24711 | 02:41:26 INFO - PROCESS | 24711 | Cycle 1(1): loaded http://localhost:60317/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60317/tests/kraken/driver.html?testName=audio-beat-detection) 02:41:27 INFO - PROCESS | 24711 | RSS: Main: 234102784 02:41:27 INFO - PROCESS | 24711 | 02:41:30 INFO - PROCESS | 24711 | Cycle 1(1): loaded http://localhost:60317/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60317/tests/kraken/driver.html?testName=audio-dft) 02:41:30 INFO - PROCESS | 24711 | RSS: Main: 221224960 02:41:30 INFO - PROCESS | 24711 | 02:41:34 INFO - PROCESS | 24711 | Cycle 1(1): loaded http://localhost:60317/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60317/tests/kraken/driver.html?testName=audio-fft) 02:41:34 INFO - PROCESS | 24711 | RSS: Main: 289247232 02:41:34 INFO - PROCESS | 24711 | 02:41:36 INFO - PROCESS | 24711 | Cycle 1(1): loaded http://localhost:60317/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60317/tests/kraken/driver.html?testName=audio-oscillator) 02:41:37 INFO - PROCESS | 24711 | RSS: Main: 199360512 02:41:37 INFO - PROCESS | 24711 | 02:41:39 INFO - PROCESS | 24711 | Cycle 1(1): loaded http://localhost:60317/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60317/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:41:40 INFO - PROCESS | 24711 | RSS: Main: 203403264 02:41:40 INFO - PROCESS | 24711 | 02:41:43 INFO - PROCESS | 24711 | Cycle 1(1): loaded http://localhost:60317/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60317/tests/kraken/driver.html?testName=imaging-darkroom) 02:41:44 INFO - PROCESS | 24711 | RSS: Main: 239329280 02:41:44 INFO - PROCESS | 24711 | 02:41:47 INFO - PROCESS | 24711 | Cycle 1(1): loaded http://localhost:60317/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60317/tests/kraken/driver.html?testName=imaging-desaturate) 02:41:48 INFO - PROCESS | 24711 | RSS: Main: 240078848 02:41:48 INFO - PROCESS | 24711 | 02:41:51 INFO - PROCESS | 24711 | Cycle 1(1): loaded http://localhost:60317/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60317/tests/kraken/driver.html?testName=json-parse-financial) 02:41:51 INFO - PROCESS | 24711 | RSS: Main: 239845376 02:41:51 INFO - PROCESS | 24711 | 02:41:53 INFO - PROCESS | 24711 | Cycle 1(1): loaded http://localhost:60317/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60317/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:41:54 INFO - PROCESS | 24711 | RSS: Main: 226852864 02:41:54 INFO - PROCESS | 24711 | 02:41:55 INFO - PROCESS | 24711 | Cycle 1(1): loaded http://localhost:60317/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60317/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:41:56 INFO - PROCESS | 24711 | RSS: Main: 232054784 02:41:56 INFO - PROCESS | 24711 | 02:41:58 INFO - PROCESS | 24711 | Cycle 1(1): loaded http://localhost:60317/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60317/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:41:59 INFO - PROCESS | 24711 | RSS: Main: 239726592 02:41:59 INFO - PROCESS | 24711 | 02:42:02 INFO - PROCESS | 24711 | Cycle 1(1): loaded http://localhost:60317/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60317/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:42:02 INFO - PROCESS | 24711 | RSS: Main: 241991680 02:42:02 INFO - PROCESS | 24711 | 02:42:05 INFO - PROCESS | 24711 | Cycle 1(1): loaded http://localhost:60317/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60317/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:42:06 INFO - PROCESS | 24711 | RSS: Main: 240898048 02:42:06 INFO - PROCESS | 24711 | 02:42:08 INFO - PROCESS | 24711 | Cycle 1(1): loaded http://localhost:60317/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60317/tests/kraken/driver.html?testName=ai-astar) 02:42:08 INFO - PROCESS | 24711 | RSS: Main: 283209728 02:42:08 INFO - PROCESS | 24711 | 02:42:08 INFO - PROCESS | 24711 | __start_tp_report 02:42:08 INFO - PROCESS | 24711 | _x_x_mozilla_page_load 02:42:08 INFO - PROCESS | 24711 | _x_x_mozilla_page_load_details 02:42:08 INFO - PROCESS | 24711 | |i|pagename|runs| 02:42:08 INFO - PROCESS | 24711 | |0;ai-astar;112;108;111;114;113;116;112;111;111;112 02:42:08 INFO - PROCESS | 24711 | |1;audio-beat-detection;154;130;138;184;130;130;157;129;182;142 02:42:08 INFO - PROCESS | 24711 | |2;audio-dft;162;162;160;163;162;160;161;162;163;162 02:42:08 INFO - PROCESS | 24711 | |3;audio-fft;76;76;76;144;75;76;77;76;100;74 02:42:08 INFO - PROCESS | 24711 | |4;audio-oscillator;85;85;117;85;85;86;119;85;85;125 02:42:08 INFO - PROCESS | 24711 | |5;imaging-gaussian-blur;117;117;117;116;116;117;116;116;116;117 02:42:08 INFO - PROCESS | 24711 | |6;imaging-darkroom;146;146;146;146;145;146;146;147;146;146 02:42:08 INFO - PROCESS | 24711 | |7;imaging-desaturate;95;95;95;94;95;94;94;96;94;94 02:42:08 INFO - PROCESS | 24711 | |8;json-parse-financial;62;64;63;64;66;63;62;62;63;63 02:42:08 INFO - PROCESS | 24711 | |9;json-stringify-tinderbox;64;65;66;65;65;65;64;65;66;66 02:42:08 INFO - PROCESS | 24711 | |10;stanford-crypto-aes;83;83;82;83;84;86;86;85;116;84 02:42:08 INFO - PROCESS | 24711 | |11;stanford-crypto-ccm;130;140;129;132;134;129;130;132;149;136 02:42:08 INFO - PROCESS | 24711 | |12;stanford-crypto-pbkdf2;175;178;170;170;174;168;169;170;174;171 02:42:08 INFO - PROCESS | 24711 | |13;stanford-crypto-sha256-iterative;67;70;68;68;66;67;67;68;71;66 02:42:08 INFO - PROCESS | 24711 | __end_tp_report 02:42:08 INFO - PROCESS | 24711 | __start_cc_report 02:42:08 INFO - PROCESS | 24711 | _x_x_mozilla_cycle_collect,1901 02:42:08 INFO - PROCESS | 24711 | __end_cc_report 02:42:08 INFO - PROCESS | 24711 | __startTimestamp1465810928392__endTimestamp 02:42:08 INFO - PROCESS | 24711 | 02:42:08 INFO - PROCESS | 24711 | ------- Summary: start ------- 02:42:08 INFO - PROCESS | 24711 | Number of tests: 14 02:42:08 INFO - PROCESS | 24711 | 02:42:08 INFO - PROCESS | 24711 | [#0] ai-astar Cycles:10 Average:112.00 Median:112.00 stddev:2.11 (1.9%) stddev-sans-first:2.24 02:42:08 INFO - PROCESS | 24711 | Values: 112.0 108.0 111.0 114.0 113.0 116.0 112.0 111.0 111.0 112.0 02:42:08 INFO - PROCESS | 24711 | 02:42:08 INFO - PROCESS | 24711 | [#1] audio-beat-detection Cycles:10 Average:147.60 Median:148.00 stddev:21.18 (14.3%) stddev-sans-first:22.34 02:42:08 INFO - PROCESS | 24711 | Values: 154.0 130.0 138.0 184.0 130.0 130.0 157.0 129.0 182.0 142.0 02:42:08 INFO - PROCESS | 24711 | 02:42:08 INFO - PROCESS | 24711 | [#2] audio-dft Cycles:10 Average:161.70 Median:162.00 stddev:1.06 (0.7%) stddev-sans-first:1.12 02:42:08 INFO - PROCESS | 24711 | Values: 162.0 162.0 160.0 163.0 162.0 160.0 161.0 162.0 163.0 162.0 02:42:08 INFO - PROCESS | 24711 | 02:42:08 INFO - PROCESS | 24711 | [#3] audio-fft Cycles:10 Average:85.00 Median:76.00 stddev:22.10 (29.1%) stddev-sans-first:23.20 02:42:08 INFO - PROCESS | 24711 | Values: 76.0 76.0 76.0 144.0 75.0 76.0 77.0 76.0 100.0 74.0 02:42:08 INFO - PROCESS | 24711 | 02:42:08 INFO - PROCESS | 24711 | [#4] audio-oscillator Cycles:10 Average:95.70 Median:85.00 stddev:17.11 (20.1%) stddev-sans-first:17.71 02:42:08 INFO - PROCESS | 24711 | Values: 85.0 85.0 117.0 85.0 85.0 86.0 119.0 85.0 85.0 125.0 02:42:08 INFO - PROCESS | 24711 | 02:42:08 INFO - PROCESS | 24711 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 02:42:08 INFO - PROCESS | 24711 | Values: 117.0 117.0 117.0 116.0 116.0 117.0 116.0 116.0 116.0 117.0 02:42:08 INFO - PROCESS | 24711 | 02:42:08 INFO - PROCESS | 24711 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 02:42:08 INFO - PROCESS | 24711 | Values: 146.0 146.0 146.0 146.0 145.0 146.0 146.0 147.0 146.0 146.0 02:42:08 INFO - PROCESS | 24711 | 02:42:08 INFO - PROCESS | 24711 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 02:42:08 INFO - PROCESS | 24711 | Values: 95.0 95.0 95.0 94.0 95.0 94.0 94.0 96.0 94.0 94.0 02:42:08 INFO - PROCESS | 24711 | 02:42:08 INFO - PROCESS | 24711 | [#8] json-parse-financial Cycles:10 Average:63.20 Median:63.00 stddev:1.23 (2.0%) stddev-sans-first:1.22 02:42:08 INFO - PROCESS | 24711 | Values: 62.0 64.0 63.0 64.0 66.0 63.0 62.0 62.0 63.0 63.0 02:42:08 INFO - PROCESS | 24711 | 02:42:08 INFO - PROCESS | 24711 | [#9] json-stringify-tinderbox Cycles:10 Average:65.10 Median:65.00 stddev:0.74 (1.1%) stddev-sans-first:0.67 02:42:08 INFO - PROCESS | 24711 | Values: 64.0 65.0 66.0 65.0 65.0 65.0 64.0 65.0 66.0 66.0 02:42:08 INFO - PROCESS | 24711 | 02:42:08 INFO - PROCESS | 24711 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:84.00 stddev:10.21 (12.2%) stddev-sans-first:10.71 02:42:08 INFO - PROCESS | 24711 | Values: 83.0 83.0 82.0 83.0 84.0 86.0 86.0 85.0 116.0 84.0 02:42:08 INFO - PROCESS | 24711 | 02:42:08 INFO - PROCESS | 24711 | [#11] stanford-crypto-ccm Cycles:10 Average:134.10 Median:133.00 stddev:6.28 (4.7%) stddev-sans-first:6.48 02:42:08 INFO - PROCESS | 24711 | Values: 130.0 140.0 129.0 132.0 134.0 129.0 130.0 132.0 149.0 136.0 02:42:08 INFO - PROCESS | 24711 | 02:42:08 INFO - PROCESS | 24711 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:172.50 stddev:3.18 (1.8%) stddev-sans-first:3.17 02:42:08 INFO - PROCESS | 24711 | Values: 175.0 178.0 170.0 170.0 174.0 168.0 169.0 170.0 174.0 171.0 02:42:08 INFO - PROCESS | 24711 | 02:42:08 INFO - PROCESS | 24711 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:1.62 (2.4%) stddev-sans-first:1.69 02:42:08 INFO - PROCESS | 24711 | Values: 67.0 70.0 68.0 68.0 66.0 67.0 67.0 68.0 71.0 66.0 02:42:08 INFO - PROCESS | 24711 | -------- Summary: end -------- 02:42:08 INFO - PROCESS | 24711 | 02:42:09 INFO - TEST-INFO | 24711: exit 0 02:42:09 INFO - TEST-OK | kraken | took 65036ms 02:42:09 INFO - SUITE-END | took 1187s 02:42:10 INFO - Completed test suite (00:19:47) 02:42:10 INFO - javascript benchmark 02:42:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2669.919902402928, 2678.3973605938654, 2711.3763834318106, 2711.593261010848, 2722.823016503927, 4753.47711751707, 4810.44679861816, 4858.975705121474, 4935.111679897622, 5002.199648056312, 4843.806247750086, 4860.2709593560985, 4860.927086093711, 4895.853124406273, 4902.779374928128, 4413.205622987869, 4441.156180325728, 4470.955290447103, 4532.864014079566, 4581.67928245023, 4512.932306015412, 4515.05183911379, 4543.204939135655, 4548.022175232324, 4581.7709114544305, 4267.637250833683, 4396.956030439686, 4398.538153493869, 4444.422225110748, 4462.821487140511, 4440.0, 4453.487848897385, 4477.283634618478, 4483.08096840147, 4522.841700540465, 4422.469303683546, 4426.203283408985, 4426.756528390937, 4455.955440445587, 4479.776011199427, 4678.7660616969015, 4774.498677638832, 4848.200046992242, 4852.053849499349, 4891.9755401222765, 5217.50433708797, 5241.73791310433, 5250.081235783722, 5257.5005374489365, 5270.947290527084, 5046.697198168122, 5300.443453437372, 5307.681539107627, 5309.601779866487, 5318.335208098988, 4810.879728006757, 4864.221724524059, 4870.220764677634, 4874.098291816025, 4897.094037603055, 5388.353397592314, 5388.622796404214, 5391.784328626811, 5391.81128660495, 5393.487618676219, 4919.631027672939, 5120.052790233819, 5120.564751996122, 5121.385433747974, 5125.794968201305, 5258.605604579672, 5266.947330526722, 5288.68267903927, 5289.735513224324, 5341.385740639798, 5218.765155568009, 5232.162853943427, 5233.487925795564, 5234.685918844882, 5292.576593872481, 4970.502949705001, 5015.498450155028, 5091.770870310845, 5213.061648903234, 5221.765020574083, 4331.805068771912, 4380.102079073846, 4395.120975804852, 4421.977890110593, 4422.71252368595, 3826.196498735241, 3837.2901013312626, 3840.251151025579, 3879.5344558652578, 3880.708946828999, 4377.16833801583, 4512.503624601291, 4514.796834142471, 4522.027764030683, 4571.314302854532, 5231.476852314814, 5265.552428043659, 5266.1574148136115, 5277.947220527745, 5293.7353132344, 4547.909041819145, 4553.840615578439, 4556.977215113903, 4566.315052742115, 4601.378813860153, 4505.504394516601, 4593.127305811885, 4670.462896766915, 4685.1800934836265, 4757.286407038902, 4856.854294371175, 4892.975535122301, 4903.362562866947, 4910.165271903857, 4950.257461380822, 5203.92194117081, 5218.373795144607, 5224.425313215467, 5225.007248622902, 5274.525292723673, 4781.856544303677, 4891.877703057452, 4899.118158731462, 4900.901981960412, 4909.828156014522, 5158.819441319536, 5175.404828444777, 5176.689398636094, 5184.637075404686, 5223.347081614798, 5229.6339256251695, 5241.9737901310245, 5248.501392367719, 5250.606204534599, 5297.629165958346, 4891.657583969088, 5049.444561098203, 5090.923636145387, 5101.183810590288, 5128.410232823273, 5084.771185296597, 5088.771005304696, 5104.872378190426, 5104.897902042013, 5157.277981082727, 5038.2190760432195, 5045.419776725724, 5099.949000509948, 5100.285959965682, 5105.33630628016, 4902.92645610309, 5101.974490127525, 5146.562542183956, 5173.974130129476, 5178.948210517847, 5002.449730529712, 5028.446870844131, 5030.195168772979, 5039.899202016012, 5089.338386009795], "value": 4787.554245998812, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [567.4759742002167, 578.2944807335052, 581.819635912867, 584.8333225030867, 593.4451288045677, 27227.0, 39380.80309598451, 40388.39417408738, 40485.19029619406, 44179.11641767163, 39802.0, 40206.99999999993, 40231.798841005824, 40297.7985110074, 40321.79839100801, 7406.444516661245, 7784.766457006284, 7935.523868567876, 9895.752606184868, 10255.589776408935, 10072.395656260647, 10192.082712555868, 10192.439415832163, 10203.591856325775, 10207.948960255188, 10254.794904101913, 10283.845742313872, 10293.433861137635, 10304.124149447269, 10312.381257124596, 7583.469157159001, 9989.300748947613, 10267.43529105899, 10268.127209187229, 10620.946895265473, 10070.446125463097, 10152.08631223186, 10152.746181345525, 10160.034796694303, 10163.745906352326, 38690.806545967374, 39073.60926390728, 39114.608853911384, 39134.804325978184, 39235.0, 3177.936441271185, 3187.6334221564584, 3227.4190645683766, 3311.850966706504, 3462.601800792916, 29779.25551861199, 30399.696003039906, 30430.956904309613, 30643.693563064306, 30667.11710816563, 3249.69127932846, 3291.8024918504966, 3301.5047742838765, 3310.420676381648, 3358.395488812013, 247847.0, 249456.0, 249649.0, 250021.0, 251918.0, 30925.69074309228, 32521.0, 32574.348513029607, 54384.728076359366, 54948.0, 33851.4922276166, 33972.15069623279, 33985.32029359399, 34033.48949765755, 34253.486197707054, 11057.0, 13413.731725365438, 13543.458261669422, 13545.255010974492, 13549.187048777105, 10915.181361397927, 11122.0, 11158.107351411867, 11367.715807104723, 11370.94314528422, 7904.881426778603, 7917.802054948557, 7925.484843485155, 7998.440109192301, 8005.239502247277, 3034.226272300549, 3040.665526792051, 3050.816950982948, 3067.8005929614505, 3091.211741006074, 846.9026062002825, 881.8104107616894, 908.9590968406437, 962.6967505235829, 999.6201443451442, 27480.450390992068, 28806.0, 28926.0, 29303.706962930097, 29360.706392936223, 919.434547753127, 957.9089986451276, 986.2947992185622, 1011.2163073617858, 1037.600523798328, 14255.287235638176, 14874.107553546823, 14886.734627556563, 14948.177850218124, 14958.252087395587, 398766.0, 400383.0, 402179.0, 403572.0, 403825.0, 50757.23864142041, 51814.740926295126, 51850.96298074017, 51936.0, 51944.74027629838, 1068.8770791358938, 1197.8921897029309, 1206.4209179593668, 1238.0652607281445, 1248.4694005047818, 28211.57682634721, 29637.25906852346, 29762.0, 29835.25411864634, 30429.0, 44352.556474434845, 44509.33236001527, 45063.54936450725, 45130.54869451263, 45164.77417612891, 303337.0, 304933.0, 304938.0, 305250.0, 305368.0, 22284.220052298093, 24214.0, 25759.227223183334, 25778.09776657808, 26996.0, 42620.36069459023, 42799.7860010698, 42806.14387712291, 43348.56651433445, 43845.34231986459, 37147.25705485929, 37388.25223495569, 37461.62538374581, 37825.62174378331, 38103.38017888296, 37140.0, 37152.0, 37196.0, 37395.6260437403, 37654.246915061, 36398.45402319021, 36719.81640091889, 37509.062273443385, 38538.229235414576, 38587.807060965635, 32833.34333313368, 34010.65989340075, 34317.14207144841, 34787.434565445124, 35391.0, 26395.076172334644, 26433.867830660725, 26490.0, 26738.59892101581, 26785.464290714466], "value": 17117.50319016774, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.10012171032946, 378.95452545694513, 379.1715102501034, 386.8510623409988, 391.74340806771573, 15688.764668529968, 16193.838061619395, 17072.658546829087, 17077.914610426946, 17097.23062462189, 15479.0, 16126.516204513853, 17089.0, 17090.0, 17110.572235694115, 4575.931361029588, 4804.231322988323, 4809.7835597398025, 4812.181929072049, 4812.181929072066, 3521.1021189596618, 3659.1035196376793, 5274.868128296785, 5561.082421400484, 5582.246396736429, 5572.832815015536, 5595.244641973342, 5628.662280263176, 5639.323281206241, 5640.605157638967, 5827.533797296227, 6163.3528479509905, 6163.815085547396, 6175.505959523227, 6176.4441200292185, 3339.231976645361, 5000.324956130912, 5346.03771321162, 5351.25082488452, 5459.208414779874, 12475.50097996079, 12555.05837062224, 12657.556985505555, 14044.508442204473, 20521.794782052137, 1898.563330433994, 1974.664307067802, 2077.491014701393, 2077.501399664085, 2512.798976081909, 8525.74422767318, 8862.822743545157, 9153.954230228806, 9252.352335336462, 9792.902070979342, 1775.5628832754, 1840.9815901840943, 1844.6034102667857, 1847.5196448923377, 1858.1545396844404, 62800.0, 63104.684476577786, 64682.0, 66561.334386656, 67072.66463667699, 9731.172850307803, 9807.75480612976, 10192.1336686381, 10216.948915255376, 10244.129249013768, 13899.235542045284, 13945.511907083204, 13971.930140349437, 14012.579622611338, 14015.439382424793, 6947.131608548932, 7248.9275107249305, 7260.745873894494, 7447.143578488435, 7456.440766942501, 6649.867002659919, 6813.693383797741, 6825.283345248777, 6839.076724642209, 7167.92832071683, 4353.172897149531, 4460.085682435093, 4481.058977614673, 4538.750368729751, 4558.703684260512, 1726.680564095621, 1782.117851663409, 1845.178895391538, 1854.2582966813113, 1869.1869036968963, 760.2739383888484, 807.3460496997451, 819.7049062337554, 866.3675516872593, 908.3732224764891, 16530.02217997468, 16632.168391580373, 16952.57618559521, 17065.14674266282, 17117.14414279281, 827.4538804389194, 849.8172892828071, 877.863931090682, 880.372089313399, 881.7310720230263, 9298.488583127857, 9707.805843883083, 9715.902840971641, 9832.557534910946, 9896.604135834486, 284391.0, 289412.0, 402970.0, 405984.0, 407414.0, 77404.22595774083, 79189.60405197938, 80052.59973700094, 80635.59682201552, 81373.0, 895.5029958373166, 937.1378331934636, 947.1570302430843, 950.0404591362774, 956.7608097975506, 15364.00133991287, 15389.69220615559, 15656.295466703798, 16071.27679254413, 16083.276252568896, 26577.33556661099, 27070.72929270682, 36053.27893442169, 36760.81619591885, 37289.44065839069, 49103.75448122736, 49888.75055624699, 50327.245091322926, 50497.45522902956, 51088.0, 7599.013089446262, 7685.654145563351, 7779.144294127755, 7854.332381747616, 7976.6410511525955, 8816.86774698393, 11364.374959377314, 11399.886001139883, 11709.180357375242, 11922.403879806148, 9593.28050396209, 9664.178544823768, 9666.226701863974, 9823.16503097216, 9842.803143937224, 9704.417734935927, 10242.692719218436, 10417.791644167226, 10630.574777008833, 10858.837117443403, 9698.85451718239, 10197.13324367437, 10662.73343166396, 10686.625968091079, 10918.399488028235, 6781.38967492928, 6814.386705196556, 7169.928300717134, 7330.413566914527, 7961.72133975308, 7714.3442807362, 9480.573374198315, 9924.801503970024, 9979.650712225037, 10048.82973565962], "value": 8032.208576183537, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1229.9508019679213, 1288.5876519513756, 1367.994524024842, 1372.7254549090178, 1668.0408764960143, 6837.453003759693, 7583.469157158994, 7772.844543109134, 7811.6875324987, 8033.277005069543, 7808.8047798805055, 7949.523028618272, 7955.124936257022, 7984.720534781284, 7986.161453047419, 2249.5838269920037, 2278.5556816420803, 2280.5438912217624, 2280.931572052841, 2283.668868014137, 2208.8785116818567, 2232.363776323748, 2262.954740905181, 2268.1721171772265, 2268.330842401487, 2213.1700612270397, 2218.190360518407, 2219.511707424364, 2222.1777942161425, 2251.864888106719, 2179.7820217978233, 2189.091527016294, 2194.594000109985, 2198.703175071369, 2199.087378737822, 2161.502854343494, 2178.6405243134864, 2201.2955854126685, 2203.4160947348964, 2208.2712704807377, 7735.342495887856, 7926.445148839583, 7973.0, 8038.437309388345, 8038.598070096472, 4639.6752227344095, 4791.47293797682, 4875.58557522611, 4876.609871210295, 4879.048585525824, 2843.1896909380725, 2994.4609970205356, 3052.4047810676925, 3053.954190687119, 3059.632844058705, 886.2776836877988, 889.0798024045147, 904.841652710773, 911.9589618467119, 917.9173874351274, 10885.18361122919, 11142.164337674707, 11158.665240042812, 11160.218784685076, 11166.441677916071, 4320.503142138631, 4423.889402764892, 4481.3726078349055, 4489.069700029019, 4515.864524064284, 9553.283503737248, 9566.712998610054, 9567.138957493858, 9567.856482152773, 9568.4737339447, 4740.5733483986605, 4742.644301677319, 4743.217369134054, 4745.501722319176, 4748.430188377348, 3809.2952803730846, 4207.6423504002505, 4280.59334363235, 4345.891352716144, 4360.629346505519, 3771.453139294787, 3783.281176576442, 3897.4543563901075, 3924.7841368724994, 3951.05174758056, 1227.374039239994, 1235.518147922311, 1246.4515613130193, 1246.9812952805714, 1254.0937774582583, 576.2426448758687, 744.3896005275622, 786.3080489169495, 830.7217082277384, 837.2213841127809, 5127.307813445212, 5301.231321458367, 5348.304720386325, 5360.517553420133, 5489.533389661923, 833.3874602167402, 843.2073850580434, 892.4600616626848, 903.1284810158229, 906.9637214511346, 3593.317269718745, 3635.5092062571216, 3673.6142705016123, 3744.457053727249, 3763.303788799081, 9699.466529340812, 9936.205103591696, 9968.700938971842, 10017.198624110199, 10056.396616203052, 9923.354981926152, 9941.900580994243, 9944.950275248577, 9946.801063978824, 9951.950240248752, 7004.859902802017, 7319.268073192745, 7327.706891724271, 7417.221191774894, 7583.824507201392, 5196.792128314976, 5202.765875535534, 5244.921326180187, 5308.442613525524, 5351.732413379237, 9071.138241867013, 9139.086091390807, 9216.2626989842, 9284.396514226553, 9297.349185557217, 8920.018797932353, 9279.536023198678, 9489.66786162481, 9498.382605130644, 9541.475218862752, 6567.2447668518735, 6750.797476075725, 6779.6271205084195, 6791.049253104668, 6925.065116209247, 7745.728899488491, 7799.805004874924, 7832.530048197126, 7869.3311068559815, 7872.212778722082, 5278.261043453843, 6567.507436942153, 6792.218894827158, 6800.591964482147, 6878.58728476293, 6889.655517224019, 6904.965475172592, 7049.7885063448175, 7085.787426377217, 7165.140183178052, 6290.402411770874, 6348.269948955929, 6353.428191462605, 6357.332480089709, 6358.682065896779, 4400.207962566768, 4591.8392856249875, 4608.976955115203, 4646.233371493666, 4673.088747694167, 4467.195904737178, 4559.316102584638, 4563.520830312902, 4565.6119229865835, 4581.015081757305], "value": 4206.388878539647, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.82088388734536, 314.4355881193259, 314.7434840604907, 319.3819958380535, 319.5733695516485, 4924.679895806775, 4928.137575924216, 4966.379202599675, 4997.175466048102, 5048.617426324362, 4818.08456393285, 4888.68223565468, 4899.432181701857, 4900.632452566063, 4913.434954980178, 3647.708183345326, 3789.848406063768, 3800.37293846515, 3801.688417495973, 3836.136869204435, 3741.2143449875557, 3775.5658099318516, 3851.1527463957887, 3931.115499012728, 3937.2912875682364, 3839.4240863870364, 3859.9421008684894, 3862.2082473093087, 3864.8840534784003, 3870.2453021660785, 3865.1689886674358, 3877.108265099043, 3898.7075969302273, 3898.961010389888, 3925.9596207005166, 3246.383187194449, 3335.399628066947, 3354.8993530194134, 3362.411577973845, 3365.562476878015, 4797.856064318076, 4798.880027999293, 4812.171374877559, 4820.879478013043, 4829.661923665345, 4434.401355816955, 4448.466184057901, 4476.761619190404, 4490.146872094291, 4509.301058335963, 4946.455889852114, 5109.310243117169, 5112.744362781846, 5126.205438157054, 5256.947430525723, 3720.6651401374006, 3746.1758413149064, 3748.962510374916, 3754.155315054091, 3760.417135344026, 5133.997740881059, 5207.255569384223, 5353.598480113968, 5360.16917377807, 5402.297701298806, 3758.6241375862196, 3766.133789228438, 3767.453719210699, 3776.055986003499, 3799.4490798834013, 3821.6178382162116, 3826.330392181357, 3831.6934645228316, 3849.480320156799, 3851.1527463957887, 4221.514525829508, 4351.3472979053395, 4365.847195348148, 4371.628411584988, 4383.298672212431, 5119.360079990001, 5149.124648809711, 5150.48495150482, 5156.0, 5162.328897243335, 1328.2827273272517, 1342.3489607540334, 1345.5828693105168, 1351.7698894006407, 1355.1124013770996, 845.9661613535512, 883.7083762358535, 893.2585953658448, 914.849049906758, 916.5692124701379, 4058.5332686740812, 4067.91864162721, 4068.3694027425795, 4133.689973252018, 4135.91728165435, 4638.8608341749805, 4702.388689470347, 4706.552877476634, 4719.457262414866, 4755.524447555217, 4085.4484644572603, 4095.5904409558807, 4115.609017143366, 4131.492005418001, 4152.937705934413, 2427.0, 2477.764612361823, 2493.6882889638796, 2520.676644761478, 2583.263769825628, 4378.78106094694, 4450.153186427597, 4457.0, 4458.621017213508, 4474.485434175046, 4783.206297638386, 4819.012102518976, 4826.0, 4835.564799168092, 4863.975680121577, 4482.21561226784, 4655.255159174516, 5063.594912406998, 5073.518015788494, 5084.491550844886, 5276.498732620395, 5311.362636483646, 5345.812896548602, 5370.865728356823, 5395.622306438669, 5184.403793563638, 5190.584753219809, 5246.816361427332, 5249.133892907627, 5259.973700131628, 4859.246816743411, 4991.775370108281, 4994.825181118644, 4997.175466048061, 5001.299558150246, 3734.981325093466, 3786.2995345861104, 3799.6770274526975, 3821.885343439701, 3824.2351529693615, 5307.174312288953, 5372.817980044384, 5384.165454354581, 5392.568594512508, 5398.62209645321, 5097.547199048254, 5122.771594356467, 5126.4360920297995, 5135.92296115566, 5136.614753893398, 5094.5160209780015, 5134.332536770196, 5138.434772175133, 5192.221166825007, 5227.686338819683, 4886.609071274218, 5107.361579802525, 5131.230315452712, 5141.974290128675, 5171.0, 5207.0, 5210.869728256824, 5220.138677118233, 5235.450277720937, 5237.135872581134, 5088.5674717647935, 5127.974360128175, 5128.410232823273, 5147.485251474823, 5168.276441298146], "value": 3830.5949537236984, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [657.6956843060713, 686.0, 691.8443350246192, 695.572223082804, 718.6649683043963, 11974.940125299372, 12969.481220751182, 12972.675683107927, 12978.93510532446, 12993.61019169424, 12710.872891271085, 12974.091813573054, 13003.739925201491, 13004.47982080718, 13022.609321720363, 619.8791235709016, 752.789219018673, 765.7894079128242, 774.0633833062002, 774.7791879314397, 780.2899361580962, 783.0994356490025, 785.5718633344841, 786.0920636664662, 789.2462698123278, 826.7933016745814, 835.4068611285963, 836.1972506393869, 837.0499483086679, 840.8906842110548, 657.8585604095094, 815.2744057788573, 833.3166803221361, 834.929031032363, 835.7409203147043, 610.7160170520684, 610.9877802443971, 611.4802417944755, 612.5742608886825, 613.2977740487162, 12893.484260629657, 12896.290704011275, 12899.67750806228, 12928.418221180069, 12930.418131184026, 11717.882821171765, 12112.81830772539, 12162.939185304105, 12229.449674764657, 12236.204646697935, 9798.118169364792, 10281.742956426075, 10793.136549076055, 10845.51195196218, 11049.005589496985, 4118.464599602063, 4185.309423945046, 4206.3690446432975, 4206.894827629303, 4343.2399330117105, 61038.389616104156, 62297.37702623006, 62344.37655623431, 62505.68747156281, 62536.0, 9915.504224788732, 10012.199024078056, 10161.4919254037, 10165.847512287322, 10307.175425965905, 17429.04140272272, 17467.38864139749, 17471.12644367802, 17475.300987960338, 17482.213300401512, 6361.777337793247, 6560.475161987029, 6589.472842172615, 6641.1366522352755, 6680.63256520896, 3584.9641503585153, 3659.688926441229, 3662.8717994870053, 3687.815609219528, 3708.42519409492, 2912.6067980822318, 2998.3553535989868, 3008.217863355543, 3010.518317069302, 3021.7280444760077, 510.9448987840098, 534.0680512505702, 548.9204065410493, 549.0254797733975, 549.1954286969609, 355.94660800880075, 452.8686680862579, 527.6332948600763, 571.9742611582489, 577.1631134854393, 6968.825779355455, 7605.619719014027, 7640.69437222516, 7670.578118203441, 7680.846383072419, 541.2611784913564, 575.3670961941831, 584.6871923520896, 595.1727099331932, 595.3570144244206, 3598.4242521196484, 3691.1141326081547, 3713.517242758424, 3808.6572208501366, 3873.9418908716393, 87447.0, 88907.55546222356, 88950.0, 89728.0, 89874.0, 77305.0, 79052.0, 79121.20878791138, 79267.60366198132, 80239.59880200679, 67741.32258677449, 68898.5215960971, 71271.6436417825, 72030.63984680148, 72321.0, 7206.819829504304, 7264.0, 7433.10802703679, 7455.776326710207, 7478.5138965967035, 40056.39915401213, 40496.392554111124, 40844.18311633691, 40880.0, 42305.365419519345, 50789.74605126951, 58982.70508647429, 59358.109628354745, 59409.40590594039, 59771.70114149575, 8912.688055918012, 9141.680041198526, 9204.171624553823, 9604.519774011133, 9900.900990989998, 9957.800843983225, 10045.748856278358, 10272.178225741773, 10305.330153540295, 10480.790384192425, 9920.454375009444, 10091.747706307107, 10145.03622155894, 10293.022162894513, 10494.265401421828, 8597.785055373666, 8732.126787321471, 9108.0, 9447.244220462484, 9563.09150630689, 9432.52837358143, 9527.428354298765, 9719.319647624598, 9764.267679924176, 10028.899711002796, 8138.633761480866, 8176.305014073871, 8315.584220788814, 8398.496090234605, 8714.997775255926, 8155.510669359857, 8378.204070613281, 8464.830703385775, 8522.531260780468, 8808.075152108937], "value": 6200.6080996586525, "unit": "score"}], "name": "dromaeo_css", "value": 6353.420755321755, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 108.0, 111.0, 114.0, 113.0, 116.0, 112.0, 111.0, 111.0, 112.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 130.0, 138.0, 184.0, 130.0, 130.0, 157.0, 129.0, 182.0, 142.0], "value": 147.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 160.0, 163.0, 162.0, 160.0, 161.0, 162.0, 163.0, 162.0], "value": 161.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 144.0, 75.0, 76.0, 77.0, 76.0, 100.0, 74.0], "value": 85.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 117.0, 85.0, 85.0, 86.0, 119.0, 85.0, 85.0, 125.0], "value": 95.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 116.0, 116.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 147.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 96.0, 94.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 63.0, 64.0, 66.0, 63.0, 62.0, 62.0, 63.0, 63.0], "value": 63.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 66.0, 65.0, 65.0, 65.0, 64.0, 65.0, 66.0, 66.0], "value": 65.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 82.0, 83.0, 84.0, 86.0, 86.0, 85.0, 116.0, 84.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 140.0, 129.0, 132.0, 134.0, 129.0, 130.0, 132.0, 149.0, 136.0], "value": 134.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 178.0, 170.0, 170.0, 174.0, 168.0, 169.0, 170.0, 174.0, 171.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 68.0, 68.0, 66.0, 67.0, 67.0, 68.0, 71.0, 66.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1548.4, "alertThreshold": 2.0}]} 02:42:10 INFO - Return code: 0 02:42:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:42:10 INFO - # TBPL SUCCESS # 02:42:10 INFO - Running post-action listener: _resource_record_post_action 02:42:10 INFO - ##### 02:42:10 INFO - ##### Finished run-tests step (success) 02:42:10 INFO - ##### 02:42:10 INFO - Running post-run listener: _resource_record_post_run 02:42:10 INFO - Total resource usage - Wall time: 1205s; CPU: 13.0%; Read bytes: 4096; Write bytes: 549126144; Read time: 16; Write time: 1858092 02:42:10 INFO - TinderboxPrint: CPU usage
13.3% 02:42:10 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 02:42:10 INFO - TinderboxPrint: I/O write bytes / time
549,126,144 / 1,858,092 02:42:10 INFO - TinderboxPrint: CPU idle
8,332.1 (86.6%) 02:42:10 INFO - TinderboxPrint: CPU user
1,219.4 (12.7%) 02:42:10 INFO - TinderboxPrint: Swap in / out
0 / 0 02:42:10 INFO - install - Wall time: 17s; CPU: 20.0%; Read bytes: 0; Write bytes: 232759296; Read time: 0; Write time: 1145644 02:42:10 INFO - run-tests - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 294952960; Read time: 16; Write time: 708496 02:42:10 INFO - Running post-run listener: _upload_blobber_files 02:42:10 INFO - Blob upload gear active. 02:42:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:42:10 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 02:42:10 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:42:10 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:42:11 INFO - (blobuploader) - INFO - Open directory for files ... 02:42:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:42:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:42:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:42:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:42:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:42:11 INFO - (blobuploader) - INFO - Done attempting. 02:42:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:42:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:42:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:42:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:42:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:42:15 INFO - (blobuploader) - INFO - Done attempting. 02:42:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:42:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:42:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:42:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:42:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:42:16 INFO - (blobuploader) - INFO - Done attempting. 02:42:16 INFO - (blobuploader) - INFO - Iteration through files over. 02:42:16 INFO - Return code: 0 02:42:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:42:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:42:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3767726ef1ff5fa0036c933db472f276f7420c59c20abc2474ed38d5da8f9b9a57c6f46272482ce324d780a45dde6e85d849bbfa9af33ffe2d1a1c5c67cdb982", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0bc6a7b4f570bee0c7bdcfa37ccc63eb874d1458d26fc97ba3f9bd8c3a5f21270875d0f42860dba0ec5a245c59c2245e6a01a4db6b3cc41b6479a2d94293d967", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/236727b923e165f7ef383d90dbf5f330e5c536e7ab76915cd06dfa4fc719c622fc9055e9a9513290355612cbad6b14a888260f1aeb760b86bba21e0a422be87f"} 02:42:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:42:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:42:16 INFO - Contents: 02:42:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3767726ef1ff5fa0036c933db472f276f7420c59c20abc2474ed38d5da8f9b9a57c6f46272482ce324d780a45dde6e85d849bbfa9af33ffe2d1a1c5c67cdb982", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0bc6a7b4f570bee0c7bdcfa37ccc63eb874d1458d26fc97ba3f9bd8c3a5f21270875d0f42860dba0ec5a245c59c2245e6a01a4db6b3cc41b6479a2d94293d967", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/236727b923e165f7ef383d90dbf5f330e5c536e7ab76915cd06dfa4fc719c622fc9055e9a9513290355612cbad6b14a888260f1aeb760b86bba21e0a422be87f"} 02:42:16 INFO - Running post-run listener: copy_logs_to_upload_dir 02:42:16 INFO - Copying logs to upload dir... 02:42:16 INFO - mkdir: /builds/slave/test/build/upload/logs 02:42:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.880085 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-06-13 02:42:16.757020) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-13 02:42:16.760931) ========= 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=8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3767726ef1ff5fa0036c933db472f276f7420c59c20abc2474ed38d5da8f9b9a57c6f46272482ce324d780a45dde6e85d849bbfa9af33ffe2d1a1c5c67cdb982", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0bc6a7b4f570bee0c7bdcfa37ccc63eb874d1458d26fc97ba3f9bd8c3a5f21270875d0f42860dba0ec5a245c59c2245e6a01a4db6b3cc41b6479a2d94293d967", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/236727b923e165f7ef383d90dbf5f330e5c536e7ab76915cd06dfa4fc719c622fc9055e9a9513290355612cbad6b14a888260f1aeb760b86bba21e0a422be87f"} build_url:https://queue.taskcluster.net/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011249 build_url: 'https://queue.taskcluster.net/v1/task/BJlVejXKQ32lMS-BIzLDIw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3767726ef1ff5fa0036c933db472f276f7420c59c20abc2474ed38d5da8f9b9a57c6f46272482ce324d780a45dde6e85d849bbfa9af33ffe2d1a1c5c67cdb982", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0bc6a7b4f570bee0c7bdcfa37ccc63eb874d1458d26fc97ba3f9bd8c3a5f21270875d0f42860dba0ec5a245c59c2245e6a01a4db6b3cc41b6479a2d94293d967", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/236727b923e165f7ef383d90dbf5f330e5c536e7ab76915cd06dfa4fc719c622fc9055e9a9513290355612cbad6b14a888260f1aeb760b86bba21e0a422be87f"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-13 02:42:16.785985) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-13 02:42:16.786306) ========= 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=8783db0840cc980e80bb0bd400000236-1465808655.496949-1401062713 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004525 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-13 02:42:16.870306) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-13 02:42:16.870658) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-13 02:42:16.870956) ========= ========= Total master_lag: 0.17 =========