builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-036 starttime: 1467095266.42 results: success (0) buildid: 20160627203026 builduid: de1ce539c7a74e938561ecad06ec0edf revision: 0546e2201cab3ce639ee0d695c2b9a422c16c976 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-27 23:27:46.416817) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-27 23:27:46.417215) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-27 23:27:46.417511) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-27 23:27:46.495835) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 23:27:46.496126) ========= 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=14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-27 23:27:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.7M=0s 2016-06-27 23:27:46 (40.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.109447 ========= master_lag: 0.19 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 23:27:46.795621) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 23:27:46.795914) ========= 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=14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021650 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 23:27:46.857112) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-27 23:27:46.857417) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0546e2201cab3ce639ee0d695c2b9a422c16c976 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 0546e2201cab3ce639ee0d695c2b9a422c16c976 --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=14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-27 23:27:47,171 truncating revision to first 12 chars 2016-06-27 23:27:47,171 Setting DEBUG logging. 2016-06-27 23:27:47,171 attempt 1/10 2016-06-27 23:27:47,171 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0546e2201cab?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-27 23:27:48,449 unpacking tar archive at: fx-team-0546e2201cab/testing/mozharness/ program finished with exit code 0 elapsedTime=1.596870 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-27 23:27:48.484995) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-27 23:27:48.485263) ========= script_repo_revision: 0546e2201cab3ce639ee0d695c2b9a422c16c976 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-27 23:27:48.485622) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-27 23:27:48.485883) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-27 23:27:48.496382) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-06-27 23:27:48.496657) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:27:48 INFO - MultiFileLogger online at 20160627 23:27:48 in /builds/slave/test 23:27:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:27:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:27:48 INFO - {'append_to_log': False, 23:27:48 INFO - 'base_work_dir': '/builds/slave/test', 23:27:48 INFO - 'blob_upload_branch': 'Fx-Team', 23:27:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:27:48 INFO - 'branch': 'Fx-Team', 23:27:48 INFO - 'buildbot_json_path': 'buildprops.json', 23:27:48 INFO - 'config_files': ('talos/linux_config.py',), 23:27:48 INFO - 'default_actions': ('clobber', 23:27:48 INFO - 'read-buildbot-config', 23:27:48 INFO - 'download-and-extract', 23:27:48 INFO - 'populate-webroot', 23:27:48 INFO - 'create-virtualenv', 23:27:48 INFO - 'install', 23:27:48 INFO - 'run-tests'), 23:27:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:27:48 INFO - 'download_minidump_stackwalk': True, 23:27:48 INFO - 'download_symbols': 'ondemand', 23:27:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:27:48 INFO - 'tooltool.py': '/tools/tooltool.py', 23:27:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:27:48 INFO - '/tools/misc-python/virtualenv.py')}, 23:27:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:27:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:27:48 INFO - 'installer_path': 'installer.exe', 23:27:48 INFO - 'log_level': 'info', 23:27:48 INFO - 'log_name': 'talos', 23:27:48 INFO - 'log_to_console': True, 23:27:48 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:27:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:27:48 INFO - 'opt_config_files': (), 23:27:48 INFO - 'pip_index': False, 23:27:48 INFO - 'sps_profile': False, 23:27:48 INFO - 'sps_profile_interval': 0, 23:27:48 INFO - 'suite': 'dromaeojs', 23:27:48 INFO - 'system_bits': '32', 23:27:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:27:48 INFO - 'title': 'talos-linux64-ix-036', 23:27:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:27:48 INFO - 'use_talos_json': True, 23:27:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:27:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:27:48 INFO - 'work_dir': 'build'} 23:27:48 INFO - ##### 23:27:48 INFO - ##### Running clobber step. 23:27:48 INFO - ##### 23:27:48 INFO - Running pre-action listener: _resource_record_pre_action 23:27:48 INFO - Running main action method: clobber 23:27:48 INFO - rmtree: /builds/slave/test/build 23:27:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:27:49 INFO - Running post-action listener: _resource_record_post_action 23:27:49 INFO - ##### 23:27:49 INFO - ##### Finished clobber step (success) 23:27:49 INFO - ##### 23:27:49 INFO - ##### 23:27:49 INFO - ##### Running read-buildbot-config step. 23:27:49 INFO - ##### 23:27:49 INFO - Running pre-action listener: _resource_record_pre_action 23:27:49 INFO - Running main action method: read_buildbot_config 23:27:49 INFO - Using buildbot properties: 23:27:49 INFO - { 23:27:49 INFO - "project": "", 23:27:49 INFO - "product": "firefox", 23:27:49 INFO - "script_repo_revision": "production", 23:27:49 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 23:27:49 INFO - "repository": "", 23:27:49 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 23:27:49 INFO - "buildid": "20160627203026", 23:27:49 INFO - "pgo_build": "True", 23:27:49 INFO - "basedir": "/builds/slave/test", 23:27:49 INFO - "buildnumber": 222, 23:27:49 INFO - "slavename": "talos-linux64-ix-036", 23:27:49 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 23:27:49 INFO - "platform": "ubuntu64_hw", 23:27:49 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 23:27:49 INFO - "branch": "fx-team", 23:27:49 INFO - "repo_path": "integration/fx-team", 23:27:49 INFO - "revision": "0546e2201cab3ce639ee0d695c2b9a422c16c976", 23:27:49 INFO - "stage_platform": "linux64-pgo", 23:27:49 INFO - "builduid": "de1ce539c7a74e938561ecad06ec0edf", 23:27:49 INFO - "slavebuilddir": "test" 23:27:49 INFO - } 23:27:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/D237ZNe6QcixMhUhAQaX1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 23:27:49 INFO - Running post-action listener: _resource_record_post_action 23:27:49 INFO - ##### 23:27:49 INFO - ##### Finished read-buildbot-config step (success) 23:27:49 INFO - ##### 23:27:49 INFO - ##### 23:27:49 INFO - ##### Running download-and-extract step. 23:27:49 INFO - ##### 23:27:49 INFO - Running pre-action listener: _resource_record_pre_action 23:27:49 INFO - Running main action method: download_and_extract 23:27:49 INFO - mkdir: /builds/slave/test/build/tests 23:27:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:27:49 INFO - https://queue.taskcluster.net/v1/task/D237ZNe6QcixMhUhAQaX1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:27:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D237ZNe6QcixMhUhAQaX1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:27:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D237ZNe6QcixMhUhAQaX1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:27:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D237ZNe6QcixMhUhAQaX1g/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 23:27:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D237ZNe6QcixMhUhAQaX1g/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 23:27:54 INFO - Downloaded 1361 bytes. 23:27:54 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:27:54 INFO - Using the following test package requirements: 23:27:54 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:27:54 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:27:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:27:54 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:27:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:27:54 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:27:54 INFO - u'jsshell-linux-x86_64.zip'], 23:27:54 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:27:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:27:54 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:27:54 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:27:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:27:54 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:27:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:27:54 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:27:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:27:54 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:27:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:27:54 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:27: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')]} 23:27:54 INFO - https://queue.taskcluster.net/v1/task/D237ZNe6QcixMhUhAQaX1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:27:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D237ZNe6QcixMhUhAQaX1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:27:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D237ZNe6QcixMhUhAQaX1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:27:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D237ZNe6QcixMhUhAQaX1g/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 23:27:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D237ZNe6QcixMhUhAQaX1g/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 23:27:57 INFO - Downloaded 24279844 bytes. 23:27:57 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'] 23:27:57 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 23:27:58 INFO - Return code: 0 23:27:58 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 23:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:27:58 INFO - https://queue.taskcluster.net/v1/task/D237ZNe6QcixMhUhAQaX1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:27:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D237ZNe6QcixMhUhAQaX1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:27:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D237ZNe6QcixMhUhAQaX1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:27:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D237ZNe6QcixMhUhAQaX1g/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 23:27:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D237ZNe6QcixMhUhAQaX1g/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 23:28:03 INFO - Downloaded 24279844 bytes. 23:28:03 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'] 23:28:03 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 23:28:04 INFO - Return code: 0 23:28:04 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')]} 23:28:04 INFO - https://queue.taskcluster.net/v1/task/D237ZNe6QcixMhUhAQaX1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:28:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D237ZNe6QcixMhUhAQaX1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:28:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D237ZNe6QcixMhUhAQaX1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:28:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D237ZNe6QcixMhUhAQaX1g/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 23:28:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D237ZNe6QcixMhUhAQaX1g/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 23:28:05 INFO - Downloaded 14405245 bytes. 23:28:05 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'] 23:28:05 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 23:28:06 INFO - Return code: 0 23:28:06 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')]} 23:28:06 INFO - https://queue.taskcluster.net/v1/task/D237ZNe6QcixMhUhAQaX1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:28:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D237ZNe6QcixMhUhAQaX1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:28:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D237ZNe6QcixMhUhAQaX1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:28:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D237ZNe6QcixMhUhAQaX1g/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 23:28:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D237ZNe6QcixMhUhAQaX1g/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 23:28:07 INFO - Downloaded 60294869 bytes. 23:28:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D237ZNe6QcixMhUhAQaX1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:28:07 INFO - mkdir: /builds/slave/test/properties 23:28:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:28:07 INFO - Writing to file /builds/slave/test/properties/build_url 23:28:07 INFO - Contents: 23:28:07 INFO - build_url:https://queue.taskcluster.net/v1/task/D237ZNe6QcixMhUhAQaX1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:28:08 INFO - Running post-action listener: _resource_record_post_action 23:28:08 INFO - Running post-action listener: set_extra_try_arguments 23:28:08 INFO - ##### 23:28:08 INFO - ##### Finished download-and-extract step (success) 23:28:08 INFO - ##### 23:28:08 INFO - ##### 23:28:08 INFO - ##### Running populate-webroot step. 23:28:08 INFO - ##### 23:28:08 INFO - Running pre-action listener: _resource_record_pre_action 23:28:08 INFO - Running main action method: populate_webroot 23:28:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:28:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:28:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:28:08 INFO - '--tppagecycles', 23:28:08 INFO - '7'], 23:28:08 INFO - 'tests': ['tsvgm']}}, 23:28:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:28:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:28:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:28:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:28:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:28:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:28:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:28:08 INFO - 'talos_options': ['--disable-e10s'], 23:28:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:28:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:28:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:28:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:28:08 INFO - 'talos_options': ['--disable-e10s'], 23:28:08 INFO - 'tests': ['damp', 'tps']}, 23:28:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:28:08 INFO - 'tests': ['damp', 'tps']}, 23:28:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:28:08 INFO - 'tests': ['dromaeo_dom']}, 23:28:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:28:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:28:08 INFO - 'tests': ['basic_compositor_video']}, 23:28:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:28:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:28:08 INFO - 'tests': ['a11yr', 23:28:08 INFO - 'ts_paint', 23:28:08 INFO - 'tpaint', 23:28:08 INFO - 'sessionrestore', 23:28:08 INFO - 'sessionrestore_no_auto_restore', 23:28:08 INFO - 'tabpaint']}, 23:28:08 INFO - 'other-e10s': {'tests': ['a11yr', 23:28:08 INFO - 'ts_paint', 23:28:08 INFO - 'tpaint', 23:28:08 INFO - 'sessionrestore', 23:28:08 INFO - 'sessionrestore_no_auto_restore', 23:28:08 INFO - 'tabpaint']}, 23:28:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:28:08 INFO - 'ts_paint', 23:28:08 INFO - 'tpaint', 23:28:08 INFO - 'sessionrestore', 23:28:08 INFO - 'sessionrestore_no_auto_restore', 23:28:08 INFO - 'tabpaint']}, 23:28:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:28:08 INFO - 'ts_paint', 23:28:08 INFO - 'tpaint', 23:28:08 INFO - 'sessionrestore', 23:28:08 INFO - 'sessionrestore_no_auto_restore', 23:28:08 INFO - 'tabpaint']}, 23:28:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:28:08 INFO - 'tests': ['a11yr', 23:28:08 INFO - 'ts_paint', 23:28:08 INFO - 'tpaint', 23:28:08 INFO - 'sessionrestore', 23:28:08 INFO - 'sessionrestore_no_auto_restore', 23:28:08 INFO - 'tabpaint']}, 23:28:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:28:08 INFO - 'tests': ['a11yr', 23:28:08 INFO - 'ts_paint', 23:28:08 INFO - 'tpaint', 23:28:08 INFO - 'sessionrestore', 23:28:08 INFO - 'sessionrestore_no_auto_restore', 23:28:08 INFO - 'tabpaint']}, 23:28:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:28:08 INFO - 'tests': ['tsvgx', 23:28:08 INFO - 'tsvgr_opacity', 23:28:08 INFO - 'tart', 23:28:08 INFO - 'tscrollx', 23:28:08 INFO - 'cart']}, 23:28:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:28:08 INFO - 'tsvgr_opacity', 23:28:08 INFO - 'tart', 23:28:08 INFO - 'tscrollx', 23:28:08 INFO - 'cart']}, 23:28:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:28:08 INFO - 'talos_options': ['--disable-e10s'], 23:28:08 INFO - 'tests': ['tp5o']}, 23:28:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:28:08 INFO - 'tests': ['tp5o']}, 23:28:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:28:08 INFO - 'talos_options': ['--disable-e10s', 23:28:08 INFO - '--xperf_path', 23:28:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:28:08 INFO - 'tests': ['tp5n']}, 23:28:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:28:08 INFO - 'talos_options': ['--xperf_path', 23:28:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:28:08 INFO - 'tests': ['tp5n']}}, 23:28:08 INFO - 'talos.zip': {'path': '', 23:28:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:28:08 INFO - Running post-action listener: _resource_record_post_action 23:28:08 INFO - ##### 23:28:08 INFO - ##### Finished populate-webroot step (success) 23:28:08 INFO - ##### 23:28:08 INFO - ##### 23:28:08 INFO - ##### Running create-virtualenv step. 23:28:08 INFO - ##### 23:28:08 INFO - Running pre-action listener: _resource_record_pre_action 23:28:08 INFO - Running main action method: create_virtualenv 23:28:08 INFO - Creating virtualenv /builds/slave/test/build/venv 23:28:08 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 23:28:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:28:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:28:08 INFO - Using real prefix '/usr' 23:28:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:28:08 INFO - Installing distribute.............................................................................................................................................................................................done. 23:28:10 INFO - Installing pip.................done. 23:28:10 INFO - Return code: 0 23:28:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:28:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:28:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:28:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:10 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 0x7f57289cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcf480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5e6f0>, '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 0xe5f5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5faa0>, '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': '14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079', '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 23:28:10 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 23:28:10 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 23:28:10 INFO - Using env: {'DISPLAY': ':0', 23:28:10 INFO - 'HOME': '/home/cltbld', 23:28:10 INFO - 'LANG': 'en_US.UTF-8', 23:28:10 INFO - 'LANGUAGE': 'en_US:en', 23:28:10 INFO - 'LOGNAME': 'cltbld', 23:28:10 INFO - 'MAIL': '/var/mail/cltbld', 23:28:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:10 INFO - 'MOZ_NO_REMOTE': '1', 23:28:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:28:10 INFO - 'NO_EM_RESTART': '1', 23:28:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:28:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:10 INFO - 'PWD': '/builds/slave/test', 23:28:10 INFO - 'SHELL': '/bin/bash', 23:28:10 INFO - 'SHLVL': '1', 23:28:10 INFO - 'TERM': 'linux', 23:28:10 INFO - 'TMOUT': '86400', 23:28:10 INFO - 'USER': 'cltbld', 23:28:10 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079', 23:28:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:10 INFO - '_': '/tools/buildbot/bin/python'} 23:28:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:11 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 23:28:11 INFO - Cleaning up... 23:28:11 INFO - Return code: 0 23:28:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:28:11 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')]} 23:28:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:11 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')]} 23:28:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:11 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 0x7f57289cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcf480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5e6f0>, '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 0xe5f5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5faa0>, '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': '14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079', '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 23:28:11 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 23:28:11 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 23:28:11 INFO - Using env: {'DISPLAY': ':0', 23:28:11 INFO - 'HOME': '/home/cltbld', 23:28:11 INFO - 'LANG': 'en_US.UTF-8', 23:28:11 INFO - 'LANGUAGE': 'en_US:en', 23:28:11 INFO - 'LOGNAME': 'cltbld', 23:28:11 INFO - 'MAIL': '/var/mail/cltbld', 23:28:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:11 INFO - 'MOZ_NO_REMOTE': '1', 23:28:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:28:11 INFO - 'NO_EM_RESTART': '1', 23:28:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:28:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:11 INFO - 'PWD': '/builds/slave/test', 23:28:11 INFO - 'SHELL': '/bin/bash', 23:28:11 INFO - 'SHLVL': '1', 23:28:11 INFO - 'TERM': 'linux', 23:28:11 INFO - 'TMOUT': '86400', 23:28:11 INFO - 'USER': 'cltbld', 23:28:11 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079', 23:28:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:11 INFO - '_': '/tools/buildbot/bin/python'} 23:28:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:11 INFO - Downloading/unpacking psutil>=3.1.1 23:28:11 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 23:28:11 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 23:28:11 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 23:28:11 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 23:28:11 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 23:28:11 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 23:28:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:28:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:28:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:28:13 INFO - Installing collected packages: psutil 23:28:13 INFO - Running setup.py install for psutil 23:28:13 INFO - building 'psutil._psutil_linux' extension 23:28:13 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 23:28:13 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 23:28:13 INFO - building 'psutil._psutil_posix' extension 23:28:13 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 23:28:13 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 23:28:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:28:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:28:13 INFO - Successfully installed psutil 23:28:13 INFO - Cleaning up... 23:28:13 INFO - Return code: 0 23:28:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:28:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:28:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:28:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:13 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 0x7f57289cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcf480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5e6f0>, '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 0xe5f5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5faa0>, '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': '14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079', '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 23:28:13 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 23:28:13 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 23:28:13 INFO - Using env: {'DISPLAY': ':0', 23:28:13 INFO - 'HOME': '/home/cltbld', 23:28:13 INFO - 'LANG': 'en_US.UTF-8', 23:28:13 INFO - 'LANGUAGE': 'en_US:en', 23:28:13 INFO - 'LOGNAME': 'cltbld', 23:28:13 INFO - 'MAIL': '/var/mail/cltbld', 23:28:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:13 INFO - 'MOZ_NO_REMOTE': '1', 23:28:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:28:13 INFO - 'NO_EM_RESTART': '1', 23:28:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:28:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:13 INFO - 'PWD': '/builds/slave/test', 23:28:13 INFO - 'SHELL': '/bin/bash', 23:28:13 INFO - 'SHLVL': '1', 23:28:13 INFO - 'TERM': 'linux', 23:28:13 INFO - 'TMOUT': '86400', 23:28:13 INFO - 'USER': 'cltbld', 23:28:13 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079', 23:28:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:13 INFO - '_': '/tools/buildbot/bin/python'} 23:28:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:28:14 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 23:28:14 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 23:28:14 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 23:28:14 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 23:28:14 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 23:28:14 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 23:28:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:28:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:28:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:28:15 INFO - Installing collected packages: mozsystemmonitor 23:28:15 INFO - Running setup.py install for mozsystemmonitor 23:28:16 INFO - Successfully installed mozsystemmonitor 23:28:16 INFO - Cleaning up... 23:28:16 INFO - Return code: 0 23:28:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:28:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:28:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:28:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:16 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 0x7f57289cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcf480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5e6f0>, '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 0xe5f5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5faa0>, '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': '14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079', '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 23:28:16 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 23:28:16 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 23:28:16 INFO - Using env: {'DISPLAY': ':0', 23:28:16 INFO - 'HOME': '/home/cltbld', 23:28:16 INFO - 'LANG': 'en_US.UTF-8', 23:28:16 INFO - 'LANGUAGE': 'en_US:en', 23:28:16 INFO - 'LOGNAME': 'cltbld', 23:28:16 INFO - 'MAIL': '/var/mail/cltbld', 23:28:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:16 INFO - 'MOZ_NO_REMOTE': '1', 23:28:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:28:16 INFO - 'NO_EM_RESTART': '1', 23:28:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:28:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:16 INFO - 'PWD': '/builds/slave/test', 23:28:16 INFO - 'SHELL': '/bin/bash', 23:28:16 INFO - 'SHLVL': '1', 23:28:16 INFO - 'TERM': 'linux', 23:28:16 INFO - 'TMOUT': '86400', 23:28:16 INFO - 'USER': 'cltbld', 23:28:16 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079', 23:28:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:16 INFO - '_': '/tools/buildbot/bin/python'} 23:28:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:16 INFO - Downloading/unpacking jsonschema==2.5.1 23:28:16 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 23:28:16 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 23:28:16 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 23:28:16 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 23:28:16 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 23:28:16 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 23:28:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:28:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:28:18 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 23:28:18 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 23:28:18 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 23:28:18 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 23:28:18 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 23:28:18 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 23:28:18 INFO - Downloading functools32-3.2.3-2.tar.gz 23:28:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:28:18 INFO - warning: no files found matching '*.txt' 23:28:18 INFO - no previously-included directories found matching 'build' 23:28:18 INFO - no previously-included directories found matching 'dist' 23:28:18 INFO - no previously-included directories found matching '.git*' 23:28:18 INFO - Installing collected packages: jsonschema, functools32 23:28:18 INFO - Running setup.py install for functools32 23:28:18 INFO - warning: no files found matching '*.txt' 23:28:18 INFO - no previously-included directories found matching 'build' 23:28:18 INFO - no previously-included directories found matching 'dist' 23:28:18 INFO - no previously-included directories found matching '.git*' 23:28:18 INFO - Successfully installed jsonschema functools32 23:28:18 INFO - Cleaning up... 23:28:18 INFO - Return code: 0 23:28:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:28:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:28:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:28:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:18 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 0x7f57289cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcf480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5e6f0>, '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 0xe5f5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5faa0>, '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': '14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079', '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 23:28:18 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 23:28:18 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 23:28:18 INFO - Using env: {'DISPLAY': ':0', 23:28:18 INFO - 'HOME': '/home/cltbld', 23:28:18 INFO - 'LANG': 'en_US.UTF-8', 23:28:18 INFO - 'LANGUAGE': 'en_US:en', 23:28:18 INFO - 'LOGNAME': 'cltbld', 23:28:18 INFO - 'MAIL': '/var/mail/cltbld', 23:28:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:18 INFO - 'MOZ_NO_REMOTE': '1', 23:28:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:28:18 INFO - 'NO_EM_RESTART': '1', 23:28:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:28:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:18 INFO - 'PWD': '/builds/slave/test', 23:28:18 INFO - 'SHELL': '/bin/bash', 23:28:18 INFO - 'SHLVL': '1', 23:28:18 INFO - 'TERM': 'linux', 23:28:18 INFO - 'TMOUT': '86400', 23:28:18 INFO - 'USER': 'cltbld', 23:28:18 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079', 23:28:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:18 INFO - '_': '/tools/buildbot/bin/python'} 23:28:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:18 INFO - Downloading/unpacking blobuploader==1.2.4 23:28:18 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 23:28:18 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 23:28:18 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 23:28:18 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 23:28:18 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 23:28:18 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 23:28:20 INFO - Downloading blobuploader-1.2.4.tar.gz 23:28:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:28:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:28:20 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 23:28:20 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 23:28:20 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 23:28:20 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 23:28:20 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 23:28:20 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 23:28:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:28:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:28:21 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 23:28:21 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 23:28:21 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 23:28:21 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 23:28:21 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 23:28:21 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 23:28:21 INFO - Downloading docopt-0.6.1.tar.gz 23:28:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:28:21 INFO - Installing collected packages: blobuploader, requests, docopt 23:28:21 INFO - Running setup.py install for blobuploader 23:28:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:28:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:28:21 INFO - Running setup.py install for requests 23:28:22 INFO - Running setup.py install for docopt 23:28:22 INFO - Successfully installed blobuploader requests docopt 23:28:22 INFO - Cleaning up... 23:28:22 INFO - Return code: 0 23:28:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:28:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:28:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:28:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:22 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 0x7f57289cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcf480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5e6f0>, '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 0xe5f5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5faa0>, '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': '14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079', '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 23:28:22 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 23:28:22 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 23:28:22 INFO - Using env: {'DISPLAY': ':0', 23:28:22 INFO - 'HOME': '/home/cltbld', 23:28:22 INFO - 'LANG': 'en_US.UTF-8', 23:28:22 INFO - 'LANGUAGE': 'en_US:en', 23:28:22 INFO - 'LOGNAME': 'cltbld', 23:28:22 INFO - 'MAIL': '/var/mail/cltbld', 23:28:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:22 INFO - 'MOZ_NO_REMOTE': '1', 23:28:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:28:22 INFO - 'NO_EM_RESTART': '1', 23:28:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:28:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:22 INFO - 'PWD': '/builds/slave/test', 23:28:22 INFO - 'SHELL': '/bin/bash', 23:28:22 INFO - 'SHLVL': '1', 23:28:22 INFO - 'TERM': 'linux', 23:28:22 INFO - 'TMOUT': '86400', 23:28:22 INFO - 'USER': 'cltbld', 23:28:22 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079', 23:28:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:22 INFO - '_': '/tools/buildbot/bin/python'} 23:28:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:28:22 INFO - Running setup.py (path:/tmp/pip-MqOsRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:28:22 INFO - Running setup.py (path:/tmp/pip-4heKo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:28:22 INFO - Running setup.py (path:/tmp/pip-rKl_bw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:28:22 INFO - Running setup.py (path:/tmp/pip-dwvkyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:28:22 INFO - Running setup.py (path:/tmp/pip-igUI6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:28:22 INFO - Running setup.py (path:/tmp/pip-R4U1Ps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:28:22 INFO - Running setup.py (path:/tmp/pip-eAzawO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:28:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:28:22 INFO - Running setup.py (path:/tmp/pip-9ZBF3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:28:23 INFO - Running setup.py (path:/tmp/pip-POudlP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:28:23 INFO - Running setup.py (path:/tmp/pip-ROFxJu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:28:23 INFO - Running setup.py (path:/tmp/pip-FQgyUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:28:23 INFO - Running setup.py (path:/tmp/pip-DcvpBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:28:23 INFO - Running setup.py (path:/tmp/pip-AoeG3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:28:23 INFO - Running setup.py (path:/tmp/pip-BGYmWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:28:23 INFO - Running setup.py (path:/tmp/pip-kjskPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:28:23 INFO - Running setup.py (path:/tmp/pip-_bMlbl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:28:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:28:23 INFO - Running setup.py (path:/tmp/pip-Q43AMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:28:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:28:23 INFO - Running setup.py install for manifestparser 23:28:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:28:23 INFO - Running setup.py install for mozcrash 23:28:23 INFO - Running setup.py install for mozdebug 23:28:23 INFO - Running setup.py install for mozdevice 23:28:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:28:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:28:24 INFO - Running setup.py install for mozfile 23:28:24 INFO - Running setup.py install for mozhttpd 23:28:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:28:24 INFO - Running setup.py install for mozinfo 23:28:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:28:24 INFO - Running setup.py install for mozInstall 23:28:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:28:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:28:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:28:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:28:24 INFO - Running setup.py install for mozleak 23:28:24 INFO - Running setup.py install for mozlog 23:28:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:28:24 INFO - Running setup.py install for moznetwork 23:28:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:28:24 INFO - Running setup.py install for mozprocess 23:28:24 INFO - Running setup.py install for mozprofile 23:28:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:28:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:28:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:28:25 INFO - Running setup.py install for mozrunner 23:28:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:28:25 INFO - Running setup.py install for mozscreenshot 23:28:25 INFO - Running setup.py install for moztest 23:28:25 INFO - Running setup.py install for mozversion 23:28:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:28:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:28:25 INFO - Cleaning up... 23:28:25 INFO - Return code: 0 23:28:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:28:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:28:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:25 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 0x7f57289cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcf480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5e6f0>, '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 0xe5f5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5faa0>, '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': '14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079', '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 23:28:25 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 23:28:25 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 23:28:25 INFO - Using env: {'DISPLAY': ':0', 23:28:25 INFO - 'HOME': '/home/cltbld', 23:28:25 INFO - 'LANG': 'en_US.UTF-8', 23:28:25 INFO - 'LANGUAGE': 'en_US:en', 23:28:25 INFO - 'LOGNAME': 'cltbld', 23:28:25 INFO - 'MAIL': '/var/mail/cltbld', 23:28:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:25 INFO - 'MOZ_NO_REMOTE': '1', 23:28:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:28:25 INFO - 'NO_EM_RESTART': '1', 23:28:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:28:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:25 INFO - 'PWD': '/builds/slave/test', 23:28:25 INFO - 'SHELL': '/bin/bash', 23:28:25 INFO - 'SHLVL': '1', 23:28:25 INFO - 'TERM': 'linux', 23:28:25 INFO - 'TMOUT': '86400', 23:28:25 INFO - 'USER': 'cltbld', 23:28:25 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079', 23:28:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:25 INFO - '_': '/tools/buildbot/bin/python'} 23:28:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:28:25 INFO - Running setup.py (path:/tmp/pip-NNoy6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:28:25 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)) 23:28:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:28:25 INFO - Running setup.py (path:/tmp/pip-sAJKLd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:28:25 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)) 23:28:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:28:25 INFO - Running setup.py (path:/tmp/pip-0gd2GT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:28:25 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)) 23:28:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:28:25 INFO - Running setup.py (path:/tmp/pip-c0Rq6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:28:25 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)) 23:28:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:28:25 INFO - Running setup.py (path:/tmp/pip-rR88uA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:28:26 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)) 23:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:28:26 INFO - Running setup.py (path:/tmp/pip-WJBc1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:28:26 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)) 23:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:28:26 INFO - Running setup.py (path:/tmp/pip-3MbytW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:28:26 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)) 23:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:28:26 INFO - Running setup.py (path:/tmp/pip-p3fQaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:28:26 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)) 23:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:28:26 INFO - Running setup.py (path:/tmp/pip-FKcwfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:28:26 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)) 23:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:28:26 INFO - Running setup.py (path:/tmp/pip-tfDy3n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:28:26 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)) 23:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:28:26 INFO - Running setup.py (path:/tmp/pip-YqdW6T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:28:26 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)) 23:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:28:26 INFO - Running setup.py (path:/tmp/pip-rxc4U1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:28:26 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)) 23:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:28:26 INFO - Running setup.py (path:/tmp/pip-pBdoYy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:28:26 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)) 23:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:28:26 INFO - Running setup.py (path:/tmp/pip-0zWZnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:28:26 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)) 23:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:28:26 INFO - Running setup.py (path:/tmp/pip-q33EkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:28:26 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)) 23:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:28:26 INFO - Running setup.py (path:/tmp/pip-QxoKC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:28:26 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)) 23:28:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:28:26 INFO - Running setup.py (path:/tmp/pip-s_GgZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:28:26 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)) 23:28:26 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)) 23:28:26 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)) 23:28:26 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)) 23:28:26 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)) 23:28:26 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)) 23:28:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:28:26 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 23:28:26 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 23:28:26 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 23:28:26 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 23:28:26 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 23:28:26 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 23:28:28 INFO - Downloading blessings-1.6.tar.gz 23:28:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:28:28 INFO - Installing collected packages: blessings 23:28:28 INFO - Running setup.py install for blessings 23:28:29 INFO - Successfully installed blessings 23:28:29 INFO - Cleaning up... 23:28:29 INFO - Return code: 0 23:28:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:28:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:28:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:28:29 INFO - Reading from file tmpfile_stdout 23:28:29 INFO - Current package versions: 23:28:29 INFO - argparse == 1.2.1 23:28:29 INFO - blessings == 1.6 23:28:29 INFO - blobuploader == 1.2.4 23:28:29 INFO - docopt == 0.6.1 23:28:29 INFO - functools32 == 3.2.3-2 23:28:29 INFO - jsonschema == 2.5.1 23:28:29 INFO - manifestparser == 1.1 23:28:29 INFO - mozInstall == 1.12 23:28:29 INFO - mozcrash == 0.17 23:28:29 INFO - mozdebug == 0.1 23:28:29 INFO - mozdevice == 0.48 23:28:29 INFO - mozfile == 1.2 23:28:29 INFO - mozhttpd == 0.7 23:28:29 INFO - mozinfo == 0.9 23:28:29 INFO - mozleak == 0.1 23:28:29 INFO - mozlog == 3.2 23:28:29 INFO - moznetwork == 0.27 23:28:29 INFO - mozprocess == 0.22 23:28:29 INFO - mozprofile == 0.28 23:28:29 INFO - mozrunner == 6.11 23:28:29 INFO - mozscreenshot == 0.1 23:28:29 INFO - mozsystemmonitor == 0.3 23:28:29 INFO - moztest == 0.7 23:28:29 INFO - mozversion == 1.4 23:28:29 INFO - psutil == 3.1.1 23:28:29 INFO - requests == 1.2.3 23:28:29 INFO - wsgiref == 0.1.2 23:28:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:28:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:28:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:28:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:29 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 0x7f57289cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcf480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5e6f0>, '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 0xe5f5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5faa0>, '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': '14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079', '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 23:28:29 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 23:28:29 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 23:28:29 INFO - Using env: {'DISPLAY': ':0', 23:28:29 INFO - 'HOME': '/home/cltbld', 23:28:29 INFO - 'LANG': 'en_US.UTF-8', 23:28:29 INFO - 'LANGUAGE': 'en_US:en', 23:28:29 INFO - 'LOGNAME': 'cltbld', 23:28:29 INFO - 'MAIL': '/var/mail/cltbld', 23:28:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:29 INFO - 'MOZ_NO_REMOTE': '1', 23:28:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:28:29 INFO - 'NO_EM_RESTART': '1', 23:28:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:28:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:29 INFO - 'PWD': '/builds/slave/test', 23:28:29 INFO - 'SHELL': '/bin/bash', 23:28:29 INFO - 'SHLVL': '1', 23:28:29 INFO - 'TERM': 'linux', 23:28:29 INFO - 'TMOUT': '86400', 23:28:29 INFO - 'USER': 'cltbld', 23:28:29 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079', 23:28:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:29 INFO - '_': '/tools/buildbot/bin/python'} 23:28:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:29 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)) 23:28:29 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)) 23:28:29 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)) 23:28:29 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)) 23:28:29 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)) 23:28:29 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)) 23:28:29 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)) 23:28:29 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)) 23:28:29 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)) 23:28:29 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)) 23:28:29 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)) 23:28:29 INFO - Cleaning up... 23:28:29 INFO - Return code: 0 23:28:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:28:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:28:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:28:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f57289cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcf480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5e6f0>, '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 0xe5f5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5faa0>, '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': '14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079', '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 23:28:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:28:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:28:29 INFO - Using env: {'DISPLAY': ':0', 23:28:29 INFO - 'HOME': '/home/cltbld', 23:28:29 INFO - 'LANG': 'en_US.UTF-8', 23:28:29 INFO - 'LANGUAGE': 'en_US:en', 23:28:29 INFO - 'LOGNAME': 'cltbld', 23:28:29 INFO - 'MAIL': '/var/mail/cltbld', 23:28:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:29 INFO - 'MOZ_NO_REMOTE': '1', 23:28:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:28:29 INFO - 'NO_EM_RESTART': '1', 23:28:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:28:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:29 INFO - 'PWD': '/builds/slave/test', 23:28:29 INFO - 'SHELL': '/bin/bash', 23:28:29 INFO - 'SHLVL': '1', 23:28:29 INFO - 'TERM': 'linux', 23:28:29 INFO - 'TMOUT': '86400', 23:28:29 INFO - 'USER': 'cltbld', 23:28:29 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079', 23:28:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:29 INFO - '_': '/tools/buildbot/bin/python'} 23:28:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:28:29 INFO - Cleaning up... 23:28:29 INFO - Return code: 0 23:28:29 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:28:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:28:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:28:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:28:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:28:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:28:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f57289cff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdcf480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe5e6f0>, '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 0xe5f5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5faa0>, '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': '14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079', '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 23:28:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 23:28:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 23:28:29 INFO - Using env: {'DISPLAY': ':0', 23:28:29 INFO - 'HOME': '/home/cltbld', 23:28:29 INFO - 'LANG': 'en_US.UTF-8', 23:28:29 INFO - 'LANGUAGE': 'en_US:en', 23:28:29 INFO - 'LOGNAME': 'cltbld', 23:28:29 INFO - 'MAIL': '/var/mail/cltbld', 23:28:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:29 INFO - 'MOZ_NO_REMOTE': '1', 23:28:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:28:29 INFO - 'NO_EM_RESTART': '1', 23:28:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:28:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:29 INFO - 'PWD': '/builds/slave/test', 23:28:29 INFO - 'SHELL': '/bin/bash', 23:28:29 INFO - 'SHLVL': '1', 23:28:29 INFO - 'TERM': 'linux', 23:28:29 INFO - 'TMOUT': '86400', 23:28:29 INFO - 'USER': 'cltbld', 23:28:29 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079', 23:28:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:29 INFO - '_': '/tools/buildbot/bin/python'} 23:28:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:28:29 INFO - Downloading/unpacking flake8 23:28:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:28:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:28:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:28:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:28:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:28:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:28:31 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:28:31 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:28:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:28:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:28:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:28:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:28:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:28:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:28:32 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:28:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:28:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:28:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:28:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:28:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:28:32 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:28:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:28:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:28:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:28:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:28:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:28:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:28:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:28:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:28:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:28:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:28:32 INFO - no previously-included directories found matching 'docs/_build' 23:28:32 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:28:32 INFO - Running setup.py install for pep8 23:28:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:28:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:28:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:28:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:28:32 INFO - no previously-included directories found matching 'docs/_build' 23:28:32 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:28:32 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:28:32 INFO - Cleaning up... 23:28:32 INFO - Return code: 0 23:28:32 INFO - Running post-action listener: _resource_record_post_action 23:28:32 INFO - Running post-action listener: _start_resource_monitoring 23:28:32 INFO - Starting resource monitoring. 23:28:32 INFO - ##### 23:28:32 INFO - ##### Finished create-virtualenv step (success) 23:28:32 INFO - ##### 23:28:32 INFO - ##### 23:28:32 INFO - ##### Running install step. 23:28:32 INFO - ##### 23:28:32 INFO - Running pre-action listener: _resource_record_pre_action 23:28:32 INFO - Running main action method: install 23:28:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:28:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:28:33 INFO - Reading from file tmpfile_stdout 23:28:33 INFO - Detecting whether we're running mozinstall >=1.0... 23:28:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:28:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:28:33 INFO - Reading from file tmpfile_stdout 23:28:33 INFO - Output received: 23:28:33 INFO - Usage: mozinstall [options] installer 23:28:33 INFO - Options: 23:28:33 INFO - -h, --help show this help message and exit 23:28:33 INFO - -d DEST, --destination=DEST 23:28:33 INFO - Directory to install application into. [default: 23:28:33 INFO - "/builds/slave/test"] 23:28:33 INFO - --app=APP Application being installed. [default: firefox] 23:28:33 INFO - mkdir: /builds/slave/test/build/application 23:28:33 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'] 23:28:33 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 23:28:50 INFO - Reading from file tmpfile_stdout 23:28:50 INFO - Output received: 23:28:50 INFO - /builds/slave/test/build/application/firefox/firefox 23:28:50 INFO - Running post-action listener: _resource_record_post_action 23:28:50 INFO - ##### 23:28:50 INFO - ##### Finished install step (success) 23:28:50 INFO - ##### 23:28:50 INFO - ##### 23:28:50 INFO - ##### Running run-tests step. 23:28:50 INFO - ##### 23:28:50 INFO - Running pre-action listener: _resource_record_pre_action 23:28:50 INFO - Running main action method: run_tests 23:28:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:28:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:28:50 INFO - Python 2.7.3 23:28:50 INFO - Return code: 0 23:28:50 INFO - grabbing minidump binary from tooltool 23:28: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')]} 23:28:50 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 0xe5e6f0>, '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 0xe5f5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe5faa0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:28:50 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 23:28:50 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 23:28:50 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 23:28:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:28:50 INFO - Return code: 0 23:28:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:28:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:28:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:28:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:28:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:28:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:28:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:28:51 INFO - Return code: 0 23:28:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D237ZNe6QcixMhUhAQaX1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 23:28:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/D237ZNe6QcixMhUhAQaX1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-036 --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 23:28:51 INFO - Using env: {'DISPLAY': ':0', 23:28:51 INFO - 'HOME': '/home/cltbld', 23:28:51 INFO - 'LANG': 'en_US.UTF-8', 23:28:51 INFO - 'LANGUAGE': 'en_US:en', 23:28:51 INFO - 'LOGNAME': 'cltbld', 23:28:51 INFO - 'MAIL': '/var/mail/cltbld', 23:28:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:28:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:28:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:28:51 INFO - 'MOZ_NO_REMOTE': '1', 23:28:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:28:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:28:51 INFO - 'NO_EM_RESTART': '1', 23:28:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:28:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:28:51 INFO - 'PWD': '/builds/slave/test', 23:28:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:28:51 INFO - 'SHELL': '/bin/bash', 23:28:51 INFO - 'SHLVL': '1', 23:28:51 INFO - 'TERM': 'linux', 23:28:51 INFO - 'TMOUT': '86400', 23:28:51 INFO - 'USER': 'cltbld', 23:28:51 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079', 23:28:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:28:51 INFO - '_': '/tools/buildbot/bin/python'} 23:28:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D237ZNe6QcixMhUhAQaX1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 23:28:51 INFO - mozversion application_buildid: 20160627203026 23:28:51 INFO - mozversion application_changeset: 0546e2201cab3ce639ee0d695c2b9a422c16c976 23:28:51 INFO - mozversion application_display_name: Nightly 23:28:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:28:51 INFO - mozversion application_name: Firefox 23:28:51 INFO - mozversion application_remotingname: firefox 23:28:51 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:28:51 INFO - mozversion application_vendor: Mozilla 23:28:51 INFO - mozversion application_version: 50.0a1 23:28:51 INFO - mozversion platform_buildid: 20160627203026 23:28:51 INFO - mozversion platform_changeset: 0546e2201cab3ce639ee0d695c2b9a422c16c976 23:28:51 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:28:51 INFO - mozversion platform_version: 50.0a1 23:28:51 INFO - using testdate: 1467095331 23:28:51 INFO - actual date: 1467095331 23:28:51 INFO - starting webserver on 'localhost:57501' 23:28:51 INFO - SUITE-START | Running 2 tests 23:28:51 INFO - TEST-START | dromaeo_css 23:28:51 INFO - operating with platform_type : linux_ 23:28:51 INFO - Initialising browser for dromaeo_css test... 23:28:51 INFO - TEST-INFO | started process 29183 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYrb_VM/profile http://localhost:57501/getInfo.html) 23:28:55 INFO - PROCESS | 29183 | __metrics Screen width/height:1600/1200 23:28:55 INFO - PROCESS | 29183 | colorDepth:24 23:28:55 INFO - PROCESS | 29183 | Browser inner width/height: 1024/697 23:28:55 INFO - PROCESS | 29183 | __metrics 23:28:56 INFO - PROCESS | 29183 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:28:57 INFO - TEST-INFO | 29183: exit 0 23:28:57 INFO - Browser initialized. 23:28:57 INFO - Running cycle 1/1 for dromaeo_css test... 23:28:57 INFO - TEST-INFO | started process 29266 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYrb_VM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:29:09 INFO - PROCESS | 29266 | RSS: Main: 174190592 23:29:09 INFO - PROCESS | 29266 | 23:30:00 INFO - PROCESS | 29266 | 1467095400460 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] 23:32:09 INFO - PROCESS | 29266 | Cycle 1(1): loaded http://localhost:57501/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57501/tests/dromaeo/cssquery-ext.html) 23:32:10 INFO - PROCESS | 29266 | RSS: Main: 309370880 23:32:10 INFO - PROCESS | 29266 | 23:35:13 INFO - PROCESS | 29266 | Cycle 1(1): loaded http://localhost:57501/tests/dromaeo/cssquery-ext.html (next: http://localhost:57501/tests/dromaeo/cssquery-jquery.html) 23:35:13 INFO - PROCESS | 29266 | RSS: Main: 257044480 23:35:13 INFO - PROCESS | 29266 | 23:38:15 INFO - PROCESS | 29266 | Cycle 1(1): loaded http://localhost:57501/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57501/tests/dromaeo/cssquery-mootools.html) 23:38:16 INFO - PROCESS | 29266 | RSS: Main: 258334720 23:38:16 INFO - PROCESS | 29266 | 23:41:18 INFO - PROCESS | 29266 | Cycle 1(1): loaded http://localhost:57501/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57501/tests/dromaeo/cssquery-prototype.html) 23:41:19 INFO - PROCESS | 29266 | RSS: Main: 254722048 23:41:19 INFO - PROCESS | 29266 | 23:44:21 INFO - PROCESS | 29266 | Cycle 1(1): loaded http://localhost:57501/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57501/tests/dromaeo/cssquery-yui.html) 23:44:22 INFO - PROCESS | 29266 | RSS: Main: 247205888 23:44:22 INFO - PROCESS | 29266 | 23:47:25 INFO - PROCESS | 29266 | Cycle 1(1): loaded http://localhost:57501/tests/dromaeo/cssquery-yui.html (next: http://localhost:57501/tests/dromaeo/cssquery-dojo.html) 23:47:25 INFO - PROCESS | 29266 | RSS: Main: 288927744 23:47:25 INFO - PROCESS | 29266 | 23:47:25 INFO - PROCESS | 29266 | __start_tp_report 23:47:25 INFO - PROCESS | 29266 | _x_x_mozilla_page_load 23:47:25 INFO - PROCESS | 29266 | _x_x_mozilla_page_load_details 23:47:25 INFO - PROCESS | 29266 | |i|pagename|runs| 23:47:25 INFO - PROCESS | 29266 | |0;dojo.html;3073.154882407338;3084.568160457536;3109.1760683418884;3109.720125188733;3144.8113113213208;5236.581073514119;5332.733363331832;5396.325459317581;5399.244105825188;5410.05324068288;5346.03771321162;5386.8383948481605;5422.8915421691645;5445.047116754572;5455.699936503491;4809.062232864593;4817.0847538967655;4823.686460380081;4914.655974081816;5014.147594908874;4915.090708218991;4926.359573255471;5154.149565321736;5158.664686795365;5169.483051694838;4811.759412029384;4844.10384078943;4925.630577706686;4931.7040977541465;4976.701397916118;4827;4843.70937743733;4844.66087373884;4875.34182885313;4904.509549045102;4851.611871050308;4925.655204135676;4930.827421040246;4931.21100623902;4974.825881094181;5264.078786212436;5308.097623404031;5375.784968601251;5474;5475.589330800206;5796.97101514494;5836;5860.648361098348;5893.7053147342895;5898.115282707586;5901.970490147564;5935.821925342247;5937.584369094165;5940.108983652443;5943.55423343251;5277.736113194325;5409.350877894678;5461.208124821958;5558.666480011172;5567.081431563787;6021.789237376671;6023.156758053876;6023.728932198062;6031.849203769941;6038;5800.187973683687;5801.796937107181;5803.390643982406;5807.622504537276;5808.88382232353;5634.211210430543;5643.181738647955;5667.659940403589;5675.5459563234845;5849.766009359663;5646.943530564724;5685.573581981368;5690.146478028246;5691.0609749391715;5697.316322041299;5656.151577263443;5693.886122277531;5704.372519022904;5710.428957104339;5712.65724056558;4197.790110494463;4200.264953633102;4318.5033721121845;4363.650907927422;4368.7815609219415;3960.9801950990636;3961.445397644332;3965.7025723070883;3998.1603863189057;4006.238814625212;5271.894562108736;5526.198701188305;5558.77764889408;5571.470710282517;5573.498385145356;5771.567132465082;5809.244798176294;5823.88352232953;5845.590808643439;5872.853178670567;5445.50990410865;5448.700321482356;5449.782008719686;5450.291462109901;5532.695701736363;4645.5354464553275;4759.4764575896625;4827.879303017453;4837.6613637045775;4886.144924638174;5996.430339117776;6051.273847138284;6058.1518587397795;6060.151578778975;6120.877582448326;5815.447532484407;5819.79630712923;5821;5822.446867547746;5825.825225243249;5905.202797622266;5962.105684147413;6020.157177994996;6036.788712395045;6049.032154855202;5746.396628353963;5754.223179870663;5755.71221438918;5758.021136406737;5808.651480911159;5770.278715160605;5811.970940145272;5820.70896455184;5828.854278642898;5850.385709500441;5529.778808847601;5633.492985631313;5665.12190610456;5687.3459552152035;5706.3152421709665;5689.6586204827845;5690.658560486384;5692.6299790513485;5698.743556540048;5703.486686198262;5545.694986775766;5594.328680558359;5610.298712660918;5615.803446879339;5621.80323688669;5494.148406996922;5503.147012213113;5574.163875418558;5629.831105066854;5635.915461268002;5442.945570544244;5498.945010550004;5501.3948465667945;5501.834944951658;5517.2551705519245 23:47:25 INFO - PROCESS | 29266 | |1;ext.html;541.7291354322839;597.575721237921;603.7524614907886;607.3380015782795;612.6691586543266;29777.255568610795;41438;42125.57874421259;42138;42251.15497690052;44302.33546496805;44708.105837883224;44717.55282447174;44771.77614111933;44828.77585612075;8105;9061.229795467376;9213.723588292361;9219.585118669676;9221.446713197196;10642.255042147053;11026.944865275662;11030.613928512505;11277.154213433983;11307.321560706343;10504.474776261195;10550.366977981308;10616.522256498438;10620.150387968944;10620.203484738637;8401.621927013239;10819.242653014293;11021.504032318524;11044;11144.498497567629;10575.73560660982;10955.34267943926;10964.725881852937;10971.725706857313;10999.120070394429;41760.58239417597;42216.78891605553;42310;42317;42394.364084538756;3556.199855032623;3605.801680907549;3837.424386342043;3939.3106206413763;4344.913101737948;33648.15879603005;33731.83134084314;33770.83114584412;33951.6604833951;34154.14614634629;3779.773213607165;3792.3363411402893;3880.611938806125;3920.3727403615285;3926.548446928611;337847;341734;343185;343493;344438;34420.13949651279;35155.296894062485;35345;37152.81423592865;60831;37618.24763504715;37652.81173594115;37734.62265377366;37837.432438513446;37856.62143378586;15223.238838058274;15275.541733748005;15301.770473442908;15311.928165028341;15332.693346133015;12607.873921260854;12930.418131184118;12934.482620695255;13012.28432436225;13458.192508449525;8258.42191046621;8450;8474.32205423577;8536.402451828313;8615.61229744663;3271.3947919634943;3308.93382132356;3330.283988942389;3345.531625572422;3364.2598628301735;996.5166894056376;1003.4581326083979;1032.5560009196117;1064.755106325534;1151.45305979659;32442.513362299585;32567;32889.3422131556;33083.3383332332;33393;1120.1430905357408;1136.6476392318407;1166.9708257293635;1226.9141160118706;1249.9437525311382;17046.82953170477;17140.228689708994;17221.56946076358;17257.56856078608;17294.481165565052;536788;537079;540345;542328;543579;58224;58229.70885145632;58251.70874145687;58286.125708114414;58893.70553147207;1273.1024627637494;1346.4479563379164;1394.9930250348887;1403.5298175111452;1411.9929400353344;31575.84212078925;32390;32831;33191.502127467626;33642.663573363956;47487.287690683974;47763.76118119387;47862.28206576835;47988;48078;372580;376552;377667;377914;378226;25389;27261.31846703769;27737.583936241375;28175.71824281731;29508.81964721387;47535.52464475311;48484.757576211894;48660;49716.25425618691;49790.50209498004;39430.46986236309;40308.1938361237;41347.58652413437;41814.79092604518;41820.95447613834;40384.3942340871;40492.39261411022;41031.38452923268;41635;41922.58077419187;41004.179916400906;41535.58464415317;41733;42023.3696494559;42527.787361063;35220;37114;37316.32076556616;37583.62416375801;37624.62375376211;27791.30521736973;28069;28400.715992840633;28427.43145137127;28618.71381286244 23:47:25 INFO - PROCESS | 29266 | |2;jquery.html;391.9059425737823;393.6181903553552;395.62020460358065;399.2693371130831;417.7890165466439;14440.205788681644;18129.274829006823;18193.454196374096;19761.110750016247;19771;17679.5580110497;18832.246710131614;19692.606147877035;19698.212071517122;19721.802781972176;5629.296337957755;5650.6892120933335;5737.540996720273;5772.047612144012;5773.711314434283;4186.099988502471;5731.340895796974;5748.3676795552665;5788.739506722187;6216.689165541705;6472.385123413292;6554.573952693084;6567.6059436433725;6568.573042752229;6570.671466426684;6309.400606942356;6539.803805885831;6548.738050477975;6549.246836613803;6553.50848686348;5526.640768350045;5778.104393818965;5781.682007489587;5782.508486778629;5799.9130013049835;23951.401214969592;23981.760182398128;24153.2754017378;24157.275281741575;24644.630330545056;2657.7873770098345;2769.8061135720304;2896.942061158786;2940.750181172993;3063.908082757521;14149.151050936976;14841.257937103102;14942.402303907831;15158.772618410732;15319.331847216601;1902.2486117983458;1955.0811118774154;1955.1983686688532;1973.6940774180025;1996.7304413904083;81931.5903420485;82479;82566.58716706438;83716.58141709314;85780.57109714474;10179.491025448699;10437.373757574569;10457.215708821868;10502.527386267637;10808;12817.423215955117;13779.793303100461;14735.189564574051;14779;14891.553253402415;7574;7918.564478953597;8140.656791629315;8216.589170541449;8264.83470330602;8303.418760686814;8451.915480845235;8455.323574114056;8538;8571.357148213785;6476.643784591893;6571.934280657228;6589.242237142694;6635.4359879410795;6771.830704232433;1889.9622007559772;1892.4984879007075;1951.9804801951798;1966.891820949833;1999.5101200206043;882.2589025218847;954.5036580977853;959.8752162219007;968.67517956904;1022.3865680591585;19339.51651208731;20282.391528254175;20373.592528149355;20521;20642.483937901372;1023.6417253961054;1037.2946396450486;1056.271172890703;1065.616378103882;1076.2143635146388;10648.946755266328;11647.76704465906;11718.94140529309;11977.101717371232;11999.280043197436;322486;328622;466986;468383.8969661763;469288;88272;94609.52695236617;96736.51631741796;99172;99519;1001.6193846338344;1044.1234399230073;1062.5484169228048;1069.5133714160038;1070.4059247117768;17699.29202831924;17872.731909021637;18073.277068917097;18156.72764908501;18305.90164590129;30077.54883676703;39831;40187.3971890416;41142.58857411388;42325.36511952262;55462.44537554573;56395;57718;57824.42175578191;58227.70886145712;8414.6213420395;8685.8697119542;8749.65001399937;8762.3438770878;8994.59524321394;9989.051040151175;13402;13454.192748435125;13471.865281347062;13615.931920340336;11832.76334473323;11932.642020739391;11949.880501195224;11956;12157.696057598278;11612.419379030845;11754.23597466162;11866;12046.156769026435;12064.819027714415;11705;11855.525778968744;12020.398980050788;12159.75680486403;12408.255504669749;8690.695825646326;8763.780905477159;8816.779580510538;9000.234980026771;9092.408993415407;11296.830547541958;11967;12054.75890482203;12133.939330303292;12236.938815305866 23:47:25 INFO - PROCESS | 29266 | |3;mootools.html;1330.826992490976;1481.9110853348795;1521.5815650696056;1627.6744651069782;1801.9279228830846;8394.748157555276;8481.194286542775;8520.105388934166;8723.258523025535;8768.736937891874;8494.40539162259;8673.13268673134;8677.219050285474;8679.132086791333;8687.956560217206;2038.4496186029762;2232.821374290061;2304.5275718477756;2326.813854891613;2334.5097529518735;2357.304595144428;2359.8702071386065;2361.315218586608;2361.4332560185517;2363.2319496163814;2290.1640901071155;2303.608386574286;2303.6199027160496;2305.13557415969;2329.580675478413;1956.9902150489227;2210.7457642371173;2238.0712004518105;2281.942951426211;2283.3834864586634;2290.7823756743082;2292.289390289016;2309.6766452696634;2312.4450131968206;2312.7687231276905;8650.134986501362;8737.694180703646;8747.16901894319;8749.212570868589;8754.3871928965;4993.475685053054;5153.587712982952;5156.20078887773;5156.407013193456;5164.018836421068;3214.791038582508;3312.685294896981;3365.3437579671972;3366.7979921204806;3370.258543120534;1039.9584016639326;1042.306865934153;1053.1627356251797;1057.751428414322;1060.957561697539;12449.502019919193;12473.75052498945;12475.376231188404;12480.563180288646;12485.93757031218;4606.147862645421;4703;4796.208625576741;4802.063597598435;4808.855734327975;10480;10483.37099774016;10485.842712359321;10486.528106235237;10487;5244.685318880803;5285.867853303698;5287.23335116406;5288.312519372457;5291.761870715827;4172.228137794457;4624.722516649012;4771.6898401603785;4830.903381932341;4832.975835120872;3929.351656976567;3947.3092208863336;4053.8783836484954;4079.5920407958965;4084.0402505411203;1280;1282.3460035381836;1294.7216348485122;1298.4286913758106;1314.1720715949082;984.9556769945226;987.4470296633908;989.4459102902398;990.4700984973005;1007.5466040281902;6039.969800150971;6183;6184;6353.96823015882;6379.266384365857;925.0425809287356;962.9807403851884;975.4732444479902;991.7768502086973;991.7867658453467;4152.896177595524;4232.111256636141;4257.297545904891;4259.97870010648;4260.6378457830815;10150.898491015143;10420.270581059252;10448.68653940383;10454.947725261325;10479.738006549745;10256.12822910061;11272.54909803615;11318.32090074598;11328.32030078198;11340;8191.959040204761;8291.129431409736;8425.70510032146;8483.74548763538;8508.361872859561;6010.3088144863905;6063.242094738158;6125.081237814185;6190.1952746142715;6206.565540412128;10341.896581034094;10390.948045260027;10432.165426765992;10438.164946804085;10442.425666588411;9879;10308.742281443025;10314.43270620123;10506.842397363893;10596.576136954744;7127.607981561064;7313.926860731324;7317.707291708272;7844.882326764989;8056.315213206943;8068.878966815385;8086.433949623705;8375.497470151811;8401.453905496122;8590.226879580869;7584.734534291273;7661.923380766121;7733.4973226740085;7970.4420690553425;7981.680732770857;7532.17146113938;7561.848763024819;7646.350060244941;7737.651805668871;7752.379809615104;7021.508494405342;7034.753783617549;7080.079589653312;7080.539764915264;7082.752103676346;5114.667546609458;5199.76601052946;5219.164933610604;5314.813981510629;5324.920126198032;5185.948140518546;5217.765200566059;5218.921716174184;5222.166302078512;5274.630775845654 23:47:25 INFO - PROCESS | 29266 | |4;prototype.html;335.53882273523703;335.68781033638714;346.35404969731457;346.589291689348;348.1470397526062;5224.4775522447735;5334.813281535147;5334.89330213396;5396.487333703297;5433.945660543393;5388.7305634718205;5394.487523685253;5394.595405344605;5398.595105367103;5404.4595540445935;4260.147970405916;4262.52942734756;4268.40242366069;4281.8287268509375;4306.784660766949;3993.800309984489;4110.362893751459;4234.555371685975;4274.935875961863;4280.400743895857;4166.854160104397;4172.33242681171;4183.518895327046;4197.202531519017;4208.431861698662;4171.478565179368;4190.120074784299;4191.057012172268;4196.475440569929;4204.327307630764;3632.4188129899353;3759.3045286622064;3760.52993375828;3768.4535742317485;3778.1688028633657;5223.660462069954;5226.5818734501145;5231.3199284092825;5262.421133675292;5264.578833693296;4814.013127308894;4825.445073816521;4864.878378040577;4871.975640121812;4884.780184891689;5415.458454154593;5637.605367624268;5669.0646043402785;5673.319201695782;5678.290213723285;4125.546189919107;4126.349460215908;4169.937450938239;4176.770277634729;4177.4778152730905;5781.682007489587;5863.824085277449;5864.797716468114;5870.11948207768;5902.527797776168;4102.938455923164;4248.428081609763;4251.681123915719;4280.422143010654;4290.377895205178;4282.892927676833;4289.849855255114;4294.677899157576;4306.354046892991;4320.2223599751715;4871.975640121776;4878.829240976549;4893.65104749715;4895.143349913751;4903.926441103386;5401;5612.410696876851;5702.059160238514;5721.5708821838525;5739.483446489836;1391.7007843313736;1426.272600973511;1446.9855301447062;1460.9269536523343;1463.7804329350683;945.2768631996528;949.8100379924043;958.1424624960761;973.3478569358547;984.3454103021554;4887.780049897763;4900.157908413112;4909.852704418873;4931.950680493221;4944.208926571731;5081.8475445736685;5126.436092029874;5136.794528218829;5145.819896303611;5164.612654050961;4628.190066738307;4743.359646447754;4791.952080479151;4806.303086052572;4843.660943733905;2701.689305729827;2709.7967652425755;2718.537848565709;2726.577380506025;2730.276476733667;5164.56101231392;5217.7912883485;5288.312519372457;5357.276767636319;5360.571154307646;4774.594159496482;5147.6911385317;5168.793248270027;5206.71363075027;5223.347081614798;5221.530062294411;5247.790088396498;5255.842324730264;5289.920651190235;5303.46199602111;5002.424236365533;5060.127752731366;5120.718360490208;5121.974390128025;5146.356705411824;4707.8823029423165;5011.974940125276;5072.340595722532;5077.77150028257;5092.337996060489;5155.50490357792;5259.552938000313;5274.602230408868;5274.97362513185;5301.867453313698;3813.8855834325013;3819.618038196158;3869.671077958405;3931.4495970564717;3932.80335983194;4892.996935628116;5139.434662187231;5169.518631057039;5586.38549759518;5630.268065151504;5686.77252909879;5714.457126572969;5751.798687046101;5755.971220143872;5767.423257674199;5617.297837770278;5673.234113394639;5702.087665973424;5716.628419152742;5730.541556675539;5415.675059496442;5490.752916118704;5510.81517473745;5539.418361072029;5578.163275508706;5729.570282228766;5729.914051289151;5738.483536481736;5745.856353591026;5755.856103597276;5338.572914166935;5532.612717109763;5541.92177933275;5620.718964051863;5632.831015069554 23:47:25 INFO - PROCESS | 29266 | |5;yui.html;693.5145398221244;695.0859619600226;715.1632589869855;727.1456039153993;736.3593673504053;13241.205527668346;14087.366068526915;14104;14299.857001429982;14974.176420296879;16484.258208380623;16519.91740041298;16589.91705041473;16611.252493637785;16629.085400302978;661.4840424468922;779.8089468080328;811.9391045671568;818.0674031603976;818.5497976113114;794.8847417124514;805.0540614777642;805.2189376304975;805.3195050182586;805.4402190477621;856.340617724355;858.935579831512;859.0593300336153;859.2352806002663;862.3101518784978;687.2508965227876;814.3851392198852;846.3609974469298;846.5640195299426;848.6350869126273;620.7144713431827;625.352759893508;625.5527297981964;625.6214989931071;625.7465726380823;14598.051126676732;14639.487617933428;14641.707165856624;14645;14661.047031942997;12173.817392739116;12628.368581570885;12740.617781466572;12756.80864787029;12976.15654982423;12540.531898416637;12981.935090324489;13041.478340866353;13064.477420903153;13104.80342794859;4436.201483732927;4456.77716114193;4462.085272519126;4463.129689710508;4557.794899229542;77860.99999999943;79334.38969893611;79406.60296698479;79474;79647.6017619914;10576.52405641748;10596.436561201674;10622.946885265525;10746.785064298827;10774.892251077388;19529.121189546506;19795.703064454046;19818.20727170926;19868.50328741793;19901.402957911287;8544.829103417896;8586.785330366665;8594.699185528476;8775.561221939006;8793.824123517621;4664.95335046652;4714.222153344728;4781.569658730731;4796.736179510091;4982.626303027215;3442.431998720183;3587.408077667156;3591.730620203495;3593.2633810068874;3595.424732042861;575.602834044508;634.6223996721943;658.5949640970864;676.090658064902;683.0539702512057;585.7247093865876;634.527277178505;636.8630744389978;640.5996252342286;683.2040672616446;9906.157976572073;9928;10007.649732259482;10031.29780915329;10342.689719308433;605.2978544887997;652.083822229768;658.275896513831;691.2914262880588;701.2566679319938;4752.405949256343;4783.354247176587;4834.758262086882;4940.481249468826;4952.207646776498;94438.52780736053;96439;98504;106642.46678766712;127527;92011.5399423012;92575.53712231395;93082.53458732799;94678;94770.52614736882;52937.47062529325;53608.46391536113;78235.60882195666;83734;84733.57633211794;9549.474778887228;10088.495575221357;10136.087752102347;10175.949120254352;10198.33710808795;36378.818105909304;44998.42505512291;45943;47100;47492.762536187096;69695;73739;74345.6282718583;74557.6272118636;75157.62421187859;12160.523281243533;12285.262884226975;12292.754144917231;12357.629271121881;13292.20246785196;14430.711385772436;14496.565103046925;14658.780118298448;15078.924605376902;15349.155796431303;10290.3825770454;14074.366653500821;14121.43514259418;14165.787513187104;14396.280185990869;13833.584992450242;14016.29918504091;14108.224047677066;14120.929395352958;14433.711325773636;13357.732845343233;13529.458821647024;13576.1854288743;13706.588802335946;14015.719685606027;10357.63748268807;10378.481075946323;10417.635382761568;10735.141188705042;11170.497327620114;11136.832947505633;11366.659000230007;11455.255408398427;11509.769804604028;11534.192606517463 23:47:25 INFO - PROCESS | 29266 | __end_tp_report 23:47:25 INFO - PROCESS | 29266 | __start_cc_report 23:47:25 INFO - PROCESS | 29266 | _x_x_mozilla_cycle_collect,1164 23:47:25 INFO - PROCESS | 29266 | __end_cc_report 23:47:25 INFO - PROCESS | 29266 | __startTimestamp1467096445305__endTimestamp 23:47:25 INFO - PROCESS | 29266 | 23:47:25 INFO - PROCESS | 29266 | ------- Summary: start ------- 23:47:25 INFO - PROCESS | 29266 | Number of tests: 6 23:47:25 INFO - PROCESS | 29266 | 23:47:25 INFO - PROCESS | 29266 | [#0] dojo.html Cycles:165 Average:5373.30 Median:5571.47 stddev:632.65 (11.4%) stddev-sans-first:608.31 23:47:25 INFO - PROCESS | 29266 | Values: 3073.2 3084.6 3109.2 3109.7 3144.8 5236.6 5332.7 5396.3 5399.2 5410.1 5346.0 5386.8 5422.9 5445.0 5455.7 4809.1 4817.1 4823.7 4914.7 5014.1 4915.1 4926.4 5154.1 5158.7 5169.5 4811.8 4844.1 4925.6 4931.7 4976.7 4827.0 4843.7 4844.7 4875.3 4904.5 4851.6 4925.7 4930.8 4931.2 4974.8 5264.1 5308.1 5375.8 5474.0 5475.6 5797.0 5836.0 5860.6 5893.7 5898.1 5902.0 5935.8 5937.6 5940.1 5943.6 5277.7 5409.4 5461.2 5558.7 5567.1 6021.8 6023.2 6023.7 6031.8 6038.0 5800.2 5801.8 5803.4 5807.6 5808.9 5634.2 5643.2 5667.7 5675.5 5849.8 5646.9 5685.6 5690.1 5691.1 5697.3 5656.2 5693.9 5704.4 5710.4 5712.7 4197.8 4200.3 4318.5 4363.7 4368.8 3961.0 3961.4 3965.7 3998.2 4006.2 5271.9 5526.2 5558.8 5571.5 5573.5 5771.6 5809.2 5823.9 5845.6 5872.9 5445.5 5448.7 5449.8 5450.3 5532.7 4645.5 4759.5 4827.9 4837.7 4886.1 5996.4 6051.3 6058.2 6060.2 6120.9 5815.4 5819.8 5821.0 5822.4 5825.8 5905.2 5962.1 6020.2 6036.8 6049.0 5746.4 5754.2 5755.7 5758.0 5808.7 5770.3 5812.0 5820.7 5828.9 5850.4 5529.8 5633.5 5665.1 5687.3 5706.3 5689.7 5690.7 5692.6 5698.7 5703.5 5545.7 5594.3 5610.3 5615.8 5621.8 5494.1 5503.1 5574.2 5629.8 5635.9 5442.9 5498.9 5501.4 5501.8 5517.3 23:47:25 INFO - PROCESS | 29266 | 23:47:25 INFO - PROCESS | 29266 | [#1] ext.html Cycles:180 Average:57105.14 Median:37133.41 stddev:113655.00 (306.1%) stddev-sans-first:113894.49 23:47:25 INFO - PROCESS | 29266 | Values: 541.7 597.6 603.8 607.3 612.7 29777.3 41438.0 42125.6 42138.0 42251.2 44302.3 44708.1 44717.6 44771.8 44828.8 8105.0 9061.2 9213.7 9219.6 9221.4 10642.3 11026.9 11030.6 11277.2 11307.3 10504.5 10550.4 10616.5 10620.2 10620.2 8401.6 10819.2 11021.5 11044.0 11144.5 10575.7 10955.3 10964.7 10971.7 10999.1 41760.6 42216.8 42310.0 42317.0 42394.4 3556.2 3605.8 3837.4 3939.3 4344.9 33648.2 33731.8 33770.8 33951.7 34154.1 3779.8 3792.3 3880.6 3920.4 3926.5 337847.0 341734.0 343185.0 343493.0 344438.0 34420.1 35155.3 35345.0 37152.8 60831.0 37618.2 37652.8 37734.6 37837.4 37856.6 15223.2 15275.5 15301.8 15311.9 15332.7 12607.9 12930.4 12934.5 13012.3 13458.2 8258.4 8450.0 8474.3 8536.4 8615.6 3271.4 3308.9 3330.3 3345.5 3364.3 996.5 1003.5 1032.6 1064.8 1151.5 32442.5 32567.0 32889.3 33083.3 33393.0 1120.1 1136.6 1167.0 1226.9 1249.9 17046.8 17140.2 17221.6 17257.6 17294.5 536788.0 537079.0 540345.0 542328.0 543579.0 58224.0 58229.7 58251.7 58286.1 58893.7 1273.1 1346.4 1395.0 1403.5 1412.0 31575.8 32390.0 32831.0 33191.5 33642.7 47487.3 47763.8 47862.3 47988.0 48078.0 372580.0 376552.0 377667.0 377914.0 378226.0 25389.0 27261.3 27737.6 28175.7 29508.8 47535.5 48484.8 48660.0 49716.3 49790.5 39430.5 40308.2 41347.6 41814.8 41821.0 40384.4 40492.4 41031.4 41635.0 41922.6 41004.2 41535.6 41733.0 42023.4 42527.8 35220.0 37114.0 37316.3 37583.6 37624.6 27791.3 28069.0 28400.7 28427.4 28618.7 23:47:25 INFO - PROCESS | 29266 | 23:47:25 INFO - PROCESS | 29266 | [#2] jquery.html Cycles:180 Average:27173.58 Median:2713.80 stddev:69354.60 (2555.6%) stddev-sans-first:69520.00 23:47:25 INFO - PROCESS | 29266 | Values: 391.9 393.6 395.6 399.3 417.8 14440.2 18129.3 18193.5 19761.1 19771.0 17679.6 18832.2 19692.6 19698.2 19721.8 5629.3 5650.7 5737.5 5772.0 5773.7 4186.1 5731.3 5748.4 5788.7 6216.7 6472.4 6554.6 6567.6 6568.6 6570.7 6309.4 6539.8 6548.7 6549.2 6553.5 5526.6 5778.1 5781.7 5782.5 5799.9 23951.4 23981.8 24153.3 24157.3 24644.6 2657.8 2769.8 2896.9 2940.8 3063.9 14149.2 14841.3 14942.4 15158.8 15319.3 1902.2 1955.1 1955.2 1973.7 1996.7 81931.6 82479.0 82566.6 83716.6 85780.6 10179.5 10437.4 10457.2 10502.5 10808.0 12817.4 13779.8 14735.2 14779.0 14891.6 7574.0 7918.6 8140.7 8216.6 8264.8 8303.4 8451.9 8455.3 8538.0 8571.4 6476.6 6571.9 6589.2 6635.4 6771.8 1890.0 1892.5 1952.0 1966.9 1999.5 882.3 954.5 959.9 968.7 1022.4 19339.5 20282.4 20373.6 20521.0 20642.5 1023.6 1037.3 1056.3 1065.6 1076.2 10648.9 11647.8 11718.9 11977.1 11999.3 322486.0 328622.0 466986.0 468383.9 469288.0 88272.0 94609.5 96736.5 99172.0 99519.0 1001.6 1044.1 1062.5 1069.5 1070.4 17699.3 17872.7 18073.3 18156.7 18305.9 30077.5 39831.0 40187.4 41142.6 42325.4 55462.4 56395.0 57718.0 57824.4 58227.7 8414.6 8685.9 8749.7 8762.3 8994.6 9989.1 13402.0 13454.2 13471.9 13615.9 11832.8 11932.6 11949.9 11956.0 12157.7 11612.4 11754.2 11866.0 12046.2 12064.8 11705.0 11855.5 12020.4 12159.8 12408.3 8690.7 8763.8 8816.8 9000.2 9092.4 11296.8 11967.0 12054.8 12133.9 12236.9 23:47:25 INFO - PROCESS | 29266 | 23:47:25 INFO - PROCESS | 29266 | [#3] mootools.html Cycles:180 Average:5813.53 Median:4799.14 stddev:3310.49 (69.0%) stddev-sans-first:3302.63 23:47:25 INFO - PROCESS | 29266 | Values: 1330.8 1481.9 1521.6 1627.7 1801.9 8394.7 8481.2 8520.1 8723.3 8768.7 8494.4 8673.1 8677.2 8679.1 8688.0 2038.4 2232.8 2304.5 2326.8 2334.5 2357.3 2359.9 2361.3 2361.4 2363.2 2290.2 2303.6 2303.6 2305.1 2329.6 1957.0 2210.7 2238.1 2281.9 2283.4 2290.8 2292.3 2309.7 2312.4 2312.8 8650.1 8737.7 8747.2 8749.2 8754.4 4993.5 5153.6 5156.2 5156.4 5164.0 3214.8 3312.7 3365.3 3366.8 3370.3 1040.0 1042.3 1053.2 1057.8 1061.0 12449.5 12473.8 12475.4 12480.6 12485.9 4606.1 4703.0 4796.2 4802.1 4808.9 10480.0 10483.4 10485.8 10486.5 10487.0 5244.7 5285.9 5287.2 5288.3 5291.8 4172.2 4624.7 4771.7 4830.9 4833.0 3929.4 3947.3 4053.9 4079.6 4084.0 1280.0 1282.3 1294.7 1298.4 1314.2 985.0 987.4 989.4 990.5 1007.5 6040.0 6183.0 6184.0 6354.0 6379.3 925.0 963.0 975.5 991.8 991.8 4152.9 4232.1 4257.3 4260.0 4260.6 10150.9 10420.3 10448.7 10454.9 10479.7 10256.1 11272.5 11318.3 11328.3 11340.0 8192.0 8291.1 8425.7 8483.7 8508.4 6010.3 6063.2 6125.1 6190.2 6206.6 10341.9 10390.9 10432.2 10438.2 10442.4 9879.0 10308.7 10314.4 10506.8 10596.6 7127.6 7313.9 7317.7 7844.9 8056.3 8068.9 8086.4 8375.5 8401.5 8590.2 7584.7 7661.9 7733.5 7970.4 7981.7 7532.2 7561.8 7646.4 7737.7 7752.4 7021.5 7034.8 7080.1 7080.5 7082.8 5114.7 5199.8 5219.2 5314.8 5324.9 5185.9 5217.8 5218.9 5222.2 5274.6 23:47:25 INFO - PROCESS | 29266 | 23:47:25 INFO - PROCESS | 29266 | [#4] prototype.html Cycles:180 Average:4575.03 Median:5121.35 stddev:1307.04 (25.5%) stddev-sans-first:1271.38 23:47:25 INFO - PROCESS | 29266 | Values: 335.5 335.7 346.4 346.6 348.1 5224.5 5334.8 5334.9 5396.5 5433.9 5388.7 5394.5 5394.6 5398.6 5404.5 4260.1 4262.5 4268.4 4281.8 4306.8 3993.8 4110.4 4234.6 4274.9 4280.4 4166.9 4172.3 4183.5 4197.2 4208.4 4171.5 4190.1 4191.1 4196.5 4204.3 3632.4 3759.3 3760.5 3768.5 3778.2 5223.7 5226.6 5231.3 5262.4 5264.6 4814.0 4825.4 4864.9 4872.0 4884.8 5415.5 5637.6 5669.1 5673.3 5678.3 4125.5 4126.3 4169.9 4176.8 4177.5 5781.7 5863.8 5864.8 5870.1 5902.5 4102.9 4248.4 4251.7 4280.4 4290.4 4282.9 4289.8 4294.7 4306.4 4320.2 4872.0 4878.8 4893.7 4895.1 4903.9 5401.0 5612.4 5702.1 5721.6 5739.5 1391.7 1426.3 1447.0 1460.9 1463.8 945.3 949.8 958.1 973.3 984.3 4887.8 4900.2 4909.9 4932.0 4944.2 5081.8 5126.4 5136.8 5145.8 5164.6 4628.2 4743.4 4792.0 4806.3 4843.7 2701.7 2709.8 2718.5 2726.6 2730.3 5164.6 5217.8 5288.3 5357.3 5360.6 4774.6 5147.7 5168.8 5206.7 5223.3 5221.5 5247.8 5255.8 5289.9 5303.5 5002.4 5060.1 5120.7 5122.0 5146.4 4707.9 5012.0 5072.3 5077.8 5092.3 5155.5 5259.6 5274.6 5275.0 5301.9 3813.9 3819.6 3869.7 3931.4 3932.8 4893.0 5139.4 5169.5 5586.4 5630.3 5686.8 5714.5 5751.8 5756.0 5767.4 5617.3 5673.2 5702.1 5716.6 5730.5 5415.7 5490.8 5510.8 5539.4 5578.2 5729.6 5729.9 5738.5 5745.9 5755.9 5338.6 5532.6 5541.9 5620.7 5632.8 23:47:25 INFO - PROCESS | 29266 | 23:47:25 INFO - PROCESS | 29266 | [#5] yui.html Cycles:180 Average:19738.49 Median:25250.40 stddev:27834.77 (110.2%) stddev-sans-first:27876.12 23:47:25 INFO - PROCESS | 29266 | Values: 693.5 695.1 715.2 727.1 736.4 13241.2 14087.4 14104.0 14299.9 14974.2 16484.3 16519.9 16589.9 16611.3 16629.1 661.5 779.8 811.9 818.1 818.5 794.9 805.1 805.2 805.3 805.4 856.3 858.9 859.1 859.2 862.3 687.3 814.4 846.4 846.6 848.6 620.7 625.4 625.6 625.6 625.7 14598.1 14639.5 14641.7 14645.0 14661.0 12173.8 12628.4 12740.6 12756.8 12976.2 12540.5 12981.9 13041.5 13064.5 13104.8 4436.2 4456.8 4462.1 4463.1 4557.8 77861.0 79334.4 79406.6 79474.0 79647.6 10576.5 10596.4 10622.9 10746.8 10774.9 19529.1 19795.7 19818.2 19868.5 19901.4 8544.8 8586.8 8594.7 8775.6 8793.8 4665.0 4714.2 4781.6 4796.7 4982.6 3442.4 3587.4 3591.7 3593.3 3595.4 575.6 634.6 658.6 676.1 683.1 585.7 634.5 636.9 640.6 683.2 9906.2 9928.0 10007.6 10031.3 10342.7 605.3 652.1 658.3 691.3 701.3 4752.4 4783.4 4834.8 4940.5 4952.2 94438.5 96439.0 98504.0 106642.5 127527.0 92011.5 92575.5 93082.5 94678.0 94770.5 52937.5 53608.5 78235.6 83734.0 84733.6 9549.5 10088.5 10136.1 10175.9 10198.3 36378.8 44998.4 45943.0 47100.0 47492.8 69695.0 73739.0 74345.6 74557.6 75157.6 12160.5 12285.3 12292.8 12357.6 13292.2 14430.7 14496.6 14658.8 15078.9 15349.2 10290.4 14074.4 14121.4 14165.8 14396.3 13833.6 14016.3 14108.2 14120.9 14433.7 13357.7 13529.5 13576.2 13706.6 14015.7 10357.6 10378.5 10417.6 10735.1 11170.5 11136.8 11366.7 11455.3 11509.8 11534.2 23:47:25 INFO - PROCESS | 29266 | -------- Summary: end -------- 23:47:25 INFO - PROCESS | 29266 | 23:47:26 INFO - TEST-INFO | 29266: exit 0 23:47:26 INFO - TEST-OK | dromaeo_css | took 1114617ms 23:47:26 INFO - TEST-START | kraken 23:47:26 INFO - operating with platform_type : linux_ 23:47:26 INFO - Initialising browser for kraken test... 23:47:26 INFO - TEST-INFO | started process 29444 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprqhbNC/profile http://localhost:57501/getInfo.html) 23:47:30 INFO - PROCESS | 29444 | __metrics Screen width/height:1600/1200 23:47:30 INFO - PROCESS | 29444 | colorDepth:24 23:47:30 INFO - PROCESS | 29444 | Browser inner width/height: 1024/697 23:47:30 INFO - PROCESS | 29444 | __metrics 23:47:31 INFO - PROCESS | 29444 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:47:32 INFO - TEST-INFO | 29444: exit 0 23:47:32 INFO - Browser initialized. 23:47:32 INFO - Running cycle 1/1 for kraken test... 23:47:32 INFO - TEST-INFO | started process 29523 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprqhbNC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:47:44 INFO - PROCESS | 29523 | RSS: Main: 173953024 23:47:44 INFO - PROCESS | 29523 | 23:47:46 INFO - PROCESS | 29523 | Cycle 1(1): loaded http://localhost:57501/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57501/tests/kraken/driver.html?testName=audio-beat-detection) 23:47:47 INFO - PROCESS | 29523 | RSS: Main: 231194624 23:47:47 INFO - PROCESS | 29523 | 23:47:50 INFO - PROCESS | 29523 | Cycle 1(1): loaded http://localhost:57501/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57501/tests/kraken/driver.html?testName=audio-dft) 23:47:50 INFO - PROCESS | 29523 | RSS: Main: 215470080 23:47:50 INFO - PROCESS | 29523 | 23:47:53 INFO - PROCESS | 29523 | Cycle 1(1): loaded http://localhost:57501/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57501/tests/kraken/driver.html?testName=audio-fft) 23:47:54 INFO - PROCESS | 29523 | RSS: Main: 285962240 23:47:54 INFO - PROCESS | 29523 | 23:47:56 INFO - PROCESS | 29523 | Cycle 1(1): loaded http://localhost:57501/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57501/tests/kraken/driver.html?testName=audio-oscillator) 23:47:57 INFO - PROCESS | 29523 | RSS: Main: 196898816 23:47:57 INFO - PROCESS | 29523 | 23:47:59 INFO - PROCESS | 29523 | Cycle 1(1): loaded http://localhost:57501/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57501/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:47:59 INFO - PROCESS | 29523 | RSS: Main: 201297920 23:47:59 INFO - PROCESS | 29523 | 23:48:02 INFO - PROCESS | 29523 | Cycle 1(1): loaded http://localhost:57501/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57501/tests/kraken/driver.html?testName=imaging-darkroom) 23:48:03 INFO - PROCESS | 29523 | RSS: Main: 236457984 23:48:03 INFO - PROCESS | 29523 | 23:48:06 INFO - PROCESS | 29523 | Cycle 1(1): loaded http://localhost:57501/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57501/tests/kraken/driver.html?testName=imaging-desaturate) 23:48:07 INFO - PROCESS | 29523 | RSS: Main: 237948928 23:48:07 INFO - PROCESS | 29523 | 23:48:10 INFO - PROCESS | 29523 | Cycle 1(1): loaded http://localhost:57501/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57501/tests/kraken/driver.html?testName=json-parse-financial) 23:48:11 INFO - PROCESS | 29523 | RSS: Main: 237363200 23:48:11 INFO - PROCESS | 29523 | 23:48:12 INFO - PROCESS | 29523 | Cycle 1(1): loaded http://localhost:57501/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57501/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:48:13 INFO - PROCESS | 29523 | RSS: Main: 223522816 23:48:13 INFO - PROCESS | 29523 | 23:48:14 INFO - PROCESS | 29523 | Cycle 1(1): loaded http://localhost:57501/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57501/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:48:15 INFO - PROCESS | 29523 | RSS: Main: 227913728 23:48:15 INFO - PROCESS | 29523 | 23:48:17 INFO - PROCESS | 29523 | Cycle 1(1): loaded http://localhost:57501/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57501/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:48:18 INFO - PROCESS | 29523 | RSS: Main: 236212224 23:48:18 INFO - PROCESS | 29523 | 23:48:20 INFO - PROCESS | 29523 | Cycle 1(1): loaded http://localhost:57501/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57501/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:48:21 INFO - PROCESS | 29523 | RSS: Main: 236867584 23:48:21 INFO - PROCESS | 29523 | 23:48:24 INFO - PROCESS | 29523 | Cycle 1(1): loaded http://localhost:57501/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57501/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:48:24 INFO - PROCESS | 29523 | RSS: Main: 236384256 23:48:24 INFO - PROCESS | 29523 | 23:48:26 INFO - PROCESS | 29523 | Cycle 1(1): loaded http://localhost:57501/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57501/tests/kraken/driver.html?testName=ai-astar) 23:48:26 INFO - PROCESS | 29523 | RSS: Main: 278999040 23:48:26 INFO - PROCESS | 29523 | 23:48:26 INFO - PROCESS | 29523 | __start_tp_report 23:48:26 INFO - PROCESS | 29523 | _x_x_mozilla_page_load 23:48:26 INFO - PROCESS | 29523 | _x_x_mozilla_page_load_details 23:48:26 INFO - PROCESS | 29523 | |i|pagename|runs| 23:48:26 INFO - PROCESS | 29523 | |0;ai-astar;103;113;109;110;111;110;108;109;109;109 23:48:26 INFO - PROCESS | 29523 | |1;audio-beat-detection;151;126;127;202;127;126;159;138;156;126 23:48:26 INFO - PROCESS | 29523 | |2;audio-dft;160;160;160;160;161;160;161;162;162;162 23:48:26 INFO - PROCESS | 29523 | |3;audio-fft;74;76;75;106;74;77;75;75;108;74 23:48:26 INFO - PROCESS | 29523 | |4;audio-oscillator;85;85;124;86;86;86;122;86;87;137 23:48:26 INFO - PROCESS | 29523 | |5;imaging-gaussian-blur;117;116;116;117;117;117;117;117;119;116 23:48:26 INFO - PROCESS | 29523 | |6;imaging-darkroom;145;146;145;146;146;146;146;146;146;145 23:48:26 INFO - PROCESS | 29523 | |7;imaging-desaturate;95;95;95;95;95;95;95;96;96;95 23:48:26 INFO - PROCESS | 29523 | |8;json-parse-financial;53;54;55;54;54;54;55;55;53;58 23:48:26 INFO - PROCESS | 29523 | |9;json-stringify-tinderbox;62;62;62;62;62;62;62;62;62;63 23:48:26 INFO - PROCESS | 29523 | |10;stanford-crypto-aes;81;85;78;77;79;80;80;85;89;81 23:48:26 INFO - PROCESS | 29523 | |11;stanford-crypto-ccm;122;123;123;125;129;123;118;121;134;123 23:48:26 INFO - PROCESS | 29523 | |12;stanford-crypto-pbkdf2;163;160;160;174;161;159;159;159;162;156 23:48:26 INFO - PROCESS | 29523 | |13;stanford-crypto-sha256-iterative;67;64;65;64;63;63;64;63;63;65 23:48:26 INFO - PROCESS | 29523 | __end_tp_report 23:48:26 INFO - PROCESS | 29523 | __start_cc_report 23:48:26 INFO - PROCESS | 29523 | _x_x_mozilla_cycle_collect,1607 23:48:26 INFO - PROCESS | 29523 | __end_cc_report 23:48:26 INFO - PROCESS | 29523 | __startTimestamp1467096506626__endTimestamp 23:48:26 INFO - PROCESS | 29523 | 23:48:26 INFO - PROCESS | 29523 | ------- Summary: start ------- 23:48:26 INFO - PROCESS | 29523 | Number of tests: 14 23:48:26 INFO - PROCESS | 29523 | 23:48:26 INFO - PROCESS | 29523 | [#0] ai-astar Cycles:10 Average:109.10 Median:109.50 stddev:2.56 (2.3%) stddev-sans-first:1.48 23:48:26 INFO - PROCESS | 29523 | Values: 103.0 113.0 109.0 110.0 111.0 110.0 108.0 109.0 109.0 109.0 23:48:26 INFO - PROCESS | 29523 | 23:48:26 INFO - PROCESS | 29523 | [#1] audio-beat-detection Cycles:10 Average:143.80 Median:144.50 stddev:24.42 (16.9%) stddev-sans-first:25.76 23:48:26 INFO - PROCESS | 29523 | Values: 151.0 126.0 127.0 202.0 127.0 126.0 159.0 138.0 156.0 126.0 23:48:26 INFO - PROCESS | 29523 | 23:48:26 INFO - PROCESS | 29523 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 23:48:26 INFO - PROCESS | 29523 | Values: 160.0 160.0 160.0 160.0 161.0 160.0 161.0 162.0 162.0 162.0 23:48:26 INFO - PROCESS | 29523 | 23:48:26 INFO - PROCESS | 29523 | [#3] audio-fft Cycles:10 Average:81.40 Median:75.00 stddev:13.53 (18.0%) stddev-sans-first:14.09 23:48:26 INFO - PROCESS | 29523 | Values: 74.0 76.0 75.0 106.0 74.0 77.0 75.0 75.0 108.0 74.0 23:48:26 INFO - PROCESS | 29523 | 23:48:26 INFO - PROCESS | 29523 | [#4] audio-oscillator Cycles:10 Average:98.40 Median:86.00 stddev:20.57 (23.9%) stddev-sans-first:21.23 23:48:26 INFO - PROCESS | 29523 | Values: 85.0 85.0 124.0 86.0 86.0 86.0 122.0 86.0 87.0 137.0 23:48:26 INFO - PROCESS | 29523 | 23:48:26 INFO - PROCESS | 29523 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.88 (0.7%) stddev-sans-first:0.93 23:48:26 INFO - PROCESS | 29523 | Values: 117.0 116.0 116.0 117.0 117.0 117.0 117.0 117.0 119.0 116.0 23:48:26 INFO - PROCESS | 29523 | 23:48:26 INFO - PROCESS | 29523 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 23:48:26 INFO - PROCESS | 29523 | Values: 145.0 146.0 145.0 146.0 146.0 146.0 146.0 146.0 146.0 145.0 23:48:26 INFO - PROCESS | 29523 | 23:48:26 INFO - PROCESS | 29523 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 23:48:26 INFO - PROCESS | 29523 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 96.0 95.0 23:48:26 INFO - PROCESS | 29523 | 23:48:26 INFO - PROCESS | 29523 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:54.50 stddev:1.43 (2.6%) stddev-sans-first:1.41 23:48:26 INFO - PROCESS | 29523 | Values: 53.0 54.0 55.0 54.0 54.0 54.0 55.0 55.0 53.0 58.0 23:48:26 INFO - PROCESS | 29523 | 23:48:26 INFO - PROCESS | 29523 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:62.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 23:48:26 INFO - PROCESS | 29523 | Values: 62.0 62.0 62.0 62.0 62.0 62.0 62.0 62.0 62.0 63.0 23:48:26 INFO - PROCESS | 29523 | 23:48:26 INFO - PROCESS | 29523 | [#10] stanford-crypto-aes Cycles:10 Average:81.50 Median:81.00 stddev:3.72 (4.6%) stddev-sans-first:3.94 23:48:26 INFO - PROCESS | 29523 | Values: 81.0 85.0 78.0 77.0 79.0 80.0 80.0 85.0 89.0 81.0 23:48:26 INFO - PROCESS | 29523 | 23:48:26 INFO - PROCESS | 29523 | [#11] stanford-crypto-ccm Cycles:10 Average:124.10 Median:123.00 stddev:4.46 (3.6%) stddev-sans-first:4.66 23:48:26 INFO - PROCESS | 29523 | Values: 122.0 123.0 123.0 125.0 129.0 123.0 118.0 121.0 134.0 123.0 23:48:26 INFO - PROCESS | 29523 | 23:48:26 INFO - PROCESS | 29523 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.30 Median:160.50 stddev:4.85 (3.0%) stddev-sans-first:5.11 23:48:26 INFO - PROCESS | 29523 | Values: 163.0 160.0 160.0 174.0 161.0 159.0 159.0 159.0 162.0 156.0 23:48:26 INFO - PROCESS | 29523 | 23:48:26 INFO - PROCESS | 29523 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:64.00 stddev:1.29 (2.0%) stddev-sans-first:0.83 23:48:26 INFO - PROCESS | 29523 | Values: 67.0 64.0 65.0 64.0 63.0 63.0 64.0 63.0 63.0 65.0 23:48:26 INFO - PROCESS | 29523 | -------- Summary: end -------- 23:48:26 INFO - PROCESS | 29523 | 23:48:27 INFO - TEST-INFO | 29523: exit 0 23:48:27 INFO - TEST-OK | kraken | took 61558ms 23:48:27 INFO - SUITE-END | took 1176s 23:48:27 INFO - Completed test suite (00:19:36) 23:48:27 INFO - javascript benchmark 23:48:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3073.154882407338, 3084.568160457536, 3109.1760683418884, 3109.720125188733, 3144.8113113213208, 5236.581073514119, 5332.733363331832, 5396.325459317581, 5399.244105825188, 5410.05324068288, 5346.03771321162, 5386.8383948481605, 5422.8915421691645, 5445.047116754572, 5455.699936503491, 4809.062232864593, 4817.0847538967655, 4823.686460380081, 4914.655974081816, 5014.147594908874, 4915.090708218991, 4926.359573255471, 5154.149565321736, 5158.664686795365, 5169.483051694838, 4811.759412029384, 4844.10384078943, 4925.630577706686, 4931.7040977541465, 4976.701397916118, 4827.0, 4843.70937743733, 4844.66087373884, 4875.34182885313, 4904.509549045102, 4851.611871050308, 4925.655204135676, 4930.827421040246, 4931.21100623902, 4974.825881094181, 5264.078786212436, 5308.097623404031, 5375.784968601251, 5474.0, 5475.589330800206, 5796.97101514494, 5836.0, 5860.648361098348, 5893.7053147342895, 5898.115282707586, 5901.970490147564, 5935.821925342247, 5937.584369094165, 5940.108983652443, 5943.55423343251, 5277.736113194325, 5409.350877894678, 5461.208124821958, 5558.666480011172, 5567.081431563787, 6021.789237376671, 6023.156758053876, 6023.728932198062, 6031.849203769941, 6038.0, 5800.187973683687, 5801.796937107181, 5803.390643982406, 5807.622504537276, 5808.88382232353, 5634.211210430543, 5643.181738647955, 5667.659940403589, 5675.5459563234845, 5849.766009359663, 5646.943530564724, 5685.573581981368, 5690.146478028246, 5691.0609749391715, 5697.316322041299, 5656.151577263443, 5693.886122277531, 5704.372519022904, 5710.428957104339, 5712.65724056558, 4197.790110494463, 4200.264953633102, 4318.5033721121845, 4363.650907927422, 4368.7815609219415, 3960.9801950990636, 3961.445397644332, 3965.7025723070883, 3998.1603863189057, 4006.238814625212, 5271.894562108736, 5526.198701188305, 5558.77764889408, 5571.470710282517, 5573.498385145356, 5771.567132465082, 5809.244798176294, 5823.88352232953, 5845.590808643439, 5872.853178670567, 5445.50990410865, 5448.700321482356, 5449.782008719686, 5450.291462109901, 5532.695701736363, 4645.5354464553275, 4759.4764575896625, 4827.879303017453, 4837.6613637045775, 4886.144924638174, 5996.430339117776, 6051.273847138284, 6058.1518587397795, 6060.151578778975, 6120.877582448326, 5815.447532484407, 5819.79630712923, 5821.0, 5822.446867547746, 5825.825225243249, 5905.202797622266, 5962.105684147413, 6020.157177994996, 6036.788712395045, 6049.032154855202, 5746.396628353963, 5754.223179870663, 5755.71221438918, 5758.021136406737, 5808.651480911159, 5770.278715160605, 5811.970940145272, 5820.70896455184, 5828.854278642898, 5850.385709500441, 5529.778808847601, 5633.492985631313, 5665.12190610456, 5687.3459552152035, 5706.3152421709665, 5689.6586204827845, 5690.658560486384, 5692.6299790513485, 5698.743556540048, 5703.486686198262, 5545.694986775766, 5594.328680558359, 5610.298712660918, 5615.803446879339, 5621.80323688669, 5494.148406996922, 5503.147012213113, 5574.163875418558, 5629.831105066854, 5635.915461268002, 5442.945570544244, 5498.945010550004, 5501.3948465667945, 5501.834944951658, 5517.2551705519245], "value": 5329.294356240419, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [541.7291354322839, 597.575721237921, 603.7524614907886, 607.3380015782795, 612.6691586543266, 29777.255568610795, 41438.0, 42125.57874421259, 42138.0, 42251.15497690052, 44302.33546496805, 44708.105837883224, 44717.55282447174, 44771.77614111933, 44828.77585612075, 8105.0, 9061.229795467376, 9213.723588292361, 9219.585118669676, 9221.446713197196, 10642.255042147053, 11026.944865275662, 11030.613928512505, 11277.154213433983, 11307.321560706343, 10504.474776261195, 10550.366977981308, 10616.522256498438, 10620.150387968944, 10620.203484738637, 8401.621927013239, 10819.242653014293, 11021.504032318524, 11044.0, 11144.498497567629, 10575.73560660982, 10955.34267943926, 10964.725881852937, 10971.725706857313, 10999.120070394429, 41760.58239417597, 42216.78891605553, 42310.0, 42317.0, 42394.364084538756, 3556.199855032623, 3605.801680907549, 3837.424386342043, 3939.3106206413763, 4344.913101737948, 33648.15879603005, 33731.83134084314, 33770.83114584412, 33951.6604833951, 34154.14614634629, 3779.773213607165, 3792.3363411402893, 3880.611938806125, 3920.3727403615285, 3926.548446928611, 337847.0, 341734.0, 343185.0, 343493.0, 344438.0, 34420.13949651279, 35155.296894062485, 35345.0, 37152.81423592865, 60831.0, 37618.24763504715, 37652.81173594115, 37734.62265377366, 37837.432438513446, 37856.62143378586, 15223.238838058274, 15275.541733748005, 15301.770473442908, 15311.928165028341, 15332.693346133015, 12607.873921260854, 12930.418131184118, 12934.482620695255, 13012.28432436225, 13458.192508449525, 8258.42191046621, 8450.0, 8474.32205423577, 8536.402451828313, 8615.61229744663, 3271.3947919634943, 3308.93382132356, 3330.283988942389, 3345.531625572422, 3364.2598628301735, 996.5166894056376, 1003.4581326083979, 1032.5560009196117, 1064.755106325534, 1151.45305979659, 32442.513362299585, 32567.0, 32889.3422131556, 33083.3383332332, 33393.0, 1120.1430905357408, 1136.6476392318407, 1166.9708257293635, 1226.9141160118706, 1249.9437525311382, 17046.82953170477, 17140.228689708994, 17221.56946076358, 17257.56856078608, 17294.481165565052, 536788.0, 537079.0, 540345.0, 542328.0, 543579.0, 58224.0, 58229.70885145632, 58251.70874145687, 58286.125708114414, 58893.70553147207, 1273.1024627637494, 1346.4479563379164, 1394.9930250348887, 1403.5298175111452, 1411.9929400353344, 31575.84212078925, 32390.0, 32831.0, 33191.502127467626, 33642.663573363956, 47487.287690683974, 47763.76118119387, 47862.28206576835, 47988.0, 48078.0, 372580.0, 376552.0, 377667.0, 377914.0, 378226.0, 25389.0, 27261.31846703769, 27737.583936241375, 28175.71824281731, 29508.81964721387, 47535.52464475311, 48484.757576211894, 48660.0, 49716.25425618691, 49790.50209498004, 39430.46986236309, 40308.1938361237, 41347.58652413437, 41814.79092604518, 41820.95447613834, 40384.3942340871, 40492.39261411022, 41031.38452923268, 41635.0, 41922.58077419187, 41004.179916400906, 41535.58464415317, 41733.0, 42023.3696494559, 42527.787361063, 35220.0, 37114.0, 37316.32076556616, 37583.62416375801, 37624.62375376211, 27791.30521736973, 28069.0, 28400.715992840633, 28427.43145137127, 28618.71381286244], "value": 19128.090540961464, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.9059425737823, 393.6181903553552, 395.62020460358065, 399.2693371130831, 417.7890165466439, 14440.205788681644, 18129.274829006823, 18193.454196374096, 19761.110750016247, 19771.0, 17679.5580110497, 18832.246710131614, 19692.606147877035, 19698.212071517122, 19721.802781972176, 5629.296337957755, 5650.6892120933335, 5737.540996720273, 5772.047612144012, 5773.711314434283, 4186.099988502471, 5731.340895796974, 5748.3676795552665, 5788.739506722187, 6216.689165541705, 6472.385123413292, 6554.573952693084, 6567.6059436433725, 6568.573042752229, 6570.671466426684, 6309.400606942356, 6539.803805885831, 6548.738050477975, 6549.246836613803, 6553.50848686348, 5526.640768350045, 5778.104393818965, 5781.682007489587, 5782.508486778629, 5799.9130013049835, 23951.401214969592, 23981.760182398128, 24153.2754017378, 24157.275281741575, 24644.630330545056, 2657.7873770098345, 2769.8061135720304, 2896.942061158786, 2940.750181172993, 3063.908082757521, 14149.151050936976, 14841.257937103102, 14942.402303907831, 15158.772618410732, 15319.331847216601, 1902.2486117983458, 1955.0811118774154, 1955.1983686688532, 1973.6940774180025, 1996.7304413904083, 81931.5903420485, 82479.0, 82566.58716706438, 83716.58141709314, 85780.57109714474, 10179.491025448699, 10437.373757574569, 10457.215708821868, 10502.527386267637, 10808.0, 12817.423215955117, 13779.793303100461, 14735.189564574051, 14779.0, 14891.553253402415, 7574.0, 7918.564478953597, 8140.656791629315, 8216.589170541449, 8264.83470330602, 8303.418760686814, 8451.915480845235, 8455.323574114056, 8538.0, 8571.357148213785, 6476.643784591893, 6571.934280657228, 6589.242237142694, 6635.4359879410795, 6771.830704232433, 1889.9622007559772, 1892.4984879007075, 1951.9804801951798, 1966.891820949833, 1999.5101200206043, 882.2589025218847, 954.5036580977853, 959.8752162219007, 968.67517956904, 1022.3865680591585, 19339.51651208731, 20282.391528254175, 20373.592528149355, 20521.0, 20642.483937901372, 1023.6417253961054, 1037.2946396450486, 1056.271172890703, 1065.616378103882, 1076.2143635146388, 10648.946755266328, 11647.76704465906, 11718.94140529309, 11977.101717371232, 11999.280043197436, 322486.0, 328622.0, 466986.0, 468383.8969661763, 469288.0, 88272.0, 94609.52695236617, 96736.51631741796, 99172.0, 99519.0, 1001.6193846338344, 1044.1234399230073, 1062.5484169228048, 1069.5133714160038, 1070.4059247117768, 17699.29202831924, 17872.731909021637, 18073.277068917097, 18156.72764908501, 18305.90164590129, 30077.54883676703, 39831.0, 40187.3971890416, 41142.58857411388, 42325.36511952262, 55462.44537554573, 56395.0, 57718.0, 57824.42175578191, 58227.70886145712, 8414.6213420395, 8685.8697119542, 8749.65001399937, 8762.3438770878, 8994.59524321394, 9989.051040151175, 13402.0, 13454.192748435125, 13471.865281347062, 13615.931920340336, 11832.76334473323, 11932.642020739391, 11949.880501195224, 11956.0, 12157.696057598278, 11612.419379030845, 11754.23597466162, 11866.0, 12046.156769026435, 12064.819027714415, 11705.0, 11855.525778968744, 12020.398980050788, 12159.75680486403, 12408.255504669749, 8690.695825646326, 8763.780905477159, 8816.779580510538, 9000.234980026771, 9092.408993415407, 11296.830547541958, 11967.0, 12054.75890482203, 12133.939330303292, 12236.938815305866], "value": 9539.0958054225, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1330.826992490976, 1481.9110853348795, 1521.5815650696056, 1627.6744651069782, 1801.9279228830846, 8394.748157555276, 8481.194286542775, 8520.105388934166, 8723.258523025535, 8768.736937891874, 8494.40539162259, 8673.13268673134, 8677.219050285474, 8679.132086791333, 8687.956560217206, 2038.4496186029762, 2232.821374290061, 2304.5275718477756, 2326.813854891613, 2334.5097529518735, 2357.304595144428, 2359.8702071386065, 2361.315218586608, 2361.4332560185517, 2363.2319496163814, 2290.1640901071155, 2303.608386574286, 2303.6199027160496, 2305.13557415969, 2329.580675478413, 1956.9902150489227, 2210.7457642371173, 2238.0712004518105, 2281.942951426211, 2283.3834864586634, 2290.7823756743082, 2292.289390289016, 2309.6766452696634, 2312.4450131968206, 2312.7687231276905, 8650.134986501362, 8737.694180703646, 8747.16901894319, 8749.212570868589, 8754.3871928965, 4993.475685053054, 5153.587712982952, 5156.20078887773, 5156.407013193456, 5164.018836421068, 3214.791038582508, 3312.685294896981, 3365.3437579671972, 3366.7979921204806, 3370.258543120534, 1039.9584016639326, 1042.306865934153, 1053.1627356251797, 1057.751428414322, 1060.957561697539, 12449.502019919193, 12473.75052498945, 12475.376231188404, 12480.563180288646, 12485.93757031218, 4606.147862645421, 4703.0, 4796.208625576741, 4802.063597598435, 4808.855734327975, 10480.0, 10483.37099774016, 10485.842712359321, 10486.528106235237, 10487.0, 5244.685318880803, 5285.867853303698, 5287.23335116406, 5288.312519372457, 5291.761870715827, 4172.228137794457, 4624.722516649012, 4771.6898401603785, 4830.903381932341, 4832.975835120872, 3929.351656976567, 3947.3092208863336, 4053.8783836484954, 4079.5920407958965, 4084.0402505411203, 1280.0, 1282.3460035381836, 1294.7216348485122, 1298.4286913758106, 1314.1720715949082, 984.9556769945226, 987.4470296633908, 989.4459102902398, 990.4700984973005, 1007.5466040281902, 6039.969800150971, 6183.0, 6184.0, 6353.96823015882, 6379.266384365857, 925.0425809287356, 962.9807403851884, 975.4732444479902, 991.7768502086973, 991.7867658453467, 4152.896177595524, 4232.111256636141, 4257.297545904891, 4259.97870010648, 4260.6378457830815, 10150.898491015143, 10420.270581059252, 10448.68653940383, 10454.947725261325, 10479.738006549745, 10256.12822910061, 11272.54909803615, 11318.32090074598, 11328.32030078198, 11340.0, 8191.959040204761, 8291.129431409736, 8425.70510032146, 8483.74548763538, 8508.361872859561, 6010.3088144863905, 6063.242094738158, 6125.081237814185, 6190.1952746142715, 6206.565540412128, 10341.896581034094, 10390.948045260027, 10432.165426765992, 10438.164946804085, 10442.425666588411, 9879.0, 10308.742281443025, 10314.43270620123, 10506.842397363893, 10596.576136954744, 7127.607981561064, 7313.926860731324, 7317.707291708272, 7844.882326764989, 8056.315213206943, 8068.878966815385, 8086.433949623705, 8375.497470151811, 8401.453905496122, 8590.226879580869, 7584.734534291273, 7661.923380766121, 7733.4973226740085, 7970.4420690553425, 7981.680732770857, 7532.17146113938, 7561.848763024819, 7646.350060244941, 7737.651805668871, 7752.379809615104, 7021.508494405342, 7034.753783617549, 7080.079589653312, 7080.539764915264, 7082.752103676346, 5114.667546609458, 5199.76601052946, 5219.164933610604, 5314.813981510629, 5324.920126198032, 5185.948140518546, 5217.765200566059, 5218.921716174184, 5222.166302078512, 5274.630775845654], "value": 4644.518901433908, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.53882273523703, 335.68781033638714, 346.35404969731457, 346.589291689348, 348.1470397526062, 5224.4775522447735, 5334.813281535147, 5334.89330213396, 5396.487333703297, 5433.945660543393, 5388.7305634718205, 5394.487523685253, 5394.595405344605, 5398.595105367103, 5404.4595540445935, 4260.147970405916, 4262.52942734756, 4268.40242366069, 4281.8287268509375, 4306.784660766949, 3993.800309984489, 4110.362893751459, 4234.555371685975, 4274.935875961863, 4280.400743895857, 4166.854160104397, 4172.33242681171, 4183.518895327046, 4197.202531519017, 4208.431861698662, 4171.478565179368, 4190.120074784299, 4191.057012172268, 4196.475440569929, 4204.327307630764, 3632.4188129899353, 3759.3045286622064, 3760.52993375828, 3768.4535742317485, 3778.1688028633657, 5223.660462069954, 5226.5818734501145, 5231.3199284092825, 5262.421133675292, 5264.578833693296, 4814.013127308894, 4825.445073816521, 4864.878378040577, 4871.975640121812, 4884.780184891689, 5415.458454154593, 5637.605367624268, 5669.0646043402785, 5673.319201695782, 5678.290213723285, 4125.546189919107, 4126.349460215908, 4169.937450938239, 4176.770277634729, 4177.4778152730905, 5781.682007489587, 5863.824085277449, 5864.797716468114, 5870.11948207768, 5902.527797776168, 4102.938455923164, 4248.428081609763, 4251.681123915719, 4280.422143010654, 4290.377895205178, 4282.892927676833, 4289.849855255114, 4294.677899157576, 4306.354046892991, 4320.2223599751715, 4871.975640121776, 4878.829240976549, 4893.65104749715, 4895.143349913751, 4903.926441103386, 5401.0, 5612.410696876851, 5702.059160238514, 5721.5708821838525, 5739.483446489836, 1391.7007843313736, 1426.272600973511, 1446.9855301447062, 1460.9269536523343, 1463.7804329350683, 945.2768631996528, 949.8100379924043, 958.1424624960761, 973.3478569358547, 984.3454103021554, 4887.780049897763, 4900.157908413112, 4909.852704418873, 4931.950680493221, 4944.208926571731, 5081.8475445736685, 5126.436092029874, 5136.794528218829, 5145.819896303611, 5164.612654050961, 4628.190066738307, 4743.359646447754, 4791.952080479151, 4806.303086052572, 4843.660943733905, 2701.689305729827, 2709.7967652425755, 2718.537848565709, 2726.577380506025, 2730.276476733667, 5164.56101231392, 5217.7912883485, 5288.312519372457, 5357.276767636319, 5360.571154307646, 4774.594159496482, 5147.6911385317, 5168.793248270027, 5206.71363075027, 5223.347081614798, 5221.530062294411, 5247.790088396498, 5255.842324730264, 5289.920651190235, 5303.46199602111, 5002.424236365533, 5060.127752731366, 5120.718360490208, 5121.974390128025, 5146.356705411824, 4707.8823029423165, 5011.974940125276, 5072.340595722532, 5077.77150028257, 5092.337996060489, 5155.50490357792, 5259.552938000313, 5274.602230408868, 5274.97362513185, 5301.867453313698, 3813.8855834325013, 3819.618038196158, 3869.671077958405, 3931.4495970564717, 3932.80335983194, 4892.996935628116, 5139.434662187231, 5169.518631057039, 5586.38549759518, 5630.268065151504, 5686.77252909879, 5714.457126572969, 5751.798687046101, 5755.971220143872, 5767.423257674199, 5617.297837770278, 5673.234113394639, 5702.087665973424, 5716.628419152742, 5730.541556675539, 5415.675059496442, 5490.752916118704, 5510.81517473745, 5539.418361072029, 5578.163275508706, 5729.570282228766, 5729.914051289151, 5738.483536481736, 5745.856353591026, 5755.856103597276, 5338.572914166935, 5532.612717109763, 5541.92177933275, 5620.718964051863, 5632.831015069554], "value": 4164.799531145863, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [693.5145398221244, 695.0859619600226, 715.1632589869855, 727.1456039153993, 736.3593673504053, 13241.205527668346, 14087.366068526915, 14104.0, 14299.857001429982, 14974.176420296879, 16484.258208380623, 16519.91740041298, 16589.91705041473, 16611.252493637785, 16629.085400302978, 661.4840424468922, 779.8089468080328, 811.9391045671568, 818.0674031603976, 818.5497976113114, 794.8847417124514, 805.0540614777642, 805.2189376304975, 805.3195050182586, 805.4402190477621, 856.340617724355, 858.935579831512, 859.0593300336153, 859.2352806002663, 862.3101518784978, 687.2508965227876, 814.3851392198852, 846.3609974469298, 846.5640195299426, 848.6350869126273, 620.7144713431827, 625.352759893508, 625.5527297981964, 625.6214989931071, 625.7465726380823, 14598.051126676732, 14639.487617933428, 14641.707165856624, 14645.0, 14661.047031942997, 12173.817392739116, 12628.368581570885, 12740.617781466572, 12756.80864787029, 12976.15654982423, 12540.531898416637, 12981.935090324489, 13041.478340866353, 13064.477420903153, 13104.80342794859, 4436.201483732927, 4456.77716114193, 4462.085272519126, 4463.129689710508, 4557.794899229542, 77860.99999999943, 79334.38969893611, 79406.60296698479, 79474.0, 79647.6017619914, 10576.52405641748, 10596.436561201674, 10622.946885265525, 10746.785064298827, 10774.892251077388, 19529.121189546506, 19795.703064454046, 19818.20727170926, 19868.50328741793, 19901.402957911287, 8544.829103417896, 8586.785330366665, 8594.699185528476, 8775.561221939006, 8793.824123517621, 4664.95335046652, 4714.222153344728, 4781.569658730731, 4796.736179510091, 4982.626303027215, 3442.431998720183, 3587.408077667156, 3591.730620203495, 3593.2633810068874, 3595.424732042861, 575.602834044508, 634.6223996721943, 658.5949640970864, 676.090658064902, 683.0539702512057, 585.7247093865876, 634.527277178505, 636.8630744389978, 640.5996252342286, 683.2040672616446, 9906.157976572073, 9928.0, 10007.649732259482, 10031.29780915329, 10342.689719308433, 605.2978544887997, 652.083822229768, 658.275896513831, 691.2914262880588, 701.2566679319938, 4752.405949256343, 4783.354247176587, 4834.758262086882, 4940.481249468826, 4952.207646776498, 94438.52780736053, 96439.0, 98504.0, 106642.46678766712, 127527.0, 92011.5399423012, 92575.53712231395, 93082.53458732799, 94678.0, 94770.52614736882, 52937.47062529325, 53608.46391536113, 78235.60882195666, 83734.0, 84733.57633211794, 9549.474778887228, 10088.495575221357, 10136.087752102347, 10175.949120254352, 10198.33710808795, 36378.818105909304, 44998.42505512291, 45943.0, 47100.0, 47492.762536187096, 69695.0, 73739.0, 74345.6282718583, 74557.6272118636, 75157.62421187859, 12160.523281243533, 12285.262884226975, 12292.754144917231, 12357.629271121881, 13292.20246785196, 14430.711385772436, 14496.565103046925, 14658.780118298448, 15078.924605376902, 15349.155796431303, 10290.3825770454, 14074.366653500821, 14121.43514259418, 14165.787513187104, 14396.280185990869, 13833.584992450242, 14016.29918504091, 14108.224047677066, 14120.929395352958, 14433.711325773636, 13357.732845343233, 13529.458821647024, 13576.1854288743, 13706.588802335946, 14015.719685606027, 10357.63748268807, 10378.481075946323, 10417.635382761568, 10735.141188705042, 11170.497327620114, 11136.832947505633, 11366.659000230007, 11455.255408398427, 11509.769804604028, 11534.192606517463], "value": 7413.242254513802, "unit": "score"}], "name": "dromaeo_css", "value": 7201.218127433825, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 109.0, 110.0, 111.0, 110.0, 108.0, 109.0, 109.0, 109.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 126.0, 127.0, 202.0, 127.0, 126.0, 159.0, 138.0, 156.0, 126.0], "value": 143.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 160.0, 161.0, 160.0, 161.0, 162.0, 162.0, 162.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 106.0, 74.0, 77.0, 75.0, 75.0, 108.0, 74.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 124.0, 86.0, 86.0, 86.0, 122.0, 86.0, 87.0, 137.0], "value": 98.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 119.0, 116.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 55.0, 54.0, 54.0, 54.0, 55.0, 55.0, 53.0, 58.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 63.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 85.0, 78.0, 77.0, 79.0, 80.0, 80.0, 85.0, 89.0, 81.0], "value": 81.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 123.0, 123.0, 125.0, 129.0, 123.0, 118.0, 121.0, 134.0, 123.0], "value": 124.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 160.0, 174.0, 161.0, 159.0, 159.0, 159.0, 162.0, 156.0], "value": 161.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 65.0, 64.0, 63.0, 63.0, 64.0, 63.0, 63.0, 65.0], "value": 64.1, "unit": "score"}], "name": "kraken", "value": 1498.8999999999996, "alertThreshold": 2.0}]} 23:48:27 INFO - Return code: 0 23:48:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:48:27 INFO - # TBPL SUCCESS # 23:48:27 INFO - Running post-action listener: _resource_record_post_action 23:48:27 INFO - ##### 23:48:27 INFO - ##### Finished run-tests step (success) 23:48:27 INFO - ##### 23:48:27 INFO - Running post-run listener: _resource_record_post_run 23:48:28 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 551460864; Read time: 16; Write time: 482816 23:48:28 INFO - TinderboxPrint: CPU usage
13.2% 23:48:28 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 23:48:28 INFO - TinderboxPrint: I/O write bytes / time
551,460,864 / 482,816 23:48:28 INFO - TinderboxPrint: CPU idle
8,264.8 (86.7%) 23:48:28 INFO - TinderboxPrint: CPU user
1,213.3 (12.7%) 23:48:28 INFO - TinderboxPrint: Swap in / out
0 / 0 23:48:28 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 256667648; Read time: 0; Write time: 261152 23:48:28 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 4096; Write bytes: 294793216; Read time: 16; Write time: 221664 23:48:28 INFO - Running post-run listener: _upload_blobber_files 23:48:28 INFO - Blob upload gear active. 23:48:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:48:28 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:48:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:48:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:48:28 INFO - (blobuploader) - INFO - Open directory for files ... 23:48:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:48:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:48:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:48:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:48:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:48:29 INFO - (blobuploader) - INFO - Done attempting. 23:48:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:48:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:48:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:48:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:48:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:48:30 INFO - (blobuploader) - INFO - Done attempting. 23:48:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:48:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:48:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:48:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:48:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:48:33 INFO - (blobuploader) - INFO - Done attempting. 23:48:33 INFO - (blobuploader) - INFO - Iteration through files over. 23:48:33 INFO - Return code: 0 23:48:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:48:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:48:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/60fd103aa29c4466320f8760a9a1fff3dfdd43c873571845a4c9ceaa5a8bb23259ebdb33b7c277a9207d1fa21c7b674a88f665e364b93a46dbf236ea0c8cf838", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/87383974312032bf7b8e15951c17c52b7a9aeef0f0fee37e4d9c4b39772b9b07b05783f64dfb8e4725745a56e7b153209d217e53fccb5aff6aec0e3a8e5a2a2e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6237a5a039a46fa0105cf94c4acb88f420c03b6029ad122bdda3043fdd4ffc21581f24a9022c22d187358c6e65fe9831932b8a27c72bcf51c4b9eec4fab58678"} 23:48:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:48:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:48:33 INFO - Contents: 23:48:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/60fd103aa29c4466320f8760a9a1fff3dfdd43c873571845a4c9ceaa5a8bb23259ebdb33b7c277a9207d1fa21c7b674a88f665e364b93a46dbf236ea0c8cf838", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/87383974312032bf7b8e15951c17c52b7a9aeef0f0fee37e4d9c4b39772b9b07b05783f64dfb8e4725745a56e7b153209d217e53fccb5aff6aec0e3a8e5a2a2e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6237a5a039a46fa0105cf94c4acb88f420c03b6029ad122bdda3043fdd4ffc21581f24a9022c22d187358c6e65fe9831932b8a27c72bcf51c4b9eec4fab58678"} 23:48:33 INFO - Running post-run listener: copy_logs_to_upload_dir 23:48:33 INFO - Copying logs to upload dir... 23:48:33 INFO - mkdir: /builds/slave/test/build/upload/logs 23:48:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.150409 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-06-27 23:48:33.686275) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-27 23:48:33.690357) ========= 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=14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/60fd103aa29c4466320f8760a9a1fff3dfdd43c873571845a4c9ceaa5a8bb23259ebdb33b7c277a9207d1fa21c7b674a88f665e364b93a46dbf236ea0c8cf838", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/87383974312032bf7b8e15951c17c52b7a9aeef0f0fee37e4d9c4b39772b9b07b05783f64dfb8e4725745a56e7b153209d217e53fccb5aff6aec0e3a8e5a2a2e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6237a5a039a46fa0105cf94c4acb88f420c03b6029ad122bdda3043fdd4ffc21581f24a9022c22d187358c6e65fe9831932b8a27c72bcf51c4b9eec4fab58678"} build_url:https://queue.taskcluster.net/v1/task/D237ZNe6QcixMhUhAQaX1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011176 build_url: 'https://queue.taskcluster.net/v1/task/D237ZNe6QcixMhUhAQaX1g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/60fd103aa29c4466320f8760a9a1fff3dfdd43c873571845a4c9ceaa5a8bb23259ebdb33b7c277a9207d1fa21c7b674a88f665e364b93a46dbf236ea0c8cf838", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/87383974312032bf7b8e15951c17c52b7a9aeef0f0fee37e4d9c4b39772b9b07b05783f64dfb8e4725745a56e7b153209d217e53fccb5aff6aec0e3a8e5a2a2e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6237a5a039a46fa0105cf94c4acb88f420c03b6029ad122bdda3043fdd4ffc21581f24a9022c22d187358c6e65fe9831932b8a27c72bcf51c4b9eec4fab58678"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-27 23:48:33.722982) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 23:48:33.723682) ========= 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=14ef55db36f7623abb31b9cf000001d5-1467094763.434449-1867379079 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004732 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 23:48:33.807352) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-27 23:48:33.807872) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-27 23:48:33.808345) ========= ========= Total master_lag: 0.40 =========